Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.10.2022 10:39
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 14915152 kB
MemAvailable 30558952 kB
Buffers 0 kB
Cached 16812328 kB
SwapCached 2188 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 2022-10-09 10:37:25
DTS_END                         2022-10-09 10:37:25.1800
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/59792
TRN_ID                          225653
ATT_ID                          681
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: 9415.55 20881 1129866
customer order: creation 175.20 463 21024
customer order: refuse 15.54 382 1865
order to supplier: creation 47.52 1245 5702
order to supplier: removal 3.43 161846 411
invoice (draft): creation 46.35 2935 5562
invoice (draft): removal 52.18 978 6262
invoice accept: apply 43.80 25943 5256
invoice accept: cancel 47.03 1220 5644
customer reserve: creation 4072.83 248 488740
customer reserve: removal 4610.93 94 553312
realization accept: apply 87.18 276 10462
realization accept: cancel 80.04 165 9605
payment from customer: creation 60.74 179 7289
payment from customer: removal 5.18 147 622
payment to supplier: creation 50.88 190 6106
payment to supplier: removal 3.66 125 439
service: total inventory turnovers 3.95 4191 474
service: total monetary turnovers 8.14 335 977
service: aggregate perf. data 0.91 16771 109
service: refresh index statistics 0.04 199896 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-10-2022 10:39:51 to 09-10-2022 10:39:53.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6305
TEST_TIME 2 5149
TEST_TIME 3 6172
TEST_TIME 4 6475
TEST_TIME 5 6453
TEST_TIME 6 6289
TEST_TIME 7 6277
TEST_TIME 8 6135
TEST_TIME 9 6055
TEST_TIME 10 6226
TEST_TIME 11 6789
TEST_TIME 12 6959
TEST_TIME 13 7097
TEST_TIME 14 7160
TEST_TIME 15 7333
TEST_TIME 16 7493
TEST_TIME 17 7590
TEST_TIME 18 7727
TEST_TIME 19 7778
TEST_TIME 20 7759
TEST_TIME 21 8118
TEST_TIME 22 8305
TEST_TIME 23 8376
TEST_TIME 24 8283
TEST_TIME 25 8374
TEST_TIME 26 8347
TEST_TIME 27 8776
TEST_TIME 28 8894
TEST_TIME 29 8966
TEST_TIME 30 9226
TEST_TIME 31 9876
TEST_TIME 32 10355
TEST_TIME 33 10797
TEST_TIME 34 11282
TEST_TIME 35 11132
TEST_TIME 36 11103
TEST_TIME 37 11067
TEST_TIME 38 11015
TEST_TIME 39 11273
TEST_TIME 40 11097
TEST_TIME 41 10985
TEST_TIME 42 10819
TEST_TIME 43 10612
TEST_TIME 44 10764
TEST_TIME 45 10694
TEST_TIME 46 10657
TEST_TIME 47 10600
TEST_TIME 48 10431
TEST_TIME 49 10368
TEST_TIME 50 10328
TEST_TIME 51 10371
TEST_TIME 52 10328
TEST_TIME 53 10344
TEST_TIME 54 10346
TEST_TIME 55 10272
TEST_TIME 56 10281
TEST_TIME 57 10197
TEST_TIME 58 10114
TEST_TIME 59 9971
TEST_TIME 60 9958
TEST_TIME 61 9936
TEST_TIME 62 9918
TEST_TIME 63 9827
TEST_TIME 64 9754
TEST_TIME 65 9727
TEST_TIME 66 9723
TEST_TIME 67 9685
TEST_TIME 68 9660
TEST_TIME 69 9800
TEST_TIME 70 9823
TEST_TIME 71 9732
TEST_TIME 72 9781
TEST_TIME 73 9866
TEST_TIME 74 9882
TEST_TIME 75 9928
TEST_TIME 76 9939
TEST_TIME 77 9949
TEST_TIME 78 9862
TEST_TIME 79 9851
TEST_TIME 80 9807
TEST_TIME 81 9782
TEST_TIME 82 9733
TEST_TIME 83 9671
TEST_TIME 84 9631
TEST_TIME 85 9640
TEST_TIME 86 9635
TEST_TIME 87 9611
TEST_TIME 88 9577
TEST_TIME 89 9563
TEST_TIME 90 9547
TEST_TIME 91 9603
TEST_TIME 92 9614
TEST_TIME 93 9657
TEST_TIME 94 9691
TEST_TIME 95 9679
TEST_TIME 96 9717
TEST_TIME 97 9739
TEST_TIME 98 9708
TEST_TIME 99 9742
TEST_TIME 100 9657
TEST_TIME 101 9662
TEST_TIME 102 9638
TEST_TIME 103 9613
TEST_TIME 104 9567
TEST_TIME 105 9533
TEST_TIME 106 9491
TEST_TIME 107 9460
TEST_TIME 108 9417
TEST_TIME 109 9426
TEST_TIME 110 9439
TEST_TIME 111 9457
TEST_TIME 112 9434
TEST_TIME 113 9452
TEST_TIME 114 9439
TEST_TIME 115 9491
TEST_TIME 116 9501
TEST_TIME 117 9505
TEST_TIME 118 9501
TEST_TIME 119 9503
TEST_TIME 120 9432

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 09-10-2022 10:39:55 to 09-10-2022 10:39:58.

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 1579563 1579563 0 0.00 0 34443 14 0 0
doc_list_biud 1579553 1579553 0 0.00 0 31718 38 0 0
sp_add_invoice_to_stock 5411 5256 155 2.86 47 244719 25944 0 155
sp_cancel_adding_invoice 5644 5644 0 0.00 6 97874 1221 0 0
sp_cancel_client_order 1865 1865 0 0.00 6 8922 382 0 0
sp_cancel_customer_reserve 553312 553312 0 0.00 1 22254 95 0 0
sp_cancel_pay_from_customer 622 622 0 0.00 4 14499 147 0 0
sp_cancel_pay_to_supplier 439 439 0 0.00 4 3361 125 0 0
sp_cancel_supplier_invoice 6276 6262 14 0.22 3 13179 978 0 14
sp_cancel_supplier_order 411 411 0 0.00 12 1552519 161846 0 0
sp_cancel_write_off 9605 9605 0 0.00 2 7805 165 0 0
sp_client_order 21024 21024 0 0.00 5 16156 464 0 0
sp_customer_reserve 488740 488740 0 0.00 0 171272 248 0 0
sp_fill_shopping_cart 32277 32277 0 0.00 1 26426 79 0 0
sp_fill_shopping_cart_clo_res 488713 488713 0 0.00 0 16110 17 0 0
sp_get_clo_for_invoice 5210 5210 0 0.00 0 13879 236 0 0
sp_kill_cost_storno 16310 16310 0 0.00 0 34433 73 0 0
sp_kill_qstorno_ret_qs2qd 1122902 1122902 0 0.00 0 22179 41 0 0
sp_kill_qty_storno 592770 592770 0 0.00 0 31718 94 0 0
sp_lock_selected_doc 21626 21626 0 0.00 0 8857 8 0 0
sp_make_cost_storno 29066 29066 0 0.00 0 15636 74 0 0
sp_multiply_rows_for_pdistr 29066 29066 0 0.00 0 25870 37 0 0
sp_multiply_rows_for_qdistr 21024 21024 0 0.00 0 11585 190 0 0
sp_pay_from_customer 7289 7289 0 0.00 5 25883 179 0 0
sp_pay_to_supplier 6106 6106 0 0.00 6 16059 190 0 0
sp_payment_common 13395 13395 0 0.00 2 5800 42 0 0
sp_qd_handle_on_cancel_clo 1865 1865 0 0.00 0 8915 297 0 0
sp_qd_handle_on_invoice_upd_sts 10853 10853 0 0.00 0 31718 487 0 0
sp_qd_handle_on_reserve_upd_sts 10462 10462 0 0.00 0 8763 58 0 0
sp_reserve_write_off 10462 10462 0 0.00 7 17714 276 0 0
sp_supplier_invoice 5562 5562 0 0.00 9 87901 2936 0 0
sp_supplier_order 5702 5702 0 0.00 7 39967 1246 0 0
srv_aggregate_perf_data 109 109 0 0.00 1968 153403 16771 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 786 181730 36103 0 0
srv_find_qd_qs_mism 1078948 1078948 0 0.00 0 10167 11 0 0
srv_make_invnt_saldo 474 474 0 0.00 84 111232 4192 0 0
srv_make_money_saldo 977 977 0 0.00 1 14305 336 0 0
srv_recalc_idx_stat 5 5 0 0.00 84655 440525 199896 0 0
t$perf-abend:sp_add_invoice_to_stock 155 155 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5256 5256 0 0.00 1 14635 929 0 0
t$perf-norm:sp_cancel_adding_invoice 427 427 0 0.00 1 6563 476 0 0
t$perf-norm:sp_cancel_client_order 1865 1865 0 0.00 0 3216 29 0 0
t$perf-norm:sp_cancel_customer_reserve 686 686 0 0.00 0 8907 42 0 0
t$perf-norm:sp_cancel_pay_from_customer 622 622 0 0.00 0 217 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 439 439 0 0.00 0 2730 29 0 0
t$perf-norm:sp_cancel_supplier_invoice 414 414 0 0.00 0 1004 33 0 0
t$perf-norm:sp_cancel_supplier_order 411 411 0 0.00 0 109193 6761 0 0
t$perf-norm:sp_cancel_write_off 1091 1091 0 0.00 0 3737 22 0 0
t$perf-norm:sp_client_order 21024 21024 0 0.00 0 10734 37 0 0
t$perf-norm:sp_customer_reserve 1079 1079 0 0.00 0 320 11 0 0
t$perf-norm:sp_pay_from_customer 7289 7289 0 0.00 0 4250 18 0 0
t$perf-norm:sp_pay_to_supplier 6106 6106 0 0.00 0 10103 24 0 0
t$perf-norm:sp_reserve_write_off 10462 10462 0 0.00 0 4153 22 0 0
t$perf-norm:sp_supplier_invoice 5562 5562 0 0.00 0 10217 33 0 0
t$perf-norm:sp_supplier_order 5702 5702 0 0.00 0 3707 31 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 648 29 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 392 15 0 0
t$perf-norm:srv_make_invnt_saldo 474 474 0 0.00 0 952 15 0 0
t$perf-norm:srv_make_money_saldo 977 977 0 0.00 0 291 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 182 79 0 0
v_add_invoice_to_stock 10728 10418 310 2.88 0 3949 27 0 310
v_all_customers 38798 38798 0 0.00 0 164 4 0 0
v_all_suppliers 31410 31410 0 0.00 0 155 4 0 0
v_all_wares 42048 42048 0 0.00 0 66 4 0 0
v_cancel_adding_invoice 854 854 0 0.00 0 3729 23 0 0
v_cancel_client_order 3730 3730 0 0.00 0 4807 13 0 0
v_cancel_customer_prepayment 1244 1244 0 0.00 0 14421 28 0 0
v_cancel_customer_reserve 1372 1372 0 0.00 0 1263 11 0 0
v_cancel_payment_to_supplier 878 878 0 0.00 0 3302 19 0 0
v_cancel_supplier_invoice 856 828 28 3.27 0 3234 27 0 28
v_cancel_supplier_order 822 822 0 0.00 0 3684 23 0 0
v_cancel_write_off 2182 2182 0 0.00 0 3858 27 0 0
v_max_id_clo_ord 5700 5700 0 0.00 0 1510 5 0 0
v_max_id_clo_res 1079 1079 0 0.00 0 1656 16 0 0
v_max_id_ord_sup 5553 5553 0 0.00 0 5420 7 0 0
v_max_non_paid_invoice 1654 1654 0 0.00 0 844 8 0 0
v_max_non_paid_realizn 3736 3736 0 0.00 0 3590 8 0 0
v_min_id_clo_ord 5700 5700 0 0.00 0 2034 8 0 0
v_min_id_clo_res 1079 1079 0 0.00 0 4113 15 0 0
v_min_id_ord_sup 5553 5553 0 0.00 0 6064 8 0 0
v_min_non_paid_invoice 6106 6106 0 0.00 0 3015 12 0 0
v_min_non_paid_realizn 7289 7289 0 0.00 0 5795 15 0 0
v_our_firm 10356 10356 0 0.00 0 62 5 0 0
v_reserve_write_off 20924 20924 0 0.00 0 16503 14 0 0
x_lock_depdocs_on_canc_invoice 11492 11492 0 0.00 0 18976 123 0 0
x_lock_depdocs_on_canc_sup_ord 411 411 0 0.00 0 6281 335 0 0
x_make_qstorno_1000_1200 5702 5702 0 0.00 2 39943 1078 0 0
x_make_qstorno_1200_2000 5562 5562 0 0.00 4 87884 2729 0 0
x_make_qstorno_2100_3300 488740 488740 0 0.00 0 171266 219 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-10-2022 10:39:59 to 09-10-2022 10:40:03.

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
08:07:28 7 8192000000 8801243904 8813535232 5751568 0.070 3 1 2 0 5852736 65392 35776
08:08:29 27858 8192000000 9726171408 10027319296 804981008 8.947 100 93 186 0 836168304 8564816 22622480
08:10:05 22325 8192000000 9760615648 10069905408 835949808 9.259 100 89 178 0 869870384 12624320 21296256
08:11:30 18060 8192000000 9810703408 10130415616 869199584 9.592 100 84 168 0 912195504 23203056 19792864
08:12:52 4629 8192000000 9863691888 10196230144 910465392 10.002 100 82 161 0 975749120 47396864 17886864
08:14:02 2450 8192000000 9917186416 10258096128 964057056 10.529 100 91 174 0 1034703824 52464000 18182768
08:15:07 2037 8192000000 9974490000 10326458368 1002292064 10.901 100 86 168 0 1085808480 66760240 16756176
08:16:11 1672 8192000000 9985817312 10348343296 1026123520 11.131 100 90 174 3 1105317616 62404976 16789120
08:17:14 1072 8192000000 10019368384 10394775552 1050999152 11.370 100 95 178 2 1142756016 72922928 18833936
08:18:16 899 8192000000 10040889776 10417618944 1061675056 11.473 100 97 180 0 1161504560 80907648 18921856
08:19:19 1757 8192000000 10042150192 10428473344 1068300464 11.536 100 81 158 0 1165182064 80899776 15981824
08:20:23 4812 8192000000 10041836912 10421895168 1076358400 11.613 100 80 144 4 1165149744 75928816 12862528
08:21:28 1113 8192000000 10037148480 10422317056 1079986544 11.647 100 80 144 2 1155362080 60392176 14983360
08:22:31 1100 8192000000 10052870704 10439622656 1092614976 11.768 100 89 172 2 1174083888 62725024 18743888
08:23:33 1067 8192000000 10083895440 10475184128 1092444912 11.766 100 90 173 2 1203828288 94631536 16751840
08:24:35 983 8192000000 10057902592 10447142912 1094672464 11.787 100 88 169 0 1182783024 71295520 16815040
08:25:37 1146 8192000000 10079138096 10469957632 1099884992 11.837 100 98 186 1 1200283088 81672208 18725888
08:26:38 1006 8192000000 10060333200 10452488192 1103293872 11.869 100 88 163 0 1184529360 63549216 17686272
08:27:42 1046 8192000000 10048976064 10438623232 1102327984 11.860 100 91 179 3 1173850160 51667696 19854480
08:28:44 3952 8192000000 10050328288 10445975552 1101323760 11.850 100 65 127 0 1182056240 67938224 12794256
08:29:49 996 8192000000 10065242048 10456911872 1089353248 11.737 100 81 163 0 1189014736 82917408 16744080
08:30:50 1015 8192000000 10032821984 10418147328 1067531344 11.528 100 96 181 0 1151108720 64236528 19340848
08:31:52 3778 8192000000 10000063872 10386538496 1056229968 11.420 100 63 124 1 1127052736 58357104 12465664
08:32:57 2392 8192000000 9995611216 10380791808 1045816608 11.321 99 65 121 1 1117198528 57772752 13609168
08:34:02 1149 8192000000 9981744832 10354561024 1034050016 11.207 99 95 183 4 1103641344 50757008 18834320
08:35:03 1156 8192000000 9994677712 10370322432 1045302128 11.316 100 92 178 2 1114652976 49793856 19556992
08:36:05 1034 8192000000 9999143056 10377564160 1039053456 11.256 100 90 173 3 1126718976 68668512 18997008
08:37:07 4028 8192000000 9987161232 10365538304 1043536704 11.299 100 56 98 3 1106777152 53421728 9818720
08:38:16 7196 8192000000 10005301648 10381148160 1063490992 11.490 99 83 136 4 1120635344 44712048 12432304
08:39:29 6964 8192000000 10052748720 10436927488 1088523120 11.729 100 80 152 2 1166122896 63272624 14327152
08:40:43 2553 8192000000 10054871488 10443407360 1101124848 11.848 100 70 133 1 1178463152 63702736 13635568
08:41:52 1100 8192000000 10107542672 10497003520 1118396560 12.012 100 97 189 1 1232904032 95245008 19262464
08:42:56 3338 8192000000 10135658768 10528006144 1122180080 12.048 100 88 172 2 1258836624 118152848 18503696
08:44:03 2563 8192000000 10150399392 10543984640 1125795936 12.082 100 83 165 1 1269184320 125860384 17528000
08:45:14 25919 8192000000 10160999120 10559537152 1126585344 12.089 99 68 133 3 1287669504 147071728 14012432
08:46:49 3254 8192000000 10208521184 10625998848 1152824128 12.336 100 89 173 0 1324038704 152316768 18897808
08:47:54 1034 8192000000 10251633424 10661400576 1167291584 12.472 100 95 184 0 1361812240 175133072 19387584
08:48:57 2375 8192000000 10248209520 10658951168 1171676416 12.512 100 86 160 2 1361846992 173242016 16928560
08:50:02 1027 8192000000 10288873136 10693750784 1172186544 12.517 100 94 184 0 1404533536 212370880 19976112
08:51:05 1005 8192000000 10330003664 10733424640 1177617616 12.568 100 97 187 1 1441765056 241687888 22459552
08:52:09 1326 8192000000 10366826496 10777554944 1179537536 12.586 100 96 185 0 1477469840 276964288 20968016
08:53:13 772 8192000000 10376842496 10775494656 1183222896 12.620 100 95 182 1 1478767504 274829440 20715168
08:54:16 1007 8192000000 10433459936 10847240192 1193504560 12.716 100 96 189 2 1537699216 323128736 21065920
08:55:20 1092 8192000000 10424856000 10854371328 1189545136 12.679 100 97 186 0 1535122032 323989024 21587872
08:56:25 945 8192000000 10455247840 10859233280 1187304480 12.658 100 95 184 1 1563935056 354443152 22187424
08:57:32 778 8192000000 10510467520 10920607744 1195667776 12.736 100 96 183 0 1616292784 398654240 21970768
08:58:35 1120 8192000000 10476097840 10939142144 1199035824 12.767 100 100 187 2 1579928128 357865280 23027024
08:59:39 847 8192000000 10503881216 10959085568 1209095440 12.861 100 92 182 0 1609422768 378549392 21777936
09:00:43 3692 8192000000 10505142544 10939727872 1201789392 12.793 100 65 129 1 1623390576 404721776 16879408
09:01:53 1001 8192000000 10568311296 10991693824 1213111744 12.898 100 75 148 0 1676192320 444013200 19067376
09:03:01 10200 8192000000 10600182096 11046002688 1207829952 12.849 100 62 122 1 1717131920 493388448 15913520
09:04:14 3217 8192000000 10582671824 11048099840 1209210848 12.862 100 66 123 3 1679735616 455199504 15325264
09:05:18 8340 8192000000 10598408128 11040055296 1208409072 12.854 100 48 94 0 1701048864 480680992 11958800
09:06:28 16663 8192000000 10553742048 11017601024 1173660864 12.531 99 47 93 1 1665588768 480052960 11874944
09:08:03 25675 8192000000 10412976080 10911301632 1163752480 12.438 99 35 70 0 1527991664 355720624 8518560
09:09:37 34752 8192000000 10268152752 10732912640 1143331008 12.247 99 27 47 2 1382327392 233063152 5933232
09:11:27 48537 8192000000 10051654960 10507517952 1125163584 12.076 99 11 22 0 1174584304 46762144 2658576
09:13:17 55967 8192000000 10123716144 10566750208 1092825104 11.770 95 30 58 0 1170617392 71104448 6687840
09:15:24 52510 8192000000 10124848864 10653945856 1124694352 12.071 99 14 29 0 1253438272 125343856 3400064
09:17:18 60187 8192000000 10100846144 10540859392 1122057760 12.046 98 53 102 1 1145510912 11264272 12188880
09:19:25 71554 8192000000 10133988752 10576166912 1137025120 12.188 100 41 78 3 1221569344 75426720 9117504
09:21:40 32633 8192000000 10063306336 10499928064 1093492656 11.776 96 28 54 1 1126729824 27451728 5785440
09:23:18 181727 8192000000 10099071552 10522193920 1062401360 11.479 94 39 77 0 1146808112 76110032 8296720
09:27:21 14568 8192000000 10135824720 10572394496 1132904512 12.149 100 43 82 0 1237195456 95266752 9024192
09:28:38 6110 8192000000 10175717008 10634711040 1140627296 12.221 100 63 124 0 1281251552 127255264 13368992
09:30:07 148484 8192000000 10108658992 10584154112 1091051632 11.753 96 8 16 0 1154759168 62069088 1638448
09:33:36 47625 8192000000 10052222384 10526433280 1109723344 11.930 99 17 34 0 1144070544 30675888 3671312
09:35:43 95883 8192000000 10039878672 10528428032 1117766752 12.006 99 20 40 0 1177403344 55334624 4301968
09:38:34 61644 8192000000 10055562320 10544599040 1120732592 12.034 99 14 28 0 1159538528 36102688 2703248
09:40:39 58295 8192000000 10035500448 10493943808 1123068960 12.056 99 13 24 3 1151387616 25870832 2447824
09:42:38 65298 8192000000 10089022016 10562875392 1127420176 12.097 99 32 62 0 1189408176 55544064 6443936
09:44:44 29995 8192000000 10138434256 10588946432 1131310800 12.134 99 40 79 0 1232774240 92962128 8501312
09:46:51 77878 8192000000 10116021568 10606448640 1136611136 12.184 100 18 37 0 1217126800 76813776 3701888
09:49:18 55022 8192000000 10125984800 10581082112 1086651488 11.711 96 30 60 1 1201402192 108182000 6568704
09:51:18 57541 8192000000 10091982784 10608103424 1130632544 12.127 100 29 58 0 1248355456 111810640 5912272
09:53:19 39563 8192000000 10130326496 10581442560 1124507760 12.070 99 46 91 1 1211388448 78047936 8832752
09:54:59 103682 8192000000 10094477680 10579718144 1136573392 12.183 100 27 54 0 1223759776 81223888 5962496
09:57:48 15630 8192000000 10109472640 10580840448 1138037296 12.197 100 39 76 0 1210477024 64654192 7785536
09:59:05 55175 8192000000 10078874800 10548187136 1091830416 11.760 96 6 11 0 1151743456 58922048 990992
10:01:06 69884 8192000000 10117603248 10591518720 1089604032 11.739 97 14 28 0 1179246960 86800368 2842560
10:03:32 66209 8192000000 10056798512 10516062208 1067161504 11.525 95 18 36 0 1126871472 56046560 3663408
10:05:52 50879 8192000000 10128578848 10590339072 1123690400 12.062 100 30 60 0 1225430144 95316160 6423584
10:07:43 31581 8192000000 10075303040 10536058880 1098749232 11.826 98 33 66 1 1205378640 99405968 7223440
10:09:16 22224 8192000000 10075638672 10520842240 1113169264 11.962 100 45 90 1 1167086432 45207168 8710000
10:10:48 45063 8192000000 10097951216 10542161920 1127919376 12.102 100 31 63 0 1215828496 81624272 6284848
10:12:36 45626 8192000000 10134968672 10574651392 1125814656 12.082 99 48 94 2 1224196224 89195808 9185760
10:14:22 68924 8192000000 10102272464 10551996416 1095537600 11.795 96 16 34 0 1154530624 55319488 3673536
10:16:36 84488 8192000000 10136088608 10567262208 1123783376 12.063 99 46 91 1 1226414192 92510816 10120000
10:19:09 58831 8192000000 10026713072 10481487872 1098148704 11.820 97 17 33 1 1130470816 28407088 3915024
10:21:09 78437 8192000000 10073037600 10544259072 1134666864 12.165 100 14 27 0 1156805424 19560176 2578384
10:23:56 47635 8192000000 10083052976 10556293120 1133555792 12.155 100 26 52 0 1186407904 47298208 5553904
10:25:53 79596 8192000000 10110083200 10564956160 1118169408 12.010 98 28 54 0 1204000384 79115616 6715360
10:28:24 25928 8192000000 10111285280 10586914816 1128693440 12.109 99 28 56 0 1199536000 64983168 5859392
10:29:53 32041 8192000000 10124570416 10582368256 1133366320 12.153 99 33 64 0 1216790432 76284400 7139712
10:31:25 30626 8192000000 10117388208 10577215488 1123067904 12.056 99 35 70 0 1216218512 85821664 7328944
10:33:06 27197 8192000000 10079351680 10517696512 1083926336 11.685 97 37 68 2 1146360128 55380544 7053248
10:34:35 23666 8192000000 10114251440 10580275200 1117701984 12.005 99 56 112 1 1199615008 70509296 11403728
10:36:02 10619 8192000000 10073380592 10500255744 1104488272 11.880 98 50 100 0 1153855616 39877376 9489968
10:37:16 58339 8192000000 9515448416 9888342016 627220464 7.111 56 6 12 0 660178816 31942864 1015488

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 09-10-2022 10:40:05 to 09-10-2022 10:40:22.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 155 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-10-2022 10:40:23 to 09-10-2022 10:40:25.

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          227813
MON$OLDEST_ACTIVE               227814
MON$OLDEST_SNAPSHOT             227814
MON$NEXT_TRANSACTION            227814
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                       2104000
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             788
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2856 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2856 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2856 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2856 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 ---
--- end of diff output ---

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

09.10.22 10:40:26. 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
2022-10-09 10:40:29 No severe PSQL-related problems occured
09.10.22 10:40:30. End of report.