{"level":"warn","ts":"2026-10-14T17:39:24.95751Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-14T17:39:24.957584Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.57: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://162.253.55.57:2380","--initial-cluster=instance=https://162.253.55.57:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.57:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.57: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-10-14T17:39:24.957667Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-14T17:39:24.957691Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.57:2380"]} {"level":"info","ts":"2026-10-14T17:39:24.957714Z","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-10-14T17:39:24.958326Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.57:2379"]} {"level":"info","ts":"2026-10-14T17:39:24.958583Z","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://162.253.55.57:2380"],"listen-peer-urls":["https://162.253.55.57:2380"],"advertise-client-urls":["https://162.253.55.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.57: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-10-14T17:39:24.965733Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.618221ms"} {"level":"info","ts":"2026-10-14T17:39:24.975099Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"db28a37eabf8270","cluster-id":"33328f64ab44b15e"} {"level":"info","ts":"2026-10-14T17:39:24.975153Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db28a37eabf8270 switched to configuration voters=()"} {"level":"info","ts":"2026-10-14T17:39:24.975166Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db28a37eabf8270 became follower at term 0"} {"level":"info","ts":"2026-10-14T17:39:24.975172Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft db28a37eabf8270 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-14T17:39:24.97521Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db28a37eabf8270 became follower at term 1"} {"level":"info","ts":"2026-10-14T17:39:24.975241Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db28a37eabf8270 switched to configuration voters=(987003241113813616)"} {"level":"warn","ts":"2026-10-14T17:39:24.983802Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-14T17:39:24.985212Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-14T17:39:24.986295Z","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-10-14T17:39:24.988435Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"db28a37eabf8270","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-14T17:39:24.989332Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"db28a37eabf8270","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-14T17:39:24.989422Z","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-10-14T17:39:24.989465Z","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-10-14T17:39:24.98948Z","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-10-14T17:39:24.989697Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db28a37eabf8270 switched to configuration voters=(987003241113813616)"} {"level":"info","ts":"2026-10-14T17:39:24.989784Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"33328f64ab44b15e","local-member-id":"db28a37eabf8270","added-peer-id":"db28a37eabf8270","added-peer-peer-urls":["https://162.253.55.57:2380"]} {"level":"info","ts":"2026-10-14T17:39:24.994277Z","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-10-14T17:39:24.99442Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.57:2380"} {"level":"info","ts":"2026-10-14T17:39:24.994491Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.57:2380"} {"level":"info","ts":"2026-10-14T17:39:24.994562Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"db28a37eabf8270","initial-advertise-peer-urls":["https://162.253.55.57:2380"],"listen-peer-urls":["https://162.253.55.57:2380"],"advertise-client-urls":["https://162.253.55.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-14T17:39:24.994612Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-14T17:39:25.17592Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db28a37eabf8270 is starting a new election at term 1"} {"level":"info","ts":"2026-10-14T17:39:25.176043Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db28a37eabf8270 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-14T17:39:25.176067Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db28a37eabf8270 received MsgPreVoteResp from db28a37eabf8270 at term 1"} {"level":"info","ts":"2026-10-14T17:39:25.176086Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db28a37eabf8270 became candidate at term 2"} {"level":"info","ts":"2026-10-14T17:39:25.176105Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db28a37eabf8270 received MsgVoteResp from db28a37eabf8270 at term 2"} {"level":"info","ts":"2026-10-14T17:39:25.176149Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db28a37eabf8270 became leader at term 2"} {"level":"info","ts":"2026-10-14T17:39:25.176166Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: db28a37eabf8270 elected leader db28a37eabf8270 at term 2"} {"level":"info","ts":"2026-10-14T17:39:25.183242Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"db28a37eabf8270","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.57:2379]}","request-path":"/0/members/db28a37eabf8270/attributes","cluster-id":"33328f64ab44b15e","publish-timeout":"7s"} {"level":"info","ts":"2026-10-14T17:39:25.183405Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-14T17:39:25.183494Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-14T17:39:25.183812Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T17:39:25.18665Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.57:2379"} {"level":"info","ts":"2026-10-14T17:39:25.186768Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-14T17:39:25.187221Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-14T17:39:25.187262Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-14T17:39:25.193086Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"33328f64ab44b15e","local-member-id":"db28a37eabf8270","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T17:39:25.19327Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T17:39:25.193334Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T17:39:28.149175Z","caller":"traceutil/trace.go:171","msg":"trace[295510435] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"340.02332ms","start":"2026-10-14T17:39:27.809121Z","end":"2026-10-14T17:39:28.149144Z","steps":["trace[295510435] 'process raft request' (duration: 339.880478ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:39:28.149621Z","caller":"traceutil/trace.go:171","msg":"trace[1239438224] linearizableReadLoop","detail":"{readStateIndex:78; appliedIndex:78; }","duration":"338.824654ms","start":"2026-10-14T17:39:27.810759Z","end":"2026-10-14T17:39:28.149583Z","steps":["trace[1239438224] 'read index received' (duration: 338.115644ms)","trace[1239438224] 'applied index is now lower than readState.Index' (duration: 677.869µs)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:39:28.149731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:39:27.809106Z","time spent":"340.162101ms","remote":"127.0.0.1:46898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-14T17:39:28.149967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.961467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-10-14T17:39:28.149954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.161808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-14T17:39:28.150045Z","caller":"traceutil/trace.go:171","msg":"trace[1235507624] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:74; }","duration":"230.085978ms","start":"2026-10-14T17:39:27.919945Z","end":"2026-10-14T17:39:28.150031Z","steps":["trace[1235507624] 'agreement among raft nodes before linearized reading' (duration: 229.981926ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:39:28.150051Z","caller":"traceutil/trace.go:171","msg":"trace[726616930] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:0; response_revision:74; }","duration":"339.30187ms","start":"2026-10-14T17:39:27.810736Z","end":"2026-10-14T17:39:28.150038Z","steps":["trace[726616930] 'agreement among raft nodes before linearized reading' (duration: 339.032986ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:39:28.150207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:39:27.810728Z","time spent":"339.464431ms","remote":"127.0.0.1:46872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/system:aggregate-to-edit\" "} {"level":"warn","ts":"2026-10-14T17:39:28.15021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.928958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-14T17:39:28.150456Z","caller":"traceutil/trace.go:171","msg":"trace[1560479106] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:74; }","duration":"269.278621ms","start":"2026-10-14T17:39:27.881141Z","end":"2026-10-14T17:39:28.15042Z","steps":["trace[1560479106] 'agreement among raft nodes before linearized reading' (duration: 268.743715ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:40:15.427615Z","caller":"traceutil/trace.go:171","msg":"trace[1086539751] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"234.636984ms","start":"2026-10-14T17:40:15.192945Z","end":"2026-10-14T17:40:15.427582Z","steps":["trace[1086539751] 'process raft request' (duration: 178.268308ms)","trace[1086539751] 'compare' (duration: 56.247115ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:40:15.508814Z","caller":"traceutil/trace.go:171","msg":"trace[1928811848] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:671; }","duration":"137.106015ms","start":"2026-10-14T17:40:15.37168Z","end":"2026-10-14T17:40:15.508786Z","steps":["trace[1928811848] 'read index received' (duration: 136.354267ms)","trace[1928811848] 'applied index is now lower than readState.Index' (duration: 750.928µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:40:15.508977Z","caller":"traceutil/trace.go:171","msg":"trace[1668687761] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"298.235539ms","start":"2026-10-14T17:40:15.210672Z","end":"2026-10-14T17:40:15.508907Z","steps":["trace[1668687761] 'process raft request' (duration: 297.604912ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:40:15.509072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.369548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-10-14T17:40:15.509112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.311307ms","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-10-14T17:40:15.509151Z","caller":"traceutil/trace.go:171","msg":"trace[2009224169] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:648; }","duration":"137.402729ms","start":"2026-10-14T17:40:15.371736Z","end":"2026-10-14T17:40:15.509139Z","steps":["trace[2009224169] 'agreement among raft nodes before linearized reading' (duration: 137.178047ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:40:15.509148Z","caller":"traceutil/trace.go:171","msg":"trace[1618011030] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:648; }","duration":"137.52226ms","start":"2026-10-14T17:40:15.371606Z","end":"2026-10-14T17:40:15.509128Z","steps":["trace[1618011030] 'agreement among raft nodes before linearized reading' (duration: 137.306658ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:40:15.807672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.980945ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T17:40:15.807844Z","caller":"traceutil/trace.go:171","msg":"trace[573560733] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"288.257721ms","start":"2026-10-14T17:40:15.519562Z","end":"2026-10-14T17:40:15.807819Z","steps":["trace[573560733] 'process raft request' (duration: 69.655899ms)","trace[573560733] 'compare' (duration: 217.800283ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:40:15.834238Z","caller":"traceutil/trace.go:171","msg":"trace[1248863011] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"313.403913ms","start":"2026-10-14T17:40:15.520805Z","end":"2026-10-14T17:40:15.834209Z","steps":["trace[1248863011] 'process raft request' (duration: 313.208691ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:40:15.834348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:40:15.520777Z","time spent":"313.516074ms","remote":"127.0.0.1:46786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T17:40:15.834487Z","caller":"traceutil/trace.go:171","msg":"trace[1829342092] linearizableReadLoop","detail":"{readStateIndex:675; appliedIndex:673; }","duration":"224.563165ms","start":"2026-10-14T17:40:15.60988Z","end":"2026-10-14T17:40:15.834443Z","steps":["trace[1829342092] 'read index received' (duration: 224.088221ms)","trace[1829342092] 'applied index is now lower than readState.Index' (duration: 471.764µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:40:15.834565Z","caller":"traceutil/trace.go:171","msg":"trace[796245233] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"254.084494ms","start":"2026-10-14T17:40:15.580422Z","end":"2026-10-14T17:40:15.834506Z","steps":["trace[796245233] 'process raft request' (duration: 253.726541ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:40:15.835336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.454326ms","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-10-14T17:40:15.835405Z","caller":"traceutil/trace.go:171","msg":"trace[1611070296] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:651; }","duration":"225.573287ms","start":"2026-10-14T17:40:15.609818Z","end":"2026-10-14T17:40:15.835392Z","steps":["trace[1611070296] 'agreement among raft nodes before linearized reading' (duration: 224.810689ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:40:16.499375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.385849ms","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-10-14T17:40:16.49945Z","caller":"traceutil/trace.go:171","msg":"trace[283494214] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:652; }","duration":"339.517881ms","start":"2026-10-14T17:40:16.159915Z","end":"2026-10-14T17:40:16.499433Z","steps":["trace[283494214] 'range keys from in-memory index tree' (duration: 339.236118ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:40:16.499504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:40:16.159901Z","time spent":"339.593732ms","remote":"127.0.0.1:46786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-14T17:40:16.551706Z","caller":"traceutil/trace.go:171","msg":"trace[312743959] linearizableReadLoop","detail":"{readStateIndex:677; appliedIndex:676; }","duration":"235.404375ms","start":"2026-10-14T17:40:16.316254Z","end":"2026-10-14T17:40:16.551658Z","steps":["trace[312743959] 'read index received' (duration: 234.092081ms)","trace[312743959] 'applied index is now lower than readState.Index' (duration: 1.310514ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:40:16.551898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.600577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:40:16.551863Z","caller":"traceutil/trace.go:171","msg":"trace[1442586046] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"238.550598ms","start":"2026-10-14T17:40:16.31325Z","end":"2026-10-14T17:40:16.5518Z","steps":["trace[1442586046] 'process raft request' (duration: 237.409417ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:40:16.551971Z","caller":"traceutil/trace.go:171","msg":"trace[1905515233] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:653; }","duration":"235.732519ms","start":"2026-10-14T17:40:16.316195Z","end":"2026-10-14T17:40:16.551927Z","steps":["trace[1905515233] 'agreement among raft nodes before linearized reading' (duration: 235.615947ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:40:31.504873Z","caller":"traceutil/trace.go:171","msg":"trace[1187265605] linearizableReadLoop","detail":"{readStateIndex:843; appliedIndex:842; }","duration":"188.063187ms","start":"2026-10-14T17:40:31.316775Z","end":"2026-10-14T17:40:31.504839Z","steps":["trace[1187265605] 'read index received' (duration: 187.787744ms)","trace[1187265605] 'applied index is now lower than readState.Index' (duration: 274.153µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:40:31.504975Z","caller":"traceutil/trace.go:171","msg":"trace[2024712342] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"212.082622ms","start":"2026-10-14T17:40:31.292851Z","end":"2026-10-14T17:40:31.504934Z","steps":["trace[2024712342] 'process raft request' (duration: 211.783369ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:40:31.505056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.267889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:40:31.50511Z","caller":"traceutil/trace.go:171","msg":"trace[437464070] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:814; }","duration":"188.407111ms","start":"2026-10-14T17:40:31.316688Z","end":"2026-10-14T17:40:31.505095Z","steps":["trace[437464070] 'agreement among raft nodes before linearized reading' (duration: 188.293259ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:41:28.941379Z","caller":"traceutil/trace.go:171","msg":"trace[88184835] transaction","detail":"{read_only:false; response_revision:1515; number_of_response:1; }","duration":"115.932233ms","start":"2026-10-14T17:41:28.825417Z","end":"2026-10-14T17:41:28.94135Z","steps":["trace[88184835] 'process raft request' (duration: 75.20046ms)","trace[88184835] 'compare' (duration: 40.606272ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:41:29.838629Z","caller":"traceutil/trace.go:171","msg":"trace[1007399402] linearizableReadLoop","detail":"{readStateIndex:1582; appliedIndex:1581; }","duration":"150.496299ms","start":"2026-10-14T17:41:29.68811Z","end":"2026-10-14T17:41:29.838606Z","steps":["trace[1007399402] 'read index received' (duration: 150.418178ms)","trace[1007399402] 'applied index is now lower than readState.Index' (duration: 77.241µs)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:41:29.838767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.660041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/queues.rabbitmq.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:41:29.838803Z","caller":"traceutil/trace.go:171","msg":"trace[1132808564] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/queues.rabbitmq.com; range_end:; response_count:0; response_revision:1537; }","duration":"150.743251ms","start":"2026-10-14T17:41:29.688048Z","end":"2026-10-14T17:41:29.838791Z","steps":["trace[1132808564] 'agreement among raft nodes before linearized reading' (duration: 150.64404ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:41:29.838915Z","caller":"traceutil/trace.go:171","msg":"trace[764828258] transaction","detail":"{read_only:false; response_revision:1537; number_of_response:1; }","duration":"162.826432ms","start":"2026-10-14T17:41:29.676065Z","end":"2026-10-14T17:41:29.838891Z","steps":["trace[764828258] 'process raft request' (duration: 162.413878ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:41:29.954919Z","caller":"traceutil/trace.go:171","msg":"trace[1982318137] transaction","detail":"{read_only:false; response_revision:1540; number_of_response:1; }","duration":"106.060781ms","start":"2026-10-14T17:41:29.848822Z","end":"2026-10-14T17:41:29.954883Z","steps":["trace[1982318137] 'process raft request' (duration: 83.262382ms)","trace[1982318137] 'compare' (duration: 22.493116ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:41:30.230146Z","caller":"traceutil/trace.go:171","msg":"trace[290409360] transaction","detail":"{read_only:false; response_revision:1550; number_of_response:1; }","duration":"116.454705ms","start":"2026-10-14T17:41:30.113664Z","end":"2026-10-14T17:41:30.230119Z","steps":["trace[290409360] 'process raft request' (duration: 94.929128ms)","trace[290409360] 'compare' (duration: 21.275484ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:41:30.524009Z","caller":"traceutil/trace.go:171","msg":"trace[482119162] transaction","detail":"{read_only:false; response_revision:1563; number_of_response:1; }","duration":"102.825871ms","start":"2026-10-14T17:41:30.421165Z","end":"2026-10-14T17:41:30.523991Z","steps":["trace[482119162] 'process raft request' (duration: 41.008695ms)","trace[482119162] 'compare' (duration: 61.479863ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:41:52.947362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.728172ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T17:41:52.947584Z","caller":"traceutil/trace.go:171","msg":"trace[1930698474] linearizableReadLoop","detail":"{readStateIndex:1975; appliedIndex:1974; }","duration":"523.854194ms","start":"2026-10-14T17:41:52.423708Z","end":"2026-10-14T17:41:52.947563Z","steps":["trace[1930698474] 'read index received' (duration: 141.851139ms)","trace[1930698474] 'applied index is now lower than readState.Index' (duration: 382.001645ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:41:52.947847Z","caller":"traceutil/trace.go:171","msg":"trace[219305487] transaction","detail":"{read_only:false; response_revision:1904; number_of_response:1; }","duration":"527.546356ms","start":"2026-10-14T17:41:52.420117Z","end":"2026-10-14T17:41:52.947663Z","steps":["trace[219305487] 'process raft request' (duration: 145.40116ms)","trace[219305487] 'compare' (duration: 381.323439ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:41:52.947878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.510831ms","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-10-14T17:41:52.947952Z","caller":"traceutil/trace.go:171","msg":"trace[1857265646] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1905; }","duration":"532.608332ms","start":"2026-10-14T17:41:52.415329Z","end":"2026-10-14T17:41:52.947937Z","steps":["trace[1857265646] 'agreement among raft nodes before linearized reading' (duration: 532.3519ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:41:52.948002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:41:52.415318Z","time spent":"532.671243ms","remote":"127.0.0.1:46786","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":"info","ts":"2026-10-14T17:41:52.948063Z","caller":"traceutil/trace.go:171","msg":"trace[1635179142] transaction","detail":"{read_only:false; response_revision:1905; number_of_response:1; }","duration":"518.952971ms","start":"2026-10-14T17:41:52.429083Z","end":"2026-10-14T17:41:52.948036Z","steps":["trace[1635179142] 'process raft request' (duration: 518.413156ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:41:52.94812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:41:52.420096Z","time spent":"527.839959ms","remote":"127.0.0.1:46786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T17:41:52.948155Z","caller":"traceutil/trace.go:171","msg":"trace[85035953] transaction","detail":"{read_only:false; response_revision:1906; number_of_response:1; }","duration":"343.481701ms","start":"2026-10-14T17:41:52.60465Z","end":"2026-10-14T17:41:52.948132Z","steps":["trace[85035953] 'process raft request' (duration: 343.183128ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:41:52.948194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:41:52.42907Z","time spent":"519.051252ms","remote":"127.0.0.1:46786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T17:41:52.948312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:41:52.604629Z","time spent":"343.595942ms","remote":"127.0.0.1:46786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T17:41:52.948583Z","caller":"traceutil/trace.go:171","msg":"trace[741661398] transaction","detail":"{read_only:false; response_revision:1907; number_of_response:1; }","duration":"273.936449ms","start":"2026-10-14T17:41:52.674633Z","end":"2026-10-14T17:41:52.948569Z","steps":["trace[741661398] 'process raft request' (duration: 273.438884ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:41:52.948584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.457766ms","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":"warn","ts":"2026-10-14T17:41:52.948604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.960839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-da870ae3-5c8f-49dc-b346-f79704b0b0f2\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-10-14T17:41:52.948638Z","caller":"traceutil/trace.go:171","msg":"trace[57313297] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1908; }","duration":"395.550157ms","start":"2026-10-14T17:41:52.553071Z","end":"2026-10-14T17:41:52.948621Z","steps":["trace[57313297] 'agreement among raft nodes before linearized reading' (duration: 395.363456ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:41:52.948637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.67756ms","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-10-14T17:41:52.948649Z","caller":"traceutil/trace.go:171","msg":"trace[1983365182] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-da870ae3-5c8f-49dc-b346-f79704b0b0f2; range_end:; response_count:1; response_revision:1908; }","duration":"274.03041ms","start":"2026-10-14T17:41:52.674603Z","end":"2026-10-14T17:41:52.948634Z","steps":["trace[1983365182] 'agreement among raft nodes before linearized reading' (duration: 273.915119ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:41:52.948678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:41:52.553045Z","time spent":"395.622178ms","remote":"127.0.0.1:46784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-10-14T17:41:52.948684Z","caller":"traceutil/trace.go:171","msg":"trace[1120450004] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1908; }","duration":"289.805292ms","start":"2026-10-14T17:41:52.658865Z","end":"2026-10-14T17:41:52.948671Z","steps":["trace[1120450004] 'agreement among raft nodes before linearized reading' (duration: 289.682591ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:41:53.349666Z","caller":"traceutil/trace.go:171","msg":"trace[605256756] linearizableReadLoop","detail":"{readStateIndex:1982; appliedIndex:1980; }","duration":"319.105371ms","start":"2026-10-14T17:41:53.030538Z","end":"2026-10-14T17:41:53.349643Z","steps":["trace[605256756] 'read index received' (duration: 318.830039ms)","trace[605256756] 'applied index is now lower than readState.Index' (duration: 274.082µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:41:53.34978Z","caller":"traceutil/trace.go:171","msg":"trace[1945518201] transaction","detail":"{read_only:false; response_revision:1911; number_of_response:1; }","duration":"390.76556ms","start":"2026-10-14T17:41:52.959003Z","end":"2026-10-14T17:41:53.349768Z","steps":["trace[1945518201] 'process raft request' (duration: 390.564399ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:41:53.349919Z","caller":"traceutil/trace.go:171","msg":"trace[631213758] transaction","detail":"{read_only:false; response_revision:1910; number_of_response:1; }","duration":"392.109883ms","start":"2026-10-14T17:41:52.957766Z","end":"2026-10-14T17:41:53.349875Z","steps":["trace[631213758] 'process raft request' (duration: 391.62186ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:41:53.350073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:41:52.957731Z","time spent":"392.257513ms","remote":"127.0.0.1:46786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T17:41:53.350331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.768696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-da870ae3-5c8f-49dc-b346-f79704b0b0f2\" ","response":"range_response_count:1 size:3746"} {"level":"info","ts":"2026-10-14T17:41:53.350456Z","caller":"traceutil/trace.go:171","msg":"trace[1077585447] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-da870ae3-5c8f-49dc-b346-f79704b0b0f2; range_end:; response_count:1; response_revision:1911; }","duration":"319.958728ms","start":"2026-10-14T17:41:53.030476Z","end":"2026-10-14T17:41:53.350435Z","steps":["trace[1077585447] 'agreement among raft nodes before linearized reading' (duration: 319.712665ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:41:53.350253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:41:52.95898Z","time spent":"390.898511ms","remote":"127.0.0.1:46700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3731,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T17:41:53.350536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:41:53.030453Z","time spent":"320.070708ms","remote":"127.0.0.1:46700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3768,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-da870ae3-5c8f-49dc-b346-f79704b0b0f2\" "} {"level":"warn","ts":"2026-10-14T17:41:53.379998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.795628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:41:53.380057Z","caller":"traceutil/trace.go:171","msg":"trace[1607296292] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1912; }","duration":"262.895078ms","start":"2026-10-14T17:41:53.117149Z","end":"2026-10-14T17:41:53.380044Z","steps":["trace[1607296292] 'agreement among raft nodes before linearized reading' (duration: 262.785567ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:41:53.380433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.392099ms","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-10-14T17:41:53.380596Z","caller":"traceutil/trace.go:171","msg":"trace[521177833] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1912; }","duration":"230.481429ms","start":"2026-10-14T17:41:53.150013Z","end":"2026-10-14T17:41:53.380495Z","steps":["trace[521177833] 'agreement among raft nodes before linearized reading' (duration: 230.215207ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:42:27.294093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.188675ms","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-10-14T17:42:27.294296Z","caller":"traceutil/trace.go:171","msg":"trace[2063798631] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2147; }","duration":"108.419292ms","start":"2026-10-14T17:42:27.185848Z","end":"2026-10-14T17:42:27.294267Z","steps":["trace[2063798631] 'range keys from in-memory index tree' (duration: 108.017061ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:42:27.419482Z","caller":"traceutil/trace.go:171","msg":"trace[2059548144] linearizableReadLoop","detail":"{readStateIndex:2227; appliedIndex:2226; }","duration":"104.778826ms","start":"2026-10-14T17:42:27.314685Z","end":"2026-10-14T17:42:27.419464Z","steps":["trace[2059548144] 'read index received' (duration: 104.653822ms)","trace[2059548144] 'applied index is now lower than readState.Index' (duration: 124.024µs)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:42:27.419609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.916109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:42:27.419632Z","caller":"traceutil/trace.go:171","msg":"trace[1827357149] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2148; }","duration":"104.993343ms","start":"2026-10-14T17:42:27.314631Z","end":"2026-10-14T17:42:27.419625Z","steps":["trace[1827357149] 'agreement among raft nodes before linearized reading' (duration: 104.91031ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:42:27.419792Z","caller":"traceutil/trace.go:171","msg":"trace[1792050500] transaction","detail":"{read_only:false; response_revision:2148; number_of_response:1; }","duration":"121.859016ms","start":"2026-10-14T17:42:27.297905Z","end":"2026-10-14T17:42:27.419764Z","steps":["trace[1792050500] 'process raft request' (duration: 121.427393ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:42:27.613422Z","caller":"traceutil/trace.go:171","msg":"trace[773004559] transaction","detail":"{read_only:false; response_revision:2149; number_of_response:1; }","duration":"110.049019ms","start":"2026-10-14T17:42:27.503353Z","end":"2026-10-14T17:42:27.613402Z","steps":["trace[773004559] 'process raft request' (duration: 102.394636ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:42:29.099135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.327312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:42:29.099214Z","caller":"traceutil/trace.go:171","msg":"trace[581293333] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:2152; }","duration":"355.482377ms","start":"2026-10-14T17:42:28.743713Z","end":"2026-10-14T17:42:29.099196Z","steps":["trace[581293333] 'count revisions from in-memory index tree' (duration: 355.234699ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:42:29.099254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:42:28.743696Z","time spent":"355.547858ms","remote":"127.0.0.1:53990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"info","ts":"2026-10-14T17:42:29.400419Z","caller":"traceutil/trace.go:171","msg":"trace[559589054] linearizableReadLoop","detail":"{readStateIndex:2233; appliedIndex:2231; }","duration":"275.870372ms","start":"2026-10-14T17:42:29.124524Z","end":"2026-10-14T17:42:29.400394Z","steps":["trace[559589054] 'read index received' (duration: 37.377692ms)","trace[559589054] 'applied index is now lower than readState.Index' (duration: 238.49166ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:42:29.400651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.10829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-10-14T17:42:29.400689Z","caller":"traceutil/trace.go:171","msg":"trace[164803380] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2154; }","duration":"276.185562ms","start":"2026-10-14T17:42:29.124492Z","end":"2026-10-14T17:42:29.400678Z","steps":["trace[164803380] 'agreement among raft nodes before linearized reading' (duration: 276.042997ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:42:29.400741Z","caller":"traceutil/trace.go:171","msg":"trace[1012518876] transaction","detail":"{read_only:false; response_revision:2154; number_of_response:1; }","duration":"288.755416ms","start":"2026-10-14T17:42:29.111776Z","end":"2026-10-14T17:42:29.400532Z","steps":["trace[1012518876] 'process raft request' (duration: 258.414117ms)","trace[1012518876] 'compare' (duration: 30.096292ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:42:29.400822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.012317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.57\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-10-14T17:42:29.400888Z","caller":"traceutil/trace.go:171","msg":"trace[374861529] range","detail":"{range_begin:/registry/masterleases/162.253.55.57; range_end:; response_count:1; response_revision:2154; }","duration":"267.12131ms","start":"2026-10-14T17:42:29.13375Z","end":"2026-10-14T17:42:29.400872Z","steps":["trace[374861529] 'agreement among raft nodes before linearized reading' (duration: 266.952115ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:42:29.4012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.557581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-14T17:42:29.401254Z","caller":"traceutil/trace.go:171","msg":"trace[1116220028] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2154; }","duration":"250.631852ms","start":"2026-10-14T17:42:29.150609Z","end":"2026-10-14T17:42:29.40124Z","steps":["trace[1116220028] 'agreement among raft nodes before linearized reading' (duration: 250.501488ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:44:08.217959Z","caller":"traceutil/trace.go:171","msg":"trace[1542441677] transaction","detail":"{read_only:false; response_revision:2796; number_of_response:1; }","duration":"137.359466ms","start":"2026-10-14T17:44:08.080576Z","end":"2026-10-14T17:44:08.217936Z","steps":["trace[1542441677] 'process raft request' (duration: 137.060941ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:44:08.378307Z","caller":"traceutil/trace.go:171","msg":"trace[2089280891] transaction","detail":"{read_only:false; response_revision:2797; number_of_response:1; }","duration":"155.968825ms","start":"2026-10-14T17:44:08.222295Z","end":"2026-10-14T17:44:08.378264Z","steps":["trace[2089280891] 'process raft request' (duration: 144.567135ms)","trace[2089280891] 'compare' (duration: 11.222447ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:44:08.387311Z","caller":"traceutil/trace.go:171","msg":"trace[2068032475] transaction","detail":"{read_only:false; response_revision:2798; number_of_response:1; }","duration":"164.49444ms","start":"2026-10-14T17:44:08.222796Z","end":"2026-10-14T17:44:08.38729Z","steps":["trace[2068032475] 'process raft request' (duration: 164.334417ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:44:14.012854Z","caller":"traceutil/trace.go:171","msg":"trace[1844661392] transaction","detail":"{read_only:false; response_revision:2824; number_of_response:1; }","duration":"180.155545ms","start":"2026-10-14T17:44:13.832673Z","end":"2026-10-14T17:44:14.012828Z","steps":["trace[1844661392] 'process raft request' (duration: 179.989862ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:48:34.86098Z","caller":"traceutil/trace.go:171","msg":"trace[2139661707] transaction","detail":"{read_only:false; response_revision:4748; number_of_response:1; }","duration":"315.244423ms","start":"2026-10-14T17:48:34.545706Z","end":"2026-10-14T17:48:34.86095Z","steps":["trace[2139661707] 'process raft request' (duration: 315.040441ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:48:34.861163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:48:34.545681Z","time spent":"315.396815ms","remote":"127.0.0.1:53914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4730,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T17:48:34.861466Z","caller":"traceutil/trace.go:171","msg":"trace[114085311] linearizableReadLoop","detail":"{readStateIndex:4927; appliedIndex:4925; }","duration":"259.030246ms","start":"2026-10-14T17:48:34.602372Z","end":"2026-10-14T17:48:34.861403Z","steps":["trace[114085311] 'read index received' (duration: 258.48826ms)","trace[114085311] 'applied index is now lower than readState.Index' (duration: 540.636µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:48:34.861542Z","caller":"traceutil/trace.go:171","msg":"trace[637386384] transaction","detail":"{read_only:false; response_revision:4749; number_of_response:1; }","duration":"289.562833ms","start":"2026-10-14T17:48:34.571928Z","end":"2026-10-14T17:48:34.861491Z","steps":["trace[637386384] 'process raft request' (duration: 288.967697ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:48:34.861838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.64341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-operator-cd88cf4bf-mgtfw\" ","response":"range_response_count:1 size:5041"} {"level":"info","ts":"2026-10-14T17:48:34.861928Z","caller":"traceutil/trace.go:171","msg":"trace[487936757] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-operator-cd88cf4bf-mgtfw; range_end:; response_count:1; response_revision:4749; }","duration":"297.775041ms","start":"2026-10-14T17:48:34.564131Z","end":"2026-10-14T17:48:34.861906Z","steps":["trace[487936757] 'agreement among raft nodes before linearized reading' (duration: 297.416977ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:48:34.877969Z","caller":"traceutil/trace.go:171","msg":"trace[610110678] transaction","detail":"{read_only:false; response_revision:4750; number_of_response:1; }","duration":"263.253417ms","start":"2026-10-14T17:48:34.614694Z","end":"2026-10-14T17:48:34.877948Z","steps":["trace[610110678] 'process raft request' (duration: 263.017404ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:48:34.878135Z","caller":"traceutil/trace.go:171","msg":"trace[1720425356] transaction","detail":"{read_only:false; response_revision:4751; number_of_response:1; }","duration":"259.38719ms","start":"2026-10-14T17:48:34.618723Z","end":"2026-10-14T17:48:34.87811Z","steps":["trace[1720425356] 'process raft request' (duration: 259.124138ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:48:34.93623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.681024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:3958"} {"level":"info","ts":"2026-10-14T17:48:34.936311Z","caller":"traceutil/trace.go:171","msg":"trace[316465095] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4751; }","duration":"328.792596ms","start":"2026-10-14T17:48:34.607496Z","end":"2026-10-14T17:48:34.936288Z","steps":["trace[316465095] 'agreement among raft nodes before linearized reading' (duration: 328.580293ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:48:34.936369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:48:34.607477Z","time spent":"328.883687ms","remote":"127.0.0.1:46600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3980,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" "} {"level":"warn","ts":"2026-10-14T17:48:34.93665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.083822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:46 size:1954231"} {"level":"info","ts":"2026-10-14T17:48:34.9367Z","caller":"traceutil/trace.go:171","msg":"trace[65632618] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:46; response_revision:4751; }","duration":"261.200213ms","start":"2026-10-14T17:48:34.675488Z","end":"2026-10-14T17:48:34.936689Z","steps":["trace[65632618] 'agreement among raft nodes before linearized reading' (duration: 260.456313ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:48:35.012423Z","caller":"traceutil/trace.go:171","msg":"trace[510981317] transaction","detail":"{read_only:false; response_revision:4753; number_of_response:1; }","duration":"130.141406ms","start":"2026-10-14T17:48:34.882248Z","end":"2026-10-14T17:48:35.012389Z","steps":["trace[510981317] 'process raft request' (duration: 129.961294ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:48:35.012489Z","caller":"traceutil/trace.go:171","msg":"trace[1464523199] transaction","detail":"{read_only:false; response_revision:4754; number_of_response:1; }","duration":"130.139505ms","start":"2026-10-14T17:48:34.882327Z","end":"2026-10-14T17:48:35.012467Z","steps":["trace[1464523199] 'process raft request' (duration: 129.998814ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:48:35.012521Z","caller":"traceutil/trace.go:171","msg":"trace[728744791] transaction","detail":"{read_only:false; response_revision:4755; number_of_response:1; }","duration":"127.657025ms","start":"2026-10-14T17:48:34.884837Z","end":"2026-10-14T17:48:35.012494Z","steps":["trace[728744791] 'process raft request' (duration: 127.512615ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:48:35.062355Z","caller":"traceutil/trace.go:171","msg":"trace[2084696276] transaction","detail":"{read_only:false; response_revision:4756; number_of_response:1; }","duration":"108.302383ms","start":"2026-10-14T17:48:34.954034Z","end":"2026-10-14T17:48:35.062336Z","steps":["trace[2084696276] 'process raft request' (duration: 108.01941ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:48:35.062493Z","caller":"traceutil/trace.go:171","msg":"trace[734961124] transaction","detail":"{read_only:false; response_revision:4757; number_of_response:1; }","duration":"108.08491ms","start":"2026-10-14T17:48:34.954382Z","end":"2026-10-14T17:48:35.062467Z","steps":["trace[734961124] 'process raft request' (duration: 107.851127ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:48:35.062724Z","caller":"traceutil/trace.go:171","msg":"trace[568865343] transaction","detail":"{read_only:false; response_revision:4758; number_of_response:1; }","duration":"105.5016ms","start":"2026-10-14T17:48:34.957169Z","end":"2026-10-14T17:48:35.062671Z","steps":["trace[568865343] 'process raft request' (duration: 105.143415ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:48:35.062877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.387578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-10-14T17:48:35.062984Z","caller":"traceutil/trace.go:171","msg":"trace[1327549693] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4759; }","duration":"105.535929ms","start":"2026-10-14T17:48:34.957421Z","end":"2026-10-14T17:48:35.062957Z","steps":["trace[1327549693] 'agreement among raft nodes before linearized reading' (duration: 105.262647ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:48:36.148596Z","caller":"traceutil/trace.go:171","msg":"trace[1155433951] transaction","detail":"{read_only:false; response_revision:4824; number_of_response:1; }","duration":"125.974074ms","start":"2026-10-14T17:48:36.022582Z","end":"2026-10-14T17:48:36.148556Z","steps":["trace[1155433951] 'process raft request' (duration: 34.227421ms)","trace[1155433951] 'compare' (duration: 91.337278ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:48:45.482041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.935262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:48:45.482123Z","caller":"traceutil/trace.go:171","msg":"trace[1808703495] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5010; }","duration":"166.063424ms","start":"2026-10-14T17:48:45.31604Z","end":"2026-10-14T17:48:45.482104Z","steps":["trace[1808703495] 'range keys from in-memory index tree' (duration: 165.868432ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:48:45.482304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.576326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-14T17:48:45.482344Z","caller":"traceutil/trace.go:171","msg":"trace[1617983052] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:5010; }","duration":"225.684807ms","start":"2026-10-14T17:48:45.256649Z","end":"2026-10-14T17:48:45.482334Z","steps":["trace[1617983052] 'count revisions from in-memory index tree' (duration: 225.453274ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:48:45.482331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.625422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-062ae59f-5916-413d-8053-362a58f2d36c\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-10-14T17:48:45.482404Z","caller":"traceutil/trace.go:171","msg":"trace[2059577884] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-062ae59f-5916-413d-8053-362a58f2d36c; range_end:; response_count:1; response_revision:5010; }","duration":"146.737383ms","start":"2026-10-14T17:48:45.335649Z","end":"2026-10-14T17:48:45.482386Z","steps":["trace[2059577884] 'range keys from in-memory index tree' (duration: 146.46287ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:48:48.868659Z","caller":"traceutil/trace.go:171","msg":"trace[1003754288] linearizableReadLoop","detail":"{readStateIndex:5242; appliedIndex:5240; }","duration":"137.5192ms","start":"2026-10-14T17:48:48.731117Z","end":"2026-10-14T17:48:48.868636Z","steps":["trace[1003754288] 'read index received' (duration: 67.588465ms)","trace[1003754288] 'applied index is now lower than readState.Index' (duration: 69.929424ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:48:48.868749Z","caller":"traceutil/trace.go:171","msg":"trace[2022656208] transaction","detail":"{read_only:false; response_revision:5056; number_of_response:1; }","duration":"141.658118ms","start":"2026-10-14T17:48:48.727048Z","end":"2026-10-14T17:48:48.868706Z","steps":["trace[2022656208] 'process raft request' (duration: 141.421045ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:48:48.868867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.731053ms","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-10-14T17:48:48.868962Z","caller":"traceutil/trace.go:171","msg":"trace[834987044] 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:5056; }","duration":"137.794734ms","start":"2026-10-14T17:48:48.731101Z","end":"2026-10-14T17:48:48.868896Z","steps":["trace[834987044] 'agreement among raft nodes before linearized reading' (duration: 137.623602ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:48:49.127797Z","caller":"traceutil/trace.go:171","msg":"trace[1926725282] transaction","detail":"{read_only:false; response_revision:5059; number_of_response:1; }","duration":"186.786076ms","start":"2026-10-14T17:48:48.940945Z","end":"2026-10-14T17:48:49.127731Z","steps":["trace[1926725282] 'process raft request' (duration: 156.466495ms)","trace[1926725282] 'compare' (duration: 30.17804ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:48:49.127823Z","caller":"traceutil/trace.go:171","msg":"trace[1331786910] transaction","detail":"{read_only:false; response_revision:5060; number_of_response:1; }","duration":"174.855373ms","start":"2026-10-14T17:48:48.952946Z","end":"2026-10-14T17:48:49.127802Z","steps":["trace[1331786910] 'process raft request' (duration: 174.764122ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:48:49.127868Z","caller":"traceutil/trace.go:171","msg":"trace[1247271857] linearizableReadLoop","detail":"{readStateIndex:5245; appliedIndex:5244; }","duration":"177.591036ms","start":"2026-10-14T17:48:48.950234Z","end":"2026-10-14T17:48:49.127825Z","steps":["trace[1247271857] 'read index received' (duration: 147.192814ms)","trace[1247271857] 'applied index is now lower than readState.Index' (duration: 30.392522ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:48:49.128418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.155103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-10-14T17:48:49.128512Z","caller":"traceutil/trace.go:171","msg":"trace[1907662809] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5060; }","duration":"178.284464ms","start":"2026-10-14T17:48:48.950209Z","end":"2026-10-14T17:48:49.128494Z","steps":["trace[1907662809] 'agreement among raft nodes before linearized reading' (duration: 178.041281ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:49:04.111089Z","caller":"traceutil/trace.go:171","msg":"trace[490512375] linearizableReadLoop","detail":"{readStateIndex:5413; appliedIndex:5412; }","duration":"230.776797ms","start":"2026-10-14T17:49:03.880278Z","end":"2026-10-14T17:49:04.111055Z","steps":["trace[490512375] 'read index received' (duration: 230.487784ms)","trace[490512375] 'applied index is now lower than readState.Index' (duration: 287.723µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:49:04.111563Z","caller":"traceutil/trace.go:171","msg":"trace[2134699122] transaction","detail":"{read_only:false; response_revision:5221; number_of_response:1; }","duration":"283.127837ms","start":"2026-10-14T17:49:03.828414Z","end":"2026-10-14T17:49:04.111542Z","steps":["trace[2134699122] 'process raft request' (duration: 282.445829ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:49:04.112033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.728539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16547"} {"level":"info","ts":"2026-10-14T17:49:04.112098Z","caller":"traceutil/trace.go:171","msg":"trace[99279656] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5221; }","duration":"231.868191ms","start":"2026-10-14T17:49:03.880213Z","end":"2026-10-14T17:49:04.112081Z","steps":["trace[99279656] 'agreement among raft nodes before linearized reading' (duration: 231.649668ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:49:04.11242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.655999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-10-14T17:49:04.112504Z","caller":"traceutil/trace.go:171","msg":"trace[673333317] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5221; }","duration":"215.76091ms","start":"2026-10-14T17:49:03.896724Z","end":"2026-10-14T17:49:04.112485Z","steps":["trace[673333317] 'agreement among raft nodes before linearized reading' (duration: 215.597288ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:49:04.112964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.641381ms","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-10-14T17:49:04.11302Z","caller":"traceutil/trace.go:171","msg":"trace[860229826] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5221; }","duration":"167.720562ms","start":"2026-10-14T17:49:03.945286Z","end":"2026-10-14T17:49:04.113006Z","steps":["trace[860229826] 'agreement among raft nodes before linearized reading' (duration: 167.57858ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:49:04.113261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.87881ms","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-10-14T17:49:04.113311Z","caller":"traceutil/trace.go:171","msg":"trace[838488242] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5221; }","duration":"170.986591ms","start":"2026-10-14T17:49:03.942312Z","end":"2026-10-14T17:49:04.113298Z","steps":["trace[838488242] 'agreement among raft nodes before linearized reading' (duration: 170.86687ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:49:04.233946Z","caller":"traceutil/trace.go:171","msg":"trace[1516350554] linearizableReadLoop","detail":"{readStateIndex:5414; appliedIndex:5413; }","duration":"112.949614ms","start":"2026-10-14T17:49:04.120953Z","end":"2026-10-14T17:49:04.233903Z","steps":["trace[1516350554] 'read index received' (duration: 111.950943ms)","trace[1516350554] 'applied index is now lower than readState.Index' (duration: 997.271µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:49:04.234092Z","caller":"traceutil/trace.go:171","msg":"trace[454860018] transaction","detail":"{read_only:false; response_revision:5222; number_of_response:1; }","duration":"113.161516ms","start":"2026-10-14T17:49:04.120914Z","end":"2026-10-14T17:49:04.234076Z","steps":["trace[454860018] 'process raft request' (duration: 112.011203ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:49:04.234364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.382609ms","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-10-14T17:49:04.234492Z","caller":"traceutil/trace.go:171","msg":"trace[924348616] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5222; }","duration":"113.571711ms","start":"2026-10-14T17:49:04.120895Z","end":"2026-10-14T17:49:04.234467Z","steps":["trace[924348616] 'agreement among raft nodes before linearized reading' (duration: 113.282238ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:49:04.323224Z","caller":"traceutil/trace.go:171","msg":"trace[921711722] transaction","detail":"{read_only:false; response_revision:5223; number_of_response:1; }","duration":"200.729852ms","start":"2026-10-14T17:49:04.122466Z","end":"2026-10-14T17:49:04.323196Z","steps":["trace[921711722] 'process raft request' (duration: 200.58481ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:49:04.323404Z","caller":"traceutil/trace.go:171","msg":"trace[979562085] transaction","detail":"{read_only:false; response_revision:5224; number_of_response:1; }","duration":"195.200827ms","start":"2026-10-14T17:49:04.128165Z","end":"2026-10-14T17:49:04.323366Z","steps":["trace[979562085] 'process raft request' (duration: 195.013585ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:49:04.367619Z","caller":"traceutil/trace.go:171","msg":"trace[1134068599] linearizableReadLoop","detail":"{readStateIndex:5417; appliedIndex:5414; }","duration":"133.550409ms","start":"2026-10-14T17:49:04.23404Z","end":"2026-10-14T17:49:04.367591Z","steps":["trace[1134068599] 'read index received' (duration: 89.094773ms)","trace[1134068599] 'applied index is now lower than readState.Index' (duration: 44.451106ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:49:04.367687Z","caller":"traceutil/trace.go:171","msg":"trace[407807231] transaction","detail":"{read_only:false; response_revision:5225; number_of_response:1; }","duration":"126.208872ms","start":"2026-10-14T17:49:04.241455Z","end":"2026-10-14T17:49:04.367664Z","steps":["trace[407807231] 'process raft request' (duration: 125.724396ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:49:04.434597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.735455ms","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-10-14T17:49:04.434784Z","caller":"traceutil/trace.go:171","msg":"trace[830817119] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5225; }","duration":"285.986719ms","start":"2026-10-14T17:49:04.148767Z","end":"2026-10-14T17:49:04.434754Z","steps":["trace[830817119] 'agreement among raft nodes before linearized reading' (duration: 218.856466ms)","trace[830817119] 'range keys from in-memory index tree' (duration: 66.793239ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:49:04.434614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.522769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-10-14T17:49:04.4349Z","caller":"traceutil/trace.go:171","msg":"trace[178768926] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5225; }","duration":"310.855712ms","start":"2026-10-14T17:49:04.124017Z","end":"2026-10-14T17:49:04.434873Z","steps":["trace[178768926] 'agreement among raft nodes before linearized reading' (duration: 243.706329ms)","trace[178768926] 'range keys from in-memory index tree' (duration: 66.726918ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:49:04.434967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:49:04.124006Z","time spent":"310.936983ms","remote":"127.0.0.1:46678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1887,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-10-14T17:49:04.434983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.101956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:17230"} {"level":"warn","ts":"2026-10-14T17:49:04.434645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.524256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-10-14T17:49:04.435186Z","caller":"traceutil/trace.go:171","msg":"trace[757209440] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5225; }","duration":"277.109854ms","start":"2026-10-14T17:49:04.15805Z","end":"2026-10-14T17:49:04.43516Z","steps":["trace[757209440] 'agreement among raft nodes before linearized reading' (duration: 209.547546ms)","trace[757209440] 'range keys from in-memory index tree' (duration: 66.93201ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:49:04.435085Z","caller":"traceutil/trace.go:171","msg":"trace[1828213188] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5226; }","duration":"107.274608ms","start":"2026-10-14T17:49:04.327792Z","end":"2026-10-14T17:49:04.435066Z","steps":["trace[1828213188] 'agreement among raft nodes before linearized reading' (duration: 106.960984ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:49:04.704659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.987975ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T17:49:04.70478Z","caller":"traceutil/trace.go:171","msg":"trace[399355830] transaction","detail":"{read_only:false; response_revision:5229; number_of_response:1; }","duration":"175.639805ms","start":"2026-10-14T17:49:04.529118Z","end":"2026-10-14T17:49:04.704758Z","steps":["trace[399355830] 'process raft request' (duration: 23.446367ms)","trace[399355830] 'compare' (duration: 151.824923ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:49:04.91598Z","caller":"traceutil/trace.go:171","msg":"trace[1547041978] transaction","detail":"{read_only:false; response_revision:5233; number_of_response:1; }","duration":"201.638322ms","start":"2026-10-14T17:49:04.714317Z","end":"2026-10-14T17:49:04.915955Z","steps":["trace[1547041978] 'process raft request' (duration: 201.584912ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:49:04.916273Z","caller":"traceutil/trace.go:171","msg":"trace[338234828] transaction","detail":"{read_only:false; response_revision:5231; number_of_response:1; }","duration":"203.736568ms","start":"2026-10-14T17:49:04.712521Z","end":"2026-10-14T17:49:04.916257Z","steps":["trace[338234828] 'process raft request' (duration: 110.089041ms)","trace[338234828] 'compare' (duration: 93.05092ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:49:04.916831Z","caller":"traceutil/trace.go:171","msg":"trace[994262755] transaction","detail":"{read_only:false; response_revision:5232; number_of_response:1; }","duration":"202.342821ms","start":"2026-10-14T17:49:04.71407Z","end":"2026-10-14T17:49:04.916413Z","steps":["trace[994262755] 'process raft request' (duration: 201.762704ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:49:25.344194Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2886} {"level":"info","ts":"2026-10-14T17:49:25.442823Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2886,"took":"97.267478ms","hash":592090538,"current-db-size-bytes":52244480,"current-db-size":"52 MB","current-db-size-in-use-bytes":27787264,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-14T17:49:25.442892Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":592090538,"revision":2886,"compact-revision":-1} {"level":"warn","ts":"2026-10-14T17:49:40.861426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.433478ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T17:49:40.861851Z","caller":"traceutil/trace.go:171","msg":"trace[1411462918] linearizableReadLoop","detail":"{readStateIndex:5751; appliedIndex:5749; }","duration":"218.415967ms","start":"2026-10-14T17:49:40.643413Z","end":"2026-10-14T17:49:40.861829Z","steps":["trace[1411462918] 'read index received' (duration: 187.277695ms)","trace[1411462918] 'applied index is now lower than readState.Index' (duration: 31.137242ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:49:40.861886Z","caller":"traceutil/trace.go:171","msg":"trace[2061623731] transaction","detail":"{read_only:false; response_revision:5549; number_of_response:1; }","duration":"245.892656ms","start":"2026-10-14T17:49:40.615956Z","end":"2026-10-14T17:49:40.861849Z","steps":["trace[2061623731] 'process raft request' (duration: 245.745084ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:49:40.861893Z","caller":"traceutil/trace.go:171","msg":"trace[1723753888] transaction","detail":"{read_only:false; response_revision:5548; number_of_response:1; }","duration":"305.262416ms","start":"2026-10-14T17:49:40.556599Z","end":"2026-10-14T17:49:40.861861Z","steps":["trace[1723753888] 'process raft request' (duration: 17.261741ms)","trace[1723753888] 'compare' (duration: 287.112764ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:49:40.862121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.68558ms","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":"warn","ts":"2026-10-14T17:49:40.8624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:49:40.556577Z","time spent":"305.588759ms","remote":"127.0.0.1:46786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T17:49:40.862425Z","caller":"traceutil/trace.go:171","msg":"trace[1583292714] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5549; }","duration":"219.015794ms","start":"2026-10-14T17:49:40.643375Z","end":"2026-10-14T17:49:40.862391Z","steps":["trace[1583292714] 'agreement among raft nodes before linearized reading' (duration: 218.579049ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:49:46.34772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.17694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:49:46.347812Z","caller":"traceutil/trace.go:171","msg":"trace[1299270219] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:5579; }","duration":"106.279302ms","start":"2026-10-14T17:49:46.241517Z","end":"2026-10-14T17:49:46.347796Z","steps":["trace[1299270219] 'count revisions from in-memory index tree' (duration: 106.133919ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:54:25.350521Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5374} {"level":"info","ts":"2026-10-14T17:54:25.490703Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5374,"took":"138.685608ms","hash":1670346439,"current-db-size-bytes":52244480,"current-db-size":"52 MB","current-db-size-in-use-bytes":18845696,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-10-14T17:54:25.490796Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1670346439,"revision":5374,"compact-revision":2886}