Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.12.2022 04: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 14464200 kB
MemAvailable 30054676 kB
Buffers 20 kB
Cached 17192196 kB
SwapCached 3428 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.3
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-12-19 04:37:22
DTS_END                         2022-12-19 04:37:22.7640
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/47778
TRN_ID                          211216
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: 8558.78 29016 1027054
customer order: creation 160.26 521 19231
customer order: refuse 13.58 480 1629
order to supplier: creation 43.22 1399 5186
order to supplier: removal 3.28 194338 393
invoice (draft): creation 43.04 3588 5165
invoice (draft): removal 46.63 1130 5595
invoice accept: apply 40.05 29685 4806
invoice accept: cancel 42.66 1519 5119
customer reserve: creation 3708.18 280 444982
customer reserve: removal 4184.64 121 502157
realization accept: apply 79.48 318 9538
realization accept: cancel 69.30 190 8316
payment from customer: creation 57.20 201 6864
payment from customer: removal 5.53 146 664
payment to supplier: creation 46.35 224 5562
payment to supplier: removal 3.06 149 367
service: total inventory turnovers 3.75 4866 450
service: total monetary turnovers 7.61 398 913
service: aggregate perf. data 0.94 17666 113
service: refresh index statistics 0.03 323096 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-12-2022 04:38:57 to 19-12-2022 04:38:59.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7679
TEST_TIME 2 6479
TEST_TIME 3 5659
TEST_TIME 4 5450
TEST_TIME 5 5972
TEST_TIME 6 6019
TEST_TIME 7 6768
TEST_TIME 8 6831
TEST_TIME 9 6899
TEST_TIME 10 7088
TEST_TIME 11 6943
TEST_TIME 12 6935
TEST_TIME 13 6831
TEST_TIME 14 6839
TEST_TIME 15 6819
TEST_TIME 16 6942
TEST_TIME 17 7181
TEST_TIME 18 7252
TEST_TIME 19 7352
TEST_TIME 20 7504
TEST_TIME 21 7528
TEST_TIME 22 7738
TEST_TIME 23 7773
TEST_TIME 24 7928
TEST_TIME 25 8022
TEST_TIME 26 8160
TEST_TIME 27 8128
TEST_TIME 28 8589
TEST_TIME 29 8585
TEST_TIME 30 8815
TEST_TIME 31 8769
TEST_TIME 32 8564
TEST_TIME 33 8808
TEST_TIME 34 9115
TEST_TIME 35 9316
TEST_TIME 36 9537
TEST_TIME 37 9781
TEST_TIME 38 9975
TEST_TIME 39 9959
TEST_TIME 40 10484
TEST_TIME 41 10376
TEST_TIME 42 10193
TEST_TIME 43 10329
TEST_TIME 44 10208
TEST_TIME 45 10180
TEST_TIME 46 10083
TEST_TIME 47 10277
TEST_TIME 48 10098
TEST_TIME 49 10003
TEST_TIME 50 9933
TEST_TIME 51 9833
TEST_TIME 52 9867
TEST_TIME 53 9734
TEST_TIME 54 9652
TEST_TIME 55 9645
TEST_TIME 56 9596
TEST_TIME 57 9482
TEST_TIME 58 9358
TEST_TIME 59 9287
TEST_TIME 60 9250
TEST_TIME 61 9283
TEST_TIME 62 9342
TEST_TIME 63 9371
TEST_TIME 64 9364
TEST_TIME 65 9314
TEST_TIME 66 9247
TEST_TIME 67 9166
TEST_TIME 68 9177
TEST_TIME 69 9156
TEST_TIME 70 9084
TEST_TIME 71 9025
TEST_TIME 72 9036
TEST_TIME 73 9111
TEST_TIME 74 9053
TEST_TIME 75 9000
TEST_TIME 76 9011
TEST_TIME 77 8972
TEST_TIME 78 9127
TEST_TIME 79 9066
TEST_TIME 80 9067
TEST_TIME 81 9171
TEST_TIME 82 9231
TEST_TIME 83 9143
TEST_TIME 84 9053
TEST_TIME 85 9021
TEST_TIME 86 8963
TEST_TIME 87 8925
TEST_TIME 88 8923
TEST_TIME 89 8901
TEST_TIME 90 8907
TEST_TIME 91 8862
TEST_TIME 92 8825
TEST_TIME 93 8791
TEST_TIME 94 8749
TEST_TIME 95 8732
TEST_TIME 96 8728
TEST_TIME 97 8722
TEST_TIME 98 8694
TEST_TIME 99 8700
TEST_TIME 100 8684
TEST_TIME 101 8765
TEST_TIME 102 8714
TEST_TIME 103 8765
TEST_TIME 104 8758
TEST_TIME 105 8872
TEST_TIME 106 8841
TEST_TIME 107 8817
TEST_TIME 108 8817
TEST_TIME 109 8791
TEST_TIME 110 8745
TEST_TIME 111 8722
TEST_TIME 112 8675
TEST_TIME 113 8633
TEST_TIME 114 8667
TEST_TIME 115 8635
TEST_TIME 116 8623
TEST_TIME 117 8601
TEST_TIME 118 8594
TEST_TIME 119 8616
TEST_TIME 120 8582

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 19-12-2022 04:39:00 to 19-12-2022 04:39:03.

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 1435923 1435923 0 0.00 0 20937 15 0 0
doc_list_biud 1435902 1435902 0 0.00 0 32058 49 0 0
sp_add_invoice_to_stock 4938 4806 132 2.67 45 194394 29685 0 132
sp_cancel_adding_invoice 5119 5119 0 0.00 7 84436 1519 0 0
sp_cancel_client_order 1629 1629 0 0.00 6 6742 481 0 0
sp_cancel_customer_reserve 502157 502157 0 0.00 1 21799 121 0 0
sp_cancel_pay_from_customer 664 664 0 0.00 4 2366 146 0 0
sp_cancel_pay_to_supplier 367 367 0 0.00 4 1614 149 0 0
sp_cancel_supplier_invoice 5602 5595 7 0.12 4 25156 1131 0 7
sp_cancel_supplier_order 393 393 0 0.00 9 1539589 194338 0 0
sp_cancel_write_off 8316 8316 0 0.00 2 9812 191 0 0
sp_client_order 19231 19231 0 0.00 5 24533 522 0 0
sp_customer_reserve 444982 444982 0 0.00 0 39141 280 0 0
sp_fill_shopping_cart 29567 29567 0 0.00 1 30035 106 0 0
sp_fill_shopping_cart_clo_res 444947 444947 0 0.00 0 18717 24 0 0
sp_get_clo_for_invoice 4767 4767 0 0.00 0 36793 473 0 0
sp_kill_cost_storno 14466 14466 0 0.00 0 11788 87 0 0
sp_kill_qstorno_ret_qs2qd 1018618 1018618 0 0.00 0 32057 52 0 0
sp_kill_qty_storno 537512 537512 0 0.00 0 32058 120 0 0
sp_lock_selected_doc 19720 19720 0 0.00 0 8844 8 0 0
sp_make_cost_storno 26731 26731 0 0.00 0 20925 92 0 0
sp_multiply_rows_for_pdistr 26728 26728 0 0.00 0 10243 42 0 0
sp_multiply_rows_for_qdistr 19231 19231 0 0.00 0 15553 190 0 0
sp_pay_from_customer 6864 6864 0 0.00 5 12812 201 0 0
sp_pay_to_supplier 5562 5562 0 0.00 6 17656 224 0 0
sp_payment_common 12425 12425 0 0.00 2 14877 58 0 0
sp_qd_handle_on_cancel_clo 1629 1629 0 0.00 1 6733 400 0 0
sp_qd_handle_on_invoice_upd_sts 9884 9884 0 0.00 0 31055 640 0 0
sp_qd_handle_on_reserve_upd_sts 9538 9538 0 0.00 0 14201 75 0 0
sp_reserve_write_off 9538 9538 0 0.00 8 19753 318 0 0
sp_supplier_invoice 5165 5165 0 0.00 10 119233 3588 0 0
sp_supplier_order 5186 5186 0 0.00 6 21340 1399 0 0
srv_aggregate_perf_data 113 113 0 0.00 2795 145689 17667 0 0
srv_fill_mon_cache_memory 73 73 0 0.00 778 138929 30999 0 0
srv_find_qd_qs_mism 980007 980007 0 0.00 0 17448 11 0 0
srv_make_invnt_saldo 450 450 0 0.00 37 68640 4867 0 0
srv_make_money_saldo 913 913 0 0.00 1 6838 399 0 0
srv_recalc_idx_stat 4 4 0 0.00 54716 444650 323096 0 0
t$perf-abend:sp_add_invoice_to_stock 132 132 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4810 4810 0 0.00 0 15711 1150 0 0
t$perf-norm:sp_cancel_adding_invoice 391 391 0 0.00 1 6921 553 0 0
t$perf-norm:sp_cancel_client_order 1629 1629 0 0.00 0 14216 39 0 0
t$perf-norm:sp_cancel_customer_reserve 677 677 0 0.00 0 1162 34 0 0
t$perf-norm:sp_cancel_pay_from_customer 664 664 0 0.00 0 962 18 0 0
t$perf-norm:sp_cancel_pay_to_supplier 367 367 0 0.00 0 1111 20 0 0
t$perf-norm:sp_cancel_supplier_invoice 382 382 0 0.00 0 1260 39 0 0
t$perf-norm:sp_cancel_supplier_order 393 393 0 0.00 0 107711 8110 0 0
t$perf-norm:sp_cancel_write_off 915 915 0 0.00 0 1089 23 0 0
t$perf-norm:sp_client_order 19231 19231 0 0.00 0 23330 46 0 0
t$perf-norm:sp_customer_reserve 925 925 0 0.00 0 2558 17 0 0
t$perf-norm:sp_pay_from_customer 6864 6864 0 0.00 0 6487 22 0 0
t$perf-norm:sp_pay_to_supplier 5562 5562 0 0.00 0 22285 25 0 0
t$perf-norm:sp_reserve_write_off 9539 9539 0 0.00 0 13427 26 0 0
t$perf-norm:sp_supplier_invoice 5165 5165 0 0.00 0 1475 33 0 0
t$perf-norm:sp_supplier_order 5186 5186 0 0.00 0 3443 37 0 0
t$perf-norm:srv_aggregate_perf_data 113 113 0 0.00 0 657 37 0 0
t$perf-norm:srv_fill_mon_cache_memory 73 73 0 0.00 0 345 11 0 0
t$perf-norm:srv_make_invnt_saldo 450 450 0 0.00 0 2617 22 0 0
t$perf-norm:srv_make_money_saldo 913 913 0 0.00 0 1971 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 117 52 0 0
v_add_invoice_to_stock 9792 9528 264 2.69 0 3028 27 0 264
v_all_customers 35114 35114 0 0.00 0 127 4 0 0
v_all_suppliers 28790 28790 0 0.00 0 128 5 0 0
v_all_wares 38460 38460 0 0.00 0 239 5 0 0
v_cancel_adding_invoice 782 782 0 0.00 0 1245 17 0 0
v_cancel_client_order 3258 3258 0 0.00 0 2270 11 0 0
v_cancel_customer_prepayment 1328 1328 0 0.00 0 2267 16 0 0
v_cancel_customer_reserve 1354 1354 0 0.00 0 1415 16 0 0
v_cancel_payment_to_supplier 734 734 0 0.00 0 1568 18 0 0
v_cancel_supplier_invoice 778 764 14 1.79 0 747 26 0 14
v_cancel_supplier_order 786 786 0 0.00 0 1253 15 0 0
v_cancel_write_off 1830 1830 0 0.00 0 1896 27 0 0
v_max_id_clo_ord 5186 5186 0 0.00 0 6551 8 0 0
v_max_id_clo_res 925 925 0 0.00 0 866 11 0 0
v_max_id_ord_sup 5151 5151 0 0.00 0 4536 7 0 0
v_max_non_paid_invoice 1503 1503 0 0.00 0 214 10 0 0
v_max_non_paid_realizn 3639 3639 0 0.00 0 7654 10 0 0
v_min_id_clo_ord 5186 5186 0 0.00 0 1555 10 0 0
v_min_id_clo_res 925 925 0 0.00 0 5851 21 0 0
v_min_id_ord_sup 5151 5151 0 0.00 0 6510 14 0 0
v_min_non_paid_invoice 5561 5561 0 0.00 0 14871 25 0 0
v_min_non_paid_realizn 6864 6864 0 0.00 0 8944 26 0 0
v_our_firm 9794 9794 0 0.00 0 65 5 0 0
v_reserve_write_off 19076 19076 0 0.00 0 3277 11 0 0
x_lock_depdocs_on_canc_invoice 10332 10332 0 0.00 0 9645 150 0 0
x_lock_depdocs_on_canc_sup_ord 393 393 0 0.00 0 10549 431 0 0
x_make_qstorno_1000_1200 5186 5186 0 0.00 2 21317 1181 0 0
x_make_qstorno_1200_2000 5165 5165 0 0.00 4 118765 3290 0 0
x_make_qstorno_2100_3300 444980 444980 0 0.00 0 39122 241 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 19-12-2022 04:39:04 to 19-12-2022 04:39:09.

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
02:07:26 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
02:08:27 21760 8192000000 9725548368 10027192320 806128096 8.958 100 85 170 1 834372720 7051680 21192944
02:09:53 16895 8192000000 9772018064 10080620544 845730512 9.357 100 89 178 0 878016032 11223008 21062512
02:11:16 16330 8192000000 9792630864 10109894656 866210832 9.562 100 81 162 0 901936896 17116416 18609648
02:12:36 7581 8192000000 9854276384 10187501568 907194864 9.970 100 82 162 1 963201312 38240928 17765520
02:13:55 5720 8192000000 9940101968 10285543424 964736208 10.535 100 92 176 2 1050659264 67357968 18565088
02:15:04 1756 8192000000 9961479968 10314403840 993262320 10.813 100 93 180 0 1076557792 65026656 18268816
02:16:10 1989 8192000000 10007057392 10369961984 1018971008 11.062 100 88 176 1 1117922736 81362288 17589440
02:17:14 1208 8192000000 10006162608 10373345280 1035015456 11.217 100 95 183 0 1124884368 71484608 18384304
02:18:17 1365 8192000000 10024014480 10398736384 1059170944 11.449 100 94 185 0 1146088144 67946352 18970848
02:19:20 898 8192000000 10072233408 10453454848 1071369808 11.565 100 94 183 1 1191165088 101385840 18409440
02:20:23 919 8192000000 10086275280 10468012032 1078246416 11.631 100 97 184 0 1202206896 105316176 18644304
02:21:25 1199 8192000000 10065509184 10444300288 1078148208 11.630 100 96 188 1 1183753440 86614576 18990656
02:22:29 1128 8192000000 10059589648 10439258112 1083386672 11.680 100 89 165 2 1179397520 79923712 16087136
02:23:32 8536 8192000000 10031769552 10419556352 1087100896 11.715 100 85 142 2 1150158880 50203968 12854016
02:24:41 1123 8192000000 10057542048 10447695872 1092819872 11.769 100 91 174 4 1178999136 69067152 17112112
02:25:44 1192 8192000000 10065389392 10453286912 1095043200 11.791 100 94 184 4 1190423424 75448816 19931408
02:26:47 1338 8192000000 10085606368 10477568000 1100255552 11.840 100 96 184 1 1203527328 84975856 18295920
02:27:50 1234 8192000000 10066352704 10455052288 1099152960 11.830 100 95 173 0 1187053504 70028288 17872256
02:28:52 935 8192000000 10054425584 10443390976 1088022720 11.724 100 89 172 1 1176669328 71333312 17313296
02:29:54 1439 8192000000 10054266624 10442588160 1091836096 11.760 100 96 183 1 1178265264 66441536 19987632
02:30:56 1056 8192000000 10033579328 10420207616 1067207408 11.525 98 94 179 1 1157166144 72433856 17524880
02:31:58 1653 8192000000 10029457008 10415632384 1068492432 11.538 100 81 152 2 1156950720 72838304 15619984
02:33:00 1406 8192000000 10026943552 10413330432 1067923984 11.532 100 75 144 1 1158109664 75246800 14938880
02:34:03 1228 8192000000 10006904928 10380316672 1042410096 11.288 100 88 164 0 1128399040 69801504 16187440
02:35:08 852 8192000000 10018242864 10395840512 1046788656 11.330 100 96 178 1 1139362400 74250128 18323616
02:36:12 810 8192000000 10025930272 10408353792 1050593248 11.366 100 92 178 1 1146067936 77013824 18460864
02:37:13 2383 8192000000 9965320544 10342039552 1038588992 11.251 99 68 125 2 1094788784 43805248 12394544
02:38:21 7405 8192000000 10018214048 10394161152 1058242496 11.440 99 85 159 1 1132859728 59367664 15249568
02:39:35 27180 8192000000 10048856368 10436612096 1076217808 11.611 100 60 110 2 1146659648 59672480 10769360
02:41:06 2678 8192000000 10045553040 10429247488 1082185696 11.668 100 74 137 0 1169985504 73112624 14687184
02:42:14 3028 8192000000 10093781696 10479747072 1092865696 11.770 100 73 138 0 1208229760 100919008 14445056
02:43:22 2935 8192000000 10110958112 10504376320 1101402928 11.851 100 85 163 1 1230849488 113156960 16289600
02:44:28 885 8192000000 10102524768 10498445312 1099272000 11.831 100 95 179 2 1221251920 102514128 19465792
02:45:33 923 8192000000 10135533760 10524237824 1120754656 12.034 100 80 143 2 1250845888 114220160 15871072
02:46:38 1040 8192000000 10145254768 10554667008 1130501248 12.126 100 92 181 0 1256973392 105446960 21025184
02:47:46 5670 8192000000 10143123072 10545795072 1140226224 12.218 100 79 148 0 1263448144 106820912 16401008
02:48:55 6303 8192000000 10209205088 10616057856 1146409344 12.276 100 70 135 2 1323575760 161938176 15228240
02:50:10 2292 8192000000 10229068288 10633547776 1158186560 12.386 100 81 155 1 1340229936 164788048 17255328
02:51:16 2168 8192000000 10288620752 10689060864 1175600608 12.549 100 88 172 0 1398407904 203352336 19454960
02:52:22 1869 8192000000 10323770880 10727895040 1180589072 12.596 100 86 161 0 1437044336 238582720 17872544
02:53:28 2496 8192000000 10344307440 10755186688 1181931344 12.608 100 86 164 1 1444629168 244194480 18503344
02:54:33 771 8192000000 10343342288 10767335424 1176461248 12.557 100 99 196 1 1453880080 254174272 23244560
02:55:38 918 8192000000 10366168336 10779627520 1173180640 12.527 100 98 197 0 1473920848 278032160 22708048
02:56:43 1799 8192000000 10411332704 10819293184 1178501024 12.576 100 93 179 0 1517559456 318754352 20304080
02:57:50 1625 8192000000 10423015728 10837536768 1181952672 12.608 100 95 186 0 1532001696 327805392 22243632
02:58:56 989 8192000000 10456274352 10892242944 1189599584 12.680 100 94 184 0 1571928736 359422160 22906992
03:00:00 1336 8192000000 10458805056 10882248704 1181711488 12.606 100 93 183 0 1561832512 358309472 21811552
03:01:05 937 8192000000 10471485456 10899554304 1200485056 12.781 100 96 183 1 1575348496 352846640 22016800
03:02:10 1036 8192000000 10479466096 10908999680 1187330688 12.659 100 96 184 1 1578530816 369744096 21456032
03:03:15 1334 8192000000 10486831936 10914578432 1188101872 12.666 100 98 194 0 1590073472 378525728 23445872
03:04:18 890 8192000000 10506059760 10964582400 1202048224 12.795 100 97 186 1 1609845936 384515616 23282096
03:05:20 855 8192000000 10520822080 10993438720 1192100080 12.703 100 97 189 1 1622563008 407133872 23329056
03:06:24 4302 8192000000 10523202848 10948202496 1208232000 12.853 100 74 148 0 1636193360 410070256 17891104
03:07:32 11423 8192000000 10504131088 10988302336 1190740560 12.690 99 53 105 2 1593663168 389487120 13435488
03:08:53 5785 8192000000 10533865792 10980503552 1186573840 12.651 100 47 92 1 1635866256 437282464 12009952
03:10:02 10401 8192000000 10592954384 11013296128 1181205344 12.601 100 61 121 2 1684843376 487761568 15876464
03:11:40 17393 8192000000 10510603408 11014680576 1177312672 12.565 100 34 66 1 1605114608 418806624 8995312
03:13:25 23401 8192000000 10386099600 10845102080 1142784240 12.242 98 31 60 0 1469830208 319445424 7600544
03:14:50 42655 8192000000 10262176080 10716946432 1118960384 12.017 97 18 36 0 1352688592 229203520 4524688
03:16:35 37752 8192000000 10159986656 10692771840 1115221136 11.982 98 23 42 1 1307082496 186481968 5379392
03:18:16 6148 8192000000 10156709728 10580434944 1147734144 12.288 100 53 102 3 1253777248 94264144 11778960
03:19:23 22726 8192000000 10195483936 10609360896 1147443216 12.285 100 76 149 0 1289698848 127095072 15160560
03:20:48 19016 8192000000 10175070048 10630447104 1148193776 12.293 100 37 71 1 1281321840 123794880 9333184
03:23:00 68438 8192000000 10148813872 10669891584 1146425936 12.276 100 15 29 0 1289251328 139363584 3461808
03:25:12 29890 8192000000 10099753584 10531188736 1140678672 12.222 100 55 105 0 1189315904 37615568 11021664
03:26:45 114908 8192000000 10102771040 10565345280 1145543520 12.268 100 25 50 0 1228981760 78057472 5380768
03:29:55 56889 8192000000 10055615536 10491912192 1128384032 12.106 99 45 75 0 1162584656 27384880 6815744
03:31:59 129779 8192000000 10074825344 10533629952 1095185296 11.792 96 28 55 0 1156064016 54827040 6051680
03:35:13 20097 8192000000 10106832960 10545614848 1138487040 12.201 100 26 49 0 1202960592 58895888 5577664
03:36:39 12077 8192000000 10164499552 10597171200 1149541952 12.305 100 47 91 0 1255102048 95862624 9697472
03:37:57 28025 8192000000 10180935264 10639208448 1165348048 12.453 100 47 87 1 1290926096 116190208 9387840
03:39:30 62183 8192000000 10147600864 10636828672 1109684176 11.929 96 24 48 1 1259514928 144182224 5648528
03:41:37 57413 8192000000 10126941888 10642771968 1138301824 12.200 99 10 20 0 1225495936 85354128 1839984
03:43:34 48741 8192000000 10073799072 10547806208 1134394576 12.163 99 12 25 0 1178111936 41586512 2130848
03:45:58 102437 8192000000 10088904240 10559197184 1109819760 11.931 97 9 16 0 1163109200 51801040 1488400
03:48:55 127200 8192000000 10080573776 10595565568 1116498656 11.994 98 10 20 0 1166537248 48094352 1944240
03:52:04 21291 8192000000 10101933808 10531819520 1121404720 12.040 98 44 88 0 1168108656 36850784 9853152
03:53:26 24530 8192000000 10155892704 10592387072 1138440912 12.201 100 35 70 0 1240324176 94729360 7153904
03:54:54 77041 8192000000 10054117168 10513281024 1102019104 11.857 97 21 42 0 1214681792 107358160 5304528
03:57:32 66550 8192000000 10126329536 10635755520 1107805600 11.912 97 20 41 0 1214570096 102445232 4319264
03:59:51 64753 8192000000 10041828240 10525532160 1095207840 11.792 98 11 22 0 1135912192 38481984 2222368
04:02:31 60819 8192000000 10068947408 10502270976 1109009104 11.923 98 25 49 0 1153270256 39446784 4814368
04:04:33 34408 8192000000 10097514688 10526994432 1124954960 12.074 100 56 112 0 1181127312 44332752 11839600
04:06:13 48820 8192000000 10055611344 10516160512 1100248864 11.840 98 24 48 0 1154910672 49400400 5261408
04:08:03 27307 8192000000 10044599648 10488582144 1100558976 11.843 97 26 52 0 1123165152 17759568 4846608
04:09:31 54984 8192000000 10046445504 10492096512 1108795344 11.921 98 11 20 0 1151043904 40324752 1923808
04:11:26 24964 8192000000 10020156512 10471165952 1080128224 11.649 96 15 28 0 1091005488 8357168 2520096
04:12:55 51378 8192000000 10089429760 10535821312 1123775904 12.063 99 33 64 0 1167554384 36141808 7636672
04:14:49 35194 8192000000 10128098224 10566533120 1141450672 12.229 100 52 101 1 1210391168 58758912 10181584
04:16:27 49356 8192000000 10121440288 10563702784 1084344816 11.689 96 30 57 0 1209007936 118630624 6032496
04:18:30 59847 8192000000 10130208256 10591703040 1114114848 11.971 98 16 32 0 1222699456 105015712 3568896
04:21:06 27582 8192000000 10145235744 10585870336 1129546224 12.117 99 32 62 1 1233855440 97948656 6360560
04:22:36 44101 8192000000 10059319376 10500075520 1111667520 11.948 98 24 42 0 1157709696 41817456 4224720
04:24:22 10982 8192000000 10094590928 10513469440 1124784912 12.072 99 63 124 1 1177399424 40686736 11927776
04:25:35 138927 8192000000 10069487280 10534957056 1122250320 12.048 99 31 61 0 1200531216 71786432 6494464
04:28:54 65785 8192000000 10081783728 10535636992 1132239456 12.142 99 10 18 0 1185090112 50950496 1900160
04:31:02 57939 8192000000 10092024304 10599096320 1137414304 12.191 99 9 18 0 1223200512 83695424 2090784
04:33:00 30852 8192000000 10102513264 10549100544 1124531200 12.070 98 29 54 0 1185107440 54780736 5795504
04:34:38 17845 8192000000 10145332544 10581086208 1139536848 12.211 99 40 80 0 1238051536 89078048 9436640
04:35:58 28800 8192000000 10157734560 10624991232 1137958384 12.196 99 33 65 1 1274357744 129110352 7289008

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 19-12-2022 04:39:11 to 19-12-2022 04:39:27.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 132 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 19-12-2022 04:39:28 to 19-12-2022 04:39:30.

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          205168
MON$OLDEST_ACTIVE               211610
MON$OLDEST_SNAPSHOT             211610
MON$NEXT_TRANSACTION            211610
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                       2098560
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             758
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2885 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2885 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2885 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2885 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 ---
:193: 
:194: mscs-test-161.budzdorov.ru    Mon Dec 19 04:37:28 2022
:195:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:196: 
:197: 
:198: mscs-test-161.budzdorov.ru    Mon Dec 19 04:37:38 2022
:199:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:200: 
:201: 
:202: mscs-test-161.budzdorov.ru    Mon Dec 19 04:37:40 2022
:203:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:204: 
--- end of diff output ---

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

19.12.22 04:39:31. 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-12-19 04:39:34 No severe PSQL-related problems occured
19.12.22 04:39:35. End of report.