Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.09.2021 16: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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 16010312 kB
MemAvailable 30751776 kB
Buffers 0 kB
Cached 15663672 kB
SwapCached 3196 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 3.0.8
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-09-26 16:37:50
DTS_END                         2021-09-26 16:37:50.9740
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(57:17)
IP                              ::1/41874
TRN_ID                          249561
ATT_ID                          721
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: 9215.92 18780 1105911
customer order: creation 181.28 343 21753
customer order: refuse 14.93 357 1791
order to supplier: creation 46.98 1261 5637
order to supplier: removal 3.30 108156 396
invoice (draft): creation 47.40 3587 5688
invoice (draft): removal 52.15 876 6258
invoice accept: apply 42.91 18333 5149
invoice accept: cancel 47.21 859 5665
customer reserve: creation 3845.58 186 461470
customer reserve: removal 4624.57 59 554948
realization accept: apply 87.58 198 10509
realization accept: cancel 86.47 116 10376
payment from customer: creation 62.23 113 7467
payment from customer: removal 5.85 113 702
payment to supplier: creation 51.63 144 6196
payment to supplier: removal 3.58 57 430
service: total inventory turnovers 4.68 1358 562
service: total monetary turnovers 6.73 55 807
service: aggregate perf. data 0.86 20314 103
service: refresh index statistics 0.03 219113 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-09-2021 16:38:40 to 26-09-2021 16:38:42.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1508
TEST_TIME 2 3403
TEST_TIME 3 3454
TEST_TIME 4 4195
TEST_TIME 5 5388
TEST_TIME 6 5650
TEST_TIME 7 5519
TEST_TIME 8 5630
TEST_TIME 9 6133
TEST_TIME 10 6244
TEST_TIME 11 6135
TEST_TIME 12 6417
TEST_TIME 13 6365
TEST_TIME 14 6598
TEST_TIME 15 7038
TEST_TIME 16 7128
TEST_TIME 17 7151
TEST_TIME 18 7123
TEST_TIME 19 7425
TEST_TIME 20 7654
TEST_TIME 21 7787
TEST_TIME 22 7881
TEST_TIME 23 8371
TEST_TIME 24 8702
TEST_TIME 25 9212
TEST_TIME 26 9423
TEST_TIME 27 9167
TEST_TIME 28 9535
TEST_TIME 29 10189
TEST_TIME 30 10837
TEST_TIME 31 10844
TEST_TIME 32 10768
TEST_TIME 33 10583
TEST_TIME 34 10422
TEST_TIME 35 10239
TEST_TIME 36 10197
TEST_TIME 37 10250
TEST_TIME 38 10001
TEST_TIME 39 9841
TEST_TIME 40 9796
TEST_TIME 41 9737
TEST_TIME 42 9840
TEST_TIME 43 9688
TEST_TIME 44 9595
TEST_TIME 45 9501
TEST_TIME 46 9455
TEST_TIME 47 9470
TEST_TIME 48 9414
TEST_TIME 49 9420
TEST_TIME 50 9456
TEST_TIME 51 9369
TEST_TIME 52 9265
TEST_TIME 53 9321
TEST_TIME 54 9233
TEST_TIME 55 9176
TEST_TIME 56 9239
TEST_TIME 57 9271
TEST_TIME 58 9284
TEST_TIME 59 9284
TEST_TIME 60 9305
TEST_TIME 61 9370
TEST_TIME 62 9351
TEST_TIME 63 9368
TEST_TIME 64 9385
TEST_TIME 65 9373
TEST_TIME 66 9401
TEST_TIME 67 9413
TEST_TIME 68 9462
TEST_TIME 69 9431
TEST_TIME 70 9377
TEST_TIME 71 9299
TEST_TIME 72 9272
TEST_TIME 73 9209
TEST_TIME 74 9202
TEST_TIME 75 9238
TEST_TIME 76 9199
TEST_TIME 77 9220
TEST_TIME 78 9196
TEST_TIME 79 9171
TEST_TIME 80 9128
TEST_TIME 81 9070
TEST_TIME 82 9025
TEST_TIME 83 9056
TEST_TIME 84 9002
TEST_TIME 85 8992
TEST_TIME 86 8984
TEST_TIME 87 9006
TEST_TIME 88 9018
TEST_TIME 89 9033
TEST_TIME 90 9025
TEST_TIME 91 9043
TEST_TIME 92 9151
TEST_TIME 93 9147
TEST_TIME 94 9143
TEST_TIME 95 9148
TEST_TIME 96 9213
TEST_TIME 97 9200
TEST_TIME 98 9283
TEST_TIME 99 9290
TEST_TIME 100 9311
TEST_TIME 101 9236
TEST_TIME 102 9189
TEST_TIME 103 9143
TEST_TIME 104 9166
TEST_TIME 105 9145
TEST_TIME 106 9188
TEST_TIME 107 9152
TEST_TIME 108 9105
TEST_TIME 109 9067
TEST_TIME 110 9009
TEST_TIME 111 9059
TEST_TIME 112 9068
TEST_TIME 113 9032
TEST_TIME 114 9087
TEST_TIME 115 9075
TEST_TIME 116 9072
TEST_TIME 117 9067
TEST_TIME 118 9040
TEST_TIME 119 9202
TEST_TIME 120 9219

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 26-09-2021 16:38:43 to 26-09-2021 16:38:47.

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 1533758 1533758 0 0.00 0 16028 7 0 0
doc_list_biud 1533756 1533756 0 0.00 0 54678 28 0 0
sp_add_invoice_to_stock 5315 5149 166 3.12 50 277426 18334 0 166
sp_cancel_adding_invoice 5665 5665 0 0.00 6 107105 859 0 0
sp_cancel_client_order 1791 1791 0 0.00 4 10618 358 0 0
sp_cancel_customer_reserve 554948 554948 0 0.00 1 54679 60 0 0
sp_cancel_pay_from_customer 702 702 0 0.00 3 13079 114 0 0
sp_cancel_pay_to_supplier 430 430 0 0.00 3 2076 58 0 0
sp_cancel_supplier_invoice 6266 6258 8 0.12 2 22015 877 0 8
sp_cancel_supplier_order 396 396 0 0.00 7 1012162 108156 0 0
sp_cancel_write_off 10376 10376 0 0.00 1 11400 117 0 0
sp_client_order 21753 21753 0 0.00 3 26469 344 0 0
sp_customer_reserve 461470 461470 0 0.00 0 147764 187 0 0
sp_fill_shopping_cart 33076 33076 0 0.00 1 16680 48 0 0
sp_fill_shopping_cart_clo_res 461465 461465 0 0.00 0 14852 14 0 0
sp_get_clo_for_invoice 5144 5144 0 0.00 0 29162 338 0 0
sp_kill_cost_storno 17173 17173 0 0.00 0 7335 61 0 0
sp_kill_qstorno_ret_qs2qd 1126926 1126926 0 0.00 0 54675 33 0 0
sp_kill_qty_storno 595087 595087 0 0.00 0 54677 71 0 0
sp_lock_selected_doc 21625 21625 0 0.00 0 9890 5 0 0
sp_make_cost_storno 29316 29316 0 0.00 0 14856 64 0 0
sp_multiply_rows_for_pdistr 29316 29316 0 0.00 0 8728 30 0 0
sp_multiply_rows_for_qdistr 21753 21753 0 0.00 0 26460 148 0 0
sp_pay_from_customer 7467 7467 0 0.00 4 16285 113 0 0
sp_pay_to_supplier 6196 6196 0 0.00 5 14834 144 0 0
sp_payment_common 13662 13662 0 0.00 1 16270 40 0 0
sp_qd_handle_on_cancel_clo 1791 1791 0 0.00 0 10604 323 0 0
sp_qd_handle_on_invoice_upd_sts 10809 10809 0 0.00 0 20179 334 0 0
sp_qd_handle_on_reserve_upd_sts 10509 10509 0 0.00 0 11518 61 0 0
sp_reserve_write_off 10509 10509 0 0.00 6 14259 198 0 0
sp_supplier_invoice 5688 5688 0 0.00 7 80189 3588 0 0
sp_supplier_order 5637 5637 0 0.00 5 38896 1261 0 0
srv_aggregate_perf_data 103 103 0 0.00 2541 298506 20315 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 3025 142717 48385 0 0
srv_find_qd_qs_mism 1057028 1057028 0 0.00 0 16026 6 0 0
srv_make_invnt_saldo 562 562 0 0.00 24 151806 1358 0 0
srv_make_money_saldo 807 807 0 0.00 1 17044 56 0 0
srv_recalc_idx_stat 4 4 0 0.00 86801 325502 219114 0 0
t$perf-abend:sp_add_invoice_to_stock 166 166 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5149 5149 0 0.00 0 21484 158 0 0
t$perf-norm:sp_cancel_adding_invoice 434 434 0 0.00 0 2753 88 0 0
t$perf-norm:sp_cancel_client_order 1791 1791 0 0.00 0 2980 14 0 0
t$perf-norm:sp_cancel_customer_reserve 748 748 0 0.00 0 2575 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 702 702 0 0.00 0 13504 27 0 0
t$perf-norm:sp_cancel_pay_to_supplier 430 430 0 0.00 0 3664 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 416 416 0 0.00 0 1834 13 0 0
t$perf-norm:sp_cancel_supplier_order 396 396 0 0.00 0 26364 1785 0 0
t$perf-norm:sp_cancel_write_off 1055 1055 0 0.00 0 3808 19 0 0
t$perf-norm:sp_client_order 21753 21753 0 0.00 0 13879 18 0 0
t$perf-norm:sp_customer_reserve 1016 1016 0 0.00 0 3187 12 0 0
t$perf-norm:sp_pay_from_customer 7467 7467 0 0.00 0 18743 14 0 0
t$perf-norm:sp_pay_to_supplier 6196 6196 0 0.00 0 5520 10 0 0
t$perf-norm:sp_reserve_write_off 10509 10509 0 0.00 0 9217 11 0 0
t$perf-norm:sp_supplier_invoice 5688 5688 0 0.00 0 5186 19 0 0
t$perf-norm:sp_supplier_order 5637 5637 0 0.00 0 4765 15 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 104 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 8 1 0 0
t$perf-norm:srv_make_invnt_saldo 562 562 0 0.00 0 1270 22 0 0
t$perf-norm:srv_make_money_saldo 807 807 0 0.00 0 1122 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10620 10288 332 3.12 0 7403 18 0 332
v_all_customers 39344 39344 0 0.00 0 34 1 0 0
v_all_suppliers 31464 31464 0 0.00 0 23 1 0 0
v_all_wares 43506 43506 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 868 868 0 0.00 0 577 6 0 0
v_cancel_client_order 3582 3582 0 0.00 0 5750 10 0 0
v_cancel_customer_prepayment 1404 1404 0 0.00 0 8729 9 0 0
v_cancel_customer_reserve 1496 1496 0 0.00 0 1812 6 0 0
v_cancel_payment_to_supplier 860 860 0 0.00 0 1488 5 0 0
v_cancel_supplier_invoice 848 832 16 1.88 0 1175 15 0 16
v_cancel_supplier_order 792 792 0 0.00 0 4786 11 0 0
v_cancel_write_off 2110 2110 0 0.00 0 4348 18 0 0
v_max_id_clo_ord 5637 5637 0 0.00 0 16655 6 0 0
v_max_id_clo_res 1016 1016 0 0.00 0 717 8 0 0
v_max_id_ord_sup 5686 5686 0 0.00 0 13507 9 0 0
v_max_non_paid_invoice 1787 1787 0 0.00 0 457 3 0 0
v_max_non_paid_realizn 4101 4101 0 0.00 0 16263 8 0 0
v_min_id_clo_ord 5637 5637 0 0.00 0 13381 16 0 0
v_min_id_clo_res 1016 1016 0 0.00 0 5413 19 0 0
v_min_id_ord_sup 5686 5686 0 0.00 0 6462 11 0 0
v_min_non_paid_invoice 6196 6196 0 0.00 0 14176 29 0 0
v_min_non_paid_realizn 7466 7466 0 0.00 0 9672 25 0 0
v_our_firm 10892 10892 0 0.00 0 19 1 0 0
v_reserve_write_off 21018 21018 0 0.00 0 8047 6 0 0
x_lock_depdocs_on_canc_invoice 11507 11507 0 0.00 0 19891 128 0 0
x_lock_depdocs_on_canc_sup_ord 396 396 0 0.00 0 2958 328 0 0
x_make_qstorno_1000_1200 5637 5637 0 0.00 2 38877 1165 0 0
x_make_qstorno_1200_2000 5688 5688 0 0.00 3 80120 3410 0 0
x_make_qstorno_2100_3300 461470 461470 0 0.00 0 147063 170 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-09-2021 16:38:48 to 26-09-2021 16:38:52.

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
14:07:54 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
14:08:56 24464 8192000000 10363031888 10680262656 1417115424 14.747 100 89 178 0 1452327392 5893152 29318816
14:10:25 15684 8192000000 10415128672 10741248000 1460621312 15.131 100 86 172 1 1497067472 8374544 28071616
14:11:46 16597 8192000000 10451935568 10784354304 1507622080 15.543 100 84 168 1 1543916896 9689264 26605552
14:13:07 14416 8192000000 10550901520 10907512832 1603768624 16.372 100 73 146 0 1636644128 10585328 22290176
14:14:25 7058 8192000000 10639469008 11008565248 1697066256 17.161 100 79 157 2 1731806256 11851120 22888880
14:15:35 6011 8192000000 10706314832 11086233600 1770536672 17.771 100 76 151 1 1804347744 12222960 21588112
14:16:44 9009 8192000000 10754126144 11141451776 1819403392 18.173 100 81 162 1 1856742480 15197744 22141344
14:17:55 2647 8192000000 10810179120 11196661760 1861867088 18.518 100 88 174 0 1900956224 14872976 24216160
14:19:00 1162 8192000000 10833273936 11222683648 1887235504 18.723 100 95 190 2 1927254592 13327216 26691872
14:20:01 12176 8192000000 10821231504 11231690752 1891872368 18.761 100 64 128 3 1919239536 8004288 19362880
14:21:16 7528 8192000000 10805641952 11239022592 1895748864 18.792 100 21 42 2 1911955840 10477472 5729504
14:22:25 10261 8192000000 10810907136 11243708416 1903952864 18.858 100 25 48 1 1917295216 6012768 7329584
14:23:36 72825 8192000000 10873043248 11275939840 1921964304 19.003 100 72 143 3 1948576112 5289520 21322288
14:25:50 22914 8192000000 10828654672 11267059712 1919650000 18.984 100 28 54 1 1933528256 5425216 8453040
14:27:13 5545 8192000000 10819112000 11247984640 1873564464 18.613 98 23 46 1 1886388064 6409152 6414448
14:28:20 16286 8192000000 10737173888 11158622208 1734596848 17.474 92 11 22 1 1742189936 4781344 2811744
14:29:38 24778 8192000000 10750510464 11180929024 1811586608 18.109 98 35 70 1 1827235728 5790016 9859104
14:31:03 10378 8192000000 10705531344 11120775168 1764029376 17.718 98 24 48 2 1776666048 6111744 6524928
14:32:13 4750 8192000000 10735676896 11149160448 1813598624 18.125 100 27 54 1 1827070672 5279024 8193024
14:33:18 67775 8192000000 10737340448 11172225024 1803534016 18.043 98 22 44 0 1815485440 4836336 7115088
14:35:26 7845 8192000000 10775458608 11201822720 1863231680 18.529 100 34 69 1 1879608624 4796032 11580912
14:36:37 6936 8192000000 10774132432 11211894784 1870724688 18.590 100 29 58 1 1885353296 5621792 9006816
14:37:46 28474 8192000000 10827694656 11249389568 1902593808 18.847 100 30 57 2 1915428352 4264944 8569600
14:39:47 3654 8192000000 10878461312 11287953408 1931757968 19.081 100 66 129 2 1961605360 11332048 18515344
14:40:56 20523 8192000000 10878262176 11306303488 1943457536 19.174 100 53 106 1 1975584640 17361456 14765648
14:42:20 11555 8192000000 10896300464 11332288512 1939811312 19.145 99 50 100 0 1970958320 16435952 14711056
14:43:37 12006 8192000000 10895667568 11324968960 1942145776 19.164 99 55 108 0 1970524848 12757584 15621488
14:44:53 6210 8192000000 10919308000 11344531456 1967710880 19.367 100 62 122 1 2003349584 17674512 17964192
14:46:06 4446 8192000000 10936944880 11356459008 1979412480 19.460 100 66 128 0 2017599152 19259872 18926800
14:47:14 3022 8192000000 10907283216 11323531264 1921605296 19.000 98 79 158 1 1967277136 22462640 23209200
14:48:24 7829 8192000000 10912067840 11338752000 1959463024 19.302 100 56 112 1 2001623696 25225776 16934896
14:49:35 3028 8192000000 10937302832 11366674432 1980860448 19.472 100 61 121 2 2028858896 28779056 19219392
14:50:40 9832 8192000000 10952502944 11386810368 1990946784 19.551 100 56 111 0 2043797536 35403840 17446912
14:51:55 7133 8192000000 10947644784 11365470208 1974735056 19.423 100 72 142 1 2032501408 36805632 20960720
14:53:12 5738 8192000000 10943952176 11358580736 1956472608 19.278 99 63 126 3 2010690480 34500144 19717728
14:54:22 3232 8192000000 10928386192 11338452992 1948162368 19.212 100 83 166 2 2010967712 37132784 25672560
14:55:32 3042 8192000000 10940766608 11349909504 1953167920 19.252 100 83 166 0 2023634256 44504656 25961680
14:56:42 7016 8192000000 10969492352 11379539968 1973229360 19.411 100 82 163 4 2050793328 52376480 25187488
14:57:57 3873 8192000000 10990541184 11404042240 1991959312 19.559 100 77 153 1 2067377312 51551328 23866672
14:59:07 3939 8192000000 10976727504 11401330688 1977438352 19.444 100 62 124 0 2058816816 61129040 20249424
15:00:21 16902 8192000000 10950552528 11391406080 1961695968 19.320 100 35 70 0 2036106240 62114752 12295520
15:01:55 16086 8192000000 10938252544 11391188992 1962002704 19.322 100 34 68 0 2031174800 57734512 11437584
15:03:15 19852 8192000000 10950158320 11397099520 1955400560 19.269 99 26 52 2 2011144960 47534416 8209984
15:04:40 28948 8192000000 10950537104 11399282688 1961725056 19.320 100 23 46 2 2031091952 60302640 9064256
15:06:24 55896 8192000000 10924584800 11361173504 1907966032 18.890 98 28 51 1 1974352512 57449472 8937008
15:08:20 20489 8192000000 10899894336 11345858560 1945313008 19.189 99 35 70 0 1968985712 13601008 10071696
15:09:43 28872 8192000000 10919427680 11337306112 1957969920 19.290 100 62 121 4 1981172016 5094480 18107616
15:11:14 53797 8192000000 10893956176 11358953472 1958821264 19.297 100 10 20 0 1978365376 16940112 2604000
15:13:08 64085 8192000000 10892986496 11326590976 1933437520 19.094 99 23 46 1 1952506560 11755360 7313680
15:15:13 86381 8192000000 10882122544 11339350016 1941097360 19.156 99 24 48 1 1956266032 8512896 6655776
15:17:44 85190 8192000000 10904689072 11371065344 1973450768 19.413 100 29 56 0 1994074832 12659856 7964208
15:20:16 36994 8192000000 10914447408 11367919616 1982133280 19.482 100 49 99 1 2010542704 13808576 14600848
15:21:58 118597 8192000000 10910736272 11366010880 1962708288 19.328 99 33 66 1 1988286528 15825856 9752384
15:24:57 35079 8192000000 10918815264 11376189440 1983554928 19.493 100 47 93 1 2013772976 15931568 14286480
15:26:41 61836 8192000000 10872050176 11343273984 1938763488 19.137 98 11 22 0 1961152400 18938416 3450496
15:28:43 79209 8192000000 10902728880 11372974080 1979326768 19.459 100 27 54 0 1996363920 9130416 7906736
15:31:12 109432 8192000000 10918903088 11382661120 1973203152 19.411 100 6 12 0 1998528192 23410688 1914352
15:34:52 56140 8192000000 10835352720 11301687296 1860139728 18.504 95 15 30 0 1880169072 15913952 4115392
15:36:48 33769 8192000000 10878217312 11331928064 1913508912 18.935 98 24 48 1 1930544848 10464624 6571312
15:38:26 142717 8192000000 10845108208 11308630016 1774353856 17.803 91 22 43 1 1797951936 17825808 5772272
15:41:55 102439 8192000000 10881016640 11338149888 1908261120 18.893 98 20 40 2 1924533312 11316512 4955680
15:44:39 138660 8192000000 10850643712 11319812096 1786411872 17.902 92 18 36 1 1812822960 22202416 4208672
15:47:59 118834 8192000000 10888665440 11336687616 1942968000 19.170 100 29 58 0 1963204544 11925200 8311344
15:51:05 61502 8192000000 10875014704 11341639680 1944446704 19.182 99 32 64 0 1977850992 24431120 8973168
15:53:09 37288 8192000000 10892172528 11357999104 1951897040 19.242 99 18 36 0 1971764016 14936816 4930160
15:55:04 79960 8192000000 10885384240 11356065792 1919862800 18.986 97 12 24 0 1930534480 7352400 3319280
15:57:28 57198 8192000000 10869321168 11339735040 1942161232 19.164 99 13 26 0 1952554336 6784864 3608240
15:59:25 89956 8192000000 10866651648 11345195008 1888414336 18.733 96 20 40 0 1905902800 11134976 6353488
16:02:03 57536 8192000000 10888686624 11368673280 1949336192 19.221 99 15 30 0 1967741600 14447408 3958000
16:04:12 30803 8192000000 10920208336 11379896320 1955082736 19.267 98 46 92 1 1977460240 10337040 12040464
16:05:43 34543 8192000000 10896417984 11356008448 1934903728 19.106 98 37 74 1 1953069488 7381072 10784688
16:07:36 80898 8192000000 10941276752 11416236032 1978842080 19.456 99 19 36 1 2000729568 16909936 4977552
16:09:57 80721 8192000000 10902580848 11360972800 1841671632 18.354 92 12 24 0 1857263616 12466448 3125536
16:12:20 34778 8192000000 10946020416 11403628544 1975372080 19.428 99 40 80 2 2003034112 16578272 11083760
16:13:57 87896 8192000000 10909289184 11395592192 1921404816 18.998 96 12 24 1 1940872240 15959344 3508080
16:16:36 97961 8192000000 10879282784 11341762560 1806878976 18.070 91 20 40 0 1827215584 14946752 5389856
16:19:19 83805 8192000000 10882033280 11350204416 1905685824 18.872 97 22 42 0 1918120960 6584944 5850192
16:21:50 73371 8192000000 10883331904 11356463104 1916348672 18.958 97 23 46 1 1938925296 15320000 7256624
16:24:20 46049 8192000000 10863659168 11345289216 1918887232 18.978 98 9 18 0 1933923280 12374880 2661168
16:26:30 101075 8192000000 10887240032 11360714752 1964812144 19.344 100 18 37 0 1980377184 9908800 5656240
16:29:45 51470 8192000000 10879160128 11353341952 1940970112 19.154 99 22 44 2 1958197360 10669456 6557792
16:31:43 72592 8192000000 10852750112 11324997632 1804105568 18.048 92 14 28 0 1820801168 12833888 3861712
16:34:02 37389 8192000000 10878939360 11352035328 1929773264 19.065 99 15 30 1 1949535888 15413008 4349616
16:36:07 48145 8192000000 10884603184 11372597248 1952978112 19.250 100 18 36 0 1981388320 22559328 5850880

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 26-09-2021 16:38:54 to 26-09-2021 16:39:08.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 166 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 26-09-2021 16:39:09 to 26-09-2021 16:39:11.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          248368
MON$OLDEST_ACTIVE               249752
MON$OLDEST_SNAPSHOT             249752
MON$NEXT_TRANSACTION            249752
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-03 16:41:55.3470
MON$PAGES                       2033120
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33501 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33501 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33501 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33501 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:893: 
:894: mscs-test-161.budzdorov.ru    Sun Sep 26 16:38:04 2021
:895:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:896: 
--- end of diff output ---

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

26.09.21 16:39:12. There are 3 logs in /var/tmp/logs-oltp30 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-09-26 16:39:15 No severe PSQL-related problems occured
26.09.21 16:39:16. End of report.