{"level":"warn","ts":"2026-10-14T09:03:59.557848Z","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-14T09:03:59.55796Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.156: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.156:2380","--initial-cluster=instance=https://199.19.213.156:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.156:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.156: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-14T09:03:59.558051Z","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-14T09:03:59.558068Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.156:2380"]} {"level":"info","ts":"2026-10-14T09:03:59.558088Z","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-14T09:03:59.559622Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.156:2379"]} {"level":"info","ts":"2026-10-14T09:03:59.559757Z","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.156:2380"],"listen-peer-urls":["https://199.19.213.156:2380"],"advertise-client-urls":["https://199.19.213.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.156: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-14T09:03:59.605348Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"45.249455ms"} {"level":"info","ts":"2026-10-14T09:03:59.623101Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"163ef968c92247ab","cluster-id":"59d11c119c278888"} {"level":"info","ts":"2026-10-14T09:03:59.623192Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"163ef968c92247ab switched to configuration voters=()"} {"level":"info","ts":"2026-10-14T09:03:59.623217Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"163ef968c92247ab became follower at term 0"} {"level":"info","ts":"2026-10-14T09:03:59.62324Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 163ef968c92247ab [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-14T09:03:59.623254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"163ef968c92247ab became follower at term 1"} {"level":"info","ts":"2026-10-14T09:03:59.623303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"163ef968c92247ab switched to configuration voters=(1602992745836857259)"} {"level":"warn","ts":"2026-10-14T09:03:59.637821Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-14T09:03:59.639595Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-14T09:03:59.641938Z","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-14T09:03:59.646213Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"163ef968c92247ab","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-14T09:03:59.650749Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"163ef968c92247ab","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-14T09:03:59.650852Z","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-14T09:03:59.650891Z","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-14T09:03:59.650901Z","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-14T09:03:59.661254Z","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-14T09:03:59.661346Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"163ef968c92247ab switched to configuration voters=(1602992745836857259)"} {"level":"info","ts":"2026-10-14T09:03:59.661571Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"163ef968c92247ab","initial-advertise-peer-urls":["https://199.19.213.156:2380"],"listen-peer-urls":["https://199.19.213.156:2380"],"advertise-client-urls":["https://199.19.213.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-14T09:03:59.661634Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-14T09:03:59.661763Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.156:2380"} {"level":"info","ts":"2026-10-14T09:03:59.661894Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.156:2380"} {"level":"info","ts":"2026-10-14T09:03:59.662533Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"59d11c119c278888","local-member-id":"163ef968c92247ab","added-peer-id":"163ef968c92247ab","added-peer-peer-urls":["https://199.19.213.156:2380"]} {"level":"info","ts":"2026-10-14T09:04:00.52443Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"163ef968c92247ab is starting a new election at term 1"} {"level":"info","ts":"2026-10-14T09:04:00.524537Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"163ef968c92247ab became pre-candidate at term 1"} {"level":"info","ts":"2026-10-14T09:04:00.524588Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"163ef968c92247ab received MsgPreVoteResp from 163ef968c92247ab at term 1"} {"level":"info","ts":"2026-10-14T09:04:00.524611Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"163ef968c92247ab became candidate at term 2"} {"level":"info","ts":"2026-10-14T09:04:00.524622Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"163ef968c92247ab received MsgVoteResp from 163ef968c92247ab at term 2"} {"level":"info","ts":"2026-10-14T09:04:00.524639Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"163ef968c92247ab became leader at term 2"} {"level":"info","ts":"2026-10-14T09:04:00.524654Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 163ef968c92247ab elected leader 163ef968c92247ab at term 2"} {"level":"info","ts":"2026-10-14T09:04:00.525909Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T09:04:00.52686Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-14T09:04:00.526861Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"163ef968c92247ab","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.156:2379]}","request-path":"/0/members/163ef968c92247ab/attributes","cluster-id":"59d11c119c278888","publish-timeout":"7s"} {"level":"info","ts":"2026-10-14T09:04:00.526912Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-14T09:04:00.527084Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-14T09:04:00.527103Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-14T09:04:00.527881Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"59d11c119c278888","local-member-id":"163ef968c92247ab","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T09:04:00.528018Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T09:04:00.528073Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T09:04:00.529407Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-14T09:04:00.529463Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.156:2379"} {"level":"info","ts":"2026-10-14T09:04:03.120476Z","caller":"traceutil/trace.go:171","msg":"trace[567303474] transaction","detail":"{read_only:false; response_revision:93; number_of_response:1; }","duration":"137.288247ms","start":"2026-10-14T09:04:02.983147Z","end":"2026-10-14T09:04:03.120435Z","steps":["trace[567303474] 'process raft request' (duration: 93.268907ms)","trace[567303474] 'compare' (duration: 43.751708ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T09:04:03.313354Z","caller":"traceutil/trace.go:171","msg":"trace[1850724925] transaction","detail":"{read_only:false; response_revision:96; number_of_response:1; }","duration":"160.85288ms","start":"2026-10-14T09:04:03.152464Z","end":"2026-10-14T09:04:03.313316Z","steps":["trace[1850724925] 'process raft request' (duration: 74.453626ms)","trace[1850724925] 'compare' (duration: 86.208497ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T09:04:07.192276Z","caller":"traceutil/trace.go:171","msg":"trace[1281934952] linearizableReadLoop","detail":"{readStateIndex:321; appliedIndex:320; }","duration":"135.687493ms","start":"2026-10-14T09:04:07.056554Z","end":"2026-10-14T09:04:07.192242Z","steps":["trace[1281934952] 'read index received' (duration: 91.510292ms)","trace[1281934952] 'applied index is now lower than readState.Index' (duration: 44.175821ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T09:04:07.192334Z","caller":"traceutil/trace.go:171","msg":"trace[520666136] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"136.048518ms","start":"2026-10-14T09:04:07.056236Z","end":"2026-10-14T09:04:07.192285Z","steps":["trace[520666136] 'process raft request' (duration: 91.890338ms)","trace[520666136] 'compare' (duration: 43.931111ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T09:04:07.192515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.934413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T09:04:07.192624Z","caller":"traceutil/trace.go:171","msg":"trace[1614999521] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:0; response_revision:312; }","duration":"136.097189ms","start":"2026-10-14T09:04:07.056507Z","end":"2026-10-14T09:04:07.192604Z","steps":["trace[1614999521] 'agreement among raft nodes before linearized reading' (duration: 135.878251ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T09:04:07.746222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.622001ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T09:04:07.746609Z","caller":"traceutil/trace.go:171","msg":"trace[1376797811] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"548.785839ms","start":"2026-10-14T09:04:07.197789Z","end":"2026-10-14T09:04:07.746575Z","steps":["trace[1376797811] 'process raft request' (duration: 232.126468ms)","trace[1376797811] 'compare' (duration: 315.498696ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T09:04:07.746702Z","caller":"traceutil/trace.go:171","msg":"trace[1818120931] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"546.263191ms","start":"2026-10-14T09:04:07.20042Z","end":"2026-10-14T09:04:07.746683Z","steps":["trace[1818120931] 'process raft request' (duration: 545.97437ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T09:04:07.746747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T09:04:07.197771Z","time spent":"548.899194ms","remote":"127.0.0.1:57364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T09:04:07.746801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T09:04:07.200403Z","time spent":"546.347005ms","remote":"127.0.0.1:57306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":219,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-14T09:04:07.746821Z","caller":"traceutil/trace.go:171","msg":"trace[822246516] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"548.801979ms","start":"2026-10-14T09:04:07.197959Z","end":"2026-10-14T09:04:07.746761Z","steps":["trace[822246516] 'process raft request' (duration: 548.367513ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T09:04:07.747019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T09:04:07.197936Z","time spent":"548.978056ms","remote":"127.0.0.1:57408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":435,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-14T09:04:08.077167Z","caller":"traceutil/trace.go:171","msg":"trace[1037442747] transaction","detail":"{read_only:false; number_of_response:0; response_revision:315; }","duration":"403.325076ms","start":"2026-10-14T09:04:07.673817Z","end":"2026-10-14T09:04:08.077142Z","steps":["trace[1037442747] 'process raft request' (duration: 403.206432ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T09:04:08.077289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T09:04:07.673798Z","time spent":"403.440491ms","remote":"127.0.0.1:57284","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-14T09:04:08.170147Z","caller":"traceutil/trace.go:171","msg":"trace[1565721066] transaction","detail":"{read_only:false; number_of_response:0; response_revision:315; }","duration":"496.165128ms","start":"2026-10-14T09:04:07.673944Z","end":"2026-10-14T09:04:08.170109Z","steps":["trace[1565721066] 'process raft request' (duration: 495.94287ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T09:04:08.170244Z","caller":"traceutil/trace.go:171","msg":"trace[552975585] linearizableReadLoop","detail":"{readStateIndex:326; appliedIndex:324; }","duration":"416.15066ms","start":"2026-10-14T09:04:07.754004Z","end":"2026-10-14T09:04:08.170155Z","steps":["trace[552975585] 'read index received' (duration: 323.039548ms)","trace[552975585] 'applied index is now lower than readState.Index' (duration: 93.109382ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T09:04:08.170329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T09:04:07.673902Z","time spent":"496.350866ms","remote":"127.0.0.1:57284","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-14T09:04:08.170524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.500913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T09:04:08.17062Z","caller":"traceutil/trace.go:171","msg":"trace[1711612437] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-after-finished-controller; range_end:; response_count:0; response_revision:316; }","duration":"416.633098ms","start":"2026-10-14T09:04:07.753955Z","end":"2026-10-14T09:04:08.170588Z","steps":["trace[1711612437] 'agreement among raft nodes before linearized reading' (duration: 416.478852ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T09:04:08.170647Z","caller":"traceutil/trace.go:171","msg":"trace[290183235] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"370.784553ms","start":"2026-10-14T09:04:07.799851Z","end":"2026-10-14T09:04:08.170635Z","steps":["trace[290183235] 'process raft request' (duration: 370.17276ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T09:04:08.17064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.680412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-10-14T09:04:08.170665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T09:04:07.753932Z","time spent":"416.720513ms","remote":"127.0.0.1:57306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":0,"response size":27,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" "} {"level":"info","ts":"2026-10-14T09:04:08.170692Z","caller":"traceutil/trace.go:171","msg":"trace[1170418200] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:316; }","duration":"235.780776ms","start":"2026-10-14T09:04:07.934896Z","end":"2026-10-14T09:04:08.170677Z","steps":["trace[1170418200] 'agreement among raft nodes before linearized reading' (duration: 235.62469ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T09:04:08.170712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T09:04:07.799827Z","time spent":"370.838535ms","remote":"127.0.0.1:57284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7096,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T09:04:08.17075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.488125ms","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-14T09:04:08.170841Z","caller":"traceutil/trace.go:171","msg":"trace[279951741] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:316; }","duration":"234.661782ms","start":"2026-10-14T09:04:07.936162Z","end":"2026-10-14T09:04:08.170824Z","steps":["trace[279951741] 'agreement among raft nodes before linearized reading' (duration: 234.460875ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T09:04:46.068321Z","caller":"traceutil/trace.go:171","msg":"trace[548671534] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"206.725816ms","start":"2026-10-14T09:04:45.86155Z","end":"2026-10-14T09:04:46.068275Z","steps":["trace[548671534] 'process raft request' (duration: 206.243529ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T09:04:49.746672Z","caller":"traceutil/trace.go:171","msg":"trace[1432859931] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"281.516536ms","start":"2026-10-14T09:04:49.465129Z","end":"2026-10-14T09:04:49.746646Z","steps":["trace[1432859931] 'process raft request' (duration: 271.424019ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T09:04:49.746883Z","caller":"traceutil/trace.go:171","msg":"trace[1232003747] linearizableReadLoop","detail":"{readStateIndex:681; appliedIndex:680; }","duration":"277.380184ms","start":"2026-10-14T09:04:49.469488Z","end":"2026-10-14T09:04:49.746868Z","steps":["trace[1232003747] 'read index received' (duration: 266.722778ms)","trace[1232003747] 'applied index is now lower than readState.Index' (duration: 10.656066ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T09:04:49.747046Z","caller":"traceutil/trace.go:171","msg":"trace[1102809134] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"219.940965ms","start":"2026-10-14T09:04:49.52706Z","end":"2026-10-14T09:04:49.747001Z","steps":["trace[1102809134] 'process raft request' (duration: 219.573252ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T09:04:49.758149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.626542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/udproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:31126"} {"level":"info","ts":"2026-10-14T09:04:49.758437Z","caller":"traceutil/trace.go:171","msg":"trace[315617469] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/udproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:659; }","duration":"288.808748ms","start":"2026-10-14T09:04:49.469454Z","end":"2026-10-14T09:04:49.758262Z","steps":["trace[315617469] 'agreement among raft nodes before linearized reading' (duration: 277.504588ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T09:04:56.680435Z","caller":"traceutil/trace.go:171","msg":"trace[991353548] linearizableReadLoop","detail":"{readStateIndex:744; appliedIndex:743; }","duration":"157.2243ms","start":"2026-10-14T09:04:56.523178Z","end":"2026-10-14T09:04:56.680403Z","steps":["trace[991353548] 'read index received' (duration: 156.945761ms)","trace[991353548] 'applied index is now lower than readState.Index' (duration: 277.319µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T09:04:56.680503Z","caller":"traceutil/trace.go:171","msg":"trace[368808154] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"179.982493ms","start":"2026-10-14T09:04:56.500484Z","end":"2026-10-14T09:04:56.680466Z","steps":["trace[368808154] 'process raft request' (duration: 179.717154ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T09:04:56.680758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.561813ms","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-14T09:04:56.680862Z","caller":"traceutil/trace.go:171","msg":"trace[554453618] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:719; }","duration":"157.673916ms","start":"2026-10-14T09:04:56.523119Z","end":"2026-10-14T09:04:56.680793Z","steps":["trace[554453618] 'agreement among raft nodes before linearized reading' (duration: 157.465989ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T09:04:56.680934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.812708ms","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-14T09:04:56.680993Z","caller":"traceutil/trace.go:171","msg":"trace[606502111] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:719; }","duration":"146.916581ms","start":"2026-10-14T09:04:56.534059Z","end":"2026-10-14T09:04:56.680975Z","steps":["trace[606502111] 'agreement among raft nodes before linearized reading' (duration: 146.659593ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T09:04:57.27122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.512767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-dl68l\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-10-14T09:04:57.271312Z","caller":"traceutil/trace.go:171","msg":"trace[1700452311] range","detail":"{range_begin:/registry/pods/kube-system/cilium-dl68l; range_end:; response_count:1; response_revision:722; }","duration":"463.693103ms","start":"2026-10-14T09:04:56.8076Z","end":"2026-10-14T09:04:57.271293Z","steps":["trace[1700452311] 'range keys from in-memory index tree' (duration: 463.357022ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T09:04:57.271301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.126727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-14T09:04:57.271353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T09:04:56.807569Z","time spent":"463.774186ms","remote":"127.0.0.1:57284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-dl68l\" "} {"level":"info","ts":"2026-10-14T09:04:57.271399Z","caller":"traceutil/trace.go:171","msg":"trace[444203311] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:722; }","duration":"129.282483ms","start":"2026-10-14T09:04:57.142093Z","end":"2026-10-14T09:04:57.271375Z","steps":["trace[444203311] 'range keys from in-memory index tree' (duration: 129.025415ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T09:04:57.942743Z","caller":"traceutil/trace.go:171","msg":"trace[829309386] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"107.504594ms","start":"2026-10-14T09:04:57.835208Z","end":"2026-10-14T09:04:57.942713Z","steps":["trace[829309386] 'process raft request' (duration: 92.04607ms)","trace[829309386] 'compare' (duration: 15.277238ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T09:05:38.651559Z","caller":"traceutil/trace.go:171","msg":"trace[1150934559] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"155.65266ms","start":"2026-10-14T09:05:38.495875Z","end":"2026-10-14T09:05:38.651528Z","steps":["trace[1150934559] 'process raft request' (duration: 155.495015ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T09:05:38.784007Z","caller":"traceutil/trace.go:171","msg":"trace[435982053] transaction","detail":"{read_only:false; response_revision:1212; number_of_response:1; }","duration":"125.64369ms","start":"2026-10-14T09:05:38.658335Z","end":"2026-10-14T09:05:38.783978Z","steps":["trace[435982053] 'process raft request' (duration: 125.400372ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T09:05:38.784158Z","caller":"traceutil/trace.go:171","msg":"trace[834733450] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"125.773494ms","start":"2026-10-14T09:05:38.658332Z","end":"2026-10-14T09:05:38.784105Z","steps":["trace[834733450] 'process raft request' (duration: 125.588088ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T09:05:46.688973Z","caller":"traceutil/trace.go:171","msg":"trace[1400535811] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"328.723455ms","start":"2026-10-14T09:05:46.360222Z","end":"2026-10-14T09:05:46.688946Z","steps":["trace[1400535811] 'process raft request' (duration: 328.123975ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T09:05:46.689156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T09:05:46.360183Z","time spent":"328.88197ms","remote":"127.0.0.1:57364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T09:05:47.154541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.820019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-10-14T09:05:47.154552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.388941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" ","response":"range_response_count:1 size:224"} {"level":"info","ts":"2026-10-14T09:05:47.154637Z","caller":"traceutil/trace.go:171","msg":"trace[740584306] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1248; }","duration":"326.997035ms","start":"2026-10-14T09:05:46.827621Z","end":"2026-10-14T09:05:47.154618Z","steps":["trace[740584306] 'range keys from in-memory index tree' (duration: 326.616953ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T09:05:47.154656Z","caller":"traceutil/trace.go:171","msg":"trace[1710249252] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-after-finished-controller; range_end:; response_count:1; response_revision:1248; }","duration":"151.549476ms","start":"2026-10-14T09:05:47.00308Z","end":"2026-10-14T09:05:47.154629Z","steps":["trace[1710249252] 'range keys from in-memory index tree' (duration: 151.283448ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T09:05:47.154553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.658984ms","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-14T09:05:47.154707Z","caller":"traceutil/trace.go:171","msg":"trace[223998300] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1248; }","duration":"142.878131ms","start":"2026-10-14T09:05:47.011821Z","end":"2026-10-14T09:05:47.154699Z","steps":["trace[223998300] 'range keys from in-memory index tree' (duration: 142.435817ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T09:05:47.15468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T09:05:46.827592Z","time spent":"327.079208ms","remote":"127.0.0.1:57364","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":"warn","ts":"2026-10-14T09:05:47.666902Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5164398666705441049,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-10-14T09:05:48.167801Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5164398666705441049,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-10-14T09:05:48.668234Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5164398666705441049,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-10-14T09:05:48.771937Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.611030176s","expected-duration":"1s"} {"level":"info","ts":"2026-10-14T09:05:48.772482Z","caller":"traceutil/trace.go:171","msg":"trace[1535067401] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"1.611721198s","start":"2026-10-14T09:05:47.160736Z","end":"2026-10-14T09:05:48.772457Z","steps":["trace[1535067401] 'process raft request' (duration: 1.611555053s)"],"step_count":1} {"level":"warn","ts":"2026-10-14T09:05:48.772621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T09:05:47.160701Z","time spent":"1.611847972s","remote":"127.0.0.1:57364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T09:05:49.09303Z","caller":"traceutil/trace.go:171","msg":"trace[1617066216] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"1.931765806s","start":"2026-10-14T09:05:47.161248Z","end":"2026-10-14T09:05:49.093014Z","steps":["trace[1617066216] 'process raft request' (duration: 1.929379281s)"],"step_count":1} {"level":"info","ts":"2026-10-14T09:05:49.093207Z","caller":"traceutil/trace.go:171","msg":"trace[16855147] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"1.931855399s","start":"2026-10-14T09:05:47.161335Z","end":"2026-10-14T09:05:49.09319Z","steps":["trace[16855147] 'process raft request' (duration: 1.931646752s)"],"step_count":1} {"level":"info","ts":"2026-10-14T09:05:49.093237Z","caller":"traceutil/trace.go:171","msg":"trace[196329008] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"1.93126335s","start":"2026-10-14T09:05:47.161961Z","end":"2026-10-14T09:05:49.093224Z","steps":["trace[196329008] 'process raft request' (duration: 1.931179287s)"],"step_count":1} {"level":"warn","ts":"2026-10-14T09:05:49.093295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T09:05:47.161945Z","time spent":"1.931321891s","remote":"127.0.0.1:57364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T09:05:49.093294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T09:05:47.161312Z","time spent":"1.931937482s","remote":"127.0.0.1:57364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T09:05:49.093405Z","caller":"traceutil/trace.go:171","msg":"trace[151115297] linearizableReadLoop","detail":"{readStateIndex:1291; appliedIndex:1286; }","duration":"1.927437239s","start":"2026-10-14T09:05:47.16595Z","end":"2026-10-14T09:05:49.093387Z","steps":["trace[151115297] 'read index received' (duration: 1.606340698s)","trace[151115297] 'applied index is now lower than readState.Index' (duration: 321.095931ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T09:05:49.093435Z","caller":"traceutil/trace.go:171","msg":"trace[114504103] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"1.930035631s","start":"2026-10-14T09:05:47.163357Z","end":"2026-10-14T09:05:49.093393Z","steps":["trace[114504103] 'process raft request' (duration: 1.929835595s)"],"step_count":1} {"level":"info","ts":"2026-10-14T09:05:49.093471Z","caller":"traceutil/trace.go:171","msg":"trace[319553694] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"398.656408ms","start":"2026-10-14T09:05:48.694804Z","end":"2026-10-14T09:05:49.093461Z","steps":["trace[319553694] 'process raft request' (duration: 398.518294ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T09:05:49.093488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T09:05:47.163336Z","time spent":"1.930124964s","remote":"127.0.0.1:57364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T09:05:49.093532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T09:05:48.69478Z","time spent":"398.7231ms","remote":"127.0.0.1:57364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T09:05:49.093794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.927830311s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:1 size:2897"} {"level":"info","ts":"2026-10-14T09:05:49.093897Z","caller":"traceutil/trace.go:171","msg":"trace[142640247] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:1; response_revision:1254; }","duration":"1.927991966s","start":"2026-10-14T09:05:47.165886Z","end":"2026-10-14T09:05:49.093878Z","steps":["trace[142640247] 'agreement among raft nodes before linearized reading' (duration: 1.927598424s)"],"step_count":1} {"level":"warn","ts":"2026-10-14T09:05:49.093968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T09:05:47.165857Z","time spent":"1.928097519s","remote":"127.0.0.1:57338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":2919,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-10-14T09:05:49.094007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T09:05:47.161225Z","time spent":"1.931854759s","remote":"127.0.0.1:57364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T09:05:49.155531Z","caller":"traceutil/trace.go:171","msg":"trace[2066534200] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"300.789315ms","start":"2026-10-14T09:05:48.85472Z","end":"2026-10-14T09:05:49.155509Z","steps":["trace[2066534200] 'process raft request' (duration: 300.63539ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T09:05:49.155614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.455242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-14T09:05:49.155641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T09:05:48.854694Z","time spent":"300.897939ms","remote":"127.0.0.1:57364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T09:05:49.155671Z","caller":"traceutil/trace.go:171","msg":"trace[464366427] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1255; }","duration":"714.560136ms","start":"2026-10-14T09:05:48.441095Z","end":"2026-10-14T09:05:49.155655Z","steps":["trace[464366427] 'agreement among raft nodes before linearized reading' (duration: 714.437722ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T09:05:49.155711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T09:05:48.441071Z","time spent":"714.629337ms","remote":"127.0.0.1:57074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-14T09:05:49.155806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.260269468s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-14T09:05:49.155889Z","caller":"traceutil/trace.go:171","msg":"trace[1632623079] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1255; }","duration":"1.260406563s","start":"2026-10-14T09:05:47.895464Z","end":"2026-10-14T09:05:49.15587Z","steps":["trace[1632623079] 'agreement among raft nodes before linearized reading' (duration: 1.260247188s)"],"step_count":1} {"level":"warn","ts":"2026-10-14T09:05:49.155938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T09:05:47.895428Z","time spent":"1.260499865s","remote":"127.0.0.1:57548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":8,"response size":29,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"info","ts":"2026-10-14T09:06:00.890582Z","caller":"traceutil/trace.go:171","msg":"trace[1923126257] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"101.459923ms","start":"2026-10-14T09:06:00.789104Z","end":"2026-10-14T09:06:00.890564Z","steps":["trace[1923126257] 'process raft request' (duration: 101.063341ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T09:06:36.549765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.790669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-10-14T09:06:36.549987Z","caller":"traceutil/trace.go:171","msg":"trace[1429853336] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1915; }","duration":"121.073594ms","start":"2026-10-14T09:06:36.428886Z","end":"2026-10-14T09:06:36.549959Z","steps":["trace[1429853336] 'range keys from in-memory index tree' (duration: 120.642095ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T09:06:36.550392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.450823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:10630"} {"level":"info","ts":"2026-10-14T09:06:36.550478Z","caller":"traceutil/trace.go:171","msg":"trace[84921566] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1915; }","duration":"121.576186ms","start":"2026-10-14T09:06:36.428884Z","end":"2026-10-14T09:06:36.55046Z","steps":["trace[84921566] 'range keys from in-memory index tree' (duration: 120.773518ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T09:06:37.224288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.348688ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T09:06:37.224384Z","caller":"traceutil/trace.go:171","msg":"trace[2070245211] linearizableReadLoop","detail":"{readStateIndex:1994; appliedIndex:1992; }","duration":"634.143374ms","start":"2026-10-14T09:06:36.590222Z","end":"2026-10-14T09:06:37.224366Z","steps":["trace[2070245211] 'read index received' (duration: 27.509132ms)","trace[2070245211] 'applied index is now lower than readState.Index' (duration: 606.633162ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T09:06:37.224647Z","caller":"traceutil/trace.go:171","msg":"trace[666695428] transaction","detail":"{read_only:false; response_revision:1918; number_of_response:1; }","duration":"660.462921ms","start":"2026-10-14T09:06:36.564167Z","end":"2026-10-14T09:06:37.22463Z","steps":["trace[666695428] 'process raft request' (duration: 131.687603ms)","trace[666695428] 'compare' (duration: 528.248266ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T09:06:37.224728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T09:06:36.564142Z","time spent":"660.546913ms","remote":"127.0.0.1:57284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10932,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T09:06:37.225336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.108164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-14T09:06:37.225387Z","caller":"traceutil/trace.go:171","msg":"trace[332007371] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1918; }","duration":"635.195906ms","start":"2026-10-14T09:06:36.590176Z","end":"2026-10-14T09:06:37.225372Z","steps":["trace[332007371] 'agreement among raft nodes before linearized reading' (duration: 635.131455ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T09:06:37.225415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T09:06:36.590152Z","time spent":"635.253618ms","remote":"127.0.0.1:57584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":2,"response size":29,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-10-14T09:06:37.587063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.238209ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T09:06:37.587756Z","caller":"traceutil/trace.go:171","msg":"trace[1242436426] transaction","detail":"{read_only:false; response_revision:1924; number_of_response:1; }","duration":"349.799805ms","start":"2026-10-14T09:06:37.237929Z","end":"2026-10-14T09:06:37.587729Z","steps":["trace[1242436426] 'process raft request' (duration: 349.663802ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T09:06:37.587826Z","caller":"traceutil/trace.go:171","msg":"trace[1130822423] transaction","detail":"{read_only:false; response_revision:1920; number_of_response:1; }","duration":"354.589639ms","start":"2026-10-14T09:06:37.23322Z","end":"2026-10-14T09:06:37.587809Z","steps":["trace[1130822423] 'process raft request' (duration: 236.526992ms)","trace[1130822423] 'compare' (duration: 117.042775ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T09:06:37.587945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T09:06:37.237916Z","time spent":"349.910667ms","remote":"127.0.0.1:57284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3726,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T09:06:37.588053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T09:06:37.233179Z","time spent":"354.793173ms","remote":"127.0.0.1:57256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":978,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T09:06:37.587772Z","caller":"traceutil/trace.go:171","msg":"trace[403941543] transaction","detail":"{read_only:false; response_revision:1921; number_of_response:1; }","duration":"354.355754ms","start":"2026-10-14T09:06:37.233397Z","end":"2026-10-14T09:06:37.587753Z","steps":["trace[403941543] 'process raft request' (duration: 353.975746ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T09:06:37.588324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T09:06:37.233382Z","time spent":"354.810224ms","remote":"127.0.0.1:57256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":894,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T09:06:37.588399Z","caller":"traceutil/trace.go:171","msg":"trace[1499270946] transaction","detail":"{read_only:false; response_revision:1923; number_of_response:1; }","duration":"354.894835ms","start":"2026-10-14T09:06:37.233489Z","end":"2026-10-14T09:06:37.588384Z","steps":["trace[1499270946] 'process raft request' (duration: 354.018457ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T09:06:37.587919Z","caller":"traceutil/trace.go:171","msg":"trace[1420245919] transaction","detail":"{read_only:false; response_revision:1925; number_of_response:1; }","duration":"184.335637ms","start":"2026-10-14T09:06:37.403458Z","end":"2026-10-14T09:06:37.587794Z","steps":["trace[1420245919] 'process raft request' (duration: 184.186163ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T09:06:37.588132Z","caller":"traceutil/trace.go:171","msg":"trace[461047715] transaction","detail":"{read_only:false; response_revision:1922; number_of_response:1; }","duration":"354.65862ms","start":"2026-10-14T09:06:37.233444Z","end":"2026-10-14T09:06:37.588103Z","steps":["trace[461047715] 'process raft request' (duration: 354.015587ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T09:06:37.589812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T09:06:37.23343Z","time spent":"356.332947ms","remote":"127.0.0.1:57364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T09:06:37.589767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T09:06:37.233459Z","time spent":"355.855136ms","remote":"127.0.0.1:57376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1530,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T09:06:37.931956Z","caller":"traceutil/trace.go:171","msg":"trace[475806273] linearizableReadLoop","detail":"{readStateIndex:2006; appliedIndex:2004; }","duration":"103.025163ms","start":"2026-10-14T09:06:37.82891Z","end":"2026-10-14T09:06:37.931935Z","steps":["trace[475806273] 'read index received' (duration: 71.864891ms)","trace[475806273] 'applied index is now lower than readState.Index' (duration: 31.159382ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T09:06:37.932186Z","caller":"traceutil/trace.go:171","msg":"trace[2036754744] transaction","detail":"{read_only:false; response_revision:1929; number_of_response:1; }","duration":"242.251626ms","start":"2026-10-14T09:06:37.689919Z","end":"2026-10-14T09:06:37.93217Z","steps":["trace[2036754744] 'process raft request' (duration: 210.871589ms)","trace[2036754744] 'compare' (duration: 30.767364ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T09:06:37.932541Z","caller":"traceutil/trace.go:171","msg":"trace[1849219695] transaction","detail":"{read_only:false; response_revision:1930; number_of_response:1; }","duration":"242.582362ms","start":"2026-10-14T09:06:37.689917Z","end":"2026-10-14T09:06:37.932499Z","steps":["trace[1849219695] 'process raft request' (duration: 241.97266ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T09:06:37.932692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.936778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"warn","ts":"2026-10-14T09:06:37.932739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.807909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-14T09:06:37.932852Z","caller":"traceutil/trace.go:171","msg":"trace[460109418] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1930; }","duration":"102.096282ms","start":"2026-10-14T09:06:37.830678Z","end":"2026-10-14T09:06:37.932775Z","steps":["trace[460109418] 'agreement among raft nodes before linearized reading' (duration: 101.689564ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T09:06:37.933073Z","caller":"traceutil/trace.go:171","msg":"trace[2135777653] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1930; }","duration":"103.939182ms","start":"2026-10-14T09:06:37.828856Z","end":"2026-10-14T09:06:37.932795Z","steps":["trace[2135777653] 'agreement among raft nodes before linearized reading' (duration: 103.716677ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T09:06:49.857652Z","caller":"traceutil/trace.go:171","msg":"trace[1936322775] transaction","detail":"{read_only:false; response_revision:2028; number_of_response:1; }","duration":"150.482595ms","start":"2026-10-14T09:06:49.707142Z","end":"2026-10-14T09:06:49.857625Z","steps":["trace[1936322775] 'process raft request' (duration: 150.25245ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T09:06:49.862384Z","caller":"traceutil/trace.go:171","msg":"trace[1585335947] transaction","detail":"{read_only:false; response_revision:2029; number_of_response:1; }","duration":"155.151317ms","start":"2026-10-14T09:06:49.707209Z","end":"2026-10-14T09:06:49.86236Z","steps":["trace[1585335947] 'process raft request' (duration: 154.954493ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T09:06:49.873619Z","caller":"traceutil/trace.go:171","msg":"trace[1084345745] linearizableReadLoop","detail":"{readStateIndex:2108; appliedIndex:2106; }","duration":"159.519585ms","start":"2026-10-14T09:06:49.714084Z","end":"2026-10-14T09:06:49.873603Z","steps":["trace[1084345745] 'read index received' (duration: 143.344485ms)","trace[1084345745] 'applied index is now lower than readState.Index' (duration: 16.17399ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T09:06:49.873803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.71722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-10-14T09:06:49.87385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.719277ms","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-14T09:06:49.873886Z","caller":"traceutil/trace.go:171","msg":"trace[1134561148] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2029; }","duration":"159.865853ms","start":"2026-10-14T09:06:49.714006Z","end":"2026-10-14T09:06:49.873872Z","steps":["trace[1134561148] 'agreement among raft nodes before linearized reading' (duration: 159.681289ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T09:06:49.873899Z","caller":"traceutil/trace.go:171","msg":"trace[2078965152] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2029; }","duration":"126.85787ms","start":"2026-10-14T09:06:49.747025Z","end":"2026-10-14T09:06:49.873883Z","steps":["trace[2078965152] 'agreement among raft nodes before linearized reading' (duration: 126.744428ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T09:06:53.252921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.519731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T09:06:53.253002Z","caller":"traceutil/trace.go:171","msg":"trace[1399140743] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2056; }","duration":"111.646084ms","start":"2026-10-14T09:06:53.141339Z","end":"2026-10-14T09:06:53.252985Z","steps":["trace[1399140743] 'range keys from in-memory index tree' (duration: 111.44702ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T09:07:20.961974Z","caller":"traceutil/trace.go:171","msg":"trace[1767551830] transaction","detail":"{read_only:false; response_revision:2211; number_of_response:1; }","duration":"115.505292ms","start":"2026-10-14T09:07:20.846426Z","end":"2026-10-14T09:07:20.961931Z","steps":["trace[1767551830] 'process raft request' (duration: 115.363318ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T09:07:21.141584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.676194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-10-14T09:07:21.141672Z","caller":"traceutil/trace.go:171","msg":"trace[588122716] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2211; }","duration":"162.768546ms","start":"2026-10-14T09:07:20.978881Z","end":"2026-10-14T09:07:21.14165Z","steps":["trace[588122716] 'range keys from in-memory index tree' (duration: 162.393316ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T09:07:21.742864Z","caller":"traceutil/trace.go:171","msg":"trace[1139529115] transaction","detail":"{read_only:false; response_revision:2213; number_of_response:1; }","duration":"152.925823ms","start":"2026-10-14T09:07:21.589904Z","end":"2026-10-14T09:07:21.74283Z","steps":["trace[1139529115] 'process raft request' (duration: 152.469443ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T09:07:54.798102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.31325ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T09:07:54.798408Z","caller":"traceutil/trace.go:171","msg":"trace[456536020] transaction","detail":"{read_only:false; response_revision:2410; number_of_response:1; }","duration":"163.855356ms","start":"2026-10-14T09:07:54.634528Z","end":"2026-10-14T09:07:54.798383Z","steps":["trace[456536020] 'process raft request' (duration: 33.197486ms)","trace[456536020] 'compare' (duration: 130.207528ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T09:07:54.798532Z","caller":"traceutil/trace.go:171","msg":"trace[1418248188] transaction","detail":"{read_only:false; response_revision:2411; number_of_response:1; }","duration":"145.280883ms","start":"2026-10-14T09:07:54.653244Z","end":"2026-10-14T09:07:54.798525Z","steps":["trace[1418248188] 'process raft request' (duration: 144.979555ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T09:08:03.543324Z","caller":"traceutil/trace.go:171","msg":"trace[296356417] transaction","detail":"{read_only:false; response_revision:2458; number_of_response:1; }","duration":"115.324279ms","start":"2026-10-14T09:08:03.427971Z","end":"2026-10-14T09:08:03.543295Z","steps":["trace[296356417] 'process raft request' (duration: 115.165245ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T09:08:08.081339Z","caller":"traceutil/trace.go:171","msg":"trace[1474742639] transaction","detail":"{read_only:false; response_revision:2512; number_of_response:1; }","duration":"191.17273ms","start":"2026-10-14T09:08:07.890141Z","end":"2026-10-14T09:08:08.081313Z","steps":["trace[1474742639] 'process raft request' (duration: 190.790361ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T09:08:12.498256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.95189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"} {"level":"info","ts":"2026-10-14T09:08:12.498331Z","caller":"traceutil/trace.go:171","msg":"trace[688652076] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2543; }","duration":"117.072753ms","start":"2026-10-14T09:08:12.381241Z","end":"2026-10-14T09:08:12.498313Z","steps":["trace[688652076] 'range keys from in-memory index tree' (duration: 116.834136ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T09:08:17.879343Z","caller":"traceutil/trace.go:171","msg":"trace[1051525096] linearizableReadLoop","detail":"{readStateIndex:2714; appliedIndex:2713; }","duration":"190.914833ms","start":"2026-10-14T09:08:17.688405Z","end":"2026-10-14T09:08:17.87932Z","steps":["trace[1051525096] 'read index received' (duration: 190.660556ms)","trace[1051525096] 'applied index is now lower than readState.Index' (duration: 253.367µs)"],"step_count":2} {"level":"warn","ts":"2026-10-14T09:08:17.879532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.107167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-10-14T09:08:17.879493Z","caller":"traceutil/trace.go:171","msg":"trace[719707618] transaction","detail":"{read_only:false; response_revision:2614; number_of_response:1; }","duration":"204.262104ms","start":"2026-10-14T09:08:17.675203Z","end":"2026-10-14T09:08:17.879465Z","steps":["trace[719707618] 'process raft request' (duration: 203.973256ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T09:08:17.879572Z","caller":"traceutil/trace.go:171","msg":"trace[1687047772] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2614; }","duration":"191.19603ms","start":"2026-10-14T09:08:17.688359Z","end":"2026-10-14T09:08:17.879555Z","steps":["trace[1687047772] 'agreement among raft nodes before linearized reading' (duration: 191.050516ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T09:08:17.987142Z","caller":"traceutil/trace.go:171","msg":"trace[1863013828] transaction","detail":"{read_only:false; response_revision:2615; number_of_response:1; }","duration":"102.183537ms","start":"2026-10-14T09:08:17.884924Z","end":"2026-10-14T09:08:17.987107Z","steps":["trace[1863013828] 'process raft request' (duration: 77.055036ms)","trace[1863013828] 'compare' (duration: 24.990598ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T09:08:48.505039Z","caller":"traceutil/trace.go:171","msg":"trace[734455758] transaction","detail":"{read_only:false; response_revision:2782; number_of_response:1; }","duration":"116.702532ms","start":"2026-10-14T09:08:48.388305Z","end":"2026-10-14T09:08:48.505008Z","steps":["trace[734455758] 'process raft request' (duration: 116.474796ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T09:08:48.653104Z","caller":"traceutil/trace.go:171","msg":"trace[1113557740] transaction","detail":"{read_only:false; response_revision:2783; number_of_response:1; }","duration":"195.614143ms","start":"2026-10-14T09:08:48.45745Z","end":"2026-10-14T09:08:48.653064Z","steps":["trace[1113557740] 'process raft request' (duration: 193.240791ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T09:12:17.569368Z","caller":"traceutil/trace.go:171","msg":"trace[384919506] transaction","detail":"{read_only:false; response_revision:3964; number_of_response:1; }","duration":"119.821886ms","start":"2026-10-14T09:12:17.449518Z","end":"2026-10-14T09:12:17.56934Z","steps":["trace[384919506] 'process raft request' (duration: 119.544058ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T09:13:24.272917Z","caller":"traceutil/trace.go:171","msg":"trace[280245863] transaction","detail":"{read_only:false; response_revision:4918; number_of_response:1; }","duration":"136.097976ms","start":"2026-10-14T09:13:24.136793Z","end":"2026-10-14T09:13:24.272891Z","steps":["trace[280245863] 'process raft request' (duration: 135.780647ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T09:13:24.317601Z","caller":"traceutil/trace.go:171","msg":"trace[372428934] transaction","detail":"{read_only:false; response_revision:4919; number_of_response:1; }","duration":"125.05766ms","start":"2026-10-14T09:13:24.192524Z","end":"2026-10-14T09:13:24.317582Z","steps":["trace[372428934] 'process raft request' (duration: 124.944677ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T09:13:30.678657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.885632ms","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-14T09:13:30.678764Z","caller":"traceutil/trace.go:171","msg":"trace[1443508105] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5027; }","duration":"197.040296ms","start":"2026-10-14T09:13:30.4817Z","end":"2026-10-14T09:13:30.67874Z","steps":["trace[1443508105] 'range keys from in-memory index tree' (duration: 196.674996ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T09:13:30.678794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.329163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a306cf1a-165c-4385-9171-515c3d91b6dc\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-10-14T09:13:30.678852Z","caller":"traceutil/trace.go:171","msg":"trace[1763471604] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a306cf1a-165c-4385-9171-515c3d91b6dc; range_end:; response_count:1; response_revision:5027; }","duration":"156.426526ms","start":"2026-10-14T09:13:30.522412Z","end":"2026-10-14T09:13:30.678839Z","steps":["trace[1763471604] 'range keys from in-memory index tree' (duration: 156.235621ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T09:13:30.679062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.375094ms","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-10-14T09:13:30.67916Z","caller":"traceutil/trace.go:171","msg":"trace[2138984404] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5027; }","duration":"178.517969ms","start":"2026-10-14T09:13:30.500624Z","end":"2026-10-14T09:13:30.679142Z","steps":["trace[2138984404] 'range keys from in-memory index tree' (duration: 178.193689ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T09:13:30.6792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.563902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a306cf1a-165c-4385-9171-515c3d91b6dc\" ","response":"range_response_count:1 size:3646"} {"level":"warn","ts":"2026-10-14T09:13:30.679214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.49514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-67d5897df6-c2nc5.18de5a806f98c3e0\" ","response":"range_response_count:1 size:898"} {"level":"info","ts":"2026-10-14T09:13:30.679239Z","caller":"traceutil/trace.go:171","msg":"trace[1025820568] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-67d5897df6-c2nc5.18de5a806f98c3e0; range_end:; response_count:1; response_revision:5027; }","duration":"253.550061ms","start":"2026-10-14T09:13:30.425683Z","end":"2026-10-14T09:13:30.679233Z","steps":["trace[1025820568] 'range keys from in-memory index tree' (duration: 253.377337ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T09:13:30.679248Z","caller":"traceutil/trace.go:171","msg":"trace[1079243248] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a306cf1a-165c-4385-9171-515c3d91b6dc; range_end:; response_count:1; response_revision:5027; }","duration":"253.675885ms","start":"2026-10-14T09:13:30.425555Z","end":"2026-10-14T09:13:30.679231Z","steps":["trace[1079243248] 'range keys from in-memory index tree' (duration: 253.377077ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T09:13:32.777554Z","caller":"traceutil/trace.go:171","msg":"trace[767334753] transaction","detail":"{read_only:false; response_revision:5072; number_of_response:1; }","duration":"188.245068ms","start":"2026-10-14T09:13:32.58929Z","end":"2026-10-14T09:13:32.777535Z","steps":["trace[767334753] 'process raft request' (duration: 188.081343ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T09:13:43.946858Z","caller":"traceutil/trace.go:171","msg":"trace[1303381885] transaction","detail":"{read_only:false; response_revision:5221; number_of_response:1; }","duration":"358.316013ms","start":"2026-10-14T09:13:43.588515Z","end":"2026-10-14T09:13:43.946831Z","steps":["trace[1303381885] 'process raft request' (duration: 358.081266ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T09:13:43.947033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T09:13:43.588483Z","time spent":"358.446777ms","remote":"127.0.0.1:57284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10670,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T09:13:43.947272Z","caller":"traceutil/trace.go:171","msg":"trace[628506405] linearizableReadLoop","detail":"{readStateIndex:5416; appliedIndex:5416; }","duration":"285.143491ms","start":"2026-10-14T09:13:43.662112Z","end":"2026-10-14T09:13:43.947256Z","steps":["trace[628506405] 'read index received' (duration: 285.135661ms)","trace[628506405] 'applied index is now lower than readState.Index' (duration: 6.65µs)"],"step_count":2} {"level":"warn","ts":"2026-10-14T09:13:43.947689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.570944ms","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-14T09:13:43.947749Z","caller":"traceutil/trace.go:171","msg":"trace[1298368377] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5221; }","duration":"285.679636ms","start":"2026-10-14T09:13:43.662054Z","end":"2026-10-14T09:13:43.947733Z","steps":["trace[1298368377] 'agreement among raft nodes before linearized reading' (duration: 285.426739ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T09:13:43.956699Z","caller":"traceutil/trace.go:171","msg":"trace[223833777] transaction","detail":"{read_only:false; response_revision:5222; number_of_response:1; }","duration":"114.279837ms","start":"2026-10-14T09:13:43.842397Z","end":"2026-10-14T09:13:43.956677Z","steps":["trace[223833777] 'process raft request' (duration: 114.102392ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T09:13:44.26533Z","caller":"traceutil/trace.go:171","msg":"trace[1672667710] linearizableReadLoop","detail":"{readStateIndex:5424; appliedIndex:5423; }","duration":"121.847032ms","start":"2026-10-14T09:13:44.143451Z","end":"2026-10-14T09:13:44.265298Z","steps":["trace[1672667710] 'read index received' (duration: 65.516572ms)","trace[1672667710] 'applied index is now lower than readState.Index' (duration: 56.32918ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T09:13:44.265508Z","caller":"traceutil/trace.go:171","msg":"trace[1621172477] transaction","detail":"{read_only:false; response_revision:5229; number_of_response:1; }","duration":"135.124543ms","start":"2026-10-14T09:13:44.130365Z","end":"2026-10-14T09:13:44.26549Z","steps":["trace[1621172477] 'process raft request' (duration: 78.624438ms)","trace[1621172477] 'compare' (duration: 56.032922ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T09:13:44.265663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.212504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T09:13:44.265717Z","caller":"traceutil/trace.go:171","msg":"trace[1125948279] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5229; }","duration":"122.328258ms","start":"2026-10-14T09:13:44.143375Z","end":"2026-10-14T09:13:44.265703Z","steps":["trace[1125948279] 'agreement among raft nodes before linearized reading' (duration: 122.232105ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T09:13:44.779842Z","caller":"traceutil/trace.go:171","msg":"trace[1197083163] linearizableReadLoop","detail":"{readStateIndex:5426; appliedIndex:5425; }","duration":"217.652922ms","start":"2026-10-14T09:13:44.562163Z","end":"2026-10-14T09:13:44.779816Z","steps":["trace[1197083163] 'read index received' (duration: 214.141712ms)","trace[1197083163] 'applied index is now lower than readState.Index' (duration: 3.51032ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T09:13:44.77995Z","caller":"traceutil/trace.go:171","msg":"trace[157765961] transaction","detail":"{read_only:false; response_revision:5231; number_of_response:1; }","duration":"222.961033ms","start":"2026-10-14T09:13:44.556977Z","end":"2026-10-14T09:13:44.779938Z","steps":["trace[157765961] 'process raft request' (duration: 219.391111ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T09:13:44.78012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.58086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T09:13:44.780187Z","caller":"traceutil/trace.go:171","msg":"trace[187119157] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:5231; }","duration":"203.704683ms","start":"2026-10-14T09:13:44.576463Z","end":"2026-10-14T09:13:44.780168Z","steps":["trace[187119157] 'agreement among raft nodes before linearized reading' (duration: 203.58749ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T09:13:44.780426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.578659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-14T09:13:44.780453Z","caller":"traceutil/trace.go:171","msg":"trace[1494776428] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:5231; }","duration":"196.62113ms","start":"2026-10-14T09:13:44.583823Z","end":"2026-10-14T09:13:44.780444Z","steps":["trace[1494776428] 'agreement among raft nodes before linearized reading' (duration: 196.551878ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T09:13:44.780494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.092123ms","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":"warn","ts":"2026-10-14T09:13:44.780499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.315081ms","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-14T09:13:44.780552Z","caller":"traceutil/trace.go:171","msg":"trace[1891121257] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5231; }","duration":"148.199847ms","start":"2026-10-14T09:13:44.632336Z","end":"2026-10-14T09:13:44.780536Z","steps":["trace[1891121257] 'agreement among raft nodes before linearized reading' (duration: 148.021862ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T09:13:44.780556Z","caller":"traceutil/trace.go:171","msg":"trace[1849072798] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5231; }","duration":"218.397733ms","start":"2026-10-14T09:13:44.562143Z","end":"2026-10-14T09:13:44.780541Z","steps":["trace[1849072798] 'agreement among raft nodes before linearized reading' (duration: 218.217548ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T09:13:44.780842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.483924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.156\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-14T09:13:44.781235Z","caller":"traceutil/trace.go:171","msg":"trace[1602972762] range","detail":"{range_begin:/registry/masterleases/199.19.213.156; range_end:; response_count:1; response_revision:5231; }","duration":"134.949519ms","start":"2026-10-14T09:13:44.646266Z","end":"2026-10-14T09:13:44.781216Z","steps":["trace[1602972762] 'agreement among raft nodes before linearized reading' (duration: 134.163566ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T09:14:00.556652Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2845} {"level":"info","ts":"2026-10-14T09:14:00.659252Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2845,"took":"101.590007ms","hash":1023051841,"current-db-size-bytes":52125696,"current-db-size":"52 MB","current-db-size-in-use-bytes":28291072,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-14T09:14:00.659365Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1023051841,"revision":2845,"compact-revision":-1} {"level":"info","ts":"2026-10-14T09:19:00.5638Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5368} {"level":"info","ts":"2026-10-14T09:19:00.641313Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5368,"took":"75.58337ms","hash":2457167273,"current-db-size-bytes":52125696,"current-db-size":"52 MB","current-db-size-in-use-bytes":19156992,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-10-14T09:19:00.641401Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2457167273,"revision":5368,"compact-revision":2845}