{"level":"warn","ts":"2026-05-11T05:41:06.536904Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-11T05:41:06.53704Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.109: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.109:2380","--initial-cluster=instance=https://199.204.45.109:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.109:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.109: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-05-11T05:41:06.537128Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-11T05:41:06.537148Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-05-11T05:41:06.537171Z","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-05-11T05:41:06.537969Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"]} {"level":"info","ts":"2026-05-11T05:41:06.538068Z","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.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.109: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-05-11T05:41:06.542082Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.751386ms"} {"level":"info","ts":"2026-05-11T05:41:06.550694Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d71a1778b31d4ce","cluster-id":"aaca35652fabf9b2"} {"level":"info","ts":"2026-05-11T05:41:06.550788Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=()"} {"level":"info","ts":"2026-05-11T05:41:06.55082Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 0"} {"level":"info","ts":"2026-05-11T05:41:06.550831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d71a1778b31d4ce [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-11T05:41:06.550846Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 1"} {"level":"info","ts":"2026-05-11T05:41:06.550893Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"warn","ts":"2026-05-11T05:41:06.556323Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-11T05:41:06.557871Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-11T05:41:06.560828Z","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-05-11T05:41:06.564083Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d71a1778b31d4ce","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-11T05:41:06.564271Z","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-05-11T05:41:06.565111Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d71a1778b31d4ce","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-11T05:41:06.564325Z","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-05-11T05:41:06.56523Z","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-05-11T05:41:06.565525Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"info","ts":"2026-05-11T05:41:06.565739Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","added-peer-id":"d71a1778b31d4ce","added-peer-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-05-11T05:41:06.575039Z","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-05-11T05:41:06.575255Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d71a1778b31d4ce","initial-advertise-peer-urls":["https://199.204.45.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-11T05:41:06.575282Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-11T05:41:06.575392Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-05-11T05:41:06.575421Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-05-11T05:41:07.052016Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce is starting a new election at term 1"} {"level":"info","ts":"2026-05-11T05:41:07.052072Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became pre-candidate at term 1"} {"level":"info","ts":"2026-05-11T05:41:07.0521Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgPreVoteResp from d71a1778b31d4ce at term 1"} {"level":"info","ts":"2026-05-11T05:41:07.052118Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became candidate at term 2"} {"level":"info","ts":"2026-05-11T05:41:07.052127Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgVoteResp from d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-05-11T05:41:07.052137Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became leader at term 2"} {"level":"info","ts":"2026-05-11T05:41:07.052149Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d71a1778b31d4ce elected leader d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-05-11T05:41:07.052973Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T05:41:07.053485Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d71a1778b31d4ce","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.109:2379]}","request-path":"/0/members/d71a1778b31d4ce/attributes","cluster-id":"aaca35652fabf9b2","publish-timeout":"7s"} {"level":"info","ts":"2026-05-11T05:41:07.05363Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T05:41:07.053706Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T05:41:07.055827Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.109:2379"} {"level":"info","ts":"2026-05-11T05:41:07.056312Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T05:41:07.056393Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T05:41:07.056408Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T05:41:07.05643Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-11T05:41:07.056437Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-11T05:41:07.057931Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-11T05:41:08.518246Z","caller":"traceutil/trace.go:171","msg":"trace[1139350599] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"219.397953ms","start":"2026-05-11T05:41:08.298812Z","end":"2026-05-11T05:41:08.51821Z","steps":["trace[1139350599] 'process raft request' (duration: 219.09482ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:41:08.518425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.362223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-11T05:41:08.518496Z","caller":"traceutil/trace.go:171","msg":"trace[15616883] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:0; response_revision:2; }","duration":"216.463924ms","start":"2026-05-11T05:41:08.302019Z","end":"2026-05-11T05:41:08.518483Z","steps":["trace[15616883] 'agreement among raft nodes before linearized reading' (duration: 216.281482ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:41:08.518209Z","caller":"traceutil/trace.go:171","msg":"trace[2116640099] linearizableReadLoop","detail":"{readStateIndex:5; appliedIndex:4; }","duration":"216.143131ms","start":"2026-05-11T05:41:08.302034Z","end":"2026-05-11T05:41:08.518177Z","steps":["trace[2116640099] 'read index received' (duration: 215.943399ms)","trace[2116640099] 'applied index is now lower than readState.Index' (duration: 198.482µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:41:08.525878Z","caller":"traceutil/trace.go:171","msg":"trace[1677126088] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"217.949949ms","start":"2026-05-11T05:41:08.307903Z","end":"2026-05-11T05:41:08.525853Z","steps":["trace[1677126088] 'process raft request' (duration: 217.781248ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:41:08.526091Z","caller":"traceutil/trace.go:171","msg":"trace[471916364] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"146.846618ms","start":"2026-05-11T05:41:08.37907Z","end":"2026-05-11T05:41:08.525917Z","steps":["trace[471916364] 'process raft request' (duration: 146.739357ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:41:08.526411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.252461ms","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":"warn","ts":"2026-05-11T05:41:08.52647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.562764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-11T05:41:08.526479Z","caller":"traceutil/trace.go:171","msg":"trace[270631971] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:10; }","duration":"149.365513ms","start":"2026-05-11T05:41:08.377092Z","end":"2026-05-11T05:41:08.526458Z","steps":["trace[270631971] 'agreement among raft nodes before linearized reading' (duration: 149.224931ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:41:08.526523Z","caller":"traceutil/trace.go:171","msg":"trace[1898684746] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"150.636554ms","start":"2026-05-11T05:41:08.375867Z","end":"2026-05-11T05:41:08.526504Z","steps":["trace[1898684746] 'agreement among raft nodes before linearized reading' (duration: 150.551893ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:41:08.526539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.822976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-11T05:41:08.526635Z","caller":"traceutil/trace.go:171","msg":"trace[1763479606] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:10; }","duration":"222.939367ms","start":"2026-05-11T05:41:08.303665Z","end":"2026-05-11T05:41:08.526604Z","steps":["trace[1763479606] 'agreement among raft nodes before linearized reading' (duration: 222.422262ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:41:08.526763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.428872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-11T05:41:08.526802Z","caller":"traceutil/trace.go:171","msg":"trace[1599329429] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:10; }","duration":"222.476613ms","start":"2026-05-11T05:41:08.304316Z","end":"2026-05-11T05:41:08.526793Z","steps":["trace[1599329429] 'agreement among raft nodes before linearized reading' (duration: 222.423382ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:41:08.52677Z","caller":"traceutil/trace.go:171","msg":"trace[1496808187] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"147.136201ms","start":"2026-05-11T05:41:08.379143Z","end":"2026-05-11T05:41:08.526279Z","steps":["trace[1496808187] 'process raft request' (duration: 146.697247ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:41:08.526925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.625022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-11T05:41:08.526943Z","caller":"traceutil/trace.go:171","msg":"trace[950688078] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"177.676512ms","start":"2026-05-11T05:41:08.349261Z","end":"2026-05-11T05:41:08.526938Z","steps":["trace[950688078] 'agreement among raft nodes before linearized reading' (duration: 177.648422ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:41:08.52689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.043198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-11T05:41:08.527222Z","caller":"traceutil/trace.go:171","msg":"trace[792329098] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"144.586936ms","start":"2026-05-11T05:41:08.382629Z","end":"2026-05-11T05:41:08.527216Z","steps":["trace[792329098] 'process raft request' (duration: 143.313594ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:41:08.527307Z","caller":"traceutil/trace.go:171","msg":"trace[1139062103] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:10; }","duration":"223.404841ms","start":"2026-05-11T05:41:08.303828Z","end":"2026-05-11T05:41:08.527233Z","steps":["trace[1139062103] 'agreement among raft nodes before linearized reading' (duration: 223.020157ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:41:08.527148Z","caller":"traceutil/trace.go:171","msg":"trace[232019303] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"147.866277ms","start":"2026-05-11T05:41:08.379274Z","end":"2026-05-11T05:41:08.52714Z","steps":["trace[232019303] 'process raft request' (duration: 146.618526ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:41:08.527319Z","caller":"traceutil/trace.go:171","msg":"trace[2033799205] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"137.502488ms","start":"2026-05-11T05:41:08.389747Z","end":"2026-05-11T05:41:08.527249Z","steps":["trace[2033799205] 'process raft request' (duration: 136.257637ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:41:08.527155Z","caller":"traceutil/trace.go:171","msg":"trace[244070131] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"147.645185ms","start":"2026-05-11T05:41:08.379497Z","end":"2026-05-11T05:41:08.527142Z","steps":["trace[244070131] 'process raft request' (duration: 146.420584ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:41:08.528062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.804194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.109\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-11T05:41:08.528128Z","caller":"traceutil/trace.go:171","msg":"trace[2074131917] range","detail":"{range_begin:/registry/masterleases/199.204.45.109; range_end:; response_count:0; response_revision:10; }","duration":"152.888755ms","start":"2026-05-11T05:41:08.375222Z","end":"2026-05-11T05:41:08.528111Z","steps":["trace[2074131917] 'agreement among raft nodes before linearized reading' (duration: 151.030918ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:41:08.529132Z","caller":"traceutil/trace.go:171","msg":"trace[456252884] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"146.738597ms","start":"2026-05-11T05:41:08.379229Z","end":"2026-05-11T05:41:08.525968Z","steps":["trace[456252884] 'process raft request' (duration: 146.635716ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:41:19.129429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.365738ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T05:41:19.129614Z","caller":"traceutil/trace.go:171","msg":"trace[431356671] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"482.668861ms","start":"2026-05-11T05:41:18.64691Z","end":"2026-05-11T05:41:19.129579Z","steps":["trace[431356671] 'process raft request' (duration: 288.86442ms)","trace[431356671] 'compare' (duration: 192.137186ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:41:19.129678Z","caller":"traceutil/trace.go:171","msg":"trace[124777904] linearizableReadLoop","detail":"{readStateIndex:374; appliedIndex:372; }","duration":"441.672568ms","start":"2026-05-11T05:41:18.687957Z","end":"2026-05-11T05:41:19.12963Z","steps":["trace[124777904] 'read index received' (duration: 40.836693ms)","trace[124777904] 'applied index is now lower than readState.Index' (duration: 400.833315ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:41:19.129809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.84722ms","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-05-11T05:41:19.129848Z","caller":"traceutil/trace.go:171","msg":"trace[323102275] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:363; }","duration":"441.91773ms","start":"2026-05-11T05:41:18.68792Z","end":"2026-05-11T05:41:19.129838Z","steps":["trace[323102275] 'agreement among raft nodes before linearized reading' (duration: 441.851969ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:41:19.129814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:41:18.646885Z","time spent":"482.848152ms","remote":"127.0.0.1:40174","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-05-11T05:41:19.129855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.68186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-11T05:41:19.129872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:41:18.687901Z","time spent":"441.96489ms","remote":"127.0.0.1:40174","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-05-11T05:41:19.129909Z","caller":"traceutil/trace.go:171","msg":"trace[1400553815] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:363; }","duration":"335.77189ms","start":"2026-05-11T05:41:18.794121Z","end":"2026-05-11T05:41:19.129893Z","steps":["trace[1400553815] 'agreement among raft nodes before linearized reading' (duration: 335.68263ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:41:19.129922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.165676ms","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-05-11T05:41:19.129964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:41:18.794107Z","time spent":"335.844342ms","remote":"127.0.0.1:39926","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-05-11T05:41:19.129969Z","caller":"traceutil/trace.go:171","msg":"trace[215283433] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:363; }","duration":"149.255978ms","start":"2026-05-11T05:41:18.980698Z","end":"2026-05-11T05:41:19.129954Z","steps":["trace[215283433] 'agreement among raft nodes before linearized reading' (duration: 149.160727ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:41:35.916768Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367596651445017,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T05:41:36.402584Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.007851835s","expected-duration":"1s"} {"level":"info","ts":"2026-05-11T05:41:36.404934Z","caller":"traceutil/trace.go:171","msg":"trace[1689041173] linearizableReadLoop","detail":"{readStateIndex:490; appliedIndex:489; }","duration":"988.550719ms","start":"2026-05-11T05:41:35.416364Z","end":"2026-05-11T05:41:36.404915Z","steps":["trace[1689041173] 'read index received' (duration: 986.402374ms)","trace[1689041173] 'applied index is now lower than readState.Index' (duration: 2.147525ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:41:36.405085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"988.69916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:41:36.405108Z","caller":"traceutil/trace.go:171","msg":"trace[788069546] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:472; }","duration":"988.7673ms","start":"2026-05-11T05:41:35.416332Z","end":"2026-05-11T05:41:36.405099Z","steps":["trace[788069546] 'agreement among raft nodes before linearized reading' (duration: 988.654289ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:41:36.405089Z","caller":"traceutil/trace.go:171","msg":"trace[1091419569] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"1.010383073s","start":"2026-05-11T05:41:35.394672Z","end":"2026-05-11T05:41:36.405055Z","steps":["trace[1091419569] 'process raft request' (duration: 1.008142777s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:41:36.405133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:41:35.416315Z","time spent":"988.81178ms","remote":"127.0.0.1:40008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-05-11T05:41:36.405284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:41:35.394657Z","time spent":"1.010492923s","remote":"127.0.0.1:39990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":828,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T05:41:37.578953Z","caller":"traceutil/trace.go:171","msg":"trace[216390619] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"110.402455ms","start":"2026-05-11T05:41:37.468525Z","end":"2026-05-11T05:41:37.578928Z","steps":["trace[216390619] 'process raft request' (duration: 107.732906ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:41:39.485583Z","caller":"traceutil/trace.go:171","msg":"trace[2117977493] linearizableReadLoop","detail":"{readStateIndex:578; appliedIndex:577; }","duration":"393.786221ms","start":"2026-05-11T05:41:39.091769Z","end":"2026-05-11T05:41:39.485555Z","steps":["trace[2117977493] 'read index received' (duration: 393.62329ms)","trace[2117977493] 'applied index is now lower than readState.Index' (duration: 161.601µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:41:39.485635Z","caller":"traceutil/trace.go:171","msg":"trace[1117823620] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"644.828019ms","start":"2026-05-11T05:41:38.840775Z","end":"2026-05-11T05:41:39.485603Z","steps":["trace[1117823620] 'process raft request' (duration: 644.630638ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:41:39.485757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.963422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:41:39.485809Z","caller":"traceutil/trace.go:171","msg":"trace[2051764347] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:554; }","duration":"394.064502ms","start":"2026-05-11T05:41:39.09173Z","end":"2026-05-11T05:41:39.485795Z","steps":["trace[2051764347] 'agreement among raft nodes before linearized reading' (duration: 393.965862ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:41:39.485817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:41:38.840759Z","time spent":"644.9525ms","remote":"127.0.0.1:40174","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-05-11T05:41:39.485853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:41:39.091708Z","time spent":"394.132672ms","remote":"127.0.0.1:39926","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-05-11T05:41:39.687358Z","caller":"traceutil/trace.go:171","msg":"trace[1315588833] linearizableReadLoop","detail":"{readStateIndex:579; appliedIndex:578; }","duration":"201.668343ms","start":"2026-05-11T05:41:39.485667Z","end":"2026-05-11T05:41:39.687336Z","steps":["trace[1315588833] 'read index received' (duration: 199.447919ms)","trace[1315588833] 'applied index is now lower than readState.Index' (duration: 2.219604ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:41:39.68739Z","caller":"traceutil/trace.go:171","msg":"trace[701194194] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"213.382893ms","start":"2026-05-11T05:41:39.473987Z","end":"2026-05-11T05:41:39.68737Z","steps":["trace[701194194] 'process raft request' (duration: 211.110838ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:41:39.687586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.945382ms","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-05-11T05:41:39.687643Z","caller":"traceutil/trace.go:171","msg":"trace[1121779043] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:555; }","duration":"397.041642ms","start":"2026-05-11T05:41:39.290584Z","end":"2026-05-11T05:41:39.687626Z","steps":["trace[1121779043] 'agreement among raft nodes before linearized reading' (duration: 396.873011ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:41:39.687722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:41:39.290574Z","time spent":"397.132614ms","remote":"127.0.0.1:40174","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-05-11T05:41:39.687896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.546309ms","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-05-11T05:41:39.687944Z","caller":"traceutil/trace.go:171","msg":"trace[2057701140] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:555; }","duration":"406.663319ms","start":"2026-05-11T05:41:39.28127Z","end":"2026-05-11T05:41:39.687934Z","steps":["trace[2057701140] 'agreement among raft nodes before linearized reading' (duration: 406.528378ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:41:39.687975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:41:39.281248Z","time spent":"406.719239ms","remote":"127.0.0.1:40174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-11T05:41:39.687993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.17343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:41:39.688061Z","caller":"traceutil/trace.go:171","msg":"trace[1037061360] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:555; }","duration":"201.25725ms","start":"2026-05-11T05:41:39.486793Z","end":"2026-05-11T05:41:39.68805Z","steps":["trace[1037061360] 'agreement among raft nodes before linearized reading' (duration: 201.173079ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:41:39.688175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.321856ms","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-05-11T05:41:39.688226Z","caller":"traceutil/trace.go:171","msg":"trace[1661008876] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:555; }","duration":"403.392806ms","start":"2026-05-11T05:41:39.284824Z","end":"2026-05-11T05:41:39.688217Z","steps":["trace[1661008876] 'agreement among raft nodes before linearized reading' (duration: 403.285245ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:41:39.688253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:41:39.284813Z","time spent":"403.434606ms","remote":"127.0.0.1:40174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-11T05:41:44.294438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.331346ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T05:41:44.294636Z","caller":"traceutil/trace.go:171","msg":"trace[1355529985] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:616; }","duration":"370.322075ms","start":"2026-05-11T05:41:43.924299Z","end":"2026-05-11T05:41:44.294621Z","steps":["trace[1355529985] 'read index received' (duration: 114.421896ms)","trace[1355529985] 'applied index is now lower than readState.Index' (duration: 255.899269ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:41:44.294683Z","caller":"traceutil/trace.go:171","msg":"trace[280016824] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"389.659773ms","start":"2026-05-11T05:41:43.904987Z","end":"2026-05-11T05:41:44.294647Z","steps":["trace[280016824] 'process raft request' (duration: 389.535043ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:41:44.29474Z","caller":"traceutil/trace.go:171","msg":"trace[1244460258] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"388.029202ms","start":"2026-05-11T05:41:43.906695Z","end":"2026-05-11T05:41:44.294724Z","steps":["trace[1244460258] 'process raft request' (duration: 387.879271ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:41:44.294805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:41:43.906685Z","time spent":"388.085662ms","remote":"127.0.0.1:40084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T05:41:44.294856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:41:43.904968Z","time spent":"389.808944ms","remote":"127.0.0.1:39990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T05:41:44.295035Z","caller":"traceutil/trace.go:171","msg":"trace[912772285] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"390.074236ms","start":"2026-05-11T05:41:43.904947Z","end":"2026-05-11T05:41:44.295021Z","steps":["trace[912772285] 'process raft request' (duration: 133.761124ms)","trace[912772285] 'compare' (duration: 255.196465ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:41:44.295115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:41:43.904929Z","time spent":"390.138726ms","remote":"127.0.0.1:40140","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-05-11T05:41:44.295199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.902899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-vnvpj\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-11T05:41:44.295231Z","caller":"traceutil/trace.go:171","msg":"trace[635251308] range","detail":"{range_begin:/registry/pods/kube-system/cilium-vnvpj; range_end:; response_count:1; response_revision:594; }","duration":"370.970109ms","start":"2026-05-11T05:41:43.924251Z","end":"2026-05-11T05:41:44.295221Z","steps":["trace[635251308] 'agreement among raft nodes before linearized reading' (duration: 370.903439ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:41:44.295258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:41:43.924232Z","time spent":"371.020259ms","remote":"127.0.0.1:40084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-vnvpj\" "} {"level":"warn","ts":"2026-05-11T05:41:44.29663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.301132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:41:44.296742Z","caller":"traceutil/trace.go:171","msg":"trace[910869743] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:594; }","duration":"204.465512ms","start":"2026-05-11T05:41:44.092258Z","end":"2026-05-11T05:41:44.296723Z","steps":["trace[910869743] 'agreement among raft nodes before linearized reading' (duration: 203.146963ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:41:44.539799Z","caller":"traceutil/trace.go:171","msg":"trace[259378587] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"233.846656ms","start":"2026-05-11T05:41:44.305924Z","end":"2026-05-11T05:41:44.53977Z","steps":["trace[259378587] 'process raft request' (duration: 233.636325ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:42:09.380153Z","caller":"traceutil/trace.go:171","msg":"trace[229651516] linearizableReadLoop","detail":"{readStateIndex:806; appliedIndex:805; }","duration":"286.99176ms","start":"2026-05-11T05:42:09.093133Z","end":"2026-05-11T05:42:09.380125Z","steps":["trace[229651516] 'read index received' (duration: 286.793119ms)","trace[229651516] 'applied index is now lower than readState.Index' (duration: 197.421µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:42:09.380254Z","caller":"traceutil/trace.go:171","msg":"trace[875484894] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"328.020529ms","start":"2026-05-11T05:42:09.052202Z","end":"2026-05-11T05:42:09.380222Z","steps":["trace[875484894] 'process raft request' (duration: 327.726032ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:42:09.3803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.141798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:42:09.380345Z","caller":"traceutil/trace.go:171","msg":"trace[1153384373] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:775; }","duration":"287.247674ms","start":"2026-05-11T05:42:09.093083Z","end":"2026-05-11T05:42:09.38033Z","steps":["trace[1153384373] 'agreement among raft nodes before linearized reading' (duration: 287.155729ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:42:09.380421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:42:09.052182Z","time spent":"328.149326ms","remote":"127.0.0.1:40084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T05:42:42.054915Z","caller":"traceutil/trace.go:171","msg":"trace[620864449] linearizableReadLoop","detail":"{readStateIndex:1224; appliedIndex:1223; }","duration":"129.614483ms","start":"2026-05-11T05:42:41.925277Z","end":"2026-05-11T05:42:42.054891Z","steps":["trace[620864449] 'read index received' (duration: 129.483148ms)","trace[620864449] 'applied index is now lower than readState.Index' (duration: 130.334µs)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:42:42.055129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.830602ms","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-05-11T05:42:42.055266Z","caller":"traceutil/trace.go:171","msg":"trace[463388802] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1183; }","duration":"130.010758ms","start":"2026-05-11T05:42:41.925232Z","end":"2026-05-11T05:42:42.055243Z","steps":["trace[463388802] 'agreement among raft nodes before linearized reading' (duration: 129.744339ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:42:42.054958Z","caller":"traceutil/trace.go:171","msg":"trace[1010835179] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"158.523102ms","start":"2026-05-11T05:42:41.896404Z","end":"2026-05-11T05:42:42.054927Z","steps":["trace[1010835179] 'process raft request' (duration: 158.320924ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:43:27.617729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.448902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-82ba1631-ce72-4422-8caa-19dc819fa5e5\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-11T05:43:27.617811Z","caller":"traceutil/trace.go:171","msg":"trace[2066879271] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-82ba1631-ce72-4422-8caa-19dc819fa5e5; range_end:; response_count:1; response_revision:1853; }","duration":"222.587314ms","start":"2026-05-11T05:43:27.395208Z","end":"2026-05-11T05:43:27.617795Z","steps":["trace[2066879271] 'range keys from in-memory index tree' (duration: 222.299687ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:43:27.730193Z","caller":"traceutil/trace.go:171","msg":"trace[1099751928] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"104.939241ms","start":"2026-05-11T05:43:27.625236Z","end":"2026-05-11T05:43:27.730175Z","steps":["trace[1099751928] 'process raft request' (duration: 104.833378ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:43:39.277923Z","caller":"traceutil/trace.go:171","msg":"trace[1762552396] transaction","detail":"{read_only:false; response_revision:1961; number_of_response:1; }","duration":"101.362278ms","start":"2026-05-11T05:43:39.176541Z","end":"2026-05-11T05:43:39.277903Z","steps":["trace[1762552396] 'process raft request' (duration: 101.245186ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:43:50.706483Z","caller":"traceutil/trace.go:171","msg":"trace[635187627] transaction","detail":"{read_only:false; response_revision:2043; number_of_response:1; }","duration":"177.997407ms","start":"2026-05-11T05:43:50.528456Z","end":"2026-05-11T05:43:50.706453Z","steps":["trace[635187627] 'process raft request' (duration: 177.894845ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:43:50.706614Z","caller":"traceutil/trace.go:171","msg":"trace[1678530888] linearizableReadLoop","detail":"{readStateIndex:2124; appliedIndex:2121; }","duration":"166.252034ms","start":"2026-05-11T05:43:50.540347Z","end":"2026-05-11T05:43:50.706599Z","steps":["trace[1678530888] 'read index received' (duration: 70.647455ms)","trace[1678530888] 'applied index is now lower than readState.Index' (duration: 95.601919ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:43:50.706645Z","caller":"traceutil/trace.go:171","msg":"trace[832509112] transaction","detail":"{read_only:false; response_revision:2044; number_of_response:1; }","duration":"117.443044ms","start":"2026-05-11T05:43:50.589178Z","end":"2026-05-11T05:43:50.706621Z","steps":["trace[832509112] 'process raft request' (duration: 117.219759ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:43:50.7069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.513099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12404"} {"level":"info","ts":"2026-05-11T05:43:50.706965Z","caller":"traceutil/trace.go:171","msg":"trace[18362000] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2044; }","duration":"166.639002ms","start":"2026-05-11T05:43:50.540311Z","end":"2026-05-11T05:43:50.70695Z","steps":["trace[18362000] 'agreement among raft nodes before linearized reading' (duration: 166.394337ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:43:50.706516Z","caller":"traceutil/trace.go:171","msg":"trace[1864108975] transaction","detail":"{read_only:false; response_revision:2042; number_of_response:1; }","duration":"181.018088ms","start":"2026-05-11T05:43:50.525472Z","end":"2026-05-11T05:43:50.70649Z","steps":["trace[1864108975] 'process raft request' (duration: 178.401516ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:43:56.600118Z","caller":"traceutil/trace.go:171","msg":"trace[493671803] transaction","detail":"{read_only:false; response_revision:2072; number_of_response:1; }","duration":"112.79614ms","start":"2026-05-11T05:43:56.48726Z","end":"2026-05-11T05:43:56.600056Z","steps":["trace[493671803] 'process raft request' (duration: 112.574535ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:43:56.895437Z","caller":"traceutil/trace.go:171","msg":"trace[1570800144] transaction","detail":"{read_only:false; response_revision:2076; number_of_response:1; }","duration":"106.799365ms","start":"2026-05-11T05:43:56.788607Z","end":"2026-05-11T05:43:56.895407Z","steps":["trace[1570800144] 'process raft request' (duration: 106.642392ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:45:47.034834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.162787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18ae6c9d902f65ac\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-05-11T05:45:47.034932Z","caller":"traceutil/trace.go:171","msg":"trace[460260240] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18ae6c9d902f65ac; range_end:; response_count:1; response_revision:2769; }","duration":"188.276768ms","start":"2026-05-11T05:45:46.846636Z","end":"2026-05-11T05:45:47.034913Z","steps":["trace[460260240] 'range keys from in-memory index tree' (duration: 187.857443ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:45:47.248405Z","caller":"traceutil/trace.go:171","msg":"trace[502281220] transaction","detail":"{read_only:false; response_revision:2770; number_of_response:1; }","duration":"211.097855ms","start":"2026-05-11T05:45:47.037281Z","end":"2026-05-11T05:45:47.248379Z","steps":["trace[502281220] 'process raft request' (duration: 210.941243ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:45:47.248866Z","caller":"traceutil/trace.go:171","msg":"trace[94975652] linearizableReadLoop","detail":"{readStateIndex:2877; appliedIndex:2877; }","duration":"155.399981ms","start":"2026-05-11T05:45:47.093451Z","end":"2026-05-11T05:45:47.248851Z","steps":["trace[94975652] 'read index received' (duration: 155.393601ms)","trace[94975652] 'applied index is now lower than readState.Index' (duration: 5.05µs)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:45:47.249014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.552593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:45:47.249064Z","caller":"traceutil/trace.go:171","msg":"trace[554395443] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2770; }","duration":"155.651976ms","start":"2026-05-11T05:45:47.0934Z","end":"2026-05-11T05:45:47.249052Z","steps":["trace[554395443] 'agreement among raft nodes before linearized reading' (duration: 155.540994ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:46:25.826211Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367596651450462,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-11T05:46:26.007276Z","caller":"traceutil/trace.go:171","msg":"trace[621361642] transaction","detail":"{read_only:false; response_revision:2971; number_of_response:1; }","duration":"906.099595ms","start":"2026-05-11T05:46:25.101154Z","end":"2026-05-11T05:46:26.007253Z","steps":["trace[621361642] 'process raft request' (duration: 905.940273ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:46:26.00743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:46:25.101133Z","time spent":"906.220696ms","remote":"127.0.0.1:40174","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-05-11T05:46:26.010616Z","caller":"traceutil/trace.go:171","msg":"trace[1254009204] transaction","detail":"{read_only:false; response_revision:2972; number_of_response:1; }","duration":"620.740196ms","start":"2026-05-11T05:46:25.389854Z","end":"2026-05-11T05:46:26.010595Z","steps":["trace[1254009204] 'process raft request' (duration: 620.568054ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:46:26.010782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:46:25.389821Z","time spent":"620.877169ms","remote":"127.0.0.1:40174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T05:46:26.011097Z","caller":"traceutil/trace.go:171","msg":"trace[1828693817] linearizableReadLoop","detail":"{readStateIndex:3088; appliedIndex:3085; }","duration":"685.72945ms","start":"2026-05-11T05:46:25.325344Z","end":"2026-05-11T05:46:26.011073Z","steps":["trace[1828693817] 'read index received' (duration: 681.767664ms)","trace[1828693817] 'applied index is now lower than readState.Index' (duration: 3.960626ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:46:26.011346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.985533ms","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":"info","ts":"2026-05-11T05:46:26.011426Z","caller":"traceutil/trace.go:171","msg":"trace[1581156732] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:2974; }","duration":"686.097524ms","start":"2026-05-11T05:46:25.325301Z","end":"2026-05-11T05:46:26.011398Z","steps":["trace[1581156732] 'agreement among raft nodes before linearized reading' (duration: 685.968333ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:46:26.011475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:46:25.325284Z","time spent":"686.175925ms","remote":"127.0.0.1:44802","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-05-11T05:46:26.011536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.61239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-11T05:46:26.011588Z","caller":"traceutil/trace.go:171","msg":"trace[901594135] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2974; }","duration":"573.719701ms","start":"2026-05-11T05:46:25.437852Z","end":"2026-05-11T05:46:26.011571Z","steps":["trace[901594135] 'agreement among raft nodes before linearized reading' (duration: 573.556849ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:46:26.011737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:46:25.437826Z","time spent":"573.856773ms","remote":"127.0.0.1:40174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-11T05:46:26.012142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.89992ms","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-05-11T05:46:26.012195Z","caller":"traceutil/trace.go:171","msg":"trace[597712307] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2974; }","duration":"402.973991ms","start":"2026-05-11T05:46:25.609208Z","end":"2026-05-11T05:46:26.012182Z","steps":["trace[597712307] 'agreement among raft nodes before linearized reading' (duration: 402.84034ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:46:26.012237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:46:25.609197Z","time spent":"403.029152ms","remote":"127.0.0.1:40174","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-05-11T05:46:26.012241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.665081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-11T05:46:26.012318Z","caller":"traceutil/trace.go:171","msg":"trace[1864355138] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2974; }","duration":"561.756682ms","start":"2026-05-11T05:46:25.450548Z","end":"2026-05-11T05:46:26.012305Z","steps":["trace[1864355138] 'agreement among raft nodes before linearized reading' (duration: 561.57963ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:46:26.012372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:46:25.450542Z","time spent":"561.820462ms","remote":"127.0.0.1:40174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-11T05:46:26.012402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.070594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-11T05:46:26.012439Z","caller":"traceutil/trace.go:171","msg":"trace[1794770926] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2974; }","duration":"567.131414ms","start":"2026-05-11T05:46:25.445296Z","end":"2026-05-11T05:46:26.012427Z","steps":["trace[1794770926] 'agreement among raft nodes before linearized reading' (duration: 567.030273ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:46:26.012483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:46:25.445283Z","time spent":"567.187924ms","remote":"127.0.0.1:40174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-11T05:46:26.012767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.740941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-05-11T05:46:26.012839Z","caller":"traceutil/trace.go:171","msg":"trace[735521120] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2974; }","duration":"453.838181ms","start":"2026-05-11T05:46:25.558983Z","end":"2026-05-11T05:46:26.012821Z","steps":["trace[735521120] 'agreement among raft nodes before linearized reading' (duration: 453.664439ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:46:26.012896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:46:25.558971Z","time spent":"453.910502ms","remote":"127.0.0.1:40088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-05-11T05:46:26.013555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.583024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-11T05:46:26.015548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.619964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-11T05:46:26.015617Z","caller":"traceutil/trace.go:171","msg":"trace[1604880] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2974; }","duration":"412.735155ms","start":"2026-05-11T05:46:25.602866Z","end":"2026-05-11T05:46:26.015601Z","steps":["trace[1604880] 'agreement among raft nodes before linearized reading' (duration: 409.591248ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:46:26.015651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:46:25.602843Z","time spent":"412.799506ms","remote":"127.0.0.1:40174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-11T05:46:26.015779Z","caller":"traceutil/trace.go:171","msg":"trace[1421125541] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:2974; }","duration":"488.679906ms","start":"2026-05-11T05:46:25.524922Z","end":"2026-05-11T05:46:26.013602Z","steps":["trace[1421125541] 'agreement among raft nodes before linearized reading' (duration: 488.563915ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:46:26.015876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:46:25.52491Z","time spent":"490.949362ms","remote":"127.0.0.1:50674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true "} {"level":"warn","ts":"2026-05-11T05:46:27.467899Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367596651450481,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T05:46:27.8712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.026597388s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18ae6c9d902f65ac\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-05-11T05:46:27.871278Z","caller":"traceutil/trace.go:171","msg":"trace[677864100] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18ae6c9d902f65ac; range_end:; response_count:1; response_revision:2979; }","duration":"1.02673341s","start":"2026-05-11T05:46:26.844527Z","end":"2026-05-11T05:46:27.87126Z","steps":["trace[677864100] 'range keys from in-memory index tree' (duration: 1.026380506s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:46:27.871375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:46:26.844515Z","time spent":"1.026848201s","remote":"127.0.0.1:39990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":804,"request content":"key:\"/registry/events/auth-system/keycloak-0.18ae6c9d902f65ac\" "} {"level":"warn","ts":"2026-05-11T05:46:27.871396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.180970597s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:46:27.871554Z","caller":"traceutil/trace.go:171","msg":"trace[1585424778] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:2979; }","duration":"1.18128508s","start":"2026-05-11T05:46:26.690233Z","end":"2026-05-11T05:46:27.871519Z","steps":["trace[1585424778] 'count revisions from in-memory index tree' (duration: 1.180867416s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:46:27.871656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:46:26.690206Z","time spent":"1.181433502s","remote":"127.0.0.1:39044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-11T05:46:27.871883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.780868873s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:46:27.871961Z","caller":"traceutil/trace.go:171","msg":"trace[1945263753] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2979; }","duration":"1.780989505s","start":"2026-05-11T05:46:26.090954Z","end":"2026-05-11T05:46:27.871944Z","steps":["trace[1945263753] 'range keys from in-memory index tree' (duration: 1.780224526s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:46:27.872028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:46:26.090939Z","time spent":"1.781057055s","remote":"127.0.0.1:39924","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-05-11T05:46:27.872304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.052513648s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-05-11T05:46:27.872408Z","caller":"traceutil/trace.go:171","msg":"trace[2070204023] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2979; }","duration":"1.05265807s","start":"2026-05-11T05:46:26.819738Z","end":"2026-05-11T05:46:27.872396Z","steps":["trace[2070204023] 'range keys from in-memory index tree' (duration: 1.052315696s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:46:27.872526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:46:26.819717Z","time spent":"1.052797471s","remote":"127.0.0.1:40084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-05-11T05:46:27.872855Z","caller":"traceutil/trace.go:171","msg":"trace[1358212132] linearizableReadLoop","detail":"{readStateIndex:3096; appliedIndex:3095; }","duration":"905.189714ms","start":"2026-05-11T05:46:26.967648Z","end":"2026-05-11T05:46:27.872838Z","steps":["trace[1358212132] 'read index received' (duration: 902.121897ms)","trace[1358212132] 'applied index is now lower than readState.Index' (duration: 3.066837ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:46:27.872897Z","caller":"traceutil/trace.go:171","msg":"trace[365005857] transaction","detail":"{read_only:false; response_revision:2980; number_of_response:1; }","duration":"1.020485599s","start":"2026-05-11T05:46:26.852375Z","end":"2026-05-11T05:46:27.87286Z","steps":["trace[365005857] 'process raft request' (duration: 1.017380902s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:46:27.873084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:46:26.852358Z","time spent":"1.020647561s","remote":"127.0.0.1:40174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T05:46:27.872957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.305595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:46:27.873324Z","caller":"traceutil/trace.go:171","msg":"trace[96584738] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:2980; }","duration":"905.708359ms","start":"2026-05-11T05:46:26.967599Z","end":"2026-05-11T05:46:27.873307Z","steps":["trace[96584738] 'agreement among raft nodes before linearized reading' (duration: 905.318135ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:46:27.87338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:46:26.967578Z","time spent":"905.78352ms","remote":"127.0.0.1:39170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-05-11T05:46:27.875209Z","caller":"traceutil/trace.go:171","msg":"trace[958440442] transaction","detail":"{read_only:false; response_revision:2981; number_of_response:1; }","duration":"180.606389ms","start":"2026-05-11T05:46:27.694594Z","end":"2026-05-11T05:46:27.8752Z","steps":["trace[958440442] 'process raft request' (duration: 180.425307ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:46:27.875258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.548633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-05-11T05:46:27.87532Z","caller":"traceutil/trace.go:171","msg":"trace[25302278] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2981; }","duration":"316.657993ms","start":"2026-05-11T05:46:27.558649Z","end":"2026-05-11T05:46:27.875307Z","steps":["trace[25302278] 'agreement among raft nodes before linearized reading' (duration: 316.471301ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:46:27.875354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:46:27.558629Z","time spent":"316.717644ms","remote":"127.0.0.1:40088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"info","ts":"2026-05-11T05:47:33.424723Z","caller":"traceutil/trace.go:171","msg":"trace[1751947680] transaction","detail":"{read_only:false; response_revision:3374; number_of_response:1; }","duration":"100.823676ms","start":"2026-05-11T05:47:33.323868Z","end":"2026-05-11T05:47:33.424692Z","steps":["trace[1751947680] 'process raft request' (duration: 100.615694ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:49:25.327031Z","caller":"traceutil/trace.go:171","msg":"trace[2111726426] transaction","detail":"{read_only:false; response_revision:4506; number_of_response:1; }","duration":"113.216639ms","start":"2026-05-11T05:49:25.213785Z","end":"2026-05-11T05:49:25.327001Z","steps":["trace[2111726426] 'process raft request' (duration: 112.519321ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:49:25.425317Z","caller":"traceutil/trace.go:171","msg":"trace[1582209436] transaction","detail":"{read_only:false; response_revision:4507; number_of_response:1; }","duration":"208.190108ms","start":"2026-05-11T05:49:25.217099Z","end":"2026-05-11T05:49:25.425289Z","steps":["trace[1582209436] 'process raft request' (duration: 168.604889ms)","trace[1582209436] 'compare' (duration: 39.320927ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:49:25.425366Z","caller":"traceutil/trace.go:171","msg":"trace[441548446] linearizableReadLoop","detail":"{readStateIndex:4687; appliedIndex:4685; }","duration":"195.052677ms","start":"2026-05-11T05:49:25.230292Z","end":"2026-05-11T05:49:25.425345Z","steps":["trace[441548446] 'read index received' (duration: 95.90091ms)","trace[441548446] 'applied index is now lower than readState.Index' (duration: 99.150697ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:49:25.425485Z","caller":"traceutil/trace.go:171","msg":"trace[1423394896] transaction","detail":"{read_only:false; response_revision:4509; number_of_response:1; }","duration":"147.140167ms","start":"2026-05-11T05:49:25.278334Z","end":"2026-05-11T05:49:25.425474Z","steps":["trace[1423394896] 'process raft request' (duration: 146.940585ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:49:25.425659Z","caller":"traceutil/trace.go:171","msg":"trace[1251819045] transaction","detail":"{read_only:false; response_revision:4508; number_of_response:1; }","duration":"148.127968ms","start":"2026-05-11T05:49:25.277493Z","end":"2026-05-11T05:49:25.425621Z","steps":["trace[1251819045] 'process raft request' (duration: 147.687483ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:49:25.425891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.367805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-05-11T05:49:25.42597Z","caller":"traceutil/trace.go:171","msg":"trace[1392262243] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4509; }","duration":"155.465276ms","start":"2026-05-11T05:49:25.270488Z","end":"2026-05-11T05:49:25.425954Z","steps":["trace[1392262243] 'agreement among raft nodes before linearized reading' (duration: 155.257144ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:49:25.425902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.601563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-11T05:49:25.426048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.888898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-05-11T05:49:25.42606Z","caller":"traceutil/trace.go:171","msg":"trace[2015899841] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:4509; }","duration":"195.803564ms","start":"2026-05-11T05:49:25.23024Z","end":"2026-05-11T05:49:25.426044Z","steps":["trace[2015899841] 'agreement among raft nodes before linearized reading' (duration: 195.597092ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:49:25.426077Z","caller":"traceutil/trace.go:171","msg":"trace[371681613] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4509; }","duration":"152.96049ms","start":"2026-05-11T05:49:25.273109Z","end":"2026-05-11T05:49:25.426069Z","steps":["trace[371681613] 'agreement among raft nodes before linearized reading' (duration: 152.854318ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:49:34.068878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.205482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-05-11T05:49:34.06897Z","caller":"traceutil/trace.go:171","msg":"trace[2100920449] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:4732; }","duration":"155.329424ms","start":"2026-05-11T05:49:33.913621Z","end":"2026-05-11T05:49:34.06895Z","steps":["trace[2100920449] 'range keys from in-memory index tree' (duration: 155.072121ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:49:34.06905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.381571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-05-11T05:49:34.069137Z","caller":"traceutil/trace.go:171","msg":"trace[1477733682] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4732; }","duration":"174.471972ms","start":"2026-05-11T05:49:33.89464Z","end":"2026-05-11T05:49:34.069112Z","steps":["trace[1477733682] 'range keys from in-memory index tree' (duration: 174.197899ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:49:34.069148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.984775ms","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-05-11T05:49:34.069312Z","caller":"traceutil/trace.go:171","msg":"trace[1800962965] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:4732; }","duration":"137.200148ms","start":"2026-05-11T05:49:33.932073Z","end":"2026-05-11T05:49:34.069273Z","steps":["trace[1800962965] 'count revisions from in-memory index tree' (duration: 136.855443ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:49:34.302431Z","caller":"traceutil/trace.go:171","msg":"trace[1265599874] transaction","detail":"{read_only:false; response_revision:4733; number_of_response:1; }","duration":"196.064497ms","start":"2026-05-11T05:49:34.106347Z","end":"2026-05-11T05:49:34.302412Z","steps":["trace[1265599874] 'process raft request' (duration: 195.846674ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:49:34.594496Z","caller":"traceutil/trace.go:171","msg":"trace[909426364] linearizableReadLoop","detail":"{readStateIndex:4926; appliedIndex:4925; }","duration":"133.887882ms","start":"2026-05-11T05:49:34.460585Z","end":"2026-05-11T05:49:34.594472Z","steps":["trace[909426364] 'read index received' (duration: 62.017972ms)","trace[909426364] 'applied index is now lower than readState.Index' (duration: 71.8679ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:49:34.594553Z","caller":"traceutil/trace.go:171","msg":"trace[1128836965] transaction","detail":"{read_only:false; response_revision:4742; number_of_response:1; }","duration":"156.364266ms","start":"2026-05-11T05:49:34.438164Z","end":"2026-05-11T05:49:34.594528Z","steps":["trace[1128836965] 'process raft request' (duration: 84.436685ms)","trace[1128836965] 'compare' (duration: 71.556187ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:49:34.594706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.105065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-05-11T05:49:34.594747Z","caller":"traceutil/trace.go:171","msg":"trace[1570061621] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4746; }","duration":"134.181645ms","start":"2026-05-11T05:49:34.460554Z","end":"2026-05-11T05:49:34.594736Z","steps":["trace[1570061621] 'agreement among raft nodes before linearized reading' (duration: 134.002463ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:49:34.594573Z","caller":"traceutil/trace.go:171","msg":"trace[1873484721] transaction","detail":"{read_only:false; response_revision:4746; number_of_response:1; }","duration":"129.000979ms","start":"2026-05-11T05:49:34.465548Z","end":"2026-05-11T05:49:34.594549Z","steps":["trace[1873484721] 'process raft request' (duration: 128.962339ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:49:34.594923Z","caller":"traceutil/trace.go:171","msg":"trace[162533540] transaction","detail":"{read_only:false; response_revision:4744; number_of_response:1; }","duration":"130.197851ms","start":"2026-05-11T05:49:34.464711Z","end":"2026-05-11T05:49:34.594908Z","steps":["trace[162533540] 'process raft request' (duration: 129.705426ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:49:34.594936Z","caller":"traceutil/trace.go:171","msg":"trace[136765093] transaction","detail":"{read_only:false; response_revision:4743; number_of_response:1; }","duration":"131.394325ms","start":"2026-05-11T05:49:34.463513Z","end":"2026-05-11T05:49:34.594907Z","steps":["trace[136765093] 'process raft request' (duration: 130.836809ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:49:34.59498Z","caller":"traceutil/trace.go:171","msg":"trace[1674850901] transaction","detail":"{read_only:false; response_revision:4745; number_of_response:1; }","duration":"130.210702ms","start":"2026-05-11T05:49:34.464755Z","end":"2026-05-11T05:49:34.594965Z","steps":["trace[1674850901] 'process raft request' (duration: 129.723526ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:49:42.559994Z","caller":"traceutil/trace.go:171","msg":"trace[292639417] linearizableReadLoop","detail":"{readStateIndex:5063; appliedIndex:5059; }","duration":"128.267539ms","start":"2026-05-11T05:49:42.4317Z","end":"2026-05-11T05:49:42.559968Z","steps":["trace[292639417] 'read index received' (duration: 35.958088ms)","trace[292639417] 'applied index is now lower than readState.Index' (duration: 92.308351ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:49:42.560085Z","caller":"traceutil/trace.go:171","msg":"trace[471099309] transaction","detail":"{read_only:false; response_revision:4872; number_of_response:1; }","duration":"131.606186ms","start":"2026-05-11T05:49:42.428444Z","end":"2026-05-11T05:49:42.56005Z","steps":["trace[471099309] 'process raft request' (duration: 99.786801ms)","trace[471099309] 'compare' (duration: 31.568003ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:49:42.560373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.642534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-05-11T05:49:42.560985Z","caller":"traceutil/trace.go:171","msg":"trace[613889833] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4872; }","duration":"128.731946ms","start":"2026-05-11T05:49:42.431679Z","end":"2026-05-11T05:49:42.560411Z","steps":["trace[613889833] 'agreement among raft nodes before linearized reading' (duration: 128.422702ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:49:47.200649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.07584ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T05:49:47.200824Z","caller":"traceutil/trace.go:171","msg":"trace[698805662] transaction","detail":"{read_only:false; response_revision:4916; number_of_response:1; }","duration":"357.909758ms","start":"2026-05-11T05:49:46.842896Z","end":"2026-05-11T05:49:47.200806Z","steps":["trace[698805662] 'process raft request' (duration: 154.596766ms)","trace[698805662] 'compare' (duration: 202.850608ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:49:47.200913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:49:46.842847Z","time spent":"358.03338ms","remote":"127.0.0.1:40174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T05:49:47.239097Z","caller":"traceutil/trace.go:171","msg":"trace[930651142] transaction","detail":"{read_only:false; response_revision:4917; number_of_response:1; }","duration":"375.724452ms","start":"2026-05-11T05:49:46.863356Z","end":"2026-05-11T05:49:47.23908Z","steps":["trace[930651142] 'process raft request' (duration: 375.56258ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:49:47.239268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:49:46.863338Z","time spent":"375.811523ms","remote":"127.0.0.1:40174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T05:49:47.239337Z","caller":"traceutil/trace.go:171","msg":"trace[1639918284] linearizableReadLoop","detail":"{readStateIndex:5110; appliedIndex:5108; }","duration":"146.071373ms","start":"2026-05-11T05:49:47.093228Z","end":"2026-05-11T05:49:47.2393Z","steps":["trace[1639918284] 'read index received' (duration: 145.389126ms)","trace[1639918284] 'applied index is now lower than readState.Index' (duration: 681.127µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:49:47.239528Z","caller":"traceutil/trace.go:171","msg":"trace[325691887] transaction","detail":"{read_only:false; response_revision:4918; number_of_response:1; }","duration":"371.479106ms","start":"2026-05-11T05:49:46.868038Z","end":"2026-05-11T05:49:47.239517Z","steps":["trace[325691887] 'process raft request' (duration: 371.004612ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:49:47.239647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:49:46.868031Z","time spent":"371.531657ms","remote":"127.0.0.1:40174","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-05-11T05:49:47.239755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.532718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:49:47.239784Z","caller":"traceutil/trace.go:171","msg":"trace[928206847] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4918; }","duration":"146.598528ms","start":"2026-05-11T05:49:47.093179Z","end":"2026-05-11T05:49:47.239777Z","steps":["trace[928206847] 'agreement among raft nodes before linearized reading' (duration: 146.551818ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:49:47.239881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.834385ms","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-05-11T05:49:47.239983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.659878ms","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-05-11T05:49:47.240052Z","caller":"traceutil/trace.go:171","msg":"trace[2069288630] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4918; }","duration":"109.751519ms","start":"2026-05-11T05:49:47.130295Z","end":"2026-05-11T05:49:47.240047Z","steps":["trace[2069288630] 'agreement among raft nodes before linearized reading' (duration: 109.630278ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:49:47.239978Z","caller":"traceutil/trace.go:171","msg":"trace[901248526] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4918; }","duration":"114.973816ms","start":"2026-05-11T05:49:47.124986Z","end":"2026-05-11T05:49:47.23996Z","steps":["trace[901248526] 'agreement among raft nodes before linearized reading' (duration: 114.720073ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:50:00.851649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.166011ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T05:50:00.85215Z","caller":"traceutil/trace.go:171","msg":"trace[373070000] transaction","detail":"{read_only:false; response_revision:5005; number_of_response:1; }","duration":"847.349336ms","start":"2026-05-11T05:50:00.004776Z","end":"2026-05-11T05:50:00.852125Z","steps":["trace[373070000] 'process raft request' (duration: 847.131514ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:50:00.85236Z","caller":"traceutil/trace.go:171","msg":"trace[2054641687] linearizableReadLoop","detail":"{readStateIndex:5198; appliedIndex:5197; }","duration":"886.445539ms","start":"2026-05-11T05:49:59.965887Z","end":"2026-05-11T05:50:00.852332Z","steps":["trace[2054641687] 'read index received' (duration: 284.49999ms)","trace[2054641687] 'applied index is now lower than readState.Index' (duration: 601.942819ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:50:00.852252Z","caller":"traceutil/trace.go:171","msg":"trace[798858738] transaction","detail":"{read_only:false; response_revision:5004; number_of_response:1; }","duration":"1.086940062s","start":"2026-05-11T05:49:59.765023Z","end":"2026-05-11T05:50:00.851963Z","steps":["trace[798858738] 'process raft request' (duration: 485.357186ms)","trace[798858738] 'compare' (duration: 600.724947ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:50:00.852616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"886.715973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.109\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-11T05:50:00.852661Z","caller":"traceutil/trace.go:171","msg":"trace[2090730717] range","detail":"{range_begin:/registry/masterleases/199.204.45.109; range_end:; response_count:1; response_revision:5005; }","duration":"886.812004ms","start":"2026-05-11T05:49:59.965835Z","end":"2026-05-11T05:50:00.852647Z","steps":["trace[2090730717] 'agreement among raft nodes before linearized reading' (duration: 886.631422ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:50:00.85249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:50:00.004757Z","time spent":"847.492758ms","remote":"127.0.0.1:40174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T05:50:00.852709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:49:59.965814Z","time spent":"886.885284ms","remote":"127.0.0.1:39962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.109\" "} {"level":"warn","ts":"2026-05-11T05:50:00.853705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:49:59.765001Z","time spent":"1.087997233s","remote":"127.0.0.1:44038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3300,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T05:50:00.861483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.719294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:50:00.861526Z","caller":"traceutil/trace.go:171","msg":"trace[886106938] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5006; }","duration":"767.802986ms","start":"2026-05-11T05:50:00.093712Z","end":"2026-05-11T05:50:00.861515Z","steps":["trace[886106938] 'agreement among raft nodes before linearized reading' (duration: 767.729675ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:50:00.86155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:50:00.093686Z","time spent":"767.858437ms","remote":"127.0.0.1:39926","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-05-11T05:50:00.861558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.123189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:50:00.861594Z","caller":"traceutil/trace.go:171","msg":"trace[1577090360] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:5006; }","duration":"430.212389ms","start":"2026-05-11T05:50:00.43137Z","end":"2026-05-11T05:50:00.861583Z","steps":["trace[1577090360] 'agreement among raft nodes before linearized reading' (duration: 430.142059ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:50:00.861736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.735607ms","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-05-11T05:50:00.861809Z","caller":"traceutil/trace.go:171","msg":"trace[2052711745] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:5006; }","duration":"613.866048ms","start":"2026-05-11T05:50:00.247907Z","end":"2026-05-11T05:50:00.861773Z","steps":["trace[2052711745] 'agreement among raft nodes before linearized reading' (duration: 613.613986ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:50:00.861648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:50:00.431347Z","time spent":"430.267171ms","remote":"127.0.0.1:39082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-05-11T05:50:00.861852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:50:00.247892Z","time spent":"613.947999ms","remote":"127.0.0.1:40008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":51,"response size":30,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"info","ts":"2026-05-11T05:51:07.127146Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2877} {"level":"info","ts":"2026-05-11T05:51:07.24833Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2877,"took":"119.891597ms","hash":4109367908,"current-db-size-bytes":53932032,"current-db-size":"54 MB","current-db-size-in-use-bytes":29114368,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-11T05:51:07.248395Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4109367908,"revision":2877,"compact-revision":-1} {"level":"info","ts":"2026-05-11T05:53:28.133083Z","caller":"traceutil/trace.go:171","msg":"trace[1636054280] transaction","detail":"{read_only:false; response_revision:6485; number_of_response:1; }","duration":"137.875899ms","start":"2026-05-11T05:53:27.995188Z","end":"2026-05-11T05:53:28.133063Z","steps":["trace[1636054280] 'process raft request' (duration: 137.739448ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:53:28.195644Z","caller":"traceutil/trace.go:171","msg":"trace[829626447] transaction","detail":"{read_only:false; response_revision:6486; number_of_response:1; }","duration":"166.688081ms","start":"2026-05-11T05:53:28.028923Z","end":"2026-05-11T05:53:28.195611Z","steps":["trace[829626447] 'process raft request' (duration: 166.467408ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:53:28.195602Z","caller":"traceutil/trace.go:171","msg":"trace[308841996] linearizableReadLoop","detail":"{readStateIndex:6731; appliedIndex:6729; }","duration":"152.121273ms","start":"2026-05-11T05:53:28.043456Z","end":"2026-05-11T05:53:28.195577Z","steps":["trace[308841996] 'read index received' (duration: 89.539467ms)","trace[308841996] 'applied index is now lower than readState.Index' (duration: 62.580426ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:53:28.195662Z","caller":"traceutil/trace.go:171","msg":"trace[1454454682] transaction","detail":"{read_only:false; response_revision:6487; number_of_response:1; }","duration":"146.739995ms","start":"2026-05-11T05:53:28.048895Z","end":"2026-05-11T05:53:28.195635Z","steps":["trace[1454454682] 'process raft request' (duration: 146.637884ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:53:28.195941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.457357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4784"} {"level":"info","ts":"2026-05-11T05:53:28.195986Z","caller":"traceutil/trace.go:171","msg":"trace[2012386281] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6487; }","duration":"152.562488ms","start":"2026-05-11T05:53:28.043412Z","end":"2026-05-11T05:53:28.195974Z","steps":["trace[2012386281] 'agreement among raft nodes before linearized reading' (duration: 152.357895ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:53:28.19617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.222486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:53:28.196263Z","caller":"traceutil/trace.go:171","msg":"trace[1503825000] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6487; }","duration":"104.352977ms","start":"2026-05-11T05:53:28.091895Z","end":"2026-05-11T05:53:28.196248Z","steps":["trace[1503825000] 'agreement among raft nodes before linearized reading' (duration: 104.186196ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:53:28.196229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.813074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-11T05:53:28.196502Z","caller":"traceutil/trace.go:171","msg":"trace[1099039770] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:6487; }","duration":"145.124427ms","start":"2026-05-11T05:53:28.051358Z","end":"2026-05-11T05:53:28.196483Z","steps":["trace[1099039770] 'agreement among raft nodes before linearized reading' (duration: 144.690142ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:56:07.133185Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5577} {"level":"info","ts":"2026-05-11T05:56:07.209836Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5577,"took":"75.602267ms","hash":3397971467,"current-db-size-bytes":53932032,"current-db-size":"54 MB","current-db-size-in-use-bytes":17903616,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-11T05:56:07.209909Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3397971467,"revision":5577,"compact-revision":2877}