{"level":"warn","ts":"2026-07-30T00:45:27.368676Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-30T00:45:27.368791Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.129:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.129:2380","--initial-cluster=instance=https://199.204.45.129:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.129:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.129:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-30T00:45:27.368892Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-30T00:45:27.368908Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-07-30T00:45:27.368936Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-30T00:45:27.370197Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"]} {"level":"info","ts":"2026-07-30T00:45:27.370401Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.129:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-30T00:45:27.374267Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.528785ms"} {"level":"info","ts":"2026-07-30T00:45:27.384689Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3cec112083e8d3ff","cluster-id":"3f68255a3b440659"} {"level":"info","ts":"2026-07-30T00:45:27.384771Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=()"} {"level":"info","ts":"2026-07-30T00:45:27.384793Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 0"} {"level":"info","ts":"2026-07-30T00:45:27.384811Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3cec112083e8d3ff [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-30T00:45:27.384823Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 1"} {"level":"info","ts":"2026-07-30T00:45:27.384855Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"warn","ts":"2026-07-30T00:45:27.390927Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-30T00:45:27.392068Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-30T00:45:27.394449Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-30T00:45:27.397829Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3cec112083e8d3ff","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-30T00:45:27.401992Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-30T00:45:27.402265Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3cec112083e8d3ff","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-30T00:45:27.402398Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-30T00:45:27.402491Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-30T00:45:27.404024Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"info","ts":"2026-07-30T00:45:27.40416Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","added-peer-id":"3cec112083e8d3ff","added-peer-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-07-30T00:45:27.404882Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-30T00:45:27.405396Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-07-30T00:45:27.405434Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-07-30T00:45:27.406486Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3cec112083e8d3ff","initial-advertise-peer-urls":["https://199.204.45.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-30T00:45:27.406529Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-30T00:45:27.985351Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff is starting a new election at term 1"} {"level":"info","ts":"2026-07-30T00:45:27.985431Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became pre-candidate at term 1"} {"level":"info","ts":"2026-07-30T00:45:27.985456Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgPreVoteResp from 3cec112083e8d3ff at term 1"} {"level":"info","ts":"2026-07-30T00:45:27.985541Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became candidate at term 2"} {"level":"info","ts":"2026-07-30T00:45:27.985565Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgVoteResp from 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-07-30T00:45:27.985583Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became leader at term 2"} {"level":"info","ts":"2026-07-30T00:45:27.985597Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3cec112083e8d3ff elected leader 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-07-30T00:45:27.986689Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T00:45:27.987506Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-30T00:45:27.987502Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3cec112083e8d3ff","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.129:2379]}","request-path":"/0/members/3cec112083e8d3ff/attributes","cluster-id":"3f68255a3b440659","publish-timeout":"7s"} {"level":"info","ts":"2026-07-30T00:45:27.987771Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-30T00:45:27.988798Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-30T00:45:27.988896Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-30T00:45:27.990014Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T00:45:27.990192Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T00:45:27.990239Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T00:45:27.993105Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-30T00:45:27.993607Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.129:2379"} {"level":"info","ts":"2026-07-30T00:45:51.424274Z","caller":"traceutil/trace.go:171","msg":"trace[1535150608] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"106.232757ms","start":"2026-07-30T00:45:51.318019Z","end":"2026-07-30T00:45:51.424252Z","steps":["trace[1535150608] 'process raft request' (duration: 106.101495ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T00:46:02.938953Z","caller":"traceutil/trace.go:171","msg":"trace[1461589609] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"133.768433ms","start":"2026-07-30T00:46:02.805161Z","end":"2026-07-30T00:46:02.938929Z","steps":["trace[1461589609] 'process raft request' (duration: 133.678689ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T00:46:02.93928Z","caller":"traceutil/trace.go:171","msg":"trace[890973577] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"134.233883ms","start":"2026-07-30T00:46:02.805011Z","end":"2026-07-30T00:46:02.939245Z","steps":["trace[890973577] 'process raft request' (duration: 133.329643ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T00:46:02.944177Z","caller":"traceutil/trace.go:171","msg":"trace[1303845618] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"132.624183ms","start":"2026-07-30T00:46:02.811539Z","end":"2026-07-30T00:46:02.944164Z","steps":["trace[1303845618] 'process raft request' (duration: 132.352521ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T00:46:02.944186Z","caller":"traceutil/trace.go:171","msg":"trace[362191248] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"129.786697ms","start":"2026-07-30T00:46:02.814372Z","end":"2026-07-30T00:46:02.944159Z","steps":["trace[362191248] 'process raft request' (duration: 129.589988ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T00:46:02.944389Z","caller":"traceutil/trace.go:171","msg":"trace[1099107699] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"116.449595ms","start":"2026-07-30T00:46:02.827927Z","end":"2026-07-30T00:46:02.944377Z","steps":["trace[1099107699] 'process raft request' (duration: 116.227885ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T00:46:02.944467Z","caller":"traceutil/trace.go:171","msg":"trace[1021047863] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"115.456781ms","start":"2026-07-30T00:46:02.829Z","end":"2026-07-30T00:46:02.944457Z","steps":["trace[1021047863] 'process raft request' (duration: 115.269463ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T00:46:02.944484Z","caller":"traceutil/trace.go:171","msg":"trace[943536895] transaction","detail":"{read_only:false; number_of_response:1; response_revision:490; }","duration":"130.062169ms","start":"2026-07-30T00:46:02.814411Z","end":"2026-07-30T00:46:02.944473Z","steps":["trace[943536895] 'process raft request' (duration: 129.599678ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T00:46:02.944622Z","caller":"traceutil/trace.go:171","msg":"trace[947693704] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"119.372195ms","start":"2026-07-30T00:46:02.825241Z","end":"2026-07-30T00:46:02.944614Z","steps":["trace[947693704] 'process raft request' (duration: 118.798969ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T00:46:02.944704Z","caller":"traceutil/trace.go:171","msg":"trace[531095278] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"113.40136ms","start":"2026-07-30T00:46:02.831287Z","end":"2026-07-30T00:46:02.944688Z","steps":["trace[531095278] 'process raft request' (duration: 113.006513ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T00:46:02.94481Z","caller":"traceutil/trace.go:171","msg":"trace[2069427078] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"107.354062ms","start":"2026-07-30T00:46:02.837444Z","end":"2026-07-30T00:46:02.944798Z","steps":["trace[2069427078] 'process raft request' (duration: 106.880121ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T00:46:02.944825Z","caller":"traceutil/trace.go:171","msg":"trace[1914169898] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"115.894081ms","start":"2026-07-30T00:46:02.82892Z","end":"2026-07-30T00:46:02.944814Z","steps":["trace[1914169898] 'process raft request' (duration: 115.267113ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T00:46:03.12377Z","caller":"traceutil/trace.go:171","msg":"trace[475919253] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"106.456478ms","start":"2026-07-30T00:46:03.017287Z","end":"2026-07-30T00:46:03.123743Z","steps":["trace[475919253] 'process raft request' (duration: 91.398168ms)","trace[475919253] 'compare' (duration: 14.884283ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T00:46:09.004615Z","caller":"traceutil/trace.go:171","msg":"trace[1721499684] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"104.88753ms","start":"2026-07-30T00:46:08.899701Z","end":"2026-07-30T00:46:09.004588Z","steps":["trace[1721499684] 'process raft request' (duration: 104.753254ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T00:46:09.297281Z","caller":"traceutil/trace.go:171","msg":"trace[449711818] linearizableReadLoop","detail":"{readStateIndex:609; appliedIndex:608; }","duration":"134.891242ms","start":"2026-07-30T00:46:09.162366Z","end":"2026-07-30T00:46:09.297258Z","steps":["trace[449711818] 'read index received' (duration: 134.028907ms)","trace[449711818] 'applied index is now lower than readState.Index' (duration: 861.475µs)"],"step_count":2} {"level":"warn","ts":"2026-07-30T00:46:09.29751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.103792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-30T00:46:09.297598Z","caller":"traceutil/trace.go:171","msg":"trace[578412273] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:587; }","duration":"135.266878ms","start":"2026-07-30T00:46:09.16232Z","end":"2026-07-30T00:46:09.297587Z","steps":["trace[578412273] 'agreement among raft nodes before linearized reading' (duration: 135.037228ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T00:46:09.297897Z","caller":"traceutil/trace.go:171","msg":"trace[281856003] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"193.595431ms","start":"2026-07-30T00:46:09.103717Z","end":"2026-07-30T00:46:09.297312Z","steps":["trace[281856003] 'process raft request' (duration: 192.950754ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T00:46:09.814737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.237692ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T00:46:09.815024Z","caller":"traceutil/trace.go:171","msg":"trace[860971803] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"507.86154ms","start":"2026-07-30T00:46:09.30714Z","end":"2026-07-30T00:46:09.815001Z","steps":["trace[860971803] 'process raft request' (duration: 12.783104ms)","trace[860971803] 'compare' (duration: 494.030593ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T00:46:09.81518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T00:46:09.307113Z","time spent":"507.962885ms","remote":"127.0.0.1:50658","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-07-30T00:46:09.815828Z","caller":"traceutil/trace.go:171","msg":"trace[1265081145] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:609; }","duration":"307.166579ms","start":"2026-07-30T00:46:09.508627Z","end":"2026-07-30T00:46:09.815794Z","steps":["trace[1265081145] 'read index received' (duration: 296.780273ms)","trace[1265081145] 'applied index is now lower than readState.Index' (duration: 10.384466ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T00:46:09.816078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.43466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-30T00:46:09.816144Z","caller":"traceutil/trace.go:171","msg":"trace[2084406619] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:589; }","duration":"307.533715ms","start":"2026-07-30T00:46:09.508594Z","end":"2026-07-30T00:46:09.816128Z","steps":["trace[2084406619] 'agreement among raft nodes before linearized reading' (duration: 307.343477ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T00:46:09.816174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T00:46:09.508575Z","time spent":"307.591496ms","remote":"127.0.0.1:50658","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-07-30T00:46:09.816485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.26305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-30T00:46:09.816597Z","caller":"traceutil/trace.go:171","msg":"trace[497052947] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:589; }","duration":"275.470509ms","start":"2026-07-30T00:46:09.541108Z","end":"2026-07-30T00:46:09.816578Z","steps":["trace[497052947] 'agreement among raft nodes before linearized reading' (duration: 275.044602ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T00:46:09.816932Z","caller":"traceutil/trace.go:171","msg":"trace[509706787] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"463.622377ms","start":"2026-07-30T00:46:09.352238Z","end":"2026-07-30T00:46:09.815861Z","steps":["trace[509706787] 'process raft request' (duration: 462.618095ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T00:46:09.817072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T00:46:09.352213Z","time spent":"464.772054ms","remote":"127.0.0.1:50636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T00:46:23.34673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.985457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T00:46:23.346832Z","caller":"traceutil/trace.go:171","msg":"trace[1971784274] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:747; }","duration":"192.151122ms","start":"2026-07-30T00:46:23.154661Z","end":"2026-07-30T00:46:23.346812Z","steps":["trace[1971784274] 'range keys from in-memory index tree' (duration: 191.896263ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T00:47:40.647724Z","caller":"traceutil/trace.go:171","msg":"trace[1463152405] linearizableReadLoop","detail":"{readStateIndex:1886; appliedIndex:1885; }","duration":"156.820351ms","start":"2026-07-30T00:47:40.490877Z","end":"2026-07-30T00:47:40.647697Z","steps":["trace[1463152405] 'read index received' (duration: 156.639098ms)","trace[1463152405] 'applied index is now lower than readState.Index' (duration: 179.823µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T00:47:40.647875Z","caller":"traceutil/trace.go:171","msg":"trace[772151908] transaction","detail":"{read_only:false; response_revision:1816; number_of_response:1; }","duration":"314.329347ms","start":"2026-07-30T00:47:40.333508Z","end":"2026-07-30T00:47:40.647837Z","steps":["trace[772151908] 'process raft request' (duration: 314.033581ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T00:47:40.647912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.021436ms","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-07-30T00:47:40.647958Z","caller":"traceutil/trace.go:171","msg":"trace[1224232649] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1816; }","duration":"157.126047ms","start":"2026-07-30T00:47:40.490823Z","end":"2026-07-30T00:47:40.647949Z","steps":["trace[1224232649] 'agreement among raft nodes before linearized reading' (duration: 157.018295ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T00:47:40.648049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T00:47:40.333498Z","time spent":"314.463789ms","remote":"127.0.0.1:50590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10970,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T00:47:40.648206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.725697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-10a9c5df-fff0-422f-8920-380217068daf\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-07-30T00:47:40.648468Z","caller":"traceutil/trace.go:171","msg":"trace[2040593713] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-10a9c5df-fff0-422f-8920-380217068daf; range_end:; response_count:1; response_revision:1816; }","duration":"111.130795ms","start":"2026-07-30T00:47:40.537318Z","end":"2026-07-30T00:47:40.648449Z","steps":["trace[2040593713] 'agreement among raft nodes before linearized reading' (duration: 110.591454ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T00:47:40.929291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.157952ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T00:47:40.929402Z","caller":"traceutil/trace.go:171","msg":"trace[1922895455] transaction","detail":"{read_only:false; response_revision:1817; number_of_response:1; }","duration":"271.844671ms","start":"2026-07-30T00:47:40.657536Z","end":"2026-07-30T00:47:40.92938Z","steps":["trace[1922895455] 'process raft request' (duration: 169.513885ms)","trace[1922895455] 'compare' (duration: 101.980699ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T00:47:41.04116Z","caller":"traceutil/trace.go:171","msg":"trace[1454211620] transaction","detail":"{read_only:false; response_revision:1818; number_of_response:1; }","duration":"383.493601ms","start":"2026-07-30T00:47:40.657647Z","end":"2026-07-30T00:47:41.041141Z","steps":["trace[1454211620] 'process raft request' (duration: 372.223056ms)","trace[1454211620] 'compare' (duration: 11.128143ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T00:47:41.041292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T00:47:40.657622Z","time spent":"383.592504ms","remote":"127.0.0.1:50664","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-07-30T00:47:41.04141Z","caller":"traceutil/trace.go:171","msg":"trace[1901627469] transaction","detail":"{read_only:false; response_revision:1819; number_of_response:1; }","duration":"383.42252ms","start":"2026-07-30T00:47:40.657981Z","end":"2026-07-30T00:47:41.041403Z","steps":["trace[1901627469] 'process raft request' (duration: 383.120934ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T00:47:41.041455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T00:47:40.657966Z","time spent":"383.467661ms","remote":"127.0.0.1:50568","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-07-30T00:47:41.041845Z","caller":"traceutil/trace.go:171","msg":"trace[774905591] transaction","detail":"{read_only:false; response_revision:1820; number_of_response:1; }","duration":"383.711926ms","start":"2026-07-30T00:47:40.658104Z","end":"2026-07-30T00:47:41.041816Z","steps":["trace[774905591] 'process raft request' (duration: 383.269518ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T00:47:41.041955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.561267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T00:47:41.04198Z","caller":"traceutil/trace.go:171","msg":"trace[1478086487] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1821; }","duration":"328.624808ms","start":"2026-07-30T00:47:40.713348Z","end":"2026-07-30T00:47:41.041973Z","steps":["trace[1478086487] 'agreement among raft nodes before linearized reading' (duration: 328.544577ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T00:47:41.042003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T00:47:40.713324Z","time spent":"328.673089ms","remote":"127.0.0.1:47244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-30T00:47:41.041994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T00:47:40.658083Z","time spent":"383.831888ms","remote":"127.0.0.1:50568","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-07-30T00:47:41.042151Z","caller":"traceutil/trace.go:171","msg":"trace[1894998678] transaction","detail":"{read_only:false; response_revision:1821; number_of_response:1; }","duration":"380.977223ms","start":"2026-07-30T00:47:40.661157Z","end":"2026-07-30T00:47:41.042135Z","steps":["trace[1894998678] 'process raft request' (duration: 380.560885ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T00:47:41.042191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T00:47:40.661149Z","time spent":"381.018474ms","remote":"127.0.0.1:50590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3729,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T00:47:41.041873Z","caller":"traceutil/trace.go:171","msg":"trace[1016758285] linearizableReadLoop","detail":"{readStateIndex:1891; appliedIndex:1886; }","duration":"328.466855ms","start":"2026-07-30T00:47:40.713397Z","end":"2026-07-30T00:47:41.041864Z","steps":["trace[1016758285] 'read index received' (duration: 113.727735ms)","trace[1016758285] 'applied index is now lower than readState.Index' (duration: 214.73838ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T00:47:41.042558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.615722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-30T00:47:41.042579Z","caller":"traceutil/trace.go:171","msg":"trace[1287452935] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1821; }","duration":"209.666793ms","start":"2026-07-30T00:47:40.832905Z","end":"2026-07-30T00:47:41.042572Z","steps":["trace[1287452935] 'agreement among raft nodes before linearized reading' (duration: 209.585891ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T00:47:52.301677Z","caller":"traceutil/trace.go:171","msg":"trace[1797048120] linearizableReadLoop","detail":"{readStateIndex:1989; appliedIndex:1988; }","duration":"182.034632ms","start":"2026-07-30T00:47:52.119615Z","end":"2026-07-30T00:47:52.30165Z","steps":["trace[1797048120] 'read index received' (duration: 176.288739ms)","trace[1797048120] 'applied index is now lower than readState.Index' (duration: 5.744753ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T00:47:52.301961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.329177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-30T00:47:52.302002Z","caller":"traceutil/trace.go:171","msg":"trace[182537658] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1916; }","duration":"182.414359ms","start":"2026-07-30T00:47:52.119577Z","end":"2026-07-30T00:47:52.301991Z","steps":["trace[182537658] 'agreement among raft nodes before linearized reading' (duration: 182.172455ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T00:47:52.30222Z","caller":"traceutil/trace.go:171","msg":"trace[839545657] transaction","detail":"{read_only:false; response_revision:1916; number_of_response:1; }","duration":"333.399705ms","start":"2026-07-30T00:47:51.9688Z","end":"2026-07-30T00:47:52.302199Z","steps":["trace[839545657] 'process raft request' (duration: 327.258374ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T00:47:52.302585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T00:47:51.968782Z","time spent":"333.489186ms","remote":"127.0.0.1:50658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T00:48:08.663808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.996085ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T00:48:08.664298Z","caller":"traceutil/trace.go:171","msg":"trace[1061829754] transaction","detail":"{read_only:false; response_revision:2021; number_of_response:1; }","duration":"206.622005ms","start":"2026-07-30T00:48:08.457636Z","end":"2026-07-30T00:48:08.664258Z","steps":["trace[1061829754] 'process raft request' (duration: 93.087471ms)","trace[1061829754] 'compare' (duration: 112.71278ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T00:48:08.669275Z","caller":"traceutil/trace.go:171","msg":"trace[649953042] linearizableReadLoop","detail":"{readStateIndex:2098; appliedIndex:2096; }","duration":"201.053932ms","start":"2026-07-30T00:48:08.468206Z","end":"2026-07-30T00:48:08.66926Z","steps":["trace[649953042] 'read index received' (duration: 82.351322ms)","trace[649953042] 'applied index is now lower than readState.Index' (duration: 118.70205ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T00:48:08.669396Z","caller":"traceutil/trace.go:171","msg":"trace[1848387813] transaction","detail":"{read_only:false; response_revision:2022; number_of_response:1; }","duration":"210.945998ms","start":"2026-07-30T00:48:08.458407Z","end":"2026-07-30T00:48:08.669353Z","steps":["trace[1848387813] 'process raft request' (duration: 210.763944ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T00:48:08.669449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.231535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-30T00:48:08.669483Z","caller":"traceutil/trace.go:171","msg":"trace[325687521] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2022; }","duration":"201.282146ms","start":"2026-07-30T00:48:08.468188Z","end":"2026-07-30T00:48:08.66947Z","steps":["trace[325687521] 'agreement among raft nodes before linearized reading' (duration: 201.126853ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T00:48:14.878238Z","caller":"traceutil/trace.go:171","msg":"trace[312145946] transaction","detail":"{read_only:false; response_revision:2054; number_of_response:1; }","duration":"183.473184ms","start":"2026-07-30T00:48:14.694738Z","end":"2026-07-30T00:48:14.878211Z","steps":["trace[312145946] 'process raft request' (duration: 183.290881ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T00:48:14.919263Z","caller":"traceutil/trace.go:171","msg":"trace[2035310172] transaction","detail":"{read_only:false; response_revision:2056; number_of_response:1; }","duration":"222.33434ms","start":"2026-07-30T00:48:14.696902Z","end":"2026-07-30T00:48:14.919237Z","steps":["trace[2035310172] 'process raft request' (duration: 222.242888ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T00:48:14.919405Z","caller":"traceutil/trace.go:171","msg":"trace[1783462598] transaction","detail":"{read_only:false; response_revision:2055; number_of_response:1; }","duration":"222.770237ms","start":"2026-07-30T00:48:14.696611Z","end":"2026-07-30T00:48:14.919382Z","steps":["trace[1783462598] 'process raft request' (duration: 207.421026ms)","trace[1783462598] 'compare' (duration: 14.960145ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T00:48:15.649831Z","caller":"traceutil/trace.go:171","msg":"trace[1636162636] transaction","detail":"{read_only:false; response_revision:2058; number_of_response:1; }","duration":"115.073397ms","start":"2026-07-30T00:48:15.53456Z","end":"2026-07-30T00:48:15.649633Z","steps":["trace[1636162636] 'process raft request' (duration: 114.823823ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T00:48:15.661285Z","caller":"traceutil/trace.go:171","msg":"trace[1913687183] transaction","detail":"{read_only:false; response_revision:2059; number_of_response:1; }","duration":"126.670226ms","start":"2026-07-30T00:48:15.534593Z","end":"2026-07-30T00:48:15.661263Z","steps":["trace[1913687183] 'process raft request' (duration: 126.557924ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T00:49:38.579493Z","caller":"traceutil/trace.go:171","msg":"trace[2419655] transaction","detail":"{read_only:false; response_revision:2602; number_of_response:1; }","duration":"100.073975ms","start":"2026-07-30T00:49:38.479391Z","end":"2026-07-30T00:49:38.579465Z","steps":["trace[2419655] 'process raft request' (duration: 99.920093ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T00:50:00.773691Z","caller":"traceutil/trace.go:171","msg":"trace[1952369181] transaction","detail":"{read_only:false; response_revision:2726; number_of_response:1; }","duration":"106.422496ms","start":"2026-07-30T00:50:00.667233Z","end":"2026-07-30T00:50:00.773656Z","steps":["trace[1952369181] 'process raft request' (duration: 106.208023ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T00:50:11.225099Z","caller":"traceutil/trace.go:171","msg":"trace[1309824563] transaction","detail":"{read_only:false; response_revision:2784; number_of_response:1; }","duration":"134.256746ms","start":"2026-07-30T00:50:11.090802Z","end":"2026-07-30T00:50:11.225059Z","steps":["trace[1309824563] 'process raft request' (duration: 133.981173ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T00:53:45.71661Z","caller":"traceutil/trace.go:171","msg":"trace[993404514] transaction","detail":"{read_only:false; response_revision:4674; number_of_response:1; }","duration":"128.174246ms","start":"2026-07-30T00:53:45.588416Z","end":"2026-07-30T00:53:45.71659Z","steps":["trace[993404514] 'process raft request' (duration: 128.141245ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T00:53:45.716878Z","caller":"traceutil/trace.go:171","msg":"trace[418369359] transaction","detail":"{read_only:false; response_revision:4672; number_of_response:1; }","duration":"129.495405ms","start":"2026-07-30T00:53:45.587371Z","end":"2026-07-30T00:53:45.716867Z","steps":["trace[418369359] 'process raft request' (duration: 31.236672ms)","trace[418369359] 'compare' (duration: 97.63983ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T00:53:45.716964Z","caller":"traceutil/trace.go:171","msg":"trace[122913423] transaction","detail":"{read_only:false; response_revision:4673; number_of_response:1; }","duration":"129.519875ms","start":"2026-07-30T00:53:45.587401Z","end":"2026-07-30T00:53:45.716921Z","steps":["trace[122913423] 'process raft request' (duration: 129.081016ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T00:53:46.193972Z","caller":"traceutil/trace.go:171","msg":"trace[4568350] transaction","detail":"{read_only:false; response_revision:4676; number_of_response:1; }","duration":"127.618828ms","start":"2026-07-30T00:53:46.066323Z","end":"2026-07-30T00:53:46.193942Z","steps":["trace[4568350] 'process raft request' (duration: 89.365248ms)","trace[4568350] 'compare' (duration: 38.081456ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T00:53:59.957144Z","caller":"traceutil/trace.go:171","msg":"trace[1699369663] transaction","detail":"{read_only:false; response_revision:4868; number_of_response:1; }","duration":"190.211094ms","start":"2026-07-30T00:53:59.76691Z","end":"2026-07-30T00:53:59.957122Z","steps":["trace[1699369663] 'process raft request' (duration: 190.090741ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T00:53:59.957307Z","caller":"traceutil/trace.go:171","msg":"trace[917711511] transaction","detail":"{read_only:false; response_revision:4867; number_of_response:1; }","duration":"192.515971ms","start":"2026-07-30T00:53:59.764779Z","end":"2026-07-30T00:53:59.957295Z","steps":["trace[917711511] 'process raft request' (duration: 187.351343ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T00:54:00.340225Z","caller":"traceutil/trace.go:171","msg":"trace[1809885595] transaction","detail":"{read_only:false; response_revision:4871; number_of_response:1; }","duration":"139.285512ms","start":"2026-07-30T00:54:00.20092Z","end":"2026-07-30T00:54:00.340205Z","steps":["trace[1809885595] 'process raft request' (duration: 138.923274ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T00:54:00.507271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.364744ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T00:54:00.507854Z","caller":"traceutil/trace.go:171","msg":"trace[330270293] linearizableReadLoop","detail":"{readStateIndex:5058; appliedIndex:5054; }","duration":"160.587366ms","start":"2026-07-30T00:54:00.347249Z","end":"2026-07-30T00:54:00.507836Z","steps":["trace[330270293] 'read index received' (duration: 30.59536ms)","trace[330270293] 'applied index is now lower than readState.Index' (duration: 129.990796ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T00:54:00.507859Z","caller":"traceutil/trace.go:171","msg":"trace[1168022310] transaction","detail":"{read_only:false; response_revision:4873; number_of_response:1; }","duration":"161.001845ms","start":"2026-07-30T00:54:00.346828Z","end":"2026-07-30T00:54:00.50783Z","steps":["trace[1168022310] 'process raft request' (duration: 160.564516ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T00:54:00.507862Z","caller":"traceutil/trace.go:171","msg":"trace[714055107] transaction","detail":"{read_only:false; response_revision:4875; number_of_response:1; }","duration":"160.854341ms","start":"2026-07-30T00:54:00.346988Z","end":"2026-07-30T00:54:00.507842Z","steps":["trace[714055107] 'process raft request' (duration: 160.723558ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T00:54:00.508217Z","caller":"traceutil/trace.go:171","msg":"trace[561243338] transaction","detail":"{read_only:false; response_revision:4872; number_of_response:1; }","duration":"161.361082ms","start":"2026-07-30T00:54:00.346824Z","end":"2026-07-30T00:54:00.508185Z","steps":["trace[561243338] 'process raft request' (duration: 30.975118ms)","trace[561243338] 'compare' (duration: 129.22638ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T00:54:00.508341Z","caller":"traceutil/trace.go:171","msg":"trace[87178124] transaction","detail":"{read_only:false; response_revision:4876; number_of_response:1; }","duration":"154.263594ms","start":"2026-07-30T00:54:00.354056Z","end":"2026-07-30T00:54:00.508319Z","steps":["trace[87178124] 'process raft request' (duration: 153.710292ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T00:54:00.50865Z","caller":"traceutil/trace.go:171","msg":"trace[922742096] transaction","detail":"{read_only:false; response_revision:4874; number_of_response:1; }","duration":"161.71992ms","start":"2026-07-30T00:54:00.346915Z","end":"2026-07-30T00:54:00.508634Z","steps":["trace[922742096] 'process raft request' (duration: 160.597066ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T00:54:00.508679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.418433ms","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-07-30T00:54:00.508735Z","caller":"traceutil/trace.go:171","msg":"trace[1724521567] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4876; }","duration":"161.498364ms","start":"2026-07-30T00:54:00.347216Z","end":"2026-07-30T00:54:00.508714Z","steps":["trace[1724521567] 'agreement among raft nodes before linearized reading' (duration: 161.31668ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T00:54:00.508871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.31593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-30T00:54:00.509008Z","caller":"traceutil/trace.go:171","msg":"trace[2021348546] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4876; }","duration":"148.507933ms","start":"2026-07-30T00:54:00.360443Z","end":"2026-07-30T00:54:00.508951Z","steps":["trace[2021348546] 'agreement among raft nodes before linearized reading' (duration: 148.244658ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T00:55:28.072012Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2873} {"level":"info","ts":"2026-07-30T00:55:28.185451Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2873,"took":"111.87059ms","hash":3499727440,"current-db-size-bytes":53833728,"current-db-size":"54 MB","current-db-size-in-use-bytes":29175808,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-07-30T00:55:28.185533Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3499727440,"revision":2873,"compact-revision":-1} {"level":"info","ts":"2026-07-30T00:58:06.879093Z","caller":"traceutil/trace.go:171","msg":"trace[1596604291] transaction","detail":"{read_only:false; response_revision:6656; number_of_response:1; }","duration":"144.871332ms","start":"2026-07-30T00:58:06.734198Z","end":"2026-07-30T00:58:06.879069Z","steps":["trace[1596604291] 'process raft request' (duration: 144.715219ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:00:28.079493Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5547} {"level":"info","ts":"2026-07-30T01:00:28.165634Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5547,"took":"85.16887ms","hash":408739863,"current-db-size-bytes":53833728,"current-db-size":"54 MB","current-db-size-in-use-bytes":18104320,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-07-30T01:00:28.165685Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":408739863,"revision":5547,"compact-revision":2873} {"level":"info","ts":"2026-07-30T01:00:35.411052Z","caller":"traceutil/trace.go:171","msg":"trace[57916555] linearizableReadLoop","detail":"{readStateIndex:7706; appliedIndex:7705; }","duration":"235.436687ms","start":"2026-07-30T01:00:35.175589Z","end":"2026-07-30T01:00:35.411025Z","steps":["trace[57916555] 'read index received' (duration: 235.279292ms)","trace[57916555] 'applied index is now lower than readState.Index' (duration: 156.265µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T01:00:35.411147Z","caller":"traceutil/trace.go:171","msg":"trace[766138107] transaction","detail":"{read_only:false; response_revision:7432; number_of_response:1; }","duration":"281.267438ms","start":"2026-07-30T01:00:35.129817Z","end":"2026-07-30T01:00:35.411084Z","steps":["trace[766138107] 'process raft request' (duration: 281.01622ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:00:35.411366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.740067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/monitoring/\" range_end:\"/registry/daemonsets/monitoring0\" limit:500 ","response":"range_response_count:1 size:8252"} {"level":"info","ts":"2026-07-30T01:00:35.411447Z","caller":"traceutil/trace.go:171","msg":"trace[108667825] range","detail":"{range_begin:/registry/daemonsets/monitoring/; range_end:/registry/daemonsets/monitoring0; response_count:1; response_revision:7432; }","duration":"235.860752ms","start":"2026-07-30T01:00:35.175565Z","end":"2026-07-30T01:00:35.411425Z","steps":["trace[108667825] 'agreement among raft nodes before linearized reading' (duration: 235.593043ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:00:35.414547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.946795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/ingress-nginx-controller-7tzvw\" ","response":"range_response_count:1 size:7124"} {"level":"info","ts":"2026-07-30T01:00:35.414621Z","caller":"traceutil/trace.go:171","msg":"trace[634682032] range","detail":"{range_begin:/registry/pods/ingress-nginx/ingress-nginx-controller-7tzvw; range_end:; response_count:1; response_revision:7435; }","duration":"210.058659ms","start":"2026-07-30T01:00:35.204545Z","end":"2026-07-30T01:00:35.414603Z","steps":["trace[634682032] 'agreement among raft nodes before linearized reading' (duration: 209.864952ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:00:35.414675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.385202ms","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-07-30T01:00:35.414753Z","caller":"traceutil/trace.go:171","msg":"trace[657886250] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7435; }","duration":"161.507436ms","start":"2026-07-30T01:00:35.253236Z","end":"2026-07-30T01:00:35.414743Z","steps":["trace[657886250] 'agreement among raft nodes before linearized reading' (duration: 161.32362ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:00:35.41481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.824648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/ingress-nginx/ingress-nginx-defaultbackend\" ","response":"range_response_count:1 size:1180"} {"level":"info","ts":"2026-07-30T01:00:35.414923Z","caller":"traceutil/trace.go:171","msg":"trace[1343450975] range","detail":"{range_begin:/registry/services/endpoints/ingress-nginx/ingress-nginx-defaultbackend; range_end:; response_count:1; response_revision:7435; }","duration":"208.958863ms","start":"2026-07-30T01:00:35.205938Z","end":"2026-07-30T01:00:35.414897Z","steps":["trace[1343450975] 'agreement among raft nodes before linearized reading' (duration: 208.643962ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:00:37.872953Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276104041403130717,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T01:00:38.09109Z","caller":"traceutil/trace.go:171","msg":"trace[2078688181] transaction","detail":"{read_only:false; response_revision:7441; number_of_response:1; }","duration":"727.596363ms","start":"2026-07-30T01:00:37.363464Z","end":"2026-07-30T01:00:38.091061Z","steps":["trace[2078688181] 'process raft request' (duration: 727.424297ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:00:38.091272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T01:00:37.363445Z","time spent":"727.735458ms","remote":"127.0.0.1:50658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T01:00:38.092488Z","caller":"traceutil/trace.go:171","msg":"trace[2034978173] linearizableReadLoop","detail":"{readStateIndex:7719; appliedIndex:7715; }","duration":"719.914088ms","start":"2026-07-30T01:00:37.372548Z","end":"2026-07-30T01:00:38.092462Z","steps":["trace[2034978173] 'read index received' (duration: 718.339885ms)","trace[2034978173] 'applied index is now lower than readState.Index' (duration: 1.572903ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T01:00:38.093126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"681.670525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/cilium-config\" ","response":"range_response_count:1 size:6531"} {"level":"warn","ts":"2026-07-30T01:00:38.093139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.593619ms","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-07-30T01:00:38.093204Z","caller":"traceutil/trace.go:171","msg":"trace[712706204] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7444; }","duration":"668.681672ms","start":"2026-07-30T01:00:37.424509Z","end":"2026-07-30T01:00:38.09319Z","steps":["trace[712706204] 'agreement among raft nodes before linearized reading' (duration: 668.470215ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:00:38.093396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.721685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T01:00:38.093579Z","caller":"traceutil/trace.go:171","msg":"trace[2043667243] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:7444; }","duration":"690.915292ms","start":"2026-07-30T01:00:37.402647Z","end":"2026-07-30T01:00:38.093562Z","steps":["trace[2043667243] 'agreement among raft nodes before linearized reading' (duration: 690.701035ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:00:38.093705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T01:00:37.40264Z","time spent":"691.047316ms","remote":"127.0.0.1:47240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"info","ts":"2026-07-30T01:00:38.092638Z","caller":"traceutil/trace.go:171","msg":"trace[1570287607] transaction","detail":"{read_only:false; response_revision:7443; number_of_response:1; }","duration":"672.887382ms","start":"2026-07-30T01:00:37.419725Z","end":"2026-07-30T01:00:38.092612Z","steps":["trace[1570287607] 'process raft request' (duration: 672.611783ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:00:38.093866Z","caller":"traceutil/trace.go:171","msg":"trace[1960937232] range","detail":"{range_begin:/registry/configmaps/kube-system/cilium-config; range_end:; response_count:1; response_revision:7444; }","duration":"682.424479ms","start":"2026-07-30T01:00:37.411424Z","end":"2026-07-30T01:00:38.093849Z","steps":["trace[1960937232] 'agreement among raft nodes before linearized reading' (duration: 681.57191ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:00:38.093947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T01:00:37.411413Z","time spent":"682.519573ms","remote":"127.0.0.1:50510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":6553,"request content":"key:\"/registry/configmaps/kube-system/cilium-config\" "} {"level":"warn","ts":"2026-07-30T01:00:38.093245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T01:00:37.424498Z","time spent":"668.738944ms","remote":"127.0.0.1:50658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-30T01:00:38.094075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.971814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-07-30T01:00:38.09416Z","caller":"traceutil/trace.go:171","msg":"trace[2119148293] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7444; }","duration":"679.083918ms","start":"2026-07-30T01:00:37.415055Z","end":"2026-07-30T01:00:38.094139Z","steps":["trace[2119148293] 'agreement among raft nodes before linearized reading' (duration: 678.788619ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:00:38.094211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T01:00:37.415049Z","time spent":"679.151521ms","remote":"127.0.0.1:50658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-30T01:00:38.093131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.534998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/\" range_end:\"/registry/serviceaccounts/openstack0\" limit:500 ","response":"range_response_count:21 size:12092"} {"level":"info","ts":"2026-07-30T01:00:38.094253Z","caller":"traceutil/trace.go:171","msg":"trace[263947212] range","detail":"{range_begin:/registry/serviceaccounts/openstack/; range_end:/registry/serviceaccounts/openstack0; response_count:21; response_revision:7444; }","duration":"721.718408ms","start":"2026-07-30T01:00:37.372524Z","end":"2026-07-30T01:00:38.094242Z","steps":["trace[263947212] 'agreement among raft nodes before linearized reading' (duration: 720.294911ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:00:38.094284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T01:00:37.372515Z","time spent":"721.758279ms","remote":"127.0.0.1:50602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":21,"response size":12114,"request content":"key:\"/registry/serviceaccounts/openstack/\" range_end:\"/registry/serviceaccounts/openstack0\" limit:500 "} {"level":"info","ts":"2026-07-30T01:00:38.092736Z","caller":"traceutil/trace.go:171","msg":"trace[1809974414] transaction","detail":"{read_only:false; response_revision:7444; number_of_response:1; }","duration":"671.329759ms","start":"2026-07-30T01:00:37.421396Z","end":"2026-07-30T01:00:38.092726Z","steps":["trace[1809974414] 'process raft request' (duration: 671.023309ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:00:38.09423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T01:00:37.419697Z","time spent":"674.220236ms","remote":"127.0.0.1:50658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T01:00:38.094447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.23839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/\" range_end:\"/registry/services/specs/openstack0\" limit:500 ","response":"range_response_count:14 size:20921"} {"level":"warn","ts":"2026-07-30T01:00:38.094442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T01:00:37.421377Z","time spent":"672.948174ms","remote":"127.0.0.1:50658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T01:00:38.09295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.28128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-node-lease/default\" ","response":"range_response_count:1 size:187"} {"level":"info","ts":"2026-07-30T01:00:38.094605Z","caller":"traceutil/trace.go:171","msg":"trace[1206545687] range","detail":"{range_begin:/registry/services/specs/openstack/; range_end:/registry/services/specs/openstack0; response_count:14; response_revision:7444; }","duration":"669.310172ms","start":"2026-07-30T01:00:37.425175Z","end":"2026-07-30T01:00:38.094485Z","steps":["trace[1206545687] 'agreement among raft nodes before linearized reading' (duration: 668.858037ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:00:38.094644Z","caller":"traceutil/trace.go:171","msg":"trace[901578793] range","detail":"{range_begin:/registry/serviceaccounts/kube-node-lease/default; range_end:; response_count:1; response_revision:7444; }","duration":"692.002988ms","start":"2026-07-30T01:00:37.402627Z","end":"2026-07-30T01:00:38.09463Z","steps":["trace[901578793] 'agreement among raft nodes before linearized reading' (duration: 690.244569ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:00:38.094655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T01:00:37.425169Z","time spent":"669.472148ms","remote":"127.0.0.1:50600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":77,"response count":14,"response size":20943,"request content":"key:\"/registry/services/specs/openstack/\" range_end:\"/registry/services/specs/openstack0\" limit:500 "} {"level":"warn","ts":"2026-07-30T01:00:38.094682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T01:00:37.402613Z","time spent":"692.05598ms","remote":"127.0.0.1:50602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":209,"request content":"key:\"/registry/serviceaccounts/kube-node-lease/default\" "} {"level":"info","ts":"2026-07-30T01:00:38.092488Z","caller":"traceutil/trace.go:171","msg":"trace[375240300] transaction","detail":"{read_only:false; response_revision:7442; number_of_response:1; }","duration":"672.811309ms","start":"2026-07-30T01:00:37.419652Z","end":"2026-07-30T01:00:38.092464Z","steps":["trace[375240300] 'process raft request' (duration: 672.55048ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:00:38.095239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T01:00:37.419632Z","time spent":"675.55274ms","remote":"127.0.0.1:50658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"}