Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.03.2021 07:28
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 df9f488d08424df294c459d6c283e9c3
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-03-04 16:07
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.26
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 16936616 kB
MemAvailable 31598400 kB
Buffers 0 kB
Cached 14757908 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.0
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-03-16 07:26:55
DTS_END                         2021-03-16 07:26:55.8880
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/41180
TRN_ID                          227752
ATT_ID                          634
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: 8776.05 27645 1053126
customer order: creation 160.47 447 19256
customer order: refuse 13.78 365 1654
order to supplier: creation 43.77 1597 5252
order to supplier: removal 3.43 143379 412
invoice (draft): creation 44.27 3744 5312
invoice (draft): removal 50.60 1229 6072
invoice accept: apply 39.37 20116 4724
invoice accept: cancel 45.27 1086 5432
customer reserve: creation 3624.36 196 434923
customer reserve: removal 4469.33 85 536319
realization accept: apply 79.70 233 9564
realization accept: cancel 80.72 138 9686
payment from customer: creation 55.13 180 6615
payment from customer: removal 5.65 149 678
payment to supplier: creation 46.91 181 5629
payment to supplier: removal 3.08 112 370
service: total inventory turnovers 3.47 4312 416
service: total monetary turnovers 5.83 689 700
service: aggregate perf. data 0.90 18084 108
service: refresh index statistics 0.03 356578 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-03-2021 07:28:59 to 16-03-2021 07:29:01.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1982
TEST_TIME 2 1965
TEST_TIME 3 2059
TEST_TIME 4 2836
TEST_TIME 5 3003
TEST_TIME 6 3413
TEST_TIME 7 3422
TEST_TIME 8 3688
TEST_TIME 9 4162
TEST_TIME 10 4740
TEST_TIME 11 4841
TEST_TIME 12 5140
TEST_TIME 13 5550
TEST_TIME 14 5916
TEST_TIME 15 6219
TEST_TIME 16 6357
TEST_TIME 17 6653
TEST_TIME 18 6618
TEST_TIME 19 6640
TEST_TIME 20 6982
TEST_TIME 21 7138
TEST_TIME 22 7370
TEST_TIME 23 7497
TEST_TIME 24 7630
TEST_TIME 25 7804
TEST_TIME 26 7822
TEST_TIME 27 7801
TEST_TIME 28 7992
TEST_TIME 29 8316
TEST_TIME 30 8997
TEST_TIME 31 9965
TEST_TIME 32 10738
TEST_TIME 33 10637
TEST_TIME 34 10468
TEST_TIME 35 10405
TEST_TIME 36 10248
TEST_TIME 37 10431
TEST_TIME 38 10282
TEST_TIME 39 10094
TEST_TIME 40 9972
TEST_TIME 41 9836
TEST_TIME 42 9742
TEST_TIME 43 9614
TEST_TIME 44 9670
TEST_TIME 45 9503
TEST_TIME 46 9591
TEST_TIME 47 9639
TEST_TIME 48 9647
TEST_TIME 49 9521
TEST_TIME 50 9474
TEST_TIME 51 9370
TEST_TIME 52 9352
TEST_TIME 53 9389
TEST_TIME 54 9321
TEST_TIME 55 9294
TEST_TIME 56 9399
TEST_TIME 57 9381
TEST_TIME 58 9353
TEST_TIME 59 9383
TEST_TIME 60 9504
TEST_TIME 61 9455
TEST_TIME 62 9485
TEST_TIME 63 9486
TEST_TIME 64 9495
TEST_TIME 65 9426
TEST_TIME 66 9356
TEST_TIME 67 9284
TEST_TIME 68 9217
TEST_TIME 69 9143
TEST_TIME 70 9057
TEST_TIME 71 9122
TEST_TIME 72 9106
TEST_TIME 73 9101
TEST_TIME 74 9111
TEST_TIME 75 9062
TEST_TIME 76 9066
TEST_TIME 77 9112
TEST_TIME 78 9093
TEST_TIME 79 9142
TEST_TIME 80 9235
TEST_TIME 81 9242
TEST_TIME 82 9252
TEST_TIME 83 9216
TEST_TIME 84 9210
TEST_TIME 85 9191
TEST_TIME 86 9115
TEST_TIME 87 9085
TEST_TIME 88 9097
TEST_TIME 89 9092
TEST_TIME 90 9084
TEST_TIME 91 9040
TEST_TIME 92 9015
TEST_TIME 93 8996
TEST_TIME 94 9043
TEST_TIME 95 9050
TEST_TIME 96 9078
TEST_TIME 97 9063
TEST_TIME 98 9102
TEST_TIME 99 9178
TEST_TIME 100 9144
TEST_TIME 101 9129
TEST_TIME 102 9062
TEST_TIME 103 9028
TEST_TIME 104 8997
TEST_TIME 105 8956
TEST_TIME 106 8917
TEST_TIME 107 8892
TEST_TIME 108 8878
TEST_TIME 109 8842
TEST_TIME 110 8835
TEST_TIME 111 8821
TEST_TIME 112 8835
TEST_TIME 113 8936
TEST_TIME 114 8925
TEST_TIME 115 8899
TEST_TIME 116 8899
TEST_TIME 117 8884
TEST_TIME 118 8840
TEST_TIME 119 8823
TEST_TIME 120 8778

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-03-2021 07:29:03 to 16-03-2021 07:29:06.

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 1456791 1456791 0 0.00 0 45613 8 0 0
doc_list_biud 1456790 1456790 0 0.00 0 37633 39 0 0
sp_add_invoice_to_stock 4819 4724 95 1.97 14 178487 20117 0 95
sp_cancel_adding_invoice 5432 5432 0 0.00 5 100323 1087 0 0
sp_cancel_client_order 1654 1654 0 0.00 4 6916 365 0 0
sp_cancel_customer_reserve 536319 536319 0 0.00 1 37637 86 0 0
sp_cancel_pay_from_customer 678 678 0 0.00 3 11961 149 0 0
sp_cancel_pay_to_supplier 370 370 0 0.00 3 14059 112 0 0
sp_cancel_supplier_invoice 6078 6072 6 0.09 3 29912 1229 0 6
sp_cancel_supplier_order 412 412 0 0.00 9 1397985 143380 0 0
sp_cancel_write_off 9686 9686 0 0.00 1 14904 139 0 0
sp_client_order 19256 19256 0 0.00 4 25384 447 0 0
sp_customer_reserve 434923 434923 0 0.00 0 71857 197 0 0
sp_fill_shopping_cart 29806 29806 0 0.00 1 15405 46 0 0
sp_fill_shopping_cart_clo_res 434918 434918 0 0.00 0 32434 22 0 0
sp_get_clo_for_invoice 4719 4719 0 0.00 0 47413 405 0 0
sp_kill_cost_storno 16166 16166 0 0.00 0 45608 82 0 0
sp_kill_qstorno_ret_qs2qd 1088808 1088808 0 0.00 0 37631 46 0 0
sp_kill_qty_storno 573858 573858 0 0.00 0 37633 99 0 0
sp_lock_selected_doc 19754 19754 0 0.00 0 10502 9 0 0
sp_make_cost_storno 26527 26527 0 0.00 0 36594 78 0 0
sp_multiply_rows_for_pdistr 26527 26527 0 0.00 0 19333 43 0 0
sp_multiply_rows_for_qdistr 19256 19256 0 0.00 0 24724 257 0 0
sp_pay_from_customer 6615 6615 0 0.00 4 41822 180 0 0
sp_pay_to_supplier 5629 5629 0 0.00 5 20674 181 0 0
sp_payment_common 12243 12243 0 0.00 1 32196 57 0 0
sp_qd_handle_on_cancel_clo 1654 1654 0 0.00 0 6834 333 0 0
sp_qd_handle_on_invoice_upd_sts 10151 10151 0 0.00 0 31286 444 0 0
sp_qd_handle_on_reserve_upd_sts 9564 9564 0 0.00 0 20779 57 0 0
sp_reserve_write_off 9564 9564 0 0.00 7 29677 233 0 0
sp_supplier_invoice 5312 5312 0 0.00 7 105567 3744 0 0
sp_supplier_order 5252 5252 0 0.00 5 55745 1598 0 0
srv_aggregate_perf_data 108 108 0 0.00 2665 218792 18084 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1468 162113 41867 0 0
srv_find_qd_qs_mism 1008684 1008684 0 0.00 0 18984 6 0 0
srv_make_invnt_saldo 416 416 0 0.00 49 101030 4312 0 0
srv_make_money_saldo 700 700 0 0.00 1 17459 690 0 0
srv_recalc_idx_stat 4 4 0 0.00 222621 524613 356579 0 0
t$perf-abend:sp_add_invoice_to_stock 95 95 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4724 4724 0 0.00 0 18355 227 0 0
t$perf-norm:sp_cancel_adding_invoice 384 384 0 0.00 0 20568 181 0 0
t$perf-norm:sp_cancel_client_order 1654 1654 0 0.00 0 4269 20 0 0
t$perf-norm:sp_cancel_customer_reserve 600 600 0 0.00 0 2804 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 678 678 0 0.00 0 9138 23 0 0
t$perf-norm:sp_cancel_pay_to_supplier 370 370 0 0.00 0 2693 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 410 410 0 0.00 0 4183 40 0 0
t$perf-norm:sp_cancel_supplier_order 412 412 0 0.00 0 31056 2075 0 0
t$perf-norm:sp_cancel_write_off 963 963 0 0.00 0 4000 8 0 0
t$perf-norm:sp_client_order 19256 19256 0 0.00 0 8566 21 0 0
t$perf-norm:sp_customer_reserve 1006 1006 0 0.00 0 2628 12 0 0
t$perf-norm:sp_pay_from_customer 6615 6615 0 0.00 0 6953 15 0 0
t$perf-norm:sp_pay_to_supplier 5629 5629 0 0.00 0 14802 15 0 0
t$perf-norm:sp_reserve_write_off 9564 9564 0 0.00 0 9197 15 0 0
t$perf-norm:sp_supplier_invoice 5312 5312 0 0.00 0 3609 23 0 0
t$perf-norm:sp_supplier_order 5252 5252 0 0.00 0 7007 19 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 2292 40 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 585 18 0 0
t$perf-norm:srv_make_invnt_saldo 416 416 0 0.00 0 5740 32 0 0
t$perf-norm:srv_make_money_saldo 700 700 0 0.00 0 4254 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 9 4 0 0
v_add_invoice_to_stock 9628 9438 190 1.97 0 5602 17 0 190
v_all_customers 35116 35116 0 0.00 0 158 1 0 0
v_all_suppliers 29526 29526 0 0.00 0 28 1 0 0
v_all_wares 38510 38510 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 768 768 0 0.00 0 1267 9 0 0
v_cancel_client_order 3308 3308 0 0.00 0 3753 8 0 0
v_cancel_customer_prepayment 1356 1356 0 0.00 0 3485 11 0 0
v_cancel_customer_reserve 1200 1200 0 0.00 0 569 4 0 0
v_cancel_payment_to_supplier 740 740 0 0.00 0 2356 8 0 0
v_cancel_supplier_invoice 832 820 12 1.44 0 2054 18 0 12
v_cancel_supplier_order 824 824 0 0.00 0 922 6 0 0
v_cancel_write_off 1926 1926 0 0.00 0 8947 22 0 0
v_max_id_clo_ord 5251 5251 0 0.00 0 903 2 0 0
v_max_id_clo_res 1006 1006 0 0.00 0 4514 14 0 0
v_max_id_ord_sup 5299 5299 0 0.00 0 4330 7 0 0
v_max_non_paid_invoice 1415 1415 0 0.00 0 263 2 0 0
v_max_non_paid_realizn 3489 3489 0 0.00 0 9612 6 0 0
v_min_id_clo_ord 5251 5251 0 0.00 0 9827 13 0 0
v_min_id_clo_res 1006 1006 0 0.00 0 6035 20 0 0
v_min_id_ord_sup 5299 5299 0 0.00 0 8666 9 0 0
v_min_non_paid_invoice 5628 5628 0 0.00 0 17943 45 0 0
v_min_non_paid_realizn 6615 6615 0 0.00 0 19137 49 0 0
v_our_firm 9646 9646 0 0.00 0 17 1 0 0
v_reserve_write_off 19128 19128 0 0.00 0 5610 7 0 0
x_lock_depdocs_on_canc_invoice 11094 11094 0 0.00 0 33399 138 0 0
x_lock_depdocs_on_canc_sup_ord 412 412 0 0.00 0 4623 394 0 0
x_make_qstorno_1000_1200 5252 5252 0 0.00 2 55137 1501 0 0
x_make_qstorno_1200_2000 5312 5312 0 0.00 3 105549 3580 0 0
x_make_qstorno_2100_3300 434923 434923 0 0.00 0 71856 172 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-03-2021 07:29:07 to 16-03-2021 07:29:11.

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
04:56:59 2 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
04:58:00 31181 8192000000 9721210464 10021244928 789711376 8.792 100 78 156 1 816607440 7063728 19832336
04:59:40 23774 8192000000 9777493936 10084839424 849624480 9.396 100 89 178 0 883865728 12778224 21463024
05:01:10 15706 8192000000 9818172592 10136567808 881695424 9.717 100 85 170 1 922760272 22123648 18941200
05:02:28 13262 8192000000 9881361392 10212200448 927700208 10.172 100 85 170 2 983448832 37712976 18035648
05:03:48 9870 8192000000 9929486576 10272866304 962958784 10.518 100 87 174 3 1045428176 64144688 18324704
05:05:00 3293 8192000000 9975526752 10326904832 993600976 10.816 100 87 173 1 1084381088 73147632 17632480
05:06:06 3628 8192000000 10010955456 10371489792 1019812768 11.070 100 86 171 1 1121783424 84400640 17570016
05:07:11 2127 8192000000 10024849984 10391769088 1046090464 11.323 100 89 176 1 1135872304 72258704 17523136
05:08:15 1910 8192000000 10034876256 10405269504 1060613792 11.462 100 85 167 2 1152283968 73178464 18491712
05:09:20 9823 8192000000 9967496592 10357178368 1064592032 11.500 100 36 70 1 1091364432 18334304 8438096
05:10:31 5151 8192000000 9987118240 10375458816 1070514688 11.557 100 35 71 1 1106923024 28807552 7600784
05:11:37 11479 8192000000 10004937408 10412597248 1079708224 11.645 100 30 59 1 1127281584 41488544 6084816
05:12:51 7588 8192000000 9987145008 10400776192 1087568080 11.720 100 15 30 3 1116798928 26305728 2925120
05:14:00 10700 8192000000 10027725168 10419884032 1089909264 11.742 100 67 135 0 1132380816 28298448 14173104
05:15:11 6388 8192000000 10000389952 10402930688 1095914176 11.799 100 39 78 0 1121146816 16059536 9173104
05:16:20 7163 8192000000 10009590288 10410704896 1088425328 11.728 99 33 67 0 1121237280 24560960 8250992
05:17:29 14780 8192000000 10001253776 10400813056 1073204416 11.583 98 41 82 4 1106376496 24314256 8857824
05:18:45 11314 8192000000 10031109968 10446192640 1084716624 11.692 100 37 75 2 1136078944 43834128 7528192
05:19:57 4994 8192000000 9931972016 10323144704 1005197392 10.929 96 35 68 1 1034893056 21405824 8289840
05:21:05 8851 8192000000 9947475264 10328559616 985294816 10.736 97 44 89 1 1021004144 25932240 9777088
05:22:18 6958 8192000000 9954583136 10334363648 1011908528 10.994 99 46 90 1 1042707904 21307632 9491744
05:23:27 18531 8192000000 9930975056 10330337280 1006746992 10.944 98 23 46 1 1043120080 31850208 4522880
05:24:46 9469 8192000000 9974967424 10379321344 1044990352 11.313 100 34 68 0 1075358960 22614880 7753728
05:26:01 3816 8192000000 9979204048 10368770048 1060120752 11.458 100 40 80 2 1097778896 27477136 10181008
05:27:06 11602 8192000000 9987366064 10376327168 1067128368 11.525 100 52 102 4 1089963392 11226656 11608368
05:28:26 53779 8192000000 10036411488 10441486336 1088386896 11.727 100 44 89 3 1129460400 30062768 11010736
05:30:40 20639 8192000000 10083284384 10486857728 1105413424 11.889 100 37 74 0 1188206960 74291936 8501600
05:32:02 25325 8192000000 10142018384 10558734336 1117636192 12.005 100 57 114 1 1238725792 108844608 12244992
05:33:52 14381 8192000000 10173861680 10582581248 1130387232 12.125 100 49 97 0 1279805760 138877680 10540848
05:35:12 7786 8192000000 10197972352 10619600896 1141752544 12.232 100 52 105 1 1308902336 155922816 11226976
05:36:27 2682 8192000000 10276123072 10684424192 1156151456 12.367 100 67 133 0 1378664272 208671664 13841152
05:37:35 18251 8192000000 10262634288 10692866048 1154963136 12.356 99 56 112 1 1357710208 190260896 12486176
05:39:02 2608 8192000000 10287756656 10714718208 1168317984 12.481 100 84 167 1 1387818928 200977488 18523456
05:40:10 3884 8192000000 10292705040 10722500608 1173706176 12.531 100 79 155 0 1391805088 201049344 17049568
05:41:20 2588 8192000000 10315334896 10741325824 1186876704 12.654 100 80 157 0 1419616864 215097264 17642896
05:42:28 2505 8192000000 10333386000 10739634176 1171459648 12.510 100 81 162 1 1430468736 240845168 18163920
05:43:35 2552 8192000000 10363076928 10765795328 1178121520 12.573 100 86 172 2 1457059472 259781440 19156512
05:44:41 3902 8192000000 10416455488 10818473984 1164272688 12.443 99 87 174 0 1506254880 322767664 19214528
05:45:50 1467 8192000000 10431786608 10843951104 1159488672 12.398 99 92 185 0 1523030336 341177104 22364560
05:46:59 2880 8192000000 10418898384 10840698880 1168153376 12.480 100 83 158 1 1522609424 334721664 19734384
05:48:07 4432 8192000000 10480716096 10906562560 1184497456 12.632 100 84 168 0 1575209056 371074496 19637104
05:49:24 1749 8192000000 10506168672 10905772032 1185379744 12.640 100 90 179 0 1597379984 391348000 20652240
05:50:28 7271 8192000000 10491560272 10916368384 1178747424 12.579 100 49 98 2 1591907936 400435776 12724736
05:51:40 1816 8192000000 10556889056 10974732288 1185457520 12.641 100 88 176 1 1644834896 438313536 21063840
05:52:50 5415 8192000000 10554920752 10994438144 1189483968 12.679 100 56 112 0 1666648368 462949488 14214912
05:54:01 7697 8192000000 10618931056 11070685184 1180219744 12.592 100 39 78 2 1714838736 523597088 11021904
05:55:26 16051 8192000000 10575188016 11057733632 1174133664 12.535 100 37 74 0 1694399472 510015632 10250176
05:56:51 13227 8192000000 10428192784 10905935872 1137861296 12.195 100 33 66 0 1568612448 421415696 9335456
05:58:09 22850 8192000000 10160028592 10705305600 1148523824 12.296 100 22 44 0 1345138256 191510512 5103920
05:59:41 21836 8192000000 10043098304 10480734208 1095775088 11.798 97 22 44 1 1115625072 15022544 4827440
06:01:06 51397 8192000000 10096281376 10552520704 1120677520 12.033 99 20 40 2 1193726688 68676736 4372432
06:03:06 116107 8192000000 10107031856 10568671232 1117412272 12.003 98 24 48 2 1157458992 33890368 6156352
06:06:06 162113 8192000000 10127401152 10590834688 1099044400 11.829 95 21 41 0 1142285360 39052688 4188272
06:09:50 62368 8192000000 10187728400 10696093696 1150572336 12.315 99 13 26 0 1278126096 124788496 2765264
06:11:57 32500 8192000000 10159743456 10634506240 1123724896 12.062 98 13 25 1 1248056768 121233648 3098224
06:13:46 32924 8192000000 10096895008 10566885376 1135760880 12.176 99 24 48 1 1209596000 68234672 5600448
06:15:26 120775 8192000000 10109726480 10595778560 1133725616 12.156 100 21 42 0 1229421056 90981808 4713632
06:18:59 54962 8192000000 10082632992 10576527360 1122831456 12.054 99 17 34 2 1163480432 36805056 3843920
06:21:14 61480 8192000000 10081005904 10582568960 1117240928 12.001 98 29 58 3 1188486608 65615376 5630304
06:23:16 16662 8192000000 10132755408 10574561280 1130009120 12.121 99 49 99 1 1185756688 46063312 9684256
06:24:34 82644 8192000000 10145209888 10617262080 1133605808 12.155 99 24 48 1 1268219792 129247552 5366432
06:26:59 52938 8192000000 10119292656 10574315520 1141784160 12.232 100 20 38 2 1172904992 26619328 4501504
06:28:53 50808 8192000000 10072118752 10531405824 1117804832 12.006 98 28 56 1 1158586832 35325184 5456816
06:30:48 22175 8192000000 10081769632 10514960384 1120440208 12.031 99 44 88 0 1183625728 54574592 8610928
06:32:11 101776 8192000000 10057921232 10536165376 1122897088 12.054 99 23 46 0 1177795568 50302864 4595616
06:34:58 24520 8192000000 10062943424 10530541568 1114873072 11.979 98 6 12 0 1158945600 42816848 1255680
06:36:37 78387 8192000000 10078566064 10601783296 1091768304 11.759 96 13 26 0 1190271072 95694784 2807984
06:39:05 54047 8192000000 10064799568 10528944128 1096851024 11.808 97 9 18 0 1149741760 51198752 1691984
06:41:00 54295 8192000000 10114414960 10555875328 1095973152 11.799 96 23 46 1 1162376368 61401440 5001776
06:42:58 58852 8192000000 10108576288 10585042944 1107548208 11.909 98 16 32 2 1192656208 81579152 3528848
06:45:18 50624 8192000000 10101452816 10543763456 1073838224 11.589 96 34 68 0 1198327424 117101248 7387952
06:47:15 65219 8192000000 10067512048 10521346048 1055353808 11.412 94 18 36 1 1103498464 44551184 3593472
06:49:27 10285 8192000000 10083419104 10506203136 1116744592 11.996 100 49 98 2 1160860864 34154688 9961584
06:50:38 75431 8192000000 10114320704 10559188992 1128042320 12.103 100 26 52 0 1206824816 73374768 5407728
06:53:11 93691 8192000000 10096140448 10594852864 1130721344 12.128 100 19 38 0 1213686368 78323040 4641984
06:55:45 65165 8192000000 10080386976 10616057856 1126418736 12.088 100 9 17 1 1186991792 58438208 2134848
06:58:16 61712 8192000000 10122550416 10590093312 1135583456 12.174 99 25 50 0 1210248512 69289552 5375504
07:00:46 58489 8192000000 10149597584 10620555264 1153096224 12.339 100 20 40 0 1245291024 87874656 4320144
07:02:44 52153 8192000000 10133041104 10598707200 1148461408 12.295 100 31 59 3 1213344720 58178720 6704592
07:04:38 64052 8192000000 10074139168 10571350016 1132242944 12.143 99 12 24 0 1199428736 65170416 2015376
07:06:45 38442 8192000000 10101681408 10561970176 1122969792 12.055 98 28 56 0 1182703120 54206912 5526416
07:08:25 85260 8192000000 10050602384 10509705216 1127705952 12.100 99 18 36 1 1169504272 38263984 3534336
07:10:51 69522 8192000000 10076727328 10561757184 1142648400 12.240 100 11 22 0 1193811200 48506272 2656528
07:13:00 44221 8192000000 10064735456 10521317376 1136139104 12.179 99 6 12 0 1169118880 31725248 1254528
07:14:47 52382 8192000000 10100692016 10548854784 1133426992 12.154 98 28 56 3 1184125680 45065712 5632976
07:16:40 46120 8192000000 10157753456 10620317696 1164215472 12.443 100 26 52 0 1256525792 85999520 6310800
07:18:37 115507 8192000000 10098049232 10620489728 1133004480 12.150 98 17 34 0 1239897344 103124016 3768848
07:21:37 14353 8192000000 10067098608 10502447104 1107357504 11.907 98 52 104 1 1147424128 29439280 10627344
07:22:52 32265 8192000000 10103152048 10545016832 1121340416 12.040 99 43 86 2 1191276432 61562464 8373552
07:24:28 97350 8192000000 10050496160 10518392832 1121851488 12.044 99 12 24 0 1166677136 42320640 2505008

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 16-03-2021 07:29:13 to 16-03-2021 07:29:28.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-03-2021 07:29:29 to 16-03-2021 07:29:31.

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          227751
MON$OLDEST_ACTIVE               228577
MON$OLDEST_SNAPSHOT             228577
MON$NEXT_TRANSACTION            228577
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                       2100544
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             787
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2387 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2387 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2387 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2387 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16: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 ---
:170: 
:171: mscs-test-161.budzdorov.ru    Tue Mar 16 07:27:17 2021
:172:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:173: 
:174: 
:175: mscs-test-161.budzdorov.ru    Tue Mar 16 07:27:24 2021
:176:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:177: 
--- end of diff output ---

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

16.03.21 07:29:32. 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-03-16 07:29:34 No severe PSQL-related problems occured
16.03.21 07:29:36. End of report.