{"level":"warn","ts":"2026-07-01T00:43:59.435053Z","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-01T00:43:59.435181Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.19:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.19:2380","--initial-cluster=instance=https://199.204.45.19:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.19:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.19: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-01T00:43:59.435314Z","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-01T00:43:59.435333Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.19:2380"]} {"level":"info","ts":"2026-07-01T00:43:59.435367Z","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-01T00:43:59.436132Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.19:2379"]} {"level":"info","ts":"2026-07-01T00:43:59.436222Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.19:2380"],"listen-peer-urls":["https://199.204.45.19:2380"],"advertise-client-urls":["https://199.204.45.19:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.19:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.19: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-01T00:43:59.440462Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.980408ms"} {"level":"info","ts":"2026-07-01T00:43:59.446975Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a847c2e9c7010db6","cluster-id":"2b11a68e27507b05"} {"level":"info","ts":"2026-07-01T00:43:59.44707Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 switched to configuration voters=()"} {"level":"info","ts":"2026-07-01T00:43:59.447102Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became follower at term 0"} {"level":"info","ts":"2026-07-01T00:43:59.447118Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a847c2e9c7010db6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-01T00:43:59.447128Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became follower at term 1"} {"level":"info","ts":"2026-07-01T00:43:59.447178Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 switched to configuration voters=(12125874831040253366)"} {"level":"warn","ts":"2026-07-01T00:43:59.456721Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-01T00:43:59.459316Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-01T00:43:59.460345Z","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-01T00:43:59.462718Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a847c2e9c7010db6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-01T00:43:59.463077Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a847c2e9c7010db6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-01T00:43:59.464392Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 switched to configuration voters=(12125874831040253366)"} {"level":"info","ts":"2026-07-01T00:43:59.464539Z","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-01T00:43:59.464738Z","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-01T00:43:59.464813Z","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-01T00:43:59.465492Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2b11a68e27507b05","local-member-id":"a847c2e9c7010db6","added-peer-id":"a847c2e9c7010db6","added-peer-peer-urls":["https://199.204.45.19:2380"]} {"level":"info","ts":"2026-07-01T00:43:59.467739Z","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-01T00:43:59.467793Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.19:2380"} {"level":"info","ts":"2026-07-01T00:43:59.467926Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.19:2380"} {"level":"info","ts":"2026-07-01T00:43:59.468577Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a847c2e9c7010db6","initial-advertise-peer-urls":["https://199.204.45.19:2380"],"listen-peer-urls":["https://199.204.45.19:2380"],"advertise-client-urls":["https://199.204.45.19:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.19:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-01T00:43:59.468615Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-01T00:43:59.647614Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 is starting a new election at term 1"} {"level":"info","ts":"2026-07-01T00:43:59.64778Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-01T00:43:59.647871Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 received MsgPreVoteResp from a847c2e9c7010db6 at term 1"} {"level":"info","ts":"2026-07-01T00:43:59.647934Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became candidate at term 2"} {"level":"info","ts":"2026-07-01T00:43:59.647999Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 received MsgVoteResp from a847c2e9c7010db6 at term 2"} {"level":"info","ts":"2026-07-01T00:43:59.648048Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became leader at term 2"} {"level":"info","ts":"2026-07-01T00:43:59.648096Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a847c2e9c7010db6 elected leader a847c2e9c7010db6 at term 2"} {"level":"info","ts":"2026-07-01T00:43:59.649255Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a847c2e9c7010db6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.19:2379]}","request-path":"/0/members/a847c2e9c7010db6/attributes","cluster-id":"2b11a68e27507b05","publish-timeout":"7s"} {"level":"info","ts":"2026-07-01T00:43:59.649367Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-01T00:43:59.649566Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T00:43:59.64962Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-01T00:43:59.649653Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-01T00:43:59.64941Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-01T00:43:59.651791Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2b11a68e27507b05","local-member-id":"a847c2e9c7010db6","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T00:43:59.65205Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T00:43:59.652123Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T00:43:59.653266Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.19:2379"} {"level":"info","ts":"2026-07-01T00:43:59.654088Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-01T00:44:02.306257Z","caller":"traceutil/trace.go:171","msg":"trace[1799503530] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"147.581823ms","start":"2026-07-01T00:44:02.158649Z","end":"2026-07-01T00:44:02.306231Z","steps":["trace[1799503530] 'read index received' (duration: 125.341193ms)","trace[1799503530] 'applied index is now lower than readState.Index' (duration: 22.23957ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:44:02.306494Z","caller":"traceutil/trace.go:171","msg":"trace[1069458464] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"148.401144ms","start":"2026-07-01T00:44:02.158051Z","end":"2026-07-01T00:44:02.306452Z","steps":["trace[1069458464] 'process raft request' (duration: 126.067437ms)","trace[1069458464] 'compare' (duration: 21.97872ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:44:02.307465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.878712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-01T00:44:02.307586Z","caller":"traceutil/trace.go:171","msg":"trace[588837419] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-view; range_end:; response_count:0; response_revision:72; }","duration":"148.937386ms","start":"2026-07-01T00:44:02.158618Z","end":"2026-07-01T00:44:02.307555Z","steps":["trace[588837419] 'agreement among raft nodes before linearized reading' (duration: 147.785986ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:44:02.307638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.300026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-01T00:44:02.307794Z","caller":"traceutil/trace.go:171","msg":"trace[317677718] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:72; }","duration":"103.449641ms","start":"2026-07-01T00:44:02.204273Z","end":"2026-07-01T00:44:02.307723Z","steps":["trace[317677718] 'agreement among raft nodes before linearized reading' (duration: 102.136857ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:44:03.078424Z","caller":"traceutil/trace.go:171","msg":"trace[1693778750] transaction","detail":"{read_only:false; response_revision:102; number_of_response:1; }","duration":"125.143793ms","start":"2026-07-01T00:44:02.953253Z","end":"2026-07-01T00:44:03.078397Z","steps":["trace[1693778750] 'process raft request' (duration: 108.925714ms)","trace[1693778750] 'compare' (duration: 16.091954ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:44:03.289471Z","caller":"traceutil/trace.go:171","msg":"trace[878482259] transaction","detail":"{read_only:false; response_revision:106; number_of_response:1; }","duration":"116.920283ms","start":"2026-07-01T00:44:03.172513Z","end":"2026-07-01T00:44:03.289434Z","steps":["trace[878482259] 'process raft request' (duration: 110.787047ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:44:03.495979Z","caller":"traceutil/trace.go:171","msg":"trace[591992269] transaction","detail":"{read_only:false; response_revision:110; number_of_response:1; }","duration":"122.097781ms","start":"2026-07-01T00:44:03.373848Z","end":"2026-07-01T00:44:03.495945Z","steps":["trace[591992269] 'process raft request' (duration: 22.243029ms)","trace[591992269] 'compare' (duration: 99.717496ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:44:03.496436Z","caller":"traceutil/trace.go:171","msg":"trace[416550859] transaction","detail":"{read_only:false; response_revision:111; number_of_response:1; }","duration":"121.699374ms","start":"2026-07-01T00:44:03.374727Z","end":"2026-07-01T00:44:03.496427Z","steps":["trace[416550859] 'process raft request' (duration: 121.650575ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:44:04.415894Z","caller":"traceutil/trace.go:171","msg":"trace[583424092] linearizableReadLoop","detail":"{readStateIndex:139; appliedIndex:138; }","duration":"210.99382ms","start":"2026-07-01T00:44:04.204874Z","end":"2026-07-01T00:44:04.415868Z","steps":["trace[583424092] 'read index received' (duration: 127.191959ms)","trace[583424092] 'applied index is now lower than readState.Index' (duration: 83.800821ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:44:04.415962Z","caller":"traceutil/trace.go:171","msg":"trace[1750300366] transaction","detail":"{read_only:false; response_revision:135; number_of_response:1; }","duration":"280.791144ms","start":"2026-07-01T00:44:04.135145Z","end":"2026-07-01T00:44:04.415936Z","steps":["trace[1750300366] 'process raft request' (duration: 196.945764ms)","trace[1750300366] 'compare' (duration: 83.619667ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:44:04.416124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.225433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:44:04.416229Z","caller":"traceutil/trace.go:171","msg":"trace[1664535300] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:135; }","duration":"211.379438ms","start":"2026-07-01T00:44:04.204821Z","end":"2026-07-01T00:44:04.416201Z","steps":["trace[1664535300] 'agreement among raft nodes before linearized reading' (duration: 211.158335ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:44:06.166104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.058706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-07-01T00:44:06.166211Z","caller":"traceutil/trace.go:171","msg":"trace[1648318918] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:229; }","duration":"197.210732ms","start":"2026-07-01T00:44:05.968977Z","end":"2026-07-01T00:44:06.166187Z","steps":["trace[1648318918] 'range keys from in-memory index tree' (duration: 196.95661ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:44:06.402091Z","caller":"traceutil/trace.go:171","msg":"trace[1439017329] transaction","detail":"{read_only:false; response_revision:234; number_of_response:1; }","duration":"194.711237ms","start":"2026-07-01T00:44:06.207351Z","end":"2026-07-01T00:44:06.402062Z","steps":["trace[1439017329] 'process raft request' (duration: 179.332732ms)","trace[1439017329] 'compare' (duration: 15.259849ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:44:06.41341Z","caller":"traceutil/trace.go:171","msg":"trace[664125780] linearizableReadLoop","detail":"{readStateIndex:241; appliedIndex:239; }","duration":"117.173198ms","start":"2026-07-01T00:44:06.296218Z","end":"2026-07-01T00:44:06.413392Z","steps":["trace[664125780] 'read index received' (duration: 90.418005ms)","trace[664125780] 'applied index is now lower than readState.Index' (duration: 26.754293ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:44:06.413481Z","caller":"traceutil/trace.go:171","msg":"trace[287775980] transaction","detail":"{read_only:false; response_revision:235; number_of_response:1; }","duration":"192.82318ms","start":"2026-07-01T00:44:06.220636Z","end":"2026-07-01T00:44:06.413459Z","steps":["trace[287775980] 'process raft request' (duration: 192.675924ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:44:06.413663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.432911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3708"} {"level":"info","ts":"2026-07-01T00:44:06.413746Z","caller":"traceutil/trace.go:171","msg":"trace[1703356359] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:235; }","duration":"117.547448ms","start":"2026-07-01T00:44:06.296184Z","end":"2026-07-01T00:44:06.413732Z","steps":["trace[1703356359] 'agreement among raft nodes before linearized reading' (duration: 117.393622ms)"],"step_count":1} 2026/07/01 00:44:06 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-07-01T00:44:07.374642Z","caller":"traceutil/trace.go:171","msg":"trace[692360771] transaction","detail":"{read_only:false; response_revision:292; number_of_response:1; }","duration":"104.588466ms","start":"2026-07-01T00:44:07.270028Z","end":"2026-07-01T00:44:07.374617Z","steps":["trace[692360771] 'process raft request' (duration: 104.398431ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:44:07.375006Z","caller":"traceutil/trace.go:171","msg":"trace[308712006] transaction","detail":"{read_only:false; response_revision:293; number_of_response:1; }","duration":"104.79705ms","start":"2026-07-01T00:44:07.270189Z","end":"2026-07-01T00:44:07.374986Z","steps":["trace[308712006] 'process raft request' (duration: 104.329822ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:44:12.329034Z","caller":"traceutil/trace.go:171","msg":"trace[867338872] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"160.106919ms","start":"2026-07-01T00:44:12.168904Z","end":"2026-07-01T00:44:12.329011Z","steps":["trace[867338872] 'process raft request' (duration: 159.963652ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:44:18.274081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.639254ms","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-01T00:44:18.274191Z","caller":"traceutil/trace.go:171","msg":"trace[1331886125] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:400; }","duration":"191.769913ms","start":"2026-07-01T00:44:18.082405Z","end":"2026-07-01T00:44:18.274175Z","steps":["trace[1331886125] 'range keys from in-memory index tree' (duration: 191.622234ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:44:23.979927Z","caller":"traceutil/trace.go:171","msg":"trace[90574752] linearizableReadLoop","detail":"{readStateIndex:427; appliedIndex:426; }","duration":"425.790551ms","start":"2026-07-01T00:44:23.554117Z","end":"2026-07-01T00:44:23.979907Z","steps":["trace[90574752] 'read index received' (duration: 425.692932ms)","trace[90574752] 'applied index is now lower than readState.Index' (duration: 96.609µs)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:44:23.980042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.909021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:44:23.980059Z","caller":"traceutil/trace.go:171","msg":"trace[232917342] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:413; }","duration":"425.976101ms","start":"2026-07-01T00:44:23.554078Z","end":"2026-07-01T00:44:23.980054Z","steps":["trace[232917342] 'agreement among raft nodes before linearized reading' (duration: 425.928841ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:44:23.980037Z","caller":"traceutil/trace.go:171","msg":"trace[1834158075] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"810.584791ms","start":"2026-07-01T00:44:23.169414Z","end":"2026-07-01T00:44:23.979999Z","steps":["trace[1834158075] 'process raft request' (duration: 810.395342ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:44:23.980153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.072334ms","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-01T00:44:23.980206Z","caller":"traceutil/trace.go:171","msg":"trace[374469885] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:413; }","duration":"356.169144ms","start":"2026-07-01T00:44:23.624021Z","end":"2026-07-01T00:44:23.980191Z","steps":["trace[374469885] 'agreement among raft nodes before linearized reading' (duration: 356.064875ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:44:23.98008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:44:23.554058Z","time spent":"426.018301ms","remote":"127.0.0.1:59992","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-01T00:44:23.980242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:44:23.624Z","time spent":"356.232554ms","remote":"127.0.0.1:60286","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-01T00:44:23.980926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:44:23.169383Z","time spent":"810.77913ms","remote":"127.0.0.1:60286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T00:44:24.390871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.60535ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T00:44:24.390972Z","caller":"traceutil/trace.go:171","msg":"trace[996725636] linearizableReadLoop","detail":"{readStateIndex:428; appliedIndex:427; }","duration":"252.415968ms","start":"2026-07-01T00:44:24.13854Z","end":"2026-07-01T00:44:24.390956Z","steps":["trace[996725636] 'read index received' (duration: 88.430569ms)","trace[996725636] 'applied index is now lower than readState.Index' (duration: 163.984179ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:44:24.39104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.508268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-01T00:44:24.391068Z","caller":"traceutil/trace.go:171","msg":"trace[2006428160] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:414; }","duration":"252.572678ms","start":"2026-07-01T00:44:24.138486Z","end":"2026-07-01T00:44:24.391059Z","steps":["trace[2006428160] 'agreement among raft nodes before linearized reading' (duration: 252.514248ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:44:24.391044Z","caller":"traceutil/trace.go:171","msg":"trace[1654266328] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"406.399309ms","start":"2026-07-01T00:44:23.984601Z","end":"2026-07-01T00:44:24.391Z","steps":["trace[1654266328] 'process raft request' (duration: 242.42505ms)","trace[1654266328] 'compare' (duration: 163.49448ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:44:24.391424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:44:23.984586Z","time spent":"406.715318ms","remote":"127.0.0.1:60286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T00:44:26.331466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.579477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4374"} {"level":"info","ts":"2026-07-01T00:44:26.331578Z","caller":"traceutil/trace.go:171","msg":"trace[1710401676] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:419; }","duration":"109.751427ms","start":"2026-07-01T00:44:26.221806Z","end":"2026-07-01T00:44:26.331558Z","steps":["trace[1710401676] 'range keys from in-memory index tree' (duration: 109.404617ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:44:42.421055Z","caller":"traceutil/trace.go:171","msg":"trace[1771541648] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"406.152805ms","start":"2026-07-01T00:44:42.014875Z","end":"2026-07-01T00:44:42.421027Z","steps":["trace[1771541648] 'process raft request' (duration: 400.185953ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:44:42.421106Z","caller":"traceutil/trace.go:171","msg":"trace[3155152] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:615; }","duration":"396.486059ms","start":"2026-07-01T00:44:42.024557Z","end":"2026-07-01T00:44:42.421044Z","steps":["trace[3155152] 'read index received' (duration: 390.032009ms)","trace[3155152] 'applied index is now lower than readState.Index' (duration: 6.45106ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:44:42.421304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.893653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-01T00:44:42.42134Z","caller":"traceutil/trace.go:171","msg":"trace[2121463077] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:591; }","duration":"273.977774ms","start":"2026-07-01T00:44:42.147348Z","end":"2026-07-01T00:44:42.421325Z","steps":["trace[2121463077] 'agreement among raft nodes before linearized reading' (duration: 273.882922ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:44:42.421332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.750184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/tlsroutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:65214"} {"level":"info","ts":"2026-07-01T00:44:42.421401Z","caller":"traceutil/trace.go:171","msg":"trace[581838326] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/tlsroutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:591; }","duration":"396.856186ms","start":"2026-07-01T00:44:42.024527Z","end":"2026-07-01T00:44:42.421383Z","steps":["trace[581838326] 'agreement among raft nodes before linearized reading' (duration: 396.620742ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:44:42.421433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:44:42.024512Z","time spent":"396.914817ms","remote":"127.0.0.1:60052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":65237,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/tlsroutes.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-07-01T00:44:42.421558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:44:42.01484Z","time spent":"406.284867ms","remote":"127.0.0.1:60052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T00:44:48.837962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.790134ms","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-01T00:44:48.838046Z","caller":"traceutil/trace.go:171","msg":"trace[1830065552] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:637; }","duration":"149.931448ms","start":"2026-07-01T00:44:48.6881Z","end":"2026-07-01T00:44:48.838032Z","steps":["trace[1830065552] 'range keys from in-memory index tree' (duration: 149.651612ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:44:49.192431Z","caller":"traceutil/trace.go:171","msg":"trace[1012639709] linearizableReadLoop","detail":"{readStateIndex:664; appliedIndex:663; }","duration":"196.941795ms","start":"2026-07-01T00:44:48.995467Z","end":"2026-07-01T00:44:49.192408Z","steps":["trace[1012639709] 'read index received' (duration: 196.7451ms)","trace[1012639709] 'applied index is now lower than readState.Index' (duration: 195.785µs)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:44:49.192583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.092958ms","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-01T00:44:49.19257Z","caller":"traceutil/trace.go:171","msg":"trace[1105069242] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"350.226908ms","start":"2026-07-01T00:44:48.84231Z","end":"2026-07-01T00:44:49.192537Z","steps":["trace[1105069242] 'process raft request' (duration: 349.967032ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:44:49.192624Z","caller":"traceutil/trace.go:171","msg":"trace[351127744] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:638; }","duration":"197.15364ms","start":"2026-07-01T00:44:48.995459Z","end":"2026-07-01T00:44:49.192613Z","steps":["trace[351127744] 'agreement among raft nodes before linearized reading' (duration: 197.039677ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:44:49.192726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:44:48.842245Z","time spent":"350.402792ms","remote":"127.0.0.1:60286","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-01T00:44:50.776961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.846568ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-01T00:44:50.777068Z","caller":"traceutil/trace.go:171","msg":"trace[1950669137] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:665; }","duration":"255.435336ms","start":"2026-07-01T00:44:50.521615Z","end":"2026-07-01T00:44:50.77705Z","steps":["trace[1950669137] 'read index received' (duration: 95.413733ms)","trace[1950669137] 'applied index is now lower than readState.Index' (duration: 160.020453ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:44:50.777206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.342521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:44:50.777242Z","caller":"traceutil/trace.go:171","msg":"trace[1917603341] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:639; }","duration":"223.406852ms","start":"2026-07-01T00:44:50.553824Z","end":"2026-07-01T00:44:50.777231Z","steps":["trace[1917603341] 'agreement among raft nodes before linearized reading' (duration: 223.333891ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:44:50.777381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.724023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-01T00:44:50.777365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.753258ms","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-01T00:44:50.777636Z","caller":"traceutil/trace.go:171","msg":"trace[2142822296] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:639; }","duration":"187.061016ms","start":"2026-07-01T00:44:50.590549Z","end":"2026-07-01T00:44:50.777611Z","steps":["trace[2142822296] 'agreement among raft nodes before linearized reading' (duration: 186.661856ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:44:50.777503Z","caller":"traceutil/trace.go:171","msg":"trace[2087089588] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:639; }","duration":"255.878907ms","start":"2026-07-01T00:44:50.521568Z","end":"2026-07-01T00:44:50.777447Z","steps":["trace[2087089588] 'agreement among raft nodes before linearized reading' (duration: 255.566949ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:44:52.619502Z","caller":"traceutil/trace.go:171","msg":"trace[1546154525] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"123.199925ms","start":"2026-07-01T00:44:52.496268Z","end":"2026-07-01T00:44:52.619468Z","steps":["trace[1546154525] 'process raft request' (duration: 123.03151ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:45:03.3539Z","caller":"traceutil/trace.go:171","msg":"trace[240241898] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"100.500852ms","start":"2026-07-01T00:45:03.25337Z","end":"2026-07-01T00:45:03.353871Z","steps":["trace[240241898] 'process raft request' (duration: 99.84088ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:45:06.509489Z","caller":"traceutil/trace.go:171","msg":"trace[1699650484] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"343.260693ms","start":"2026-07-01T00:45:06.166202Z","end":"2026-07-01T00:45:06.509463Z","steps":["trace[1699650484] 'process raft request' (duration: 343.105557ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:45:06.509748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:45:06.166105Z","time spent":"343.46394ms","remote":"127.0.0.1:60286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T00:45:06.586603Z","caller":"traceutil/trace.go:171","msg":"trace[321485363] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"213.478796ms","start":"2026-07-01T00:45:06.373088Z","end":"2026-07-01T00:45:06.586567Z","steps":["trace[321485363] 'process raft request' (duration: 213.240827ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:45:10.498913Z","caller":"traceutil/trace.go:171","msg":"trace[553770704] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"110.985501ms","start":"2026-07-01T00:45:10.3879Z","end":"2026-07-01T00:45:10.498886Z","steps":["trace[553770704] 'process raft request' (duration: 110.734131ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:45:10.633292Z","caller":"traceutil/trace.go:171","msg":"trace[473933704] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"126.656861ms","start":"2026-07-01T00:45:10.506608Z","end":"2026-07-01T00:45:10.633265Z","steps":["trace[473933704] 'process raft request' (duration: 86.974061ms)","trace[473933704] 'compare' (duration: 39.536965ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:45:10.6333Z","caller":"traceutil/trace.go:171","msg":"trace[250663071] linearizableReadLoop","detail":"{readStateIndex:849; appliedIndex:848; }","duration":"111.134316ms","start":"2026-07-01T00:45:10.522133Z","end":"2026-07-01T00:45:10.633267Z","steps":["trace[250663071] 'read index received' (duration: 71.354213ms)","trace[250663071] 'applied index is now lower than readState.Index' (duration: 39.778883ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:45:10.633521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.376106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-01T00:45:10.633577Z","caller":"traceutil/trace.go:171","msg":"trace[1623731962] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:816; }","duration":"111.48345ms","start":"2026-07-01T00:45:10.52208Z","end":"2026-07-01T00:45:10.633563Z","steps":["trace[1623731962] 'agreement among raft nodes before linearized reading' (duration: 111.321993ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:45:10.634046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.276006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:45:10.634104Z","caller":"traceutil/trace.go:171","msg":"trace[1129453043] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:816; }","duration":"103.349238ms","start":"2026-07-01T00:45:10.530744Z","end":"2026-07-01T00:45:10.634093Z","steps":["trace[1129453043] 'agreement among raft nodes before linearized reading' (duration: 103.266395ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:45:10.634035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.39377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:45:10.634282Z","caller":"traceutil/trace.go:171","msg":"trace[1266408431] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:816; }","duration":"103.679941ms","start":"2026-07-01T00:45:10.530581Z","end":"2026-07-01T00:45:10.634261Z","steps":["trace[1266408431] 'agreement among raft nodes before linearized reading' (duration: 103.38481ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:45:10.755084Z","caller":"traceutil/trace.go:171","msg":"trace[662695869] linearizableReadLoop","detail":"{readStateIndex:851; appliedIndex:849; }","duration":"108.1019ms","start":"2026-07-01T00:45:10.646968Z","end":"2026-07-01T00:45:10.75507Z","steps":["trace[662695869] 'read index received' (duration: 23.76844ms)","trace[662695869] 'applied index is now lower than readState.Index' (duration: 84.33265ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:45:10.755299Z","caller":"traceutil/trace.go:171","msg":"trace[2016301324] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"115.153491ms","start":"2026-07-01T00:45:10.640138Z","end":"2026-07-01T00:45:10.755292Z","steps":["trace[2016301324] 'process raft request' (duration: 113.872442ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:45:10.755491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.492955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:45:10.755563Z","caller":"traceutil/trace.go:171","msg":"trace[444074833] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:818; }","duration":"108.60904ms","start":"2026-07-01T00:45:10.646937Z","end":"2026-07-01T00:45:10.755546Z","steps":["trace[444074833] 'agreement among raft nodes before linearized reading' (duration: 108.466394ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:45:10.755946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.458834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:45:10.756204Z","caller":"traceutil/trace.go:171","msg":"trace[1271636672] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:818; }","duration":"109.209562ms","start":"2026-07-01T00:45:10.64698Z","end":"2026-07-01T00:45:10.756189Z","steps":["trace[1271636672] 'agreement among raft nodes before linearized reading' (duration: 108.430102ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:45:12.10449Z","caller":"traceutil/trace.go:171","msg":"trace[391312974] linearizableReadLoop","detail":"{readStateIndex:890; appliedIndex:888; }","duration":"148.11804ms","start":"2026-07-01T00:45:11.956346Z","end":"2026-07-01T00:45:12.104464Z","steps":["trace[391312974] 'read index received' (duration: 17.772641ms)","trace[391312974] 'applied index is now lower than readState.Index' (duration: 130.344119ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:45:12.104528Z","caller":"traceutil/trace.go:171","msg":"trace[1901458605] transaction","detail":"{read_only:false; response_revision:857; number_of_response:1; }","duration":"156.087079ms","start":"2026-07-01T00:45:11.948407Z","end":"2026-07-01T00:45:12.104494Z","steps":["trace[1901458605] 'process raft request' (duration: 126.339625ms)","trace[1901458605] 'compare' (duration: 29.593738ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:45:12.104753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.38797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/\" range_end:\"/registry/serviceaccounts/envoy-gateway-system0\" limit:10000 ","response":"range_response_count:3 size:1862"} {"level":"info","ts":"2026-07-01T00:45:12.104815Z","caller":"traceutil/trace.go:171","msg":"trace[433002091] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/; range_end:/registry/serviceaccounts/envoy-gateway-system0; response_count:3; response_revision:857; }","duration":"148.488325ms","start":"2026-07-01T00:45:11.956314Z","end":"2026-07-01T00:45:12.104802Z","steps":["trace[433002091] 'agreement among raft nodes before linearized reading' (duration: 148.258455ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:45:23.067933Z","caller":"traceutil/trace.go:171","msg":"trace[20411309] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"101.766389ms","start":"2026-07-01T00:45:22.966141Z","end":"2026-07-01T00:45:23.067908Z","steps":["trace[20411309] 'process raft request' (duration: 101.55454ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:45:23.072742Z","caller":"traceutil/trace.go:171","msg":"trace[1619415829] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"105.75085ms","start":"2026-07-01T00:45:22.966957Z","end":"2026-07-01T00:45:23.072708Z","steps":["trace[1619415829] 'process raft request' (duration: 105.582923ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:45:29.324789Z","caller":"traceutil/trace.go:171","msg":"trace[1086513872] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"214.193203ms","start":"2026-07-01T00:45:29.110567Z","end":"2026-07-01T00:45:29.324761Z","steps":["trace[1086513872] 'process raft request' (duration: 213.160953ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:45:31.255911Z","caller":"traceutil/trace.go:171","msg":"trace[908251830] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"122.428898ms","start":"2026-07-01T00:45:31.133444Z","end":"2026-07-01T00:45:31.255879Z","steps":["trace[908251830] 'process raft request' (duration: 122.173525ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:45:31.770636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.374983ms","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-01T00:45:31.770646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.337975ms","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-01T00:45:31.770728Z","caller":"traceutil/trace.go:171","msg":"trace[1607768258] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1007; }","duration":"243.50735ms","start":"2026-07-01T00:45:31.527198Z","end":"2026-07-01T00:45:31.770705Z","steps":["trace[1607768258] 'range keys from in-memory index tree' (duration: 243.178894ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:45:31.770744Z","caller":"traceutil/trace.go:171","msg":"trace[1915364797] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1007; }","duration":"217.521155ms","start":"2026-07-01T00:45:31.553198Z","end":"2026-07-01T00:45:31.770719Z","steps":["trace[1915364797] 'count revisions from in-memory index tree' (duration: 217.250341ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:45:31.770904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.471873ms","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-01T00:45:31.770973Z","caller":"traceutil/trace.go:171","msg":"trace[1490657402] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1007; }","duration":"249.580588ms","start":"2026-07-01T00:45:31.521376Z","end":"2026-07-01T00:45:31.770957Z","steps":["trace[1490657402] 'range keys from in-memory index tree' (duration: 249.307194ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:45:31.904708Z","caller":"traceutil/trace.go:171","msg":"trace[564352034] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"129.522076ms","start":"2026-07-01T00:45:31.775144Z","end":"2026-07-01T00:45:31.904666Z","steps":["trace[564352034] 'process raft request' (duration: 126.096698ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:45:31.904896Z","caller":"traceutil/trace.go:171","msg":"trace[507098689] linearizableReadLoop","detail":"{readStateIndex:1047; appliedIndex:1045; }","duration":"101.024538ms","start":"2026-07-01T00:45:31.803593Z","end":"2026-07-01T00:45:31.904618Z","steps":["trace[507098689] 'read index received' (duration: 40.691007ms)","trace[507098689] 'applied index is now lower than readState.Index' (duration: 60.332451ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:45:31.905018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.398677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-01T00:45:31.905085Z","caller":"traceutil/trace.go:171","msg":"trace[1184169461] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1009; }","duration":"101.537542ms","start":"2026-07-01T00:45:31.803538Z","end":"2026-07-01T00:45:31.905076Z","steps":["trace[1184169461] 'agreement among raft nodes before linearized reading' (duration: 101.408196ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:45:35.040903Z","caller":"traceutil/trace.go:171","msg":"trace[1022690062] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"166.602551ms","start":"2026-07-01T00:45:34.874232Z","end":"2026-07-01T00:45:35.040835Z","steps":["trace[1022690062] 'process raft request' (duration: 166.37001ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:45:35.478517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.783714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-01T00:45:35.47859Z","caller":"traceutil/trace.go:171","msg":"trace[799288413] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1019; }","duration":"205.877779ms","start":"2026-07-01T00:45:35.272693Z","end":"2026-07-01T00:45:35.47857Z","steps":["trace[799288413] 'range keys from in-memory index tree' (duration: 205.599604ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:45:35.478519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.35255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-01T00:45:35.478731Z","caller":"traceutil/trace.go:171","msg":"trace[235083886] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1019; }","duration":"110.607014ms","start":"2026-07-01T00:45:35.368097Z","end":"2026-07-01T00:45:35.478704Z","steps":["trace[235083886] 'range keys from in-memory index tree' (duration: 110.158121ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:45:35.63732Z","caller":"traceutil/trace.go:171","msg":"trace[1225551153] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"154.08059ms","start":"2026-07-01T00:45:35.483203Z","end":"2026-07-01T00:45:35.637283Z","steps":["trace[1225551153] 'process raft request' (duration: 117.23952ms)","trace[1225551153] 'compare' (duration: 36.589357ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:45:46.155262Z","caller":"traceutil/trace.go:171","msg":"trace[1995152430] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"150.505307ms","start":"2026-07-01T00:45:46.004735Z","end":"2026-07-01T00:45:46.15524Z","steps":["trace[1995152430] 'process raft request' (duration: 136.081746ms)","trace[1995152430] 'compare' (duration: 14.254101ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:45:54.402772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.147685ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T00:45:54.403099Z","caller":"traceutil/trace.go:171","msg":"trace[1640113599] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"178.060055ms","start":"2026-07-01T00:45:54.225007Z","end":"2026-07-01T00:45:54.403067Z","steps":["trace[1640113599] 'process raft request' (duration: 63.525108ms)","trace[1640113599] 'compare' (duration: 114.005597ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:45:55.399248Z","caller":"traceutil/trace.go:171","msg":"trace[974481853] linearizableReadLoop","detail":"{readStateIndex:1133; appliedIndex:1132; }","duration":"236.355573ms","start":"2026-07-01T00:45:55.162868Z","end":"2026-07-01T00:45:55.399224Z","steps":["trace[974481853] 'read index received' (duration: 236.191154ms)","trace[974481853] 'applied index is now lower than readState.Index' (duration: 163.449µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:45:55.399269Z","caller":"traceutil/trace.go:171","msg":"trace[1206094082] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"341.039156ms","start":"2026-07-01T00:45:55.058208Z","end":"2026-07-01T00:45:55.399247Z","steps":["trace[1206094082] 'process raft request' (duration: 340.833615ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:45:55.399398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:45:55.058192Z","time spent":"341.133591ms","remote":"127.0.0.1:60024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T00:45:55.399506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.630759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-01T00:45:55.399569Z","caller":"traceutil/trace.go:171","msg":"trace[846665279] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1089; }","duration":"236.727455ms","start":"2026-07-01T00:45:55.162824Z","end":"2026-07-01T00:45:55.399552Z","steps":["trace[846665279] 'agreement among raft nodes before linearized reading' (duration: 236.487761ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:45:55.91473Z","caller":"traceutil/trace.go:171","msg":"trace[1984508337] linearizableReadLoop","detail":"{readStateIndex:1135; appliedIndex:1134; }","duration":"129.705467ms","start":"2026-07-01T00:45:55.785Z","end":"2026-07-01T00:45:55.914706Z","steps":["trace[1984508337] 'read index received' (duration: 129.561279ms)","trace[1984508337] 'applied index is now lower than readState.Index' (duration: 143.128µs)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:45:55.915005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.988323ms","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-01T00:45:55.915079Z","caller":"traceutil/trace.go:171","msg":"trace[2024092656] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1091; }","duration":"130.080069ms","start":"2026-07-01T00:45:55.784985Z","end":"2026-07-01T00:45:55.915065Z","steps":["trace[2024092656] 'agreement among raft nodes before linearized reading' (duration: 129.836515ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:45:55.915489Z","caller":"traceutil/trace.go:171","msg":"trace[1022253737] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"230.192728ms","start":"2026-07-01T00:45:55.684578Z","end":"2026-07-01T00:45:55.914771Z","steps":["trace[1022253737] 'process raft request' (duration: 229.960824ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:45:56.954312Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":988152107137392714,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-01T00:45:57.454985Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":988152107137392714,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-01T00:45:57.682295Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.274546822s","expected-duration":"1s"} {"level":"info","ts":"2026-07-01T00:45:57.685323Z","caller":"traceutil/trace.go:171","msg":"trace[399578193] linearizableReadLoop","detail":"{readStateIndex:1138; appliedIndex:1137; }","duration":"1.231110833s","start":"2026-07-01T00:45:56.454196Z","end":"2026-07-01T00:45:57.685307Z","steps":["trace[399578193] 'read index received' (duration: 1.228370513s)","trace[399578193] 'applied index is now lower than readState.Index' (duration: 2.73983ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:45:57.68534Z","caller":"traceutil/trace.go:171","msg":"trace[1455555911] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"1.277677195s","start":"2026-07-01T00:45:56.407645Z","end":"2026-07-01T00:45:57.685322Z","steps":["trace[1455555911] 'process raft request' (duration: 1.274859811s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:45:57.685467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.231266691s","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-01T00:45:57.685486Z","caller":"traceutil/trace.go:171","msg":"trace[987467899] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1094; }","duration":"1.231332306s","start":"2026-07-01T00:45:56.454149Z","end":"2026-07-01T00:45:57.685481Z","steps":["trace[987467899] 'agreement among raft nodes before linearized reading' (duration: 1.23123262s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:45:57.68547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:45:56.407619Z","time spent":"1.277764879s","remote":"127.0.0.1:60286","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-01T00:45:57.685487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.130255012s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T00:45:57.685501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:45:56.454128Z","time spent":"1.231369288s","remote":"127.0.0.1:60286","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-01T00:45:57.685526Z","caller":"traceutil/trace.go:171","msg":"trace[556790715] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1094; }","duration":"1.130348697s","start":"2026-07-01T00:45:56.555163Z","end":"2026-07-01T00:45:57.685512Z","steps":["trace[556790715] 'agreement among raft nodes before linearized reading' (duration: 1.130268862s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:45:57.685559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:45:56.555144Z","time spent":"1.13040747s","remote":"127.0.0.1:59992","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-01T00:45:57.685618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"782.099652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:45:57.685675Z","caller":"traceutil/trace.go:171","msg":"trace[1533098273] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1094; }","duration":"782.188277ms","start":"2026-07-01T00:45:56.903471Z","end":"2026-07-01T00:45:57.685659Z","steps":["trace[1533098273] 'agreement among raft nodes before linearized reading' (duration: 782.108633ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:45:57.685704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.049198469s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-01T00:45:57.685725Z","caller":"traceutil/trace.go:171","msg":"trace[782143250] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1094; }","duration":"1.049256273s","start":"2026-07-01T00:45:56.636461Z","end":"2026-07-01T00:45:57.685717Z","steps":["trace[782143250] 'agreement among raft nodes before linearized reading' (duration: 1.04921174s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:45:57.685725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:45:56.90345Z","time spent":"782.261663ms","remote":"127.0.0.1:60348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-07-01T00:45:57.685748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:45:56.636441Z","time spent":"1.049301705s","remote":"127.0.0.1:60360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":16,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-07-01T00:45:57.685786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.33177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-01T00:45:57.68581Z","caller":"traceutil/trace.go:171","msg":"trace[2055482437] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1094; }","duration":"229.387494ms","start":"2026-07-01T00:45:57.456415Z","end":"2026-07-01T00:45:57.685803Z","steps":["trace[2055482437] 'agreement among raft nodes before linearized reading' (duration: 229.31824ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:46:01.825035Z","caller":"traceutil/trace.go:171","msg":"trace[336330235] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"110.06048ms","start":"2026-07-01T00:46:01.714945Z","end":"2026-07-01T00:46:01.825005Z","steps":["trace[336330235] 'process raft request' (duration: 109.759472ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:46:03.995399Z","caller":"traceutil/trace.go:171","msg":"trace[997830242] linearizableReadLoop","detail":"{readStateIndex:1158; appliedIndex:1157; }","duration":"106.321244ms","start":"2026-07-01T00:46:03.889053Z","end":"2026-07-01T00:46:03.995375Z","steps":["trace[997830242] 'read index received' (duration: 106.149724ms)","trace[997830242] 'applied index is now lower than readState.Index' (duration: 170.47µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:46:03.995537Z","caller":"traceutil/trace.go:171","msg":"trace[1345485975] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"165.281289ms","start":"2026-07-01T00:46:03.830245Z","end":"2026-07-01T00:46:03.995526Z","steps":["trace[1345485975] 'process raft request' (duration: 164.975381ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:46:03.99577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.710547ms","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-01T00:46:03.99581Z","caller":"traceutil/trace.go:171","msg":"trace[1050077304] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1113; }","duration":"106.799302ms","start":"2026-07-01T00:46:03.889001Z","end":"2026-07-01T00:46:03.9958Z","steps":["trace[1050077304] 'agreement among raft nodes before linearized reading' (duration: 106.663424ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:46:03.996023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.142433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-01T00:46:03.996094Z","caller":"traceutil/trace.go:171","msg":"trace[667183277] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1113; }","duration":"106.221048ms","start":"2026-07-01T00:46:03.889855Z","end":"2026-07-01T00:46:03.996076Z","steps":["trace[667183277] 'agreement among raft nodes before linearized reading' (duration: 106.034057ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:46:04.131866Z","caller":"traceutil/trace.go:171","msg":"trace[952051391] linearizableReadLoop","detail":"{readStateIndex:1160; appliedIndex:1158; }","duration":"101.165131ms","start":"2026-07-01T00:46:04.030671Z","end":"2026-07-01T00:46:04.131836Z","steps":["trace[952051391] 'read index received' (duration: 8.425077ms)","trace[952051391] 'applied index is now lower than readState.Index' (duration: 92.738624ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:46:04.132059Z","caller":"traceutil/trace.go:171","msg":"trace[1950609695] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"131.985657ms","start":"2026-07-01T00:46:04.000045Z","end":"2026-07-01T00:46:04.13203Z","steps":["trace[1950609695] 'process raft request' (duration: 83.846128ms)","trace[1950609695] 'compare' (duration: 47.749635ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:46:04.132481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.795589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-01T00:46:04.132589Z","caller":"traceutil/trace.go:171","msg":"trace[2009583582] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1115; }","duration":"101.954379ms","start":"2026-07-01T00:46:04.030622Z","end":"2026-07-01T00:46:04.132576Z","steps":["trace[2009583582] 'agreement among raft nodes before linearized reading' (duration: 101.660481ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:46:16.571728Z","caller":"traceutil/trace.go:171","msg":"trace[1561473026] transaction","detail":"{read_only:false; response_revision:1159; number_of_response:1; }","duration":"144.289974ms","start":"2026-07-01T00:46:16.4274Z","end":"2026-07-01T00:46:16.57169Z","steps":["trace[1561473026] 'process raft request' (duration: 144.009526ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:46:22.467223Z","caller":"traceutil/trace.go:171","msg":"trace[1913834246] linearizableReadLoop","detail":"{readStateIndex:1225; appliedIndex:1224; }","duration":"117.972359ms","start":"2026-07-01T00:46:22.349228Z","end":"2026-07-01T00:46:22.467201Z","steps":["trace[1913834246] 'read index received' (duration: 117.730863ms)","trace[1913834246] 'applied index is now lower than readState.Index' (duration: 240.496µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:46:22.46728Z","caller":"traceutil/trace.go:171","msg":"trace[1072300723] transaction","detail":"{read_only:false; response_revision:1176; number_of_response:1; }","duration":"125.732378ms","start":"2026-07-01T00:46:22.341512Z","end":"2026-07-01T00:46:22.467244Z","steps":["trace[1072300723] 'process raft request' (duration: 125.526725ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:46:22.467424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.172102ms","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-01T00:46:22.467477Z","caller":"traceutil/trace.go:171","msg":"trace[596053759] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1176; }","duration":"118.225916ms","start":"2026-07-01T00:46:22.349225Z","end":"2026-07-01T00:46:22.467451Z","steps":["trace[596053759] 'agreement among raft nodes before linearized reading' (duration: 118.079357ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:47:00.557081Z","caller":"traceutil/trace.go:171","msg":"trace[1418020572] linearizableReadLoop","detail":"{readStateIndex:1396; appliedIndex:1395; }","duration":"108.8879ms","start":"2026-07-01T00:47:00.448166Z","end":"2026-07-01T00:47:00.557054Z","steps":["trace[1418020572] 'read index received' (duration: 94.628734ms)","trace[1418020572] 'applied index is now lower than readState.Index' (duration: 14.258075ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:47:00.557223Z","caller":"traceutil/trace.go:171","msg":"trace[1159010299] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"132.155293ms","start":"2026-07-01T00:47:00.425038Z","end":"2026-07-01T00:47:00.557193Z","steps":["trace[1159010299] 'process raft request' (duration: 117.811532ms)","trace[1159010299] 'compare' (duration: 13.927792ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:47:00.557309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.123976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-01T00:47:00.557344Z","caller":"traceutil/trace.go:171","msg":"trace[514723975] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1338; }","duration":"109.19432ms","start":"2026-07-01T00:47:00.44814Z","end":"2026-07-01T00:47:00.557334Z","steps":["trace[514723975] 'agreement among raft nodes before linearized reading' (duration: 109.05172ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:47:00.883571Z","caller":"traceutil/trace.go:171","msg":"trace[1381644358] linearizableReadLoop","detail":"{readStateIndex:1397; appliedIndex:1396; }","duration":"287.71601ms","start":"2026-07-01T00:47:00.595813Z","end":"2026-07-01T00:47:00.883529Z","steps":["trace[1381644358] 'read index received' (duration: 275.913866ms)","trace[1381644358] 'applied index is now lower than readState.Index' (duration: 11.801244ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:47:00.883668Z","caller":"traceutil/trace.go:171","msg":"trace[1567565266] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"322.052895ms","start":"2026-07-01T00:47:00.5616Z","end":"2026-07-01T00:47:00.883653Z","steps":["trace[1567565266] 'process raft request' (duration: 310.126563ms)","trace[1567565266] 'compare' (duration: 11.548876ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:47:00.883728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.905502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-01T00:47:00.883762Z","caller":"traceutil/trace.go:171","msg":"trace[1079142662] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1339; }","duration":"287.984798ms","start":"2026-07-01T00:47:00.595767Z","end":"2026-07-01T00:47:00.883752Z","steps":["trace[1079142662] 'agreement among raft nodes before linearized reading' (duration: 287.909603ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:47:00.883761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:47:00.561583Z","time spent":"322.120271ms","remote":"127.0.0.1:60286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T00:47:00.884125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.843446ms","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-01T00:47:00.884168Z","caller":"traceutil/trace.go:171","msg":"trace[1967771613] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1339; }","duration":"109.907351ms","start":"2026-07-01T00:47:00.774239Z","end":"2026-07-01T00:47:00.884147Z","steps":["trace[1967771613] 'agreement among raft nodes before linearized reading' (duration: 109.799503ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:47:00.884203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.139317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-01T00:47:00.884232Z","caller":"traceutil/trace.go:171","msg":"trace[970467277] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1339; }","duration":"155.207332ms","start":"2026-07-01T00:47:00.729016Z","end":"2026-07-01T00:47:00.884224Z","steps":["trace[970467277] 'agreement among raft nodes before linearized reading' (duration: 154.962855ms)"],"step_count":1}