Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.07.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 16171128 kB
MemAvailable 31147096 kB
Buffers 0 kB
Cached 15549928 kB
SwapCached 2548 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.2
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-07-06 10:37:18
DTS_END                         2022-07-06 10:37:18.5640
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/59728
TRN_ID                          214146
ATT_ID                          603
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: 8533.13 23150 1023975
customer order: creation 166.49 510 19979
customer order: refuse 13.59 500 1631
order to supplier: creation 45.12 1430 5414
order to supplier: removal 3.32 164147 398
invoice (draft): creation 44.53 3311 5344
invoice (draft): removal 46.30 1065 5556
invoice accept: apply 40.02 28590 4802
invoice accept: cancel 41.73 1367 5007
customer reserve: creation 3759.07 268 451088
customer reserve: removal 4092.89 104 491147
realization accept: apply 82.20 307 9864
realization accept: cancel 72.88 185 8746
payment from customer: creation 56.81 198 6817
payment from customer: removal 5.39 146 647
payment to supplier: creation 47.03 221 5644
payment to supplier: removal 3.28 138 394
service: total inventory turnovers 3.88 4476 466
service: total monetary turnovers 7.56 413 907
service: aggregate perf. data 1.00 15619 120
service: refresh index statistics 0.03 240008 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-07-2022 10:38:41 to 06-07-2022 10:38:43.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9451
TEST_TIME 2 6140
TEST_TIME 3 6126
TEST_TIME 4 6225
TEST_TIME 5 6261
TEST_TIME 6 5847
TEST_TIME 7 5718
TEST_TIME 8 6038
TEST_TIME 9 6024
TEST_TIME 10 6170
TEST_TIME 11 6286
TEST_TIME 12 6219
TEST_TIME 13 6310
TEST_TIME 14 6424
TEST_TIME 15 6754
TEST_TIME 16 6874
TEST_TIME 17 7082
TEST_TIME 18 7193
TEST_TIME 19 7379
TEST_TIME 20 7361
TEST_TIME 21 7365
TEST_TIME 22 7447
TEST_TIME 23 7633
TEST_TIME 24 7626
TEST_TIME 25 7595
TEST_TIME 26 8380
TEST_TIME 27 8432
TEST_TIME 28 8463
TEST_TIME 29 8870
TEST_TIME 30 9214
TEST_TIME 31 9523
TEST_TIME 32 9782
TEST_TIME 33 10321
TEST_TIME 34 10355
TEST_TIME 35 10401
TEST_TIME 36 10263
TEST_TIME 37 10297
TEST_TIME 38 10257
TEST_TIME 39 10051
TEST_TIME 40 9821
TEST_TIME 41 9894
TEST_TIME 42 9728
TEST_TIME 43 9658
TEST_TIME 44 9660
TEST_TIME 45 9563
TEST_TIME 46 9514
TEST_TIME 47 9426
TEST_TIME 48 9324
TEST_TIME 49 9275
TEST_TIME 50 9141
TEST_TIME 51 9121
TEST_TIME 52 9020
TEST_TIME 53 9108
TEST_TIME 54 9033
TEST_TIME 55 9043
TEST_TIME 56 9064
TEST_TIME 57 9055
TEST_TIME 58 9158
TEST_TIME 59 9109
TEST_TIME 60 9083
TEST_TIME 61 9136
TEST_TIME 62 9109
TEST_TIME 63 9180
TEST_TIME 64 9167
TEST_TIME 65 9277
TEST_TIME 66 9303
TEST_TIME 67 9368
TEST_TIME 68 9264
TEST_TIME 69 9159
TEST_TIME 70 9071
TEST_TIME 71 9045
TEST_TIME 72 9025
TEST_TIME 73 8960
TEST_TIME 74 8881
TEST_TIME 75 8831
TEST_TIME 76 8743
TEST_TIME 77 8736
TEST_TIME 78 8901
TEST_TIME 79 8839
TEST_TIME 80 8796
TEST_TIME 81 8793
TEST_TIME 82 8893
TEST_TIME 83 8844
TEST_TIME 84 8820
TEST_TIME 85 8865
TEST_TIME 86 8857
TEST_TIME 87 8878
TEST_TIME 88 8960
TEST_TIME 89 9036
TEST_TIME 90 8976
TEST_TIME 91 8889
TEST_TIME 92 8836
TEST_TIME 93 8850
TEST_TIME 94 8791
TEST_TIME 95 8737
TEST_TIME 96 8686
TEST_TIME 97 8740
TEST_TIME 98 8744
TEST_TIME 99 8714
TEST_TIME 100 8703
TEST_TIME 101 8702
TEST_TIME 102 8710
TEST_TIME 103 8690
TEST_TIME 104 8730
TEST_TIME 105 8769
TEST_TIME 106 8780
TEST_TIME 107 8806
TEST_TIME 108 8865
TEST_TIME 109 8847
TEST_TIME 110 8795
TEST_TIME 111 8776
TEST_TIME 112 8712
TEST_TIME 113 8708
TEST_TIME 114 8673
TEST_TIME 115 8678
TEST_TIME 116 8637
TEST_TIME 117 8621
TEST_TIME 118 8612
TEST_TIME 119 8610
TEST_TIME 120 8561

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 06-07-2022 10:38:45 to 06-07-2022 10:38:48.

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 1438314 1438314 0 0.00 0 20458 15 0 0
doc_list_biud 1438298 1438298 0 0.00 0 34293 42 0 0
sp_add_invoice_to_stock 4876 4802 74 1.51 33 235529 28590 0 74
sp_cancel_adding_invoice 5007 5007 0 0.00 4 75888 1367 0 0
sp_cancel_client_order 1631 1631 0 0.00 6 21374 501 0 0
sp_cancel_customer_reserve 491147 491147 0 0.00 1 31748 105 0 0
sp_cancel_pay_from_customer 647 647 0 0.00 4 3800 147 0 0
sp_cancel_pay_to_supplier 394 394 0 0.00 4 4004 139 0 0
sp_cancel_supplier_invoice 5561 5556 5 0.08 3 18224 1065 0 5
sp_cancel_supplier_order 398 398 0 0.00 11 1484600 164148 0 0
sp_cancel_write_off 8746 8746 0 0.00 2 12795 186 0 0
sp_client_order 19979 19979 0 0.00 6 26884 511 0 0
sp_customer_reserve 451088 451088 0 0.00 1 141841 269 0 0
sp_fill_shopping_cart 30730 30730 0 0.00 1 19680 88 0 0
sp_fill_shopping_cart_clo_res 451052 451052 0 0.00 0 17726 22 0 0
sp_get_clo_for_invoice 4758 4758 0 0.00 0 32162 383 0 0
sp_kill_cost_storno 14794 14794 0 0.00 0 6108 82 0 0
sp_kill_qstorno_ret_qs2qd 996994 996994 0 0.00 0 24229 46 0 0
sp_kill_qty_storno 527104 527104 0 0.00 0 34293 106 0 0
sp_lock_selected_doc 20063 20063 0 0.00 0 8154 8 0 0
sp_make_cost_storno 27082 27082 0 0.00 0 20454 84 0 0
sp_multiply_rows_for_pdistr 27079 27079 0 0.00 0 7631 42 0 0
sp_multiply_rows_for_qdistr 19979 19979 0 0.00 0 11028 210 0 0
sp_pay_from_customer 6817 6817 0 0.00 5 12635 198 0 0
sp_pay_to_supplier 5644 5644 0 0.00 7 24046 221 0 0
sp_payment_common 12460 12460 0 0.00 2 23969 55 0 0
sp_qd_handle_on_cancel_clo 1631 1631 0 0.00 0 21350 417 0 0
sp_qd_handle_on_invoice_upd_sts 9764 9764 0 0.00 0 34293 565 0 0
sp_qd_handle_on_reserve_upd_sts 9862 9862 0 0.00 0 13383 67 0 0
sp_reserve_write_off 9864 9864 0 0.00 8 20465 308 0 0
sp_supplier_invoice 5344 5344 0 0.00 10 77912 3312 0 0
sp_supplier_order 5414 5414 0 0.00 8 27434 1430 0 0
srv_aggregate_perf_data 120 120 0 0.00 2131 216841 15619 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 780 136813 36067 0 0
srv_find_qd_qs_mism 975969 975969 0 0.00 0 14325 12 0 0
srv_make_invnt_saldo 466 466 0 0.00 31 197433 4476 0 0
srv_make_money_saldo 907 907 0 0.00 1 25909 413 0 0
srv_recalc_idx_stat 4 4 0 0.00 28522 366670 240008 0 0
t$perf-abend:sp_add_invoice_to_stock 74 74 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4808 4808 0 0.00 0 20550 1006 0 0
t$perf-norm:sp_cancel_adding_invoice 397 397 0 0.00 1 21015 549 0 0
t$perf-norm:sp_cancel_client_order 1631 1631 0 0.00 0 1397 29 0 0
t$perf-norm:sp_cancel_customer_reserve 653 653 0 0.00 0 517 28 0 0
t$perf-norm:sp_cancel_pay_from_customer 647 647 0 0.00 0 2402 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 394 394 0 0.00 0 149 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 376 376 0 0.00 0 3372 47 0 0
t$perf-norm:sp_cancel_supplier_order 398 398 0 0.00 0 97974 6988 0 0
t$perf-norm:sp_cancel_write_off 952 952 0 0.00 0 3281 25 0 0
t$perf-norm:sp_client_order 19979 19979 0 0.00 0 5814 40 0 0
t$perf-norm:sp_customer_reserve 968 968 0 0.00 0 1267 17 0 0
t$perf-norm:sp_pay_from_customer 6817 6817 0 0.00 0 18829 24 0 0
t$perf-norm:sp_pay_to_supplier 5644 5644 0 0.00 0 4586 21 0 0
t$perf-norm:sp_reserve_write_off 9864 9864 0 0.00 0 3961 25 0 0
t$perf-norm:sp_supplier_invoice 5344 5344 0 0.00 0 13622 34 0 0
t$perf-norm:sp_supplier_order 5414 5414 0 0.00 0 5978 38 0 0
t$perf-norm:srv_aggregate_perf_data 120 120 0 0.00 0 1440 28 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 358 9 0 0
t$perf-norm:srv_make_invnt_saldo 466 466 0 0.00 0 932 16 0 0
t$perf-norm:srv_make_money_saldo 907 907 0 0.00 0 3790 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 5 169 54 0 0
v_add_invoice_to_stock 9650 9502 148 1.53 0 10472 29 0 148
v_all_customers 36666 36666 0 0.00 0 158 4 0 0
v_all_suppliers 29858 29858 0 0.00 0 64 5 0 0
v_all_wares 39956 39956 0 0.00 0 77 4 0 0
v_cancel_adding_invoice 794 794 0 0.00 0 10513 32 0 0
v_cancel_client_order 3262 3262 0 0.00 0 6148 17 0 0
v_cancel_customer_prepayment 1294 1294 0 0.00 0 3727 20 0 0
v_cancel_customer_reserve 1306 1306 0 0.00 0 1940 12 0 0
v_cancel_payment_to_supplier 788 788 0 0.00 0 2640 14 0 0
v_cancel_supplier_invoice 762 752 10 1.31 0 6101 29 0 10
v_cancel_supplier_order 796 796 0 0.00 0 6174 23 0 0
v_cancel_write_off 1904 1904 0 0.00 0 1433 22 0 0
v_max_id_clo_ord 5413 5413 0 0.00 0 2288 6 0 0
v_max_id_clo_res 968 968 0 0.00 0 3781 18 0 0
v_max_id_ord_sup 5338 5338 0 0.00 0 2784 7 0 0
v_max_non_paid_invoice 1466 1466 0 0.00 0 2870 10 0 0
v_max_non_paid_realizn 3495 3495 0 0.00 0 3691 10 0 0
v_min_id_clo_ord 5413 5413 0 0.00 0 2577 9 0 0
v_min_id_clo_res 968 968 0 0.00 0 12377 22 0 0
v_min_id_ord_sup 5338 5338 0 0.00 0 4672 10 0 0
v_min_non_paid_invoice 5644 5644 0 0.00 0 23961 29 0 0
v_min_non_paid_realizn 6816 6816 0 0.00 0 6977 20 0 0
v_our_firm 9926 9926 0 0.00 0 63 5 0 0
v_reserve_write_off 19722 19722 0 0.00 0 7258 15 0 0
x_lock_depdocs_on_canc_invoice 10187 10187 0 0.00 0 18257 128 0 0
x_lock_depdocs_on_canc_sup_ord 398 398 0 0.00 0 15296 398 0 0
x_make_qstorno_1000_1200 5414 5414 0 0.00 3 27369 1258 0 0
x_make_qstorno_1200_2000 5344 5344 0 0.00 5 77608 3060 0 0
x_make_qstorno_2100_3300 451088 451088 0 0.00 0 141836 234 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-07-2022 10:38:49 to 06-07-2022 10:38:53.

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:22 2 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:23 25334 8192000000 9721456624 10017366016 795479568 8.850 100 88 176 0 825466912 7902512 22084832
08:10:03 21487 8192000000 9765780336 10076266496 842588512 9.326 100 87 172 0 875697376 12430608 20678256
08:11:35 18771 8192000000 9814634976 10136457216 880849056 9.708 100 83 166 0 922536368 22465696 19221616
08:12:59 11990 8192000000 9873076752 10208194560 918986832 10.086 100 79 156 0 984305792 48168880 17150080
08:14:13 3402 8192000000 9913428672 10256912384 965552864 10.543 100 85 162 1 1039077632 55992912 17531856
08:15:20 3390 8192000000 9969583648 10324766720 999813904 10.877 100 80 160 0 1080679168 64736272 16128992
08:16:27 2765 8192000000 9981675520 10344075264 1023121344 11.102 100 89 165 1 1100050736 61049520 15879872
08:17:32 1046 8192000000 10010150672 10374557696 1035973888 11.226 100 98 180 0 1127137648 72982960 18180800
08:18:35 1127 8192000000 10035395856 10405928960 1053340784 11.393 100 96 190 1 1157393120 84692912 19359424
08:19:38 1031 8192000000 10015374784 10390056960 1061706624 11.473 100 94 183 0 1140318832 60462400 18149808
08:20:42 981 8192000000 10055804992 10433093632 1066509120 11.519 100 95 183 2 1174900304 90412080 17979104
08:21:45 1253 8192000000 10043746624 10426200064 1070617584 11.558 100 90 169 1 1158077664 70223008 17237072
08:22:48 1276 8192000000 10051575728 10433183744 1075927680 11.609 100 94 184 0 1169357568 74487888 18942000
08:23:51 1122 8192000000 10062101728 10449252352 1080250544 11.650 100 97 186 0 1184480112 86521888 17707680
08:24:54 1218 8192000000 10061727488 10448347136 1085969808 11.704 100 94 180 3 1178941568 75822880 17148880
08:25:56 1107 8192000000 10070856208 10459582464 1093763136 11.778 100 92 176 2 1194115984 82800176 17552672
08:26:59 1033 8192000000 10077688672 10469015552 1099917120 11.837 100 97 188 2 1201469136 81338112 20213904
08:28:03 1249 8192000000 10051436608 10437996544 1101972128 11.856 100 87 168 5 1176429792 58234336 16223328
08:29:05 1293 8192000000 10070126768 10458664960 1102357536 11.860 100 95 185 2 1193847856 72428512 19061808
08:30:07 2796 8192000000 10061345968 10460307456 1099268976 11.831 100 74 143 3 1180495568 66228608 14997984
08:31:11 1219 8192000000 10045426320 10426826752 1076291760 11.612 99 96 179 0 1165872064 70863664 18716640
08:32:14 1005 8192000000 10026149936 10407972864 1066886496 11.522 99 91 168 2 1152673808 66104320 19682992
08:33:16 1678 8192000000 10005583040 10381455360 1048630096 11.348 100 81 153 0 1127582112 63024880 15927136
08:34:19 1359 8192000000 9995352736 10377433088 1055578032 11.414 100 72 128 2 1118556816 50748384 12230400
08:35:22 1733 8192000000 9983624624 10357432320 1044645744 11.309 100 86 160 2 1103799520 43198880 15954896
08:36:27 1285 8192000000 9989530240 10356629504 1029785792 11.166 100 97 174 1 1119390016 69927088 19677136
08:37:31 1398 8192000000 9992653952 10366025728 1042221024 11.286 99 91 181 2 1115411152 52454464 20735664
08:38:44 16641 8192000000 10007533600 10390089728 1063630512 11.491 100 73 143 0 1136116672 58298368 14187792
08:40:06 5126 8192000000 10050924928 10432987136 1079916240 11.647 100 77 153 0 1172549504 77462256 15171008
08:41:16 2843 8192000000 10075624480 10466430976 1086161232 11.706 99 66 128 0 1189178880 89252720 13764928
08:42:25 5919 8192000000 10099637056 10492915712 1105435776 11.889 100 65 123 0 1214530416 96151296 12943344
08:43:35 7890 8192000000 10149088448 10541428736 1123007888 12.055 100 73 140 0 1267383120 129550816 14824416
08:44:52 1498 8192000000 10161483760 10565066752 1131611792 12.137 100 85 161 1 1274595104 125444848 17538464
08:45:56 3730 8192000000 10202349040 10604216320 1140154720 12.217 100 85 163 4 1316129072 157265760 18708592
08:47:05 1829 8192000000 10215989376 10614853632 1145394624 12.266 100 92 179 0 1338051392 173551536 19105232
08:48:10 1516 8192000000 10248015536 10642055168 1157074720 12.376 100 87 155 0 1364690336 191496848 16118768
08:49:22 1691 8192000000 10282752976 10681659392 1173147920 12.526 100 85 158 0 1390847280 200602720 17096640
08:50:30 2005 8192000000 10320233184 10722713600 1174284112 12.537 100 88 169 0 1429665104 236318576 19062416
08:51:35 841 8192000000 10345020240 10745876480 1182882528 12.617 100 88 165 1 1450189824 247953456 19353840
08:52:40 2324 8192000000 10355632672 10793730048 1181095040 12.600 100 88 174 0 1462605296 261101536 20408720
08:53:45 2388 8192000000 10390091104 10807234560 1188822464 12.672 100 88 175 0 1501450608 291936992 20691152
08:54:51 1732 8192000000 10390399152 10814091264 1194005472 12.721 100 91 176 1 1503413248 289459344 19948432
08:55:56 804 8192000000 10409536480 10841939968 1192488480 12.707 100 98 188 1 1517717024 302997664 22230880
08:56:58 1375 8192000000 10476430624 10882555904 1199559824 12.772 100 96 188 1 1574021968 353602240 20859904
08:58:02 765 8192000000 10489828976 10900443136 1196385552 12.743 100 100 200 3 1595748928 375438576 23924800
08:59:08 1105 8192000000 10530770160 10946633728 1198617696 12.763 100 98 192 0 1632876624 411707488 22551440
09:00:13 1075 8192000000 10549142336 10977517568 1197937392 12.757 100 88 173 0 1654814016 436058384 20818240
09:01:16 2095 8192000000 10543037504 10964574208 1172720080 12.522 99 89 170 2 1646437728 453680480 20037168
09:02:22 1758 8192000000 10543771648 10987864064 1164314384 12.444 99 83 164 0 1661658960 478752704 18591872
09:03:28 1054 8192000000 10536983328 11021869056 1183675632 12.624 100 84 167 0 1642638320 437739888 21222800
09:04:32 10291 8192000000 10441403968 10889207808 1155518304 12.361 99 44 87 0 1542092896 375816960 10757632
09:05:50 6898 8192000000 10446918640 10927112192 1162418256 12.426 100 43 86 0 1544962224 371170032 11373936
09:07:07 6959 8192000000 10351254112 10816716800 1142351760 12.238 100 47 93 1 1457133696 303269408 11512528
09:08:18 35247 8192000000 10271856496 10744160256 1153457264 12.342 100 24 48 0 1402518416 243509344 5551808
09:10:43 56777 8192000000 10142723968 10599469056 1136282032 12.181 99 23 44 0 1221098096 80072080 4743984
09:13:07 45381 8192000000 10120661952 10549932032 1102129184 11.858 96 29 57 0 1203605008 94860960 6614864
09:14:57 79599 8192000000 10087870016 10516602880 1114515120 11.975 98 45 90 1 1176538048 52288640 9734288
09:17:18 111192 8192000000 10046233488 10539012096 1109878880 11.931 98 19 38 0 1165260960 51866464 3515616
09:20:12 23814 8192000000 10073962416 10525429760 1125181040 12.076 100 27 49 1 1190336544 60312752 4842752
09:21:42 55453 8192000000 10082146352 10538504192 1135829136 12.176 100 12 22 0 1189569072 51740528 1999408
09:23:42 109452 8192000000 10100792768 10571300864 1115838144 11.988 98 10 20 0 1163782240 45733088 2211008
09:26:31 23821 8192000000 10121337040 10571599872 1143123984 12.245 100 30 55 1 1225949456 76485712 6339760
09:29:15 42689 8192000000 10147146032 10599866368 1149286800 12.303 100 28 54 0 1251644800 96688480 5669520
09:31:00 73849 8192000000 10123615600 10599829504 1135538736 12.174 99 19 38 0 1227291792 87178320 4574736
09:33:20 56912 8192000000 10127820480 10576056320 1110354080 11.936 97 24 46 1 1199711152 83714336 5642736
09:35:35 32059 8192000000 10093510784 10540163072 1136750448 12.185 99 31 54 2 1191064928 48718784 5595696
09:37:20 35124 8192000000 10191818128 10641211392 1152319840 12.331 99 40 80 0 1256171456 95529040 8322576
09:38:59 32655 8192000000 10191597616 10628259840 1159283872 12.397 99 42 73 1 1260146992 93824832 7038288
09:40:39 23600 8192000000 10129416816 10594156544 1150511664 12.314 99 25 50 0 1204463376 49120976 4830736
09:42:13 62345 8192000000 10190295424 10631532544 1168522000 12.483 100 38 76 0 1320545904 144089952 7933952
09:44:17 136812 8192000000 10056767280 10500182016 1077055376 11.619 93 17 34 0 1101394400 21162816 3176208
09:47:37 39568 8192000000 10072636720 10520780800 1112096416 11.952 97 40 80 1 1166216384 45835248 8284720
09:49:26 61533 8192000000 10070966560 10528149504 1123876096 12.064 98 18 37 0 1173562912 46231888 3454928
09:52:39 87050 8192000000 10178196704 10633478144 1144013536 12.253 99 34 68 0 1254094720 102502960 7578224
09:55:07 79514 8192000000 10076388144 10619916288 1116819184 11.997 98 8 16 0 1212046944 93306704 1921056
09:57:27 42413 8192000000 10110146720 10555625472 1123256656 12.058 99 31 60 1 1198119248 68244336 6618256
09:59:15 50836 8192000000 10045160512 10543489024 1099435888 11.832 97 13 26 1 1140999360 38723696 2839776
10:01:07 29091 8192000000 10129306256 10559160320 1133556352 12.155 100 52 104 0 1216305904 72330640 10418912
10:02:58 34217 8192000000 10082938656 10525683712 1087708480 11.721 97 38 74 0 1160485360 65597168 7179712
10:04:39 34470 8192000000 10124332848 10582204416 1109961088 11.932 99 45 88 0 1226983488 107995712 9026688
10:06:15 86942 8192000000 10049435728 10513797120 1090391872 11.746 97 19 38 0 1132993664 38918592 3683200
10:08:42 79596 8192000000 10081228560 10527223808 1120157584 12.028 99 37 74 0 1172390016 44926976 7305456
10:11:02 57200 8192000000 10057115984 10509283328 1113685952 11.967 98 9 18 1 1152827744 37563936 1577856
10:13:38 47863 8192000000 10093623008 10573254656 1114259328 11.973 98 26 50 0 1197057248 77387072 5410848
10:15:27 59395 8192000000 10079587056 10510696448 1091333184 11.755 96 23 44 0 1127201648 30826480 5041984
10:17:34 35795 8192000000 10109879248 10562330624 1114314896 11.973 98 35 68 0 1188590480 65725712 8549872
10:19:11 51668 8192000000 10127955472 10592415744 1138685776 12.203 99 24 48 0 1222676256 78381568 5608912
10:21:04 50055 8192000000 10120021552 10588127232 1130353968 12.125 99 17 34 0 1263231680 128964976 3912736
10:22:55 33177 8192000000 10150770864 10590973952 1144493376 12.258 100 42 84 0 1227317504 73856464 8967664
10:24:41 122717 8192000000 10070534208 10516533248 1094489056 11.785 96 11 22 0 1122872032 26126416 2256560
10:28:03 106355 8192000000 10092180688 10533806080 1149041872 12.301 100 28 55 0 1192277296 37759568 5475856
10:30:50 64370 8192000000 10088366784 10566184960 1136824608 12.186 99 13 25 0 1189791104 50622720 2343776
10:32:57 17762 8192000000 10055020928 10571382784 1137623680 12.193 100 4 6 0 1168234112 30070608 539824
10:34:15 30668 8192000000 10070773760 10523840512 1138002288 12.197 100 25 47 1 1175752208 31644144 6105776
10:35:52 55775 8192000000 10081100080 10540720128 1108199040 11.915 97 19 38 0 1191871520 79841248 3831232

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 06-07-2022 10:38:55 to 06-07-2022 10:39:11.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 74 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-07-2022 10:39:12 to 06-07-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          209038
MON$OLDEST_ACTIVE               214332
MON$OLDEST_SNAPSHOT             214332
MON$NEXT_TRANSACTION            214332
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                       2037600
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             759
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2796 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2796 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2796 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2796 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 ---
:42: 
:43: mscs-test-161.budzdorov.ru    Wed Jul  6 10:37:21 2022
:44:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:45: 
:46: 
:47: mscs-test-161.budzdorov.ru    Wed Jul  6 10:37:26 2022
:48:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:49: 
--- end of diff output ---

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

06.07.22 10:39:15. There are 6 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-07-06 10:39:17 No severe PSQL-related problems occured
06.07.22 10:39:19. End of report.