Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.06.2022 04:37
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 15874000 kB
MemAvailable 31233740 kB
Buffers 20 kB
Cached 15735528 kB
SwapCached 2436 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.2
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-06-10 04:37:17
DTS_END                         2022-06-10 04:37:17.6050
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/54260
TRN_ID                          223623
ATT_ID                          597
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: 9201.41 25077 1104169
customer order: creation 172.22 478 20666
customer order: refuse 14.52 383 1742
order to supplier: creation 44.97 1301 5396
order to supplier: removal 3.48 167377 417
invoice (draft): creation 47.78 3195 5733
invoice (draft): removal 52.06 987 6247
invoice accept: apply 41.88 24901 5026
invoice accept: cancel 47.33 1123 5679
customer reserve: creation 3889.82 236 466778
customer reserve: removal 4585.65 99 550278
realization accept: apply 84.34 257 10121
realization accept: cancel 86.86 160 10423
payment from customer: creation 59.48 165 7138
payment from customer: removal 6.22 103 746
payment to supplier: creation 49.48 189 5938
payment to supplier: removal 3.37 154 404
service: total inventory turnovers 3.82 4065 458
service: total monetary turnovers 7.22 394 866
service: aggregate perf. data 0.91 17006 109
service: refresh index statistics 0.03 278958 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-06-2022 04:37:56 to 10-06-2022 04:37:58.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8890
TEST_TIME 2 6948
TEST_TIME 3 6691
TEST_TIME 4 6640
TEST_TIME 5 6551
TEST_TIME 6 6519
TEST_TIME 7 6878
TEST_TIME 8 7067
TEST_TIME 9 7226
TEST_TIME 10 7304
TEST_TIME 11 7389
TEST_TIME 12 7361
TEST_TIME 13 7369
TEST_TIME 14 7430
TEST_TIME 15 7486
TEST_TIME 16 7414
TEST_TIME 17 7584
TEST_TIME 18 7509
TEST_TIME 19 7493
TEST_TIME 20 7615
TEST_TIME 21 8069
TEST_TIME 22 8224
TEST_TIME 23 8297
TEST_TIME 24 8317
TEST_TIME 25 8241
TEST_TIME 26 8291
TEST_TIME 27 8507
TEST_TIME 28 9049
TEST_TIME 29 9433
TEST_TIME 30 9415
TEST_TIME 31 10047
TEST_TIME 32 10580
TEST_TIME 33 11101
TEST_TIME 34 10964
TEST_TIME 35 11200
TEST_TIME 36 11167
TEST_TIME 37 11084
TEST_TIME 38 10894
TEST_TIME 39 10797
TEST_TIME 40 10693
TEST_TIME 41 10591
TEST_TIME 42 10468
TEST_TIME 43 10436
TEST_TIME 44 10350
TEST_TIME 45 10250
TEST_TIME 46 10473
TEST_TIME 47 10476
TEST_TIME 48 10369
TEST_TIME 49 10236
TEST_TIME 50 10118
TEST_TIME 51 10015
TEST_TIME 52 9949
TEST_TIME 53 9822
TEST_TIME 54 9856
TEST_TIME 55 9773
TEST_TIME 56 9672
TEST_TIME 57 9579
TEST_TIME 58 9763
TEST_TIME 59 9732
TEST_TIME 60 9716
TEST_TIME 61 9687
TEST_TIME 62 9597
TEST_TIME 63 9685
TEST_TIME 64 9663
TEST_TIME 65 9725
TEST_TIME 66 9641
TEST_TIME 67 9639
TEST_TIME 68 9695
TEST_TIME 69 9645
TEST_TIME 70 9684
TEST_TIME 71 9676
TEST_TIME 72 9683
TEST_TIME 73 9701
TEST_TIME 74 9686
TEST_TIME 75 9719
TEST_TIME 76 9716
TEST_TIME 77 9743
TEST_TIME 78 9800
TEST_TIME 79 9695
TEST_TIME 80 9689
TEST_TIME 81 9607
TEST_TIME 82 9568
TEST_TIME 83 9577
TEST_TIME 84 9524
TEST_TIME 85 9530
TEST_TIME 86 9521
TEST_TIME 87 9454
TEST_TIME 88 9367
TEST_TIME 89 9350
TEST_TIME 90 9431
TEST_TIME 91 9398
TEST_TIME 92 9408
TEST_TIME 93 9379
TEST_TIME 94 9405
TEST_TIME 95 9398
TEST_TIME 96 9406
TEST_TIME 97 9419
TEST_TIME 98 9413
TEST_TIME 99 9461
TEST_TIME 100 9425
TEST_TIME 101 9488
TEST_TIME 102 9536
TEST_TIME 103 9489
TEST_TIME 104 9431
TEST_TIME 105 9395
TEST_TIME 106 9408
TEST_TIME 107 9362
TEST_TIME 108 9318
TEST_TIME 109 9309
TEST_TIME 110 9280
TEST_TIME 111 9231
TEST_TIME 112 9210
TEST_TIME 113 9187
TEST_TIME 114 9178
TEST_TIME 115 9167
TEST_TIME 116 9176
TEST_TIME 117 9168
TEST_TIME 118 9264
TEST_TIME 119 9251
TEST_TIME 120 9227

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-06-2022 04:38:00 to 10-06-2022 04:38:03.

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 1533846 1533846 0 0.00 0 29367 13 0 0
doc_list_biud 1533845 1533845 0 0.00 0 37144 41 0 0
sp_add_invoice_to_stock 5114 5026 88 1.72 47 184324 24901 0 88
sp_cancel_adding_invoice 5679 5679 0 0.00 6 73065 1124 0 0
sp_cancel_client_order 1742 1742 0 0.00 6 11283 384 0 0
sp_cancel_customer_reserve 550278 550278 0 0.00 1 28273 99 0 0
sp_cancel_pay_from_customer 746 746 0 0.00 3 4635 103 0 0
sp_cancel_pay_to_supplier 404 404 0 0.00 4 20363 155 0 0
sp_cancel_supplier_invoice 6253 6247 6 0.09 2 23136 988 0 6
sp_cancel_supplier_order 417 417 0 0.00 10 1505892 167378 0 0
sp_cancel_write_off 10423 10423 0 0.00 2 20620 160 0 0
sp_client_order 20666 20666 0 0.00 6 40536 479 0 0
sp_customer_reserve 466778 466778 0 0.00 1 43261 237 0 0
sp_fill_shopping_cart 31793 31793 0 0.00 2 29498 81 0 0
sp_fill_shopping_cart_clo_res 466745 466745 0 0.00 0 12924 17 0 0
sp_get_clo_for_invoice 4985 4985 0 0.00 0 23055 272 0 0
sp_kill_cost_storno 17252 17252 0 0.00 0 20600 69 0 0
sp_kill_qstorno_ret_qs2qd 1117643 1117643 0 0.00 0 28212 43 0 0
sp_kill_qty_storno 589891 589891 0 0.00 0 37141 99 0 0
sp_lock_selected_doc 20987 20987 0 0.00 0 6939 6 0 0
sp_make_cost_storno 28181 28181 0 0.00 0 11617 76 0 0
sp_multiply_rows_for_pdistr 28181 28181 0 0.00 0 28499 36 0 0
sp_multiply_rows_for_qdistr 20666 20666 0 0.00 0 29392 180 0 0
sp_pay_from_customer 7138 7138 0 0.00 5 21048 166 0 0
sp_pay_to_supplier 5938 5938 0 0.00 6 28547 190 0 0
sp_payment_common 13076 13076 0 0.00 2 5735 39 0 0
sp_qd_handle_on_cancel_clo 1742 1742 0 0.00 1 11266 311 0 0
sp_qd_handle_on_invoice_upd_sts 10663 10663 0 0.00 0 37138 501 0 0
sp_qd_handle_on_reserve_upd_sts 10121 10121 0 0.00 0 18971 56 0 0
sp_reserve_write_off 10121 10121 0 0.00 8 18992 257 0 0
sp_supplier_invoice 5733 5733 0 0.00 10 129725 3195 0 0
sp_supplier_order 5396 5396 0 0.00 7 24925 1301 0 0
srv_aggregate_perf_data 109 109 0 0.00 2541 258146 17007 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 886 113115 36721 0 0
srv_find_qd_qs_mism 1054761 1054761 0 0.00 0 29363 10 0 0
srv_make_invnt_saldo 458 458 0 0.00 91 57108 4066 0 0
srv_make_money_saldo 866 866 0 0.00 1 18517 395 0 0
srv_recalc_idx_stat 4 4 0 0.00 89558 540648 278959 0 0
t$perf-abend:sp_add_invoice_to_stock 88 88 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5027 5027 0 0.00 0 16481 878 0 0
t$perf-norm:sp_cancel_adding_invoice 430 430 0 0.00 1 12954 420 0 0
t$perf-norm:sp_cancel_client_order 1742 1742 0 0.00 0 5371 34 0 0
t$perf-norm:sp_cancel_customer_reserve 692 692 0 0.00 0 1318 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 746 746 0 0.00 0 12864 36 0 0
t$perf-norm:sp_cancel_pay_to_supplier 404 404 0 0.00 0 960 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 411 411 0 0.00 0 644 33 0 0
t$perf-norm:sp_cancel_supplier_order 417 417 0 0.00 0 126210 6280 0 0
t$perf-norm:sp_cancel_write_off 1041 1041 0 0.00 0 2331 20 0 0
t$perf-norm:sp_client_order 20666 20666 0 0.00 0 10022 34 0 0
t$perf-norm:sp_customer_reserve 1027 1027 0 0.00 0 5910 19 0 0
t$perf-norm:sp_pay_from_customer 7138 7138 0 0.00 0 10844 20 0 0
t$perf-norm:sp_pay_to_supplier 5938 5938 0 0.00 0 6099 21 0 0
t$perf-norm:sp_reserve_write_off 10121 10121 0 0.00 0 12890 27 0 0
t$perf-norm:sp_supplier_invoice 5733 5733 0 0.00 0 4861 29 0 0
t$perf-norm:sp_supplier_order 5396 5396 0 0.00 0 1798 28 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 692 30 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 324 11 0 0
t$perf-norm:srv_make_invnt_saldo 458 458 0 0.00 0 1865 21 0 0
t$perf-norm:srv_make_money_saldo 866 866 0 0.00 0 1369 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 6 156 48 0 0
v_add_invoice_to_stock 10142 9966 176 1.73 0 19491 31 0 176
v_all_customers 38132 38132 0 0.00 0 99 3 0 0
v_all_suppliers 31174 31174 0 0.00 0 70 4 0 0
v_all_wares 41332 41332 0 0.00 0 141 4 0 0
v_cancel_adding_invoice 860 860 0 0.00 0 1990 15 0 0
v_cancel_client_order 3484 3484 0 0.00 0 10101 13 0 0
v_cancel_customer_prepayment 1492 1492 0 0.00 0 3065 11 0 0
v_cancel_customer_reserve 1384 1384 0 0.00 0 3432 12 0 0
v_cancel_payment_to_supplier 808 808 0 0.00 0 20260 37 0 0
v_cancel_supplier_invoice 834 822 12 1.43 0 20571 48 0 12
v_cancel_supplier_order 834 834 0 0.00 0 279 9 0 0
v_cancel_write_off 2082 2082 0 0.00 0 3057 24 0 0
v_max_id_clo_ord 5396 5396 0 0.00 0 724 5 0 0
v_max_id_clo_res 1027 1027 0 0.00 0 1834 13 0 0
v_max_id_ord_sup 5731 5731 0 0.00 0 7159 8 0 0
v_max_non_paid_invoice 1478 1478 0 0.00 0 1334 8 0 0
v_max_non_paid_realizn 3646 3646 0 0.00 0 1359 7 0 0
v_min_id_clo_ord 5396 5396 0 0.00 0 1235 8 0 0
v_min_id_clo_res 1027 1027 0 0.00 0 2532 14 0 0
v_min_id_ord_sup 5731 5731 0 0.00 0 11024 15 0 0
v_min_non_paid_invoice 5938 5938 0 0.00 0 4946 11 0 0
v_min_non_paid_realizn 7138 7138 0 0.00 0 2863 12 0 0
v_our_firm 10184 10184 0 0.00 0 96 4 0 0
v_reserve_write_off 20242 20242 0 0.00 0 14779 11 0 0
x_lock_depdocs_on_canc_invoice 11515 11515 0 0.00 0 15176 124 0 0
x_lock_depdocs_on_canc_sup_ord 417 417 0 0.00 0 12983 387 0 0
x_make_qstorno_1000_1200 5396 5396 0 0.00 2 24917 1135 0 0
x_make_qstorno_1200_2000 5733 5733 0 0.00 5 119133 2977 0 0
x_make_qstorno_2100_3300 466778 466778 0 0.00 0 43256 208 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-06-2022 04:38:04 to 10-06-2022 04:38:08.

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
02:07:21 2 8192000000 8796669024 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:22 27674 8192000000 9720656272 10018250752 796693680 8.863 100 86 172 0 825946976 7638208 21615088
02:09:54 8960 8192000000 9754841184 10060476416 831792240 9.217 100 85 170 0 863971248 11596416 20582592
02:11:09 19003 8192000000 9808920864 10130665472 874826688 9.648 100 81 162 1 910919968 17390128 18703152
02:12:31 12982 8192000000 9862873136 10198142976 923815680 10.134 100 78 156 0 972886640 31888768 17182192
02:13:48 6119 8192000000 9916485504 10261663744 964790176 10.536 100 81 159 0 1032361840 50948800 16622864
02:14:56 3902 8192000000 9972439072 10325745664 999564544 10.874 100 88 176 0 1091295408 73881296 17849568
02:16:04 1197 8192000000 9999414368 10364178432 1016571488 11.039 100 97 189 0 1115120112 79240880 19307744
02:17:07 1320 8192000000 10028184336 10395881472 1035999552 11.226 100 92 183 1 1146015280 91890912 18124816
02:18:09 1033 8192000000 10014939440 10388672512 1056190544 11.420 100 97 186 1 1136565408 61819760 18555104
02:19:11 1096 8192000000 10052068832 10430955520 1072062256 11.572 100 99 189 1 1174958448 84217648 18678544
02:20:13 896 8192000000 10044515920 10425122816 1070434400 11.556 100 90 177 0 1170327728 82331968 17561360
02:21:15 1006 8192000000 10021249280 10404483072 1073194880 11.583 100 84 165 2 1141808144 52366096 16247168
02:22:16 1114 8192000000 10035778912 10427277312 1078538000 11.634 100 88 171 2 1165493328 69550224 17405104
02:23:18 1088 8192000000 10055716496 10443431936 1083574256 11.682 100 95 181 1 1178366128 75744032 19047840
02:24:20 1075 8192000000 10036459904 10423836672 1084640080 11.692 100 93 182 1 1153319104 50821056 17857968
02:25:21 1398 8192000000 10052031856 10439000064 1092499088 11.766 100 91 173 0 1166817072 57148592 17169392
02:26:25 1996 8192000000 10043774448 10433323008 1089930848 11.742 100 82 163 0 1160784384 54029632 16823904
02:27:30 975 8192000000 10072823952 10460205056 1087737408 11.721 100 92 181 1 1198358240 91740960 18879872
02:28:32 1013 8192000000 10062098640 10449555456 1083644048 11.682 100 94 182 0 1184187536 81849008 18694480
02:29:34 1038 8192000000 10035024240 10426331136 1079917632 11.647 100 84 169 1 1156439088 60407904 16113552
02:30:38 1237 8192000000 10013258448 10392846336 1068754592 11.540 100 96 181 3 1135522592 47942064 18825936
02:31:40 1151 8192000000 10028024080 10411851776 1066190784 11.516 100 87 172 0 1149174608 66957280 16026544
02:32:42 1179 8192000000 10016278448 10391494656 1047553680 11.337 100 91 176 0 1141091680 76553072 16984928
02:33:44 1236 8192000000 9986214896 10359042048 1032489760 11.192 100 95 178 0 1112615200 62079936 18045504
02:34:46 1070 8192000000 10014372160 10385620992 1040401280 11.269 100 96 179 1 1134384048 75432464 18550304
02:35:48 1298 8192000000 10009958544 10386161664 1042650448 11.290 100 96 187 0 1131440096 70123280 18666368
02:36:50 870 8192000000 9975696912 10350075904 1036281568 11.229 100 89 170 0 1096758112 41702832 18773712
02:37:53 5346 8192000000 10002648912 10389999616 1056146144 11.420 100 71 134 1 1127518896 57247904 14124848
02:39:03 13721 8192000000 10056792176 10436730880 1086211696 11.707 100 91 171 1 1177457040 72722640 18522704
02:40:20 12514 8192000000 10077138384 10476638208 1090444672 11.747 100 57 111 0 1188497232 86723360 11329200
02:41:43 28318 8192000000 10108330016 10509639680 1112679744 11.958 100 61 119 0 1217233104 92341552 12211808
02:43:27 6933 8192000000 10145755264 10549055488 1122817872 12.054 100 70 136 1 1259005088 120919952 15267264
02:44:37 1351 8192000000 10198101456 10595799040 1142009968 12.234 100 93 179 1 1313829920 152899520 18920432
02:45:42 2803 8192000000 10197416064 10596249600 1150935120 12.318 100 77 148 1 1310895536 144555792 15404624
02:46:46 2442 8192000000 10225392976 10628030464 1158838944 12.392 100 90 165 1 1337337600 161245600 17253056
02:47:50 1732 8192000000 10262453664 10658996224 1166374528 12.463 100 95 182 3 1372559040 184466144 21718368
02:48:56 2210 8192000000 10275564576 10683330560 1176479056 12.557 100 93 174 0 1398139280 201451696 20208528
02:50:02 2032 8192000000 10348528144 10745462784 1189551008 12.679 100 91 176 0 1457247808 248620128 19076672
02:51:07 876 8192000000 10365702048 10772242432 1189290624 12.677 100 95 187 1 1478772784 267447936 22034224
02:52:12 1203 8192000000 10427572832 10833285120 1205862640 12.831 100 93 184 1 1538368336 311889616 20616080
02:53:16 968 8192000000 10458586048 10878185472 1208630672 12.856 100 92 176 1 1565374896 336821600 19922624
02:54:21 985 8192000000 10480779552 10890047488 1202373568 12.798 100 96 194 0 1587590768 362363552 22853648
02:55:27 913 8192000000 10531892672 10940145664 1207740400 12.848 100 92 184 1 1639912016 409370112 22801504
02:56:32 1125 8192000000 10566641536 10996318208 1202480176 12.799 100 98 195 2 1677207584 450665424 24061984
02:57:37 881 8192000000 10526439632 10991792128 1190722816 12.690 100 97 194 0 1629716320 415085488 23908016
02:58:39 946 8192000000 10568838016 11003027456 1195295616 12.733 100 97 187 0 1673049600 453829520 23924464
02:59:43 7926 8192000000 10536769504 10994290688 1191797184 12.700 100 59 115 0 1658777840 452228288 14752368
03:01:11 9015 8192000000 10583925152 11035201536 1192023120 12.702 100 51 100 3 1681626544 476164848 13438576
03:02:27 6348 8192000000 10623102640 11080896512 1201147216 12.787 100 59 112 1 1722536544 506364208 15025120
03:03:38 7378 8192000000 10647593136 11096768512 1204896816 12.822 100 60 119 1 1761080128 540464304 15719008
03:04:47 9874 8192000000 10600720208 11110195200 1195179968 12.732 100 47 92 0 1755774672 547700272 12894432
03:06:00 25381 8192000000 10517188144 11041849344 1190165920 12.685 100 37 74 0 1681951712 482149632 9636160
03:07:30 35321 8192000000 10545956048 11011559424 1172506640 12.520 99 29 58 0 1646404976 466624848 7273488
03:09:06 26145 8192000000 10321965312 10889240576 1158768896 12.392 100 21 42 0 1522306944 357846512 5691536
03:10:48 53426 8192000000 10167268864 10636124160 1113990144 11.970 97 17 34 1 1251068560 132585312 4493104
03:12:51 84692 8192000000 10112180640 10632384512 1117957104 12.008 98 15 30 0 1185907088 64777920 3172064
03:15:41 60040 8192000000 10061525264 10516918272 1108081184 11.914 98 17 34 1 1138745792 27282432 3382176
03:17:41 80938 8192000000 10060228688 10491990016 1098354320 11.822 96 29 55 1 1142559744 38352720 5852704
03:20:03 81311 8192000000 10094183040 10523156480 1104485376 11.880 97 34 64 2 1158258384 46108976 7664032
03:22:31 77151 8192000000 10100731936 10590359552 1118350224 12.011 99 18 36 1 1220134816 97832192 3952400
03:24:53 102522 8192000000 10096592144 10531827712 1114273040 11.973 98 20 40 0 1141475632 23167312 4035280
03:27:46 75436 8192000000 10084099952 10528665600 1125401216 12.078 99 30 60 0 1206908208 74373376 7133616
03:30:07 25853 8192000000 10078933776 10514923520 1124895760 12.073 99 31 58 0 1170746704 39573248 6277696
03:31:35 24594 8192000000 10107829632 10560032768 1145913120 12.271 100 20 40 1 1211987152 61630736 4443296
03:33:01 34718 8192000000 10131541328 10553987072 1103167360 11.868 97 33 66 0 1194863584 84287408 7408816
03:34:37 20787 8192000000 10234407280 10668662784 1135767392 12.176 100 55 107 1 1326630080 179261824 11600864
03:36:17 41006 8192000000 10103283792 10582978560 1104114944 11.877 98 16 32 0 1209704768 101762976 3826848
03:37:58 65588 8192000000 10095912816 10552369152 1111240912 11.944 98 14 26 1 1180748288 67021248 2486128
03:40:05 46695 8192000000 10105254000 10578857984 1132415792 12.144 100 9 18 0 1205396624 71212752 1768080
03:41:54 109958 8192000000 10093460464 10517729280 1072223328 11.573 94 27 52 1 1137335280 59254336 5857616
03:45:18 41254 8192000000 10078059392 10548772864 1120296544 12.030 99 23 46 0 1189075408 64022448 4756416
03:47:09 26946 8192000000 10097214384 10549399552 1127790768 12.101 99 32 64 0 1192292144 58355600 6145776
03:48:38 21424 8192000000 10099263536 10533998592 1132376336 12.144 100 39 76 1 1196004560 55942768 7685456
03:50:00 72334 8192000000 10102273584 10577391616 1067011392 11.524 94 15 30 1 1128869504 58737984 3120128
03:52:39 19860 8192000000 10123126560 10553802752 1118541072 12.013 99 53 103 4 1179909632 50372576 10995984
03:54:09 97116 8192000000 10111634608 10629910528 1116654592 11.995 98 9 18 0 1231251296 112586496 2010208
03:56:46 72359 8192000000 10064815184 10501300224 1092553344 11.767 97 29 56 0 1128745184 30440416 5751424
03:59:04 7853 8192000000 10096443568 10522914816 1128850688 12.111 100 49 90 3 1188639840 49700288 10088864
04:00:12 113115 8192000000 10109462320 10563874816 1094021424 11.781 96 17 34 0 1158314064 60556896 3735744
04:03:41 72855 8192000000 10108311760 10566955008 1126303264 12.086 99 17 34 1 1189352272 59518592 3530416
04:05:54 64204 8192000000 10141895120 10638692352 1141569680 12.230 100 9 18 0 1244404832 100423952 2411200
04:08:06 37187 8192000000 10029581584 10469597184 1059241680 11.449 94 19 35 0 1092645888 30014480 3389728
04:09:43 26876 8192000000 10062715024 10504179712 1127083248 12.094 100 36 72 1 1157218672 22725808 7409616
04:11:15 23415 8192000000 10092538464 10536103936 1113723280 11.968 99 47 94 1 1181536864 58015760 9797824
04:12:43 75381 8192000000 10126404544 10588786688 1129358528 12.115 99 31 59 2 1236901616 101490192 6052896
04:15:03 59789 8192000000 10133372384 10591600640 1121797296 12.044 98 23 45 1 1234932512 108192864 4942352
04:17:04 81121 8192000000 10156301184 10628435968 1133494656 12.154 99 18 36 1 1226578720 89084992 3999072
04:19:38 15086 8192000000 10093831280 10519171072 1122656096 12.052 98 41 82 3 1165146976 33996432 8494448
04:20:54 60909 8192000000 10127745184 10571296768 1133181328 12.151 100 58 117 0 1232098672 87315024 11602320
04:23:19 41711 8192000000 10064578512 10555817984 1107048416 11.904 98 11 19 2 1148890752 39920544 1921792
04:25:03 62409 8192000000 10085881904 10545037312 1139713808 12.213 100 10 20 0 1185875168 44325568 1835792
04:27:05 91385 8192000000 10080758688 10551775232 1118479696 12.013 98 6 12 0 1156033840 36298192 1255952
04:29:37 32993 8192000000 10107285248 10565877760 1141116208 12.226 100 24 47 2 1217922944 71435696 5371040
04:31:10 14706 8192000000 10152896400 10601857024 1133495168 12.154 99 38 76 1 1229360592 87263392 8602032
04:32:29 22735 8192000000 10150054192 10588258304 1137795008 12.195 100 54 103 0 1242645504 94056272 10794224
04:34:15 84878 8192000000 10154019936 10668703744 1077844080 11.627 94 31 62 1 1233492960 148763088 6885792
04:36:54 30864 8192000000 9232894496 9572057088 369006960 4.310 33 3 6 0 402698512 33158592 532960

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 10-06-2022 04:38:10 to 10-06-2022 04:38:26.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 88 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-06-2022 04:38:27 to 10-06-2022 04:38:29.

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          224559
MON$OLDEST_ACTIVE               224560
MON$OLDEST_SNAPSHOT             224560
MON$NEXT_TRANSACTION            224560
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                       2076832
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             783
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

10.06.22 04:38:30. 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-06-10 04:38:32 No severe PSQL-related problems occured
10.06.22 04:38:33. End of report.