Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.01.2023 07: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 14824504 kB
MemAvailable 30466924 kB
Buffers 0 kB
Cached 16899700 kB
SwapCached 2688 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-19 07:37:23
DTS_END                         2023-01-19 07:37:23.3910
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/45450
TRN_ID                          227196
ATT_ID                          674
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: 8974.97 27253 1076996
customer order: creation 177.38 440 21286
customer order: refuse 15.35 455 1842
order to supplier: creation 46.77 1412 5612
order to supplier: removal 3.29 170839 395
invoice (draft): creation 48.62 3330 5834
invoice (draft): removal 48.24 1096 5789
invoice accept: apply 44.13 26381 5295
invoice accept: cancel 43.43 1478 5212
customer reserve: creation 4047.91 253 485749
customer reserve: removal 4199.83 104 503980
realization accept: apply 86.95 314 10434
realization accept: cancel 77.84 181 9341
payment from customer: creation 61.78 190 7413
payment from customer: removal 6.06 142 727
payment to supplier: creation 50.82 199 6098
payment to supplier: removal 3.69 113 443
service: total inventory turnovers 3.95 4630 474
service: total monetary turnovers 7.92 457 950
service: aggregate perf. data 0.98 18120 118
service: refresh index statistics 0.03 314919 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-01-2023 07:38:54 to 19-01-2023 07:38:56.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6833
TEST_TIME 2 4943
TEST_TIME 3 5700
TEST_TIME 4 5479
TEST_TIME 5 6006
TEST_TIME 6 5708
TEST_TIME 7 5763
TEST_TIME 8 5492
TEST_TIME 9 5417
TEST_TIME 10 5736
TEST_TIME 11 5881
TEST_TIME 12 5898
TEST_TIME 13 5969
TEST_TIME 14 6062
TEST_TIME 15 6245
TEST_TIME 16 6441
TEST_TIME 17 6575
TEST_TIME 18 6652
TEST_TIME 19 6627
TEST_TIME 20 6890
TEST_TIME 21 7297
TEST_TIME 22 7390
TEST_TIME 23 7714
TEST_TIME 24 7909
TEST_TIME 25 8122
TEST_TIME 26 8052
TEST_TIME 27 8176
TEST_TIME 28 8218
TEST_TIME 29 8249
TEST_TIME 30 8618
TEST_TIME 31 8532
TEST_TIME 32 8980
TEST_TIME 33 9020
TEST_TIME 34 9540
TEST_TIME 35 9798
TEST_TIME 36 9945
TEST_TIME 37 9937
TEST_TIME 38 9994
TEST_TIME 39 10270
TEST_TIME 40 10138
TEST_TIME 41 10023
TEST_TIME 42 9954
TEST_TIME 43 9912
TEST_TIME 44 9806
TEST_TIME 45 9845
TEST_TIME 46 9723
TEST_TIME 47 9714
TEST_TIME 48 9663
TEST_TIME 49 9760
TEST_TIME 50 9679
TEST_TIME 51 9658
TEST_TIME 52 9609
TEST_TIME 53 9584
TEST_TIME 54 9545
TEST_TIME 55 9416
TEST_TIME 56 9529
TEST_TIME 57 9479
TEST_TIME 58 9413
TEST_TIME 59 9332
TEST_TIME 60 9262
TEST_TIME 61 9231
TEST_TIME 62 9216
TEST_TIME 63 9194
TEST_TIME 64 9225
TEST_TIME 65 9224
TEST_TIME 66 9227
TEST_TIME 67 9163
TEST_TIME 68 9176
TEST_TIME 69 9244
TEST_TIME 70 9208
TEST_TIME 71 9252
TEST_TIME 72 9380
TEST_TIME 73 9342
TEST_TIME 74 9325
TEST_TIME 75 9337
TEST_TIME 76 9303
TEST_TIME 77 9339
TEST_TIME 78 9362
TEST_TIME 79 9393
TEST_TIME 80 9344
TEST_TIME 81 9393
TEST_TIME 82 9417
TEST_TIME 83 9451
TEST_TIME 84 9420
TEST_TIME 85 9433
TEST_TIME 86 9414
TEST_TIME 87 9322
TEST_TIME 88 9309
TEST_TIME 89 9290
TEST_TIME 90 9241
TEST_TIME 91 9201
TEST_TIME 92 9196
TEST_TIME 93 9172
TEST_TIME 94 9190
TEST_TIME 95 9133
TEST_TIME 96 9092
TEST_TIME 97 9018
TEST_TIME 98 9028
TEST_TIME 99 9137
TEST_TIME 100 9098
TEST_TIME 101 9118
TEST_TIME 102 9157
TEST_TIME 103 9122
TEST_TIME 104 9145
TEST_TIME 105 9115
TEST_TIME 106 9170
TEST_TIME 107 9198
TEST_TIME 108 9175
TEST_TIME 109 9209
TEST_TIME 110 9171
TEST_TIME 111 9176
TEST_TIME 112 9172
TEST_TIME 113 9244
TEST_TIME 114 9173
TEST_TIME 115 9136
TEST_TIME 116 9136
TEST_TIME 117 9097
TEST_TIME 118 9076
TEST_TIME 119 9045
TEST_TIME 120 8990

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 19-01-2023 07:38:58 to 19-01-2023 07:39:01.

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 1522682 1522682 0 0.00 0 24090 15 0 0
doc_list_biud 1522682 1522682 0 0.00 0 20529 41 0 0
sp_add_invoice_to_stock 5461 5295 166 3.03 94 195363 26381 0 166
sp_cancel_adding_invoice 5212 5212 0 0.00 5 106822 1478 0 0
sp_cancel_client_order 1842 1842 0 0.00 6 9361 456 0 0
sp_cancel_customer_reserve 503980 503980 0 0.00 1 19654 104 0 0
sp_cancel_pay_from_customer 727 727 0 0.00 4 6142 143 0 0
sp_cancel_pay_to_supplier 443 443 0 0.00 3 1353 114 0 0
sp_cancel_supplier_invoice 5811 5789 22 0.37 2 16327 1096 0 22
sp_cancel_supplier_order 395 395 0 0.00 21 1525198 170840 0 0
sp_cancel_write_off 9341 9341 0 0.00 2 8273 181 0 0
sp_client_order 21286 21286 0 0.00 6 19466 441 0 0
sp_customer_reserve 485749 485749 0 0.00 1 98735 254 0 0
sp_fill_shopping_cart 32727 32727 0 0.00 1 39871 88 0 0
sp_fill_shopping_cart_clo_res 485717 485717 0 0.00 0 13838 16 0 0
sp_get_clo_for_invoice 5258 5258 0 0.00 0 24185 256 0 0
sp_kill_cost_storno 15723 15723 0 0.00 0 8218 82 0 0
sp_kill_qstorno_ret_qs2qd 1023485 1023485 0 0.00 0 19627 46 0 0
sp_kill_qty_storno 542249 542249 0 0.00 0 20529 107 0 0
sp_lock_selected_doc 21803 21803 0 0.00 0 6579 8 0 0
sp_make_cost_storno 29201 29201 0 0.00 0 24046 92 0 0
sp_multiply_rows_for_pdistr 29201 29201 0 0.00 0 9073 41 0 0
sp_multiply_rows_for_qdistr 21286 21286 0 0.00 0 9784 148 0 0
sp_pay_from_customer 7413 7413 0 0.00 6 12682 190 0 0
sp_pay_to_supplier 6098 6098 0 0.00 7 13314 199 0 0
sp_payment_common 13511 13511 0 0.00 2 9181 50 0 0
sp_qd_handle_on_cancel_clo 1842 1842 0 0.00 1 9354 370 0 0
sp_qd_handle_on_invoice_upd_sts 10468 10468 0 0.00 0 20529 591 0 0
sp_qd_handle_on_reserve_upd_sts 10434 10434 0 0.00 0 14423 67 0 0
sp_reserve_write_off 10434 10434 0 0.00 8 24125 314 0 0
sp_supplier_invoice 5834 5834 0 0.00 7 96264 3331 0 0
sp_supplier_order 5612 5612 0 0.00 8 35817 1413 0 0
srv_aggregate_perf_data 118 118 0 0.00 2592 207321 18121 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 763 123699 32021 0 0
srv_find_qd_qs_mism 1025130 1025130 0 0.00 0 9924 11 0 0
srv_make_invnt_saldo 474 474 0 0.00 90 79269 4631 0 0
srv_make_money_saldo 950 950 0 0.00 1 25602 458 0 0
srv_recalc_idx_stat 4 4 0 0.00 187147 489437 314920 0 0
t$perf-abend:sp_add_invoice_to_stock 166 166 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 22 22 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5295 5295 0 0.00 1 18945 993 0 0
t$perf-norm:sp_cancel_adding_invoice 458 458 0 0.00 1 17875 558 0 0
t$perf-norm:sp_cancel_client_order 1842 1842 0 0.00 0 2329 29 0 0
t$perf-norm:sp_cancel_customer_reserve 725 725 0 0.00 0 740 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 727 727 0 0.00 0 651 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 443 443 0 0.00 0 2011 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 421 421 0 0.00 0 858 36 0 0
t$perf-norm:sp_cancel_supplier_order 395 395 0 0.00 0 85832 7829 0 0
t$perf-norm:sp_cancel_write_off 1102 1102 0 0.00 0 2453 23 0 0
t$perf-norm:sp_client_order 21286 21286 0 0.00 0 36225 43 0 0
t$perf-norm:sp_customer_reserve 988 988 0 0.00 0 669 13 0 0
t$perf-norm:sp_pay_from_customer 7413 7413 0 0.00 0 6503 21 0 0
t$perf-norm:sp_pay_to_supplier 6098 6098 0 0.00 0 22412 29 0 0
t$perf-norm:sp_reserve_write_off 10434 10434 0 0.00 0 6728 25 0 0
t$perf-norm:sp_supplier_invoice 5834 5834 0 0.00 0 3228 31 0 0
t$perf-norm:sp_supplier_order 5612 5612 0 0.00 0 25194 46 0 0
t$perf-norm:srv_aggregate_perf_data 118 118 0 0.00 0 318 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 27 3 0 0
t$perf-norm:srv_make_invnt_saldo 474 474 0 0.00 0 939 17 0 0
t$perf-norm:srv_make_money_saldo 950 950 0 0.00 0 32542 41 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 10 232 76 0 0
v_add_invoice_to_stock 10844 10512 332 3.06 0 7948 28 0 332
v_all_customers 38776 38776 0 0.00 0 127 4 0 0
v_all_suppliers 31502 31502 0 0.00 0 185 5 0 0
v_all_wares 42572 42572 0 0.00 0 197 4 0 0
v_cancel_adding_invoice 916 916 0 0.00 0 2503 22 0 0
v_cancel_client_order 3684 3684 0 0.00 0 2690 11 0 0
v_cancel_customer_prepayment 1454 1454 0 0.00 0 3332 16 0 0
v_cancel_customer_reserve 1450 1450 0 0.00 1 684 11 0 0
v_cancel_payment_to_supplier 886 886 0 0.00 0 1324 14 0 0
v_cancel_supplier_invoice 886 842 44 4.96 0 3334 30 0 44
v_cancel_supplier_order 790 790 0 0.00 0 2029 15 0 0
v_cancel_write_off 2204 2204 0 0.00 0 1461 25 0 0
v_max_id_clo_ord 5612 5612 0 0.00 0 3788 6 0 0
v_max_id_clo_res 988 988 0 0.00 0 1999 16 0 0
v_max_id_ord_sup 5828 5828 0 0.00 0 3559 8 0 0
v_max_non_paid_invoice 1787 1787 0 0.00 0 147 7 0 0
v_max_non_paid_realizn 3985 3985 0 0.00 0 1104 9 0 0
v_min_id_clo_ord 5612 5612 0 0.00 0 7372 10 0 0
v_min_id_clo_res 988 988 0 0.00 0 1051 9 0 0
v_min_id_ord_sup 5828 5828 0 0.00 0 7127 11 0 0
v_min_non_paid_invoice 6098 6098 0 0.00 0 6339 19 0 0
v_min_non_paid_realizn 7413 7413 0 0.00 0 9177 21 0 0
v_our_firm 10652 10652 0 0.00 0 53 5 0 0
v_reserve_write_off 20868 20868 0 0.00 0 3505 12 0 0
x_lock_depdocs_on_canc_invoice 10580 10580 0 0.00 0 27229 152 0 0
x_lock_depdocs_on_canc_sup_ord 395 395 0 0.00 0 15584 510 0 0
x_make_qstorno_1000_1200 5612 5612 0 0.00 2 35802 1228 0 0
x_make_qstorno_1200_2000 5834 5834 0 0.00 3 96245 3091 0 0
x_make_qstorno_2100_3300 485749 485749 0 0.00 0 98722 225 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 19-01-2023 07:39:02 to 19-01-2023 07:39:07.

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:27 33 8192000000 8813112720 8829788160 12285072 0.149 7 1 2 0 12398160 77312 35776
05:08:29 25338 8192000000 9722640240 10023583744 799502400 8.891 100 84 168 0 827185152 7657936 20024816
05:09:56 26846 8192000000 9768080064 10076651520 842171728 9.322 100 90 180 0 876061392 12752288 21137376
05:11:26 18216 8192000000 9805113216 10120982528 861568944 9.516 100 90 180 0 904339952 21858256 20912752
05:12:47 8998 8192000000 9845991168 10177355776 901888496 9.917 100 83 164 0 961093648 40532672 18672480
05:13:59 5360 8192000000 9912005264 10249424896 940209024 10.295 100 89 175 0 1024577088 65647664 18720400
05:15:08 1879 8192000000 9950877648 10301288448 982872768 10.712 100 94 182 0 1070763920 69467120 18424032
05:16:12 2017 8192000000 9997881216 10356211712 1014787168 11.022 100 92 182 0 1112727728 79551104 18389456
05:17:16 1508 8192000000 10010424656 10376806400 1037098752 11.237 100 94 183 0 1123529488 68288464 18142272
05:18:19 1107 8192000000 10005216544 10380009472 1055611600 11.414 100 94 186 0 1124808240 49236208 19960432
05:19:22 923 8192000000 10033120880 10413907968 1074101760 11.591 100 96 187 2 1159028640 66134192 18792688
05:20:24 1311 8192000000 10047878288 10427215872 1078927216 11.637 100 91 173 1 1169657072 73303168 17426688
05:21:27 1817 8192000000 10079673536 10461638656 1086168928 11.706 100 92 175 0 1203012992 99356704 17487360
05:22:30 1107 8192000000 10047102432 10432847872 1090408640 11.747 100 91 173 3 1170647360 63086016 17152704
05:23:32 7594 8192000000 10059022016 10458349568 1093607856 11.777 100 58 114 1 1184137888 79149728 11380304
05:24:41 1018 8192000000 10060861968 10454142976 1099433856 11.832 100 91 170 0 1185580608 68488896 17657856
05:25:43 1180 8192000000 10062017088 10450046976 1100506896 11.842 100 87 158 4 1182032288 67457232 14068160
05:26:45 1927 8192000000 10086667360 10483240960 1104797424 11.883 100 84 158 2 1213094048 91560000 16736624
05:27:47 1189 8192000000 10069229008 10456645632 1106616288 11.900 100 93 181 2 1190122576 64558704 18947584
05:28:50 1022 8192000000 10061555904 10452262912 1107858800 11.912 100 84 163 3 1184909360 60866240 16184320
05:29:52 5767 8192000000 10042467264 10436124672 1097878720 11.818 99 59 113 1 1164312336 55656560 10777056
05:31:00 1161 8192000000 10048295136 10436907008 1085983488 11.704 100 89 164 3 1171463136 69557744 15921904
05:32:01 1216 8192000000 10042782656 10425602048 1079435920 11.642 100 89 175 1 1165847120 69139936 17271264
05:33:04 1124 8192000000 10040723504 10419306496 1072388784 11.575 100 93 177 1 1163584704 71824672 19371248
05:34:06 1142 8192000000 9984832144 10362187776 1036842512 11.234 100 91 164 5 1107991552 54209280 16939760
05:35:08 1297 8192000000 9997247120 10373099520 1042880272 11.292 100 95 176 4 1119890464 59211936 17798256
05:36:11 1149 8192000000 9995005152 10366410752 1031503232 11.183 100 95 181 4 1117152544 68106032 17543280
05:37:12 1201 8192000000 9950565200 10322186240 1008735024 10.963 99 82 156 1 1071873264 48992432 14145808
05:38:20 9267 8192000000 9975590000 10355679232 1039940800 11.264 100 66 119 1 1097721408 45515456 12265152
05:39:37 1490 8192000000 10028327200 10407391232 1061410928 11.470 100 84 155 2 1142070848 65621376 15038544
05:40:43 3945 8192000000 10046198224 10437120000 1070527200 11.557 100 84 164 0 1172311568 84218320 17566048
05:41:52 11800 8192000000 10104413888 10495447040 1080271216 11.650 100 74 146 1 1214964304 118844816 15848272
05:43:15 2820 8192000000 10089152032 10481332224 1092550192 11.767 100 62 116 2 1201381520 96198896 12632432
05:44:53 12028 8192000000 10111284592 10496905216 1107008480 11.904 100 72 132 1 1222005776 101903120 13094176
05:46:10 11181 8192000000 10158489392 10558881792 1113404896 11.965 100 77 144 1 1269995744 141434240 15156608
05:47:25 8161 8192000000 10187175408 10585927680 1131049376 12.131 100 74 146 1 1296266192 149723440 15493376
05:48:38 1341 8192000000 10238623936 10630778880 1154649872 12.353 100 82 155 0 1347914832 176239088 17025872
05:49:41 1675 8192000000 10287911696 10680324096 1164337760 12.444 100 97 189 2 1399273616 213570720 21365136
05:50:46 2271 8192000000 10313847968 10712137728 1168881648 12.486 100 94 182 3 1430304640 241767904 19655088
05:51:53 2127 8192000000 10357485264 10767474688 1177113120 12.563 100 85 168 2 1470360192 274440336 18806736
05:53:01 1499 8192000000 10388066752 10786656256 1178628768 12.577 100 98 188 1 1493544400 294314976 20600656
05:54:06 1239 8192000000 10397692256 10809425920 1184139056 12.629 100 91 173 3 1508325008 305245904 18940048
05:55:10 1581 8192000000 10419387408 10824036352 1182307056 12.612 100 95 190 1 1526291504 322218704 21765744
05:56:16 756 8192000000 10433807472 10845618176 1165649024 12.456 100 97 190 1 1542791408 354957840 22184544
05:57:19 1055 8192000000 10458190128 10890321920 1174539744 12.539 100 98 191 0 1564140272 366118464 23482064
05:58:23 959 8192000000 10469669760 10896019456 1179297088 12.584 100 98 189 1 1576894960 375649680 21948192
05:59:27 906 8192000000 10450952352 10901209088 1185757760 12.644 100 91 175 1 1558239056 350502288 21979008
06:00:31 2230 8192000000 10451931712 10880278528 1177997840 12.572 100 80 157 0 1558174528 361540192 18636496
06:01:44 3730 8192000000 10409432016 10912251904 1175026784 12.544 100 64 119 1 1515823856 326140704 14656368
06:02:55 808 8192000000 10453756272 10880520192 1176725232 12.560 100 95 190 1 1558929952 360427168 21777552
06:04:00 4237 8192000000 10471165136 10893303808 1175857184 12.552 100 63 127 0 1577088848 385513968 15717696
06:05:11 7738 8192000000 10476267680 10915725312 1168024416 12.478 100 56 107 1 1586087184 404450656 13612112
06:06:23 8672 8192000000 10446021120 10904866816 1164643728 12.447 100 41 82 0 1553642640 378670016 10328896
06:07:36 5674 8192000000 10426247520 10904088576 1158888656 12.393 100 54 104 1 1532989200 360758304 13342240
06:08:45 1716 8192000000 10440814144 10882248704 1153039504 12.338 99 71 142 1 1542925616 373274192 16611920
06:09:49 7807 8192000000 10350545776 10813804544 1156053888 12.366 100 46 90 0 1469454784 301720880 11680016
06:10:58 18170 8192000000 10288345728 10803793920 1133175328 12.151 100 43 82 0 1450239120 306226464 10837328
06:12:25 12171 8192000000 10214331456 10677460992 1128525024 12.107 99 43 86 0 1314836672 176309072 10002576
06:13:45 26524 8192000000 10188944128 10652692480 1142850768 12.242 100 50 94 1 1310924352 157272352 10801232
06:15:18 40085 8192000000 10141688480 10637352960 1129576816 12.117 99 22 43 1 1251293632 116530656 5186160
06:17:00 84883 8192000000 10065848144 10517639168 1129725888 12.119 99 16 32 0 1157768320 24031536 4010896
06:19:49 93839 8192000000 10098508304 10549522432 1142370288 12.238 99 13 26 1 1179819728 34888848 2560592
06:22:27 44618 8192000000 10085192816 10530574336 1125838640 12.082 98 15 30 0 1176328320 47215680 3274000
06:24:13 84428 8192000000 10092656624 10519494656 1112005136 11.951 97 18 34 0 1175345040 59767088 3572816
06:26:57 58280 8192000000 10068741120 10500993024 1114693072 11.977 97 20 42 0 1148607520 29987200 3927248
06:28:57 15326 8192000000 10066852960 10479083520 1123432384 12.059 99 53 102 0 1150257168 15779968 11044816
06:30:18 14376 8192000000 10092678480 10524475392 1131463664 12.135 100 22 42 1 1185465760 49788656 4213440
06:31:38 87618 8192000000 10149290752 10604077056 1149894144 12.309 100 26 50 1 1200507984 44076384 6537456
06:34:33 36595 8192000000 10092468976 10513473536 1115146112 11.981 97 38 70 2 1147078352 23535744 8396496
06:36:12 105374 8192000000 10088548672 10518409216 1108240928 11.916 96 42 82 0 1178403904 61576448 8586528
06:38:59 23191 8192000000 10158510448 10588127232 1157401344 12.379 100 45 85 0 1248177616 80757392 10018880
06:40:36 4613 8192000000 10152911040 10575093760 1162603152 12.428 100 73 141 2 1252363920 74552272 15208496
06:41:43 34650 8192000000 10192238736 10629779456 1151619472 12.325 99 41 78 2 1299617952 138895616 9102864
06:43:19 78480 8192000000 10193384768 10616999936 1121666736 12.043 98 29 58 0 1256688544 127744288 7277520
06:45:43 23482 8192000000 10186777872 10641809408 1137074464 12.188 100 28 52 0 1291843408 148778528 5990416
06:47:20 79391 8192000000 10137023952 10615939072 1103737072 11.873 97 21 40 0 1235011632 127022576 4251984
06:49:58 48910 8192000000 10047803744 10552090624 1082896976 11.675 97 8 16 0 1139675680 55233200 1545504
06:51:47 15042 8192000000 10082526064 10505748480 1099446976 11.832 98 49 98 1 1160146800 51188896 9510928
06:53:05 20443 8192000000 10087130400 10505773056 1123408560 12.059 100 63 122 0 1174169120 37816432 12944128
06:54:27 21804 8192000000 10093578352 10521436160 1130372608 12.125 100 57 114 1 1199935056 57551904 12010544
06:56:07 42164 8192000000 10083052368 10539380736 1140823968 12.223 100 26 52 1 1197223472 50334032 6065472
06:57:53 24174 8192000000 10151692032 10590355456 1145589120 12.268 100 43 87 0 1217598640 63445808 8563712
06:59:21 82665 8192000000 10135437232 10577485824 1091999904 11.762 95 16 33 1 1179803904 84112304 3691696
07:01:45 55101 8192000000 10119300608 10592284672 1119607136 12.023 99 28 52 0 1187121536 62258112 5256288
07:03:40 79045 8192000000 10089446944 10534420480 1116290736 11.992 98 31 62 0 1168110096 45272400 6546960
07:05:59 75834 8192000000 10054769584 10493919232 1087979744 11.723 96 27 50 1 1133075952 39969008 5127200
07:08:28 60153 8192000000 10127958176 10585001984 1140363840 12.219 100 26 53 0 1247926192 101967408 5594944
07:10:28 52646 8192000000 10134416000 10576756736 1114477152 11.975 97 22 43 0 1194982304 75945648 4559504
07:13:33 83075 8192000000 10160582688 10611863552 1135116352 12.170 99 20 40 0 1251043632 110249584 5677696
07:16:17 123699 8192000000 10124805008 10588725248 1133725168 12.156 99 10 20 0 1173468112 37804736 1938208
07:19:21 26566 8192000000 10147072112 10582913024 1146662320 12.278 99 52 98 0 1233677744 76423424 10592000
07:21:10 48696 8192000000 10137982720 10622148608 1159986992 12.403 100 38 72 1 1249092576 81839968 7265616
07:22:59 41430 8192000000 10132659056 10626330624 1149592368 12.306 99 26 52 0 1252930176 97591536 5746272
07:24:43 62994 8192000000 10116614576 10554716160 1119279248 12.020 97 27 53 1 1172740784 47660256 5801280
07:26:46 41813 8192000000 10098186352 10538348544 1109330096 11.926 97 40 74 0 1155142336 37239648 8572592
07:28:29 32944 8192000000 10107280432 10543099904 1118545376 12.013 99 44 88 0 1196071968 68885280 8641312
07:30:03 38756 8192000000 10094413280 10560729088 1118054992 12.009 99 25 50 0 1203425568 80297888 5072688
07:31:43 78169 8192000000 10091483616 10561974272 1125853472 12.082 100 19 38 0 1202126560 72459136 3813952
07:34:06 24449 8192000000 10054556576 10559574016 1078130560 11.630 97 6 12 0 1140561024 61365280 1065184
07:35:40 39844 8192000000 10038303632 10504945664 1099298112 11.831 99 13 24 0 1129930096 28265920 2366064

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-01-2023 07:39:09 to 19-01-2023 07:39:25.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 166 v_add_invoice_to_stock 335544517
except 22 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 19-01-2023 07:39:26 to 19-01-2023 07:39:28.

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          226729
MON$OLDEST_ACTIVE               227466
MON$OLDEST_SNAPSHOT             227466
MON$NEXT_TRANSACTION            227466
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                       2099776
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             787
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

19.01.23 07:39:29. 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-19 07:39:32 No severe PSQL-related problems occured
19.01.23 07:39:33. End of report.