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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16575460 kB
MemAvailable 31552648 kB
Buffers 0 kB
Cached 15145892 kB
SwapCached 0 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.1
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 2021-12-04 10:37:10
DTS_END                         2021-12-04 10:37:10.7480
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/44954
TRN_ID                          239663
ATT_ID                          650
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: 9049.70 24576 1085964
customer order: creation 172.90 374 20748
customer order: refuse 15.10 373 1812
order to supplier: creation 47.67 1353 5720
order to supplier: removal 3.03 124154 364
invoice (draft): creation 47.34 3691 5681
invoice (draft): removal 49.45 934 5934
invoice accept: apply 43.29 19431 5195
invoice accept: cancel 44.96 970 5395
customer reserve: creation 3950.95 194 474114
customer reserve: removal 4364.83 68 523779
realization accept: apply 87.53 181 10504
realization accept: cancel 89.24 135 10709
payment from customer: creation 61.33 111 7359
payment from customer: removal 5.76 78 691
payment to supplier: creation 51.41 112 6169
payment to supplier: removal 3.19 68 383
service: total inventory turnovers 4.11 3573 493
service: total monetary turnovers 6.58 594 790
service: aggregate perf. data 1.00 15013 120
service: refresh index statistics 0.03 320106 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 04-12-2021 10:38:44 to 04-12-2021 10:38:46.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8816
TEST_TIME 2 6326
TEST_TIME 3 5395
TEST_TIME 4 5367
TEST_TIME 5 5937
TEST_TIME 6 5338
TEST_TIME 7 5229
TEST_TIME 8 5124
TEST_TIME 9 5252
TEST_TIME 10 5422
TEST_TIME 11 5439
TEST_TIME 12 5778
TEST_TIME 13 6241
TEST_TIME 14 6267
TEST_TIME 15 6635
TEST_TIME 16 7207
TEST_TIME 17 7522
TEST_TIME 18 7640
TEST_TIME 19 7971
TEST_TIME 20 8458
TEST_TIME 21 8865
TEST_TIME 22 8910
TEST_TIME 23 9145
TEST_TIME 24 9168
TEST_TIME 25 9457
TEST_TIME 26 9835
TEST_TIME 27 9622
TEST_TIME 28 10143
TEST_TIME 29 10258
TEST_TIME 30 11128
TEST_TIME 31 11128
TEST_TIME 32 10972
TEST_TIME 33 11122
TEST_TIME 34 10926
TEST_TIME 35 10764
TEST_TIME 36 10796
TEST_TIME 37 10622
TEST_TIME 38 10393
TEST_TIME 39 10517
TEST_TIME 40 10445
TEST_TIME 41 10390
TEST_TIME 42 10237
TEST_TIME 43 10186
TEST_TIME 44 10262
TEST_TIME 45 10174
TEST_TIME 46 10076
TEST_TIME 47 10171
TEST_TIME 48 10176
TEST_TIME 49 10038
TEST_TIME 50 10115
TEST_TIME 51 10050
TEST_TIME 52 10068
TEST_TIME 53 10094
TEST_TIME 54 10076
TEST_TIME 55 10079
TEST_TIME 56 10010
TEST_TIME 57 10078
TEST_TIME 58 10019
TEST_TIME 59 10113
TEST_TIME 60 10131
TEST_TIME 61 10089
TEST_TIME 62 10071
TEST_TIME 63 9942
TEST_TIME 64 9952
TEST_TIME 65 9868
TEST_TIME 66 9816
TEST_TIME 67 9757
TEST_TIME 68 9687
TEST_TIME 69 9644
TEST_TIME 70 9559
TEST_TIME 71 9497
TEST_TIME 72 9590
TEST_TIME 73 9613
TEST_TIME 74 9612
TEST_TIME 75 9630
TEST_TIME 76 9627
TEST_TIME 77 9598
TEST_TIME 78 9615
TEST_TIME 79 9605
TEST_TIME 80 9634
TEST_TIME 81 9610
TEST_TIME 82 9643
TEST_TIME 83 9651
TEST_TIME 84 9738
TEST_TIME 85 9634
TEST_TIME 86 9534
TEST_TIME 87 9499
TEST_TIME 88 9479
TEST_TIME 89 9428
TEST_TIME 90 9384
TEST_TIME 91 9311
TEST_TIME 92 9326
TEST_TIME 93 9273
TEST_TIME 94 9249
TEST_TIME 95 9258
TEST_TIME 96 9221
TEST_TIME 97 9217
TEST_TIME 98 9264
TEST_TIME 99 9270
TEST_TIME 100 9375
TEST_TIME 101 9404
TEST_TIME 102 9446
TEST_TIME 103 9429
TEST_TIME 104 9407
TEST_TIME 105 9485
TEST_TIME 106 9461
TEST_TIME 107 9409
TEST_TIME 108 9417
TEST_TIME 109 9340
TEST_TIME 110 9332
TEST_TIME 111 9286
TEST_TIME 112 9264
TEST_TIME 113 9221
TEST_TIME 114 9231
TEST_TIME 115 9206
TEST_TIME 116 9185
TEST_TIME 117 9169
TEST_TIME 118 9145
TEST_TIME 119 9117
TEST_TIME 120 9051

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 04-12-2021 10:38:48 to 04-12-2021 10:38:51.

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 1523564 1523564 0 0.00 0 23267 7 0 0
doc_list_biud 1523564 1523564 0 0.00 0 70170 30 0 0
sp_add_invoice_to_stock 5306 5195 111 2.09 33 203657 19432 0 111
sp_cancel_adding_invoice 5395 5395 0 0.00 6 63765 971 0 0
sp_cancel_client_order 1812 1812 0 0.00 5 11847 374 0 0
sp_cancel_customer_reserve 523779 523779 0 0.00 1 29528 69 0 0
sp_cancel_pay_from_customer 691 691 0 0.00 3 4633 78 0 0
sp_cancel_pay_to_supplier 383 383 0 0.00 3 1739 68 0 0
sp_cancel_supplier_invoice 5941 5934 7 0.11 3 70175 935 0 7
sp_cancel_supplier_order 364 364 0 0.00 7 1318678 124155 0 0
sp_cancel_write_off 10709 10709 0 0.00 1 10395 135 0 0
sp_client_order 20748 20748 0 0.00 4 26841 374 0 0
sp_customer_reserve 474114 474114 0 0.00 0 94590 195 0 0
sp_fill_shopping_cart 32136 32136 0 0.00 1 25789 46 0 0
sp_fill_shopping_cart_clo_res 474112 474112 0 0.00 0 19139 15 0 0
sp_get_clo_for_invoice 5192 5192 0 0.00 0 38385 311 0 0
sp_kill_cost_storno 17178 17178 0 0.00 0 7121 62 0 0
sp_kill_qstorno_ret_qs2qd 1064565 1064565 0 0.00 0 70168 37 0 0
sp_kill_qty_storno 563689 563689 0 0.00 0 70170 80 0 0
sp_lock_selected_doc 21594 21594 0 0.00 0 10918 5 0 0
sp_make_cost_storno 29224 29224 0 0.00 0 23263 55 0 0
sp_multiply_rows_for_pdistr 29224 29224 0 0.00 0 16159 31 0 0
sp_multiply_rows_for_qdistr 20748 20748 0 0.00 0 15169 208 0 0
sp_pay_from_customer 7359 7359 0 0.00 4 12298 112 0 0
sp_pay_to_supplier 6169 6169 0 0.00 5 11257 112 0 0
sp_payment_common 13528 13528 0 0.00 1 7610 18 0 0
sp_qd_handle_on_cancel_clo 1812 1812 0 0.00 0 10756 343 0 0
sp_qd_handle_on_invoice_upd_sts 10587 10587 0 0.00 0 36242 386 0 0
sp_qd_handle_on_reserve_upd_sts 10504 10504 0 0.00 0 13214 53 0 0
sp_reserve_write_off 10504 10504 0 0.00 7 30512 182 0 0
sp_supplier_invoice 5681 5681 0 0.00 7 127069 3691 0 0
sp_supplier_order 5720 5720 0 0.00 5 71201 1353 0 0
srv_aggregate_perf_data 120 120 0 0.00 2662 261191 15014 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 936 173481 42045 0 0
srv_find_qd_qs_mism 1036333 1036333 0 0.00 0 21365 7 0 0
srv_make_invnt_saldo 493 493 0 0.00 30 120707 3573 0 0
srv_make_money_saldo 790 790 0 0.00 1 17426 595 0 0
srv_recalc_idx_stat 4 4 0 0.00 207356 459454 320106 0 0
t$perf-abend:sp_add_invoice_to_stock 111 111 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5195 5195 0 0.00 0 27501 193 0 0
t$perf-norm:sp_cancel_adding_invoice 442 442 0 0.00 1 3715 105 0 0
t$perf-norm:sp_cancel_client_order 1812 1812 0 0.00 0 4733 12 0 0
t$perf-norm:sp_cancel_customer_reserve 721 721 0 0.00 0 2265 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 691 691 0 0.00 0 5162 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 383 383 0 0.00 0 1883 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 458 458 0 0.00 0 848 16 0 0
t$perf-norm:sp_cancel_supplier_order 364 364 0 0.00 0 32799 1818 0 0
t$perf-norm:sp_cancel_write_off 1027 1027 0 0.00 0 3906 9 0 0
t$perf-norm:sp_client_order 20748 20748 0 0.00 0 26851 19 0 0
t$perf-norm:sp_customer_reserve 1062 1062 0 0.00 0 1234 5 0 0
t$perf-norm:sp_pay_from_customer 7359 7359 0 0.00 0 5004 9 0 0
t$perf-norm:sp_pay_to_supplier 6169 6169 0 0.00 0 4867 9 0 0
t$perf-norm:sp_reserve_write_off 10504 10504 0 0.00 0 4851 9 0 0
t$perf-norm:sp_supplier_invoice 5681 5681 0 0.00 0 8037 20 0 0
t$perf-norm:sp_supplier_order 5720 5720 0 0.00 0 2207 17 0 0
t$perf-norm:srv_aggregate_perf_data 120 120 0 0.00 0 485 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 41 1 0 0
t$perf-norm:srv_make_invnt_saldo 493 493 0 0.00 0 1131 11 0 0
t$perf-norm:srv_make_money_saldo 790 790 0 0.00 0 3401 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 26 8 0 0
v_add_invoice_to_stock 10606 10384 222 2.09 0 3820 15 0 222
v_all_customers 37996 37996 0 0.00 0 24 1 0 0
v_all_suppliers 31612 31612 0 0.00 0 28 1 0 0
v_all_wares 41494 41494 0 0.00 0 37 1 0 0
v_cancel_adding_invoice 884 884 0 0.00 0 547 8 0 0
v_cancel_client_order 3624 3624 0 0.00 0 2290 4 0 0
v_cancel_customer_prepayment 1382 1382 0 0.00 0 981 5 0 0
v_cancel_customer_reserve 1442 1442 0 0.00 0 1948 6 0 0
v_cancel_payment_to_supplier 766 766 0 0.00 0 503 5 0 0
v_cancel_supplier_invoice 930 916 14 1.50 0 185 12 0 14
v_cancel_supplier_order 728 728 0 0.00 0 816 4 0 0
v_cancel_write_off 2054 2054 0 0.00 0 1596 17 0 0
v_max_id_clo_ord 5720 5720 0 0.00 0 2490 2 0 0
v_max_id_clo_res 1062 1062 0 0.00 0 4746 19 0 0
v_max_id_ord_sup 5669 5669 0 0.00 0 6108 4 0 0
v_max_non_paid_invoice 1752 1752 0 0.00 0 1300 3 0 0
v_max_non_paid_realizn 3934 3934 0 0.00 0 1865 3 0 0
v_min_id_clo_ord 5720 5720 0 0.00 0 5111 10 0 0
v_min_id_clo_res 1062 1062 0 0.00 0 8865 25 0 0
v_min_id_ord_sup 5669 5669 0 0.00 0 4755 9 0 0
v_min_non_paid_invoice 6169 6169 0 0.00 0 4702 10 0 0
v_min_non_paid_realizn 7359 7359 0 0.00 0 7601 13 0 0
v_our_firm 10348 10348 0 0.00 0 27 1 0 0
v_reserve_write_off 21008 21008 0 0.00 0 3005 5 0 0
x_lock_depdocs_on_canc_invoice 10871 10871 0 0.00 0 15542 112 0 0
x_lock_depdocs_on_canc_sup_ord 364 364 0 0.00 0 4344 365 0 0
x_make_qstorno_1000_1200 5720 5720 0 0.00 2 71127 1254 0 0
x_make_qstorno_1200_2000 5681 5681 0 0.00 4 122479 3533 0 0
x_make_qstorno_2100_3300 474114 474114 0 0.00 0 93961 177 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-12-2021 10:38:52 to 04-12-2021 10:38:56.

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:14 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:15 32046 8192000000 9725670864 10026815488 804077008 8.938 100 87 174 0 832399920 7493232 20829680
08:09:51 15584 8192000000 9769410160 10077736960 846459472 9.365 100 90 180 0 880939456 13539088 20940896
08:11:12 23344 8192000000 9819497584 10137018368 875226080 9.652 100 87 174 0 914148176 18466800 20455296
08:12:43 15785 8192000000 9875783760 10209783808 918745568 10.084 100 75 150 0 983482656 48614496 16122592
08:14:04 8816 8192000000 9919364800 10264039424 963462048 10.523 100 85 170 0 1037377136 55665888 18249200
08:15:16 4173 8192000000 9982793872 10337902592 1000838016 10.887 100 80 159 0 1095079792 77895456 16346320
08:16:21 4204 8192000000 9988464240 10349371392 1025390256 11.124 100 76 153 1 1104060560 63435360 15234944
08:17:28 3232 8192000000 10025044464 10393513984 1051200992 11.372 100 83 164 1 1131045984 63473744 16371248
08:18:33 1697 8192000000 10037777168 10408501248 1066789200 11.521 100 90 180 0 1142281904 57274368 18218336
08:19:36 23877 8192000000 9996995360 10389725184 1072970896 11.580 100 58 117 3 1115051680 29396800 12683984
08:21:01 6170 8192000000 10026251744 10425049088 1082617728 11.672 100 38 75 0 1144675920 54369088 7689104
08:22:09 6947 8192000000 9994449568 10385944576 1090027952 11.743 100 37 73 0 1114847952 15300576 9519424
08:23:17 6141 8192000000 9993222480 10394435584 1096104688 11.801 100 36 66 2 1125924896 21956096 7864112
08:24:23 42724 8192000000 9992542720 10406404096 1086348672 11.708 99 27 52 1 1120162720 27943888 5870160
08:26:07 2580 8192000000 10033248640 10422890496 1100600576 11.843 100 57 114 1 1135691744 22039168 13052000
08:27:11 10516 8192000000 10017480016 10429329408 1091648240 11.758 99 34 68 0 1123235360 24067136 7519984
08:28:22 14264 8192000000 9975076304 10371887104 1044453760 11.307 96 29 56 0 1074016480 22750592 6812128
08:29:38 25616 8192000000 9960507632 10370375680 1010801168 10.983 95 17 34 2 1044131472 29064912 4265392
08:31:05 7184 8192000000 9959373152 10347905024 1021006800 11.082 98 34 68 0 1054357136 25653616 7696720
08:32:13 5390 8192000000 9927477568 10297057280 988723824 10.769 97 30 60 2 1010948192 15384912 6839456
08:33:21 7702 8192000000 9965057632 10353885184 1037894576 11.244 100 23 46 1 1070831408 27796256 5140576
08:34:32 31880 8192000000 9959159168 10365538304 1050669072 11.367 100 15 28 2 1070071456 15988448 3413936
08:36:06 6611 8192000000 9966101872 10363621376 1066542144 11.519 100 35 68 0 1083614112 8919376 8152592
08:37:15 6034 8192000000 9987671360 10380849152 1075275088 11.602 100 43 86 1 1096313232 11870736 9167408
08:38:34 2606 8192000000 10040183120 10427162624 1087472272 11.719 100 75 150 0 1139075312 35567008 16036032
08:39:39 64916 8192000000 10042740032 10452406272 1096874048 11.808 100 45 85 1 1155422576 49285552 9262976
08:41:53 19278 8192000000 10101292800 10509488128 1112570368 11.957 100 41 80 0 1204177536 82973312 8633856
08:43:20 35602 8192000000 10143499648 10542895104 1130676816 12.128 100 43 86 0 1224043488 83793920 9572752
08:45:07 6355 8192000000 10174679568 10579902464 1144607232 12.259 100 44 87 1 1278074944 123459040 10008672
08:46:16 24568 8192000000 10233919360 10655010816 1159583424 12.399 100 36 72 1 1350411648 182857584 7970640
08:47:44 4452 8192000000 10274446192 10680295424 1181987344 12.609 100 67 133 2 1386150112 189650512 14512256
08:48:54 6707 8192000000 10339859568 10738896896 1181343936 12.603 100 84 169 0 1435547008 236047600 18155472
08:50:07 3279 8192000000 10346268752 10750124032 1192347712 12.705 100 69 136 3 1447964224 240731760 14884752
08:51:16 1582 8192000000 10364413872 10770726912 1184215744 12.629 100 68 133 0 1470584800 271416752 14952304
08:52:25 2512 8192000000 10394631936 10796290048 1158972816 12.394 100 80 159 2 1488708208 311833968 17901424
08:53:32 2202 8192000000 10389159552 10803822592 1162692288 12.428 100 89 177 2 1502339760 319476800 20170672
08:54:40 934 8192000000 10393967984 10803118080 1158266128 12.387 100 85 170 1 1492752704 314414336 20072240
08:55:46 3134 8192000000 10370799936 10769772544 1136265520 12.180 99 72 144 0 1463974144 310942576 16766048
08:56:56 2138 8192000000 10392603936 10799484928 1151234048 12.321 100 83 165 2 1498761888 327751488 19776352
08:58:02 1335 8192000000 10325694096 10758656000 1135272816 12.171 100 74 148 1 1420448288 267706784 17468688
08:59:09 12620 8192000000 10335135920 10752708608 1132545056 12.145 100 46 92 2 1431065904 287475568 11045280
09:00:27 3096 8192000000 10353209232 10780348416 1137603456 12.193 100 62 124 2 1449341280 296124144 15613680
09:01:33 20812 8192000000 10375192240 10824794112 1108331024 11.917 97 36 72 4 1431995328 314488688 9175616
09:02:56 18565 8192000000 10382124144 10806702080 1110720528 11.939 97 28 56 0 1439854176 322028480 7105168
09:04:18 20683 8192000000 10342160816 10776764416 1127545568 12.098 100 28 56 0 1430036128 295920816 6569744
09:06:06 35903 8192000000 10296832192 10743799808 1118204160 12.010 99 12 24 2 1371118768 249665088 3249520
09:08:05 53343 8192000000 10098521728 10525515776 1133347552 12.153 100 23 46 0 1182745392 44813424 4584416
09:10:08 72428 8192000000 10043696192 10491437056 1096683824 11.806 97 18 37 1 1146028288 45352400 3992064
09:12:22 74608 8192000000 10156158864 10597064704 1141726480 12.232 100 23 46 0 1250873248 104378560 4768208
09:14:38 85034 8192000000 10178089424 10616098816 1138219568 12.199 100 50 100 1 1259304400 109547696 11537136
09:17:12 66875 8192000000 10078031696 10494963712 1115827728 11.988 98 58 108 1 1150890592 23877904 11184960
09:19:21 31397 8192000000 10133949904 10557894656 1127564256 12.098 98 51 102 0 1212280896 73929552 10787088
09:21:10 39290 8192000000 10097905744 10584657920 1115835424 11.988 98 11 22 0 1190269376 71670768 2763184
09:22:50 55147 8192000000 10153918752 10598219776 1149287072 12.303 100 23 46 2 1248565968 94364320 4914576
09:24:46 58319 8192000000 10086709904 10567905280 1098546064 11.824 96 14 28 1 1183151968 81415568 3190336
09:26:45 48018 8192000000 10119857936 10596151296 1143538496 12.249 100 10 20 0 1218823184 73305152 1979536
09:28:34 44571 8192000000 10086429424 10560688128 1125096640 12.075 98 14 29 0 1184317280 56635408 2585232
09:30:22 21412 8192000000 10096242784 10551582720 1134533424 12.164 99 50 97 0 1183131184 38705840 9891920
09:31:46 44082 8192000000 10072521136 10511208448 1134375552 12.163 99 31 60 0 1196739088 56155072 6208464
09:33:35 66825 8192000000 10119563152 10565378048 1130281840 12.124 99 23 46 1 1211927184 76935024 4710320
09:36:16 83002 8192000000 10062674768 10550349824 1119902432 12.026 99 17 34 1 1171553776 48357856 3293488
09:38:40 26265 8192000000 10101065312 10525241344 1119375792 12.021 98 34 68 1 1172750176 46338960 7035424
09:40:09 126697 8192000000 10037165360 10495877120 1064653808 11.501 95 34 69 0 1140236000 68409440 7172752
09:43:17 20751 8192000000 10057565760 10510745600 1094825904 11.789 99 25 50 0 1164443920 64360976 5257040
09:44:39 56029 8192000000 10078979440 10535358464 1097633824 11.815 98 18 36 2 1176392880 74409008 4350048
09:46:40 55683 8192000000 10095215408 10544734208 1101804544 11.855 97 19 38 0 1189491712 83134304 4552864
09:48:49 48557 8192000000 10117378400 10597580800 1118470208 12.013 99 9 18 1 1209832288 89689680 1672400
09:50:38 44026 8192000000 10050322144 10487066624 1046268336 11.325 93 15 29 0 1085063360 35923872 2871152
09:52:23 58465 8192000000 10063202960 10519355392 1112265392 11.954 99 20 40 0 1164857232 48500464 4091376
09:54:27 22948 8192000000 10083911872 10509185024 1116542672 11.994 99 44 83 0 1167944848 42012464 9389712
09:55:52 60222 8192000000 10070387488 10493382656 1098234960 11.821 97 28 56 1 1142243344 38602272 5406112
09:57:53 42989 8192000000 10078123584 10514198528 1097230640 11.811 97 41 82 1 1158153776 53121680 7801456
09:59:44 35268 8192000000 10085971248 10515435520 1111013680 11.942 98 34 69 0 1160022112 42498288 6510144
10:01:19 140926 8192000000 10058787456 10505334784 1143179392 12.245 100 12 24 0 1202772656 57084784 2508480
10:04:47 28649 8192000000 10071043552 10537496576 1117379168 12.002 98 11 22 0 1170113248 50002176 2731904
10:06:52 47678 8192000000 10061341728 10501525504 1095458656 11.795 96 15 30 0 1122119664 23316048 3344960
10:08:54 73139 8192000000 10063428800 10549686272 1120478128 12.032 98 12 24 0 1176287328 53462432 2346768
10:11:08 41427 8192000000 10123122448 10570493952 1144776560 12.260 100 25 50 0 1213242224 63318496 5147168
10:13:04 37121 8192000000 10118086688 10560708608 1139349856 12.209 99 21 42 0 1209763920 65804816 4609248
10:14:41 80767 8192000000 10184449232 10692476928 1160391760 12.407 100 25 50 0 1281460624 115558272 5510592
10:17:25 25957 8192000000 10092206784 10551951360 1148304704 12.294 99 13 25 0 1179465728 28840736 2320288
10:18:54 105425 8192000000 10098262032 10583171072 1140297168 12.218 98 8 16 0 1202695392 60998736 1399488
10:21:40 57229 8192000000 10106906528 10604146688 1142815696 12.242 99 16 32 0 1212064224 66151584 3096944
10:23:54 173481 8192000000 10060700112 10516877312 1069841728 11.551 93 25 50 0 1138001760 62820240 5339792
10:27:58 41122 8192000000 10049641072 10496212992 1088728816 11.731 97 8 16 0 1137456416 47110304 1617296
10:29:55 30768 8192000000 10064313856 10533863424 1101239552 11.849 98 16 33 0 1152464624 47885568 3339504
10:31:42 65884 8192000000 10052288464 10507665408 1119485472 12.022 99 11 22 1 1145019984 22873856 2660656
10:33:48 33935 8192000000 10101065664 10539642880 1132996096 12.150 99 20 40 0 1180227648 42913056 4318496
10:35:28 49741 8192000000 10134362864 10570051584 1117566416 12.004 97 30 60 0 1202244352 77901568 6776368

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 04-12-2021 10:38:58 to 04-12-2021 10:39:13.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 111 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-12-2021 10:39:14 to 04-12-2021 10:39:16.

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          234967
MON$OLDEST_ACTIVE               240631
MON$OLDEST_SNAPSHOT             240631
MON$NEXT_TRANSACTION            240631
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                       2057760
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             810
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2675 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2675 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2675 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2675 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 ---
:31: 
:32: mscs-test-161.budzdorov.ru    Sat Dec  4 10:37:20 2021
:33:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:34: 
--- end of diff output ---

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

04.12.21 10:39:17. 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
2021-12-04 10:39:19 No severe PSQL-related problems occured
04.12.21 10:39:20. End of report.