{"level":"warn","ts":"2026-07-08T15:31:22.472787Z","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-08T15:31:22.472944Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.112:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.112:2380","--initial-cluster=instance=https://199.19.213.112:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.112:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.112: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-08T15:31:22.473067Z","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-08T15:31:22.473083Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.112:2380"]} {"level":"info","ts":"2026-07-08T15:31:22.473125Z","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-08T15:31:22.474356Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.112:2379"]} {"level":"info","ts":"2026-07-08T15:31:22.474494Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.112:2380"],"listen-peer-urls":["https://199.19.213.112:2380"],"advertise-client-urls":["https://199.19.213.112:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.112:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.112: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-08T15:31:22.477443Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.593657ms"} {"level":"info","ts":"2026-07-08T15:31:22.483284Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"197930edc5411b2e","cluster-id":"8165613f50a6d530"} {"level":"info","ts":"2026-07-08T15:31:22.483367Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"197930edc5411b2e switched to configuration voters=()"} {"level":"info","ts":"2026-07-08T15:31:22.483397Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"197930edc5411b2e became follower at term 0"} {"level":"info","ts":"2026-07-08T15:31:22.483406Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 197930edc5411b2e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-08T15:31:22.483418Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"197930edc5411b2e became follower at term 1"} {"level":"info","ts":"2026-07-08T15:31:22.483447Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"197930edc5411b2e switched to configuration voters=(1835552120904948526)"} {"level":"warn","ts":"2026-07-08T15:31:22.489324Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-08T15:31:22.490863Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-08T15:31:22.492473Z","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-08T15:31:22.495488Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"197930edc5411b2e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-08T15:31:22.495668Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"197930edc5411b2e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-08T15:31:22.495762Z","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-08T15:31:22.495799Z","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-08T15:31:22.495806Z","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-08T15:31:22.497329Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"197930edc5411b2e switched to configuration voters=(1835552120904948526)"} {"level":"info","ts":"2026-07-08T15:31:22.497579Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8165613f50a6d530","local-member-id":"197930edc5411b2e","added-peer-id":"197930edc5411b2e","added-peer-peer-urls":["https://199.19.213.112:2380"]} {"level":"info","ts":"2026-07-08T15:31:22.499841Z","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-08T15:31:22.499962Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.112:2380"} {"level":"info","ts":"2026-07-08T15:31:22.499994Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.112:2380"} {"level":"info","ts":"2026-07-08T15:31:22.500212Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"197930edc5411b2e","initial-advertise-peer-urls":["https://199.19.213.112:2380"],"listen-peer-urls":["https://199.19.213.112:2380"],"advertise-client-urls":["https://199.19.213.112:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.112:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-08T15:31:22.500315Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-08T15:31:22.684112Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"197930edc5411b2e is starting a new election at term 1"} {"level":"info","ts":"2026-07-08T15:31:22.684171Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"197930edc5411b2e became pre-candidate at term 1"} {"level":"info","ts":"2026-07-08T15:31:22.684211Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"197930edc5411b2e received MsgPreVoteResp from 197930edc5411b2e at term 1"} {"level":"info","ts":"2026-07-08T15:31:22.68423Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"197930edc5411b2e became candidate at term 2"} {"level":"info","ts":"2026-07-08T15:31:22.684247Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"197930edc5411b2e received MsgVoteResp from 197930edc5411b2e at term 2"} {"level":"info","ts":"2026-07-08T15:31:22.684264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"197930edc5411b2e became leader at term 2"} {"level":"info","ts":"2026-07-08T15:31:22.684282Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 197930edc5411b2e elected leader 197930edc5411b2e at term 2"} {"level":"info","ts":"2026-07-08T15:31:22.685545Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T15:31:22.686256Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"197930edc5411b2e","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.112:2379]}","request-path":"/0/members/197930edc5411b2e/attributes","cluster-id":"8165613f50a6d530","publish-timeout":"7s"} {"level":"info","ts":"2026-07-08T15:31:22.686283Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-08T15:31:22.686389Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-08T15:31:22.686484Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-08T15:31:22.686504Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-08T15:31:22.686968Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8165613f50a6d530","local-member-id":"197930edc5411b2e","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T15:31:22.687125Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T15:31:22.687158Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T15:31:22.691602Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.112:2379"} {"level":"info","ts":"2026-07-08T15:31:22.692779Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-08T15:31:24.832766Z","caller":"traceutil/trace.go:171","msg":"trace[600490544] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"152.01204ms","start":"2026-07-08T15:31:24.680661Z","end":"2026-07-08T15:31:24.832673Z","steps":["trace[600490544] 'process raft request' (duration: 142.377132ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:31:24.832836Z","caller":"traceutil/trace.go:171","msg":"trace[1559801914] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"145.596265ms","start":"2026-07-08T15:31:24.687194Z","end":"2026-07-08T15:31:24.83279Z","steps":["trace[1559801914] 'process raft request' (duration: 145.455812ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:31:24.83289Z","caller":"traceutil/trace.go:171","msg":"trace[1212430243] linearizableReadLoop","detail":"{readStateIndex:14; appliedIndex:6; }","duration":"107.480432ms","start":"2026-07-08T15:31:24.725347Z","end":"2026-07-08T15:31:24.832827Z","steps":["trace[1212430243] 'read index received' (duration: 97.761982ms)","trace[1212430243] 'applied index is now lower than readState.Index' (duration: 9.71725ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:31:24.832953Z","caller":"traceutil/trace.go:171","msg":"trace[769499479] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"138.921542ms","start":"2026-07-08T15:31:24.693995Z","end":"2026-07-08T15:31:24.832916Z","steps":["trace[769499479] 'process raft request' (duration: 138.765958ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:31:24.833051Z","caller":"traceutil/trace.go:171","msg":"trace[1320402038] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"146.073878ms","start":"2026-07-08T15:31:24.686969Z","end":"2026-07-08T15:31:24.833043Z","steps":["trace[1320402038] 'process raft request' (duration: 145.644357ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:31:24.833239Z","caller":"traceutil/trace.go:171","msg":"trace[744337233] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"145.736368ms","start":"2026-07-08T15:31:24.687491Z","end":"2026-07-08T15:31:24.833227Z","steps":["trace[744337233] 'process raft request' (duration: 145.186714ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:31:24.833335Z","caller":"traceutil/trace.go:171","msg":"trace[1608724218] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"145.77488ms","start":"2026-07-08T15:31:24.687548Z","end":"2026-07-08T15:31:24.833323Z","steps":["trace[1608724218] 'process raft request' (duration: 145.149064ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:31:24.833557Z","caller":"traceutil/trace.go:171","msg":"trace[1105624986] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"145.369349ms","start":"2026-07-08T15:31:24.688169Z","end":"2026-07-08T15:31:24.833538Z","steps":["trace[1105624986] 'process raft request' (duration: 144.572298ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:31:24.834702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.46753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-08T15:31:24.834797Z","caller":"traceutil/trace.go:171","msg":"trace[828112015] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"101.900088ms","start":"2026-07-08T15:31:24.732873Z","end":"2026-07-08T15:31:24.834773Z","steps":["trace[828112015] 'agreement among raft nodes before linearized reading' (duration: 100.300756ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:31:24.834889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.563428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3171"} {"level":"warn","ts":"2026-07-08T15:31:24.835082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.506725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.112\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-08T15:31:24.835126Z","caller":"traceutil/trace.go:171","msg":"trace[493469666] range","detail":"{range_begin:/registry/masterleases/199.19.213.112; range_end:; response_count:0; response_revision:11; }","duration":"155.11957ms","start":"2026-07-08T15:31:24.679994Z","end":"2026-07-08T15:31:24.835113Z","steps":["trace[493469666] 'agreement among raft nodes before linearized reading' (duration: 154.483034ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:31:24.8353Z","caller":"traceutil/trace.go:171","msg":"trace[2057836299] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:11; }","duration":"156.776253ms","start":"2026-07-08T15:31:24.678291Z","end":"2026-07-08T15:31:24.835067Z","steps":["trace[2057836299] 'agreement among raft nodes before linearized reading' (duration: 154.907405ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:31:24.835663Z","caller":"traceutil/trace.go:171","msg":"trace[935168883] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"140.376061ms","start":"2026-07-08T15:31:24.695274Z","end":"2026-07-08T15:31:24.83565Z","steps":["trace[935168883] 'process raft request' (duration: 137.518077ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:31:24.836361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.789267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-08T15:31:24.835038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.447442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-08T15:31:24.836413Z","caller":"traceutil/trace.go:171","msg":"trace[1561668383] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"106.691772ms","start":"2026-07-08T15:31:24.729707Z","end":"2026-07-08T15:31:24.836399Z","steps":["trace[1561668383] 'agreement among raft nodes before linearized reading' (duration: 103.447207ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:31:24.836454Z","caller":"traceutil/trace.go:171","msg":"trace[331586431] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"154.924777ms","start":"2026-07-08T15:31:24.681505Z","end":"2026-07-08T15:31:24.83643Z","steps":["trace[331586431] 'agreement among raft nodes before linearized reading' (duration: 152.411702ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:31:24.836845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.505969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-08T15:31:24.836972Z","caller":"traceutil/trace.go:171","msg":"trace[78954136] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"156.997079ms","start":"2026-07-08T15:31:24.679954Z","end":"2026-07-08T15:31:24.836952Z","steps":["trace[78954136] 'agreement among raft nodes before linearized reading' (duration: 153.109988ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:31:24.83696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.342398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-08T15:31:24.837548Z","caller":"traceutil/trace.go:171","msg":"trace[1839217251] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"105.949512ms","start":"2026-07-08T15:31:24.73158Z","end":"2026-07-08T15:31:24.837529Z","steps":["trace[1839217251] 'agreement among raft nodes before linearized reading' (duration: 104.32838ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:31:24.836964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.675308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-08T15:31:24.840803Z","caller":"traceutil/trace.go:171","msg":"trace[2030987218] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"108.892418ms","start":"2026-07-08T15:31:24.731888Z","end":"2026-07-08T15:31:24.840781Z","steps":["trace[2030987218] 'agreement among raft nodes before linearized reading' (duration: 102.647237ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:31:24.977445Z","caller":"traceutil/trace.go:171","msg":"trace[991227060] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"136.179242ms","start":"2026-07-08T15:31:24.841243Z","end":"2026-07-08T15:31:24.977422Z","steps":["trace[991227060] 'process raft request' (duration: 134.609511ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:31:24.977518Z","caller":"traceutil/trace.go:171","msg":"trace[1902781651] transaction","detail":"{read_only:false; number_of_response:0; response_revision:13; }","duration":"136.231103ms","start":"2026-07-08T15:31:24.84127Z","end":"2026-07-08T15:31:24.977501Z","steps":["trace[1902781651] 'process raft request' (duration: 136.031188ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:31:24.977724Z","caller":"traceutil/trace.go:171","msg":"trace[385896214] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"136.193653ms","start":"2026-07-08T15:31:24.841519Z","end":"2026-07-08T15:31:24.977712Z","steps":["trace[385896214] 'process raft request' (duration: 135.878515ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:31:24.977768Z","caller":"traceutil/trace.go:171","msg":"trace[1345963775] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"136.145852ms","start":"2026-07-08T15:31:24.841607Z","end":"2026-07-08T15:31:24.977753Z","steps":["trace[1345963775] 'process raft request' (duration: 135.937366ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:31:24.977957Z","caller":"traceutil/trace.go:171","msg":"trace[1326512054] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"136.008007ms","start":"2026-07-08T15:31:24.84194Z","end":"2026-07-08T15:31:24.977948Z","steps":["trace[1326512054] 'process raft request' (duration: 135.72883ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:31:24.977994Z","caller":"traceutil/trace.go:171","msg":"trace[790985386] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"136.322756ms","start":"2026-07-08T15:31:24.841656Z","end":"2026-07-08T15:31:24.977978Z","steps":["trace[790985386] 'process raft request' (duration: 135.975668ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:31:24.978071Z","caller":"traceutil/trace.go:171","msg":"trace[1398659297] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"135.502915ms","start":"2026-07-08T15:31:24.84256Z","end":"2026-07-08T15:31:24.978063Z","steps":["trace[1398659297] 'process raft request' (duration: 135.182506ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:31:24.978103Z","caller":"traceutil/trace.go:171","msg":"trace[2084674800] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"133.76313ms","start":"2026-07-08T15:31:24.844287Z","end":"2026-07-08T15:31:24.97805Z","steps":["trace[2084674800] 'process raft request' (duration: 133.571855ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:31:24.978136Z","caller":"traceutil/trace.go:171","msg":"trace[1290565992] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"135.579766ms","start":"2026-07-08T15:31:24.842549Z","end":"2026-07-08T15:31:24.978128Z","steps":["trace[1290565992] 'process raft request' (duration: 135.230167ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:31:25.560749Z","caller":"traceutil/trace.go:171","msg":"trace[728280303] transaction","detail":"{read_only:false; response_revision:71; number_of_response:1; }","duration":"118.747802ms","start":"2026-07-08T15:31:25.441972Z","end":"2026-07-08T15:31:25.56072Z","steps":["trace[728280303] 'process raft request' (duration: 118.552887ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:31:25.756542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.200408ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-07-08T15:31:25.756653Z","caller":"traceutil/trace.go:171","msg":"trace[1469501234] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"163.487176ms","start":"2026-07-08T15:31:25.593149Z","end":"2026-07-08T15:31:25.756636Z","steps":["trace[1469501234] 'read index received' (duration: 44.619111ms)","trace[1469501234] 'applied index is now lower than readState.Index' (duration: 118.866865ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:31:25.756815Z","caller":"traceutil/trace.go:171","msg":"trace[1359286888] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"165.874878ms","start":"2026-07-08T15:31:25.590802Z","end":"2026-07-08T15:31:25.756677Z","steps":["trace[1359286888] 'process raft request' (duration: 46.991982ms)","trace[1359286888] 'compare' (duration: 118.041065ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:31:25.756973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.768493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-08T15:31:25.75702Z","caller":"traceutil/trace.go:171","msg":"trace[1178594970] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:72; }","duration":"111.855195ms","start":"2026-07-08T15:31:25.645151Z","end":"2026-07-08T15:31:25.757006Z","steps":["trace[1178594970] 'agreement among raft nodes before linearized reading' (duration: 111.580088ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:31:25.756982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.794434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-08T15:31:25.757094Z","caller":"traceutil/trace.go:171","msg":"trace[1114116884] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:72; }","duration":"163.993339ms","start":"2026-07-08T15:31:25.593073Z","end":"2026-07-08T15:31:25.757067Z","steps":["trace[1114116884] 'agreement among raft nodes before linearized reading' (duration: 163.63952ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:31:26.096542Z","caller":"traceutil/trace.go:171","msg":"trace[876876762] linearizableReadLoop","detail":"{readStateIndex:78; appliedIndex:77; }","duration":"252.361479ms","start":"2026-07-08T15:31:25.844155Z","end":"2026-07-08T15:31:26.096517Z","steps":["trace[876876762] 'read index received' (duration: 172.12722ms)","trace[876876762] 'applied index is now lower than readState.Index' (duration: 80.233129ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:31:26.096709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.545964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-08T15:31:26.096748Z","caller":"traceutil/trace.go:171","msg":"trace[2145655755] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:74; }","duration":"252.612265ms","start":"2026-07-08T15:31:25.844125Z","end":"2026-07-08T15:31:26.096737Z","steps":["trace[2145655755] 'agreement among raft nodes before linearized reading' (duration: 252.491672ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:31:26.09688Z","caller":"traceutil/trace.go:171","msg":"trace[684100103] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"258.563729ms","start":"2026-07-08T15:31:25.838242Z","end":"2026-07-08T15:31:26.096806Z","steps":["trace[684100103] 'process raft request' (duration: 178.044422ms)","trace[684100103] 'compare' (duration: 80.048554ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:31:26.324417Z","caller":"traceutil/trace.go:171","msg":"trace[1388314005] transaction","detail":"{read_only:false; response_revision:75; number_of_response:1; }","duration":"223.218567ms","start":"2026-07-08T15:31:26.101164Z","end":"2026-07-08T15:31:26.324383Z","steps":["trace[1388314005] 'process raft request' (duration: 140.77564ms)","trace[1388314005] 'compare' (duration: 82.290463ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:31:31.349714Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1958677995294750108,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T15:31:31.717133Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.569594861s","expected-duration":"1s"} {"level":"info","ts":"2026-07-08T15:31:31.723419Z","caller":"traceutil/trace.go:171","msg":"trace[39371820] linearizableReadLoop","detail":"{readStateIndex:294; appliedIndex:293; }","duration":"874.349311ms","start":"2026-07-08T15:31:30.849034Z","end":"2026-07-08T15:31:31.723383Z","steps":["trace[39371820] 'read index received' (duration: 868.300685ms)","trace[39371820] 'applied index is now lower than readState.Index' (duration: 6.047076ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:31:31.72374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"874.653788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-08T15:31:31.723774Z","caller":"traceutil/trace.go:171","msg":"trace[1756509179] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:281; }","duration":"874.793081ms","start":"2026-07-08T15:31:30.848971Z","end":"2026-07-08T15:31:31.723764Z","steps":["trace[1756509179] 'agreement among raft nodes before linearized reading' (duration: 874.616527ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:31:31.72377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.100645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-07-08T15:31:31.723808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:31:30.848943Z","time spent":"874.857603ms","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-08T15:31:31.723858Z","caller":"traceutil/trace.go:171","msg":"trace[1479786991] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:281; }","duration":"648.228488ms","start":"2026-07-08T15:31:31.075597Z","end":"2026-07-08T15:31:31.723826Z","steps":["trace[1479786991] 'agreement among raft nodes before linearized reading' (duration: 648.077474ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:31:31.723857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.766176ms","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-08T15:31:31.723913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:31:31.07558Z","time spent":"648.32074ms","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-08T15:31:31.723946Z","caller":"traceutil/trace.go:171","msg":"trace[215408154] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:281; }","duration":"766.9357ms","start":"2026-07-08T15:31:30.95698Z","end":"2026-07-08T15:31:31.723916Z","steps":["trace[215408154] 'agreement among raft nodes before linearized reading' (duration: 766.678644ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:31:31.724002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:31:30.956954Z","time spent":"767.034753ms","remote":"127.0.0.1:58822","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-08T15:31:31.726796Z","caller":"traceutil/trace.go:171","msg":"trace[2093908983] transaction","detail":"{read_only:false; response_revision:281; number_of_response:1; }","duration":"1.576243383s","start":"2026-07-08T15:31:30.147341Z","end":"2026-07-08T15:31:31.723585Z","steps":["trace[2093908983] 'process raft request' (duration: 1.570074134s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:31:31.727003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:31:30.147285Z","time spent":"1.5796132s","remote":"127.0.0.1:58752","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-08T15:31:32.238987Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1958677995294750112,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T15:31:33.73166Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.129862729s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-08T15:31:34.832581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.229462368s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T15:31:34.833067Z","caller":"traceutil/trace.go:171","msg":"trace[1531213078] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"3.096842409s","start":"2026-07-08T15:31:31.736162Z","end":"2026-07-08T15:31:34.833005Z","steps":["trace[1531213078] 'process raft request' (duration: 865.552733ms)","trace[1531213078] 'compare' (duration: 2.228491974s)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:31:34.833182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:31:31.736134Z","time spent":"3.096979202s","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T15:31:34.838807Z","caller":"traceutil/trace.go:171","msg":"trace[328175363] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"3.10112971s","start":"2026-07-08T15:31:31.736775Z","end":"2026-07-08T15:31:34.837905Z","steps":["trace[328175363] 'process raft request' (duration: 3.100444993s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:31:34.839003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:31:31.736759Z","time spent":"3.102111115s","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T15:31:34.839309Z","caller":"traceutil/trace.go:171","msg":"trace[1546948635] linearizableReadLoop","detail":"{readStateIndex:297; appliedIndex:294; }","duration":"3.100602047s","start":"2026-07-08T15:31:31.738674Z","end":"2026-07-08T15:31:34.839276Z","steps":["trace[1546948635] 'read index received' (duration: 863.075791ms)","trace[1546948635] 'applied index is now lower than readState.Index' (duration: 2.237525746s)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:31:34.839402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.1007238s","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-08T15:31:34.839431Z","caller":"traceutil/trace.go:171","msg":"trace[1595353743] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:284; }","duration":"3.100761741s","start":"2026-07-08T15:31:31.738658Z","end":"2026-07-08T15:31:34.83942Z","steps":["trace[1595353743] 'agreement among raft nodes before linearized reading' (duration: 3.10071112s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:31:34.839427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.076015933s","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-08T15:31:34.83948Z","caller":"traceutil/trace.go:171","msg":"trace[1618696856] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:284; }","duration":"3.076112755s","start":"2026-07-08T15:31:31.763358Z","end":"2026-07-08T15:31:34.839471Z","steps":["trace[1618696856] 'agreement among raft nodes before linearized reading' (duration: 3.076037203s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:31:34.839503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.458043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"warn","ts":"2026-07-08T15:31:34.839505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:31:31.763328Z","time spent":"3.076170596s","remote":"127.0.0.1:58822","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-08T15:31:34.839526Z","caller":"traceutil/trace.go:171","msg":"trace[1559712636] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:284; }","duration":"154.533065ms","start":"2026-07-08T15:31:34.684984Z","end":"2026-07-08T15:31:34.839517Z","steps":["trace[1559712636] 'agreement among raft nodes before linearized reading' (duration: 154.492124ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:31:34.839452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:31:31.73865Z","time spent":"3.100796532s","remote":"127.0.0.1:58670","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-08T15:31:34.840246Z","caller":"traceutil/trace.go:171","msg":"trace[1793841828] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"3.101478699s","start":"2026-07-08T15:31:31.737858Z","end":"2026-07-08T15:31:34.839337Z","steps":["trace[1793841828] 'process raft request' (duration: 3.099481388s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:31:34.84105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:31:31.73785Z","time spent":"3.102483976s","remote":"127.0.0.1:58822","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-08T15:31:36.083678Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.240061351s","expected-duration":"1s"} {"level":"info","ts":"2026-07-08T15:31:36.144898Z","caller":"traceutil/trace.go:171","msg":"trace[851145668] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"1.30128419s","start":"2026-07-08T15:31:34.843534Z","end":"2026-07-08T15:31:36.144818Z","steps":["trace[851145668] 'process raft request' (duration: 1.240623076s)","trace[851145668] 'compare' (duration: 60.50229ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:31:36.145125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:31:34.843519Z","time spent":"1.301493236s","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T15:31:36.1481Z","caller":"traceutil/trace.go:171","msg":"trace[214750062] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"1.303844876s","start":"2026-07-08T15:31:34.844213Z","end":"2026-07-08T15:31:36.148058Z","steps":["trace[214750062] 'process raft request' (duration: 1.303478087s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:31:36.148287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:31:34.844206Z","time spent":"1.303995371s","remote":"127.0.0.1:58752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":182,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T15:31:36.148123Z","caller":"traceutil/trace.go:171","msg":"trace[294173842] transaction","detail":"{read_only:false; response_revision:288; number_of_response:1; }","duration":"1.006296692s","start":"2026-07-08T15:31:35.141798Z","end":"2026-07-08T15:31:36.148095Z","steps":["trace[294173842] 'process raft request' (duration: 1.00622505s)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:31:36.148123Z","caller":"traceutil/trace.go:171","msg":"trace[861961105] transaction","detail":"{read_only:false; response_revision:287; number_of_response:1; }","duration":"1.301405223s","start":"2026-07-08T15:31:34.846688Z","end":"2026-07-08T15:31:36.148093Z","steps":["trace[861961105] 'process raft request' (duration: 1.301128786s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:31:36.149047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:31:34.846645Z","time spent":"1.301986669s","remote":"127.0.0.1:58730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4311,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T15:31:36.149479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:31:35.141758Z","time spent":"1.006627531s","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T15:31:36.311683Z","caller":"traceutil/trace.go:171","msg":"trace[648271857] transaction","detail":"{read_only:false; response_revision:293; number_of_response:1; }","duration":"108.852518ms","start":"2026-07-08T15:31:36.202795Z","end":"2026-07-08T15:31:36.311647Z","steps":["trace[648271857] 'process raft request' (duration: 42.165858ms)","trace[648271857] 'compare' (duration: 66.531796ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:31:36.639051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.796677ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-08T15:31:36.639201Z","caller":"traceutil/trace.go:171","msg":"trace[997427093] transaction","detail":"{read_only:false; response_revision:298; number_of_response:1; }","duration":"232.104257ms","start":"2026-07-08T15:31:36.40707Z","end":"2026-07-08T15:31:36.639174Z","steps":["trace[997427093] 'process raft request' (duration: 40.088794ms)","trace[997427093] 'compare' (duration: 191.622203ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:31:40.183146Z","caller":"traceutil/trace.go:171","msg":"trace[106159710] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"112.689326ms","start":"2026-07-08T15:31:40.070433Z","end":"2026-07-08T15:31:40.183122Z","steps":["trace[106159710] 'process raft request' (duration: 90.176085ms)","trace[106159710] 'compare' (duration: 22.392227ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:31:40.210252Z","caller":"traceutil/trace.go:171","msg":"trace[1443202515] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"133.117914ms","start":"2026-07-08T15:31:40.077107Z","end":"2026-07-08T15:31:40.210225Z","steps":["trace[1443202515] 'process raft request' (duration: 133.021141ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:31:41.163001Z","caller":"traceutil/trace.go:171","msg":"trace[1765169749] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"292.732201ms","start":"2026-07-08T15:31:40.870231Z","end":"2026-07-08T15:31:41.162963Z","steps":["trace[1765169749] 'process raft request' (duration: 292.481774ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:31:41.429039Z","caller":"traceutil/trace.go:171","msg":"trace[1644355851] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"555.700022ms","start":"2026-07-08T15:31:40.873315Z","end":"2026-07-08T15:31:41.429015Z","steps":["trace[1644355851] 'process raft request' (duration: 555.654171ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:31:41.4292Z","caller":"traceutil/trace.go:171","msg":"trace[1019287094] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"558.097424ms","start":"2026-07-08T15:31:40.871007Z","end":"2026-07-08T15:31:41.429104Z","steps":["trace[1019287094] 'process raft request' (duration: 557.759376ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:31:41.429419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:31:40.873301Z","time spent":"555.815026ms","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T15:31:41.429526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:31:40.870999Z","time spent":"558.349491ms","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T15:32:06.105947Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1958677995294750490,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T15:32:06.659535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.184907ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-08T15:32:06.659795Z","caller":"traceutil/trace.go:171","msg":"trace[1693152037] linearizableReadLoop","detail":"{readStateIndex:475; appliedIndex:473; }","duration":"1.053899651s","start":"2026-07-08T15:32:05.605874Z","end":"2026-07-08T15:32:06.659774Z","steps":["trace[1693152037] 'read index received' (duration: 797.402946ms)","trace[1693152037] 'applied index is now lower than readState.Index' (duration: 256.495455ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:32:06.659906Z","caller":"traceutil/trace.go:171","msg":"trace[349597672] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"1.115460269s","start":"2026-07-08T15:32:05.544433Z","end":"2026-07-08T15:32:06.659893Z","steps":["trace[349597672] 'process raft request' (duration: 858.767759ms)","trace[349597672] 'compare' (duration: 254.908095ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:32:06.659964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:32:05.544401Z","time spent":"1.11553575s","remote":"127.0.0.1:58910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1639,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T15:32:06.660321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.054416784s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-08T15:32:06.660422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.053135491s","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-08T15:32:06.660442Z","caller":"traceutil/trace.go:171","msg":"trace[1455476542] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:455; }","duration":"1.054576668s","start":"2026-07-08T15:32:05.605836Z","end":"2026-07-08T15:32:06.660412Z","steps":["trace[1455476542] 'agreement among raft nodes before linearized reading' (duration: 1.05425799s)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:32:06.660489Z","caller":"traceutil/trace.go:171","msg":"trace[209220761] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:455; }","duration":"1.053267304s","start":"2026-07-08T15:32:05.607198Z","end":"2026-07-08T15:32:06.660466Z","steps":["trace[209220761] 'agreement among raft nodes before linearized reading' (duration: 1.053118101s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:32:06.660496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:32:05.605817Z","time spent":"1.05466825s","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-08T15:32:06.660543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:32:05.607176Z","time spent":"1.053354517s","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-08T15:32:06.660726Z","caller":"traceutil/trace.go:171","msg":"trace[23759495] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"1.11512865s","start":"2026-07-08T15:32:05.545586Z","end":"2026-07-08T15:32:06.660715Z","steps":["trace[23759495] 'process raft request' (duration: 1.114125974s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:32:06.660803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:32:05.545547Z","time spent":"1.115202322s","remote":"127.0.0.1:58910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2364,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T15:32:06.66083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"988.951787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:32:06.660978Z","caller":"traceutil/trace.go:171","msg":"trace[210468679] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:455; }","duration":"989.09657ms","start":"2026-07-08T15:32:05.671839Z","end":"2026-07-08T15:32:06.660936Z","steps":["trace[210468679] 'agreement among raft nodes before linearized reading' (duration: 988.873894ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:32:06.661051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:32:05.671797Z","time spent":"989.239624ms","remote":"127.0.0.1:58520","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-08T15:32:06.661076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.484646ms","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-08T15:32:06.66114Z","caller":"traceutil/trace.go:171","msg":"trace[603130623] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:455; }","duration":"225.548468ms","start":"2026-07-08T15:32:06.435571Z","end":"2026-07-08T15:32:06.661119Z","steps":["trace[603130623] 'agreement among raft nodes before linearized reading' (duration: 225.318862ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:32:09.171943Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1958677995294750555,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T15:32:09.672376Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1958677995294750555,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T15:32:09.790846Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.277107067s","expected-duration":"1s"} {"level":"info","ts":"2026-07-08T15:32:09.791606Z","caller":"traceutil/trace.go:171","msg":"trace[1985677496] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"1.278072172s","start":"2026-07-08T15:32:08.513498Z","end":"2026-07-08T15:32:09.79157Z","steps":["trace[1985677496] 'process raft request' (duration: 1.277911488s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:32:09.791852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:32:08.513462Z","time spent":"1.278294938s","remote":"127.0.0.1:58584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T15:32:09.805034Z","caller":"traceutil/trace.go:171","msg":"trace[1193777353] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"1.268923786s","start":"2026-07-08T15:32:08.536083Z","end":"2026-07-08T15:32:09.805006Z","steps":["trace[1193777353] 'process raft request' (duration: 1.268414143s)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:32:09.805137Z","caller":"traceutil/trace.go:171","msg":"trace[799129012] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"1.288621324s","start":"2026-07-08T15:32:08.516507Z","end":"2026-07-08T15:32:09.805128Z","steps":["trace[799129012] 'process raft request' (duration: 1.287724941s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:32:09.805284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:32:08.536053Z","time spent":"1.2690785s","remote":"127.0.0.1:58584","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-08T15:32:09.805292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:32:08.516469Z","time spent":"1.288723736s","remote":"127.0.0.1:58584","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-08T15:32:09.805048Z","caller":"traceutil/trace.go:171","msg":"trace[944396238] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"1.285572846s","start":"2026-07-08T15:32:08.519399Z","end":"2026-07-08T15:32:09.804972Z","steps":["trace[944396238] 'process raft request' (duration: 1.28495964s)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:32:09.805515Z","caller":"traceutil/trace.go:171","msg":"trace[244968307] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"1.276242775s","start":"2026-07-08T15:32:08.529259Z","end":"2026-07-08T15:32:09.805502Z","steps":["trace[244968307] 'process raft request' (duration: 1.275138547s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:32:09.805558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:32:08.529238Z","time spent":"1.276297817s","remote":"127.0.0.1:58584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T15:32:09.805561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:32:08.51938Z","time spent":"1.28613966s","remote":"127.0.0.1:58584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T15:32:09.805676Z","caller":"traceutil/trace.go:171","msg":"trace[402734901] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"1.25592559s","start":"2026-07-08T15:32:08.549733Z","end":"2026-07-08T15:32:09.805658Z","steps":["trace[402734901] 'process raft request' (duration: 1.255042238s)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:32:09.805755Z","caller":"traceutil/trace.go:171","msg":"trace[682683973] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"1.266734249s","start":"2026-07-08T15:32:08.539012Z","end":"2026-07-08T15:32:09.805746Z","steps":["trace[682683973] 'process raft request' (duration: 1.265536009s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:32:09.805779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:32:08.549625Z","time spent":"1.256122545s","remote":"127.0.0.1:58584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T15:32:09.805816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:32:08.539Z","time spent":"1.26679151s","remote":"127.0.0.1:58584","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-08T15:32:09.805926Z","caller":"traceutil/trace.go:171","msg":"trace[971295465] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"1.247166875s","start":"2026-07-08T15:32:08.558749Z","end":"2026-07-08T15:32:09.805916Z","steps":["trace[971295465] 'process raft request' (duration: 1.246315073s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:32:09.806009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:32:08.558726Z","time spent":"1.247233737s","remote":"127.0.0.1:58584","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-08T15:32:09.806283Z","caller":"traceutil/trace.go:171","msg":"trace[234201104] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"1.259613926s","start":"2026-07-08T15:32:08.546656Z","end":"2026-07-08T15:32:09.80627Z","steps":["trace[234201104] 'process raft request' (duration: 1.257940393s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:32:09.806324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:32:08.546641Z","time spent":"1.259662727s","remote":"127.0.0.1:58584","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-08T15:32:09.805687Z","caller":"traceutil/trace.go:171","msg":"trace[1204190977] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"1.254822942s","start":"2026-07-08T15:32:08.550852Z","end":"2026-07-08T15:32:09.805675Z","steps":["trace[1204190977] 'process raft request' (duration: 1.254108374s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:32:09.806502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:32:08.550843Z","time spent":"1.255630713s","remote":"127.0.0.1:58584","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-08T15:32:09.808486Z","caller":"traceutil/trace.go:171","msg":"trace[1613143130] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"1.212937263s","start":"2026-07-08T15:32:08.595524Z","end":"2026-07-08T15:32:09.808461Z","steps":["trace[1613143130] 'process raft request' (duration: 1.209890494s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:32:09.808562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:32:08.595476Z","time spent":"1.213054395s","remote":"127.0.0.1:58584","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-08T15:32:09.80981Z","caller":"traceutil/trace.go:171","msg":"trace[1380047324] linearizableReadLoop","detail":"{readStateIndex:522; appliedIndex:510; }","duration":"1.138219256s","start":"2026-07-08T15:32:08.671578Z","end":"2026-07-08T15:32:09.809797Z","steps":["trace[1380047324] 'read index received' (duration: 1.11979768s)","trace[1380047324] 'applied index is now lower than readState.Index' (duration: 18.420756ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:32:09.809841Z","caller":"traceutil/trace.go:171","msg":"trace[211054890] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"1.09302327s","start":"2026-07-08T15:32:08.716797Z","end":"2026-07-08T15:32:09.80982Z","steps":["trace[211054890] 'process raft request' (duration: 1.089133739s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:32:09.809917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:32:08.716753Z","time spent":"1.093116012s","remote":"127.0.0.1:58584","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-08T15:32:09.822441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.150845661s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:32:09.822535Z","caller":"traceutil/trace.go:171","msg":"trace[1406954470] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:501; }","duration":"1.151003475s","start":"2026-07-08T15:32:08.671509Z","end":"2026-07-08T15:32:09.822512Z","steps":["trace[1406954470] 'agreement among raft nodes before linearized reading' (duration: 1.13838585s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:32:09.822575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:32:08.671483Z","time spent":"1.151084577s","remote":"127.0.0.1:58520","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-08T15:32:09.827916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.151917649s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-08T15:32:09.827991Z","caller":"traceutil/trace.go:171","msg":"trace[1362598461] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:504; }","duration":"1.152075253s","start":"2026-07-08T15:32:08.6759Z","end":"2026-07-08T15:32:09.827975Z","steps":["trace[1362598461] 'agreement among raft nodes before linearized reading' (duration: 1.151258522s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:32:09.828028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:32:08.67587Z","time spent":"1.152151504s","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-08T15:32:09.830315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.501583ms","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-08T15:32:09.830362Z","caller":"traceutil/trace.go:171","msg":"trace[52830545] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:505; }","duration":"437.607636ms","start":"2026-07-08T15:32:09.392737Z","end":"2026-07-08T15:32:09.830344Z","steps":["trace[52830545] 'agreement among raft nodes before linearized reading' (duration: 436.466087ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:32:09.830385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:32:09.392712Z","time spent":"437.667698ms","remote":"127.0.0.1:58822","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-08T15:32:09.831024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.154663369s","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-08T15:32:09.831069Z","caller":"traceutil/trace.go:171","msg":"trace[1247374964] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:505; }","duration":"1.154722601s","start":"2026-07-08T15:32:08.676337Z","end":"2026-07-08T15:32:09.83106Z","steps":["trace[1247374964] 'agreement among raft nodes before linearized reading' (duration: 1.154426274s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:32:09.831092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:32:08.676332Z","time spent":"1.154754782s","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-08T15:32:09.831579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.155285265s","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-08T15:32:09.831615Z","caller":"traceutil/trace.go:171","msg":"trace[4066723] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:505; }","duration":"1.155341066s","start":"2026-07-08T15:32:08.676266Z","end":"2026-07-08T15:32:09.831607Z","steps":["trace[4066723] 'agreement among raft nodes before linearized reading' (duration: 1.15511064s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:32:09.831641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:32:08.676258Z","time spent":"1.155373398s","remote":"127.0.0.1:58822","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-08T15:32:11.019679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.606737ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T15:32:11.019794Z","caller":"traceutil/trace.go:171","msg":"trace[2089927747] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"371.674375ms","start":"2026-07-08T15:32:10.648101Z","end":"2026-07-08T15:32:11.019776Z","steps":["trace[2089927747] 'process raft request' (duration: 225.871613ms)","trace[2089927747] 'compare' (duration: 145.412582ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:32:11.01984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:32:10.648061Z","time spent":"371.761488ms","remote":"127.0.0.1:58584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":74754,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T15:32:11.020389Z","caller":"traceutil/trace.go:171","msg":"trace[628792133] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"215.416893ms","start":"2026-07-08T15:32:10.80494Z","end":"2026-07-08T15:32:11.020357Z","steps":["trace[628792133] 'process raft request' (duration: 215.145856ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:32:11.231725Z","caller":"traceutil/trace.go:171","msg":"trace[1959314331] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"192.695836ms","start":"2026-07-08T15:32:11.039002Z","end":"2026-07-08T15:32:11.231698Z","steps":["trace[1959314331] 'process raft request' (duration: 165.059581ms)","trace[1959314331] 'compare' (duration: 27.490551ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:32:11.265484Z","caller":"traceutil/trace.go:171","msg":"trace[83056223] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"127.110518ms","start":"2026-07-08T15:32:11.138349Z","end":"2026-07-08T15:32:11.26546Z","steps":["trace[83056223] 'process raft request' (duration: 126.990975ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:32:11.714436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.868114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:80703"} {"level":"info","ts":"2026-07-08T15:32:11.714573Z","caller":"traceutil/trace.go:171","msg":"trace[497235954] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:566; }","duration":"148.087221ms","start":"2026-07-08T15:32:11.566454Z","end":"2026-07-08T15:32:11.714541Z","steps":["trace[497235954] 'range keys from in-memory index tree' (duration: 147.587097ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:32:16.454751Z","caller":"traceutil/trace.go:171","msg":"trace[2086067378] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"195.074847ms","start":"2026-07-08T15:32:16.259651Z","end":"2026-07-08T15:32:16.454726Z","steps":["trace[2086067378] 'process raft request' (duration: 194.501882ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:32:16.789858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.491973ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-08T15:32:16.789983Z","caller":"traceutil/trace.go:171","msg":"trace[2076889102] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:623; }","duration":"118.957367ms","start":"2026-07-08T15:32:16.671006Z","end":"2026-07-08T15:32:16.789964Z","steps":["trace[2076889102] 'read index received' (duration: 40.491µs)","trace[2076889102] 'applied index is now lower than readState.Index' (duration: 118.915506ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:32:16.790078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.06289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:32:16.790111Z","caller":"traceutil/trace.go:171","msg":"trace[1502301864] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:598; }","duration":"119.134361ms","start":"2026-07-08T15:32:16.670966Z","end":"2026-07-08T15:32:16.790101Z","steps":["trace[1502301864] 'agreement among raft nodes before linearized reading' (duration: 119.051639ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:32:16.790219Z","caller":"traceutil/trace.go:171","msg":"trace[728449453] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"270.57596ms","start":"2026-07-08T15:32:16.51959Z","end":"2026-07-08T15:32:16.790166Z","steps":["trace[728449453] 'process raft request' (duration: 34.700227ms)","trace[728449453] 'compare' (duration: 235.240007ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:32:17.259837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.590834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.112\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-08T15:32:17.25998Z","caller":"traceutil/trace.go:171","msg":"trace[1401943302] range","detail":"{range_begin:/registry/masterleases/199.19.213.112; range_end:; response_count:1; response_revision:598; }","duration":"211.81702ms","start":"2026-07-08T15:32:17.048136Z","end":"2026-07-08T15:32:17.259953Z","steps":["trace[1401943302] 'range keys from in-memory index tree' (duration: 211.347619ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:32:17.260032Z","caller":"traceutil/trace.go:171","msg":"trace[65471913] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:624; }","duration":"143.851232ms","start":"2026-07-08T15:32:17.116121Z","end":"2026-07-08T15:32:17.259972Z","steps":["trace[65471913] 'read index received' (duration: 49.721586ms)","trace[65471913] 'applied index is now lower than readState.Index' (duration: 94.128586ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:32:17.26007Z","caller":"traceutil/trace.go:171","msg":"trace[1503925244] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"168.949141ms","start":"2026-07-08T15:32:17.091081Z","end":"2026-07-08T15:32:17.26003Z","steps":["trace[1503925244] 'process raft request' (duration: 74.789555ms)","trace[1503925244] 'compare' (duration: 93.93958ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:32:17.26023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.080637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-b5fn9\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-08T15:32:17.260315Z","caller":"traceutil/trace.go:171","msg":"trace[1643896465] range","detail":"{range_begin:/registry/pods/kube-system/cilium-b5fn9; range_end:; response_count:1; response_revision:599; }","duration":"144.215891ms","start":"2026-07-08T15:32:17.116076Z","end":"2026-07-08T15:32:17.260291Z","steps":["trace[1643896465] 'agreement among raft nodes before linearized reading' (duration: 144.026896ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:32:18.299708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"919.909719ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T15:32:18.299939Z","caller":"traceutil/trace.go:171","msg":"trace[1501077529] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"1.110685294s","start":"2026-07-08T15:32:17.189237Z","end":"2026-07-08T15:32:18.299922Z","steps":["trace[1501077529] 'process raft request' (duration: 190.494458ms)","trace[1501077529] 'compare' (duration: 919.610291ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:32:18.300028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:32:17.189213Z","time spent":"1.110779327s","remote":"127.0.0.1:58646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T15:32:18.306306Z","caller":"traceutil/trace.go:171","msg":"trace[1332844128] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:626; }","duration":"390.618566ms","start":"2026-07-08T15:32:17.915664Z","end":"2026-07-08T15:32:18.306283Z","steps":["trace[1332844128] 'read index received' (duration: 390.40722ms)","trace[1332844128] 'applied index is now lower than readState.Index' (duration: 210.286µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:32:18.306501Z","caller":"traceutil/trace.go:171","msg":"trace[30585965] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"1.033649092s","start":"2026-07-08T15:32:17.27277Z","end":"2026-07-08T15:32:18.306419Z","steps":["trace[30585965] 'process raft request' (duration: 1.033421166s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:32:18.306631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:32:17.261781Z","time spent":"1.044845601s","remote":"127.0.0.1:58560","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-08T15:32:18.306768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:32:17.272738Z","time spent":"1.033834407s","remote":"127.0.0.1:58730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T15:32:18.307193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.493619ms","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-08T15:32:18.30724Z","caller":"traceutil/trace.go:171","msg":"trace[981665896] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:601; }","duration":"391.612032ms","start":"2026-07-08T15:32:17.915616Z","end":"2026-07-08T15:32:18.307228Z","steps":["trace[981665896] 'agreement among raft nodes before linearized reading' (duration: 391.413046ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:32:18.307295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.275077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-08T15:32:18.307404Z","caller":"traceutil/trace.go:171","msg":"trace[446961583] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:601; }","duration":"390.391409ms","start":"2026-07-08T15:32:17.916975Z","end":"2026-07-08T15:32:18.307367Z","steps":["trace[446961583] 'agreement among raft nodes before linearized reading' (duration: 390.152923ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:32:18.307432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:32:17.915591Z","time spent":"391.735885ms","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-08T15:32:18.307638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.264566ms","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-08T15:32:18.307682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.985659ms","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-08T15:32:18.307805Z","caller":"traceutil/trace.go:171","msg":"trace[1187507193] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:601; }","duration":"390.109552ms","start":"2026-07-08T15:32:17.917652Z","end":"2026-07-08T15:32:18.307761Z","steps":["trace[1187507193] 'agreement among raft nodes before linearized reading' (duration: 389.805584ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:32:18.307682Z","caller":"traceutil/trace.go:171","msg":"trace[1691624940] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:601; }","duration":"390.374339ms","start":"2026-07-08T15:32:17.917297Z","end":"2026-07-08T15:32:18.307672Z","steps":["trace[1691624940] 'agreement among raft nodes before linearized reading' (duration: 390.232985ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:32:18.307874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:32:17.917635Z","time spent":"390.204594ms","remote":"127.0.0.1:58822","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-08T15:32:18.307889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:32:17.91726Z","time spent":"390.607285ms","remote":"127.0.0.1:58822","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-08T15:32:18.308565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:32:17.916961Z","time spent":"390.498572ms","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-08T15:32:18.521084Z","caller":"traceutil/trace.go:171","msg":"trace[273076803] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"209.166321ms","start":"2026-07-08T15:32:18.311891Z","end":"2026-07-08T15:32:18.521057Z","steps":["trace[273076803] 'process raft request' (duration: 172.167714ms)","trace[273076803] 'compare' (duration: 36.518895ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:32:18.521175Z","caller":"traceutil/trace.go:171","msg":"trace[1924433483] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:629; }","duration":"149.333503ms","start":"2026-07-08T15:32:18.37182Z","end":"2026-07-08T15:32:18.521154Z","steps":["trace[1924433483] 'read index received' (duration: 112.122111ms)","trace[1924433483] 'applied index is now lower than readState.Index' (duration: 37.209972ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:32:18.521401Z","caller":"traceutil/trace.go:171","msg":"trace[1072720244] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"157.30794ms","start":"2026-07-08T15:32:18.364074Z","end":"2026-07-08T15:32:18.521381Z","steps":["trace[1072720244] 'process raft request' (duration: 157.032023ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:32:18.521582Z","caller":"traceutil/trace.go:171","msg":"trace[110787930] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"206.140362ms","start":"2026-07-08T15:32:18.315419Z","end":"2026-07-08T15:32:18.52156Z","steps":["trace[110787930] 'process raft request' (duration: 205.65263ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:32:18.522176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.339859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-07-08T15:32:18.522241Z","caller":"traceutil/trace.go:171","msg":"trace[1413581689] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:607; }","duration":"150.415802ms","start":"2026-07-08T15:32:18.371809Z","end":"2026-07-08T15:32:18.522225Z","steps":["trace[1413581689] 'agreement among raft nodes before linearized reading' (duration: 149.468207ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:32:18.522313Z","caller":"traceutil/trace.go:171","msg":"trace[2103221273] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"207.443397ms","start":"2026-07-08T15:32:18.314857Z","end":"2026-07-08T15:32:18.5223Z","steps":["trace[2103221273] 'process raft request' (duration: 206.164753ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:32:18.522237Z","caller":"traceutil/trace.go:171","msg":"trace[1044332717] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"207.54729ms","start":"2026-07-08T15:32:18.314667Z","end":"2026-07-08T15:32:18.522214Z","steps":["trace[1044332717] 'process raft request' (duration: 206.297237ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:32:21.025199Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1958677995294750755,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T15:32:21.526117Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1958677995294750755,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T15:32:22.026497Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1958677995294750755,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T15:32:22.124228Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.114861494s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-08T15:32:22.190374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.519361361s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:32:22.190463Z","caller":"traceutil/trace.go:171","msg":"trace[404948874] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:607; }","duration":"3.519495054s","start":"2026-07-08T15:32:18.670945Z","end":"2026-07-08T15:32:22.190441Z","steps":["trace[404948874] 'range keys from in-memory index tree' (duration: 3.519277618s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:32:22.190516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:32:18.670926Z","time spent":"3.519577106s","remote":"127.0.0.1:58520","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-08T15:32:22.193245Z","caller":"traceutil/trace.go:171","msg":"trace[2057477422] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"3.186085748s","start":"2026-07-08T15:32:19.007136Z","end":"2026-07-08T15:32:22.193222Z","steps":["trace[2057477422] 'process raft request' (duration: 3.117589396s)","trace[2057477422] 'compare' (duration: 67.091886ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:32:22.193394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:32:19.007085Z","time spent":"3.186233372s","remote":"127.0.0.1:58646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T15:32:22.195895Z","caller":"traceutil/trace.go:171","msg":"trace[2124488179] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"1.844856139s","start":"2026-07-08T15:32:20.351024Z","end":"2026-07-08T15:32:22.19588Z","steps":["trace[2124488179] 'process raft request' (duration: 1.843064622s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:32:22.195978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:32:20.351004Z","time spent":"1.844937052s","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T15:32:22.214724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.68989796s","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-08T15:32:22.214781Z","caller":"traceutil/trace.go:171","msg":"trace[457378097] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:609; }","duration":"1.690013664s","start":"2026-07-08T15:32:20.524757Z","end":"2026-07-08T15:32:22.214771Z","steps":["trace[457378097] 'agreement among raft nodes before linearized reading' (duration: 1.689560602s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:32:22.214808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:32:20.524731Z","time spent":"1.690071335s","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-08T15:32:22.214268Z","caller":"traceutil/trace.go:171","msg":"trace[773460099] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:634; }","duration":"1.689270923s","start":"2026-07-08T15:32:20.524808Z","end":"2026-07-08T15:32:22.214079Z","steps":["trace[773460099] 'read index received' (duration: 1.599769578s)","trace[773460099] 'applied index is now lower than readState.Index' (duration: 89.500005ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:32:22.215132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.688783781s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-08T15:32:22.215158Z","caller":"traceutil/trace.go:171","msg":"trace[1040794317] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:609; }","duration":"1.688808071s","start":"2026-07-08T15:32:20.526341Z","end":"2026-07-08T15:32:22.215149Z","steps":["trace[1040794317] 'agreement among raft nodes before linearized reading' (duration: 1.68873896s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:32:22.215176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:32:20.526305Z","time spent":"1.688866633s","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-08T15:32:22.21528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.543471862s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:32:22.215302Z","caller":"traceutil/trace.go:171","msg":"trace[1723705123] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:609; }","duration":"1.543535683s","start":"2026-07-08T15:32:20.67176Z","end":"2026-07-08T15:32:22.215296Z","steps":["trace[1723705123] 'agreement among raft nodes before linearized reading' (duration: 1.543504302s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:32:22.21532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:32:20.671735Z","time spent":"1.543581565s","remote":"127.0.0.1:58532","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-08T15:32:22.215485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.688209186s","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-08T15:32:22.215512Z","caller":"traceutil/trace.go:171","msg":"trace[1660085499] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:609; }","duration":"1.688237807s","start":"2026-07-08T15:32:20.527268Z","end":"2026-07-08T15:32:22.215506Z","steps":["trace[1660085499] 'agreement among raft nodes before linearized reading' (duration: 1.688125184s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:32:22.215532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:32:20.527217Z","time spent":"1.688310889s","remote":"127.0.0.1:58822","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-08T15:32:22.21563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.68915136s","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-08T15:32:22.215679Z","caller":"traceutil/trace.go:171","msg":"trace[214720984] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:609; }","duration":"1.689260683s","start":"2026-07-08T15:32:20.526408Z","end":"2026-07-08T15:32:22.215668Z","steps":["trace[214720984] 'agreement among raft nodes before linearized reading' (duration: 1.689105799s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:32:22.215707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:32:20.526386Z","time spent":"1.689316565s","remote":"127.0.0.1:58822","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-08T15:32:24.335969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.595952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-b5fn9\" ","response":"range_response_count:1 size:17247"} {"level":"info","ts":"2026-07-08T15:32:24.336067Z","caller":"traceutil/trace.go:171","msg":"trace[1559525166] range","detail":"{range_begin:/registry/pods/kube-system/cilium-b5fn9; range_end:; response_count:1; response_revision:624; }","duration":"139.756946ms","start":"2026-07-08T15:32:24.196288Z","end":"2026-07-08T15:32:24.336045Z","steps":["trace[1559525166] 'range keys from in-memory index tree' (duration: 139.288274ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:32:24.336052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.910681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-08T15:32:24.336131Z","caller":"traceutil/trace.go:171","msg":"trace[32999888] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:624; }","duration":"239.024944ms","start":"2026-07-08T15:32:24.097089Z","end":"2026-07-08T15:32:24.336114Z","steps":["trace[32999888] 'count revisions from in-memory index tree' (duration: 238.731136ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:32:28.635703Z","caller":"traceutil/trace.go:171","msg":"trace[1742739618] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"274.350237ms","start":"2026-07-08T15:32:28.361327Z","end":"2026-07-08T15:32:28.635678Z","steps":["trace[1742739618] 'process raft request' (duration: 269.522962ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:32:28.641735Z","caller":"traceutil/trace.go:171","msg":"trace[1891252464] linearizableReadLoop","detail":"{readStateIndex:722; appliedIndex:720; }","duration":"258.9964ms","start":"2026-07-08T15:32:28.382719Z","end":"2026-07-08T15:32:28.641716Z","steps":["trace[1891252464] 'read index received' (duration: 248.187851ms)","trace[1891252464] 'applied index is now lower than readState.Index' (duration: 10.807619ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:32:28.641899Z","caller":"traceutil/trace.go:171","msg":"trace[286233458] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"269.266046ms","start":"2026-07-08T15:32:28.37254Z","end":"2026-07-08T15:32:28.641806Z","steps":["trace[286233458] 'process raft request' (duration: 269.005759ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:32:28.642138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.025236ms","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-08T15:32:28.642212Z","caller":"traceutil/trace.go:171","msg":"trace[1121133655] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:692; }","duration":"258.147309ms","start":"2026-07-08T15:32:28.384056Z","end":"2026-07-08T15:32:28.642203Z","steps":["trace[1121133655] 'agreement among raft nodes before linearized reading' (duration: 257.991655ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:32:28.64219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.150079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-08T15:32:28.642325Z","caller":"traceutil/trace.go:171","msg":"trace[844358172] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:692; }","duration":"258.290932ms","start":"2026-07-08T15:32:28.383977Z","end":"2026-07-08T15:32:28.642268Z","steps":["trace[844358172] 'agreement among raft nodes before linearized reading' (duration: 258.046876ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:32:28.642341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.074849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-07-08T15:32:28.642395Z","caller":"traceutil/trace.go:171","msg":"trace[1597758973] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:692; }","duration":"180.181441ms","start":"2026-07-08T15:32:28.462192Z","end":"2026-07-08T15:32:28.642373Z","steps":["trace[1597758973] 'agreement among raft nodes before linearized reading' (duration: 179.835482ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:32:28.642423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.266657ms","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-08T15:32:28.642487Z","caller":"traceutil/trace.go:171","msg":"trace[532887902] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:692; }","duration":"259.772929ms","start":"2026-07-08T15:32:28.382692Z","end":"2026-07-08T15:32:28.642465Z","steps":["trace[532887902] 'agreement among raft nodes before linearized reading' (duration: 259.164424ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:32:39.926842Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1958677995294751157,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T15:32:40.380498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.071294876s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:32:40.380727Z","caller":"traceutil/trace.go:171","msg":"trace[1225551748] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:778; }","duration":"1.071555353s","start":"2026-07-08T15:32:39.309147Z","end":"2026-07-08T15:32:40.380702Z","steps":["trace[1225551748] 'count revisions from in-memory index tree' (duration: 1.071161373s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:32:40.380812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:32:39.309122Z","time spent":"1.071672805s","remote":"127.0.0.1:58706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-07-08T15:32:40.427491Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1958677995294751157,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T15:32:40.928163Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1958677995294751157,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T15:32:41.22926Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.839349616s","expected-duration":"1s"} {"level":"info","ts":"2026-07-08T15:32:41.229777Z","caller":"traceutil/trace.go:171","msg":"trace[584248439] linearizableReadLoop","detail":"{readStateIndex:812; appliedIndex:811; }","duration":"1.803174861s","start":"2026-07-08T15:32:39.426573Z","end":"2026-07-08T15:32:41.229748Z","steps":["trace[584248439] 'read index received' (duration: 1.802868503s)","trace[584248439] 'applied index is now lower than readState.Index' (duration: 305.038µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:32:41.22992Z","caller":"traceutil/trace.go:171","msg":"trace[639001672] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"1.840222639s","start":"2026-07-08T15:32:39.389666Z","end":"2026-07-08T15:32:41.229889Z","steps":["trace[639001672] 'process raft request' (duration: 1.8398823s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:32:41.230025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.803435208s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-08T15:32:41.230065Z","caller":"traceutil/trace.go:171","msg":"trace[1732592064] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:779; }","duration":"1.803532971s","start":"2026-07-08T15:32:39.42652Z","end":"2026-07-08T15:32:41.230053Z","steps":["trace[1732592064] 'agreement among raft nodes before linearized reading' (duration: 1.803388817s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:32:41.230069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:32:39.389628Z","time spent":"1.840363933s","remote":"127.0.0.1:58794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T15:32:41.230106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:32:39.426497Z","time spent":"1.803598422s","remote":"127.0.0.1:58616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":74,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-07-08T15:32:41.230146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.595240142s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-08T15:32:41.230226Z","caller":"traceutil/trace.go:171","msg":"trace[532827152] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:779; }","duration":"1.595382575s","start":"2026-07-08T15:32:39.634821Z","end":"2026-07-08T15:32:41.230204Z","steps":["trace[532827152] 'agreement among raft nodes before linearized reading' (duration: 1.595231302s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:32:41.230233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.788367ms","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-08T15:32:41.230261Z","caller":"traceutil/trace.go:171","msg":"trace[1956104766] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:779; }","duration":"495.861369ms","start":"2026-07-08T15:32:40.734392Z","end":"2026-07-08T15:32:41.230253Z","steps":["trace[1956104766] 'agreement among raft nodes before linearized reading' (duration: 495.764276ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:32:41.230271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:32:39.634799Z","time spent":"1.595463278s","remote":"127.0.0.1:58962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":30,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-07-08T15:32:41.230318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.494971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:32:41.23034Z","caller":"traceutil/trace.go:171","msg":"trace[2013131148] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:779; }","duration":"381.559713ms","start":"2026-07-08T15:32:40.848772Z","end":"2026-07-08T15:32:41.230332Z","steps":["trace[2013131148] 'agreement among raft nodes before linearized reading' (duration: 381.526612ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:32:41.230358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:32:40.848752Z","time spent":"381.600453ms","remote":"127.0.0.1:37564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-07-08T15:32:41.230557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.261154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-08T15:32:41.230562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.274243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-08T15:32:41.2306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.091789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-08T15:32:41.230621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.22990676s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-08T15:32:41.230637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.512486ms","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-08T15:32:41.230639Z","caller":"traceutil/trace.go:171","msg":"trace[1048386271] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:779; }","duration":"473.162031ms","start":"2026-07-08T15:32:40.757466Z","end":"2026-07-08T15:32:41.230628Z","steps":["trace[1048386271] 'agreement among raft nodes before linearized reading' (duration: 473.030738ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:32:41.230657Z","caller":"traceutil/trace.go:171","msg":"trace[218252403] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:779; }","duration":"508.583069ms","start":"2026-07-08T15:32:40.722068Z","end":"2026-07-08T15:32:41.230651Z","steps":["trace[218252403] 'agreement among raft nodes before linearized reading' (duration: 508.523687ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:32:41.230661Z","caller":"traceutil/trace.go:171","msg":"trace[1449371466] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:779; }","duration":"1.229995502s","start":"2026-07-08T15:32:40.000654Z","end":"2026-07-08T15:32:41.230649Z","steps":["trace[1449371466] 'agreement among raft nodes before linearized reading' (duration: 1.2299138s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:32:41.230678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:32:40.72204Z","time spent":"508.633999ms","remote":"127.0.0.1:58822","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-08T15:32:41.230674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:32:40.757451Z","time spent":"473.215982ms","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-08T15:32:41.230693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:32:40.000621Z","time spent":"1.230063164s","remote":"127.0.0.1:58584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":31,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-07-08T15:32:41.230281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:32:40.734371Z","time spent":"495.905689ms","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-08T15:32:41.230831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.638932ms","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-08T15:32:41.230883Z","caller":"traceutil/trace.go:171","msg":"trace[1443235659] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:779; }","duration":"539.724944ms","start":"2026-07-08T15:32:40.691141Z","end":"2026-07-08T15:32:41.230866Z","steps":["trace[1443235659] 'agreement among raft nodes before linearized reading' (duration: 539.587751ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:32:41.230922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:32:40.69112Z","time spent":"539.792775ms","remote":"127.0.0.1:58822","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-08T15:32:41.230614Z","caller":"traceutil/trace.go:171","msg":"trace[1582179682] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:779; }","duration":"495.349875ms","start":"2026-07-08T15:32:40.735247Z","end":"2026-07-08T15:32:41.230597Z","steps":["trace[1582179682] 'agreement among raft nodes before linearized reading' (duration: 495.214172ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:32:41.232358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:32:40.735233Z","time spent":"497.10661ms","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-08T15:32:41.230578Z","caller":"traceutil/trace.go:171","msg":"trace[1379985534] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:779; }","duration":"560.326997ms","start":"2026-07-08T15:32:40.670244Z","end":"2026-07-08T15:32:41.230571Z","steps":["trace[1379985534] 'agreement among raft nodes before linearized reading' (duration: 560.292666ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:32:41.23249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:32:40.670214Z","time spent":"562.266265ms","remote":"127.0.0.1:58520","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-08T15:33:16.986307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.31490228s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:33:16.986434Z","caller":"traceutil/trace.go:171","msg":"trace[1877908041] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:996; }","duration":"1.315072474s","start":"2026-07-08T15:33:15.671338Z","end":"2026-07-08T15:33:16.98641Z","steps":["trace[1877908041] 'range keys from in-memory index tree' (duration: 1.314827147s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:33:16.986479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:33:15.67132Z","time spent":"1.315146506s","remote":"127.0.0.1:58520","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-08T15:33:16.986356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.889287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:33:16.986564Z","caller":"traceutil/trace.go:171","msg":"trace[1809704341] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:996; }","duration":"661.151685ms","start":"2026-07-08T15:33:16.325392Z","end":"2026-07-08T15:33:16.986543Z","steps":["trace[1809704341] 'count revisions from in-memory index tree' (duration: 660.739732ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:33:16.986624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:33:16.325371Z","time spent":"661.234048ms","remote":"127.0.0.1:58770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-07-08T15:33:17.945791Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1958677995294751619,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T15:33:18.446294Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1958677995294751619,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T15:33:18.592367Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.539928262s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-08T15:33:18.592818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:33:17.0522Z","time spent":"1.540613633s","remote":"127.0.0.1:58560","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-08T15:33:18.739197Z","caller":"traceutil/trace.go:171","msg":"trace[2047424320] linearizableReadLoop","detail":"{readStateIndex:1040; appliedIndex:1037; }","duration":"1.294045813s","start":"2026-07-08T15:33:17.445128Z","end":"2026-07-08T15:33:18.739174Z","steps":["trace[2047424320] 'read index received' (duration: 1.147477254s)","trace[2047424320] 'applied index is now lower than readState.Index' (duration: 146.567649ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:33:18.739247Z","caller":"traceutil/trace.go:171","msg":"trace[894671321] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"1.148293488s","start":"2026-07-08T15:33:17.590911Z","end":"2026-07-08T15:33:18.739204Z","steps":["trace[894671321] 'process raft request' (duration: 1.148070221s)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:33:18.739306Z","caller":"traceutil/trace.go:171","msg":"trace[88455836] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"870.834873ms","start":"2026-07-08T15:33:17.868461Z","end":"2026-07-08T15:33:18.739296Z","steps":["trace[88455836] 'process raft request' (duration: 870.665458ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:33:18.739445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:33:17.868423Z","time spent":"870.918666ms","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T15:33:18.73953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:33:17.590884Z","time spent":"1.148468513s","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T15:33:18.73954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.294403723s","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-08T15:33:18.739699Z","caller":"traceutil/trace.go:171","msg":"trace[1612334102] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:998; }","duration":"1.294593309s","start":"2026-07-08T15:33:17.445085Z","end":"2026-07-08T15:33:18.739678Z","steps":["trace[1612334102] 'agreement among raft nodes before linearized reading' (duration: 1.29431377s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:33:18.739743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:33:17.445067Z","time spent":"1.294665001s","remote":"127.0.0.1:58822","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-08T15:33:18.740081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.29229063s","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-08T15:33:18.740119Z","caller":"traceutil/trace.go:171","msg":"trace[992140296] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:999; }","duration":"1.292340022s","start":"2026-07-08T15:33:17.447769Z","end":"2026-07-08T15:33:18.740109Z","steps":["trace[992140296] 'agreement among raft nodes before linearized reading' (duration: 1.292220909s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:33:18.740145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:33:17.447764Z","time spent":"1.292375013s","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-08T15:33:18.740221Z","caller":"traceutil/trace.go:171","msg":"trace[1998824421] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"145.651572ms","start":"2026-07-08T15:33:18.594559Z","end":"2026-07-08T15:33:18.740211Z","steps":["trace[1998824421] 'process raft request' (duration: 145.365034ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:33:18.740457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.285798028s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-08T15:33:18.740468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.280579055s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-08T15:33:18.740526Z","caller":"traceutil/trace.go:171","msg":"trace[520854170] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:999; }","duration":"1.280650227s","start":"2026-07-08T15:33:17.459861Z","end":"2026-07-08T15:33:18.740511Z","steps":["trace[520854170] 'agreement among raft nodes before linearized reading' (duration: 1.280411349s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:33:18.74057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.286949484s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-08T15:33:18.740574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:33:17.459855Z","time spent":"1.280707698s","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-08T15:33:18.740602Z","caller":"traceutil/trace.go:171","msg":"trace[1204742096] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:999; }","duration":"1.286999184s","start":"2026-07-08T15:33:17.453594Z","end":"2026-07-08T15:33:18.740594Z","steps":["trace[1204742096] 'agreement among raft nodes before linearized reading' (duration: 1.286908412s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:33:18.740812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:33:17.453578Z","time spent":"1.287223872s","remote":"127.0.0.1:58822","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-08T15:33:18.740485Z","caller":"traceutil/trace.go:171","msg":"trace[901773187] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:999; }","duration":"1.285873411s","start":"2026-07-08T15:33:17.454603Z","end":"2026-07-08T15:33:18.740476Z","steps":["trace[901773187] 'agreement among raft nodes before linearized reading' (duration: 1.285787818s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:33:18.74091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:33:17.454587Z","time spent":"1.286311733s","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-08T15:33:49.420699Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1958677995294751870,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T15:33:49.862905Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.00873488s","expected-duration":"1s"} {"level":"info","ts":"2026-07-08T15:33:49.863592Z","caller":"traceutil/trace.go:171","msg":"trace[1033687875] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"1.009830369s","start":"2026-07-08T15:33:48.853723Z","end":"2026-07-08T15:33:49.863553Z","steps":["trace[1033687875] 'process raft request' (duration: 1.009582883s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:33:49.863897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:33:48.853686Z","time spent":"1.010059374s","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T15:33:50.218901Z","caller":"traceutil/trace.go:171","msg":"trace[1700004511] linearizableReadLoop","detail":"{readStateIndex:1146; appliedIndex:1144; }","duration":"1.298560509s","start":"2026-07-08T15:33:48.920284Z","end":"2026-07-08T15:33:50.218844Z","steps":["trace[1700004511] 'read index received' (duration: 942.976133ms)","trace[1700004511] 'applied index is now lower than readState.Index' (duration: 355.583266ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:33:50.219043Z","caller":"traceutil/trace.go:171","msg":"trace[88568561] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"880.301129ms","start":"2026-07-08T15:33:49.338725Z","end":"2026-07-08T15:33:50.219026Z","steps":["trace[88568561] 'process raft request' (duration: 879.729284ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:33:50.219203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:33:49.338691Z","time spent":"880.391641ms","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T15:33:50.219325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.045695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-08T15:33:50.219411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.219894298s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-08T15:33:50.219445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.220820763s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-08T15:33:50.219491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.228018099s","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-08T15:33:50.219534Z","caller":"traceutil/trace.go:171","msg":"trace[337600595] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1098; }","duration":"1.220935085s","start":"2026-07-08T15:33:48.99858Z","end":"2026-07-08T15:33:50.219515Z","steps":["trace[337600595] 'agreement among raft nodes before linearized reading' (duration: 1.220669558s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:33:50.219565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.534014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:33:50.219567Z","caller":"traceutil/trace.go:171","msg":"trace[1026795774] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1098; }","duration":"1.228134842s","start":"2026-07-08T15:33:48.991413Z","end":"2026-07-08T15:33:50.219548Z","steps":["trace[1026795774] 'agreement among raft nodes before linearized reading' (duration: 1.227734052s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:33:50.219587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:33:48.998569Z","time spent":"1.221007548s","remote":"127.0.0.1:58822","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-08T15:33:50.219621Z","caller":"traceutil/trace.go:171","msg":"trace[1410758879] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1098; }","duration":"286.651827ms","start":"2026-07-08T15:33:49.932955Z","end":"2026-07-08T15:33:50.219607Z","steps":["trace[1410758879] 'agreement among raft nodes before linearized reading' (duration: 286.557084ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:33:50.219475Z","caller":"traceutil/trace.go:171","msg":"trace[1498142441] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1098; }","duration":"1.220001422s","start":"2026-07-08T15:33:48.999458Z","end":"2026-07-08T15:33:50.219459Z","steps":["trace[1498142441] 'agreement among raft nodes before linearized reading' (duration: 1.219812707s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:33:50.219642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:33:48.991392Z","time spent":"1.228235456s","remote":"127.0.0.1:58822","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-08T15:33:50.219692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:33:48.99945Z","time spent":"1.220220828s","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-08T15:33:50.219472Z","caller":"traceutil/trace.go:171","msg":"trace[1485549237] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1098; }","duration":"544.286741ms","start":"2026-07-08T15:33:49.675153Z","end":"2026-07-08T15:33:50.21944Z","steps":["trace[1485549237] 'agreement among raft nodes before linearized reading' (duration: 544.013984ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:33:50.21981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:33:49.675118Z","time spent":"544.671152ms","remote":"127.0.0.1:58520","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-08T15:33:50.220051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.221680225s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-08T15:33:50.220248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.299968156s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-08T15:33:50.220168Z","caller":"traceutil/trace.go:171","msg":"trace[96961438] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1098; }","duration":"1.22188149s","start":"2026-07-08T15:33:48.998262Z","end":"2026-07-08T15:33:50.220143Z","steps":["trace[96961438] 'agreement among raft nodes before linearized reading' (duration: 1.221216572s)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:33:50.220303Z","caller":"traceutil/trace.go:171","msg":"trace[990027828] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1098; }","duration":"1.300066969s","start":"2026-07-08T15:33:48.920223Z","end":"2026-07-08T15:33:50.22029Z","steps":["trace[990027828] 'agreement among raft nodes before linearized reading' (duration: 1.298862107s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:33:50.220801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:33:48.920197Z","time spent":"1.300588922s","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-08T15:33:50.222901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:33:48.998239Z","time spent":"1.222231839s","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-08T15:33:52.244975Z","caller":"traceutil/trace.go:171","msg":"trace[1374825661] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"373.793287ms","start":"2026-07-08T15:33:51.871051Z","end":"2026-07-08T15:33:52.244845Z","steps":["trace[1374825661] 'process raft request' (duration: 373.635163ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:33:52.245287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:33:51.871014Z","time spent":"374.169487ms","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T15:33:52.546327Z","caller":"traceutil/trace.go:171","msg":"trace[901179253] linearizableReadLoop","detail":"{readStateIndex:1153; appliedIndex:1152; }","duration":"304.127039ms","start":"2026-07-08T15:33:52.242172Z","end":"2026-07-08T15:33:52.546299Z","steps":["trace[901179253] 'read index received' (duration: 2.76396ms)","trace[901179253] 'applied index is now lower than readState.Index' (duration: 301.361439ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:33:52.546792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.605243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-08T15:33:52.546883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.839219ms","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-08T15:33:52.546841Z","caller":"traceutil/trace.go:171","msg":"trace[711423602] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1104; }","duration":"304.726356ms","start":"2026-07-08T15:33:52.242103Z","end":"2026-07-08T15:33:52.546829Z","steps":["trace[711423602] 'agreement among raft nodes before linearized reading' (duration: 304.348766ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:33:52.546914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.750247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-08T15:33:52.546941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.084809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-08T15:33:52.546997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:33:52.242075Z","time spent":"304.908911ms","remote":"127.0.0.1:58822","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-08T15:33:52.546989Z","caller":"traceutil/trace.go:171","msg":"trace[527432001] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1104; }","duration":"263.025074ms","start":"2026-07-08T15:33:52.283938Z","end":"2026-07-08T15:33:52.546963Z","steps":["trace[527432001] 'agreement among raft nodes before linearized reading' (duration: 262.604433ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:33:52.547021Z","caller":"traceutil/trace.go:171","msg":"trace[1230435735] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1104; }","duration":"262.922701ms","start":"2026-07-08T15:33:52.284069Z","end":"2026-07-08T15:33:52.546992Z","steps":["trace[1230435735] 'agreement among raft nodes before linearized reading' (duration: 262.607483ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:33:52.546871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.162701ms","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-08T15:33:52.547157Z","caller":"traceutil/trace.go:171","msg":"trace[1174066381] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1104; }","duration":"262.259764ms","start":"2026-07-08T15:33:52.28476Z","end":"2026-07-08T15:33:52.54702Z","steps":["trace[1174066381] 'agreement among raft nodes before linearized reading' (duration: 261.897534ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:33:52.547161Z","caller":"traceutil/trace.go:171","msg":"trace[22958813] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1104; }","duration":"262.51223ms","start":"2026-07-08T15:33:52.284629Z","end":"2026-07-08T15:33:52.547141Z","steps":["trace[22958813] 'agreement among raft nodes before linearized reading' (duration: 261.967566ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:33:52.677614Z","caller":"traceutil/trace.go:171","msg":"trace[275024486] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"123.933895ms","start":"2026-07-08T15:33:52.553651Z","end":"2026-07-08T15:33:52.677584Z","steps":["trace[275024486] 'process raft request' (duration: 123.819192ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:33:52.67773Z","caller":"traceutil/trace.go:171","msg":"trace[1991615567] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"123.700639ms","start":"2026-07-08T15:33:52.554009Z","end":"2026-07-08T15:33:52.67771Z","steps":["trace[1991615567] 'process raft request' (duration: 123.514424ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:33:52.677662Z","caller":"traceutil/trace.go:171","msg":"trace[2054390936] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"124.777987ms","start":"2026-07-08T15:33:52.552801Z","end":"2026-07-08T15:33:52.677579Z","steps":["trace[2054390936] 'process raft request' (duration: 124.567042ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:33:52.677962Z","caller":"traceutil/trace.go:171","msg":"trace[403738433] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"124.13337ms","start":"2026-07-08T15:33:52.553814Z","end":"2026-07-08T15:33:52.677947Z","steps":["trace[403738433] 'process raft request' (duration: 123.684358ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:33:54.588389Z","caller":"traceutil/trace.go:171","msg":"trace[387340444] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"605.855868ms","start":"2026-07-08T15:33:53.982502Z","end":"2026-07-08T15:33:54.588358Z","steps":["trace[387340444] 'process raft request' (duration: 605.674353ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:33:54.588526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:33:53.982468Z","time spent":"605.993701ms","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T15:33:54.610905Z","caller":"traceutil/trace.go:171","msg":"trace[1390658857] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"358.515461ms","start":"2026-07-08T15:33:54.252355Z","end":"2026-07-08T15:33:54.610871Z","steps":["trace[1390658857] 'process raft request' (duration: 358.320066ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:33:54.611122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:33:54.25232Z","time spent":"358.667505ms","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T15:33:54.930773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.45066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:33:54.930933Z","caller":"traceutil/trace.go:171","msg":"trace[1164014139] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1111; }","duration":"257.648915ms","start":"2026-07-08T15:33:54.673221Z","end":"2026-07-08T15:33:54.93087Z","steps":["trace[1164014139] 'range keys from in-memory index tree' (duration: 257.355247ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:33:54.930875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.241399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-08T15:33:54.931038Z","caller":"traceutil/trace.go:171","msg":"trace[1652082880] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1111; }","duration":"248.454905ms","start":"2026-07-08T15:33:54.682556Z","end":"2026-07-08T15:33:54.931011Z","steps":["trace[1652082880] 'range keys from in-memory index tree' (duration: 247.999234ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:33:54.931183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.836924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-08T15:33:54.931287Z","caller":"traceutil/trace.go:171","msg":"trace[1200669901] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1111; }","duration":"284.006298ms","start":"2026-07-08T15:33:54.647257Z","end":"2026-07-08T15:33:54.931263Z","steps":["trace[1200669901] 'range keys from in-memory index tree' (duration: 283.559087ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:33:54.931664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.521845ms","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-08T15:33:54.931723Z","caller":"traceutil/trace.go:171","msg":"trace[563487746] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1111; }","duration":"249.596836ms","start":"2026-07-08T15:33:54.68211Z","end":"2026-07-08T15:33:54.931707Z","steps":["trace[563487746] 'range keys from in-memory index tree' (duration: 249.415531ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:33:54.931757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.247936ms","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-08T15:33:54.93182Z","caller":"traceutil/trace.go:171","msg":"trace[1493542100] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1111; }","duration":"249.321859ms","start":"2026-07-08T15:33:54.682486Z","end":"2026-07-08T15:33:54.931808Z","steps":["trace[1493542100] 'range keys from in-memory index tree' (duration: 249.02029ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:33:54.932706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.390906ms","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-08T15:33:54.9328Z","caller":"traceutil/trace.go:171","msg":"trace[1178526051] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1111; }","duration":"250.55799ms","start":"2026-07-08T15:33:54.68222Z","end":"2026-07-08T15:33:54.932778Z","steps":["trace[1178526051] 'range keys from in-memory index tree' (duration: 249.126443ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:33:55.309004Z","caller":"traceutil/trace.go:171","msg":"trace[161442271] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"370.03899ms","start":"2026-07-08T15:33:54.938938Z","end":"2026-07-08T15:33:55.308977Z","steps":["trace[161442271] 'process raft request' (duration: 369.970488ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:33:55.309286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:33:54.938885Z","time spent":"370.209964ms","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T15:33:55.309287Z","caller":"traceutil/trace.go:171","msg":"trace[1617109645] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"370.653126ms","start":"2026-07-08T15:33:54.938549Z","end":"2026-07-08T15:33:55.309202Z","steps":["trace[1617109645] 'process raft request' (duration: 370.200264ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:33:55.309576Z","caller":"traceutil/trace.go:171","msg":"trace[755944648] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"371.250661ms","start":"2026-07-08T15:33:54.938296Z","end":"2026-07-08T15:33:55.309547Z","steps":["trace[755944648] 'process raft request' (duration: 354.698502ms)","trace[755944648] 'compare' (duration: 14.63493ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:33:55.309674Z","caller":"traceutil/trace.go:171","msg":"trace[760698674] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"370.81699ms","start":"2026-07-08T15:33:54.938842Z","end":"2026-07-08T15:33:55.309659Z","steps":["trace[760698674] 'process raft request' (duration: 369.961368ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:33:55.309683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:33:54.938526Z","time spent":"370.941833ms","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T15:33:55.30973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:33:54.938267Z","time spent":"371.377774ms","remote":"127.0.0.1:58822","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-08T15:33:55.309748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:33:54.938811Z","time spent":"370.896313ms","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T15:34:13.941075Z","caller":"traceutil/trace.go:171","msg":"trace[265481028] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"526.590292ms","start":"2026-07-08T15:34:13.41446Z","end":"2026-07-08T15:34:13.941051Z","steps":["trace[265481028] 'process raft request' (duration: 526.14792ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:34:13.941201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:34:13.414445Z","time spent":"526.687435ms","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T15:34:13.945662Z","caller":"traceutil/trace.go:171","msg":"trace[1049736951] linearizableReadLoop","detail":"{readStateIndex:1291; appliedIndex:1287; }","duration":"101.682978ms","start":"2026-07-08T15:34:13.84396Z","end":"2026-07-08T15:34:13.945643Z","steps":["trace[1049736951] 'read index received' (duration: 96.981946ms)","trace[1049736951] 'applied index is now lower than readState.Index' (duration: 4.700022ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:34:13.945736Z","caller":"traceutil/trace.go:171","msg":"trace[601485648] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"531.088368ms","start":"2026-07-08T15:34:13.41461Z","end":"2026-07-08T15:34:13.945699Z","steps":["trace[601485648] 'process raft request' (duration: 530.623846ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:34:13.945768Z","caller":"traceutil/trace.go:171","msg":"trace[806815434] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"530.993556ms","start":"2026-07-08T15:34:13.414739Z","end":"2026-07-08T15:34:13.945733Z","steps":["trace[806815434] 'process raft request' (duration: 530.612936ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:34:13.945822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.841582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-08T15:34:13.945851Z","caller":"traceutil/trace.go:171","msg":"trace[128457677] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1236; }","duration":"101.922654ms","start":"2026-07-08T15:34:13.84392Z","end":"2026-07-08T15:34:13.945842Z","steps":["trace[128457677] 'agreement among raft nodes before linearized reading' (duration: 101.792071ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:34:13.945928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:34:13.414598Z","time spent":"531.216652ms","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T15:34:13.945934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:34:13.414705Z","time spent":"531.12932ms","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T15:34:13.946082Z","caller":"traceutil/trace.go:171","msg":"trace[1729300266] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"530.156964ms","start":"2026-07-08T15:34:13.415916Z","end":"2026-07-08T15:34:13.946073Z","steps":["trace[1729300266] 'process raft request' (duration: 529.474156ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:34:13.946132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:34:13.415904Z","time spent":"530.203865ms","remote":"127.0.0.1:58822","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-08T15:34:16.692874Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1958677995294752183,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-08T15:34:16.865632Z","caller":"traceutil/trace.go:171","msg":"trace[351463629] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"914.3085ms","start":"2026-07-08T15:34:15.951291Z","end":"2026-07-08T15:34:16.865599Z","steps":["trace[351463629] 'process raft request' (duration: 914.000162ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:34:16.86586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:34:15.951269Z","time spent":"914.450744ms","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T15:34:18.671831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999950567s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-08T15:34:18.671916Z","caller":"traceutil/trace.go:171","msg":"trace[1198003966] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000086551s","start":"2026-07-08T15:34:16.671812Z","end":"2026-07-08T15:34:18.671899Z","steps":["trace[1198003966] 'agreement among raft nodes before linearized reading' (duration: 1.999986218s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:34:18.672103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:34:16.67179Z","time spent":"2.000158562s","remote":"127.0.0.1:58520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/08 15:34:18 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-08T15:34:18.74471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.470771268s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:34:18.744856Z","caller":"traceutil/trace.go:171","msg":"trace[270138809] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1240; }","duration":"1.470925902s","start":"2026-07-08T15:34:17.27391Z","end":"2026-07-08T15:34:18.744835Z","steps":["trace[270138809] 'range keys from in-memory index tree' (duration: 1.470756968s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:34:18.7457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.846536757s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T15:34:18.74594Z","caller":"traceutil/trace.go:171","msg":"trace[1991301631] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"2.793240608s","start":"2026-07-08T15:34:15.952679Z","end":"2026-07-08T15:34:18.745919Z","steps":["trace[1991301631] 'process raft request' (duration: 946.357492ms)","trace[1991301631] 'compare' (duration: 1.844998298s)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:34:18.746041Z","caller":"traceutil/trace.go:171","msg":"trace[1346652397] linearizableReadLoop","detail":"{readStateIndex:1298; appliedIndex:1295; }","duration":"2.557648715s","start":"2026-07-08T15:34:16.188369Z","end":"2026-07-08T15:34:18.746017Z","steps":["trace[1346652397] 'read index received' (duration: 677.25178ms)","trace[1346652397] 'applied index is now lower than readState.Index' (duration: 1.880395675s)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:34:18.746159Z","caller":"traceutil/trace.go:171","msg":"trace[1156090592] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"2.050043107s","start":"2026-07-08T15:34:16.696103Z","end":"2026-07-08T15:34:18.746146Z","steps":["trace[1156090592] 'process raft request' (duration: 2.049863561s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:34:18.746214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:34:16.696086Z","time spent":"2.050101668s","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T15:34:18.746228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.557863572s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:34:18.746274Z","caller":"traceutil/trace.go:171","msg":"trace[672027058] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1244; }","duration":"2.557961965s","start":"2026-07-08T15:34:16.188297Z","end":"2026-07-08T15:34:18.746259Z","steps":["trace[672027058] 'agreement among raft nodes before linearized reading' (duration: 2.557852652s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:34:18.746319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:34:16.188265Z","time spent":"2.558044377s","remote":"127.0.0.1:58870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"info","ts":"2026-07-08T15:34:18.746353Z","caller":"traceutil/trace.go:171","msg":"trace[693593970] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"2.792202671s","start":"2026-07-08T15:34:15.954142Z","end":"2026-07-08T15:34:18.746345Z","steps":["trace[693593970] 'process raft request' (duration: 2.791782809s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:34:18.746408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:34:15.954129Z","time spent":"2.792250193s","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T15:34:18.746053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:34:15.952629Z","time spent":"2.79334966s","remote":"127.0.0.1:58822","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-08T15:34:18.746751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.149316912s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:34:18.746811Z","caller":"traceutil/trace.go:171","msg":"trace[829633835] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1244; }","duration":"2.149420945s","start":"2026-07-08T15:34:16.597377Z","end":"2026-07-08T15:34:18.746797Z","steps":["trace[829633835] 'agreement among raft nodes before linearized reading' (duration: 2.149326613s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:34:18.746868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:34:16.597354Z","time spent":"2.149495158s","remote":"127.0.0.1:59048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"info","ts":"2026-07-08T15:34:18.747117Z","caller":"traceutil/trace.go:171","msg":"trace[987982989] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"2.793159655s","start":"2026-07-08T15:34:15.953944Z","end":"2026-07-08T15:34:18.747104Z","steps":["trace[987982989] 'process raft request' (duration: 2.791907952s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:34:18.747192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.185144288s","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:7"} {"level":"info","ts":"2026-07-08T15:34:18.747269Z","caller":"traceutil/trace.go:171","msg":"trace[1390935924] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1244; }","duration":"1.18525484s","start":"2026-07-08T15:34:17.561995Z","end":"2026-07-08T15:34:18.74725Z","steps":["trace[1390935924] 'agreement among raft nodes before linearized reading' (duration: 1.185126877s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:34:18.747335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:34:17.561981Z","time spent":"1.185332243s","remote":"127.0.0.1:56970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"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-08T15:34:18.74731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:34:15.953901Z","time spent":"2.793247528s","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T15:34:18.747776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.693215338s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.112\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-08T15:34:18.74782Z","caller":"traceutil/trace.go:171","msg":"trace[1305391183] range","detail":"{range_begin:/registry/masterleases/199.19.213.112; range_end:; response_count:1; response_revision:1244; }","duration":"1.69329307s","start":"2026-07-08T15:34:17.054518Z","end":"2026-07-08T15:34:18.747811Z","steps":["trace[1305391183] 'agreement among raft nodes before linearized reading' (duration: 1.693144927s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:34:18.747852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:34:17.054501Z","time spent":"1.693342862s","remote":"127.0.0.1:58560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.112\" "} {"level":"warn","ts":"2026-07-08T15:34:18.747932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.641275102s","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-08T15:34:18.748027Z","caller":"traceutil/trace.go:171","msg":"trace[498856959] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1244; }","duration":"1.641431546s","start":"2026-07-08T15:34:17.106569Z","end":"2026-07-08T15:34:18.748Z","steps":["trace[498856959] 'agreement among raft nodes before linearized reading' (duration: 1.641220701s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:34:18.748074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:34:17.10654Z","time spent":"1.641524348s","remote":"127.0.0.1:58822","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-08T15:34:32.913065Z","caller":"traceutil/trace.go:171","msg":"trace[484501462] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"103.279574ms","start":"2026-07-08T15:34:32.809753Z","end":"2026-07-08T15:34:32.913033Z","steps":["trace[484501462] 'process raft request' (duration: 45.84845ms)","trace[484501462] 'compare' (duration: 56.810917ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:34:34.881716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.254156326s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T15:34:34.882328Z","caller":"traceutil/trace.go:171","msg":"trace[782536079] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"1.962372269s","start":"2026-07-08T15:34:32.919908Z","end":"2026-07-08T15:34:34.882281Z","steps":["trace[782536079] 'process raft request' (duration: 1.961945697s)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:34:34.882449Z","caller":"traceutil/trace.go:171","msg":"trace[610236328] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"1.962454571s","start":"2026-07-08T15:34:32.919958Z","end":"2026-07-08T15:34:34.882412Z","steps":["trace[610236328] 'process raft request' (duration: 1.962056181s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:34:34.88265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:34:32.919953Z","time spent":"1.962586074s","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T15:34:34.882686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:34:32.919897Z","time spent":"1.962523423s","remote":"127.0.0.1:58822","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-08T15:34:34.882681Z","caller":"traceutil/trace.go:171","msg":"trace[1902219196] linearizableReadLoop","detail":"{readStateIndex:1364; appliedIndex:1360; }","duration":"1.920018091s","start":"2026-07-08T15:34:32.962611Z","end":"2026-07-08T15:34:34.882629Z","steps":["trace[1902219196] 'read index received' (duration: 18.604188ms)","trace[1902219196] 'applied index is now lower than readState.Index' (duration: 1.901377792s)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:34:34.883278Z","caller":"traceutil/trace.go:171","msg":"trace[1800622582] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"1.963542309s","start":"2026-07-08T15:34:32.919052Z","end":"2026-07-08T15:34:34.882594Z","steps":["trace[1800622582] 'process raft request' (duration: 708.387656ms)","trace[1800622582] 'compare' (duration: 1.253071808s)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:34:34.883393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:34:32.919018Z","time spent":"1.964325819s","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T15:34:34.973605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.693055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-08T15:34:34.973689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.270940765s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:34:34.973706Z","caller":"traceutil/trace.go:171","msg":"trace[359468796] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1306; }","duration":"360.880269ms","start":"2026-07-08T15:34:34.612803Z","end":"2026-07-08T15:34:34.973683Z","steps":["trace[359468796] 'agreement among raft nodes before linearized reading' (duration: 360.693664ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:34:34.97373Z","caller":"traceutil/trace.go:171","msg":"trace[887758354] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1306; }","duration":"1.271088669s","start":"2026-07-08T15:34:33.702628Z","end":"2026-07-08T15:34:34.973717Z","steps":["trace[887758354] 'agreement among raft nodes before linearized reading' (duration: 1.271034068s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:34:34.973753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:34:34.612776Z","time spent":"360.965582ms","remote":"127.0.0.1:56858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-07-08T15:34:34.973761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:34:33.702598Z","time spent":"1.271154281s","remote":"127.0.0.1:56862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-07-08T15:34:34.973759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.151256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-08T15:34:34.973782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.011169427s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-08T15:34:34.973808Z","caller":"traceutil/trace.go:171","msg":"trace[1782571498] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1306; }","duration":"238.260588ms","start":"2026-07-08T15:34:34.735533Z","end":"2026-07-08T15:34:34.973794Z","steps":["trace[1782571498] 'agreement among raft nodes before linearized reading' (duration: 238.175526ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:34:34.97383Z","caller":"traceutil/trace.go:171","msg":"trace[164829576] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1306; }","duration":"2.011257229s","start":"2026-07-08T15:34:32.962554Z","end":"2026-07-08T15:34:34.973811Z","steps":["trace[164829576] 'agreement among raft nodes before linearized reading' (duration: 1.920207185s)","trace[164829576] 'range keys from in-memory index tree' (duration: 90.630212ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:34:34.973869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:34:32.962513Z","time spent":"2.011346531s","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-08T15:34:34.973604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"981.940119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-08T15:34:34.97394Z","caller":"traceutil/trace.go:171","msg":"trace[519273311] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1306; }","duration":"982.342639ms","start":"2026-07-08T15:34:33.99157Z","end":"2026-07-08T15:34:34.973912Z","steps":["trace[519273311] 'agreement among raft nodes before linearized reading' (duration: 981.942549ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:34:34.974019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:34:33.99149Z","time spent":"982.507103ms","remote":"127.0.0.1:48088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":4,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-07-08T15:34:34.974522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.302176844s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:34:34.974566Z","caller":"traceutil/trace.go:171","msg":"trace[179783349] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1306; }","duration":"1.302280777s","start":"2026-07-08T15:34:33.672274Z","end":"2026-07-08T15:34:34.974555Z","steps":["trace[179783349] 'agreement among raft nodes before linearized reading' (duration: 1.301198548s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:34:34.974594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:34:33.672242Z","time spent":"1.302344359s","remote":"127.0.0.1:58520","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-08T15:34:35.072685Z","caller":"traceutil/trace.go:171","msg":"trace[2050880412] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"152.075292ms","start":"2026-07-08T15:34:34.920575Z","end":"2026-07-08T15:34:35.072651Z","steps":["trace[2050880412] 'process raft request' (duration: 151.833965ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:34:35.184547Z","caller":"traceutil/trace.go:171","msg":"trace[1099256087] linearizableReadLoop","detail":"{readStateIndex:1367; appliedIndex:1366; }","duration":"111.310364ms","start":"2026-07-08T15:34:35.073208Z","end":"2026-07-08T15:34:35.184518Z","steps":["trace[1099256087] 'read index received' (duration: 88.585089ms)","trace[1099256087] 'applied index is now lower than readState.Index' (duration: 22.724305ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:34:35.184761Z","caller":"traceutil/trace.go:171","msg":"trace[1809293243] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"203.682333ms","start":"2026-07-08T15:34:34.981029Z","end":"2026-07-08T15:34:35.184711Z","steps":["trace[1809293243] 'process raft request' (duration: 180.749282ms)","trace[1809293243] 'compare' (duration: 22.601091ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:34:35.184832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.847ms","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-08T15:34:35.184884Z","caller":"traceutil/trace.go:171","msg":"trace[1220079972] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1308; }","duration":"197.964573ms","start":"2026-07-08T15:34:34.986912Z","end":"2026-07-08T15:34:35.184876Z","steps":["trace[1220079972] 'agreement among raft nodes before linearized reading' (duration: 197.736427ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:34:35.249217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.589919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:34:35.249328Z","caller":"traceutil/trace.go:171","msg":"trace[1372432680] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1309; }","duration":"164.770234ms","start":"2026-07-08T15:34:35.084533Z","end":"2026-07-08T15:34:35.249303Z","steps":["trace[1372432680] 'agreement among raft nodes before linearized reading' (duration: 164.549728ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:34:35.249625Z","caller":"traceutil/trace.go:171","msg":"trace[1346794505] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"126.320808ms","start":"2026-07-08T15:34:35.123277Z","end":"2026-07-08T15:34:35.249598Z","steps":["trace[1346794505] 'process raft request' (duration: 125.631099ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:34:36.624419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.64194ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T15:34:36.624572Z","caller":"traceutil/trace.go:171","msg":"trace[225978689] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"848.600298ms","start":"2026-07-08T15:34:35.775947Z","end":"2026-07-08T15:34:36.624547Z","steps":["trace[225978689] 'process raft request' (duration: 243.707141ms)","trace[225978689] 'compare' (duration: 604.352842ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:34:36.624647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:34:35.77591Z","time spent":"848.704731ms","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T15:34:37.174162Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1958677995294752356,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T15:34:37.635776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.73138ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:34:37.635932Z","caller":"traceutil/trace.go:171","msg":"trace[1944781898] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1312; }","duration":"361.895595ms","start":"2026-07-08T15:34:37.27401Z","end":"2026-07-08T15:34:37.635906Z","steps":["trace[1944781898] 'range keys from in-memory index tree' (duration: 361.71265ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:34:37.67499Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1958677995294752356,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T15:34:37.738861Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.449785798s","expected-duration":"1s"} {"level":"info","ts":"2026-07-08T15:34:37.739522Z","caller":"traceutil/trace.go:171","msg":"trace[1051447287] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"1.450533907s","start":"2026-07-08T15:34:36.288957Z","end":"2026-07-08T15:34:37.739491Z","steps":["trace[1051447287] 'process raft request' (duration: 1.45030104s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:34:37.739734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:34:36.288932Z","time spent":"1.450720533s","remote":"127.0.0.1:58616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":783,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T15:34:37.801495Z","caller":"traceutil/trace.go:171","msg":"trace[1911192084] linearizableReadLoop","detail":"{readStateIndex:1373; appliedIndex:1371; }","duration":"1.128303951s","start":"2026-07-08T15:34:36.673169Z","end":"2026-07-08T15:34:37.801473Z","steps":["trace[1911192084] 'read index received' (duration: 1.065997689s)","trace[1911192084] 'applied index is now lower than readState.Index' (duration: 62.304592ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:34:37.801813Z","caller":"traceutil/trace.go:171","msg":"trace[1970032442] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"720.665548ms","start":"2026-07-08T15:34:37.081132Z","end":"2026-07-08T15:34:37.801797Z","steps":["trace[1970032442] 'process raft request' (duration: 720.211527ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:34:37.801937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:34:37.081097Z","time spent":"720.74786ms","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T15:34:37.80221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.129042161s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:34:37.802261Z","caller":"traceutil/trace.go:171","msg":"trace[1652156394] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1314; }","duration":"1.129148283s","start":"2026-07-08T15:34:36.673101Z","end":"2026-07-08T15:34:37.802249Z","steps":["trace[1652156394] 'agreement among raft nodes before linearized reading' (duration: 1.128991699s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:34:37.802303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:34:36.673045Z","time spent":"1.129251746s","remote":"127.0.0.1:58520","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-08T15:34:37.983898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.756168ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-08T15:34:37.984006Z","caller":"traceutil/trace.go:171","msg":"trace[2088148693] linearizableReadLoop","detail":"{readStateIndex:1374; appliedIndex:1373; }","duration":"182.427867ms","start":"2026-07-08T15:34:37.801561Z","end":"2026-07-08T15:34:37.983989Z","steps":["trace[2088148693] 'read index received' (duration: 81.423082ms)","trace[2088148693] 'applied index is now lower than readState.Index' (duration: 101.003604ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:34:37.984115Z","caller":"traceutil/trace.go:171","msg":"trace[897451283] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"239.046418ms","start":"2026-07-08T15:34:37.745057Z","end":"2026-07-08T15:34:37.984104Z","steps":["trace[897451283] 'process raft request' (duration: 138.019453ms)","trace[897451283] 'compare' (duration: 100.315547ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:34:37.984525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.095917763s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-08T15:34:37.984521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.632771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-08T15:34:37.984521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.095761828s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-08T15:34:37.984543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"793.944176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-08T15:34:37.984612Z","caller":"traceutil/trace.go:171","msg":"trace[43155121] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1315; }","duration":"1.095911103s","start":"2026-07-08T15:34:36.888682Z","end":"2026-07-08T15:34:37.984593Z","steps":["trace[43155121] 'agreement among raft nodes before linearized reading' (duration: 1.095595035s)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:34:37.98461Z","caller":"traceutil/trace.go:171","msg":"trace[782016978] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1315; }","duration":"698.791405ms","start":"2026-07-08T15:34:37.285795Z","end":"2026-07-08T15:34:37.984587Z","steps":["trace[782016978] 'agreement among raft nodes before linearized reading' (duration: 698.557349ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:34:37.984628Z","caller":"traceutil/trace.go:171","msg":"trace[2130848383] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1315; }","duration":"794.08314ms","start":"2026-07-08T15:34:37.190525Z","end":"2026-07-08T15:34:37.984608Z","steps":["trace[2130848383] 'agreement among raft nodes before linearized reading' (duration: 793.778232ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:34:37.984612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.095143473s","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-08T15:34:37.984604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.461789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-08T15:34:37.984656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:34:37.285753Z","time spent":"698.894498ms","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-08T15:34:37.984682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:34:37.190498Z","time spent":"794.174722ms","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-08T15:34:37.98471Z","caller":"traceutil/trace.go:171","msg":"trace[1462957902] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1315; }","duration":"1.095253525s","start":"2026-07-08T15:34:36.889425Z","end":"2026-07-08T15:34:37.984679Z","steps":["trace[1462957902] 'agreement among raft nodes before linearized reading' (duration: 1.094970398s)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:34:37.984709Z","caller":"traceutil/trace.go:171","msg":"trace[942377641] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1315; }","duration":"179.636604ms","start":"2026-07-08T15:34:37.805053Z","end":"2026-07-08T15:34:37.98469Z","steps":["trace[942377641] 'agreement among raft nodes before linearized reading' (duration: 179.460599ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:34:37.984656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:34:36.88866Z","time spent":"1.095987555s","remote":"127.0.0.1:58822","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-08T15:34:37.984774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:34:36.889412Z","time spent":"1.095344228s","remote":"127.0.0.1:58822","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-08T15:34:37.984556Z","caller":"traceutil/trace.go:171","msg":"trace[624842493] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1315; }","duration":"1.096006755s","start":"2026-07-08T15:34:36.888539Z","end":"2026-07-08T15:34:37.984546Z","steps":["trace[624842493] 'agreement among raft nodes before linearized reading' (duration: 1.095850901s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:34:37.9849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"928.344015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.112\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-07-08T15:34:37.984836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:34:36.888514Z","time spent":"1.096312883s","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-08T15:34:37.984944Z","caller":"traceutil/trace.go:171","msg":"trace[1990124394] range","detail":"{range_begin:/registry/masterleases/199.19.213.112; range_end:; response_count:1; response_revision:1315; }","duration":"928.420187ms","start":"2026-07-08T15:34:37.056513Z","end":"2026-07-08T15:34:37.984933Z","steps":["trace[1990124394] 'agreement among raft nodes before linearized reading' (duration: 927.860392ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:34:37.984975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:34:37.056482Z","time spent":"928.486479ms","remote":"127.0.0.1:58560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.112\" "} {"level":"warn","ts":"2026-07-08T15:34:38.439652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.924788ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-07-08T15:34:38.439736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:34:37.987129Z","time spent":"452.60384ms","remote":"127.0.0.1:58560","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-08T15:34:38.446493Z","caller":"traceutil/trace.go:171","msg":"trace[83375066] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"456.077172ms","start":"2026-07-08T15:34:37.990384Z","end":"2026-07-08T15:34:38.446462Z","steps":["trace[83375066] 'process raft request' (duration: 455.872376ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:34:38.44685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:34:37.99035Z","time spent":"456.334977ms","remote":"127.0.0.1:58822","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-08T15:34:38.446983Z","caller":"traceutil/trace.go:171","msg":"trace[1931923997] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"455.669921ms","start":"2026-07-08T15:34:37.99127Z","end":"2026-07-08T15:34:38.44694Z","steps":["trace[1931923997] 'process raft request' (duration: 455.152758ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:34:38.447185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:34:37.991256Z","time spent":"455.825225ms","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T15:34:38.447202Z","caller":"traceutil/trace.go:171","msg":"trace[1925695295] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"455.815414ms","start":"2026-07-08T15:34:37.991367Z","end":"2026-07-08T15:34:38.447182Z","steps":["trace[1925695295] 'process raft request' (duration: 455.26185ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:34:38.447324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:34:37.991357Z","time spent":"455.914027ms","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T15:34:38.447392Z","caller":"traceutil/trace.go:171","msg":"trace[1310394502] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"455.846076ms","start":"2026-07-08T15:34:37.991502Z","end":"2026-07-08T15:34:38.447348Z","steps":["trace[1310394502] 'process raft request' (duration: 455.465876ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:34:38.447453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:34:37.991494Z","time spent":"455.933708ms","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T15:34:38.447524Z","caller":"traceutil/trace.go:171","msg":"trace[708626560] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"455.217758ms","start":"2026-07-08T15:34:37.992295Z","end":"2026-07-08T15:34:38.447513Z","steps":["trace[708626560] 'process raft request' (duration: 454.813217ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:34:38.44759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:34:37.992284Z","time spent":"455.269469ms","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T15:34:38.447475Z","caller":"traceutil/trace.go:171","msg":"trace[1240369129] linearizableReadLoop","detail":"{readStateIndex:1380; appliedIndex:1374; }","duration":"453.459722ms","start":"2026-07-08T15:34:37.994002Z","end":"2026-07-08T15:34:38.447461Z","steps":["trace[1240369129] 'read index received' (duration: 144.74936ms)","trace[1240369129] 'applied index is now lower than readState.Index' (duration: 308.709082ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:34:38.447873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.864693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:34:38.447919Z","caller":"traceutil/trace.go:171","msg":"trace[1089568842] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1320; }","duration":"453.944895ms","start":"2026-07-08T15:34:37.993962Z","end":"2026-07-08T15:34:38.447907Z","steps":["trace[1089568842] 'agreement among raft nodes before linearized reading' (duration: 453.865993ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:34:38.447953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:34:37.993947Z","time spent":"453.998186ms","remote":"127.0.0.1:58520","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-08T15:34:38.452164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.738745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-08T15:34:38.452223Z","caller":"traceutil/trace.go:171","msg":"trace[1965749384] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1321; }","duration":"310.843778ms","start":"2026-07-08T15:34:38.141366Z","end":"2026-07-08T15:34:38.45221Z","steps":["trace[1965749384] 'agreement among raft nodes before linearized reading' (duration: 310.697435ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:34:38.45226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:34:38.141347Z","time spent":"310.90742ms","remote":"127.0.0.1:37578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":30,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-07-08T15:34:47.404769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.124729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:621"} {"level":"warn","ts":"2026-07-08T15:34:47.404781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.40293ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T15:34:47.404909Z","caller":"traceutil/trace.go:171","msg":"trace[880951402] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1388; }","duration":"237.292813ms","start":"2026-07-08T15:34:47.167547Z","end":"2026-07-08T15:34:47.40484Z","steps":["trace[880951402] 'range keys from in-memory index tree' (duration: 236.866232ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:34:47.404962Z","caller":"traceutil/trace.go:171","msg":"trace[587483725] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1388; }","duration":"131.591265ms","start":"2026-07-08T15:34:47.273329Z","end":"2026-07-08T15:34:47.40492Z","steps":["trace[587483725] 'range keys from in-memory index tree' (duration: 131.38381ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:34:47.976344Z","caller":"traceutil/trace.go:171","msg":"trace[301762647] transaction","detail":"{read_only:false; response_revision:1390; number_of_response:1; }","duration":"543.322675ms","start":"2026-07-08T15:34:47.432993Z","end":"2026-07-08T15:34:47.976316Z","steps":["trace[301762647] 'process raft request' (duration: 524.292347ms)","trace[301762647] 'compare' (duration: 18.911025ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:34:47.976489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:34:47.432974Z","time spent":"543.431777ms","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":513,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T15:34:47.979124Z","caller":"traceutil/trace.go:171","msg":"trace[1757456302] transaction","detail":"{read_only:false; response_revision:1391; number_of_response:1; }","duration":"119.701794ms","start":"2026-07-08T15:34:47.85941Z","end":"2026-07-08T15:34:47.979112Z","steps":["trace[1757456302] 'process raft request' (duration: 119.646713ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:34:48.668612Z","caller":"traceutil/trace.go:171","msg":"trace[826268231] linearizableReadLoop","detail":"{readStateIndex:1454; appliedIndex:1453; }","duration":"170.322999ms","start":"2026-07-08T15:34:48.498268Z","end":"2026-07-08T15:34:48.668591Z","steps":["trace[826268231] 'read index received' (duration: 170.137104ms)","trace[826268231] 'applied index is now lower than readState.Index' (duration: 184.435µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:34:48.66868Z","caller":"traceutil/trace.go:171","msg":"trace[2090029166] transaction","detail":"{read_only:false; response_revision:1392; number_of_response:1; }","duration":"310.221762ms","start":"2026-07-08T15:34:48.358399Z","end":"2026-07-08T15:34:48.668621Z","steps":["trace[2090029166] 'process raft request' (duration: 310.010547ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:34:48.668839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.559565ms","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-08T15:34:48.668909Z","caller":"traceutil/trace.go:171","msg":"trace[1723608440] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1392; }","duration":"170.688838ms","start":"2026-07-08T15:34:48.498212Z","end":"2026-07-08T15:34:48.668901Z","steps":["trace[1723608440] 'agreement among raft nodes before linearized reading' (duration: 170.502373ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:34:48.669095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.965413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-08T15:34:48.669179Z","caller":"traceutil/trace.go:171","msg":"trace[751271474] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1392; }","duration":"164.085826ms","start":"2026-07-08T15:34:48.505073Z","end":"2026-07-08T15:34:48.669158Z","steps":["trace[751271474] 'agreement among raft nodes before linearized reading' (duration: 163.837409ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:34:48.669197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.654047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-08T15:34:48.669259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.151049ms","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-08T15:34:48.669314Z","caller":"traceutil/trace.go:171","msg":"trace[1980393937] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1392; }","duration":"170.817432ms","start":"2026-07-08T15:34:48.49847Z","end":"2026-07-08T15:34:48.669287Z","steps":["trace[1980393937] 'agreement among raft nodes before linearized reading' (duration: 170.448643ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:34:48.669337Z","caller":"traceutil/trace.go:171","msg":"trace[1469780228] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1392; }","duration":"166.299053ms","start":"2026-07-08T15:34:48.503015Z","end":"2026-07-08T15:34:48.669314Z","steps":["trace[1469780228] 'agreement among raft nodes before linearized reading' (duration: 165.999785ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:34:48.669674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.165095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-08T15:34:48.669767Z","caller":"traceutil/trace.go:171","msg":"trace[802335425] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1392; }","duration":"167.280208ms","start":"2026-07-08T15:34:48.502464Z","end":"2026-07-08T15:34:48.669744Z","steps":["trace[802335425] 'agreement among raft nodes before linearized reading' (duration: 166.933069ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:34:48.669833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:34:48.358382Z","time spent":"310.431647ms","remote":"127.0.0.1:58822","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-08T15:34:48.880047Z","caller":"traceutil/trace.go:171","msg":"trace[319926130] transaction","detail":"{read_only:false; response_revision:1393; number_of_response:1; }","duration":"205.385347ms","start":"2026-07-08T15:34:48.674632Z","end":"2026-07-08T15:34:48.880017Z","steps":["trace[319926130] 'process raft request' (duration: 204.681939ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:34:48.882697Z","caller":"traceutil/trace.go:171","msg":"trace[1021307482] transaction","detail":"{read_only:false; response_revision:1394; number_of_response:1; }","duration":"207.343659ms","start":"2026-07-08T15:34:48.67532Z","end":"2026-07-08T15:34:48.882663Z","steps":["trace[1021307482] 'process raft request' (duration: 206.63294ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:34:48.882826Z","caller":"traceutil/trace.go:171","msg":"trace[2047842528] transaction","detail":"{read_only:false; response_revision:1397; number_of_response:1; }","duration":"207.351088ms","start":"2026-07-08T15:34:48.675461Z","end":"2026-07-08T15:34:48.882812Z","steps":["trace[2047842528] 'process raft request' (duration: 207.116672ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:34:48.882862Z","caller":"traceutil/trace.go:171","msg":"trace[404737781] transaction","detail":"{read_only:false; response_revision:1395; number_of_response:1; }","duration":"207.465622ms","start":"2026-07-08T15:34:48.675389Z","end":"2026-07-08T15:34:48.882855Z","steps":["trace[404737781] 'process raft request' (duration: 207.01525ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:34:48.882894Z","caller":"traceutil/trace.go:171","msg":"trace[201333183] transaction","detail":"{read_only:false; response_revision:1396; number_of_response:1; }","duration":"207.458161ms","start":"2026-07-08T15:34:48.675429Z","end":"2026-07-08T15:34:48.882887Z","steps":["trace[201333183] 'process raft request' (duration: 207.050471ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:34:48.882934Z","caller":"traceutil/trace.go:171","msg":"trace[526534651] transaction","detail":"{read_only:false; response_revision:1398; number_of_response:1; }","duration":"206.925097ms","start":"2026-07-08T15:34:48.676002Z","end":"2026-07-08T15:34:48.882927Z","steps":["trace[526534651] 'process raft request' (duration: 206.614289ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:34:48.882976Z","caller":"traceutil/trace.go:171","msg":"trace[1372879221] transaction","detail":"{read_only:false; response_revision:1399; number_of_response:1; }","duration":"204.202827ms","start":"2026-07-08T15:34:48.678765Z","end":"2026-07-08T15:34:48.882968Z","steps":["trace[1372879221] 'process raft request' (duration: 203.882638ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:36:16.482062Z","caller":"traceutil/trace.go:171","msg":"trace[1849941973] transaction","detail":"{read_only:false; response_revision:1839; number_of_response:1; }","duration":"153.128399ms","start":"2026-07-08T15:36:16.328904Z","end":"2026-07-08T15:36:16.482032Z","steps":["trace[1849941973] 'process raft request' (duration: 152.871222ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:36:21.671824Z","caller":"traceutil/trace.go:171","msg":"trace[582960017] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"157.325427ms","start":"2026-07-08T15:36:21.514461Z","end":"2026-07-08T15:36:21.671787Z","steps":["trace[582960017] 'process raft request' (duration: 72.431873ms)","trace[582960017] 'compare' (duration: 84.690569ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:36:21.672336Z","caller":"traceutil/trace.go:171","msg":"trace[1281422243] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"157.80579ms","start":"2026-07-08T15:36:21.514492Z","end":"2026-07-08T15:36:21.672298Z","steps":["trace[1281422243] 'process raft request' (duration: 157.249965ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:36:21.672983Z","caller":"traceutil/trace.go:171","msg":"trace[2019693730] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"157.80688ms","start":"2026-07-08T15:36:21.514619Z","end":"2026-07-08T15:36:21.672426Z","steps":["trace[2019693730] 'process raft request' (duration: 157.295767ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:36:31.990538Z","caller":"traceutil/trace.go:171","msg":"trace[1834416102] linearizableReadLoop","detail":"{readStateIndex:2002; appliedIndex:2000; }","duration":"119.066603ms","start":"2026-07-08T15:36:31.871447Z","end":"2026-07-08T15:36:31.990513Z","steps":["trace[1834416102] 'read index received' (duration: 64.388748ms)","trace[1834416102] 'applied index is now lower than readState.Index' (duration: 54.676515ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:36:31.9907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.234547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-08T15:36:31.990695Z","caller":"traceutil/trace.go:171","msg":"trace[326657488] transaction","detail":"{read_only:false; response_revision:1920; number_of_response:1; }","duration":"229.817459ms","start":"2026-07-08T15:36:31.760833Z","end":"2026-07-08T15:36:31.990651Z","steps":["trace[326657488] 'process raft request' (duration: 229.407509ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:36:31.99072Z","caller":"traceutil/trace.go:171","msg":"trace[184470690] transaction","detail":"{read_only:false; response_revision:1919; number_of_response:1; }","duration":"230.849365ms","start":"2026-07-08T15:36:31.759848Z","end":"2026-07-08T15:36:31.990698Z","steps":["trace[184470690] 'process raft request' (duration: 176.112199ms)","trace[184470690] 'compare' (duration: 54.047389ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:36:31.990742Z","caller":"traceutil/trace.go:171","msg":"trace[1712633185] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1920; }","duration":"119.34046ms","start":"2026-07-08T15:36:31.871386Z","end":"2026-07-08T15:36:31.990727Z","steps":["trace[1712633185] 'agreement among raft nodes before linearized reading' (duration: 119.217727ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:37:19.947459Z","caller":"traceutil/trace.go:171","msg":"trace[238212768] transaction","detail":"{read_only:false; response_revision:2160; number_of_response:1; }","duration":"114.791542ms","start":"2026-07-08T15:37:19.832608Z","end":"2026-07-08T15:37:19.947399Z","steps":["trace[238212768] 'process raft request' (duration: 114.503344ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:37:42.230786Z","caller":"traceutil/trace.go:171","msg":"trace[1193039288] transaction","detail":"{read_only:false; response_revision:2271; number_of_response:1; }","duration":"148.67801ms","start":"2026-07-08T15:37:42.082065Z","end":"2026-07-08T15:37:42.230743Z","steps":["trace[1193039288] 'process raft request' (duration: 148.421744ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:37:42.437044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.663431ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T15:37:42.43739Z","caller":"traceutil/trace.go:171","msg":"trace[440132355] transaction","detail":"{read_only:false; response_revision:2272; number_of_response:1; }","duration":"352.83901ms","start":"2026-07-08T15:37:42.084503Z","end":"2026-07-08T15:37:42.437342Z","steps":["trace[440132355] 'process raft request' (duration: 211.685615ms)","trace[440132355] 'compare' (duration: 140.490287ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:37:42.437621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:37:42.08448Z","time spent":"353.022495ms","remote":"127.0.0.1:58822","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-08T15:37:42.484018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.630169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:621"} {"level":"info","ts":"2026-07-08T15:37:42.484128Z","caller":"traceutil/trace.go:171","msg":"trace[792276532] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2272; }","duration":"252.816553ms","start":"2026-07-08T15:37:42.231289Z","end":"2026-07-08T15:37:42.484106Z","steps":["trace[792276532] 'agreement among raft nodes before linearized reading' (duration: 252.512425ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:37:42.483776Z","caller":"traceutil/trace.go:171","msg":"trace[139211399] linearizableReadLoop","detail":"{readStateIndex:2369; appliedIndex:2367; }","duration":"252.351992ms","start":"2026-07-08T15:37:42.231346Z","end":"2026-07-08T15:37:42.483698Z","steps":["trace[139211399] 'read index received' (duration: 64.879855ms)","trace[139211399] 'applied index is now lower than readState.Index' (duration: 187.469857ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:37:42.484873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.152193ms","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-08T15:37:42.484919Z","caller":"traceutil/trace.go:171","msg":"trace[1852609508] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2272; }","duration":"223.245625ms","start":"2026-07-08T15:37:42.261659Z","end":"2026-07-08T15:37:42.484905Z","steps":["trace[1852609508] 'agreement among raft nodes before linearized reading' (duration: 223.098081ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:37:42.669005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.488625ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T15:37:42.669235Z","caller":"traceutil/trace.go:171","msg":"trace[394246442] transaction","detail":"{read_only:false; response_revision:2275; number_of_response:1; }","duration":"178.433162ms","start":"2026-07-08T15:37:42.490784Z","end":"2026-07-08T15:37:42.669217Z","steps":["trace[394246442] 'process raft request' (duration: 178.326879ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:37:42.66928Z","caller":"traceutil/trace.go:171","msg":"trace[1483208569] transaction","detail":"{read_only:false; response_revision:2274; number_of_response:1; }","duration":"179.48811ms","start":"2026-07-08T15:37:42.489735Z","end":"2026-07-08T15:37:42.669223Z","steps":["trace[1483208569] 'process raft request' (duration: 77.707658ms)","trace[1483208569] 'compare' (duration: 101.33313ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:37:42.669333Z","caller":"traceutil/trace.go:171","msg":"trace[1292634162] transaction","detail":"{read_only:false; response_revision:2276; number_of_response:1; }","duration":"175.634679ms","start":"2026-07-08T15:37:42.493658Z","end":"2026-07-08T15:37:42.669293Z","steps":["trace[1292634162] 'process raft request' (duration: 175.504065ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:37:42.794783Z","caller":"traceutil/trace.go:171","msg":"trace[161403458] transaction","detail":"{read_only:false; response_revision:2277; number_of_response:1; }","duration":"118.584308ms","start":"2026-07-08T15:37:42.676182Z","end":"2026-07-08T15:37:42.794767Z","steps":["trace[161403458] 'process raft request' (duration: 117.151172ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:38:14.218929Z","caller":"traceutil/trace.go:171","msg":"trace[721693467] transaction","detail":"{read_only:false; response_revision:2432; number_of_response:1; }","duration":"103.730504ms","start":"2026-07-08T15:38:14.11516Z","end":"2026-07-08T15:38:14.218891Z","steps":["trace[721693467] 'process raft request' (duration: 103.465687ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:38:50.994727Z","caller":"traceutil/trace.go:171","msg":"trace[1782297773] linearizableReadLoop","detail":"{readStateIndex:2751; appliedIndex:2750; }","duration":"179.10869ms","start":"2026-07-08T15:38:50.815594Z","end":"2026-07-08T15:38:50.994703Z","steps":["trace[1782297773] 'read index received' (duration: 178.949805ms)","trace[1782297773] 'applied index is now lower than readState.Index' (duration: 157.674µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:38:50.99487Z","caller":"traceutil/trace.go:171","msg":"trace[428809572] transaction","detail":"{read_only:false; response_revision:2640; number_of_response:1; }","duration":"355.199991ms","start":"2026-07-08T15:38:50.63966Z","end":"2026-07-08T15:38:50.99486Z","steps":["trace[428809572] 'process raft request' (duration: 354.887583ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:38:50.995006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:38:50.639634Z","time spent":"355.267243ms","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T15:38:50.995474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.867449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-08T15:38:50.995555Z","caller":"traceutil/trace.go:171","msg":"trace[1861464585] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2640; }","duration":"179.982372ms","start":"2026-07-08T15:38:50.815556Z","end":"2026-07-08T15:38:50.995538Z","steps":["trace[1861464585] 'agreement among raft nodes before linearized reading' (duration: 179.740946ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:38:55.203437Z","caller":"traceutil/trace.go:171","msg":"trace[267332090] transaction","detail":"{read_only:false; response_revision:2666; number_of_response:1; }","duration":"110.900256ms","start":"2026-07-08T15:38:55.092499Z","end":"2026-07-08T15:38:55.2034Z","steps":["trace[267332090] 'process raft request' (duration: 23.937004ms)","trace[267332090] 'compare' (duration: 86.771326ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:38:55.226003Z","caller":"traceutil/trace.go:171","msg":"trace[216043999] transaction","detail":"{read_only:false; response_revision:2668; number_of_response:1; }","duration":"132.430956ms","start":"2026-07-08T15:38:55.093546Z","end":"2026-07-08T15:38:55.225977Z","steps":["trace[216043999] 'process raft request' (duration: 132.356884ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:38:55.226213Z","caller":"traceutil/trace.go:171","msg":"trace[894274823] transaction","detail":"{read_only:false; response_revision:2669; number_of_response:1; }","duration":"131.541712ms","start":"2026-07-08T15:38:55.094661Z","end":"2026-07-08T15:38:55.226202Z","steps":["trace[894274823] 'process raft request' (duration: 131.276185ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:38:55.226735Z","caller":"traceutil/trace.go:171","msg":"trace[1227085018] transaction","detail":"{read_only:false; response_revision:2667; number_of_response:1; }","duration":"133.239297ms","start":"2026-07-08T15:38:55.093424Z","end":"2026-07-08T15:38:55.226664Z","steps":["trace[1227085018] 'process raft request' (duration: 132.328543ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:38:57.54632Z","caller":"traceutil/trace.go:171","msg":"trace[1059371025] linearizableReadLoop","detail":"{readStateIndex:2788; appliedIndex:2787; }","duration":"452.315388ms","start":"2026-07-08T15:38:57.093971Z","end":"2026-07-08T15:38:57.546286Z","steps":["trace[1059371025] 'read index received' (duration: 452.087583ms)","trace[1059371025] 'applied index is now lower than readState.Index' (duration: 226.465µs)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:38:57.546391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:38:57.069498Z","time spent":"476.881267ms","remote":"127.0.0.1:58560","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-08T15:38:57.546614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.615176ms","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-08T15:38:57.546664Z","caller":"traceutil/trace.go:171","msg":"trace[210957567] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2675; }","duration":"452.748949ms","start":"2026-07-08T15:38:57.093904Z","end":"2026-07-08T15:38:57.546653Z","steps":["trace[210957567] 'agreement among raft nodes before linearized reading' (duration: 452.523103ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:38:57.546705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:38:57.093876Z","time spent":"452.820811ms","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-08T15:38:57.609722Z","caller":"traceutil/trace.go:171","msg":"trace[499067958] transaction","detail":"{read_only:false; response_revision:2676; number_of_response:1; }","duration":"323.101756ms","start":"2026-07-08T15:38:57.28658Z","end":"2026-07-08T15:38:57.609682Z","steps":["trace[499067958] 'process raft request' (duration: 322.385767ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:38:57.60997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:38:57.286544Z","time spent":"323.302951ms","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T15:38:57.610012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.281335ms","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-08T15:38:57.610144Z","caller":"traceutil/trace.go:171","msg":"trace[684929206] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2676; }","duration":"372.4767ms","start":"2026-07-08T15:38:57.237636Z","end":"2026-07-08T15:38:57.610112Z","steps":["trace[684929206] 'agreement among raft nodes before linearized reading' (duration: 372.104941ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:38:57.610143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.283236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-08T15:38:57.610206Z","caller":"traceutil/trace.go:171","msg":"trace[1024416396] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2676; }","duration":"372.362367ms","start":"2026-07-08T15:38:57.237832Z","end":"2026-07-08T15:38:57.610194Z","steps":["trace[1024416396] 'agreement among raft nodes before linearized reading' (duration: 372.199073ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:38:57.610219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:38:57.237614Z","time spent":"372.589604ms","remote":"127.0.0.1:58822","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-08T15:38:57.610261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:38:57.237825Z","time spent":"372.402138ms","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-08T15:38:57.610461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.142828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-08T15:38:57.610496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.467726ms","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-08T15:38:57.610542Z","caller":"traceutil/trace.go:171","msg":"trace[1028714773] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2676; }","duration":"373.292572ms","start":"2026-07-08T15:38:57.23723Z","end":"2026-07-08T15:38:57.610522Z","steps":["trace[1028714773] 'agreement among raft nodes before linearized reading' (duration: 373.059506ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:38:57.610557Z","caller":"traceutil/trace.go:171","msg":"trace[1271013097] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2676; }","duration":"401.567508ms","start":"2026-07-08T15:38:57.208971Z","end":"2026-07-08T15:38:57.610539Z","steps":["trace[1271013097] 'agreement among raft nodes before linearized reading' (duration: 401.395394ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:38:57.610596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:38:57.237198Z","time spent":"373.381143ms","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-08T15:38:57.610603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T15:38:57.208947Z","time spent":"401.64567ms","remote":"127.0.0.1:58822","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-08T15:38:57.760529Z","caller":"traceutil/trace.go:171","msg":"trace[2024817693] transaction","detail":"{read_only:false; response_revision:2679; number_of_response:1; }","duration":"144.040107ms","start":"2026-07-08T15:38:57.616455Z","end":"2026-07-08T15:38:57.760495Z","steps":["trace[2024817693] 'process raft request' (duration: 143.75923ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:38:57.76052Z","caller":"traceutil/trace.go:171","msg":"trace[269425206] transaction","detail":"{read_only:false; response_revision:2680; number_of_response:1; }","duration":"144.017186ms","start":"2026-07-08T15:38:57.616468Z","end":"2026-07-08T15:38:57.760485Z","steps":["trace[269425206] 'process raft request' (duration: 143.917713ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:38:57.760673Z","caller":"traceutil/trace.go:171","msg":"trace[474095873] transaction","detail":"{read_only:false; response_revision:2681; number_of_response:1; }","duration":"144.140779ms","start":"2026-07-08T15:38:57.616509Z","end":"2026-07-08T15:38:57.760649Z","steps":["trace[474095873] 'process raft request' (duration: 143.931514ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:38:57.761037Z","caller":"traceutil/trace.go:171","msg":"trace[12127361] transaction","detail":"{read_only:false; response_revision:2682; number_of_response:1; }","duration":"144.015998ms","start":"2026-07-08T15:38:57.617004Z","end":"2026-07-08T15:38:57.76102Z","steps":["trace[12127361] 'process raft request' (duration: 143.600626ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:38:57.761052Z","caller":"traceutil/trace.go:171","msg":"trace[1352205921] linearizableReadLoop","detail":"{readStateIndex:2795; appliedIndex:2791; }","duration":"123.133444ms","start":"2026-07-08T15:38:57.637822Z","end":"2026-07-08T15:38:57.760956Z","steps":["trace[1352205921] 'read index received' (duration: 122.316102ms)","trace[1352205921] 'applied index is now lower than readState.Index' (duration: 816.492µs)"],"step_count":2} {"level":"warn","ts":"2026-07-08T15:38:57.761217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.37487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-07-08T15:38:57.76125Z","caller":"traceutil/trace.go:171","msg":"trace[792545502] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:2682; }","duration":"123.481722ms","start":"2026-07-08T15:38:57.637758Z","end":"2026-07-08T15:38:57.76124Z","steps":["trace[792545502] 'agreement among raft nodes before linearized reading' (duration: 123.384ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:38:57.761385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.919945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-08T15:38:57.7616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.989074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:621"} {"level":"info","ts":"2026-07-08T15:38:57.761491Z","caller":"traceutil/trace.go:171","msg":"trace[89200010] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:2682; }","duration":"119.071458ms","start":"2026-07-08T15:38:57.642399Z","end":"2026-07-08T15:38:57.76147Z","steps":["trace[89200010] 'agreement among raft nodes before linearized reading' (duration: 118.853883ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:38:57.761767Z","caller":"traceutil/trace.go:171","msg":"trace[1846791493] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2682; }","duration":"112.170758ms","start":"2026-07-08T15:38:57.649571Z","end":"2026-07-08T15:38:57.761741Z","steps":["trace[1846791493] 'agreement among raft nodes before linearized reading' (duration: 111.770988ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T15:39:26.314024Z","caller":"traceutil/trace.go:171","msg":"trace[2027556625] linearizableReadLoop","detail":"{readStateIndex:2951; appliedIndex:2950; }","duration":"191.614574ms","start":"2026-07-08T15:39:26.122383Z","end":"2026-07-08T15:39:26.313998Z","steps":["trace[2027556625] 'read index received' (duration: 191.4479ms)","trace[2027556625] 'applied index is now lower than readState.Index' (duration: 165.204µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T15:39:26.314166Z","caller":"traceutil/trace.go:171","msg":"trace[1793491733] transaction","detail":"{read_only:false; response_revision:2833; number_of_response:1; }","duration":"222.59648ms","start":"2026-07-08T15:39:26.091518Z","end":"2026-07-08T15:39:26.314114Z","steps":["trace[1793491733] 'process raft request' (duration: 222.330783ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:39:26.314293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.892042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-2gnr4\" ","response":"range_response_count:1 size:4905"} {"level":"info","ts":"2026-07-08T15:39:26.31434Z","caller":"traceutil/trace.go:171","msg":"trace[1623561015] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-2gnr4; range_end:; response_count:1; response_revision:2833; }","duration":"192.002255ms","start":"2026-07-08T15:39:26.122326Z","end":"2026-07-08T15:39:26.314328Z","steps":["trace[1623561015] 'agreement among raft nodes before linearized reading' (duration: 191.83018ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:39:26.314446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.684749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-62k5k\" ","response":"range_response_count:1 size:4908"} {"level":"info","ts":"2026-07-08T15:39:26.31456Z","caller":"traceutil/trace.go:171","msg":"trace[2019863199] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-62k5k; range_end:; response_count:1; response_revision:2833; }","duration":"133.868043ms","start":"2026-07-08T15:39:26.180666Z","end":"2026-07-08T15:39:26.314534Z","steps":["trace[2019863199] 'agreement among raft nodes before linearized reading' (duration: 133.563315ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T15:39:42.458958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.117576ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"}