Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.01.2023 13:40
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 15020468 kB
MemAvailable 30428040 kB
Buffers 0 kB
Cached 16712820 kB
SwapCached 7736 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-02 13:37:24
DTS_END                         2023-01-02 13:37:24.7460
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/41878
TRN_ID                          227636
ATT_ID                          610
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: 9131.81 22114 1095817
customer order: creation 181.55 448 21786
customer order: refuse 14.98 433 1797
order to supplier: creation 47.82 1373 5738
order to supplier: removal 3.61 159502 433
invoice (draft): creation 47.93 3044 5752
invoice (draft): removal 51.73 1015 6207
invoice accept: apply 42.63 26187 5115
invoice accept: cancel 46.53 1189 5583
customer reserve: creation 3883.68 256 466041
customer reserve: removal 4502.53 100 540304
realization accept: apply 87.69 265 10523
realization accept: cancel 85.07 159 10208
payment from customer: creation 61.24 183 7349
payment from customer: removal 6.28 105 753
payment to supplier: creation 51.73 186 6207
payment to supplier: removal 3.56 121 427
service: total inventory turnovers 4.28 3740 513
service: total monetary turnovers 7.94 363 953
service: aggregate perf. data 1.03 14833 124
service: refresh index statistics 0.03 228780 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-01-2023 13:40:53 to 02-01-2023 13:40:55.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6285
TEST_TIME 2 5001
TEST_TIME 3 5273
TEST_TIME 4 4944
TEST_TIME 5 5292
TEST_TIME 6 6040
TEST_TIME 7 6368
TEST_TIME 8 6736
TEST_TIME 9 6594
TEST_TIME 10 6650
TEST_TIME 11 6570
TEST_TIME 12 7048
TEST_TIME 13 6901
TEST_TIME 14 7078
TEST_TIME 15 7027
TEST_TIME 16 7249
TEST_TIME 17 7294
TEST_TIME 18 7323
TEST_TIME 19 7625
TEST_TIME 20 7857
TEST_TIME 21 7844
TEST_TIME 22 8011
TEST_TIME 23 8095
TEST_TIME 24 8390
TEST_TIME 25 8412
TEST_TIME 26 8398
TEST_TIME 27 8547
TEST_TIME 28 8678
TEST_TIME 29 8691
TEST_TIME 30 8966
TEST_TIME 31 9325
TEST_TIME 32 10029
TEST_TIME 33 10185
TEST_TIME 34 10697
TEST_TIME 35 11042
TEST_TIME 36 10786
TEST_TIME 37 10686
TEST_TIME 38 10769
TEST_TIME 39 10642
TEST_TIME 40 10645
TEST_TIME 41 10511
TEST_TIME 42 10549
TEST_TIME 43 10465
TEST_TIME 44 10493
TEST_TIME 45 10333
TEST_TIME 46 10133
TEST_TIME 47 10073
TEST_TIME 48 10002
TEST_TIME 49 9878
TEST_TIME 50 9800
TEST_TIME 51 9801
TEST_TIME 52 9781
TEST_TIME 53 9677
TEST_TIME 54 9729
TEST_TIME 55 9737
TEST_TIME 56 9773
TEST_TIME 57 9822
TEST_TIME 58 9793
TEST_TIME 59 9688
TEST_TIME 60 9678
TEST_TIME 61 9653
TEST_TIME 62 9653
TEST_TIME 63 9690
TEST_TIME 64 9743
TEST_TIME 65 9688
TEST_TIME 66 9669
TEST_TIME 67 9688
TEST_TIME 68 9660
TEST_TIME 69 9777
TEST_TIME 70 9789
TEST_TIME 71 9800
TEST_TIME 72 9824
TEST_TIME 73 9748
TEST_TIME 74 9695
TEST_TIME 75 9636
TEST_TIME 76 9609
TEST_TIME 77 9562
TEST_TIME 78 9532
TEST_TIME 79 9476
TEST_TIME 80 9438
TEST_TIME 81 9446
TEST_TIME 82 9389
TEST_TIME 83 9337
TEST_TIME 84 9433
TEST_TIME 85 9440
TEST_TIME 86 9419
TEST_TIME 87 9402
TEST_TIME 88 9394
TEST_TIME 89 9353
TEST_TIME 90 9389
TEST_TIME 91 9422
TEST_TIME 92 9471
TEST_TIME 93 9435
TEST_TIME 94 9439
TEST_TIME 95 9486
TEST_TIME 96 9551
TEST_TIME 97 9494
TEST_TIME 98 9410
TEST_TIME 99 9402
TEST_TIME 100 9383
TEST_TIME 101 9368
TEST_TIME 102 9339
TEST_TIME 103 9276
TEST_TIME 104 9270
TEST_TIME 105 9202
TEST_TIME 106 9159
TEST_TIME 107 9167
TEST_TIME 108 9221
TEST_TIME 109 9243
TEST_TIME 110 9230
TEST_TIME 111 9215
TEST_TIME 112 9235
TEST_TIME 113 9227
TEST_TIME 114 9194
TEST_TIME 115 9177
TEST_TIME 116 9218
TEST_TIME 117 9243
TEST_TIME 118 9245
TEST_TIME 119 9188
TEST_TIME 120 9152

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 02-01-2023 13:40:57 to 02-01-2023 13:41:00.

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 1527502 1527502 0 0.00 0 35779 14 0 0
doc_list_biud 1527493 1527493 0 0.00 0 27243 41 0 0
sp_add_invoice_to_stock 5249 5115 134 2.55 41 239720 26188 0 134
sp_cancel_adding_invoice 5583 5583 0 0.00 5 115525 1189 0 0
sp_cancel_client_order 1797 1797 0 0.00 5 23202 433 0 0
sp_cancel_customer_reserve 540304 540304 0 0.00 1 27246 100 0 0
sp_cancel_pay_from_customer 753 753 0 0.00 4 3040 106 0 0
sp_cancel_pay_to_supplier 427 427 0 0.00 4 7049 121 0 0
sp_cancel_supplier_invoice 6225 6207 18 0.28 3 19295 1016 0 18
sp_cancel_supplier_order 433 433 0 0.00 20 1270743 159503 0 0
sp_cancel_write_off 10208 10208 0 0.00 1 7591 159 0 0
sp_client_order 21786 21786 0 0.00 5 23958 448 0 0
sp_customer_reserve 466041 466041 0 0.00 0 105902 257 0 0
sp_fill_shopping_cart 33261 33261 0 0.00 1 29531 81 0 0
sp_fill_shopping_cart_clo_res 466012 466012 0 0.00 0 17257 18 0 0
sp_get_clo_for_invoice 5083 5083 0 0.00 0 30097 265 0 0
sp_kill_cost_storno 16971 16971 0 0.00 0 35773 74 0 0
sp_kill_qstorno_ret_qs2qd 1097456 1097456 0 0.00 0 23208 44 0 0
sp_kill_qty_storno 580135 580135 0 0.00 0 27242 100 0 0
sp_lock_selected_doc 21648 21648 0 0.00 0 10321 8 0 0
sp_make_cost_storno 29162 29162 0 0.00 0 24218 76 0 0
sp_multiply_rows_for_pdistr 29159 29159 0 0.00 0 7745 37 0 0
sp_multiply_rows_for_qdistr 21786 21786 0 0.00 0 12699 168 0 0
sp_pay_from_customer 7349 7349 0 0.00 5 15334 184 0 0
sp_pay_to_supplier 6207 6207 0 0.00 6 23617 186 0 0
sp_payment_common 13555 13555 0 0.00 2 13496 43 0 0
sp_qd_handle_on_cancel_clo 1797 1797 0 0.00 0 14713 340 0 0
sp_qd_handle_on_invoice_upd_sts 10665 10665 0 0.00 0 20167 484 0 0
sp_qd_handle_on_reserve_upd_sts 10521 10521 0 0.00 0 16337 61 0 0
sp_reserve_write_off 10523 10523 0 0.00 8 24257 266 0 0
sp_supplier_invoice 5752 5752 0 0.00 8 93174 3045 0 0
sp_supplier_order 5738 5738 0 0.00 7 38894 1373 0 0
srv_aggregate_perf_data 124 124 0 0.00 2438 168695 14833 0 0
srv_fill_mon_cache_memory 73 73 0 0.00 633 139307 34475 0 0
srv_find_qd_qs_mism 1046457 1046457 0 0.00 0 25623 11 0 0
srv_make_invnt_saldo 513 513 0 0.00 61 75595 3740 0 0
srv_make_money_saldo 953 953 0 0.00 1 11157 364 0 0
srv_recalc_idx_stat 4 4 0 0.00 85925 394189 228780 0 0
t$perf-abend:sp_add_invoice_to_stock 134 134 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 18 18 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5118 5118 0 0.00 1 25896 867 0 0
t$perf-norm:sp_cancel_adding_invoice 424 424 0 0.00 1 7359 437 0 0
t$perf-norm:sp_cancel_client_order 1797 1797 0 0.00 0 2252 26 0 0
t$perf-norm:sp_cancel_customer_reserve 754 754 0 0.00 0 2257 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 753 753 0 0.00 0 4266 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 427 427 0 0.00 0 1310 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 440 440 0 0.00 0 1011 36 0 0
t$perf-norm:sp_cancel_supplier_order 433 433 0 0.00 0 81510 6799 0 0
t$perf-norm:sp_cancel_write_off 1019 1019 0 0.00 0 790 19 0 0
t$perf-norm:sp_client_order 21787 21787 0 0.00 0 6379 34 0 0
t$perf-norm:sp_customer_reserve 1082 1082 0 0.00 0 3255 20 0 0
t$perf-norm:sp_pay_from_customer 7349 7349 0 0.00 0 5948 20 0 0
t$perf-norm:sp_pay_to_supplier 6208 6208 0 0.00 0 2941 17 0 0
t$perf-norm:sp_reserve_write_off 10523 10523 0 0.00 0 8294 22 0 0
t$perf-norm:sp_supplier_invoice 5752 5752 0 0.00 0 14732 37 0 0
t$perf-norm:sp_supplier_order 5738 5738 0 0.00 0 3439 29 0 0
t$perf-norm:srv_aggregate_perf_data 124 124 0 0.00 0 458 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 73 73 0 0.00 0 527 11 0 0
t$perf-norm:srv_make_invnt_saldo 513 513 0 0.00 0 985 16 0 0
t$perf-norm:srv_make_money_saldo 953 953 0 0.00 0 477 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 198 82 0 0
v_add_invoice_to_stock 10428 10160 268 2.57 0 23255 29 0 268
v_all_customers 39770 39770 0 0.00 0 183 4 0 0
v_all_suppliers 32184 32184 0 0.00 0 130 4 0 0
v_all_wares 43565 43565 0 0.00 0 112 4 0 0
v_cancel_adding_invoice 848 848 0 0.00 0 23435 44 0 0
v_cancel_client_order 3594 3594 0 0.00 0 22993 21 0 0
v_cancel_customer_prepayment 1506 1506 0 0.00 0 2971 16 0 0
v_cancel_customer_reserve 1508 1508 0 0.00 1 5419 18 0 0
v_cancel_payment_to_supplier 854 854 0 0.00 0 498 10 0 0
v_cancel_supplier_invoice 916 880 36 3.93 0 18211 64 0 36
v_cancel_supplier_order 866 866 0 0.00 0 1929 15 0 0
v_cancel_write_off 2038 2038 0 0.00 0 1983 26 0 0
v_max_id_clo_ord 5733 5733 0 0.00 0 881 5 0 0
v_max_id_clo_res 1082 1082 0 0.00 0 2695 13 0 0
v_max_id_ord_sup 5745 5745 0 0.00 0 1753 5 0 0
v_max_non_paid_invoice 1590 1590 0 0.00 0 336 7 0 0
v_max_non_paid_realizn 3808 3808 0 0.00 0 948 8 0 0
v_min_id_clo_ord 5733 5733 0 0.00 0 2685 8 0 0
v_min_id_clo_res 1082 1082 0 0.00 0 2756 13 0 0
v_min_id_ord_sup 5745 5745 0 0.00 0 8033 14 0 0
v_min_non_paid_invoice 6205 6205 0 0.00 0 6766 12 0 0
v_min_non_paid_realizn 7349 7349 0 0.00 0 13481 20 0 0
v_our_firm 10872 10872 0 0.00 0 73 4 0 0
v_reserve_write_off 21042 21042 0 0.00 0 9751 12 0 0
x_lock_depdocs_on_canc_invoice 11350 11350 0 0.00 0 14072 121 0 0
x_lock_depdocs_on_canc_sup_ord 433 433 0 0.00 0 3051 331 0 0
x_make_qstorno_1000_1200 5738 5738 0 0.00 2 38869 1207 0 0
x_make_qstorno_1200_2000 5752 5752 0 0.00 3 93132 2823 0 0
x_make_qstorno_2100_3300 466041 466041 0 0.00 0 105885 226 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-01-2023 13:41:01 to 02-01-2023 13:41:05.

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
11:07:28 934 8192000000 8930925312 8986558464 4612288 0.056 3 1 2 0 4709904 61840 35776
11:08:30 13290 8192000000 9716355184 10013806592 799692864 8.893 100 89 178 0 833076448 12079344 21304240
11:09:57 14307 8192000000 9758653680 10067079168 836063520 9.260 100 88 176 0 868565920 11168272 21334128
11:11:16 14870 8192000000 9806196960 10125185024 875180720 9.652 100 85 170 0 910946048 16236400 19528928
11:12:35 3916 8192000000 9851825920 10184155136 917016640 10.067 100 82 162 0 968047712 32898464 18132608
11:13:43 5682 8192000000 9925389760 10269708288 957602784 10.466 100 86 172 1 1041533024 65864448 18065792
11:14:51 2872 8192000000 9954127648 10308124672 989855456 10.780 100 86 168 0 1071020416 63660528 17504432
11:15:56 1395 8192000000 10012213184 10373963776 1014270512 11.017 100 97 190 0 1134495088 101278912 18945664
11:16:59 811 8192000000 9993913376 10356867072 1035769312 11.224 100 97 176 0 1106265344 53795952 16700080
11:18:01 958 8192000000 10051413680 10427002880 1058164480 11.439 100 92 180 0 1170359952 93882176 18313296
11:19:03 907 8192000000 10045878592 10425716736 1071054832 11.562 100 96 187 0 1168931552 78863392 19013328
11:20:06 1196 8192000000 10056303632 10440818688 1075575344 11.605 100 85 164 2 1176093456 84969296 15548816
11:21:08 5479 8192000000 10013145040 10408980480 1079953936 11.647 100 59 112 2 1141618896 50937632 10727328
11:22:14 1185 8192000000 10045669376 10439331840 1084863056 11.694 100 77 152 1 1170293328 69958896 15471376
11:23:16 969 8192000000 10058952816 10445680640 1090959856 11.752 100 97 191 0 1177820656 66931680 19929120
11:24:18 998 8192000000 10055339056 10445856768 1095008720 11.790 100 81 154 3 1169928784 59123776 15796288
11:25:20 4800 8192000000 10047916176 10444468224 1097287520 11.812 100 69 125 2 1167815888 58778672 11749696
11:26:26 2225 8192000000 10066347296 10460467200 1100208464 11.840 100 72 141 1 1181886704 67745776 13932464
11:27:28 1127 8192000000 10068593056 10457620480 1109534880 11.928 100 98 189 0 1191562944 63365904 18662160
11:28:30 1075 8192000000 10096463136 10491183104 1104305888 11.878 100 86 172 0 1218226944 96149536 17771520
11:29:33 1993 8192000000 10056251952 10447462400 1091700352 11.759 100 75 145 2 1171035888 65674448 13661088
11:30:36 2412 8192000000 10051637872 10433449984 1083748144 11.683 100 76 147 2 1167571152 69297456 14525552
11:31:39 2832 8192000000 10007048928 10392477696 1064669648 11.501 100 67 132 1 1139410144 61651648 13088848
11:32:42 954 8192000000 10019904096 10398527488 1053958736 11.399 100 95 181 1 1140286416 67633792 18693888
11:33:44 2549 8192000000 10000825744 10383998976 1055194480 11.410 100 75 143 1 1134103776 64483840 14425456
11:34:47 2052 8192000000 9967320784 10344570880 1016594288 11.039 99 76 140 1 1082566768 51288432 14684048
11:35:50 1127 8192000000 9985682592 10355191808 1024699840 11.117 100 91 175 0 1104738672 63510192 16528640
11:36:52 1276 8192000000 9987058128 10369638400 1043698384 11.300 100 74 145 3 1107545744 48835120 15012240
11:37:57 12350 8192000000 10006491200 10386747392 1058009776 11.437 100 71 141 1 1123598112 50922160 14666176
11:39:18 5934 8192000000 10033528736 10422509568 1078417696 11.632 100 66 124 1 1156484896 63578944 14488256
11:40:30 30562 8192000000 10060560912 10466848768 1093142064 11.773 100 34 68 0 1175002384 75019888 6840432
11:42:12 1951 8192000000 10119410160 10510577664 1111862800 11.950 100 91 180 0 1231050496 100291600 18896096
11:43:15 2628 8192000000 10146230256 10540032000 1113284656 11.964 100 79 149 0 1261123600 131297888 16541056
11:44:23 1579 8192000000 10154579344 10558431232 1128689888 12.109 100 83 161 0 1276928576 131124528 17114160
11:45:27 1230 8192000000 10183299968 10588880896 1137402736 12.191 100 93 177 1 1298473472 142266528 18804208
11:46:32 3396 8192000000 10186496880 10586054656 1153659760 12.344 100 85 163 2 1302390592 131617552 17113280
11:47:38 4264 8192000000 10215061344 10626580480 1160902096 12.412 100 65 126 1 1327518864 152887600 13729168
11:48:48 4533 8192000000 10254255840 10689286144 1169116112 12.489 100 69 138 1 1360635552 176299904 15219536
11:49:57 2354 8192000000 10263049952 10672693248 1174070160 12.535 100 88 166 1 1375442480 181452672 19919648
11:51:08 3116 8192000000 10312651600 10736791552 1187015280 12.656 100 84 161 2 1426536528 220048064 19473184
11:52:17 736 8192000000 10367076928 10773270528 1198211984 12.760 100 86 169 0 1474752064 258055648 18484432
11:53:20 1009 8192000000 10402925872 10819612672 1207880688 12.849 100 91 179 2 1511394944 283162848 20351408
11:54:25 1084 8192000000 10427511392 10843668480 1204277744 12.816 100 91 174 1 1539693184 314217104 21198336
11:55:33 1223 8192000000 10441120016 10888232960 1201146192 12.787 100 96 189 0 1545818960 321398704 23274064
11:56:37 624 8192000000 10466293584 10890571776 1199060320 12.768 100 97 186 0 1568460256 347606720 21793216
11:57:41 1298 8192000000 10492510224 10913079296 1204022768 12.814 100 100 195 0 1599490016 372013440 23453808
11:58:43 1011 8192000000 10504353216 10921299968 1189782336 12.681 100 100 191 1 1606669760 393878240 23009184
11:59:47 737 8192000000 10529552192 10962345984 1198263920 12.760 100 98 189 2 1635032944 413577216 23191808
12:00:50 2841 8192000000 10586044608 11009482752 1204261968 12.816 100 78 150 1 1690906240 467498032 19146240
12:02:07 4693 8192000000 10537556224 10996781056 1208239584 12.853 100 52 103 0 1646679088 425299200 13140304
12:03:16 1396 8192000000 10553697632 10998730752 1190261184 12.686 99 77 147 1 1664256256 453956112 20038960
12:04:20 7148 8192000000 10553252704 11014737920 1175309280 12.546 99 55 109 0 1654703952 465394352 14000320
12:05:32 12541 8192000000 10543628800 10978983936 1174235104 12.536 100 47 90 0 1651047680 465305056 11507520
12:06:58 19259 8192000000 10548683136 11026853888 1165507696 12.455 100 41 82 0 1651274096 474582848 11183552
12:08:23 6997 8192000000 10483251488 10956816384 1155993456 12.366 100 52 102 2 1578320848 409774912 12552480
12:09:37 12225 8192000000 10301989488 10784657408 1152470736 12.333 100 29 56 0 1435095424 275667840 6956848
12:11:15 12777 8192000000 10202639200 10683162624 1149601600 12.306 100 24 47 1 1296839472 141501632 5736240
12:12:29 65163 8192000000 10054741520 10479308800 1090081248 11.743 96 32 52 2 1120954832 25652720 5220864
12:14:34 68768 8192000000 10090734912 10557927424 1134571888 12.164 100 20 40 0 1210722800 72103120 4047792
12:16:52 139307 8192000000 10107903904 10562650112 1095990160 11.800 96 21 42 0 1139916144 39669408 4256576
12:20:15 73401 8192000000 10089080176 10535456768 1119070160 12.018 98 15 30 1 1160869040 38434256 3364624
12:22:29 76238 8192000000 10040343312 10475294720 1103674368 11.872 98 31 62 1 1120422192 9294912 7452912
12:24:48 27113 8192000000 10070452880 10495488000 1125535664 12.079 99 62 119 0 1159686480 20549264 13601552
12:26:16 71908 8192000000 10097211520 10516475904 1115024160 11.980 98 54 103 4 1169257840 41295984 12937696
12:28:30 30179 8192000000 10134057504 10570780672 1140398960 12.219 100 36 70 0 1216373968 67540976 8434032
12:30:07 3912 8192000000 10142067872 10571755520 1144033536 12.253 100 63 119 2 1237792304 79734880 14023888
12:31:12 31126 8192000000 10207078224 10665873408 1136782720 12.185 99 40 80 0 1310986192 164753424 9450048
12:32:44 28547 8192000000 10164432768 10643091456 1145574768 12.268 100 15 30 0 1272011024 122675856 3760400
12:34:26 74573 8192000000 10097427904 10541879296 1149206704 12.302 100 15 30 0 1197463392 44937040 3319648
12:36:49 64515 8192000000 10079817968 10563407872 1098751920 11.826 97 13 26 0 1153707536 51870448 3085168
12:38:54 39847 8192000000 10110554128 10540654592 1114537088 11.975 98 40 80 0 1183172816 59993632 8642096
12:41:04 21855 8192000000 10134393840 10573684736 1143647296 12.250 100 36 71 1 1210680112 59925680 7107136
12:42:28 26735 8192000000 10099505568 10528182272 1119783424 12.025 98 49 97 0 1166371760 36560448 10027888
12:43:58 79001 8192000000 10127281520 10606567424 1093541344 11.776 96 18 36 0 1142906352 45479792 3885216
12:46:21 84583 8192000000 10068618336 10535952384 1079507440 11.643 95 16 32 0 1137266336 53608880 4150016
12:48:46 18198 8192000000 10072832480 10514231296 1109299680 11.926 99 24 46 0 1162629040 48244688 5084672
12:50:11 124781 8192000000 10082223232 10554880000 1117367552 12.002 99 42 79 2 1185633152 57827280 10438320
12:53:26 70207 8192000000 10077754128 10536374272 1127147200 12.094 99 27 54 0 1188786224 56316624 5322400
12:55:38 28469 8192000000 10075148896 10521038848 1110396448 11.936 98 20 40 0 1171462864 57217824 3848592
12:57:11 51047 8192000000 10125283152 10569748480 1101510544 11.852 97 21 42 0 1208756960 102642960 4603456
12:59:02 57014 8192000000 10111528304 10574921728 1112171536 11.953 98 11 22 1 1201263152 86470048 2621568
13:01:14 77625 8192000000 10079466464 10540433408 1120075392 12.028 99 9 18 1 1184680672 62479696 2125584
13:03:32 22587 8192000000 10069244528 10496614400 1097463424 11.814 97 23 44 0 1134489440 32935408 4090608
13:05:01 44351 8192000000 10103290640 10557280256 1103062400 11.867 97 24 48 0 1182701088 74606048 5032640
13:06:51 38162 8192000000 10136257344 10602020864 1111727488 11.949 98 27 53 0 1195799744 78803312 5268944
13:08:33 88764 8192000000 10068798160 10536845312 1068265072 11.536 94 24 44 1 1141751200 69179536 4306592
13:11:02 1577 8192000000 10105406384 10517241856 1131796672 12.138 100 88 168 0 1197670480 48006704 17867104
13:12:09 55375 8192000000 10121226304 10580623360 1094416240 11.785 96 17 34 0 1177781712 79859440 3506032
13:14:05 18172 8192000000 10098764208 10521923584 1135975328 12.178 100 49 92 0 1170090608 24335440 9779840
13:15:27 112717 8192000000 10058457488 10539044864 1124209088 12.067 99 29 53 2 1203640944 73523504 5908352
13:18:20 49203 8192000000 10085349632 10553143296 1111804224 11.949 98 27 54 0 1174872992 56953200 6115568
13:20:11 44868 8192000000 10087329840 10538901504 1132031280 12.141 99 20 40 0 1185040416 48404608 4604528
13:21:57 56112 8192000000 10103762112 10560155648 1117302080 12.001 98 17 34 0 1178337280 57236832 3798368
13:23:54 100345 8192000000 10136316208 10613841920 1119600416 12.023 98 21 42 0 1237848624 113207568 5040640
13:26:35 70899 8192000000 10065366112 10536452096 1098418048 11.823 97 9 18 0 1134629760 34244432 1967280
13:28:51 31427 8192000000 10125202992 10578505728 1138395056 12.200 100 40 76 2 1197163376 51158992 7609328
13:30:27 33745 8192000000 10089779984 10539155456 1113176208 11.962 98 28 56 0 1158243200 39781872 5285120
13:32:01 57041 8192000000 10140683488 10594496512 1117077408 11.999 97 29 58 0 1221286496 98070128 6138960
13:34:02 36020 8192000000 10107281936 10583482368 1125700560 12.081 99 42 80 1 1192302176 58672784 7928832
13:35:41 27316 8192000000 10114253664 10558009344 1143060192 12.244 100 60 121 1 1212859536 58177184 11622160
13:37:13 32145 8192000000 9563438800 9955926016 665030432 7.508 59 12 24 0 712760688 45539520 2190736

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 02-01-2023 13:41:07 to 02-01-2023 13:41:24.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 134 v_add_invoice_to_stock 335544517
except 18 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 02-01-2023 13:41:25 to 02-01-2023 13:41:27.

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          228973
MON$OLDEST_ACTIVE               228974
MON$OLDEST_SNAPSHOT             228974
MON$NEXT_TRANSACTION            228974
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                       2066784
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             787
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 ---
--- end of diff output ---

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

02.01.23 13:41:28. 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-02 13:41:30 No severe PSQL-related problems occured
02.01.23 13:41:32. End of report.