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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15292764 kB
MemAvailable 30407884 kB
Buffers 0 kB
Cached 16381140 kB
SwapCached 4572 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 2023-01-29 07:37:22
DTS_END                         2023-01-29 07:37:22.6490
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/56830
TRN_ID                          221521
ATT_ID                          593
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: 9018.92 30029 1082271
customer order: creation 165.01 449 19801
customer order: refuse 14.26 356 1711
order to supplier: creation 44.23 1385 5307
order to supplier: removal 2.96 190078 355
invoice (draft): creation 45.22 3262 5426
invoice (draft): removal 50.33 1023 6040
invoice accept: apply 41.21 25645 4945
invoice accept: cancel 45.16 1117 5419
customer reserve: creation 3874.29 243 464915
customer reserve: removal 4447.86 98 533743
realization accept: apply 82.08 256 9849
realization accept: cancel 76.93 166 9232
payment from customer: creation 58.08 165 6970
payment from customer: removal 5.68 124 681
payment to supplier: creation 49.59 171 5951
payment to supplier: removal 3.27 107 392
service: total inventory turnovers 4.06 3493 487
service: total monetary turnovers 7.67 352 920
service: aggregate perf. data 1.03 14144 123
service: refresh index statistics 0.03 357937 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-01-2023 07:38:07 to 29-01-2023 07:38:09.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6634
TEST_TIME 2 7282
TEST_TIME 3 6442
TEST_TIME 4 6797
TEST_TIME 5 6809
TEST_TIME 6 6537
TEST_TIME 7 6789
TEST_TIME 8 6733
TEST_TIME 9 6863
TEST_TIME 10 6951
TEST_TIME 11 6946
TEST_TIME 12 6971
TEST_TIME 13 7216
TEST_TIME 14 7077
TEST_TIME 15 7091
TEST_TIME 16 7046
TEST_TIME 17 7249
TEST_TIME 18 7350
TEST_TIME 19 7639
TEST_TIME 20 7922
TEST_TIME 21 7940
TEST_TIME 22 8216
TEST_TIME 23 8215
TEST_TIME 24 8234
TEST_TIME 25 8338
TEST_TIME 26 8953
TEST_TIME 27 9184
TEST_TIME 28 9593
TEST_TIME 29 10521
TEST_TIME 30 11067
TEST_TIME 31 11435
TEST_TIME 32 11149
TEST_TIME 33 10969
TEST_TIME 34 10913
TEST_TIME 35 10809
TEST_TIME 36 10723
TEST_TIME 37 10570
TEST_TIME 38 10437
TEST_TIME 39 10309
TEST_TIME 40 10150
TEST_TIME 41 9982
TEST_TIME 42 10101
TEST_TIME 43 9940
TEST_TIME 44 9841
TEST_TIME 45 9737
TEST_TIME 46 9730
TEST_TIME 47 9704
TEST_TIME 48 9695
TEST_TIME 49 9646
TEST_TIME 50 9640
TEST_TIME 51 9640
TEST_TIME 52 9660
TEST_TIME 53 9683
TEST_TIME 54 9711
TEST_TIME 55 9611
TEST_TIME 56 9739
TEST_TIME 57 9842
TEST_TIME 58 9783
TEST_TIME 59 9657
TEST_TIME 60 9617
TEST_TIME 61 9598
TEST_TIME 62 9522
TEST_TIME 63 9491
TEST_TIME 64 9440
TEST_TIME 65 9383
TEST_TIME 66 9330
TEST_TIME 67 9297
TEST_TIME 68 9266
TEST_TIME 69 9274
TEST_TIME 70 9325
TEST_TIME 71 9297
TEST_TIME 72 9283
TEST_TIME 73 9264
TEST_TIME 74 9350
TEST_TIME 75 9331
TEST_TIME 76 9395
TEST_TIME 77 9438
TEST_TIME 78 9432
TEST_TIME 79 9402
TEST_TIME 80 9440
TEST_TIME 81 9453
TEST_TIME 82 9492
TEST_TIME 83 9421
TEST_TIME 84 9367
TEST_TIME 85 9312
TEST_TIME 86 9319
TEST_TIME 87 9298
TEST_TIME 88 9233
TEST_TIME 89 9180
TEST_TIME 90 9212
TEST_TIME 91 9190
TEST_TIME 92 9183
TEST_TIME 93 9163
TEST_TIME 94 9149
TEST_TIME 95 9166
TEST_TIME 96 9159
TEST_TIME 97 9243
TEST_TIME 98 9215
TEST_TIME 99 9271
TEST_TIME 100 9236
TEST_TIME 101 9298
TEST_TIME 102 9310
TEST_TIME 103 9316
TEST_TIME 104 9237
TEST_TIME 105 9214
TEST_TIME 106 9191
TEST_TIME 107 9152
TEST_TIME 108 9119
TEST_TIME 109 9092
TEST_TIME 110 9075
TEST_TIME 111 9052
TEST_TIME 112 9043
TEST_TIME 113 9029
TEST_TIME 114 8998
TEST_TIME 115 9009
TEST_TIME 116 9061
TEST_TIME 117 9023
TEST_TIME 118 9014
TEST_TIME 119 9062
TEST_TIME 120 9039

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 29-01-2023 07:38:11 to 29-01-2023 07:38:14.

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 1509203 1509203 0 0.00 0 43184 13 0 0
doc_list_biud 1509179 1509179 0 0.00 0 38319 40 0 0
sp_add_invoice_to_stock 5090 4945 145 2.84 34 231325 25646 0 145
sp_cancel_adding_invoice 5419 5419 0 0.00 5 82248 1117 0 0
sp_cancel_client_order 1711 1711 0 0.00 5 7253 357 0 0
sp_cancel_customer_reserve 533743 533743 0 0.00 1 27750 99 0 0
sp_cancel_pay_from_customer 681 681 0 0.00 4 7676 124 0 0
sp_cancel_pay_to_supplier 392 392 0 0.00 3 4149 107 0 0
sp_cancel_supplier_invoice 6053 6040 13 0.21 3 17561 1023 0 13
sp_cancel_supplier_order 355 355 0 0.00 13 1491666 190078 0 0
sp_cancel_write_off 9232 9232 0 0.00 2 12010 167 0 0
sp_client_order 19801 19801 0 0.00 5 27853 449 0 0
sp_customer_reserve 464915 464915 0 0.00 0 96703 244 0 0
sp_fill_shopping_cart 30527 30527 0 0.00 1 23803 82 0 0
sp_fill_shopping_cart_clo_res 464873 464873 0 0.00 0 18707 21 0 0
sp_get_clo_for_invoice 4892 4892 0 0.00 0 40756 364 0 0
sp_kill_cost_storno 15724 15724 0 0.00 0 12979 70 0 0
sp_kill_qstorno_ret_qs2qd 1083113 1083113 0 0.00 0 27726 43 0 0
sp_kill_qty_storno 571238 571238 0 0.00 0 38318 99 0 0
sp_lock_selected_doc 20262 20262 0 0.00 0 9409 7 0 0
sp_make_cost_storno 27660 27660 0 0.00 0 15741 72 0 0
sp_multiply_rows_for_pdistr 27659 27659 0 0.00 0 15676 36 0 0
sp_multiply_rows_for_qdistr 19801 19801 0 0.00 0 27833 201 0 0
sp_pay_from_customer 6970 6970 0 0.00 6 15753 165 0 0
sp_pay_to_supplier 5951 5951 0 0.00 6 11023 172 0 0
sp_payment_common 12921 12921 0 0.00 2 6596 42 0 0
sp_qd_handle_on_cancel_clo 1711 1711 0 0.00 0 7244 289 0 0
sp_qd_handle_on_invoice_upd_sts 10309 10309 0 0.00 0 38317 467 0 0
sp_qd_handle_on_reserve_upd_sts 9848 9848 0 0.00 0 14017 62 0 0
sp_reserve_write_off 9849 9849 0 0.00 8 15451 257 0 0
sp_supplier_invoice 5426 5426 0 0.00 9 72295 3263 0 0
sp_supplier_order 5307 5307 0 0.00 6 51917 1386 0 0
srv_aggregate_perf_data 123 123 0 0.00 2509 154665 14144 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 693 124922 38480 0 0
srv_find_qd_qs_mism 1033238 1033238 0 0.00 0 30202 10 0 0
srv_make_invnt_saldo 487 487 0 0.00 47 73327 3493 0 0
srv_make_money_saldo 920 920 0 0.00 1 14782 353 0 0
srv_recalc_idx_stat 4 4 0 0.00 181025 531330 357938 0 0
t$perf-abend:sp_add_invoice_to_stock 145 145 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4948 4948 0 0.00 1 13185 783 0 0
t$perf-norm:sp_cancel_adding_invoice 386 386 0 0.00 2 5359 371 0 0
t$perf-norm:sp_cancel_client_order 1711 1711 0 0.00 0 9001 28 0 0
t$perf-norm:sp_cancel_customer_reserve 655 655 0 0.00 0 1937 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 681 681 0 0.00 0 1698 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 392 392 0 0.00 0 1002 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 388 388 0 0.00 0 649 20 0 0
t$perf-norm:sp_cancel_supplier_order 355 355 0 0.00 0 94073 6575 0 0
t$perf-norm:sp_cancel_write_off 960 960 0 0.00 0 4756 25 0 0
t$perf-norm:sp_client_order 19801 19801 0 0.00 0 11557 30 0 0
t$perf-norm:sp_customer_reserve 987 987 0 0.00 0 1283 14 0 0
t$perf-norm:sp_pay_from_customer 6970 6970 0 0.00 0 4920 17 0 0
t$perf-norm:sp_pay_to_supplier 5951 5951 0 0.00 0 6193 18 0 0
t$perf-norm:sp_reserve_write_off 9849 9849 0 0.00 0 6486 21 0 0
t$perf-norm:sp_supplier_invoice 5426 5426 0 0.00 0 4200 27 0 0
t$perf-norm:sp_supplier_order 5307 5307 0 0.00 0 6745 32 0 0
t$perf-norm:srv_aggregate_perf_data 123 123 0 0.00 0 2970 31 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 172 6 0 0
t$perf-norm:srv_make_invnt_saldo 487 487 0 0.00 0 7430 30 0 0
t$perf-norm:srv_make_money_saldo 920 920 0 0.00 0 6155 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 189 51 0 0
v_add_invoice_to_stock 10062 9772 290 2.88 0 7585 25 0 290
v_all_customers 36083 36083 0 0.00 0 89 3 0 0
v_all_suppliers 30645 30645 0 0.00 0 156 4 0 0
v_all_wares 39600 39600 0 0.00 0 156 3 0 0
v_cancel_adding_invoice 772 772 0 0.00 0 2915 18 0 0
v_cancel_client_order 3422 3422 0 0.00 0 2062 9 0 0
v_cancel_customer_prepayment 1362 1362 0 0.00 0 859 12 0 0
v_cancel_customer_reserve 1310 1310 0 0.00 1 1459 11 0 0
v_cancel_payment_to_supplier 784 784 0 0.00 0 1147 13 0 0
v_cancel_supplier_invoice 802 776 26 3.24 0 2041 25 0 26
v_cancel_supplier_order 710 710 0 0.00 0 946 12 0 0
v_cancel_write_off 1920 1920 0 0.00 0 1819 24 0 0
v_max_id_clo_ord 5306 5306 0 0.00 0 208 4 0 0
v_max_id_clo_res 987 987 0 0.00 0 2762 17 0 0
v_max_id_ord_sup 5421 5421 0 0.00 0 5207 8 0 0
v_max_non_paid_invoice 1354 1354 0 0.00 0 1041 8 0 0
v_max_non_paid_realizn 3706 3706 0 0.00 0 1099 7 0 0
v_min_id_clo_ord 5306 5306 0 0.00 0 510 7 0 0
v_min_id_clo_res 987 987 0 0.00 0 3904 15 0 0
v_min_id_ord_sup 5421 5421 0 0.00 0 7426 11 0 0
v_min_non_paid_invoice 5951 5951 0 0.00 0 6065 19 0 0
v_min_non_paid_realizn 6970 6970 0 0.00 0 6588 15 0 0
v_our_firm 10044 10044 0 0.00 0 60 4 0 0
v_reserve_write_off 19696 19696 0 0.00 0 4559 10 0 0
x_lock_depdocs_on_canc_invoice 11071 11071 0 0.00 0 13578 121 0 0
x_lock_depdocs_on_canc_sup_ord 355 355 0 0.00 0 24294 455 0 0
x_make_qstorno_1000_1200 5307 5307 0 0.00 2 51893 1239 0 0
x_make_qstorno_1200_2000 5426 5426 0 0.00 3 70700 3016 0 0
x_make_qstorno_2100_3300 464915 464915 0 0.00 0 96699 212 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-01-2023 07:38:15 to 29-01-2023 07:38:19.

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
05:07:26 2 8192000000 8799296144 8810713088 5305136 0.064 2 1 2 0 5404560 63648 35776
05:08:27 20112 8192000000 9723496320 10023268352 802572656 8.922 100 83 166 0 830468832 6979216 20916960
05:09:55 17859 8192000000 9769842624 10077798400 844350464 9.343 100 89 178 0 876827824 11124704 21352656
05:11:18 12352 8192000000 9794034864 10114408448 869749760 9.598 100 80 160 0 905252240 17622928 17879552
05:12:32 8727 8192000000 9855441056 10187010048 911730064 10.014 100 81 159 1 964386016 35441024 17214928
05:13:47 3701 8192000000 9919430528 10262151168 946861760 10.360 100 89 177 0 1039000160 72673056 19465344
05:14:54 2297 8192000000 9950880240 10301366272 987612464 10.758 100 90 176 1 1069165232 63641568 17911200
05:16:00 1578 8192000000 9999697792 10361643008 1017091424 11.044 100 88 174 0 1113099856 77285616 18722816
05:17:04 1928 8192000000 10021749600 10387103744 1035801648 11.224 100 93 181 0 1138714832 84487680 18425504
05:18:08 868 8192000000 10060066224 10436440064 1055711728 11.415 100 98 188 0 1182487984 107247712 19528544
05:19:09 913 8192000000 10057984320 10442354688 1064364544 11.498 100 93 180 0 1175491760 93720784 17406432
05:20:11 1142 8192000000 10038808016 10416873472 1068129408 11.534 100 93 177 0 1161416272 75176416 18110448
05:21:13 3205 8192000000 10024479776 10414010368 1068558752 11.538 100 69 129 0 1152218800 71161120 12498928
05:22:17 1124 8192000000 10043648624 10428702720 1080945856 11.656 100 93 176 3 1159917472 60505984 18465632
05:23:19 4612 8192000000 10011965072 10401345536 1081262928 11.660 100 63 122 0 1144455408 51015696 12176784
05:24:25 987 8192000000 10070609472 10462371840 1086032560 11.705 100 85 164 1 1195119120 93559424 15527136
05:25:27 1314 8192000000 10060069712 10454261760 1090593088 11.748 100 92 180 3 1178942688 70074352 18275248
05:26:29 4742 8192000000 10055214208 10447912960 1092434704 11.766 100 70 136 0 1180535840 74430672 13670464
05:27:35 1035 8192000000 10043538672 10435604480 1094726128 11.788 100 95 185 1 1165720336 52363984 18630224
05:28:37 1213 8192000000 10081855232 10473529344 1100739584 11.845 100 94 173 2 1198851760 79661008 18451168
05:29:39 1208 8192000000 10052781504 10438246400 1079737440 11.645 100 94 184 2 1174824768 76397360 18689968
05:30:41 1212 8192000000 10048152096 10429480960 1075421776 11.604 100 96 178 4 1167325280 74412688 17490816
05:31:44 1172 8192000000 10003235952 10378559488 1050236144 11.363 100 88 167 1 1126378320 58916752 17225424
05:32:45 1251 8192000000 9998036528 10384826368 1056915216 11.427 100 75 145 3 1117177360 45725792 14536352
05:33:49 4346 8192000000 10013341168 10397478912 1059498320 11.452 100 59 114 1 1131924480 60208960 12217200
05:34:53 12532 8192000000 9993925488 10373312512 1005210448 10.929 96 56 109 0 1087144992 71187376 10747168
05:36:07 798 8192000000 9960515888 10333917184 1030905184 11.177 99 89 176 0 1082558240 33238144 18414912
05:37:09 2009 8192000000 9990062512 10374430720 1048224912 11.344 100 74 140 2 1110412240 47628624 14558704
05:38:15 3361 8192000000 10027231296 10401280000 1047818464 11.340 100 82 151 1 1149183296 86091088 15273744
05:39:23 10586 8192000000 10040006800 10435997696 1062499216 11.480 100 58 110 1 1169739920 95927072 11313632
05:40:36 3925 8192000000 10051504304 10436030464 1076626736 11.615 100 71 134 1 1182901488 92830256 13444496
05:41:44 9642 8192000000 10100521856 10495668224 1091115168 11.753 100 66 129 1 1229792640 125318480 13358992
05:42:58 6682 8192000000 10118131936 10501779456 1101547936 11.852 100 78 156 2 1227587936 109065056 16974944
05:44:09 1314 8192000000 10162442928 10553257984 1121815344 12.044 100 89 164 1 1274226672 133860176 18551152
05:45:14 1815 8192000000 10207616288 10598555648 1137942816 12.196 100 79 154 0 1318375808 163504640 16928352
05:46:19 1255 8192000000 10264159456 10653675520 1154995408 12.356 100 96 186 1 1377235120 201796704 20443008
05:47:23 1093 8192000000 10293729936 10687279104 1174025936 12.534 100 91 173 2 1403173440 210129968 19017536
05:48:28 1154 8192000000 10343116128 10746118144 1181702592 12.606 100 92 174 0 1451339360 249411328 20225440
05:49:31 1280 8192000000 10354795936 10777788416 1182209168 12.611 100 92 176 1 1465361920 263226832 19925920
05:50:35 1622 8192000000 10377062320 10770505728 1181353984 12.603 100 92 174 0 1481946704 280327744 20264976
05:51:40 1099 8192000000 10446569136 10849902592 1193653488 12.717 100 96 189 1 1552433728 337269456 21510784
05:52:45 3593 8192000000 10502636144 10906497024 1201178032 12.787 100 90 175 1 1610952976 389270976 20503968
05:53:51 1027 8192000000 10558667248 10972295168 1214944336 12.915 100 100 198 1 1664614624 425542896 24127392
05:54:55 925 8192000000 10547845616 10979450880 1201195088 12.787 100 99 196 1 1654445008 429714448 23535472
05:55:58 938 8192000000 10571301936 11013742592 1200241568 12.779 99 94 185 1 1674871648 453105968 21524112
05:57:00 836 8192000000 10578250032 11033419776 1202146144 12.796 100 82 164 0 1683403248 460807056 20450048
05:58:05 1542 8192000000 10589480000 11034013696 1202245888 12.797 100 83 162 1 1694499152 472161824 20091440
05:59:11 2720 8192000000 10642993264 11095781376 1216493056 12.929 100 72 142 0 1748393088 514242784 17657248
06:00:17 10517 8192000000 10625154752 11073110016 1199759088 12.774 100 50 99 0 1727280336 514507376 13013872
06:01:35 7848 8192000000 10665389008 11122618368 1203588560 12.810 100 50 100 0 1787110208 570014448 13507200
06:02:44 15895 8192000000 10649688480 11136602112 1187561680 12.661 100 48 96 0 1761563824 560942704 13059440
06:04:11 28912 8192000000 10566515824 11074818048 1185046944 12.637 100 37 74 1 1676542032 481332912 10162176
06:05:41 20124 8192000000 10439071344 10994106368 1155416480 12.360 99 26 51 0 1537079904 374748912 6914512
06:07:02 60675 8192000000 10172819680 10670444544 1131571328 12.136 98 17 34 0 1292414256 156779040 4063888
06:09:03 44183 8192000000 10098064624 10527354880 1135027280 12.169 99 70 130 2 1181700336 34176384 12496672
06:10:49 686 8192000000 10141684432 10558263296 1148347232 12.294 100 96 187 1 1240691104 73183584 19160288
06:11:51 47859 8192000000 10094174768 10573754368 1131587376 12.136 98 26 51 0 1216146080 79603360 4955344
06:13:43 58008 8192000000 10104846864 10556116992 1144536592 12.258 99 27 54 1 1199596512 49071664 5988256
06:15:49 114822 8192000000 10080061184 10572206080 1128263728 12.105 98 10 20 0 1182165584 51751584 2150272
06:19:20 101859 8192000000 10082047152 10553749504 1122292240 12.049 97 18 34 0 1166963024 40955072 3715712
06:22:12 57367 8192000000 10116216576 10586279936 1150717712 12.316 100 21 40 1 1209398160 54269264 4411184
06:24:10 68726 8192000000 10102672560 10573914112 1104946304 11.885 96 13 26 0 1172170144 64201488 3022352
06:26:35 31904 8192000000 10143218160 10602262528 1126602512 12.089 99 36 72 0 1214282192 79676512 8003168
06:28:26 84024 8192000000 10144656944 10601689088 1116733184 11.996 97 22 44 0 1208402800 87121424 4548192
06:30:59 36357 8192000000 10097844224 10540650496 1115226320 11.982 98 29 54 0 1170976032 50095008 5654704
06:32:36 38080 8192000000 10180926304 10630291456 1139841536 12.214 100 49 98 0 1266586320 116573552 10171232
06:34:14 73587 8192000000 10084290208 10520637440 1109305088 11.926 98 21 40 1 1207315856 93117184 4893584
06:36:28 124921 8192000000 10035695248 10484658176 1061332848 11.469 94 36 70 0 1110779584 41636032 7810704
06:39:55 17559 8192000000 10063507904 10506215424 1119374144 12.021 100 47 93 0 1173659552 45282544 9002864
06:41:18 124625 8192000000 10079566352 10533072896 1106891696 11.903 98 19 38 0 1173527024 61961536 4673792
06:44:33 40488 8192000000 10058543440 10549882880 1102818544 11.864 98 14 24 0 1143693696 37699808 3175344
06:46:13 88431 8192000000 10063273040 10551787520 1077820528 11.627 96 12 24 0 1146901296 66481712 2599056
06:48:43 18021 8192000000 10083126464 10517823488 1110119488 11.934 98 39 78 0 1163767200 46477856 7169856
06:50:05 13592 8192000000 10109259776 10540949504 1112906576 11.960 98 52 94 2 1195090560 72524432 9659552
06:51:27 38975 8192000000 10096888960 10558717952 1136378640 12.181 100 29 58 1 1218074928 75584400 6111888
06:53:09 73865 8192000000 10099773840 10585681920 1080860192 11.656 95 17 34 1 1165919712 81472576 3586944
06:55:31 22109 8192000000 10040698608 10458357760 1099950128 11.837 98 46 82 1 1135735200 27421648 8363424
06:56:53 49323 8192000000 10097135920 10575806464 1097581184 11.815 98 25 48 1 1209876960 107058560 5237216
06:58:44 47234 8192000000 10124232784 10580979712 1105639088 11.891 98 34 68 0 1190595616 78584208 6372320
07:00:43 112955 8192000000 10093590880 10544480256 1134917696 12.168 100 42 82 1 1172633296 29082880 8632720
07:03:37 61699 8192000000 10086293136 10586562560 1141821408 12.233 100 6 12 0 1216623936 73500016 1302512
07:05:38 83571 8192000000 10109272336 10560667648 1113042816 11.961 97 19 37 0 1196056544 78657328 4356400
07:08:19 69265 8192000000 10103860608 10558918656 1118025040 12.008 98 13 26 1 1146678928 25377776 3276112
07:10:37 16155 8192000000 10139299264 10589126656 1147404480 12.285 100 64 125 1 1243434656 82740864 13289312
07:11:53 46800 8192000000 10155795280 10603560960 1144557376 12.258 99 33 65 0 1251219744 99947472 6714896
07:13:41 38640 8192000000 10145595264 10583257088 1111342832 11.945 97 27 54 0 1227101712 110075376 5683504
07:15:22 48412 8192000000 10097562800 10577833984 1116516480 11.994 98 25 48 1 1224700112 103325744 4857888
07:17:25 46340 8192000000 10158553472 10585075712 1089121760 11.734 95 45 90 0 1178504864 80212656 9170448
07:19:13 47530 8192000000 10105830352 10540834816 1126887280 12.092 99 25 48 0 1196254608 64796304 4571024
07:21:02 93182 8192000000 10084188032 10522734592 1145537232 12.268 100 42 81 0 1183914704 31121696 7255776
07:23:39 58589 8192000000 10057419392 10516176896 1110462192 11.937 98 6 12 0 1142831008 30896192 1472624
07:25:38 58451 8192000000 10047692672 10528710656 1093109568 11.772 97 8 16 0 1134836864 39726304 2000992
07:28:56 38260 8192000000 10069053456 10522578944 1082011888 11.667 96 34 68 1 1134919088 45567168 7340032
07:30:40 78905 8192000000 10114234288 10561507328 1088944144 11.733 96 22 44 0 1160507024 67124464 4438416
07:32:59 63362 8192000000 10076897536 10518609920 1102381360 11.860 98 26 51 0 1203851088 95709104 5760624
07:35:11 32985 8192000000 10154128768 10587353088 1120077920 12.028 99 36 72 0 1231756208 103928848 7749440
07:36:45 42771 8192000000 9208669712 9520418816 339976128 3.984 31 12 24 0 377630224 35236688 2417408

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 29-01-2023 07:38:21 to 29-01-2023 07:38:37.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 145 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 29-01-2023 07:38:38 to 29-01-2023 07:38:40.

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          221858
MON$OLDEST_ACTIVE               221859
MON$OLDEST_SNAPSHOT             221859
MON$NEXT_TRANSACTION            221859
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                       2037344
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             779
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2894 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2894 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2894 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2894 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 ---
--- end of diff output ---

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

29.01.23 07:38:42. 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
2023-01-29 07:38:44 No severe PSQL-related problems occured
29.01.23 07:38:46. End of report.