{"level":"warn","ts":"2026-07-15T14:24:34.276869Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-15T14:24:34.276981Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.162:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.162:2380","--initial-cluster=instance=https://199.19.213.162:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.162:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.162:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-15T14:24:34.277066Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-15T14:24:34.277078Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.162:2380"]} {"level":"info","ts":"2026-07-15T14:24:34.277101Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-15T14:24:34.277922Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.162:2379"]} {"level":"info","ts":"2026-07-15T14:24:34.278039Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.162:2380"],"listen-peer-urls":["https://199.19.213.162:2380"],"advertise-client-urls":["https://199.19.213.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.162:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-15T14:24:34.282182Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.761328ms"} {"level":"info","ts":"2026-07-15T14:24:34.288933Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"8c0a14645b8502c5","cluster-id":"49fe27222e1c6db4"} {"level":"info","ts":"2026-07-15T14:24:34.289086Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 switched to configuration voters=()"} {"level":"info","ts":"2026-07-15T14:24:34.289123Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 became follower at term 0"} {"level":"info","ts":"2026-07-15T14:24:34.28918Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8c0a14645b8502c5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-15T14:24:34.2892Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 became follower at term 1"} {"level":"info","ts":"2026-07-15T14:24:34.289286Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 switched to configuration voters=(10090900336341746373)"} {"level":"warn","ts":"2026-07-15T14:24:34.29453Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-15T14:24:34.295582Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-15T14:24:34.296833Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-15T14:24:34.298738Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"8c0a14645b8502c5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-15T14:24:34.299056Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-15T14:24:34.299161Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-15T14:24:34.299249Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-15T14:24:34.299204Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8c0a14645b8502c5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-15T14:24:34.304508Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 switched to configuration voters=(10090900336341746373)"} {"level":"info","ts":"2026-07-15T14:24:34.304748Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"49fe27222e1c6db4","local-member-id":"8c0a14645b8502c5","added-peer-id":"8c0a14645b8502c5","added-peer-peer-urls":["https://199.19.213.162:2380"]} {"level":"info","ts":"2026-07-15T14:24:34.305843Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-15T14:24:34.305966Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.162:2380"} {"level":"info","ts":"2026-07-15T14:24:34.306052Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.162:2380"} {"level":"info","ts":"2026-07-15T14:24:34.307543Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"8c0a14645b8502c5","initial-advertise-peer-urls":["https://199.19.213.162:2380"],"listen-peer-urls":["https://199.19.213.162:2380"],"advertise-client-urls":["https://199.19.213.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-15T14:24:34.307617Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-15T14:24:34.890203Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 is starting a new election at term 1"} {"level":"info","ts":"2026-07-15T14:24:34.8903Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-15T14:24:34.890328Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 received MsgPreVoteResp from 8c0a14645b8502c5 at term 1"} {"level":"info","ts":"2026-07-15T14:24:34.890348Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 became candidate at term 2"} {"level":"info","ts":"2026-07-15T14:24:34.890365Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 received MsgVoteResp from 8c0a14645b8502c5 at term 2"} {"level":"info","ts":"2026-07-15T14:24:34.890384Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 became leader at term 2"} {"level":"info","ts":"2026-07-15T14:24:34.890403Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8c0a14645b8502c5 elected leader 8c0a14645b8502c5 at term 2"} {"level":"info","ts":"2026-07-15T14:24:34.891473Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"8c0a14645b8502c5","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.162:2379]}","request-path":"/0/members/8c0a14645b8502c5/attributes","cluster-id":"49fe27222e1c6db4","publish-timeout":"7s"} {"level":"info","ts":"2026-07-15T14:24:34.891541Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-15T14:24:34.89205Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T14:24:34.892122Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-15T14:24:34.892969Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"49fe27222e1c6db4","local-member-id":"8c0a14645b8502c5","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T14:24:34.89308Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-15T14:24:34.893093Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T14:24:34.893133Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T14:24:34.893166Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-15T14:24:34.894228Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-15T14:24:34.895286Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.162:2379"} {"level":"warn","ts":"2026-07-15T14:24:35.21587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.708436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-15T14:24:35.215939Z","caller":"traceutil/trace.go:171","msg":"trace[1961929217] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"188.806551ms","start":"2026-07-15T14:24:35.027119Z","end":"2026-07-15T14:24:35.215926Z","steps":["trace[1961929217] 'range keys from in-memory index tree' (duration: 188.640332ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:24:35.216114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.9514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-15T14:24:35.216149Z","caller":"traceutil/trace.go:171","msg":"trace[435873600] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"189.002773ms","start":"2026-07-15T14:24:35.027134Z","end":"2026-07-15T14:24:35.216136Z","steps":["trace[435873600] 'count revisions from in-memory index tree' (duration: 188.849884ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:24:35.216171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.097767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-15T14:24:35.216244Z","caller":"traceutil/trace.go:171","msg":"trace[1643996954] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1; }","duration":"123.254387ms","start":"2026-07-15T14:24:35.092962Z","end":"2026-07-15T14:24:35.216217Z","steps":["trace[1643996954] 'count revisions from in-memory index tree' (duration: 123.053725ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:24:35.216262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.804897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-15T14:24:35.216293Z","caller":"traceutil/trace.go:171","msg":"trace[1811197783] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1; }","duration":"177.857511ms","start":"2026-07-15T14:24:35.038424Z","end":"2026-07-15T14:24:35.216281Z","steps":["trace[1811197783] 'range keys from in-memory index tree' (duration: 177.775446ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:24:35.216461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.084555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-15T14:24:35.2165Z","caller":"traceutil/trace.go:171","msg":"trace[62381332] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1; }","duration":"178.150048ms","start":"2026-07-15T14:24:35.038342Z","end":"2026-07-15T14:24:35.216492Z","steps":["trace[62381332] 'count revisions from in-memory index tree' (duration: 177.969608ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:24:35.216443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.185049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-15T14:24:35.216588Z","caller":"traceutil/trace.go:171","msg":"trace[1855035853] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1; }","duration":"113.36623ms","start":"2026-07-15T14:24:35.103199Z","end":"2026-07-15T14:24:35.216565Z","steps":["trace[1855035853] 'range keys from in-memory index tree' (duration: 113.142876ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:24:35.216681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.250768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-15T14:24:35.216706Z","caller":"traceutil/trace.go:171","msg":"trace[1383209814] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"157.289971ms","start":"2026-07-15T14:24:35.059409Z","end":"2026-07-15T14:24:35.216699Z","steps":["trace[1383209814] 'count revisions from in-memory index tree' (duration: 157.184954ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:24:35.216777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.261959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-15T14:24:35.216803Z","caller":"traceutil/trace.go:171","msg":"trace[1869303639] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"157.305451ms","start":"2026-07-15T14:24:35.059488Z","end":"2026-07-15T14:24:35.216794Z","steps":["trace[1869303639] 'range keys from in-memory index tree' (duration: 157.206945ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:24:35.216852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.125146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-15T14:24:35.216876Z","caller":"traceutil/trace.go:171","msg":"trace[2072327737] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1; }","duration":"145.171219ms","start":"2026-07-15T14:24:35.071699Z","end":"2026-07-15T14:24:35.21687Z","steps":["trace[2072327737] 'range keys from in-memory index tree' (duration: 145.091244ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:24:35.216973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.3405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-15T14:24:35.217047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.912031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-15T14:24:35.217073Z","caller":"traceutil/trace.go:171","msg":"trace[1309361175] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1; }","duration":"134.971245ms","start":"2026-07-15T14:24:35.082095Z","end":"2026-07-15T14:24:35.217066Z","steps":["trace[1309361175] 'range keys from in-memory index tree' (duration: 134.870048ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:24:35.217056Z","caller":"traceutil/trace.go:171","msg":"trace[754855013] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1; }","duration":"113.466336ms","start":"2026-07-15T14:24:35.103577Z","end":"2026-07-15T14:24:35.217044Z","steps":["trace[754855013] 'count revisions from in-memory index tree' (duration: 113.246723ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:24:35.217277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.302464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-15T14:24:35.217354Z","caller":"traceutil/trace.go:171","msg":"trace[794371150] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1; }","duration":"104.40855ms","start":"2026-07-15T14:24:35.112934Z","end":"2026-07-15T14:24:35.217343Z","steps":["trace[794371150] 'range keys from in-memory index tree' (duration: 104.133423ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:24:35.2158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.092107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-15T14:24:35.217561Z","caller":"traceutil/trace.go:171","msg":"trace[1255591712] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1; }","duration":"124.890926ms","start":"2026-07-15T14:24:35.092662Z","end":"2026-07-15T14:24:35.217553Z","steps":["trace[1255591712] 'range keys from in-memory index tree' (duration: 123.020423ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:24:35.217634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.480965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-15T14:24:35.215837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.409537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-15T14:24:35.217725Z","caller":"traceutil/trace.go:171","msg":"trace[454710876] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1; }","duration":"216.326973ms","start":"2026-07-15T14:24:35.00139Z","end":"2026-07-15T14:24:35.217717Z","steps":["trace[454710876] 'count revisions from in-memory index tree' (duration: 213.834782ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:24:35.217706Z","caller":"traceutil/trace.go:171","msg":"trace[412386948] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1; }","duration":"104.649144ms","start":"2026-07-15T14:24:35.113046Z","end":"2026-07-15T14:24:35.217695Z","steps":["trace[412386948] 'count revisions from in-memory index tree' (duration: 104.344755ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:24:35.215848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.237162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-15T14:24:35.217985Z","caller":"traceutil/trace.go:171","msg":"trace[2111143932] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"204.403533ms","start":"2026-07-15T14:24:35.013574Z","end":"2026-07-15T14:24:35.217977Z","steps":["trace[2111143932] 'range keys from in-memory index tree' (duration: 202.155857ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:24:35.215859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.185019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-15T14:24:35.218186Z","caller":"traceutil/trace.go:171","msg":"trace[1396547553] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"204.53155ms","start":"2026-07-15T14:24:35.013646Z","end":"2026-07-15T14:24:35.218178Z","steps":["trace[1396547553] 'count revisions from in-memory index tree' (duration: 202.103864ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:24:35.216609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.276752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-15T14:24:35.218416Z","caller":"traceutil/trace.go:171","msg":"trace[897027040] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1; }","duration":"168.108393ms","start":"2026-07-15T14:24:35.0503Z","end":"2026-07-15T14:24:35.218408Z","steps":["trace[897027040] 'count revisions from in-memory index tree' (duration: 166.209949ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:24:35.216997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.335489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-15T14:24:35.218648Z","caller":"traceutil/trace.go:171","msg":"trace[2065165581] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1; }","duration":"147.006739ms","start":"2026-07-15T14:24:35.071623Z","end":"2026-07-15T14:24:35.218629Z","steps":["trace[2065165581] 'count revisions from in-memory index tree' (duration: 145.214902ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:24:35.216631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.24493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-15T14:24:35.218713Z","caller":"traceutil/trace.go:171","msg":"trace[847264566] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1; }","duration":"168.353198ms","start":"2026-07-15T14:24:35.050353Z","end":"2026-07-15T14:24:35.218706Z","steps":["trace[847264566] 'range keys from in-memory index tree' (duration: 166.186376ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:24:35.217026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.959234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-15T14:24:35.218883Z","caller":"traceutil/trace.go:171","msg":"trace[462899143] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1; }","duration":"136.848377ms","start":"2026-07-15T14:24:35.082026Z","end":"2026-07-15T14:24:35.218874Z","steps":["trace[462899143] 'count revisions from in-memory index tree' (duration: 134.908651ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:25:16.052829Z","caller":"traceutil/trace.go:171","msg":"trace[1036981087] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"157.650734ms","start":"2026-07-15T14:25:15.895155Z","end":"2026-07-15T14:25:16.052805Z","steps":["trace[1036981087] 'process raft request' (duration: 157.142659ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:25:18.116966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.673664ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-15T14:25:18.117093Z","caller":"traceutil/trace.go:171","msg":"trace[1197830088] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:657; }","duration":"290.557102ms","start":"2026-07-15T14:25:17.826524Z","end":"2026-07-15T14:25:18.117081Z","steps":["trace[1197830088] 'read index received' (duration: 86.250338ms)","trace[1197830088] 'applied index is now lower than readState.Index' (duration: 204.305834ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T14:25:18.117167Z","caller":"traceutil/trace.go:171","msg":"trace[1777548729] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"366.14019ms","start":"2026-07-15T14:25:17.75102Z","end":"2026-07-15T14:25:18.11716Z","steps":["trace[1777548729] 'process raft request' (duration: 161.801534ms)","trace[1777548729] 'compare' (duration: 203.565959ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T14:25:18.117223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:25:17.750996Z","time spent":"366.208153ms","remote":"127.0.0.1:50256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-15T14:25:18.11742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.572781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.162\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-07-15T14:25:18.117547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.019024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-f7t54\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-15T14:25:18.117584Z","caller":"traceutil/trace.go:171","msg":"trace[1261669034] range","detail":"{range_begin:/registry/pods/kube-system/cilium-f7t54; range_end:; response_count:1; response_revision:633; }","duration":"291.084756ms","start":"2026-07-15T14:25:17.826491Z","end":"2026-07-15T14:25:18.117576Z","steps":["trace[1261669034] 'agreement among raft nodes before linearized reading' (duration: 290.9385ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:25:18.117677Z","caller":"traceutil/trace.go:171","msg":"trace[539250257] range","detail":"{range_begin:/registry/masterleases/199.19.213.162; range_end:; response_count:1; response_revision:633; }","duration":"171.679287ms","start":"2026-07-15T14:25:17.945797Z","end":"2026-07-15T14:25:18.117476Z","steps":["trace[539250257] 'agreement among raft nodes before linearized reading' (duration: 171.509979ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:25:34.363717Z","caller":"traceutil/trace.go:171","msg":"trace[1991673913] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"124.268821ms","start":"2026-07-15T14:25:34.23942Z","end":"2026-07-15T14:25:34.363689Z","steps":["trace[1991673913] 'process raft request' (duration: 124.116595ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:26:26.329078Z","caller":"traceutil/trace.go:171","msg":"trace[217382933] transaction","detail":"{read_only:false; response_revision:1463; number_of_response:1; }","duration":"118.481216ms","start":"2026-07-15T14:26:26.210562Z","end":"2026-07-15T14:26:26.329043Z","steps":["trace[217382933] 'process raft request' (duration: 118.337471ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:26:51.653242Z","caller":"traceutil/trace.go:171","msg":"trace[1336740666] linearizableReadLoop","detail":"{readStateIndex:1956; appliedIndex:1955; }","duration":"127.007102ms","start":"2026-07-15T14:26:51.526216Z","end":"2026-07-15T14:26:51.653224Z","steps":["trace[1336740666] 'read index received' (duration: 126.871318ms)","trace[1336740666] 'applied index is now lower than readState.Index' (duration: 134.834µs)"],"step_count":2} {"level":"warn","ts":"2026-07-15T14:26:51.653392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.167448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-15T14:26:51.653464Z","caller":"traceutil/trace.go:171","msg":"trace[1434119309] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1881; }","duration":"127.24033ms","start":"2026-07-15T14:26:51.526167Z","end":"2026-07-15T14:26:51.653408Z","steps":["trace[1434119309] 'agreement among raft nodes before linearized reading' (duration: 127.129047ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:26:51.653346Z","caller":"traceutil/trace.go:171","msg":"trace[119733751] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"146.745922ms","start":"2026-07-15T14:26:51.506577Z","end":"2026-07-15T14:26:51.653323Z","steps":["trace[119733751] 'process raft request' (duration: 146.527246ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:26:51.981764Z","caller":"traceutil/trace.go:171","msg":"trace[2074485072] linearizableReadLoop","detail":"{readStateIndex:1958; appliedIndex:1956; }","duration":"250.643631ms","start":"2026-07-15T14:26:51.731105Z","end":"2026-07-15T14:26:51.981748Z","steps":["trace[2074485072] 'read index received' (duration: 159.201008ms)","trace[2074485072] 'applied index is now lower than readState.Index' (duration: 91.441973ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T14:26:51.981754Z","caller":"traceutil/trace.go:171","msg":"trace[29691250] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"333.350376ms","start":"2026-07-15T14:26:51.648371Z","end":"2026-07-15T14:26:51.981721Z","steps":["trace[29691250] 'process raft request' (duration: 241.831391ms)","trace[29691250] 'compare' (duration: 91.3377ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T14:26:51.981862Z","caller":"traceutil/trace.go:171","msg":"trace[456366793] transaction","detail":"{read_only:false; response_revision:1883; number_of_response:1; }","duration":"319.744075ms","start":"2026-07-15T14:26:51.66211Z","end":"2026-07-15T14:26:51.981854Z","steps":["trace[456366793] 'process raft request' (duration: 319.55391ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:26:51.982068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:26:51.64835Z","time spent":"333.482251ms","remote":"127.0.0.1:50008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T14:26:51.982091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.991671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-15T14:26:51.982113Z","caller":"traceutil/trace.go:171","msg":"trace[186706475] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1883; }","duration":"251.058914ms","start":"2026-07-15T14:26:51.731049Z","end":"2026-07-15T14:26:51.982108Z","steps":["trace[186706475] 'agreement among raft nodes before linearized reading' (duration: 250.900579ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:26:51.982196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:26:51.662091Z","time spent":"319.810868ms","remote":"127.0.0.1:50008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T14:26:52.410624Z","caller":"traceutil/trace.go:171","msg":"trace[887082334] transaction","detail":"{read_only:false; response_revision:1886; number_of_response:1; }","duration":"126.845842ms","start":"2026-07-15T14:26:52.28374Z","end":"2026-07-15T14:26:52.410586Z","steps":["trace[887082334] 'process raft request' (duration: 126.520502ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:27:03.863243Z","caller":"traceutil/trace.go:171","msg":"trace[998098688] transaction","detail":"{read_only:false; response_revision:1984; number_of_response:1; }","duration":"149.000185ms","start":"2026-07-15T14:27:03.714208Z","end":"2026-07-15T14:27:03.863208Z","steps":["trace[998098688] 'process raft request' (duration: 148.766568ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:27:06.98873Z","caller":"traceutil/trace.go:171","msg":"trace[948969649] transaction","detail":"{read_only:false; response_revision:2011; number_of_response:1; }","duration":"150.268023ms","start":"2026-07-15T14:27:06.838435Z","end":"2026-07-15T14:27:06.988703Z","steps":["trace[948969649] 'process raft request' (duration: 141.409405ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:27:27.6138Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":199741019632566784,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-15T14:27:27.824889Z","caller":"traceutil/trace.go:171","msg":"trace[328248224] transaction","detail":"{read_only:false; response_revision:2126; number_of_response:1; }","duration":"752.844552ms","start":"2026-07-15T14:27:27.072022Z","end":"2026-07-15T14:27:27.824867Z","steps":["trace[328248224] 'process raft request' (duration: 752.700268ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:27:27.825192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:27:27.072005Z","time spent":"753.004537ms","remote":"127.0.0.1:50008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T14:27:27.825957Z","caller":"traceutil/trace.go:171","msg":"trace[1440412182] linearizableReadLoop","detail":"{readStateIndex:2210; appliedIndex:2208; }","duration":"713.193998ms","start":"2026-07-15T14:27:27.112746Z","end":"2026-07-15T14:27:27.82594Z","steps":["trace[1440412182] 'read index received' (duration: 711.952234ms)","trace[1440412182] 'applied index is now lower than readState.Index' (duration: 1.241014ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T14:27:27.826025Z","caller":"traceutil/trace.go:171","msg":"trace[2099944885] transaction","detail":"{read_only:false; response_revision:2127; number_of_response:1; }","duration":"255.383688ms","start":"2026-07-15T14:27:27.570633Z","end":"2026-07-15T14:27:27.826017Z","steps":["trace[2099944885] 'process raft request' (duration: 255.238474ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:27:27.826231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.465444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-15T14:27:27.826303Z","caller":"traceutil/trace.go:171","msg":"trace[113943916] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2127; }","duration":"713.580978ms","start":"2026-07-15T14:27:27.112706Z","end":"2026-07-15T14:27:27.826287Z","steps":["trace[113943916] 'agreement among raft nodes before linearized reading' (duration: 713.366302ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:27:27.826338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:27:27.112691Z","time spent":"713.640579ms","remote":"127.0.0.1:50008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-15T14:27:28.452215Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":199741019632566788,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-15T14:27:28.617801Z","caller":"traceutil/trace.go:171","msg":"trace[1702782931] transaction","detail":"{read_only:false; response_revision:2128; number_of_response:1; }","duration":"785.453599ms","start":"2026-07-15T14:27:27.829366Z","end":"2026-07-15T14:27:28.61482Z","steps":["trace[1702782931] 'process raft request' (duration: 753.331282ms)","trace[1702782931] 'compare' (duration: 31.826479ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T14:27:28.617909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:27:27.829347Z","time spent":"788.505891ms","remote":"127.0.0.1:50008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T14:27:28.6235Z","caller":"traceutil/trace.go:171","msg":"trace[561034492] linearizableReadLoop","detail":"{readStateIndex:2212; appliedIndex:2210; }","duration":"671.720568ms","start":"2026-07-15T14:27:27.951756Z","end":"2026-07-15T14:27:28.623477Z","steps":["trace[561034492] 'read index received' (duration: 630.895765ms)","trace[561034492] 'applied index is now lower than readState.Index' (duration: 40.823623ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T14:27:28.623701Z","caller":"traceutil/trace.go:171","msg":"trace[27914672] transaction","detail":"{read_only:false; response_revision:2129; number_of_response:1; }","duration":"519.686189ms","start":"2026-07-15T14:27:28.104003Z","end":"2026-07-15T14:27:28.623689Z","steps":["trace[27914672] 'process raft request' (duration: 519.268037ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:27:28.62385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:27:28.103984Z","time spent":"519.783492ms","remote":"127.0.0.1:50008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T14:27:28.624383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.630033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.162\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-15T14:27:28.624473Z","caller":"traceutil/trace.go:171","msg":"trace[664163555] range","detail":"{range_begin:/registry/masterleases/199.19.213.162; range_end:; response_count:1; response_revision:2130; }","duration":"672.743985ms","start":"2026-07-15T14:27:27.951715Z","end":"2026-07-15T14:27:28.624459Z","steps":["trace[664163555] 'agreement among raft nodes before linearized reading' (duration: 672.56064ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:27:28.624518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:27:27.951702Z","time spent":"672.806957ms","remote":"127.0.0.1:49774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.162\" "} {"level":"warn","ts":"2026-07-15T14:27:28.624728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.652645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-15T14:27:28.624801Z","caller":"traceutil/trace.go:171","msg":"trace[679598038] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2130; }","duration":"661.743418ms","start":"2026-07-15T14:27:27.96304Z","end":"2026-07-15T14:27:28.624783Z","steps":["trace[679598038] 'agreement among raft nodes before linearized reading' (duration: 661.627295ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:27:28.625019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:27:27.963025Z","time spent":"661.978245ms","remote":"127.0.0.1:50008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-15T14:27:28.625313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.977893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-15T14:27:28.625345Z","caller":"traceutil/trace.go:171","msg":"trace[1494005591] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2130; }","duration":"485.048054ms","start":"2026-07-15T14:27:28.140287Z","end":"2026-07-15T14:27:28.625335Z","steps":["trace[1494005591] 'agreement among raft nodes before linearized reading' (duration: 484.974562ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:27:28.625354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.813356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-15T14:27:28.625412Z","caller":"traceutil/trace.go:171","msg":"trace[135093666] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2130; }","duration":"448.911089ms","start":"2026-07-15T14:27:28.176489Z","end":"2026-07-15T14:27:28.6254Z","steps":["trace[135093666] 'agreement among raft nodes before linearized reading' (duration: 448.765245ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:27:28.62542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.994102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-15T14:27:28.625442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:27:28.176469Z","time spent":"448.966941ms","remote":"127.0.0.1:50008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-15T14:27:28.625453Z","caller":"traceutil/trace.go:171","msg":"trace[686167138] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:2130; }","duration":"279.072604ms","start":"2026-07-15T14:27:28.346371Z","end":"2026-07-15T14:27:28.625443Z","steps":["trace[686167138] 'agreement among raft nodes before linearized reading' (duration: 279.011573ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:27:28.625371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:27:28.140261Z","time spent":"485.102295ms","remote":"127.0.0.1:50008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-15T14:27:28.625528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.987971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T14:27:28.625567Z","caller":"traceutil/trace.go:171","msg":"trace[717748205] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2130; }","duration":"439.052543ms","start":"2026-07-15T14:27:28.186505Z","end":"2026-07-15T14:27:28.625557Z","steps":["trace[717748205] 'agreement among raft nodes before linearized reading' (duration: 438.996602ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:27:28.625597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:27:28.186491Z","time spent":"439.099614ms","remote":"127.0.0.1:49750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-15T14:27:28.96618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.275491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-15T14:27:28.966239Z","caller":"traceutil/trace.go:171","msg":"trace[1385480079] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2134; }","duration":"135.358424ms","start":"2026-07-15T14:27:28.830868Z","end":"2026-07-15T14:27:28.966226Z","steps":["trace[1385480079] 'range keys from in-memory index tree' (duration: 135.170609ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:27:30.526288Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":199741019632566809,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-15T14:27:30.799818Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.090973921s","expected-duration":"1s"} {"level":"info","ts":"2026-07-15T14:27:30.800837Z","caller":"traceutil/trace.go:171","msg":"trace[1853426217] transaction","detail":"{read_only:false; response_revision:2137; number_of_response:1; }","duration":"1.092058749s","start":"2026-07-15T14:27:29.708758Z","end":"2026-07-15T14:27:30.800817Z","steps":["trace[1853426217] 'process raft request' (duration: 1.091931386s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:27:30.800998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:27:29.708747Z","time spent":"1.092181793s","remote":"127.0.0.1:49824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":831,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T14:27:30.809175Z","caller":"traceutil/trace.go:171","msg":"trace[720458305] linearizableReadLoop","detail":"{readStateIndex:2223; appliedIndex:2221; }","duration":"783.087969ms","start":"2026-07-15T14:27:30.026071Z","end":"2026-07-15T14:27:30.809159Z","steps":["trace[720458305] 'read index received' (duration: 775.104805ms)","trace[720458305] 'applied index is now lower than readState.Index' (duration: 7.982564ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T14:27:30.80934Z","caller":"traceutil/trace.go:171","msg":"trace[688013706] transaction","detail":"{read_only:false; response_revision:2139; number_of_response:1; }","duration":"783.045257ms","start":"2026-07-15T14:27:30.02628Z","end":"2026-07-15T14:27:30.809325Z","steps":["trace[688013706] 'process raft request' (duration: 782.793371ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:27:30.809799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"783.618983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1986"} {"level":"info","ts":"2026-07-15T14:27:30.809864Z","caller":"traceutil/trace.go:171","msg":"trace[1464112307] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:2141; }","duration":"783.707095ms","start":"2026-07-15T14:27:30.026146Z","end":"2026-07-15T14:27:30.809853Z","steps":["trace[1464112307] 'agreement among raft nodes before linearized reading' (duration: 783.439578ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:27:30.809403Z","caller":"traceutil/trace.go:171","msg":"trace[650861611] transaction","detail":"{read_only:false; response_revision:2140; number_of_response:1; }","duration":"172.884466ms","start":"2026-07-15T14:27:30.636499Z","end":"2026-07-15T14:27:30.809384Z","steps":["trace[650861611] 'process raft request' (duration: 172.601828ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:27:30.809906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:27:30.026131Z","time spent":"783.762206ms","remote":"127.0.0.1:49942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2009,"request content":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" "} {"level":"info","ts":"2026-07-15T14:27:30.809465Z","caller":"traceutil/trace.go:171","msg":"trace[218556900] transaction","detail":"{read_only:false; response_revision:2141; number_of_response:1; }","duration":"172.783983ms","start":"2026-07-15T14:27:30.636643Z","end":"2026-07-15T14:27:30.809427Z","steps":["trace[218556900] 'process raft request' (duration: 172.477825ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:27:30.809548Z","caller":"traceutil/trace.go:171","msg":"trace[750625995] transaction","detail":"{read_only:false; response_revision:2138; number_of_response:1; }","duration":"783.289514ms","start":"2026-07-15T14:27:30.026247Z","end":"2026-07-15T14:27:30.809537Z","steps":["trace[750625995] 'process raft request' (duration: 782.735889ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:27:30.810185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:27:30.026239Z","time spent":"783.906251ms","remote":"127.0.0.1:50008","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-07-15T14:27:30.80958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"783.485579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-15T14:27:30.810315Z","caller":"traceutil/trace.go:171","msg":"trace[521273806] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2141; }","duration":"784.26477ms","start":"2026-07-15T14:27:30.026041Z","end":"2026-07-15T14:27:30.810305Z","steps":["trace[521273806] 'agreement among raft nodes before linearized reading' (duration: 783.367285ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:27:30.810347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:27:30.026025Z","time spent":"784.315822ms","remote":"127.0.0.1:58188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-15T14:27:30.809672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.0472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-15T14:27:30.810467Z","caller":"traceutil/trace.go:171","msg":"trace[1619244949] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2141; }","duration":"173.853222ms","start":"2026-07-15T14:27:30.636602Z","end":"2026-07-15T14:27:30.810455Z","steps":["trace[1619244949] 'agreement among raft nodes before linearized reading' (duration: 172.998269ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:27:30.809654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.705878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T14:27:30.810607Z","caller":"traceutil/trace.go:171","msg":"trace[146045927] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:2141; }","duration":"491.694066ms","start":"2026-07-15T14:27:30.318899Z","end":"2026-07-15T14:27:30.810594Z","steps":["trace[146045927] 'agreement among raft nodes before linearized reading' (duration: 490.708099ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:27:30.810647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:27:30.318888Z","time spent":"491.745667ms","remote":"127.0.0.1:49860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-07-15T14:27:30.809683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.876531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-15T14:27:30.810816Z","caller":"traceutil/trace.go:171","msg":"trace[1840950361] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2141; }","duration":"141.016852ms","start":"2026-07-15T14:27:30.66979Z","end":"2026-07-15T14:27:30.810807Z","steps":["trace[1840950361] 'agreement among raft nodes before linearized reading' (duration: 139.82772ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:27:30.809698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.859923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T14:27:30.810989Z","caller":"traceutil/trace.go:171","msg":"trace[415049204] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2141; }","duration":"627.151858ms","start":"2026-07-15T14:27:30.183829Z","end":"2026-07-15T14:27:30.810981Z","steps":["trace[415049204] 'agreement among raft nodes before linearized reading' (duration: 625.822242ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:27:30.811023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:27:30.183793Z","time spent":"627.22197ms","remote":"127.0.0.1:49744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-15T14:27:30.809707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.757586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-15T14:27:30.811225Z","caller":"traceutil/trace.go:171","msg":"trace[170045109] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2141; }","duration":"132.320228ms","start":"2026-07-15T14:27:30.678896Z","end":"2026-07-15T14:27:30.811216Z","steps":["trace[170045109] 'agreement among raft nodes before linearized reading' (duration: 130.726245ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:27:30.809727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.942883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-15T14:27:30.811422Z","caller":"traceutil/trace.go:171","msg":"trace[2119108698] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2141; }","duration":"141.662959ms","start":"2026-07-15T14:27:30.669749Z","end":"2026-07-15T14:27:30.811412Z","steps":["trace[2119108698] 'agreement among raft nodes before linearized reading' (duration: 139.941013ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:27:30.809736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.896099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12383"} {"level":"info","ts":"2026-07-15T14:27:30.811637Z","caller":"traceutil/trace.go:171","msg":"trace[502863698] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2141; }","duration":"421.814411ms","start":"2026-07-15T14:27:30.389814Z","end":"2026-07-15T14:27:30.811628Z","steps":["trace[502863698] 'agreement among raft nodes before linearized reading' (duration: 419.878019ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:27:30.811675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:27:30.389803Z","time spent":"421.861922ms","remote":"127.0.0.1:49932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12406,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-15T14:27:30.809775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"783.463888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-15T14:27:30.811896Z","caller":"traceutil/trace.go:171","msg":"trace[453438738] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2141; }","duration":"785.581525ms","start":"2026-07-15T14:27:30.026306Z","end":"2026-07-15T14:27:30.811887Z","steps":["trace[453438738] 'agreement among raft nodes before linearized reading' (duration: 783.419937ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:27:30.811936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:27:30.026291Z","time spent":"785.635807ms","remote":"127.0.0.1:50008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-15T14:27:30.813089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:27:30.026268Z","time spent":"783.568301ms","remote":"127.0.0.1:50008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T14:27:30.934583Z","caller":"traceutil/trace.go:171","msg":"trace[249899243] transaction","detail":"{read_only:false; response_revision:2142; number_of_response:1; }","duration":"118.524549ms","start":"2026-07-15T14:27:30.816032Z","end":"2026-07-15T14:27:30.934557Z","steps":["trace[249899243] 'process raft request' (duration: 73.526062ms)","trace[249899243] 'compare' (duration: 44.844803ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T14:27:30.934601Z","caller":"traceutil/trace.go:171","msg":"trace[1632851181] transaction","detail":"{read_only:false; response_revision:2143; number_of_response:1; }","duration":"118.475926ms","start":"2026-07-15T14:27:30.816112Z","end":"2026-07-15T14:27:30.934588Z","steps":["trace[1632851181] 'process raft request' (duration: 118.395764ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:27:30.93493Z","caller":"traceutil/trace.go:171","msg":"trace[50548135] transaction","detail":"{read_only:false; response_revision:2145; number_of_response:1; }","duration":"116.868744ms","start":"2026-07-15T14:27:30.818051Z","end":"2026-07-15T14:27:30.934919Z","steps":["trace[50548135] 'process raft request' (duration: 116.770292ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:27:30.934908Z","caller":"traceutil/trace.go:171","msg":"trace[1046410549] transaction","detail":"{read_only:false; response_revision:2144; number_of_response:1; }","duration":"116.868295ms","start":"2026-07-15T14:27:30.818028Z","end":"2026-07-15T14:27:30.934896Z","steps":["trace[1046410549] 'process raft request' (duration: 116.527076ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:27:30.934937Z","caller":"traceutil/trace.go:171","msg":"trace[1365266852] linearizableReadLoop","detail":"{readStateIndex:2230; appliedIndex:2225; }","duration":"112.002163ms","start":"2026-07-15T14:27:30.822923Z","end":"2026-07-15T14:27:30.934925Z","steps":["trace[1365266852] 'read index received' (duration: 66.602556ms)","trace[1365266852] 'applied index is now lower than readState.Index' (duration: 45.398907ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T14:27:30.935037Z","caller":"traceutil/trace.go:171","msg":"trace[1319363227] transaction","detail":"{read_only:false; response_revision:2146; number_of_response:1; }","duration":"116.893765ms","start":"2026-07-15T14:27:30.818131Z","end":"2026-07-15T14:27:30.935025Z","steps":["trace[1319363227] 'process raft request' (duration: 116.737571ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:27:30.935146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.204739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T14:27:30.935227Z","caller":"traceutil/trace.go:171","msg":"trace[1956248281] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2146; }","duration":"112.319152ms","start":"2026-07-15T14:27:30.822897Z","end":"2026-07-15T14:27:30.935216Z","steps":["trace[1956248281] 'agreement among raft nodes before linearized reading' (duration: 112.142057ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:33:25.743Z","caller":"traceutil/trace.go:171","msg":"trace[1640671238] transaction","detail":"{read_only:false; response_revision:4816; number_of_response:1; }","duration":"116.359572ms","start":"2026-07-15T14:33:25.62662Z","end":"2026-07-15T14:33:25.74298Z","steps":["trace[1640671238] 'process raft request' (duration: 116.157753ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:33:25.77567Z","caller":"traceutil/trace.go:171","msg":"trace[2091461156] linearizableReadLoop","detail":"{readStateIndex:5007; appliedIndex:5004; }","duration":"148.734206ms","start":"2026-07-15T14:33:25.626903Z","end":"2026-07-15T14:33:25.775637Z","steps":["trace[2091461156] 'read index received' (duration: 115.865082ms)","trace[2091461156] 'applied index is now lower than readState.Index' (duration: 32.868224ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T14:33:25.775824Z","caller":"traceutil/trace.go:171","msg":"trace[1826758803] transaction","detail":"{read_only:false; response_revision:4818; number_of_response:1; }","duration":"148.904293ms","start":"2026-07-15T14:33:25.626854Z","end":"2026-07-15T14:33:25.775758Z","steps":["trace[1826758803] 'process raft request' (duration: 148.733456ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:33:25.776221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.948874ms","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-07-15T14:33:25.775865Z","caller":"traceutil/trace.go:171","msg":"trace[536455036] transaction","detail":"{read_only:false; response_revision:4817; number_of_response:1; }","duration":"149.032038ms","start":"2026-07-15T14:33:25.626703Z","end":"2026-07-15T14:33:25.775735Z","steps":["trace[536455036] 'process raft request' (duration: 147.10415ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:33:25.776295Z","caller":"traceutil/trace.go:171","msg":"trace[893928534] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4818; }","duration":"149.532728ms","start":"2026-07-15T14:33:25.62674Z","end":"2026-07-15T14:33:25.776273Z","steps":["trace[893928534] 'agreement among raft nodes before linearized reading' (duration: 148.990416ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:33:31.255127Z","caller":"traceutil/trace.go:171","msg":"trace[1473016481] transaction","detail":"{read_only:false; response_revision:4921; number_of_response:1; }","duration":"134.215188ms","start":"2026-07-15T14:33:31.120868Z","end":"2026-07-15T14:33:31.255084Z","steps":["trace[1473016481] 'process raft request' (duration: 61.218513ms)","trace[1473016481] 'compare' (duration: 72.849029ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T14:33:31.255584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.749409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ad132a83-a5fa-446c-b40a-81637173453b\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-07-15T14:33:31.255619Z","caller":"traceutil/trace.go:171","msg":"trace[1540416868] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ad132a83-a5fa-446c-b40a-81637173453b; range_end:; response_count:1; response_revision:4920; }","duration":"140.844843ms","start":"2026-07-15T14:33:31.114765Z","end":"2026-07-15T14:33:31.25561Z","steps":["trace[1540416868] 'range keys from in-memory index tree' (duration: 139.974669ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:33:31.307814Z","caller":"traceutil/trace.go:171","msg":"trace[1141664791] linearizableReadLoop","detail":"{readStateIndex:5113; appliedIndex:5112; }","duration":"119.198226ms","start":"2026-07-15T14:33:31.188563Z","end":"2026-07-15T14:33:31.307761Z","steps":["trace[1141664791] 'read index received' (duration: 119.037619ms)","trace[1141664791] 'applied index is now lower than readState.Index' (duration: 159.297µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T14:33:31.307871Z","caller":"traceutil/trace.go:171","msg":"trace[616579898] transaction","detail":"{read_only:false; response_revision:4922; number_of_response:1; }","duration":"136.425776ms","start":"2026-07-15T14:33:31.171425Z","end":"2026-07-15T14:33:31.30785Z","steps":["trace[616579898] 'process raft request' (duration: 136.180916ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:33:31.308531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.511208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ad132a83-a5fa-446c-b40a-81637173453b\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-07-15T14:33:31.308601Z","caller":"traceutil/trace.go:171","msg":"trace[1235001431] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ad132a83-a5fa-446c-b40a-81637173453b; range_end:; response_count:1; response_revision:4922; }","duration":"120.06045ms","start":"2026-07-15T14:33:31.18852Z","end":"2026-07-15T14:33:31.308581Z","steps":["trace[1235001431] 'agreement among raft nodes before linearized reading' (duration: 119.382673ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:33:31.413759Z","caller":"traceutil/trace.go:171","msg":"trace[1955096901] transaction","detail":"{read_only:false; response_revision:4923; number_of_response:1; }","duration":"151.954609ms","start":"2026-07-15T14:33:31.261782Z","end":"2026-07-15T14:33:31.413737Z","steps":["trace[1955096901] 'process raft request' (duration: 144.269101ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:33:47.131116Z","caller":"traceutil/trace.go:171","msg":"trace[2003253683] transaction","detail":"{read_only:false; response_revision:5123; number_of_response:1; }","duration":"279.142089ms","start":"2026-07-15T14:33:46.851954Z","end":"2026-07-15T14:33:47.131096Z","steps":["trace[2003253683] 'process raft request' (duration: 279.001674ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:33:47.175527Z","caller":"traceutil/trace.go:171","msg":"trace[1232111391] transaction","detail":"{read_only:false; response_revision:5125; number_of_response:1; }","duration":"322.710739ms","start":"2026-07-15T14:33:46.852795Z","end":"2026-07-15T14:33:47.175506Z","steps":["trace[1232111391] 'process raft request' (duration: 322.620795ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:33:47.175654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:33:46.852787Z","time spent":"322.801812ms","remote":"127.0.0.1:50008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T14:33:47.175824Z","caller":"traceutil/trace.go:171","msg":"trace[2123054905] transaction","detail":"{read_only:false; response_revision:5124; number_of_response:1; }","duration":"323.691745ms","start":"2026-07-15T14:33:46.852125Z","end":"2026-07-15T14:33:47.175816Z","steps":["trace[2123054905] 'process raft request' (duration: 323.119573ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:33:47.175866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:33:46.852115Z","time spent":"323.730828ms","remote":"127.0.0.1:50008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T14:34:34.923172Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2908} {"level":"info","ts":"2026-07-15T14:34:35.012818Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2908,"took":"88.60927ms","hash":3879072735,"current-db-size-bytes":52592640,"current-db-size":"53 MB","current-db-size-in-use-bytes":28573696,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-07-15T14:34:35.01289Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3879072735,"revision":2908,"compact-revision":-1} {"level":"info","ts":"2026-07-15T14:39:34.928736Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5499} {"level":"info","ts":"2026-07-15T14:39:35.019873Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5499,"took":"89.539867ms","hash":173589788,"current-db-size-bytes":52592640,"current-db-size":"53 MB","current-db-size-in-use-bytes":18567168,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-07-15T14:39:35.019939Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":173589788,"revision":5499,"compact-revision":2908}