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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16713792 kB
MemAvailable 31516664 kB
Buffers 0 kB
Cached 14944128 kB
SwapCached 0 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.1
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-11-29 10:37:08
DTS_END                         2021-11-29 10:37:08.8560
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/50642
TRN_ID                          223380
ATT_ID                          613
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: 8894.96 24159 1067395
customer order: creation 160.28 450 19233
customer order: refuse 13.35 321 1602
order to supplier: creation 42.53 1504 5104
order to supplier: removal 3.58 107579 430
invoice (draft): creation 42.35 3803 5082
invoice (draft): removal 51.53 956 6184
invoice accept: apply 38.67 19523 4641
invoice accept: cancel 46.62 835 5594
customer reserve: creation 3604.28 190 432513
customer reserve: removal 4613.27 66 553592
realization accept: apply 78.14 209 9377
realization accept: cancel 80.23 106 9628
payment from customer: creation 55.28 119 6634
payment from customer: removal 5.02 106 602
payment to supplier: creation 46.32 133 5558
payment to supplier: removal 3.15 72 378
service: total inventory turnovers 3.87 3390 464
service: total monetary turnovers 5.62 560 674
service: aggregate perf. data 0.84 15137 101
service: refresh index statistics 0.03 328125 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-11-2021 10:38:04 to 29-11-2021 10:38:06.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7139
TEST_TIME 2 5354
TEST_TIME 3 4765
TEST_TIME 4 4958
TEST_TIME 5 4699
TEST_TIME 6 4655
TEST_TIME 7 5213
TEST_TIME 8 5787
TEST_TIME 9 5844
TEST_TIME 10 5960
TEST_TIME 11 6516
TEST_TIME 12 6724
TEST_TIME 13 6792
TEST_TIME 14 6893
TEST_TIME 15 6889
TEST_TIME 16 7102
TEST_TIME 17 7169
TEST_TIME 18 7305
TEST_TIME 19 7415
TEST_TIME 20 7920
TEST_TIME 21 8242
TEST_TIME 22 8744
TEST_TIME 23 9067
TEST_TIME 24 9466
TEST_TIME 25 9547
TEST_TIME 26 9468
TEST_TIME 27 9705
TEST_TIME 28 10831
TEST_TIME 29 10559
TEST_TIME 30 10259
TEST_TIME 31 10069
TEST_TIME 32 10034
TEST_TIME 33 9885
TEST_TIME 34 9808
TEST_TIME 35 9707
TEST_TIME 36 9684
TEST_TIME 37 9570
TEST_TIME 38 9490
TEST_TIME 39 9356
TEST_TIME 40 9288
TEST_TIME 41 9263
TEST_TIME 42 9170
TEST_TIME 43 9272
TEST_TIME 44 9258
TEST_TIME 45 9161
TEST_TIME 46 9097
TEST_TIME 47 9039
TEST_TIME 48 9048
TEST_TIME 49 9134
TEST_TIME 50 9127
TEST_TIME 51 9078
TEST_TIME 52 9086
TEST_TIME 53 9097
TEST_TIME 54 9305
TEST_TIME 55 9316
TEST_TIME 56 9231
TEST_TIME 57 9297
TEST_TIME 58 9362
TEST_TIME 59 9344
TEST_TIME 60 9478
TEST_TIME 61 9413
TEST_TIME 62 9289
TEST_TIME 63 9184
TEST_TIME 64 9130
TEST_TIME 65 9251
TEST_TIME 66 9197
TEST_TIME 67 9173
TEST_TIME 68 9205
TEST_TIME 69 9199
TEST_TIME 70 9132
TEST_TIME 71 9101
TEST_TIME 72 9061
TEST_TIME 73 9113
TEST_TIME 74 9071
TEST_TIME 75 9178
TEST_TIME 76 9161
TEST_TIME 77 9193
TEST_TIME 78 9223
TEST_TIME 79 9255
TEST_TIME 80 9366
TEST_TIME 81 9352
TEST_TIME 82 9329
TEST_TIME 83 9279
TEST_TIME 84 9200
TEST_TIME 85 9133
TEST_TIME 86 9122
TEST_TIME 87 9086
TEST_TIME 88 9055
TEST_TIME 89 9013
TEST_TIME 90 8985
TEST_TIME 91 8941
TEST_TIME 92 8953
TEST_TIME 93 8965
TEST_TIME 94 8998
TEST_TIME 95 8986
TEST_TIME 96 8960
TEST_TIME 97 9036
TEST_TIME 98 8990
TEST_TIME 99 9006
TEST_TIME 100 9017
TEST_TIME 101 9106
TEST_TIME 102 9133
TEST_TIME 103 9142
TEST_TIME 104 9089
TEST_TIME 105 9016
TEST_TIME 106 9009
TEST_TIME 107 9017
TEST_TIME 108 8985
TEST_TIME 109 8943
TEST_TIME 110 8922
TEST_TIME 111 8918
TEST_TIME 112 8873
TEST_TIME 113 8872
TEST_TIME 114 8903
TEST_TIME 115 8908
TEST_TIME 116 8927
TEST_TIME 117 8963
TEST_TIME 118 8987
TEST_TIME 119 8968
TEST_TIME 120 8903

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 29-11-2021 10:38:08 to 29-11-2021 10:38:11.

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 1467399 1467399 0 0.00 0 31595 6 0 0
doc_list_biud 1467396 1467396 0 0.00 0 87230 32 0 0
sp_add_invoice_to_stock 4743 4641 102 2.15 44 200950 19523 0 102
sp_cancel_adding_invoice 5594 5594 0 0.00 5 93557 835 0 0
sp_cancel_client_order 1602 1602 0 0.00 4 13136 322 0 0
sp_cancel_customer_reserve 553592 553592 0 0.00 1 87232 67 0 0
sp_cancel_pay_from_customer 602 602 0 0.00 3 6349 106 0 0
sp_cancel_pay_to_supplier 378 378 0 0.00 3 1954 73 0 0
sp_cancel_supplier_invoice 6195 6184 11 0.17 3 31016 957 0 11
sp_cancel_supplier_order 430 430 0 0.00 6 940050 107579 0 0
sp_cancel_write_off 9628 9628 0 0.00 1 16274 107 0 0
sp_client_order 19233 19233 0 0.00 4 27931 451 0 0
sp_customer_reserve 432513 432513 0 0.00 0 81373 191 0 0
sp_fill_shopping_cart 29391 29391 0 0.00 1 24613 47 0 0
sp_fill_shopping_cart_clo_res 432503 432503 0 0.00 0 28728 20 0 0
sp_get_clo_for_invoice 4628 4628 0 0.00 0 59634 480 0 0
sp_kill_cost_storno 16202 16202 0 0.00 0 17827 59 0 0
sp_kill_qstorno_ret_qs2qd 1123426 1123426 0 0.00 0 83394 36 0 0
sp_kill_qty_storno 591034 591034 0 0.00 0 87229 77 0 0
sp_lock_selected_doc 19417 19417 0 0.00 0 6471 5 0 0
sp_make_cost_storno 26197 26197 0 0.00 0 31299 60 0 0
sp_multiply_rows_for_pdistr 26195 26195 0 0.00 0 20887 34 0 0
sp_multiply_rows_for_qdistr 19233 19233 0 0.00 0 15966 232 0 0
sp_pay_from_customer 6634 6634 0 0.00 4 20739 119 0 0
sp_pay_to_supplier 5558 5558 0 0.00 5 18291 134 0 0
sp_payment_common 12191 12191 0 0.00 1 20727 32 0 0
sp_qd_handle_on_cancel_clo 1602 1602 0 0.00 0 13129 301 0 0
sp_qd_handle_on_invoice_upd_sts 10221 10221 0 0.00 0 26882 357 0 0
sp_qd_handle_on_reserve_upd_sts 9377 9377 0 0.00 0 13695 67 0 0
sp_reserve_write_off 9377 9377 0 0.00 7 21197 209 0 0
sp_supplier_invoice 5082 5082 0 0.00 7 85844 3804 0 0
sp_supplier_order 5104 5104 0 0.00 5 56206 1504 0 0
srv_aggregate_perf_data 101 101 0 0.00 3005 131960 15137 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1994 183305 51633 0 0
srv_find_qd_qs_mism 1022647 1022647 0 0.00 0 13207 5 0 0
srv_make_invnt_saldo 464 464 0 0.00 63 99967 3390 0 0
srv_make_money_saldo 674 674 0 0.00 1 12666 560 0 0
srv_recalc_idx_stat 4 4 0 0.00 202609 479653 328126 0 0
t$perf-abend:sp_add_invoice_to_stock 102 102 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4641 4641 0 0.00 0 10185 152 0 0
t$perf-norm:sp_cancel_adding_invoice 365 365 0 0.00 0 2470 74 0 0
t$perf-norm:sp_cancel_client_order 1602 1602 0 0.00 0 1259 12 0 0
t$perf-norm:sp_cancel_customer_reserve 672 672 0 0.00 0 3122 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 602 602 0 0.00 0 3383 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 378 378 0 0.00 0 594 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 416 416 0 0.00 0 1140 19 0 0
t$perf-norm:sp_cancel_supplier_order 430 430 0 0.00 0 14975 1334 0 0
t$perf-norm:sp_cancel_write_off 948 948 0 0.00 0 1087 5 0 0
t$perf-norm:sp_client_order 19233 19233 0 0.00 0 7826 20 0 0
t$perf-norm:sp_customer_reserve 989 989 0 0.00 0 1192 4 0 0
t$perf-norm:sp_pay_from_customer 6634 6634 0 0.00 0 10466 12 0 0
t$perf-norm:sp_pay_to_supplier 5558 5558 0 0.00 0 9057 12 0 0
t$perf-norm:sp_reserve_write_off 9377 9377 0 0.00 0 6595 11 0 0
t$perf-norm:sp_supplier_invoice 5082 5082 0 0.00 0 6344 19 0 0
t$perf-norm:sp_supplier_order 5104 5104 0 0.00 0 6519 16 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 453 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 395 9 0 0
t$perf-norm:srv_make_invnt_saldo 464 464 0 0.00 0 2208 16 0 0
t$perf-norm:srv_make_money_saldo 674 674 0 0.00 0 109 2 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 9458 9254 204 2.15 0 4042 16 0 204
v_all_customers 35478 35478 0 0.00 0 76 1 0 0
v_all_suppliers 28358 28358 0 0.00 0 115 1 0 0
v_all_wares 38466 38466 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 730 730 0 0.00 0 1611 8 0 0
v_cancel_client_order 3204 3204 0 0.00 0 3596 5 0 0
v_cancel_customer_prepayment 1204 1204 0 0.00 0 5012 11 0 0
v_cancel_customer_reserve 1344 1344 0 0.00 0 5699 8 0 0
v_cancel_payment_to_supplier 756 756 0 0.00 0 342 3 0 0
v_cancel_supplier_invoice 854 832 22 2.57 0 1313 13 0 22
v_cancel_supplier_order 860 860 0 0.00 0 3258 11 0 0
v_cancel_write_off 1896 1896 0 0.00 0 4441 16 0 0
v_max_id_clo_ord 5087 5087 0 0.00 0 688 1 0 0
v_max_id_clo_res 989 989 0 0.00 0 8146 29 0 0
v_max_id_ord_sup 5069 5069 0 0.00 0 6812 9 0 0
v_max_non_paid_invoice 1535 1535 0 0.00 0 1034 4 0 0
v_max_non_paid_realizn 3345 3345 0 0.00 0 2841 4 0 0
v_min_id_clo_ord 5087 5087 0 0.00 0 12830 11 0 0
v_min_id_clo_res 989 989 0 0.00 0 7653 14 0 0
v_min_id_ord_sup 5069 5069 0 0.00 0 4964 7 0 0
v_min_non_paid_invoice 5558 5558 0 0.00 0 16693 23 0 0
v_min_non_paid_realizn 6633 6633 0 0.00 0 20692 26 0 0
v_our_firm 9564 9564 0 0.00 0 13 1 0 0
v_reserve_write_off 18754 18754 0 0.00 0 6010 5 0 0
x_lock_depdocs_on_canc_invoice 11362 11362 0 0.00 0 21239 109 0 0
x_lock_depdocs_on_canc_sup_ord 430 430 0 0.00 0 20606 381 0 0
x_make_qstorno_1000_1200 5104 5104 0 0.00 2 53730 1416 0 0
x_make_qstorno_1200_2000 5082 5082 0 0.00 3 85808 3621 0 0
x_make_qstorno_2100_3300 432513 432513 0 0.00 0 81349 168 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-11-2021 10:38:12 to 29-11-2021 10:38:16.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
08:07:12 2 8192000000 8797060016 8807632896 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:13 27630 8192000000 9716344896 10012729344 796705600 8.863 100 88 176 0 826292480 8447968 21138912
08:09:46 29747 8192000000 9770014416 10080997376 843727536 9.337 100 88 176 0 877299328 13273792 20298000
08:11:22 21485 8192000000 9825317264 10144370688 884146144 9.741 100 90 180 0 931046480 26767120 20133216
08:12:47 7707 8192000000 9857225552 10191667200 915425680 10.051 100 75 150 0 969731680 37953408 16352592
08:13:57 8549 8192000000 9932391664 10273202176 954745424 10.438 100 88 174 0 1043158688 70183424 18229840
08:15:09 4318 8192000000 9966057888 10319745024 994112752 10.821 100 79 157 0 1076302960 66258928 15931280
08:16:15 3010 8192000000 10004840848 10364809216 1020742256 11.079 100 89 177 0 1120562992 82438672 17382064
08:17:20 1637 8192000000 10024443008 10391494656 1048488560 11.346 100 93 184 1 1139843040 71904432 19450048
08:18:23 1856 8192000000 10032437440 10402217984 1057238144 11.430 100 93 184 4 1143580720 67308608 19033968
08:19:26 3083 8192000000 10003046288 10377289728 1065754784 11.512 100 86 172 1 1118788336 34990848 18042704
08:20:30 6348 8192000000 10002986112 10412109824 1074468848 11.595 100 35 70 2 1124923632 42898720 7556064
08:21:36 5163 8192000000 10002357280 10400784384 1084411728 11.689 100 37 75 3 1125050864 32763792 7875344
08:22:42 11045 8192000000 10003296320 10414809088 1087562704 11.719 100 32 63 1 1136870192 42517856 6789632
08:23:55 37876 8192000000 9999277328 10409353216 1091140512 11.754 100 49 99 0 1127390752 24947616 11302624
08:25:36 5670 8192000000 10012871824 10409799680 1097918080 11.818 100 36 70 1 1124767104 19045536 7803488
08:26:42 5474 8192000000 10005185520 10402820096 1096935792 11.809 100 37 76 2 1117655952 12515296 8204864
08:27:50 9313 8192000000 9992118224 10396217344 1036957648 11.235 95 18 36 0 1065455376 24474496 4023232
08:29:02 36425 8192000000 9955622896 10356187136 995560640 10.835 92 24 47 1 1029087264 28916432 4610192
08:30:39 3885 8192000000 9985673488 10356789248 1017807472 11.051 99 52 102 3 1081602464 54034832 9760160
08:31:44 6215 8192000000 9926922544 10296983552 985479024 10.738 97 26 50 2 1004450240 12912784 6058432
08:32:51 6826 8192000000 9965222640 10352418816 1038337680 11.249 100 43 86 3 1057008832 9795248 8875904
08:34:01 39168 8192000000 9949649312 10352525312 1035142752 11.218 99 37 74 0 1066838800 22943328 8752720
08:35:54 5148 8192000000 9982537632 10373570560 1058281680 11.440 100 52 104 5 1083867744 13965824 11620240
08:37:00 9856 8192000000 9997801472 10402828288 1075029776 11.600 100 33 66 2 1102094976 20000880 7064320
08:38:24 95929 8192000000 10058329984 10458140672 1105079248 11.886 100 38 76 0 1161063824 48002976 7981600
08:41:04 27225 8192000000 10065387232 10467127296 1120341952 12.030 100 44 86 1 1167123568 37487920 9293696
08:42:35 24384 8192000000 10107627984 10511241216 1129980448 12.121 100 49 98 0 1204116928 63649856 10486624
08:44:07 9846 8192000000 10164493200 10564988928 1144663200 12.259 100 57 111 0 1262981392 106290720 12027472
08:45:20 5062 8192000000 10180115072 10591825920 1155563616 12.362 100 52 104 0 1287702144 120229392 11909136
08:46:29 12813 8192000000 10177366384 10589581312 1160608960 12.409 100 47 93 3 1281287568 110198480 10480128
08:47:46 12180 8192000000 10248978464 10657280000 1171123536 12.507 100 71 141 2 1349851440 162874208 15853696
08:49:02 9263 8192000000 10258096304 10667737088 1174921424 12.543 100 70 140 0 1354575200 164714016 14939760
08:50:15 10462 8192000000 10227962384 10643517440 1178683360 12.578 100 46 92 1 1337803856 148938608 10181888
08:51:42 4160 8192000000 10284744384 10703798272 1193052704 12.712 100 60 116 1 1395526832 188957008 13517120
08:52:52 5155 8192000000 10337249040 10761728000 1195294240 12.733 100 69 138 0 1435323344 223978608 16050496
08:54:04 8928 8192000000 10324923664 10765672448 1184868416 12.636 99 52 103 1 1422911280 225170352 12872512
08:55:17 1994 8192000000 10380883872 10782007296 1156413136 12.370 99 81 161 0 1466889712 291424464 19052112
08:56:25 4433 8192000000 10425186864 10830147584 1155955888 12.365 100 67 134 1 1518853968 346554736 16343344
08:57:35 7529 8192000000 10420436960 10843283456 1146918544 12.281 99 62 125 2 1516938288 354242256 15777488
08:58:45 13908 8192000000 10359140144 10777100288 1140940544 12.224 100 40 78 2 1457068368 306344880 9782944
09:00:01 4386 8192000000 10313448448 10742996992 1134017840 12.159 100 43 88 0 1415911824 271392336 10501648
09:01:12 28088 8192000000 10226084256 10707062784 1109300368 11.926 98 21 42 1 1345009920 230185872 5523680
09:02:44 37751 8192000000 10247274112 10688577536 1088220464 11.726 97 21 42 1 1327118208 233456240 5441504
09:04:23 13768 8192000000 10300579680 10735644672 1104830160 11.883 100 22 44 0 1393035136 282176560 6028416
09:05:38 39528 8192000000 10033702736 10507730944 1066660896 11.520 97 4 8 0 1123498240 55230400 1606944
09:07:35 36788 8192000000 10062144432 10478944256 1103014256 11.866 99 49 98 1 1146743728 33589568 10139904
09:09:12 49685 8192000000 10074549360 10504888320 1120130208 12.028 100 33 66 0 1182322992 55325632 6867152
09:11:06 76954 8192000000 10094516032 10550087680 1133081872 12.150 100 15 30 0 1194312832 58306560 2924400
09:13:29 163537 8192000000 10087876448 10528727040 1095734016 11.797 96 23 46 0 1153385728 52798272 4853440
09:17:28 122668 8192000000 10107325920 10538250240 1111832048 11.950 98 29 58 0 1203400816 85277200 6291568
09:20:37 35390 8192000000 10094704272 10586472448 1112070832 11.952 99 10 20 1 1182044272 68003936 1969504
09:22:21 62989 8192000000 10071349760 10512035840 1125591248 12.080 99 30 60 0 1163832368 30702112 7539008
09:24:24 69928 8192000000 10117609312 10646806528 1140325024 12.219 100 12 24 1 1239216976 95514144 3377808
09:27:00 72017 8192000000 10084159344 10543489024 1125941968 12.083 98 18 36 1 1180400864 50566352 3892544
09:29:13 73497 8192000000 10124032144 10597191680 1123865472 12.063 98 13 26 0 1192965104 66654960 2444672
09:31:27 63581 8192000000 10097092512 10588688384 1142983568 12.244 99 12 24 0 1205602304 60114256 2504480
09:33:42 41093 8192000000 10131709344 10601783296 1147134144 12.283 99 38 76 0 1233578752 78618800 7825808
09:35:30 77505 8192000000 10165430336 10652598272 1156326208 12.369 100 23 46 0 1226736720 65854000 4556512
09:37:50 73782 8192000000 10108944672 10565013504 1157134384 12.376 99 25 50 1 1217188512 54922560 5131568
09:40:19 84729 8192000000 10183611888 10641764352 1133011376 12.150 97 34 68 1 1221848736 81321072 7516288
09:43:39 122860 8192000000 10085157584 10539511808 1108573808 11.919 96 17 34 0 1177858832 65865472 3419552
09:46:44 57612 8192000000 10059309712 10528612352 1106397792 11.898 97 5 10 0 1137912480 30727472 787216
09:48:42 57370 8192000000 10091871536 10516897792 1093605376 11.777 97 28 57 0 1146072656 46665168 5802112
09:50:43 73664 8192000000 10110664512 10555215872 1075434848 11.604 95 24 48 1 1145835360 65149072 5251440
09:53:28 55426 8192000000 10075806704 10522755072 1102920336 11.865 98 28 56 0 1179877616 70918896 6038384
09:55:27 38940 8192000000 10091069104 10532081664 1112631312 11.957 99 19 38 0 1177797184 60677664 4488208
09:57:06 85513 8192000000 10054037584 10541170688 1106708928 11.901 100 3 6 0 1167990992 60849616 432448
09:59:39 183305 8192000000 10074736512 10517753856 1082181984 11.668 96 22 44 0 1127774112 40924528 4667600
10:03:44 65735 8192000000 10072268112 10517139456 1115110960 11.981 99 26 52 1 1148085840 27161888 5812992
10:06:04 48000 8192000000 10071283120 10539630592 1117902096 12.007 99 9 17 0 1187403024 67648448 1852480
10:07:53 61470 8192000000 10113706800 10550128640 1136037504 12.178 99 22 44 0 1191937600 51233088 4667008
10:11:02 38363 8192000000 10039325632 10473979904 1096687120 11.806 97 13 26 2 1129841520 30749024 2405376
10:12:41 70816 8192000000 10124868576 10558615552 1120925312 12.036 99 24 48 0 1203547152 77573808 5048032
10:15:25 32346 8192000000 10098077088 10520363008 1123952208 12.064 99 40 80 0 1161593184 29101120 8539856
10:17:06 39854 8192000000 10136510112 10581585920 1128289488 12.105 99 19 38 1 1213020992 80637424 4094080
10:18:47 96236 8192000000 10074816496 10528002048 1115291232 11.982 98 16 32 1 1155780000 37390320 3098448
10:21:25 125204 8192000000 10129186448 10587389952 1129587696 12.117 99 47 92 0 1191696528 52572768 9536064
10:24:37 55313 8192000000 10057932384 10532925440 1117424688 12.003 98 9 17 1 1167560896 48262160 1874048
10:26:36 49585 8192000000 10096494720 10559467520 1130790704 12.129 99 17 34 0 1180021104 45571552 3658848
10:28:25 65329 8192000000 10104339376 10548834304 1130884368 12.130 99 14 27 1 1197673984 63560016 3229600
10:30:53 74959 8192000000 10119886704 10575511552 1143607632 12.249 99 21 42 0 1222039856 73707008 4725216
10:33:12 56910 8192000000 10087920592 10544373760 1124644896 12.071 98 24 48 1 1193201728 63073840 5482992
10:35:18 52303 8192000000 10092077376 10554347520 1130386976 12.125 99 39 78 0 1184397056 45698384 8311696

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 29-11-2021 10:38:18 to 29-11-2021 10:38:33.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 102 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 29-11-2021 10:38:33 to 29-11-2021 10:38:35.

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          222418
MON$OLDEST_ACTIVE               225522
MON$OLDEST_SNAPSHOT             225522
MON$NEXT_TRANSACTION            225522
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                       2039840
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             777
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2672 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2672 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2672 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2672 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 ---
:63: 
:64: mscs-test-161.budzdorov.ru    Mon Nov 29 10:37:16 2021
:65:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:66: 
:67: 
:68: mscs-test-161.budzdorov.ru    Mon Nov 29 10:37:18 2021
:69:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:70: 
:71: 
:72: mscs-test-161.budzdorov.ru    Mon Nov 29 10:37:19 2021
:73:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:74: 
--- end of diff output ---

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

29.11.21 10:38:36. 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-11-29 10:38:39 No severe PSQL-related problems occured
29.11.21 10:38:40. End of report.