{"level":"warn","ts":"2026-04-11T17:40:38.431819Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-11T17:40:38.431905Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.194: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.194:2380","--initial-cluster=instance=https://199.19.213.194:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.194:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.194: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-04-11T17:40:38.431967Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-11T17:40:38.431979Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.194:2380"]} {"level":"info","ts":"2026-04-11T17:40:38.431996Z","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-04-11T17:40:38.432627Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.194:2379"]} {"level":"info","ts":"2026-04-11T17:40:38.4327Z","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.194:2380"],"listen-peer-urls":["https://199.19.213.194:2380"],"advertise-client-urls":["https://199.19.213.194:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.194:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.194: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-04-11T17:40:38.436233Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.2966ms"} {"level":"info","ts":"2026-04-11T17:40:38.441811Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1444e0f5dfca45ef","cluster-id":"46f0df2abd5471c9"} {"level":"info","ts":"2026-04-11T17:40:38.441902Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1444e0f5dfca45ef switched to configuration voters=()"} {"level":"info","ts":"2026-04-11T17:40:38.441927Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1444e0f5dfca45ef became follower at term 0"} {"level":"info","ts":"2026-04-11T17:40:38.441953Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1444e0f5dfca45ef [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-11T17:40:38.441975Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1444e0f5dfca45ef became follower at term 1"} {"level":"info","ts":"2026-04-11T17:40:38.442023Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1444e0f5dfca45ef switched to configuration voters=(1460539525801068015)"} {"level":"warn","ts":"2026-04-11T17:40:38.446875Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-11T17:40:38.448126Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-11T17:40:38.449241Z","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-04-11T17:40:38.4513Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1444e0f5dfca45ef","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-11T17:40:38.451503Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1444e0f5dfca45ef","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-11T17:40:38.451667Z","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-04-11T17:40:38.451777Z","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-04-11T17:40:38.451855Z","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-04-11T17:40:38.452328Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1444e0f5dfca45ef switched to configuration voters=(1460539525801068015)"} {"level":"info","ts":"2026-04-11T17:40:38.455445Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"46f0df2abd5471c9","local-member-id":"1444e0f5dfca45ef","added-peer-id":"1444e0f5dfca45ef","added-peer-peer-urls":["https://199.19.213.194:2380"]} {"level":"info","ts":"2026-04-11T17:40:38.464122Z","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-04-11T17:40:38.464288Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.194:2380"} {"level":"info","ts":"2026-04-11T17:40:38.464368Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-11T17:40:38.464425Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.194:2380"} {"level":"info","ts":"2026-04-11T17:40:38.464328Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1444e0f5dfca45ef","initial-advertise-peer-urls":["https://199.19.213.194:2380"],"listen-peer-urls":["https://199.19.213.194:2380"],"advertise-client-urls":["https://199.19.213.194:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.194:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-11T17:40:39.442666Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1444e0f5dfca45ef is starting a new election at term 1"} {"level":"info","ts":"2026-04-11T17:40:39.44274Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1444e0f5dfca45ef became pre-candidate at term 1"} {"level":"info","ts":"2026-04-11T17:40:39.44279Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1444e0f5dfca45ef received MsgPreVoteResp from 1444e0f5dfca45ef at term 1"} {"level":"info","ts":"2026-04-11T17:40:39.442811Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1444e0f5dfca45ef became candidate at term 2"} {"level":"info","ts":"2026-04-11T17:40:39.442822Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1444e0f5dfca45ef received MsgVoteResp from 1444e0f5dfca45ef at term 2"} {"level":"info","ts":"2026-04-11T17:40:39.442994Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1444e0f5dfca45ef became leader at term 2"} {"level":"info","ts":"2026-04-11T17:40:39.443022Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1444e0f5dfca45ef elected leader 1444e0f5dfca45ef at term 2"} {"level":"info","ts":"2026-04-11T17:40:39.554767Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1444e0f5dfca45ef","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.194:2379]}","request-path":"/0/members/1444e0f5dfca45ef/attributes","cluster-id":"46f0df2abd5471c9","publish-timeout":"7s"} {"level":"info","ts":"2026-04-11T17:40:39.554808Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-11T17:40:39.554788Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-11T17:40:39.55517Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T17:40:39.555688Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-11T17:40:39.555765Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-11T17:40:39.556505Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"46f0df2abd5471c9","local-member-id":"1444e0f5dfca45ef","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T17:40:39.556649Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T17:40:39.556715Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T17:40:39.558137Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-11T17:40:39.55916Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.194:2379"} {"level":"warn","ts":"2026-04-11T17:40:42.052284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.743378ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-11T17:40:42.052413Z","caller":"traceutil/trace.go:171","msg":"trace[685400526] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"274.840574ms","start":"2026-04-11T17:40:41.777557Z","end":"2026-04-11T17:40:42.052397Z","steps":["trace[685400526] 'read index received' (duration: 144.543622ms)","trace[685400526] 'applied index is now lower than readState.Index' (duration: 130.295732ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:40:42.052493Z","caller":"traceutil/trace.go:171","msg":"trace[1250974958] transaction","detail":"{read_only:false; response_revision:71; number_of_response:1; }","duration":"276.107225ms","start":"2026-04-11T17:40:41.776327Z","end":"2026-04-11T17:40:42.052434Z","steps":["trace[1250974958] 'process raft request' (duration: 145.781202ms)","trace[1250974958] 'compare' (duration: 129.601767ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:40:42.052545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.974485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-11T17:40:42.052585Z","caller":"traceutil/trace.go:171","msg":"trace[962975877] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:71; }","duration":"275.061527ms","start":"2026-04-11T17:40:41.777515Z","end":"2026-04-11T17:40:42.052576Z","steps":["trace[962975877] 'agreement among raft nodes before linearized reading' (duration: 274.958626ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:40:42.083532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.747873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-11T17:40:42.083552Z","caller":"traceutil/trace.go:171","msg":"trace[1201727660] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"305.374803ms","start":"2026-04-11T17:40:41.77813Z","end":"2026-04-11T17:40:42.083505Z","steps":["trace[1201727660] 'process raft request' (duration: 305.00485ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:40:42.083633Z","caller":"traceutil/trace.go:171","msg":"trace[277846020] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:72; }","duration":"201.856084ms","start":"2026-04-11T17:40:41.881749Z","end":"2026-04-11T17:40:42.083605Z","steps":["trace[277846020] 'agreement among raft nodes before linearized reading' (duration: 201.633902ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:40:42.083722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:40:41.778115Z","time spent":"305.523415ms","remote":"127.0.0.1:48836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-11T17:40:42.084002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.492395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-11T17:40:42.084122Z","caller":"traceutil/trace.go:171","msg":"trace[743063747] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:72; }","duration":"284.702727ms","start":"2026-04-11T17:40:41.799397Z","end":"2026-04-11T17:40:42.0841Z","steps":["trace[743063747] 'agreement among raft nodes before linearized reading' (duration: 284.524355ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:40:42.22671Z","caller":"traceutil/trace.go:171","msg":"trace[1599711156] linearizableReadLoop","detail":"{readStateIndex:78; appliedIndex:77; }","duration":"143.389495ms","start":"2026-04-11T17:40:42.083298Z","end":"2026-04-11T17:40:42.226688Z","steps":["trace[1599711156] 'read index received' (duration: 117.901629ms)","trace[1599711156] 'applied index is now lower than readState.Index' (duration: 25.486736ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:40:42.226765Z","caller":"traceutil/trace.go:171","msg":"trace[785890379] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"170.325392ms","start":"2026-04-11T17:40:42.056399Z","end":"2026-04-11T17:40:42.226725Z","steps":["trace[785890379] 'process raft request' (duration: 144.734775ms)","trace[785890379] 'compare' (duration: 25.450616ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:40:42.226956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.670052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-cluster-critical\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-11T17:40:42.226948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.322642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-11T17:40:42.227013Z","caller":"traceutil/trace.go:171","msg":"trace[859561118] range","detail":"{range_begin:/registry/priorityclasses/system-cluster-critical; range_end:; response_count:0; response_revision:73; }","duration":"140.793023ms","start":"2026-04-11T17:40:42.086205Z","end":"2026-04-11T17:40:42.226998Z","steps":["trace[859561118] 'agreement among raft nodes before linearized reading' (duration: 140.617761ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:40:42.227058Z","caller":"traceutil/trace.go:171","msg":"trace[115928105] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:73; }","duration":"171.479213ms","start":"2026-04-11T17:40:42.05556Z","end":"2026-04-11T17:40:42.227039Z","steps":["trace[115928105] 'agreement among raft nodes before linearized reading' (duration: 171.259731ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:41:08.532079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.255075ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-11T17:41:08.532197Z","caller":"traceutil/trace.go:171","msg":"trace[136064379] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"176.113483ms","start":"2026-04-11T17:41:08.356065Z","end":"2026-04-11T17:41:08.532178Z","steps":["trace[136064379] 'process raft request' (duration: 54.661546ms)","trace[136064379] 'compare' (duration: 121.097765ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:41:08.558471Z","caller":"traceutil/trace.go:171","msg":"trace[509321276] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"195.249392ms","start":"2026-04-11T17:41:08.363133Z","end":"2026-04-11T17:41:08.558382Z","steps":["trace[509321276] 'process raft request' (duration: 195.140631ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:41:08.55845Z","caller":"traceutil/trace.go:171","msg":"trace[245128610] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"195.229852ms","start":"2026-04-11T17:41:08.363197Z","end":"2026-04-11T17:41:08.558426Z","steps":["trace[245128610] 'process raft request' (duration: 195.131862ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:41:08.55873Z","caller":"traceutil/trace.go:171","msg":"trace[1378868728] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"195.407493ms","start":"2026-04-11T17:41:08.363238Z","end":"2026-04-11T17:41:08.558645Z","steps":["trace[1378868728] 'process raft request' (duration: 195.112572ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:41:08.558791Z","caller":"traceutil/trace.go:171","msg":"trace[782426763] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"186.228005ms","start":"2026-04-11T17:41:08.372544Z","end":"2026-04-11T17:41:08.558772Z","steps":["trace[782426763] 'process raft request' (duration: 185.917973ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:41:08.558769Z","caller":"traceutil/trace.go:171","msg":"trace[500763836] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"186.90357ms","start":"2026-04-11T17:41:08.371791Z","end":"2026-04-11T17:41:08.558694Z","steps":["trace[500763836] 'process raft request' (duration: 186.592228ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:41:08.559028Z","caller":"traceutil/trace.go:171","msg":"trace[1665596855] transaction","detail":"{read_only:false; number_of_response:1; response_revision:522; }","duration":"180.922882ms","start":"2026-04-11T17:41:08.378093Z","end":"2026-04-11T17:41:08.559015Z","steps":["trace[1665596855] 'process raft request' (duration: 180.400429ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:41:08.559043Z","caller":"traceutil/trace.go:171","msg":"trace[1901291190] transaction","detail":"{read_only:false; number_of_response:1; response_revision:521; }","duration":"187.183571ms","start":"2026-04-11T17:41:08.371843Z","end":"2026-04-11T17:41:08.559026Z","steps":["trace[1901291190] 'process raft request' (duration: 186.590427ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:41:08.559583Z","caller":"traceutil/trace.go:171","msg":"trace[1826754526] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"181.364876ms","start":"2026-04-11T17:41:08.378205Z","end":"2026-04-11T17:41:08.559569Z","steps":["trace[1826754526] 'process raft request' (duration: 180.332569ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:41:08.559623Z","caller":"traceutil/trace.go:171","msg":"trace[1458868733] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"170.849929ms","start":"2026-04-11T17:41:08.388761Z","end":"2026-04-11T17:41:08.559611Z","steps":["trace[1458868733] 'process raft request' (duration: 170.739869ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:41:08.559693Z","caller":"traceutil/trace.go:171","msg":"trace[575704357] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"164.362979ms","start":"2026-04-11T17:41:08.395314Z","end":"2026-04-11T17:41:08.559676Z","steps":["trace[575704357] 'process raft request' (duration: 164.237828ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:41:08.559711Z","caller":"traceutil/trace.go:171","msg":"trace[789772412] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"164.284108ms","start":"2026-04-11T17:41:08.395417Z","end":"2026-04-11T17:41:08.559701Z","steps":["trace[789772412] 'process raft request' (duration: 164.178647ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:41:08.559736Z","caller":"traceutil/trace.go:171","msg":"trace[1821311003] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"159.349186ms","start":"2026-04-11T17:41:08.400376Z","end":"2026-04-11T17:41:08.559725Z","steps":["trace[1821311003] 'process raft request' (duration: 159.251035ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:41:08.565193Z","caller":"traceutil/trace.go:171","msg":"trace[2134819305] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"153.766001ms","start":"2026-04-11T17:41:08.411405Z","end":"2026-04-11T17:41:08.565171Z","steps":["trace[2134819305] 'process raft request' (duration: 153.203557ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:41:14.982723Z","caller":"traceutil/trace.go:171","msg":"trace[1522102971] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:634; }","duration":"140.93214ms","start":"2026-04-11T17:41:14.841757Z","end":"2026-04-11T17:41:14.982689Z","steps":["trace[1522102971] 'read index received' (duration: 139.890363ms)","trace[1522102971] 'applied index is now lower than readState.Index' (duration: 1.040767ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:41:14.982769Z","caller":"traceutil/trace.go:171","msg":"trace[45716757] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"167.74558ms","start":"2026-04-11T17:41:14.814993Z","end":"2026-04-11T17:41:14.982738Z","steps":["trace[45716757] 'process raft request' (duration: 166.898524ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:41:14.982953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.180551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-2ml9x\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-11T17:41:14.983006Z","caller":"traceutil/trace.go:171","msg":"trace[1892876427] range","detail":"{range_begin:/registry/pods/kube-system/cilium-2ml9x; range_end:; response_count:1; response_revision:616; }","duration":"141.302742ms","start":"2026-04-11T17:41:14.841692Z","end":"2026-04-11T17:41:14.982994Z","steps":["trace[1892876427] 'agreement among raft nodes before linearized reading' (duration: 141.10952ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:41:15.469348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.671017ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-11T17:41:15.469562Z","caller":"traceutil/trace.go:171","msg":"trace[660063250] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"470.893487ms","start":"2026-04-11T17:41:14.998629Z","end":"2026-04-11T17:41:15.469523Z","steps":["trace[660063250] 'process raft request' (duration: 308.828237ms)","trace[660063250] 'compare' (duration: 161.548337ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:41:15.469616Z","caller":"traceutil/trace.go:171","msg":"trace[1227563298] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"439.379927ms","start":"2026-04-11T17:41:15.030205Z","end":"2026-04-11T17:41:15.469584Z","steps":["trace[1227563298] 'process raft request' (duration: 439.238356ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:41:15.469669Z","caller":"traceutil/trace.go:171","msg":"trace[608636942] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"434.409317ms","start":"2026-04-11T17:41:15.035237Z","end":"2026-04-11T17:41:15.469646Z","steps":["trace[608636942] 'process raft request' (duration: 434.267917ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:41:15.469728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:14.998584Z","time spent":"471.061677ms","remote":"127.0.0.1:48660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T17:41:15.469771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:15.03523Z","time spent":"434.494088ms","remote":"127.0.0.1:48730","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-04-11T17:41:15.469772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:15.030186Z","time spent":"439.504258ms","remote":"127.0.0.1:48750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T17:41:38.184839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.426675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-x5zk9\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-11T17:41:38.184938Z","caller":"traceutil/trace.go:171","msg":"trace[705995717] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-x5zk9; range_end:; response_count:1; response_revision:798; }","duration":"233.559945ms","start":"2026-04-11T17:41:37.951355Z","end":"2026-04-11T17:41:38.184915Z","steps":["trace[705995717] 'range keys from in-memory index tree' (duration: 233.279703ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:41:38.184993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.153268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-11T17:41:38.184942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.705646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-11T17:41:38.185137Z","caller":"traceutil/trace.go:171","msg":"trace[959433809] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:798; }","duration":"281.383538ms","start":"2026-04-11T17:41:37.90373Z","end":"2026-04-11T17:41:38.185113Z","steps":["trace[959433809] 'range keys from in-memory index tree' (duration: 280.955847ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:41:38.185166Z","caller":"traceutil/trace.go:171","msg":"trace[747310967] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:798; }","duration":"265.983518ms","start":"2026-04-11T17:41:37.919158Z","end":"2026-04-11T17:41:38.185141Z","steps":["trace[747310967] 'range keys from in-memory index tree' (duration: 265.532636ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:42:31.070136Z","caller":"traceutil/trace.go:171","msg":"trace[243942065] transaction","detail":"{read_only:false; response_revision:1499; number_of_response:1; }","duration":"121.640161ms","start":"2026-04-11T17:42:30.94847Z","end":"2026-04-11T17:42:31.07011Z","steps":["trace[243942065] 'process raft request' (duration: 103.326793ms)","trace[243942065] 'compare' (duration: 17.612895ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:42:31.070731Z","caller":"traceutil/trace.go:171","msg":"trace[1795793354] linearizableReadLoop","detail":"{readStateIndex:1541; appliedIndex:1539; }","duration":"119.258793ms","start":"2026-04-11T17:42:30.95146Z","end":"2026-04-11T17:42:31.070719Z","steps":["trace[1795793354] 'read index received' (duration: 3.555332ms)","trace[1795793354] 'applied index is now lower than readState.Index' (duration: 115.702621ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:42:31.070886Z","caller":"traceutil/trace.go:171","msg":"trace[1672055768] transaction","detail":"{read_only:false; response_revision:1501; number_of_response:1; }","duration":"118.211183ms","start":"2026-04-11T17:42:30.95263Z","end":"2026-04-11T17:42:31.070841Z","steps":["trace[1672055768] 'process raft request' (duration: 118.037539ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:42:31.071092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.5956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/exchanges.rabbitmq.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:42:31.070879Z","caller":"traceutil/trace.go:171","msg":"trace[1674207246] transaction","detail":"{read_only:false; response_revision:1500; number_of_response:1; }","duration":"119.308115ms","start":"2026-04-11T17:42:30.951543Z","end":"2026-04-11T17:42:31.070851Z","steps":["trace[1674207246] 'process raft request' (duration: 118.295444ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:42:31.071169Z","caller":"traceutil/trace.go:171","msg":"trace[1436006282] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/exchanges.rabbitmq.com; range_end:; response_count:0; response_revision:1501; }","duration":"119.719493ms","start":"2026-04-11T17:42:30.95143Z","end":"2026-04-11T17:42:31.071149Z","steps":["trace[1436006282] 'agreement among raft nodes before linearized reading' (duration: 119.5668ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:42:31.087948Z","caller":"traceutil/trace.go:171","msg":"trace[1323541844] transaction","detail":"{read_only:false; response_revision:1502; number_of_response:1; }","duration":"129.960109ms","start":"2026-04-11T17:42:30.957959Z","end":"2026-04-11T17:42:31.087919Z","steps":["trace[1323541844] 'process raft request' (duration: 129.745555ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:42:31.088032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.285587ms","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-04-11T17:42:31.088102Z","caller":"traceutil/trace.go:171","msg":"trace[458720748] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1502; }","duration":"136.377478ms","start":"2026-04-11T17:42:30.951709Z","end":"2026-04-11T17:42:31.088087Z","steps":["trace[458720748] 'agreement among raft nodes before linearized reading' (duration: 136.148794ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:42:31.088621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.894067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1986"} {"level":"info","ts":"2026-04-11T17:42:31.088667Z","caller":"traceutil/trace.go:171","msg":"trace[587490950] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:1502; }","duration":"130.974388ms","start":"2026-04-11T17:42:30.957681Z","end":"2026-04-11T17:42:31.088656Z","steps":["trace[587490950] 'agreement among raft nodes before linearized reading' (duration: 130.161882ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:42:31.362996Z","caller":"traceutil/trace.go:171","msg":"trace[814127476] linearizableReadLoop","detail":"{readStateIndex:1553; appliedIndex:1552; }","duration":"125.927657ms","start":"2026-04-11T17:42:31.237038Z","end":"2026-04-11T17:42:31.362965Z","steps":["trace[814127476] 'read index received' (duration: 66.819456ms)","trace[814127476] 'applied index is now lower than readState.Index' (duration: 59.106691ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:42:31.36317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.10122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/operatorpolicies.rabbitmq.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:42:31.363229Z","caller":"traceutil/trace.go:171","msg":"trace[462195467] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/operatorpolicies.rabbitmq.com; range_end:; response_count:0; response_revision:1511; }","duration":"126.206433ms","start":"2026-04-11T17:42:31.237004Z","end":"2026-04-11T17:42:31.363211Z","steps":["trace[462195467] 'agreement among raft nodes before linearized reading' (duration: 126.08162ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:42:31.363391Z","caller":"traceutil/trace.go:171","msg":"trace[1918050588] transaction","detail":"{read_only:false; response_revision:1511; number_of_response:1; }","duration":"127.605202ms","start":"2026-04-11T17:42:31.235413Z","end":"2026-04-11T17:42:31.363018Z","steps":["trace[1918050588] 'process raft request' (duration: 68.526832ms)","trace[1918050588] 'compare' (duration: 58.793684ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:42:51.711314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.005186ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-11T17:42:51.711685Z","caller":"traceutil/trace.go:171","msg":"trace[164090091] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"237.323103ms","start":"2026-04-11T17:42:51.474329Z","end":"2026-04-11T17:42:51.711652Z","steps":["trace[164090091] 'process raft request' (duration: 18.90393ms)","trace[164090091] 'compare' (duration: 217.902175ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:43:06.910339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.583939ms","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":"info","ts":"2026-04-11T17:43:06.910422Z","caller":"traceutil/trace.go:171","msg":"trace[457470392] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1971; }","duration":"106.71424ms","start":"2026-04-11T17:43:06.803688Z","end":"2026-04-11T17:43:06.910403Z","steps":["trace[457470392] 'range keys from in-memory index tree' (duration: 106.410406ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:43:25.546853Z","caller":"traceutil/trace.go:171","msg":"trace[1490534277] transaction","detail":"{read_only:false; response_revision:2090; number_of_response:1; }","duration":"130.789794ms","start":"2026-04-11T17:43:25.416022Z","end":"2026-04-11T17:43:25.546811Z","steps":["trace[1490534277] 'process raft request' (duration: 130.611162ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:43:25.666227Z","caller":"traceutil/trace.go:171","msg":"trace[888891037] transaction","detail":"{read_only:false; response_revision:2091; number_of_response:1; }","duration":"115.915578ms","start":"2026-04-11T17:43:25.550284Z","end":"2026-04-11T17:43:25.666199Z","steps":["trace[888891037] 'process raft request' (duration: 101.397646ms)","trace[888891037] 'compare' (duration: 14.277399ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:43:25.727685Z","caller":"traceutil/trace.go:171","msg":"trace[488936802] transaction","detail":"{read_only:false; response_revision:2092; number_of_response:1; }","duration":"174.947926ms","start":"2026-04-11T17:43:25.552709Z","end":"2026-04-11T17:43:25.727657Z","steps":["trace[488936802] 'process raft request' (duration: 174.734882ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:43:25.728282Z","caller":"traceutil/trace.go:171","msg":"trace[1429323797] transaction","detail":"{read_only:false; response_revision:2093; number_of_response:1; }","duration":"174.658833ms","start":"2026-04-11T17:43:25.553601Z","end":"2026-04-11T17:43:25.72826Z","steps":["trace[1429323797] 'process raft request' (duration: 173.981963ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:43:26.600232Z","caller":"traceutil/trace.go:171","msg":"trace[669710279] transaction","detail":"{read_only:false; response_revision:2095; number_of_response:1; }","duration":"236.768761ms","start":"2026-04-11T17:43:26.363434Z","end":"2026-04-11T17:43:26.600203Z","steps":["trace[669710279] 'process raft request' (duration: 236.577079ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:44:21.370448Z","caller":"traceutil/trace.go:171","msg":"trace[1101618093] linearizableReadLoop","detail":"{readStateIndex:2523; appliedIndex:2521; }","duration":"120.785843ms","start":"2026-04-11T17:44:21.249642Z","end":"2026-04-11T17:44:21.370427Z","steps":["trace[1101618093] 'read index received' (duration: 16.989392ms)","trace[1101618093] 'applied index is now lower than readState.Index' (duration: 103.795451ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:44:21.370665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.992986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-11T17:44:21.370696Z","caller":"traceutil/trace.go:171","msg":"trace[1133657823] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2435; }","duration":"121.088128ms","start":"2026-04-11T17:44:21.2496Z","end":"2026-04-11T17:44:21.370688Z","steps":["trace[1133657823] 'agreement among raft nodes before linearized reading' (duration: 120.936125ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:44:21.370564Z","caller":"traceutil/trace.go:171","msg":"trace[834381510] transaction","detail":"{read_only:false; response_revision:2435; number_of_response:1; }","duration":"154.249273ms","start":"2026-04-11T17:44:21.216228Z","end":"2026-04-11T17:44:21.370477Z","steps":["trace[834381510] 'process raft request' (duration: 83.812959ms)","trace[834381510] 'compare' (duration: 70.284292ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:44:58.141249Z","caller":"traceutil/trace.go:171","msg":"trace[281035052] linearizableReadLoop","detail":"{readStateIndex:2793; appliedIndex:2792; }","duration":"168.120194ms","start":"2026-04-11T17:44:57.973091Z","end":"2026-04-11T17:44:58.141211Z","steps":["trace[281035052] 'read index received' (duration: 167.911842ms)","trace[281035052] 'applied index is now lower than readState.Index' (duration: 206.762µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:44:58.141371Z","caller":"traceutil/trace.go:171","msg":"trace[1323951615] transaction","detail":"{read_only:false; response_revision:2697; number_of_response:1; }","duration":"203.41708ms","start":"2026-04-11T17:44:57.937937Z","end":"2026-04-11T17:44:58.141354Z","steps":["trace[1323951615] 'process raft request' (duration: 203.136358ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:44:58.14155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.448928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-11T17:44:58.141605Z","caller":"traceutil/trace.go:171","msg":"trace[416427118] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2697; }","duration":"168.553748ms","start":"2026-04-11T17:44:57.973034Z","end":"2026-04-11T17:44:58.141588Z","steps":["trace[416427118] 'agreement among raft nodes before linearized reading' (duration: 168.356457ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:45:03.678604Z","caller":"traceutil/trace.go:171","msg":"trace[265692937] linearizableReadLoop","detail":"{readStateIndex:2823; appliedIndex:2822; }","duration":"125.593242ms","start":"2026-04-11T17:45:03.552995Z","end":"2026-04-11T17:45:03.678589Z","steps":["trace[265692937] 'read index received' (duration: 125.437101ms)","trace[265692937] 'applied index is now lower than readState.Index' (duration: 153.501µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:45:03.678692Z","caller":"traceutil/trace.go:171","msg":"trace[1472008027] transaction","detail":"{read_only:false; response_revision:2725; number_of_response:1; }","duration":"195.585218ms","start":"2026-04-11T17:45:03.48308Z","end":"2026-04-11T17:45:03.678665Z","steps":["trace[1472008027] 'process raft request' (duration: 195.395556ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:45:03.678754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.356929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-11T17:45:03.678882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.752764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-11T17:45:03.678915Z","caller":"traceutil/trace.go:171","msg":"trace[2112810366] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2725; }","duration":"125.949355ms","start":"2026-04-11T17:45:03.552956Z","end":"2026-04-11T17:45:03.678905Z","steps":["trace[2112810366] 'agreement among raft nodes before linearized reading' (duration: 125.722403ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:45:03.678917Z","caller":"traceutil/trace.go:171","msg":"trace[1243995475] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2725; }","duration":"125.557261ms","start":"2026-04-11T17:45:03.553341Z","end":"2026-04-11T17:45:03.678898Z","steps":["trace[1243995475] 'agreement among raft nodes before linearized reading' (duration: 125.348229ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:45:04.033294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.39457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:45:04.033375Z","caller":"traceutil/trace.go:171","msg":"trace[762886199] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2728; }","duration":"212.556642ms","start":"2026-04-11T17:45:03.820803Z","end":"2026-04-11T17:45:04.03336Z","steps":["trace[762886199] 'range keys from in-memory index tree' (duration: 212.3189ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:48:08.124661Z","caller":"traceutil/trace.go:171","msg":"trace[497305568] transaction","detail":"{read_only:false; response_revision:3843; number_of_response:1; }","duration":"119.61984ms","start":"2026-04-11T17:48:08.005014Z","end":"2026-04-11T17:48:08.124634Z","steps":["trace[497305568] 'process raft request' (duration: 119.348046ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:48:56.367349Z","caller":"traceutil/trace.go:171","msg":"trace[1945497526] transaction","detail":"{read_only:false; response_revision:4526; number_of_response:1; }","duration":"127.370727ms","start":"2026-04-11T17:48:56.239949Z","end":"2026-04-11T17:48:56.36732Z","steps":["trace[1945497526] 'process raft request' (duration: 93.917634ms)","trace[1945497526] 'compare' (duration: 33.291041ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:48:56.367648Z","caller":"traceutil/trace.go:171","msg":"trace[2054562623] transaction","detail":"{read_only:false; response_revision:4527; number_of_response:1; }","duration":"117.398504ms","start":"2026-04-11T17:48:56.250244Z","end":"2026-04-11T17:48:56.367642Z","steps":["trace[2054562623] 'process raft request' (duration: 117.076989ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:48:59.212915Z","caller":"traceutil/trace.go:171","msg":"trace[2115505415] transaction","detail":"{read_only:false; response_revision:4621; number_of_response:1; }","duration":"165.40714ms","start":"2026-04-11T17:48:59.047471Z","end":"2026-04-11T17:48:59.212879Z","steps":["trace[2115505415] 'process raft request' (duration: 165.216777ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:48:59.221301Z","caller":"traceutil/trace.go:171","msg":"trace[188130459] transaction","detail":"{read_only:false; response_revision:4622; number_of_response:1; }","duration":"172.883667ms","start":"2026-04-11T17:48:59.048396Z","end":"2026-04-11T17:48:59.221279Z","steps":["trace[188130459] 'process raft request' (duration: 172.748995ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:48:59.221492Z","caller":"traceutil/trace.go:171","msg":"trace[826428549] transaction","detail":"{read_only:false; response_revision:4623; number_of_response:1; }","duration":"172.800186ms","start":"2026-04-11T17:48:59.048683Z","end":"2026-04-11T17:48:59.221483Z","steps":["trace[826428549] 'process raft request' (duration: 172.550472ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:48:59.221766Z","caller":"traceutil/trace.go:171","msg":"trace[366383030] linearizableReadLoop","detail":"{readStateIndex:4796; appliedIndex:4793; }","duration":"152.23678ms","start":"2026-04-11T17:48:59.069507Z","end":"2026-04-11T17:48:59.221744Z","steps":["trace[366383030] 'read index received' (duration: 143.224501ms)","trace[366383030] 'applied index is now lower than readState.Index' (duration: 9.011079ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:48:59.221967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.453354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-04-11T17:48:59.22204Z","caller":"traceutil/trace.go:171","msg":"trace[1905093410] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4624; }","duration":"152.569435ms","start":"2026-04-11T17:48:59.06945Z","end":"2026-04-11T17:48:59.22202Z","steps":["trace[1905093410] 'agreement among raft nodes before linearized reading' (duration: 152.391663ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:01.354881Z","caller":"traceutil/trace.go:171","msg":"trace[1372078160] linearizableReadLoop","detail":"{readStateIndex:4817; appliedIndex:4816; }","duration":"128.610644ms","start":"2026-04-11T17:49:01.226252Z","end":"2026-04-11T17:49:01.354863Z","steps":["trace[1372078160] 'read index received' (duration: 128.516343ms)","trace[1372078160] 'applied index is now lower than readState.Index' (duration: 93.321µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:49:01.355024Z","caller":"traceutil/trace.go:171","msg":"trace[1604233718] transaction","detail":"{read_only:false; response_revision:4644; number_of_response:1; }","duration":"133.422963ms","start":"2026-04-11T17:49:01.221571Z","end":"2026-04-11T17:49:01.354994Z","steps":["trace[1604233718] 'process raft request' (duration: 133.127898ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:01.355261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.973409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-11T17:49:01.355301Z","caller":"traceutil/trace.go:171","msg":"trace[1148530961] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4644; }","duration":"129.067991ms","start":"2026-04-11T17:49:01.226222Z","end":"2026-04-11T17:49:01.35529Z","steps":["trace[1148530961] 'agreement among raft nodes before linearized reading' (duration: 128.910569ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:01.373756Z","caller":"traceutil/trace.go:171","msg":"trace[1142043523] transaction","detail":"{read_only:false; response_revision:4646; number_of_response:1; }","duration":"127.271995ms","start":"2026-04-11T17:49:01.246464Z","end":"2026-04-11T17:49:01.373736Z","steps":["trace[1142043523] 'process raft request' (duration: 127.230824ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:01.374133Z","caller":"traceutil/trace.go:171","msg":"trace[1737132013] transaction","detail":"{read_only:false; response_revision:4645; number_of_response:1; }","duration":"147.002266ms","start":"2026-04-11T17:49:01.227116Z","end":"2026-04-11T17:49:01.374118Z","steps":["trace[1737132013] 'process raft request' (duration: 146.476119ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:05.114127Z","caller":"traceutil/trace.go:171","msg":"trace[135564502] transaction","detail":"{read_only:false; response_revision:4762; number_of_response:1; }","duration":"107.624581ms","start":"2026-04-11T17:49:05.006468Z","end":"2026-04-11T17:49:05.114092Z","steps":["trace[135564502] 'process raft request' (duration: 107.441698ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:05.470839Z","caller":"traceutil/trace.go:171","msg":"trace[1894874758] transaction","detail":"{read_only:false; response_revision:4763; number_of_response:1; }","duration":"100.856445ms","start":"2026-04-11T17:49:05.369959Z","end":"2026-04-11T17:49:05.470816Z","steps":["trace[1894874758] 'process raft request' (duration: 100.685503ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:18.24042Z","caller":"traceutil/trace.go:171","msg":"trace[1361954814] linearizableReadLoop","detail":"{readStateIndex:5105; appliedIndex:5104; }","duration":"103.533814ms","start":"2026-04-11T17:49:18.136859Z","end":"2026-04-11T17:49:18.240393Z","steps":["trace[1361954814] 'read index received' (duration: 38.141596ms)","trace[1361954814] 'applied index is now lower than readState.Index' (duration: 65.390848ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:49:18.240735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.852678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10395"} {"level":"info","ts":"2026-04-11T17:49:18.240833Z","caller":"traceutil/trace.go:171","msg":"trace[2015157343] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4923; }","duration":"103.99383ms","start":"2026-04-11T17:49:18.136821Z","end":"2026-04-11T17:49:18.240814Z","steps":["trace[2015157343] 'agreement among raft nodes before linearized reading' (duration: 103.717776ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:50:39.58357Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2914} {"level":"info","ts":"2026-04-11T17:50:39.651894Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2914,"took":"66.824116ms","hash":797712049,"current-db-size-bytes":52645888,"current-db-size":"53 MB","current-db-size-in-use-bytes":29241344,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-11T17:50:39.651965Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":797712049,"revision":2914,"compact-revision":-1} {"level":"info","ts":"2026-04-11T17:55:39.590035Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5610} {"level":"info","ts":"2026-04-11T17:55:39.659806Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5610,"took":"68.594292ms","hash":75859567,"current-db-size-bytes":52645888,"current-db-size":"53 MB","current-db-size-in-use-bytes":18149376,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-11T17:55:39.659878Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":75859567,"revision":5610,"compact-revision":2914}