Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.07.2022 07:48
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 15961416 kB
MemAvailable 31097892 kB
Buffers 0 kB
Cached 15754020 kB
SwapCached 2492 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-07-16 07:47:43
DTS_END                         2022-07-16 07:47:43.3750
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/39536
TRN_ID                          212577
ATT_ID                          612
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: 8928.75 29621 1071450
customer order: creation 159.37 489 19124
customer order: refuse 13.64 417 1637
order to supplier: creation 43.80 1407 5256
order to supplier: removal 3.40 167219 408
invoice (draft): creation 43.27 3229 5192
invoice (draft): removal 52.23 918 6268
invoice accept: apply 39.38 26507 4725
invoice accept: cancel 46.78 1052 5614
customer reserve: creation 3646.52 252 437582
customer reserve: removal 4598.67 97 551840
realization accept: apply 77.73 272 9328
realization accept: cancel 82.31 145 9877
payment from customer: creation 54.84 174 6581
payment from customer: removal 5.67 136 680
payment to supplier: creation 45.60 219 5472
payment to supplier: removal 3.25 131 390
service: total inventory turnovers 4.08 3676 490
service: total monetary turnovers 7.25 355 870
service: aggregate perf. data 0.93 13390 112
service: refresh index statistics 0.03 372335 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 16-07-2022 07:49:09 to 16-07-2022 07:49:12.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4061
TEST_TIME 2 6203
TEST_TIME 3 6229
TEST_TIME 4 5529
TEST_TIME 5 5337
TEST_TIME 6 5542
TEST_TIME 7 5386
TEST_TIME 8 5731
TEST_TIME 9 5865
TEST_TIME 10 5846
TEST_TIME 11 5959
TEST_TIME 12 5943
TEST_TIME 13 6023
TEST_TIME 14 6029
TEST_TIME 15 6337
TEST_TIME 16 6283
TEST_TIME 17 6537
TEST_TIME 18 6655
TEST_TIME 19 6820
TEST_TIME 20 6992
TEST_TIME 21 7302
TEST_TIME 22 7634
TEST_TIME 23 7553
TEST_TIME 24 8094
TEST_TIME 25 8712
TEST_TIME 26 9107
TEST_TIME 27 9895
TEST_TIME 28 9952
TEST_TIME 29 10322
TEST_TIME 30 10789
TEST_TIME 31 10667
TEST_TIME 32 10365
TEST_TIME 33 10207
TEST_TIME 34 10029
TEST_TIME 35 9916
TEST_TIME 36 9808
TEST_TIME 37 9657
TEST_TIME 38 9578
TEST_TIME 39 9490
TEST_TIME 40 9384
TEST_TIME 41 9332
TEST_TIME 42 9185
TEST_TIME 43 9615
TEST_TIME 44 9402
TEST_TIME 45 9230
TEST_TIME 46 9208
TEST_TIME 47 9154
TEST_TIME 48 9061
TEST_TIME 49 9183
TEST_TIME 50 9147
TEST_TIME 51 9082
TEST_TIME 52 9077
TEST_TIME 53 9176
TEST_TIME 54 9163
TEST_TIME 55 9276
TEST_TIME 56 9254
TEST_TIME 57 9201
TEST_TIME 58 9229
TEST_TIME 59 9124
TEST_TIME 60 8982
TEST_TIME 61 9018
TEST_TIME 62 8972
TEST_TIME 63 8930
TEST_TIME 64 8834
TEST_TIME 65 8849
TEST_TIME 66 8838
TEST_TIME 67 8894
TEST_TIME 68 8857
TEST_TIME 69 8831
TEST_TIME 70 8849
TEST_TIME 71 8865
TEST_TIME 72 8953
TEST_TIME 73 8907
TEST_TIME 74 8876
TEST_TIME 75 8982
TEST_TIME 76 8983
TEST_TIME 77 8942
TEST_TIME 78 9117
TEST_TIME 79 9135
TEST_TIME 80 9150
TEST_TIME 81 9085
TEST_TIME 82 8977
TEST_TIME 83 9004
TEST_TIME 84 8977
TEST_TIME 85 8926
TEST_TIME 86 8875
TEST_TIME 87 8846
TEST_TIME 88 8813
TEST_TIME 89 8788
TEST_TIME 90 8744
TEST_TIME 91 8708
TEST_TIME 92 8723
TEST_TIME 93 8718
TEST_TIME 94 8754
TEST_TIME 95 8715
TEST_TIME 96 8699
TEST_TIME 97 8689
TEST_TIME 98 8683
TEST_TIME 99 8698
TEST_TIME 100 8725
TEST_TIME 101 8765
TEST_TIME 102 8726
TEST_TIME 103 8675
TEST_TIME 104 8777
TEST_TIME 105 8889
TEST_TIME 106 9109
TEST_TIME 107 9070
TEST_TIME 108 9030
TEST_TIME 109 9030
TEST_TIME 110 9035
TEST_TIME 111 9102
TEST_TIME 112 9153
TEST_TIME 113 9123
TEST_TIME 114 9054
TEST_TIME 115 9012
TEST_TIME 116 8978
TEST_TIME 117 9022
TEST_TIME 118 8990
TEST_TIME 119 9004
TEST_TIME 120 8951

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-07-2022 07:49:13 to 16-07-2022 07:49:16.

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 1473866 1473866 0 0.00 0 22216 13 0 0
doc_list_biud 1473856 1473856 0 0.00 0 86250 42 0 0
sp_add_invoice_to_stock 4831 4725 106 2.19 49 238062 26508 0 106
sp_cancel_adding_invoice 5614 5614 0 0.00 7 92788 1052 0 0
sp_cancel_client_order 1637 1637 0 0.00 5 26886 417 0 0
sp_cancel_customer_reserve 551840 551840 0 0.00 1 45708 98 0 0
sp_cancel_pay_from_customer 680 680 0 0.00 4 10033 136 0 0
sp_cancel_pay_to_supplier 390 390 0 0.00 3 6164 132 0 0
sp_cancel_supplier_invoice 6280 6268 12 0.19 3 86254 919 0 12
sp_cancel_supplier_order 408 408 0 0.00 14 1421837 167220 0 0
sp_cancel_write_off 9877 9877 0 0.00 2 11244 146 0 0
sp_client_order 19124 19124 0 0.00 6 35076 490 0 0
sp_customer_reserve 437582 437582 0 0.00 0 90395 252 0 0
sp_fill_shopping_cart 29566 29566 0 0.00 1 15452 83 0 0
sp_fill_shopping_cart_clo_res 437549 437549 0 0.00 0 14334 19 0 0
sp_get_clo_for_invoice 4679 4679 0 0.00 0 36165 349 0 0
sp_kill_cost_storno 16561 16561 0 0.00 0 7507 62 0 0
sp_kill_qstorno_ret_qs2qd 1120233 1120233 0 0.00 0 86249 43 0 0
sp_kill_qty_storno 589651 589651 0 0.00 0 86249 98 0 0
sp_lock_selected_doc 19528 19528 0 0.00 0 7475 8 0 0
sp_make_cost_storno 26060 26060 0 0.00 0 22213 83 0 0
sp_multiply_rows_for_pdistr 26060 26060 0 0.00 0 12235 38 0 0
sp_multiply_rows_for_qdistr 19123 19123 0 0.00 0 15957 194 0 0
sp_pay_from_customer 6581 6581 0 0.00 5 22235 175 0 0
sp_pay_to_supplier 5472 5472 0 0.00 6 13230 220 0 0
sp_payment_common 12053 12053 0 0.00 2 9605 48 0 0
sp_qd_handle_on_cancel_clo 1637 1637 0 0.00 0 26872 344 0 0
sp_qd_handle_on_invoice_upd_sts 10293 10293 0 0.00 0 52850 521 0 0
sp_qd_handle_on_reserve_upd_sts 9328 9328 0 0.00 0 17108 65 0 0
sp_reserve_write_off 9328 9328 0 0.00 8 19313 272 0 0
sp_supplier_invoice 5192 5192 0 0.00 9 99163 3230 0 0
sp_supplier_order 5256 5256 0 0.00 8 75855 1407 0 0
srv_aggregate_perf_data 112 112 0 0.00 2312 126735 13390 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 818 153128 39931 0 0
srv_find_qd_qs_mism 1025207 1025207 0 0.00 0 17234 10 0 0
srv_make_invnt_saldo 490 490 0 0.00 45 75102 3676 0 0
srv_make_money_saldo 870 870 0 0.00 1 11618 356 0 0
srv_recalc_idx_stat 4 4 0 0.00 240212 536763 372336 0 0
t$perf-abend:sp_add_invoice_to_stock 106 106 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4727 4727 0 0.00 1 17022 896 0 0
t$perf-norm:sp_cancel_adding_invoice 360 360 0 0.00 1 5899 405 0 0
t$perf-norm:sp_cancel_client_order 1637 1637 0 0.00 0 27079 59 0 0
t$perf-norm:sp_cancel_customer_reserve 667 667 0 0.00 0 3504 34 0 0
t$perf-norm:sp_cancel_pay_from_customer 680 680 0 0.00 0 2188 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 390 390 0 0.00 0 268 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 399 399 0 0.00 0 1790 36 0 0
t$perf-norm:sp_cancel_supplier_order 408 408 0 0.00 0 85516 6182 0 0
t$perf-norm:sp_cancel_write_off 985 985 0 0.00 0 1412 24 0 0
t$perf-norm:sp_client_order 19124 19124 0 0.00 0 8634 35 0 0
t$perf-norm:sp_customer_reserve 956 956 0 0.00 0 609 14 0 0
t$perf-norm:sp_pay_from_customer 6581 6581 0 0.00 0 5260 18 0 0
t$perf-norm:sp_pay_to_supplier 5472 5472 0 0.00 0 24604 25 0 0
t$perf-norm:sp_reserve_write_off 9328 9328 0 0.00 0 5323 22 0 0
t$perf-norm:sp_supplier_invoice 5192 5192 0 0.00 0 7282 36 0 0
t$perf-norm:sp_supplier_order 5256 5256 0 0.00 0 5409 30 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 495 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 448 10 0 0
t$perf-norm:srv_make_invnt_saldo 490 490 0 0.00 0 578 14 0 0
t$perf-norm:srv_make_money_saldo 870 870 0 0.00 0 4392 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 193 69 0 0
v_add_invoice_to_stock 9560 9348 212 2.21 0 3827 26 0 212
v_all_customers 34768 34768 0 0.00 0 67 3 0 0
v_all_suppliers 28806 28806 0 0.00 0 72 4 0 0
v_all_wares 38246 38246 0 0.00 0 96 4 0 0
v_cancel_adding_invoice 720 720 0 0.00 0 135 13 0 0
v_cancel_client_order 3274 3274 0 0.00 0 2600 9 0 0
v_cancel_customer_prepayment 1360 1360 0 0.00 0 9999 20 0 0
v_cancel_customer_reserve 1334 1334 0 0.00 1 1838 11 0 0
v_cancel_payment_to_supplier 780 780 0 0.00 0 2416 17 0 0
v_cancel_supplier_invoice 822 798 24 2.91 0 1888 26 0 24
v_cancel_supplier_order 816 816 0 0.00 0 3768 14 0 0
v_cancel_write_off 1970 1970 0 0.00 0 5119 28 0 0
v_max_id_clo_ord 5253 5253 0 0.00 0 581 5 0 0
v_max_id_clo_res 956 956 0 0.00 0 1044 13 0 0
v_max_id_ord_sup 5189 5189 0 0.00 0 9515 10 0 0
v_max_non_paid_invoice 1511 1511 0 0.00 0 1081 8 0 0
v_max_non_paid_realizn 3551 3551 0 0.00 0 1802 8 0 0
v_min_id_clo_ord 5253 5253 0 0.00 0 4493 10 0 0
v_min_id_clo_res 956 956 0 0.00 0 3862 13 0 0
v_min_id_ord_sup 5189 5189 0 0.00 0 3752 12 0 0
v_min_non_paid_invoice 5472 5472 0 0.00 0 8137 22 0 0
v_min_non_paid_realizn 6581 6581 0 0.00 0 5511 18 0 0
v_our_firm 9538 9538 0 0.00 0 61 4 0 0
v_reserve_write_off 18656 18656 0 0.00 0 4235 11 0 0
x_lock_depdocs_on_canc_invoice 11483 11483 0 0.00 0 18565 117 0 0
x_lock_depdocs_on_canc_sup_ord 408 408 0 0.00 0 4855 327 0 0
x_make_qstorno_1000_1200 5256 5256 0 0.00 2 75779 1246 0 0
x_make_qstorno_1200_2000 5192 5192 0 0.00 4 99132 2993 0 0
x_make_qstorno_2100_3300 437581 437581 0 0.00 0 90392 221 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-07-2022 07:49:17 to 16-07-2022 07:49:21.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:17:47 1 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
05:18:48 12072 8192000000 9714292080 10011705344 801046144 8.907 100 85 170 0 829232688 7873216 20313328
05:20:06 23350 8192000000 9760395120 10069762048 834945072 9.249 100 86 172 0 867486848 12157776 20384000
05:21:32 10921 8192000000 9795458432 10110750720 866844432 9.569 100 86 171 0 904781024 17985744 19950848
05:22:45 9656 8192000000 9852590176 10180046848 897934848 9.878 100 86 172 0 958288864 40817536 19536480
05:23:59 4883 8192000000 9898551520 10239959040 941830464 10.311 100 82 158 0 1010067392 50542448 17694480
05:25:08 2837 8192000000 9940375232 10289999872 985402064 10.737 100 91 179 2 1056159424 52306080 18451280
05:26:16 1401 8192000000 9986405344 10342367232 1012577136 11.000 100 94 180 0 1105184112 74617120 17989856
05:27:19 1075 8192000000 10016610608 10379845632 1032456912 11.192 100 95 185 1 1138520000 87602576 18460512
05:28:21 1437 8192000000 10031985328 10407239680 1047236224 11.334 100 94 179 1 1149734960 84206048 18292688
05:29:25 1017 8192000000 10039383936 10412134400 1056343104 11.421 100 97 190 1 1159860944 84404608 19113232
05:30:29 1291 8192000000 10053197712 10432229376 1064850688 11.503 100 93 181 0 1173761120 91134800 17775632
05:31:32 1415 8192000000 10034718832 10415726592 1073667216 11.587 100 86 166 0 1159153248 69275520 16210512
05:32:34 671 8192000000 10063552544 10447568896 1081218448 11.659 100 95 183 0 1182355728 83637904 17499376
05:33:36 1388 8192000000 10048599936 10433167360 1083550992 11.681 100 93 183 0 1171758496 68285600 19921904
05:34:39 987 8192000000 10063957296 10453164032 1087630416 11.720 100 96 188 2 1184911984 78253888 19027680
05:35:41 1049 8192000000 10073560736 10460655616 1088719392 11.730 100 96 183 1 1196342288 88221760 19401136
05:36:43 1261 8192000000 10068039168 10454716416 1094164304 11.782 100 92 179 2 1188512368 74563904 19784160
05:37:46 938 8192000000 10057957328 10443681792 1095404944 11.794 100 86 166 0 1178550560 66063248 17082368
05:38:48 911 8192000000 10053946128 10444677120 1092614528 11.768 100 88 167 1 1175417776 65593040 17210208
05:39:50 1163 8192000000 10055430384 10438557696 1081459568 11.661 100 94 178 0 1176653888 75110144 20084176
05:40:52 1481 8192000000 10046418640 10434416640 1071496816 11.566 99 96 185 2 1171168368 81660064 18011488
05:41:54 1111 8192000000 10065119600 10447441920 1077196800 11.621 100 96 175 0 1188037200 91021008 19819392
05:42:57 1081 8192000000 10008619504 10390634496 1055554400 11.414 100 77 145 2 1133412848 63480688 14377760
05:43:59 11700 8192000000 9974555888 10366013440 1008802960 10.964 97 55 103 3 1067967216 48562304 10601952
05:45:12 6594 8192000000 9988145312 10364719104 1026240768 11.132 99 69 139 2 1101289952 61438592 13610592
05:46:20 4530 8192000000 9984985824 10364014592 1044046560 11.304 100 68 130 3 1106406224 49452304 12907360
05:47:26 2543 8192000000 9994763248 10379444224 1046570576 11.328 100 69 135 1 1123093824 61155296 15367952
05:48:35 14837 8192000000 10001071280 10389270528 1056460912 11.423 100 61 122 0 1120625136 52219104 11945120
05:49:54 1200 8192000000 10044611376 10426060800 1070769840 11.559 100 93 180 0 1166602176 76703312 19129024
05:51:02 12158 8192000000 10063586720 10452860928 1082697424 11.673 100 66 129 1 1181112272 85243568 13171280
05:52:20 2490 8192000000 10082980752 10470936576 1097235648 11.811 100 71 138 0 1199543536 87861872 14446016
05:53:44 3538 8192000000 10141151424 10535698432 1111034736 11.942 100 70 136 1 1257434640 131264992 15134912
05:55:07 1990 8192000000 10196576640 10589753344 1126332672 12.087 100 89 171 2 1310159104 165677440 18148992
05:56:13 1023 8192000000 10231537696 10627702784 1146409936 12.276 100 87 157 2 1345423008 182650448 16362624
05:57:18 1280 8192000000 10274165520 10665668608 1162656992 12.428 100 88 175 0 1384738592 203343008 18738592
05:58:25 3795 8192000000 10262289504 10667151360 1160678048 12.410 99 67 130 0 1380119776 204456912 14984816
05:59:33 1094 8192000000 10340454144 10740744192 1182363728 12.612 100 98 190 1 1454043520 249515584 22164208
06:00:40 2297 8192000000 10387622752 10791256064 1193794544 12.719 100 79 156 0 1503467168 291642896 18029728
06:01:47 806 8192000000 10427348480 10851545088 1203215488 12.806 100 94 176 0 1536860448 313577728 20067232
06:02:49 1237 8192000000 10464181008 10870173696 1202264448 12.797 100 95 179 0 1572828544 348392672 22171424
06:03:51 1298 8192000000 10502325488 10928291840 1191010768 12.693 99 96 185 2 1609863136 396166688 22685680
06:04:56 923 8192000000 10532167664 10943041536 1193247360 12.714 100 96 192 3 1640424176 424104496 23072320
06:05:59 1009 8192000000 10559911552 10986008576 1199489200 12.772 100 88 172 0 1670048288 450556368 20002720
06:07:03 1080 8192000000 10581815984 11011411968 1189459408 12.678 100 98 188 1 1684000176 472170480 22370288
06:08:06 936 8192000000 10567310128 10992513024 1170636576 12.503 100 98 194 1 1675001184 479692976 24671632
06:09:12 1530 8192000000 10521584544 10976669696 1154054816 12.348 99 93 180 2 1624026368 447879840 22091712
06:10:16 2362 8192000000 10486592096 10941296640 1150255200 12.312 100 73 142 0 1586668128 418872256 17540672
06:11:22 5837 8192000000 10492573808 10973200384 1143742736 12.251 100 60 113 3 1609152960 450389248 15020976
06:12:30 25407 8192000000 10416238112 10867281920 1147937408 12.290 100 46 90 1 1535835712 376054816 11843488
06:14:23 39276 8192000000 10205740848 10698620928 1107163616 11.906 97 17 34 0 1324462448 212687872 4610960
06:16:21 65145 8192000000 10117050608 10650304512 1097922688 11.818 97 10 20 0 1205581104 105553456 2104960
06:18:27 59850 8192000000 10045244896 10469302272 1112058144 11.952 99 65 116 0 1144658768 17704432 14896192
06:20:28 53886 8192000000 10055923936 10498633728 1103398384 11.870 99 39 78 0 1163326096 52153312 7774400
06:22:27 83007 8192000000 10057487872 10530062336 1112330832 11.954 99 18 37 0 1161545472 45361072 3853568
06:25:14 41998 8192000000 10068978784 10520166400 1126413968 12.088 100 18 35 0 1166576880 36263568 3899344
06:26:57 85212 8192000000 10119335408 10563817472 1130148608 12.123 99 25 46 1 1202314832 66940512 5225712
06:29:23 108436 8192000000 10113493024 10729779200 1152761872 12.335 100 22 44 0 1273819424 115992576 5064976
06:32:13 33965 8192000000 10101495392 10647429120 1135911872 12.177 99 47 88 2 1208322432 63486960 8923600
06:33:48 26450 8192000000 10081821824 10529468416 1131471600 12.135 99 25 49 0 1181327296 45023152 4832544
06:35:46 33843 8192000000 10103358016 10560802816 1152002528 12.328 100 29 57 0 1196978864 39045872 5930464
06:37:25 46691 8192000000 10115078944 10581008384 1158783168 12.392 100 14 28 0 1216871408 54451584 3636656
06:39:16 65017 8192000000 10147347536 10627145728 1126896032 12.092 98 22 40 1 1255206368 123183680 5126656
06:41:31 109902 8192000000 10072070608 10528301056 1097914512 11.818 96 11 22 0 1138548336 38461952 2171872
06:44:21 8552 8192000000 10110012544 10536005632 1142762704 12.242 100 55 108 0 1197839488 43995744 11081040
06:45:34 57710 8192000000 10128287264 10579558400 1124924832 12.073 98 34 68 0 1200690384 68772800 6992752
06:48:02 64531 8192000000 10108943136 10575953920 1151088016 12.320 100 20 38 0 1225875936 70478880 4309040
06:50:09 135362 8192000000 10136567520 10607017984 1134747664 12.166 98 18 36 0 1225539104 86825712 3965728
06:53:25 56947 8192000000 10095098704 10580340736 1125588592 12.080 98 25 46 1 1199179424 69252976 4337856
06:55:26 47309 8192000000 10110568272 10549841920 1135048480 12.169 99 30 58 0 1181983456 40960496 5974480
06:57:32 66392 8192000000 10099544768 10553294848 1133714768 12.156 99 18 34 1 1223962640 86188752 4059120
06:59:40 54875 8192000000 10067661200 10495406080 1064448112 11.499 94 28 49 0 1106745264 36896672 5400480
07:01:38 30573 8192000000 10082726032 10536845312 1070097456 11.553 97 41 82 1 1167719952 89146896 8475600
07:03:13 57324 8192000000 10102516624 10541957120 1091321360 11.755 98 28 54 1 1187538416 90462880 5754176
07:05:11 85026 8192000000 10086357072 10525646848 1094806880 11.788 99 31 61 0 1155275008 53872720 6595408
07:07:37 153128 8192000000 10067017376 10526793728 1107049584 11.904 99 21 36 1 1164113616 53284224 3779808
07:11:35 47316 8192000000 10019282864 10469011456 1105758208 11.892 99 12 24 0 1127627504 19165936 2703360
07:13:26 59670 8192000000 10068117024 10517000192 1117825184 12.006 99 10 20 0 1158390784 38781136 1784464
07:15:30 77384 8192000000 10056718048 10550304768 1129525472 12.117 100 9 18 0 1182700752 51426960 1748320
07:17:48 58306 8192000000 10106528448 10549981184 1108519040 11.918 97 24 48 0 1178088368 64200384 5368944
07:19:48 54736 8192000000 10120219792 10565799936 1117947376 12.008 98 38 75 0 1202427664 76528688 7951600
07:21:54 23439 8192000000 10152650080 10591367168 1145942336 12.271 100 29 58 0 1253358144 100990416 6425392
07:23:21 27655 8192000000 10188966752 10616377344 1129712672 12.119 98 44 88 0 1267677568 128240448 9724448
07:24:53 17607 8192000000 10227602592 10655145984 1138587296 12.202 99 43 86 0 1309549488 161132416 9829776
07:26:15 25883 8192000000 10312726048 10746806272 1163753616 12.438 100 46 92 0 1389577616 214675600 11148400
07:27:41 26817 8192000000 10289593152 10735038464 1159401568 12.398 99 38 75 0 1362136912 194366672 8368672
07:29:13 18590 8192000000 10288610192 10719924224 1167334080 12.472 100 42 80 1 1386516256 209925760 9256416
07:30:34 27319 8192000000 10368761568 10827796480 1166278400 12.462 99 56 110 0 1461682864 282722256 12682208
07:32:42 24968 8192000000 10248003952 10854838272 1160389936 12.407 100 13 26 0 1385897184 222325904 3181344
07:34:08 24269 8192000000 10093605552 10532261888 1132054416 12.141 98 37 70 3 1167768832 28808032 6906384
07:35:32 25229 8192000000 10135896096 10571067392 1152983312 12.337 100 56 107 2 1227880496 63012096 11885088
07:37:00 98620 8192000000 10165548352 10652246016 1148088160 12.292 99 32 62 0 1251520080 96964336 6467584
07:39:41 46850 8192000000 10085432304 10510716928 1127227904 12.095 98 38 63 2 1171695600 37638624 6829072
07:41:29 144446 8192000000 10125869664 10585944064 1120933600 12.036 97 29 56 0 1210869952 84041760 5894592
07:46:25 87492 8192000000 9410187808 9755123712 526062784 6.034 46 8 16 0 548922224 20905072 1954368

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 16-07-2022 07:49:23 to 16-07-2022 07:49:39.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 106 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-07-2022 07:49:40 to 16-07-2022 07:49:42.

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          214264
MON$OLDEST_ACTIVE               214265
MON$OLDEST_SNAPSHOT             214265
MON$NEXT_TRANSACTION            214265
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                       2048736
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             762
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2799 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2799 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2799 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2799 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Sat Jul 16 07:48:00 2022
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
--- end of diff output ---

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

16.07.22 07:49:43. 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-07-16 07:49:45 No severe PSQL-related problems occured
16.07.22 07:49:47. End of report.