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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16303672 kB
MemAvailable 31343664 kB
Buffers 0 kB
Cached 15421400 kB
SwapCached 1180 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 2022-01-09 10:37:13
DTS_END                         2022-01-09 10:37:13.5340
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/40906
TRN_ID                          205958
ATT_ID                          608
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: 8606.97 27843 1032836
customer order: creation 156.62 534 18794
customer order: refuse 13.21 486 1585
order to supplier: creation 41.96 1444 5035
order to supplier: removal 3.43 167471 411
invoice (draft): creation 41.86 3586 5023
invoice (draft): removal 48.91 1091 5869
invoice accept: apply 38.17 28229 4581
invoice accept: cancel 44.03 1363 5284
customer reserve: creation 3609.88 262 433185
customer reserve: removal 4337.77 102 520533
realization accept: apply 77.24 302 9269
realization accept: cancel 77.71 168 9325
payment from customer: creation 52.79 185 6335
payment from customer: removal 4.88 116 586
payment to supplier: creation 44.23 211 5308
payment to supplier: removal 2.85 115 342
service: total inventory turnovers 3.69 4521 443
service: total monetary turnovers 6.78 362 814
service: aggregate perf. data 0.91 15104 109
service: refresh index statistics 0.04 331199 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-01-2022 10:38:42 to 09-01-2022 10:38:44.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8229
TEST_TIME 2 5591
TEST_TIME 3 5712
TEST_TIME 4 5461
TEST_TIME 5 5428
TEST_TIME 6 5790
TEST_TIME 7 5902
TEST_TIME 8 6081
TEST_TIME 9 6337
TEST_TIME 10 6446
TEST_TIME 11 6712
TEST_TIME 12 6606
TEST_TIME 13 6777
TEST_TIME 14 6745
TEST_TIME 15 6762
TEST_TIME 16 6696
TEST_TIME 17 6775
TEST_TIME 18 6920
TEST_TIME 19 7198
TEST_TIME 20 7480
TEST_TIME 21 7799
TEST_TIME 22 8002
TEST_TIME 23 8271
TEST_TIME 24 8676
TEST_TIME 25 8547
TEST_TIME 26 8636
TEST_TIME 27 9037
TEST_TIME 28 9292
TEST_TIME 29 9213
TEST_TIME 30 9161
TEST_TIME 31 9418
TEST_TIME 32 9686
TEST_TIME 33 9947
TEST_TIME 34 9964
TEST_TIME 35 10167
TEST_TIME 36 10190
TEST_TIME 37 10231
TEST_TIME 38 10368
TEST_TIME 39 10280
TEST_TIME 40 10147
TEST_TIME 41 9999
TEST_TIME 42 9895
TEST_TIME 43 10088
TEST_TIME 44 10101
TEST_TIME 45 9903
TEST_TIME 46 9799
TEST_TIME 47 9671
TEST_TIME 48 9590
TEST_TIME 49 9557
TEST_TIME 50 9541
TEST_TIME 51 9470
TEST_TIME 52 9507
TEST_TIME 53 9422
TEST_TIME 54 9311
TEST_TIME 55 9246
TEST_TIME 56 9357
TEST_TIME 57 9380
TEST_TIME 58 9261
TEST_TIME 59 9190
TEST_TIME 60 9130
TEST_TIME 61 9091
TEST_TIME 62 9108
TEST_TIME 63 9077
TEST_TIME 64 8967
TEST_TIME 65 8894
TEST_TIME 66 8838
TEST_TIME 67 8945
TEST_TIME 68 8889
TEST_TIME 69 8912
TEST_TIME 70 8926
TEST_TIME 71 8905
TEST_TIME 72 8854
TEST_TIME 73 8936
TEST_TIME 74 8925
TEST_TIME 75 8943
TEST_TIME 76 9060
TEST_TIME 77 9040
TEST_TIME 78 9159
TEST_TIME 79 9145
TEST_TIME 80 9114
TEST_TIME 81 9066
TEST_TIME 82 8960
TEST_TIME 83 9064
TEST_TIME 84 9005
TEST_TIME 85 9005
TEST_TIME 86 8950
TEST_TIME 87 8946
TEST_TIME 88 8908
TEST_TIME 89 8862
TEST_TIME 90 8828
TEST_TIME 91 8755
TEST_TIME 92 8781
TEST_TIME 93 8776
TEST_TIME 94 8765
TEST_TIME 95 8779
TEST_TIME 96 8789
TEST_TIME 97 8751
TEST_TIME 98 8728
TEST_TIME 99 8803
TEST_TIME 100 8809
TEST_TIME 101 8801
TEST_TIME 102 8821
TEST_TIME 103 8782
TEST_TIME 104 8851
TEST_TIME 105 8858
TEST_TIME 106 8878
TEST_TIME 107 8955
TEST_TIME 108 8929
TEST_TIME 109 8944
TEST_TIME 110 8868
TEST_TIME 111 8855
TEST_TIME 112 8836
TEST_TIME 113 8795
TEST_TIME 114 8742
TEST_TIME 115 8728
TEST_TIME 116 8717
TEST_TIME 117 8698
TEST_TIME 118 8687
TEST_TIME 119 8693
TEST_TIME 120 8628

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 09-01-2022 10:38:45 to 09-01-2022 10:38:49.

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 1432054 1432054 0 0.00 0 35179 14 0 0
doc_list_biud 1432054 1432054 0 0.00 0 82792 44 0 0
sp_add_invoice_to_stock 4701 4581 120 2.55 40 202803 28229 0 120
sp_cancel_adding_invoice 5284 5284 0 0.00 7 116749 1364 0 0
sp_cancel_client_order 1585 1585 0 0.00 6 13227 486 0 0
sp_cancel_customer_reserve 520533 520533 0 0.00 1 33563 103 0 0
sp_cancel_pay_from_customer 586 586 0 0.00 3 4075 117 0 0
sp_cancel_pay_to_supplier 342 342 0 0.00 4 1806 115 0 0
sp_cancel_supplier_invoice 5885 5869 16 0.27 3 82799 1091 0 16
sp_cancel_supplier_order 411 411 0 0.00 14 1534797 167472 0 0
sp_cancel_write_off 9325 9325 0 0.00 2 10590 168 0 0
sp_client_order 18794 18794 0 0.00 6 56543 535 0 0
sp_customer_reserve 433185 433185 0 0.00 1 100412 263 0 0
sp_fill_shopping_cart 28848 28848 0 0.00 2 19588 87 0 0
sp_fill_shopping_cart_clo_res 433161 433161 0 0.00 0 16290 21 0 0
sp_get_clo_for_invoice 4539 4539 0 0.00 0 33354 367 0 0
sp_kill_cost_storno 15537 15537 0 0.00 0 35166 82 0 0
sp_kill_qstorno_ret_qs2qd 1056671 1056671 0 0.00 0 82791 46 0 0
sp_kill_qty_storno 556809 556809 0 0.00 0 82792 105 0 0
sp_lock_selected_doc 19104 19104 0 0.00 0 9695 8 0 0
sp_make_cost_storno 25445 25445 0 0.00 0 26315 86 0 0
sp_multiply_rows_for_pdistr 25445 25445 0 0.00 0 9292 42 0 0
sp_multiply_rows_for_qdistr 18794 18794 0 0.00 0 26307 205 0 0
sp_pay_from_customer 6335 6335 0 0.00 6 13861 186 0 0
sp_pay_to_supplier 5308 5308 0 0.00 6 26398 211 0 0
sp_payment_common 11643 11643 0 0.00 2 7361 47 0 0
sp_qd_handle_on_cancel_clo 1585 1585 0 0.00 0 7260 399 0 0
sp_qd_handle_on_invoice_upd_sts 9817 9817 0 0.00 0 43865 595 0 0
sp_qd_handle_on_reserve_upd_sts 9269 9269 0 0.00 0 25303 70 0 0
sp_reserve_write_off 9269 9269 0 0.00 8 25374 303 0 0
sp_supplier_invoice 5023 5023 0 0.00 9 135464 3587 0 0
sp_supplier_order 5035 5035 0 0.00 7 54973 1445 0 0
srv_aggregate_perf_data 109 109 0 0.00 2452 156061 15104 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 796 123223 36789 0 0
srv_find_qd_qs_mism 988160 988160 0 0.00 0 23124 11 0 0
srv_make_invnt_saldo 443 443 0 0.00 51 111367 4522 0 0
srv_make_money_saldo 814 814 0 0.00 1 10496 363 0 0
srv_recalc_idx_stat 5 5 0 0.00 89685 567400 331199 0 0
t$perf-abend:sp_add_invoice_to_stock 120 120 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4581 4581 0 0.00 1 30915 1048 0 0
t$perf-norm:sp_cancel_adding_invoice 382 382 0 0.00 1 6959 512 0 0
t$perf-norm:sp_cancel_client_order 1585 1585 0 0.00 0 16530 40 0 0
t$perf-norm:sp_cancel_customer_reserve 661 661 0 0.00 0 24742 68 0 0
t$perf-norm:sp_cancel_pay_from_customer 586 586 0 0.00 0 4342 23 0 0
t$perf-norm:sp_cancel_pay_to_supplier 342 342 0 0.00 0 5735 29 0 0
t$perf-norm:sp_cancel_supplier_invoice 388 388 0 0.00 0 1687 33 0 0
t$perf-norm:sp_cancel_supplier_order 411 411 0 0.00 0 93549 8450 0 0
t$perf-norm:sp_cancel_write_off 947 947 0 0.00 0 5476 26 0 0
t$perf-norm:sp_client_order 18794 18794 0 0.00 0 6109 41 0 0
t$perf-norm:sp_customer_reserve 946 946 0 0.00 0 1111 20 0 0
t$perf-norm:sp_pay_from_customer 6335 6335 0 0.00 0 9495 24 0 0
t$perf-norm:sp_pay_to_supplier 5308 5308 0 0.00 0 8865 23 0 0
t$perf-norm:sp_reserve_write_off 9269 9269 0 0.00 0 5210 27 0 0
t$perf-norm:sp_supplier_invoice 5023 5023 0 0.00 0 4977 32 0 0
t$perf-norm:sp_supplier_order 5035 5035 0 0.00 0 6391 34 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 654 34 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 531 14 0 0
t$perf-norm:srv_make_invnt_saldo 443 443 0 0.00 0 5059 27 0 0
t$perf-norm:srv_make_money_saldo 814 814 0 0.00 0 874 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 10 188 55 0 0
v_add_invoice_to_stock 9306 9066 240 2.57 0 13694 31 0 240
v_all_customers 34366 34366 0 0.00 0 81 4 0 0
v_all_suppliers 28170 28170 0 0.00 0 135 4 0 0
v_all_wares 37588 37588 0 0.00 0 102 4 0 0
v_cancel_adding_invoice 764 764 0 0.00 0 253 13 0 0
v_cancel_client_order 3170 3170 0 0.00 0 12481 15 0 0
v_cancel_customer_prepayment 1172 1172 0 0.00 0 1618 12 0 0
v_cancel_customer_reserve 1322 1322 0 0.00 0 2032 11 0 0
v_cancel_payment_to_supplier 684 684 0 0.00 0 571 13 0 0
v_cancel_supplier_invoice 808 776 32 3.96 0 12440 40 0 32
v_cancel_supplier_order 822 822 0 0.00 0 1374 12 0 0
v_cancel_write_off 1894 1894 0 0.00 0 1311 21 0 0
v_max_id_clo_ord 5035 5035 0 0.00 0 7864 6 0 0
v_max_id_clo_res 946 946 0 0.00 0 3729 20 0 0
v_max_id_ord_sup 5019 5019 0 0.00 0 4933 9 0 0
v_max_non_paid_invoice 1277 1277 0 0.00 0 58 7 0 0
v_max_non_paid_realizn 3328 3328 0 0.00 0 2836 9 0 0
v_min_id_clo_ord 5035 5035 0 0.00 0 7851 10 0 0
v_min_id_clo_res 946 946 0 0.00 0 1262 11 0 0
v_min_id_ord_sup 5019 5019 0 0.00 0 3721 7 0 0
v_min_non_paid_invoice 5308 5308 0 0.00 0 7311 18 0 0
v_min_non_paid_realizn 6335 6335 0 0.00 0 5914 18 0 0
v_our_firm 9236 9236 0 0.00 0 143 4 0 0
v_reserve_write_off 18538 18538 0 0.00 0 13706 16 0 0
x_lock_depdocs_on_canc_invoice 10765 10765 0 0.00 0 18085 132 0 0
x_lock_depdocs_on_canc_sup_ord 411 411 0 0.00 0 4740 369 0 0
x_make_qstorno_1000_1200 5035 5035 0 0.00 2 54954 1268 0 0
x_make_qstorno_1200_2000 5023 5023 0 0.00 3 135381 3340 0 0
x_make_qstorno_2100_3300 433185 433185 0 0.00 0 100408 229 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-01-2022 10:38:50 to 09-01-2022 10:38:54.

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:17 144 8192000000 8843050864 8871866368 23528960 0.286 8 7 8 1 23994416 263184 202272
08:08:18 29551 8192000000 9733577952 10032693248 811956960 9.017 100 89 178 0 840797072 7919008 20921104
08:09:52 23074 8192000000 9774221408 10087526400 851458192 9.415 100 84 168 0 883265072 12328944 19477936
08:11:23 15114 8192000000 9818422320 10140172288 889313792 9.792 100 85 170 0 928403648 19725936 19363920
08:12:41 12971 8192000000 9881970432 10215809024 930504528 10.200 100 84 167 0 989993728 41177888 18311312
08:13:56 6633 8192000000 9920132192 10262982656 959050272 10.480 100 82 165 0 1036022256 59890752 17081232
08:15:06 3086 8192000000 9974320464 10324054016 991237536 10.793 100 86 169 0 1093201872 84616672 17347664
08:16:11 1607 8192000000 9978758752 10337931264 1015198048 11.026 100 88 170 0 1099260432 67545072 16517312
08:17:15 1716 8192000000 10024813152 10386980864 1028433344 11.153 100 88 173 0 1138060048 92298624 17328080
08:18:19 1133 8192000000 10025641504 10398703616 1047752016 11.339 100 94 179 0 1147182496 80867984 18562496
08:19:21 1002 8192000000 10027151776 10402701312 1062054160 11.476 100 96 189 0 1156301712 75589936 18657616
08:20:23 1120 8192000000 10035665792 10410930176 1073994368 11.590 100 97 188 4 1155476160 62660928 18820864
08:21:26 2126 8192000000 10033630144 10414637056 1083293776 11.679 100 79 147 0 1152405616 55625296 13486544
08:22:29 1093 8192000000 10067049856 10454290432 1090114384 11.744 100 96 179 1 1186623760 77028448 19480928
08:23:31 5312 8192000000 10047156944 10435710976 1088513840 11.729 100 69 134 1 1179899376 78291392 13094144
08:24:38 1090 8192000000 10070741360 10455269376 1101310640 11.850 100 89 172 4 1190431104 71696368 17424096
08:25:45 2078 8192000000 10073947616 10463395840 1099689984 11.835 100 75 145 1 1190575072 77204608 13680480
08:26:48 1011 8192000000 10067721696 10457817088 1100749808 11.845 100 90 175 2 1190217024 72203680 17263536
08:27:52 1226 8192000000 10075626656 10464788480 1106864944 11.903 100 95 177 1 1203791296 76732000 20194352
08:28:54 8362 8192000000 10058247296 10443923456 1095908128 11.799 100 85 159 2 1175761152 65215584 14637440
08:30:03 965 8192000000 10048180384 10430988288 1076407216 11.613 100 77 152 0 1168848400 77279184 15162000
08:31:06 2053 8192000000 10042000336 10430730240 1078747808 11.636 99 73 137 1 1166621440 75526960 12346672
08:32:08 1059 8192000000 10007059840 10386206720 1065662800 11.511 100 88 166 0 1124764400 43136288 15965312
08:33:10 1201 8192000000 10011810288 10391883776 1071886800 11.570 100 90 170 2 1128052848 39156992 17009056
08:34:12 1265 8192000000 10001859456 10378809344 1053913936 11.398 99 93 177 5 1126615600 53652048 19049616
08:35:14 4331 8192000000 9985564688 10366603264 1022656752 11.098 98 68 132 1 1107407808 72472544 12278512
08:36:19 1761 8192000000 9957608032 10330525696 1020535344 11.077 99 81 151 1 1084039744 47409168 16095232
08:37:21 3580 8192000000 10016835168 10397552640 1055989248 11.418 100 78 146 1 1137707008 68455808 13261952
08:38:37 11888 8192000000 10027659200 10411114496 1067246944 11.526 100 69 127 3 1142776128 63633760 11895424
08:39:53 9221 8192000000 10039905296 10429276160 1080203392 11.649 100 63 111 0 1163597424 71873520 11520512
08:41:18 3855 8192000000 10055105904 10438131712 1081385872 11.661 99 72 130 1 1173080192 78789584 12904736
08:42:28 17801 8192000000 10141485840 10536919040 1111413952 11.946 100 77 150 0 1258801248 130790864 16596432
08:43:55 1210 8192000000 10168302608 10554331136 1128988896 12.112 100 90 177 0 1281202896 132465616 19748384
08:45:02 4769 8192000000 10189709968 10595176448 1136226224 12.180 100 70 138 0 1303846928 152519008 15101696
08:46:12 3145 8192000000 10194060160 10599530496 1138195520 12.199 100 79 155 1 1310063152 154398240 17469392
08:47:21 1824 8192000000 10219351088 10618273792 1144724384 12.260 100 94 184 0 1343333712 177600048 21009280
08:48:27 3033 8192000000 10248496416 10650005504 1159279680 12.397 100 76 150 0 1358814336 183537312 15997344
08:49:33 4506 8192000000 10277962720 10686164992 1170025232 12.497 100 73 143 0 1388585552 202714080 15846240
08:50:44 1385 8192000000 10309322640 10711011328 1178240384 12.574 100 94 187 1 1419635024 220689776 20704864
08:51:50 2552 8192000000 10344406832 10747396096 1190936480 12.692 100 92 174 0 1453062304 241519904 20605920
08:52:57 1555 8192000000 10391484480 10800181248 1197945728 12.757 100 86 169 0 1504123552 286084656 20093168
08:54:02 1720 8192000000 10451598848 10859339776 1197619008 12.754 100 96 189 1 1560878992 341727344 21532640
08:55:08 2578 8192000000 10432994864 10870366208 1196965152 12.748 100 97 192 0 1536547808 317194992 22387664
08:56:14 796 8192000000 10453452304 10894004224 1182996608 12.618 99 98 199 0 1557440016 350541536 23901872
08:57:19 1693 8192000000 10459911520 10909224960 1190817856 12.691 100 91 177 1 1568562512 355937328 21807328
08:58:24 1110 8192000000 10450805344 10882818048 1200826144 12.784 100 98 193 0 1553549568 330429904 22293520
08:59:26 2526 8192000000 10444928576 10912411648 1203404816 12.808 100 70 138 0 1547927664 327281792 17241056
09:00:34 790 8192000000 10432754880 10900004864 1198673440 12.764 100 89 175 1 1534230800 315229536 20327824
09:01:37 4196 8192000000 10415177440 10856976384 1191025296 12.693 100 75 150 1 1523522496 314471184 18026016
09:02:49 4329 8192000000 10414069040 10857050112 1172964992 12.525 100 64 122 1 1516370432 326736192 16669248
09:04:00 1305 8192000000 10390004016 10847313920 1186570752 12.651 100 89 171 0 1490428464 283899056 19958656
09:05:03 2160 8192000000 10320900512 10781847552 1158252032 12.387 98 69 138 1 1415781008 241606864 15922112
09:06:08 17888 8192000000 10327869072 10753204224 1170390464 12.500 100 54 98 0 1425669536 242373600 12905472
09:07:46 16228 8192000000 10331396144 10839924736 1153421152 12.342 100 28 56 0 1461325056 300540976 7362928
09:09:51 27315 8192000000 10223268592 10675560448 1131539248 12.136 98 30 60 1 1329374640 191275712 6559680
09:11:22 31477 8192000000 10154763168 10650935296 1124098592 12.066 97 19 38 0 1265656896 137393264 4165040
09:13:06 49222 8192000000 10113681664 10558828544 1110182672 11.934 97 18 36 0 1209916336 96130848 3602816
09:15:06 73009 8192000000 10127795056 10622414848 1130925488 12.130 99 12 24 0 1246288272 112906080 2456704
09:17:23 33184 8192000000 10173135024 10602127360 1146774576 12.279 100 55 109 2 1255146400 96279696 12092128
09:18:57 101979 8192000000 10123962304 10613501952 1122259936 12.048 98 41 79 1 1255428672 124112368 9056368
09:22:05 58752 8192000000 10071609312 10537459712 1119730720 12.024 99 9 19 0 1163213696 41546000 1936976
09:24:04 81632 8192000000 10115950032 10556997632 1089265312 11.736 96 23 46 1 1159846752 65259568 5321872
09:26:26 21574 8192000000 10162197280 10608111616 1133947216 12.159 100 39 74 2 1254034528 111728560 8358752
09:28:03 85205 8192000000 10122909216 10598711296 1109954928 11.932 98 15 30 1 1230447392 116932704 3559760
09:30:29 58021 8192000000 10168882608 10627670016 1140397632 12.219 100 13 26 0 1265066304 121689600 2979072
09:32:40 55065 8192000000 10118422192 10609311744 1102209808 11.859 97 14 28 0 1181987152 75904336 3873008
09:35:09 25141 8192000000 10070188096 10513403904 1141184064 12.227 100 36 66 1 1174604400 27022320 6398016
09:36:37 62875 8192000000 10125184944 10579324928 1141653616 12.231 99 38 75 1 1220737760 71447760 7636384
09:39:04 58037 8192000000 10086948464 10540306432 1131086512 12.132 99 15 29 1 1211789728 77383936 3319280
09:41:06 63108 8192000000 10118039120 10552864768 1145749728 12.270 100 31 58 0 1200083040 47382528 6950784
09:43:15 36468 8192000000 10139369888 10601820160 1140635360 12.222 99 29 58 0 1245548208 99091872 5820976
09:44:51 62987 8192000000 10109847296 10555969536 1142393728 12.238 99 20 40 0 1179142608 32804512 3944368
09:47:05 75870 8192000000 10097086416 10567548928 1142598256 12.240 99 16 32 0 1202898016 56778128 3521632
09:49:22 37154 8192000000 10168231696 10617409536 1161103664 12.414 99 41 79 0 1254977184 85623344 8250176
09:51:02 85175 8192000000 10184925616 10674831360 1129744384 12.119 97 16 32 0 1225078736 91662416 3671936
09:53:28 37853 8192000000 10094414944 10582962176 1150543008 12.315 100 17 31 0 1233535536 79782480 3210048
09:55:09 29317 8192000000 10173427568 10623451136 1154895472 12.355 100 35 70 0 1261328576 98831920 7601184
09:56:42 78416 8192000000 10140345600 10581872640 1163377120 12.435 99 32 65 0 1240023872 69678704 6968048
09:59:07 121003 8192000000 10102871536 10605899776 1129645536 12.118 97 34 67 0 1205169312 67146544 8377232
10:02:31 90105 8192000000 10083819872 10535587840 1125056640 12.075 98 12 24 0 1171835904 44400848 2378416
10:05:20 70952 8192000000 10085733504 10530967552 1121948320 12.045 97 20 40 0 1155266640 28609728 4708592
10:07:34 74769 8192000000 10111155520 10564337664 1113233072 11.963 97 20 40 0 1198742352 81077296 4431984
10:10:07 79752 8192000000 10041253376 10496098304 1104640912 11.882 98 12 24 0 1184150528 76733936 2775680
10:12:27 84460 8192000000 10053761536 10517913600 1095695248 11.797 97 12 24 0 1124341760 26143488 2503024
10:15:04 12996 8192000000 10117931776 10545893376 1132743744 12.147 99 43 86 0 1197934000 56837456 8352800
10:16:30 36759 8192000000 10072128688 10517221376 1107219120 11.906 98 26 52 1 1153819072 41530384 5069568
10:18:07 16564 8192000000 10112057072 10540355584 1124189712 12.067 99 42 84 1 1209919888 77236192 8493984
10:19:24 33421 8192000000 10090897472 10540691456 1097272880 11.812 98 41 82 3 1171863264 65730752 8859632
10:20:58 34461 8192000000 10140442000 10604068864 1123455120 12.060 100 40 80 1 1229080848 97357392 8268336
10:22:34 43424 8192000000 10145230640 10583732224 1137663216 12.194 100 40 80 1 1222402080 76600912 8137952
10:24:20 31721 8192000000 10067659024 10522013696 1117320224 12.002 98 20 40 1 1181196048 60110496 3765328
10:25:52 123220 8192000000 10072625872 10537988096 1128292080 12.105 100 30 60 0 1217566256 82762176 6512000
10:29:03 40529 8192000000 10063805520 10527821824 1144563616 12.258 100 15 30 0 1181931472 34229232 3138624
10:30:57 69484 8192000000 10092099232 10565550080 1148014080 12.291 100 11 21 0 1190664192 40002768 2647344
10:33:46 68731 8192000000 10093031328 10553921536 1128793936 12.110 98 20 40 1 1172778272 39481248 4503088
10:35:55 49379 8192000000 10118432192 10568097792 1150739424 12.316 100 17 34 0 1199778448 45526592 3512432

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 09-01-2022 10:38:56 to 09-01-2022 10:39:12.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 120 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 09-01-2022 10:39:13 to 09-01-2022 10:39:14.

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          203310
MON$OLDEST_ACTIVE               207375
MON$OLDEST_SNAPSHOT             207375
MON$NEXT_TRANSACTION            207375
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                       2064608
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             750
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2704 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2704 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2704 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2704 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 ---
:215: 
:216: mscs-test-161.budzdorov.ru    Sun Jan  9 10:37:16 2022
:217:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:218: 
:219: 
:220: mscs-test-161.budzdorov.ru    Sun Jan  9 10:37:16 2022
:221:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:222: 
:223: 
:224: mscs-test-161.budzdorov.ru    Sun Jan  9 10:37:18 2022
:225:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:226: 
--- end of diff output ---

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

09.01.22 10:39:15. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-01-09 10:39:18 No severe PSQL-related problems occured
09.01.22 10:39:19. End of report.