Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.01.2023 07:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 14873308 kB
MemAvailable 30408104 kB
Buffers 0 kB
Cached 16849840 kB
SwapCached 4544 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.3
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2023-01-05 07:37:22
DTS_END                         2023-01-05 07:37:22.3980
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/56584
TRN_ID                          225299
ATT_ID                          614
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 9346.26 29010 1121551
customer order: creation 167.07 440 20048
customer order: refuse 14.85 359 1782
order to supplier: creation 45.08 1332 5409
order to supplier: removal 3.47 162703 416
invoice (draft): creation 44.03 3134 5283
invoice (draft): removal 53.06 887 6367
invoice accept: apply 40.91 25339 4909
invoice accept: cancel 48.53 1025 5824
customer reserve: creation 3858.63 239 463036
customer reserve: removal 4766.38 94 571966
realization accept: apply 83.13 253 9976
realization accept: cancel 90.74 152 10889
payment from customer: creation 60.26 170 7231
payment from customer: removal 5.78 108 693
payment to supplier: creation 48.58 190 5830
payment to supplier: removal 3.39 74 407
service: total inventory turnovers 3.98 3893 478
service: total monetary turnovers 7.45 346 894
service: aggregate perf. data 0.91 16839 109
service: refresh index statistics 0.03 362625 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 05-01-2023 07:38:59 to 05-01-2023 07:39:02.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6837
TEST_TIME 2 4928
TEST_TIME 3 4769
TEST_TIME 4 5170
TEST_TIME 5 5023
TEST_TIME 6 5134
TEST_TIME 7 5015
TEST_TIME 8 5157
TEST_TIME 9 5353
TEST_TIME 10 5548
TEST_TIME 11 5699
TEST_TIME 12 5852
TEST_TIME 13 6221
TEST_TIME 14 6252
TEST_TIME 15 6387
TEST_TIME 16 6600
TEST_TIME 17 6726
TEST_TIME 18 7105
TEST_TIME 19 7624
TEST_TIME 20 7537
TEST_TIME 21 7462
TEST_TIME 22 7884
TEST_TIME 23 8100
TEST_TIME 24 8143
TEST_TIME 25 8716
TEST_TIME 26 9144
TEST_TIME 27 9223
TEST_TIME 28 9347
TEST_TIME 29 9640
TEST_TIME 30 10240
TEST_TIME 31 10587
TEST_TIME 32 10665
TEST_TIME 33 11120
TEST_TIME 34 11029
TEST_TIME 35 10936
TEST_TIME 36 10757
TEST_TIME 37 10812
TEST_TIME 38 10648
TEST_TIME 39 10640
TEST_TIME 40 10638
TEST_TIME 41 10647
TEST_TIME 42 10537
TEST_TIME 43 10550
TEST_TIME 44 10521
TEST_TIME 45 10402
TEST_TIME 46 10373
TEST_TIME 47 10226
TEST_TIME 48 10269
TEST_TIME 49 10182
TEST_TIME 50 10085
TEST_TIME 51 10024
TEST_TIME 52 10003
TEST_TIME 53 9909
TEST_TIME 54 9910
TEST_TIME 55 9881
TEST_TIME 56 9817
TEST_TIME 57 9967
TEST_TIME 58 9851
TEST_TIME 59 9908
TEST_TIME 60 9908
TEST_TIME 61 9843
TEST_TIME 62 9741
TEST_TIME 63 9790
TEST_TIME 64 9932
TEST_TIME 65 9959
TEST_TIME 66 9959
TEST_TIME 67 9946
TEST_TIME 68 9924
TEST_TIME 69 9906
TEST_TIME 70 10011
TEST_TIME 71 9988
TEST_TIME 72 9968
TEST_TIME 73 9871
TEST_TIME 74 9774
TEST_TIME 75 9800
TEST_TIME 76 9706
TEST_TIME 77 9654
TEST_TIME 78 9590
TEST_TIME 79 9581
TEST_TIME 80 9541
TEST_TIME 81 9526
TEST_TIME 82 9463
TEST_TIME 83 9432
TEST_TIME 84 9373
TEST_TIME 85 9336
TEST_TIME 86 9370
TEST_TIME 87 9430
TEST_TIME 88 9497
TEST_TIME 89 9449
TEST_TIME 90 9466
TEST_TIME 91 9456
TEST_TIME 92 9547
TEST_TIME 93 9506
TEST_TIME 94 9553
TEST_TIME 95 9543
TEST_TIME 96 9567
TEST_TIME 97 9490
TEST_TIME 98 9456
TEST_TIME 99 9462
TEST_TIME 100 9446
TEST_TIME 101 9405
TEST_TIME 102 9342
TEST_TIME 103 9351
TEST_TIME 104 9321
TEST_TIME 105 9298
TEST_TIME 106 9274
TEST_TIME 107 9287
TEST_TIME 108 9264
TEST_TIME 109 9271
TEST_TIME 110 9271
TEST_TIME 111 9226
TEST_TIME 112 9228
TEST_TIME 113 9294
TEST_TIME 114 9269
TEST_TIME 115 9443
TEST_TIME 116 9526
TEST_TIME 117 9508
TEST_TIME 118 9442
TEST_TIME 119 9423
TEST_TIME 120 9368

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 05-01-2023 07:39:03 to 05-01-2023 07:39:06.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1547458 1547458 0 0.00 0 13706 12 0 0
doc_list_biud 1547458 1547458 0 0.00 0 62645 40 0 0
sp_add_invoice_to_stock 5122 4909 213 4.15 73 236382 25340 0 213
sp_cancel_adding_invoice 5824 5824 0 0.00 6 77795 1025 0 0
sp_cancel_client_order 1782 1782 0 0.00 5 6248 359 0 0
sp_cancel_customer_reserve 571966 571966 0 0.00 1 62666 95 0 0
sp_cancel_pay_from_customer 693 693 0 0.00 3 6036 108 0 0
sp_cancel_pay_to_supplier 407 407 0 0.00 4 689 74 0 0
sp_cancel_supplier_invoice 6380 6367 13 0.20 3 22004 888 0 13
sp_cancel_supplier_order 416 416 0 0.00 12 1619554 162703 0 0
sp_cancel_write_off 10889 10889 0 0.00 2 12080 153 0 0
sp_client_order 20048 20048 0 0.00 6 22325 441 0 0
sp_customer_reserve 463036 463036 0 0.00 0 54076 240 0 0
sp_fill_shopping_cart 30733 30733 0 0.00 1 16056 72 0 0
sp_fill_shopping_cart_clo_res 463025 463025 0 0.00 0 14123 17 0 0
sp_get_clo_for_invoice 4872 4872 0 0.00 0 24803 261 0 0
sp_kill_cost_storno 17813 17813 0 0.00 0 7932 63 0 0
sp_kill_qstorno_ret_qs2qd 1161604 1161604 0 0.00 0 55502 42 0 0
sp_kill_qty_storno 612091 612091 0 0.00 0 62645 95 0 0
sp_lock_selected_doc 20622 20622 0 0.00 0 6657 7 0 0
sp_make_cost_storno 27908 27908 0 0.00 0 13537 77 0 0
sp_multiply_rows_for_pdistr 27908 27908 0 0.00 0 8772 33 0 0
sp_multiply_rows_for_qdistr 20048 20048 0 0.00 0 11242 166 0 0
sp_pay_from_customer 7231 7231 0 0.00 6 14477 171 0 0
sp_pay_to_supplier 5830 5830 0 0.00 6 13473 190 0 0
sp_payment_common 13061 13061 0 0.00 2 8057 49 0 0
sp_qd_handle_on_cancel_clo 1782 1782 0 0.00 0 5013 296 0 0
sp_qd_handle_on_invoice_upd_sts 10695 10695 0 0.00 0 15259 443 0 0
sp_qd_handle_on_reserve_upd_sts 9976 9976 0 0.00 0 22252 64 0 0
sp_reserve_write_off 9976 9976 0 0.00 8 22302 253 0 0
sp_supplier_invoice 5283 5283 0 0.00 10 100591 3135 0 0
sp_supplier_order 5409 5409 0 0.00 7 83737 1332 0 0
srv_aggregate_perf_data 110 109 1 0.90 2820 224667 16839 0 1
srv_fill_mon_cache_memory 71 71 0 0.00 1023 107158 34332 0 0
srv_find_qd_qs_mism 1072703 1072703 0 0.00 0 12804 10 0 0
srv_make_invnt_saldo 478 478 0 0.00 61 74064 3893 0 0
srv_make_money_saldo 894 894 0 0.00 1 12147 347 0 0
srv_recalc_idx_stat 4 4 0 0.00 235315 481019 362625 0 0
t$perf-abend:sp_add_invoice_to_stock 213 213 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4910 4910 0 0.00 1 9895 702 0 0
t$perf-norm:sp_cancel_adding_invoice 422 422 0 0.00 2 5351 349 0 0
t$perf-norm:sp_cancel_client_order 1782 1782 0 0.00 0 1602 26 0 0
t$perf-norm:sp_cancel_customer_reserve 655 655 0 0.00 0 467 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 693 693 0 0.00 0 475 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 407 407 0 0.00 0 1687 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 371 371 0 0.00 0 7808 56 0 0
t$perf-norm:sp_cancel_supplier_order 416 416 0 0.00 1 102273 6175 0 0
t$perf-norm:sp_cancel_write_off 1029 1029 0 0.00 0 1477 15 0 0
t$perf-norm:sp_client_order 20048 20048 0 0.00 0 7465 29 0 0
t$perf-norm:sp_customer_reserve 1001 1001 0 0.00 0 803 11 0 0
t$perf-norm:sp_pay_from_customer 7231 7231 0 0.00 0 8493 20 0 0
t$perf-norm:sp_pay_to_supplier 5830 5830 0 0.00 0 11586 17 0 0
t$perf-norm:sp_reserve_write_off 9976 9976 0 0.00 0 12186 18 0 0
t$perf-norm:sp_supplier_invoice 5283 5283 0 0.00 0 2797 24 0 0
t$perf-norm:sp_supplier_order 5409 5409 0 0.00 0 5182 26 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 154 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 71 4 0 0
t$perf-norm:srv_make_invnt_saldo 478 478 0 0.00 0 1817 20 0 0
t$perf-norm:srv_make_money_saldo 894 894 0 0.00 0 796 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 6 187 57 0 0
v_add_invoice_to_stock 10168 9742 426 4.18 0 12711 28 0 426
v_all_customers 37158 37158 0 0.00 0 170 3 0 0
v_all_suppliers 30268 30268 0 0.00 0 100 4 0 0
v_all_wares 40096 40096 0 0.00 0 159 3 0 0
v_cancel_adding_invoice 844 844 0 0.00 0 935 12 0 0
v_cancel_client_order 3564 3564 0 0.00 0 1496 9 0 0
v_cancel_customer_prepayment 1386 1386 0 0.00 0 867 10 0 0
v_cancel_customer_reserve 1310 1310 0 0.00 1 1869 10 0 0
v_cancel_payment_to_supplier 814 814 0 0.00 0 585 9 0 0
v_cancel_supplier_invoice 768 742 26 3.38 0 433 21 0 26
v_cancel_supplier_order 832 832 0 0.00 0 857 11 0 0
v_cancel_write_off 2058 2058 0 0.00 0 4776 25 0 0
v_max_id_clo_ord 5409 5409 0 0.00 0 890 4 0 0
v_max_id_clo_res 1001 1001 0 0.00 0 2068 14 0 0
v_max_id_ord_sup 5276 5276 0 0.00 0 3077 7 0 0
v_max_non_paid_invoice 1381 1381 0 0.00 0 59 6 0 0
v_max_non_paid_realizn 3701 3701 0 0.00 0 5009 8 0 0
v_min_id_clo_ord 5409 5409 0 0.00 0 434 6 0 0
v_min_id_clo_res 1001 1001 0 0.00 0 1362 8 0 0
v_min_id_ord_sup 5276 5276 0 0.00 0 10869 13 0 0
v_min_non_paid_invoice 5830 5830 0 0.00 0 6089 21 0 0
v_min_non_paid_realizn 7231 7231 0 0.00 0 7050 24 0 0
v_our_firm 9998 9998 0 0.00 0 150 4 0 0
v_reserve_write_off 19952 19952 0 0.00 0 3665 9 0 0
x_lock_depdocs_on_canc_invoice 11820 11820 0 0.00 0 16018 111 0 0
x_lock_depdocs_on_canc_sup_ord 416 416 0 0.00 0 12250 372 0 0
x_make_qstorno_1000_1200 5409 5409 0 0.00 2 83599 1195 0 0
x_make_qstorno_1200_2000 5283 5283 0 0.00 5 97040 2922 0 0
x_make_qstorno_2100_3300 463036 463036 0 0.00 0 54064 212 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 05-01-2023 07:39:07 to 05-01-2023 07:39:12.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:07:26 2 8192000000 8796917904 8807436288 3189136 0.038 1 1 2 0 3286752 61840 35776
05:08:26 25281 8192000000 9722101616 10019205120 800567152 8.902 100 94 188 0 831765312 8075920 23122240
05:10:02 20448 8192000000 9761542320 10066284544 830666080 9.206 100 89 178 0 864574784 12297408 21611296
05:11:25 12799 8192000000 9797405408 10115203072 868097504 9.581 100 87 174 0 912308464 23793184 20417776
05:12:43 4405 8192000000 9861952320 10195927040 913756016 10.034 100 80 157 0 972827488 41527296 17544176
05:13:54 5073 8192000000 9902731296 10249048064 960666384 10.496 100 74 143 0 1021393968 45394272 15333312
05:15:01 2062 8192000000 9949988288 10304004096 1001224080 10.890 100 88 171 0 1068938816 50756352 16958384
05:16:05 1957 8192000000 10008512416 10369077248 1028154832 11.151 100 92 179 0 1129851664 83822688 17874144
05:17:09 974 8192000000 10010003792 10380058624 1052314256 11.383 100 92 176 0 1137642592 67890880 17437456
05:18:11 1396 8192000000 10038046048 10410606592 1061306832 11.469 100 95 181 1 1166012528 86465360 18240336
05:19:14 1285 8192000000 10035826000 10415820800 1067792832 11.531 100 92 177 0 1157751344 71681680 18276832
05:20:17 929 8192000000 10025541248 10402779136 1070507952 11.557 100 96 187 1 1148130912 58833520 18789440
05:21:18 1249 8192000000 10042248240 10429706240 1073063024 11.581 100 85 168 2 1162390784 71866912 17460848
05:22:20 1264 8192000000 10063916064 10449223680 1080175456 11.649 100 95 186 0 1194330816 94831296 19324064
05:23:23 1511 8192000000 10061920432 10454437888 1084999552 11.695 100 84 162 2 1187276800 85329744 16947504
05:24:25 1275 8192000000 10039472368 10430857216 1091076256 11.753 100 82 151 2 1161932592 56867936 13988400
05:25:28 1447 8192000000 10076041952 10468220928 1092545200 11.767 100 92 177 1 1199189328 88995008 17649120
05:26:30 883 8192000000 10066975152 10455281664 1095562496 11.796 100 97 180 3 1190370800 75633296 19175008
05:27:31 982 8192000000 10038259296 10428776448 1093373936 11.775 100 77 147 2 1156106512 48493776 14238800
05:28:33 3405 8192000000 10031303616 10421964800 1089050864 11.734 100 71 140 0 1159311712 56001024 14259824
05:29:39 1205 8192000000 10047678480 10433019904 1075345616 11.603 100 92 175 1 1169860224 76937856 17576752
05:30:41 1197 8192000000 10033127952 10418073600 1062167664 11.477 100 92 175 1 1155372224 74719840 18484720
05:31:43 1236 8192000000 10025276384 10402996224 1066655152 11.520 100 98 196 0 1148576336 61733872 20187312
05:32:45 1169 8192000000 10028049936 10404319232 1048712112 11.348 100 97 187 0 1146578240 78309136 19556992
05:33:47 1123 8192000000 10018288064 10394525696 1043970528 11.303 100 93 166 5 1142376672 82546592 15859552
05:34:49 1636 8192000000 9997316048 10382159872 1051535360 11.375 100 88 169 0 1130812928 61551424 17726144
05:35:52 1205 8192000000 9980607232 10351988736 1024683648 11.117 100 96 179 0 1106540400 61772624 20084128
05:36:54 1245 8192000000 9986289392 10372042752 1049513712 11.356 100 72 134 1 1112677520 49022272 14141536
05:38:00 6136 8192000000 9993339104 10374049792 1037357232 11.239 99 82 134 3 1103830768 54167344 12306192
05:39:15 3272 8192000000 10016879984 10397442048 1062783664 11.483 100 72 132 3 1125159312 49456400 12919248
05:40:25 5123 8192000000 10060103456 10445787136 1075536800 11.605 100 69 127 1 1176491072 88181152 12773120
05:41:34 6650 8192000000 10057479248 10441936896 1093878240 11.780 100 87 157 3 1181805296 72426032 15501024
05:42:45 8439 8192000000 10125549472 10520363008 1106401584 11.898 100 70 139 2 1247876720 126884944 14590192
05:43:59 12347 8192000000 10150723888 10547462144 1111539184 11.947 100 70 138 0 1258024880 131464928 15020768
05:45:13 5064 8192000000 10202629248 10605330432 1122050848 12.046 100 60 115 0 1314838640 180146096 12641696
05:46:21 1605 8192000000 10247746960 10645209088 1143302544 12.247 100 91 175 1 1360302160 197308240 19691376
05:47:25 1163 8192000000 10270564512 10664128512 1161492592 12.417 100 86 163 2 1382833072 203327536 18012944
05:48:29 2709 8192000000 10311386512 10713165824 1173195088 12.527 100 87 169 0 1421785984 229782624 18808272
05:49:36 1253 8192000000 10334759024 10739912704 1176287664 12.556 100 85 159 0 1446205088 252253088 17664336
05:50:41 1102 8192000000 10382970800 10792779776 1185904368 12.645 100 97 186 2 1491208928 283921824 21382736
05:51:47 1016 8192000000 10392818208 10801364992 1179728704 12.588 100 90 172 4 1495451104 296413040 19309360
05:52:51 1181 8192000000 10434638256 10847838208 1187920640 12.664 100 97 188 0 1541599616 331563664 22115312
05:53:55 2726 8192000000 10465448288 10894635008 1200687632 12.783 100 78 152 0 1574555424 355694528 18173264
05:55:00 1267 8192000000 10477448160 10934784000 1194907840 12.729 100 91 175 0 1579084576 362788432 21388304
05:56:05 2366 8192000000 10500123616 10942062592 1203392960 12.808 100 86 171 1 1604617264 380663008 20561296
05:57:10 4969 8192000000 10452411344 10885955584 1187044192 12.656 100 56 110 0 1568335984 367639552 13652240
05:58:21 11132 8192000000 10489543760 10918236160 1157213536 12.377 99 49 98 0 1586524944 416555584 12755824
05:59:39 3190 8192000000 10493950512 10949193728 1183320880 12.621 100 63 123 0 1601461584 402927728 15212976
06:00:58 2415 8192000000 10473654560 10893393920 1176441008 12.557 99 71 138 1 1571055424 378148672 16465744
06:02:01 8138 8192000000 10443318368 10926694400 1169422304 12.491 99 51 94 0 1556886192 375299952 12163936
06:03:14 9966 8192000000 10418510592 10857807872 1163669312 12.438 100 44 88 0 1523580672 348433536 11477824
06:04:45 28290 8192000000 10386595408 10855915520 1157970544 12.384 100 29 53 1 1484383616 319559904 6853168
06:06:25 22821 8192000000 10300570976 10830295040 1147854336 12.289 100 37 74 1 1469343584 312444928 9044320
06:07:49 19852 8192000000 10209483424 10673274880 1143143200 12.245 98 31 61 0 1320318816 170173776 7001840
06:09:36 29751 8192000000 10143420896 10613702656 1139352608 12.209 100 34 68 0 1242751840 96208256 7190976
06:11:28 76581 8192000000 10075899120 10530045952 1116330272 11.992 98 29 59 0 1175108160 51875232 6902656
06:13:45 53788 8192000000 10055350128 10530656256 1096450064 11.804 98 15 30 0 1174962864 75495584 3017216
06:16:17 31076 8192000000 10069121888 10493300736 1122829632 12.054 99 53 100 3 1157011264 23382672 10798960
06:17:49 96866 8192000000 10072433184 10519625728 1093925632 11.780 96 15 28 0 1114755584 18751568 2078384
06:20:33 78983 8192000000 10093285872 10545987584 1071051536 11.562 94 13 25 0 1119487680 45371456 3064688
06:23:28 104488 8192000000 10088881648 10581663744 1066662240 11.520 94 54 99 0 1132628480 54290768 11675472
06:26:15 39065 8192000000 10054347584 10486792192 1100489200 11.842 99 45 83 2 1157592640 47234256 9869184
06:28:03 1515 8192000000 10091308240 10508431360 1122830816 12.054 100 73 140 2 1182875392 43918880 16125696
06:29:05 68043 8192000000 10149987152 10576408576 1126470000 12.088 98 45 84 0 1237780048 102227984 9082064
06:31:16 11093 8192000000 10181283824 10600157184 1134449568 12.163 100 68 118 0 1266298928 119475632 12373728
06:32:27 58799 8192000000 10156319648 10605223936 1112759136 11.959 99 24 49 0 1253265392 134351808 6154448
06:34:27 49670 8192000000 10117354912 10625781760 1112057184 11.952 99 12 24 0 1205472528 90807152 2608192
06:36:18 30211 8192000000 10080902672 10576662528 1116548432 11.994 99 15 30 1 1177310528 57666800 3095296
06:37:56 61747 8192000000 10104996544 10549305344 1100518592 11.843 97 18 31 0 1161871120 57948512 3404016
06:39:59 28823 8192000000 10180474176 10652725248 1139780016 12.213 100 34 68 0 1287594064 139981376 7832672
06:41:34 34892 8192000000 10112291728 10558214144 1133363696 12.153 100 23 46 0 1244948544 106234720 5350128
06:43:11 36409 8192000000 10087512992 10533892096 1126594240 12.089 100 38 75 0 1172399120 38868272 6936608
06:44:48 44121 8192000000 10093464304 10515791872 1100312720 11.841 97 45 87 0 1156737120 47720656 8703744
06:46:33 64815 8192000000 10117853648 10596986880 1128142272 12.104 99 32 59 0 1225637040 91760816 5733952
06:48:43 22985 8192000000 10120546192 10565410816 1140454320 12.220 100 33 66 0 1195190288 48251088 6484880
06:50:08 85310 8192000000 10091734768 10552561664 1132778352 12.148 99 22 44 0 1179366240 42495536 4092352
06:52:41 72954 8192000000 10087366240 10569977856 1129616208 12.118 98 9 18 0 1179220512 47706432 1897872
06:54:59 63757 8192000000 10104545568 10559434752 1136284176 12.181 99 8 16 0 1195273760 57223120 1766464
06:57:04 29213 8192000000 10087526464 10551259136 1122646256 12.052 98 21 41 0 1168947632 42264736 4036640
06:59:01 45164 8192000000 10115781504 10574462976 1150083136 12.310 100 20 38 0 1214507376 59294080 5130160
07:00:51 107157 8192000000 10205529984 10682036224 1161532368 12.418 100 19 38 0 1276178576 110386416 4259792
07:04:35 78973 8192000000 10035894192 10494300160 1045048384 11.313 92 17 34 0 1104418416 55946592 3423440
07:07:04 22525 8192000000 10144125568 10594881536 1132332992 12.143 100 52 102 0 1222925456 80290592 10301872
07:08:29 33982 8192000000 10114131856 10552479744 1115636944 11.986 98 52 99 0 1196304496 70591696 10075856
07:10:04 18204 8192000000 10090028032 10516389888 1113714800 11.968 98 51 97 0 1168358992 44149648 10494544
07:11:24 29106 8192000000 10108772944 10557325312 1116744368 11.996 99 41 80 0 1191153392 66441488 7967536
07:12:57 74443 8192000000 10094065184 10566189056 1108472096 11.918 98 38 76 0 1187879248 71145264 8261888
07:15:30 58656 8192000000 10126673184 10590236672 1124495344 12.069 99 18 36 0 1219646096 91157920 3992832
07:17:59 84348 8192000000 10081264864 10559946752 1135958096 12.177 100 8 16 0 1180951456 43580928 1412432
07:20:25 38484 8192000000 10056417488 10524286976 1107775328 11.911 98 12 24 0 1167505376 57140688 2589360
07:22:09 23627 8192000000 10079523984 10523484160 1099972544 11.837 97 36 69 0 1162878416 54668864 8237008
07:23:33 20150 8192000000 10126301744 10564734976 1122563504 12.051 99 42 81 0 1220667024 89874784 8228736
07:24:58 22827 8192000000 10115127184 10551365632 1111728400 11.949 99 29 56 0 1211793728 93591600 6473728
07:26:23 37043 8192000000 10163119520 10627072000 1116401680 11.993 99 37 74 0 1244421888 119984848 8035360
07:28:04 31568 8192000000 10212747488 10643705856 1140833072 12.223 100 57 111 0 1283953872 131166192 11954608
07:29:38 40105 8192000000 10273749840 10772840448 1149246944 12.302 100 44 88 0 1386149232 227060752 9841536
07:31:48 45093 8192000000 10201098496 10710593536 1137012704 12.187 99 13 26 0 1288471920 148501536 2957680
07:33:33 83817 8192000000 10067528496 10527199232 1090921328 11.751 96 23 46 0 1127038992 31621472 4496192
07:35:57 92920 8192000000 9198395584 9479327744 315215568 3.705 28 7 14 0 350637648 34177040 1245040

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 05-01-2023 07:39:13 to 05-01-2023 07:39:30.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 213 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 05-01-2023 07:39:31 to 05-01-2023 07:39:33.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          224337
MON$OLDEST_ACTIVE               225564
MON$OLDEST_SNAPSHOT             225564
MON$NEXT_TRANSACTION            225564
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-30 07:30:08.6030 GMT
MON$PAGES                       2087520
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             785
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2885 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2885 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2885 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2885 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:978: 
:979: mscs-test-161.budzdorov.ru    Thu Jan  5 07:37:36 2023
:980:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:981: 
--- end of diff output ---

Final processing ISQL logs in /var/tmp/logs-oltp40 according to config parameter 'remove_isql_logs'

05.01.23 07:39:34. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2023-01-05 07:39:36 No severe PSQL-related problems occured
05.01.23 07:39:37. End of report.