Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.08.2022 13: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 15859352 kB
MemAvailable 30882264 kB
Buffers 0 kB
Cached 15831280 kB
SwapCached 2524 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-08-14 13:37:18
DTS_END                         2022-08-14 13:37:18.9600
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/49712
TRN_ID                          218792
ATT_ID                          646
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: 8792.48 25400 1055098
customer order: creation 162.14 449 19457
customer order: refuse 14.04 388 1685
order to supplier: creation 44.15 1339 5298
order to supplier: removal 3.33 159884 399
invoice (draft): creation 43.61 3202 5233
invoice (draft): removal 49.85 958 5982
invoice accept: apply 40.52 24189 4862
invoice accept: cancel 44.70 1090 5364
customer reserve: creation 3737.90 231 448548
customer reserve: removal 4365.99 95 523919
realization accept: apply 78.84 253 9461
realization accept: cancel 82.87 155 9944
payment from customer: creation 56.53 168 6783
payment from customer: removal 5.49 164 659
payment to supplier: creation 47.25 197 5670
payment to supplier: removal 3.38 108 406
service: total inventory turnovers 3.97 3458 476
service: total monetary turnovers 7.06 357 847
service: aggregate perf. data 0.84 18035 101
service: refresh index statistics 0.03 293277 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 14-08-2022 13:38:32 to 14-08-2022 13:38:35.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9853
TEST_TIME 2 8013
TEST_TIME 3 6668
TEST_TIME 4 6054
TEST_TIME 5 6129
TEST_TIME 6 6057
TEST_TIME 7 6031
TEST_TIME 8 6063
TEST_TIME 9 6111
TEST_TIME 10 6069
TEST_TIME 11 6249
TEST_TIME 12 6210
TEST_TIME 13 6590
TEST_TIME 14 6762
TEST_TIME 15 6591
TEST_TIME 16 6827
TEST_TIME 17 7289
TEST_TIME 18 7412
TEST_TIME 19 7819
TEST_TIME 20 7974
TEST_TIME 21 7863
TEST_TIME 22 7778
TEST_TIME 23 8120
TEST_TIME 24 8655
TEST_TIME 25 9165
TEST_TIME 26 9780
TEST_TIME 27 10130
TEST_TIME 28 10262
TEST_TIME 29 10441
TEST_TIME 30 10766
TEST_TIME 31 11217
TEST_TIME 32 10974
TEST_TIME 33 10693
TEST_TIME 34 10513
TEST_TIME 35 10408
TEST_TIME 36 10315
TEST_TIME 37 10172
TEST_TIME 38 10027
TEST_TIME 39 10019
TEST_TIME 40 9935
TEST_TIME 41 9868
TEST_TIME 42 9724
TEST_TIME 43 9730
TEST_TIME 44 9656
TEST_TIME 45 9618
TEST_TIME 46 9442
TEST_TIME 47 9561
TEST_TIME 48 9503
TEST_TIME 49 9405
TEST_TIME 50 9285
TEST_TIME 51 9335
TEST_TIME 52 9235
TEST_TIME 53 9243
TEST_TIME 54 9218
TEST_TIME 55 9242
TEST_TIME 56 9271
TEST_TIME 57 9251
TEST_TIME 58 9307
TEST_TIME 59 9323
TEST_TIME 60 9293
TEST_TIME 61 9270
TEST_TIME 62 9351
TEST_TIME 63 9336
TEST_TIME 64 9373
TEST_TIME 65 9451
TEST_TIME 66 9351
TEST_TIME 67 9300
TEST_TIME 68 9198
TEST_TIME 69 9255
TEST_TIME 70 9178
TEST_TIME 71 9116
TEST_TIME 72 9073
TEST_TIME 73 9033
TEST_TIME 74 8926
TEST_TIME 75 8914
TEST_TIME 76 8904
TEST_TIME 77 8932
TEST_TIME 78 8921
TEST_TIME 79 8909
TEST_TIME 80 8909
TEST_TIME 81 8916
TEST_TIME 82 8913
TEST_TIME 83 8901
TEST_TIME 84 8907
TEST_TIME 85 8942
TEST_TIME 86 9120
TEST_TIME 87 9164
TEST_TIME 88 9128
TEST_TIME 89 9080
TEST_TIME 90 9070
TEST_TIME 91 9021
TEST_TIME 92 9025
TEST_TIME 93 9035
TEST_TIME 94 9055
TEST_TIME 95 9050
TEST_TIME 96 9045
TEST_TIME 97 9057
TEST_TIME 98 9034
TEST_TIME 99 9023
TEST_TIME 100 9010
TEST_TIME 101 9020
TEST_TIME 102 9042
TEST_TIME 103 9034
TEST_TIME 104 9055
TEST_TIME 105 9040
TEST_TIME 106 8964
TEST_TIME 107 9012
TEST_TIME 108 8984
TEST_TIME 109 8936
TEST_TIME 110 8884
TEST_TIME 111 8949
TEST_TIME 112 8936
TEST_TIME 113 8902
TEST_TIME 114 8876
TEST_TIME 115 8837
TEST_TIME 116 8803
TEST_TIME 117 8796
TEST_TIME 118 8839
TEST_TIME 119 8850
TEST_TIME 120 8821

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-08-2022 13:38:36 to 14-08-2022 13:38:39.

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 1469346 1469346 0 0.00 0 28638 12 0 0
doc_list_biud 1469346 1469346 0 0.00 0 49089 40 0 0
sp_add_invoice_to_stock 5003 4862 141 2.81 33 198711 24189 0 141
sp_cancel_adding_invoice 5364 5364 0 0.00 5 64487 1090 0 0
sp_cancel_client_order 1685 1685 0 0.00 7 11282 389 0 0
sp_cancel_customer_reserve 523919 523919 0 0.00 1 49091 96 0 0
sp_cancel_pay_from_customer 659 659 0 0.00 4 13356 164 0 0
sp_cancel_pay_to_supplier 406 406 0 0.00 3 3176 108 0 0
sp_cancel_supplier_invoice 5995 5982 13 0.21 2 20527 959 0 13
sp_cancel_supplier_order 399 399 0 0.00 7 1438547 159885 0 0
sp_cancel_write_off 9944 9944 0 0.00 2 24639 155 0 0
sp_client_order 19457 19457 0 0.00 6 46037 450 0 0
sp_customer_reserve 448548 448548 0 0.00 0 184149 232 0 0
sp_fill_shopping_cart 29986 29986 0 0.00 1 16690 77 0 0
sp_fill_shopping_cart_clo_res 448536 448536 0 0.00 0 18173 19 0 0
sp_get_clo_for_invoice 4812 4812 0 0.00 0 39183 359 0 0
sp_kill_cost_storno 16373 16373 0 0.00 0 16376 69 0 0
sp_kill_qstorno_ret_qs2qd 1064163 1064163 0 0.00 0 49081 43 0 0
sp_kill_qty_storno 561561 561561 0 0.00 0 49089 96 0 0
sp_lock_selected_doc 19919 19919 0 0.00 0 8519 6 0 0
sp_make_cost_storno 26721 26721 0 0.00 0 28628 76 0 0
sp_multiply_rows_for_pdistr 26721 26721 0 0.00 0 7547 34 0 0
sp_multiply_rows_for_qdistr 19457 19457 0 0.00 0 46026 164 0 0
sp_pay_from_customer 6783 6783 0 0.00 5 12350 169 0 0
sp_pay_to_supplier 5670 5670 0 0.00 6 19098 197 0 0
sp_payment_common 12453 12453 0 0.00 2 7103 40 0 0
sp_qd_handle_on_cancel_clo 1685 1685 0 0.00 1 11238 316 0 0
sp_qd_handle_on_invoice_upd_sts 10171 10171 0 0.00 0 34634 467 0 0
sp_qd_handle_on_reserve_upd_sts 9461 9461 0 0.00 0 19349 63 0 0
sp_reserve_write_off 9461 9461 0 0.00 8 19675 253 0 0
sp_supplier_invoice 5233 5233 0 0.00 8 76827 3202 0 0
sp_supplier_order 5298 5298 0 0.00 7 53752 1340 0 0
srv_aggregate_perf_data 101 101 0 0.00 2403 174297 18036 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 925 177941 40751 0 0
srv_find_qd_qs_mism 1008700 1008700 0 0.00 0 20281 9 0 0
srv_make_invnt_saldo 476 476 0 0.00 55 68912 3458 0 0
srv_make_money_saldo 847 847 0 0.00 1 15218 358 0 0
srv_recalc_idx_stat 4 4 0 0.00 62126 486635 293278 0 0
t$perf-abend:sp_add_invoice_to_stock 141 141 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 4862 4862 0 0.00 0 13642 707 0 0
t$perf-norm:sp_cancel_adding_invoice 415 415 0 0.00 1 7906 332 0 0
t$perf-norm:sp_cancel_client_order 1685 1685 0 0.00 0 28764 39 0 0
t$perf-norm:sp_cancel_customer_reserve 707 707 0 0.00 0 840 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 659 659 0 0.00 0 4037 19 0 0
t$perf-norm:sp_cancel_pay_to_supplier 406 406 0 0.00 0 10890 38 0 0
t$perf-norm:sp_cancel_supplier_invoice 404 404 0 0.00 0 631 29 0 0
t$perf-norm:sp_cancel_supplier_order 399 399 0 0.00 0 74126 5971 0 0
t$perf-norm:sp_cancel_write_off 976 976 0 0.00 0 501 12 0 0
t$perf-norm:sp_client_order 19457 19457 0 0.00 0 8542 30 0 0
t$perf-norm:sp_customer_reserve 930 930 0 0.00 0 4628 23 0 0
t$perf-norm:sp_pay_from_customer 6783 6783 0 0.00 0 28312 28 0 0
t$perf-norm:sp_pay_to_supplier 5670 5670 0 0.00 0 9807 18 0 0
t$perf-norm:sp_reserve_write_off 9461 9461 0 0.00 0 16597 23 0 0
t$perf-norm:sp_supplier_invoice 5233 5233 0 0.00 0 2564 23 0 0
t$perf-norm:sp_supplier_order 5298 5298 0 0.00 0 13982 26 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 223 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 58 3 0 0
t$perf-norm:srv_make_invnt_saldo 476 476 0 0.00 0 959 14 0 0
t$perf-norm:srv_make_money_saldo 847 847 0 0.00 0 669 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 31 203 80 0 0
v_add_invoice_to_stock 9896 9614 282 2.84 0 5509 24 0 282
v_all_customers 36184 36184 0 0.00 0 85 3 0 0
v_all_suppliers 29446 29446 0 0.00 0 77 4 0 0
v_all_wares 38912 38912 0 0.00 0 52 3 0 0
v_cancel_adding_invoice 830 830 0 0.00 0 479 11 0 0
v_cancel_client_order 3370 3370 0 0.00 0 5862 13 0 0
v_cancel_customer_prepayment 1318 1318 0 0.00 0 8056 24 0 0
v_cancel_customer_reserve 1414 1414 0 0.00 1 5207 14 0 0
v_cancel_payment_to_supplier 812 812 0 0.00 0 3144 17 0 0
v_cancel_supplier_invoice 834 808 26 3.11 0 1147 22 0 26
v_cancel_supplier_order 798 798 0 0.00 0 2080 15 0 0
v_cancel_write_off 1952 1952 0 0.00 0 3630 24 0 0
v_max_id_clo_ord 5298 5298 0 0.00 0 469 4 0 0
v_max_id_clo_res 930 930 0 0.00 0 3053 15 0 0
v_max_id_ord_sup 5232 5232 0 0.00 0 4657 7 0 0
v_max_non_paid_invoice 1477 1477 0 0.00 0 3332 9 0 0
v_max_non_paid_realizn 3416 3416 0 0.00 0 2127 6 0 0
v_min_id_clo_ord 5298 5298 0 0.00 0 730 6 0 0
v_min_id_clo_res 930 930 0 0.00 0 1872 10 0 0
v_min_id_ord_sup 5232 5232 0 0.00 0 8981 13 0 0
v_min_non_paid_invoice 5670 5670 0 0.00 0 6806 15 0 0
v_min_non_paid_realizn 6783 6783 0 0.00 0 4798 15 0 0
v_our_firm 9462 9462 0 0.00 0 61 4 0 0
v_reserve_write_off 18922 18922 0 0.00 0 5515 13 0 0
x_lock_depdocs_on_canc_invoice 10942 10942 0 0.00 0 16913 127 0 0
x_lock_depdocs_on_canc_sup_ord 399 399 0 0.00 0 8486 384 0 0
x_make_qstorno_1000_1200 5298 5298 0 0.00 2 53732 1203 0 0
x_make_qstorno_1200_2000 5233 5233 0 0.00 4 71050 2970 0 0
x_make_qstorno_2100_3300 448548 448548 0 0.00 0 184147 203 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-08-2022 13:38:40 to 14-08-2022 13:38:44.

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
11:07:22 2 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:24 26670 8192000000 9726166064 10025664512 803098288 8.928 100 87 174 0 832379552 7943904 21337360
11:09:55 22330 8192000000 9763764432 10070614016 837107728 9.271 100 86 170 0 869852528 12656960 20087840
11:11:28 11893 8192000000 9810885488 10135576576 874953344 9.649 100 86 172 0 924276112 29339984 19982784
11:12:43 10798 8192000000 9868367792 10208792576 924921600 10.145 100 78 154 0 982560672 41403920 16235152
11:13:56 2082 8192000000 9920798464 10262450176 964876400 10.537 100 93 182 1 1036899648 52906336 19116912
11:15:01 1455 8192000000 9962892048 10316652544 996941456 10.849 100 90 173 1 1086916912 72160112 17815344
11:16:04 2027 8192000000 9994617504 10358562816 1025144768 11.122 100 90 172 1 1108954032 66886176 16923088
11:17:08 892 8192000000 10036039520 10411601920 1041513440 11.279 100 96 181 2 1154502576 94711840 18277296
11:18:11 1379 8192000000 10000997552 10375294976 1057194528 11.430 100 93 178 1 1123811808 47832960 18784320
11:19:14 5673 8192000000 10051919088 10431668224 1063891936 11.494 100 81 156 1 1161974560 82943600 15139024
11:20:25 1070 8192000000 10016318400 10396082176 1069128688 11.544 100 84 151 1 1137704400 52039520 16536192
11:21:27 1085 8192000000 10057766688 10445021184 1076934496 11.618 100 97 187 1 1182269840 86951360 18383984
11:22:29 1036 8192000000 10033385408 10416914432 1079425616 11.642 100 98 180 0 1155248816 56877184 18946016
11:23:31 1186 8192000000 10039270560 10430062592 1082157408 11.668 100 84 157 2 1162163808 63573952 16432448
11:24:34 3031 8192000000 10013702224 10413760512 1090770912 11.750 100 73 136 6 1145389632 40803968 13814752
11:25:37 1220 8192000000 10068642224 10457358336 1090711840 11.749 100 95 184 0 1189784480 79880992 19191648
11:26:39 1453 8192000000 10060963856 10456018944 1093169344 11.773 100 91 170 1 1187326656 75921616 18235696
11:27:42 1083 8192000000 10052125440 10435203072 1077045552 11.619 100 94 188 1 1174327360 77768400 19513408
11:28:44 969 8192000000 10021972816 10409308160 1079562160 11.643 100 92 167 0 1143425744 44961408 18902176
11:29:47 1007 8192000000 10020407248 10403713024 1072924160 11.580 100 89 173 1 1141315808 50854448 17537200
11:30:51 1268 8192000000 10009113120 10391379968 1049635904 11.357 99 97 184 1 1133122464 64327136 19159424
11:31:53 1069 8192000000 10033996896 10414436352 1064788576 11.502 100 96 183 0 1153906160 69826304 19291280
11:32:55 1171 8192000000 9990287968 10375700480 1037823712 11.244 100 67 132 1 1113021488 60889936 14307840
11:33:58 970 8192000000 9928988672 10298961920 995527744 10.835 98 89 169 2 1060649152 43102656 22018752
11:35:00 1620 8192000000 9950781472 10323582976 1003674480 10.914 98 77 145 2 1060555936 41391712 15489744
11:36:03 8585 8192000000 10021376880 10405965824 1042661552 11.290 100 58 112 1 1141166496 87570672 10934272
11:37:12 1047 8192000000 10026634816 10401931264 1054442672 11.403 100 95 185 0 1145093696 71773760 18877264
11:38:21 6792 8192000000 10024751312 10412756992 1069670672 11.549 100 62 124 2 1154677248 72841280 12165296
11:39:34 1726 8192000000 10071056192 10449854464 1087307184 11.717 100 73 133 1 1187058336 86147360 13603792
11:40:40 19160 8192000000 10086730128 10478915584 1105380032 11.889 100 59 114 0 1195966480 78454016 12132432
11:42:03 1860 8192000000 10123320048 10510921728 1112006464 11.951 100 82 147 0 1240189936 111896992 16286480
11:43:14 2386 8192000000 10171728064 10565484544 1118412688 12.012 99 82 156 1 1285563680 150944752 16206240
11:44:21 1221 8192000000 10210825456 10606231552 1136413072 12.182 100 86 169 0 1328366816 173130112 18823632
11:45:26 2164 8192000000 10259372736 10654629888 1146091216 12.273 100 87 172 0 1368316752 203107392 19118144
11:46:33 990 8192000000 10299135056 10691260416 1154891936 12.355 100 94 177 1 1409255696 233470080 20893680
11:47:37 2740 8192000000 10348418720 10752163840 1163778496 12.439 100 82 162 0 1457863552 274967040 19118016
11:48:46 2164 8192000000 10371982656 10777219072 1169675744 12.494 100 84 160 1 1487145600 298595520 18874336
11:49:55 990 8192000000 10436896544 10838413312 1182109376 12.610 100 95 186 1 1543512560 338471616 22931568
11:51:00 1960 8192000000 10458304736 10864771072 1189480368 12.679 100 87 170 1 1564144976 354522016 20142592
11:52:05 921 8192000000 10482006192 10887540736 1195542816 12.735 100 95 186 0 1587567920 369954000 22071104
11:53:09 1189 8192000000 10536661072 10951413760 1192842448 12.710 100 93 182 0 1640532704 425477520 22212736
11:54:13 2553 8192000000 10486740112 10938970112 1184621056 12.633 99 93 177 0 1590510944 383511568 22378320
11:55:20 1060 8192000000 10549397840 10963841024 1198259408 12.760 100 96 189 3 1649232912 428269056 22704448
11:56:24 9750 8192000000 10491981136 10944024576 1185317536 12.640 99 47 90 1 1607152432 409709504 12125392
11:57:39 7538 8192000000 10526456368 10977660928 1182865088 12.617 100 44 88 0 1631791600 437582464 11344048
11:58:51 3655 8192000000 10561009792 10985144320 1186005776 12.646 100 64 128 0 1665989152 463609328 16374048
12:00:04 11987 8192000000 10598002256 11072856064 1180815280 12.598 100 53 106 1 1700058544 505034576 14208688
12:01:23 14833 8192000000 10478773024 10978091008 1166748784 12.466 99 44 87 1 1608885664 430875488 11261392
12:02:58 9311 8192000000 10377735040 10868318208 1141735584 12.232 99 35 70 0 1506331648 355631296 8964768
12:04:15 29291 8192000000 10280789264 10736062464 1135084880 12.169 99 25 50 0 1349623424 208656624 5881920
12:05:46 37303 8192000000 10213395088 10698461184 1135351488 12.172 99 19 37 0 1353607088 213784480 4471120
12:07:53 35922 8192000000 10090258288 10560380928 1128130656 12.104 99 17 32 0 1220021184 88443520 3447008
12:09:33 121928 8192000000 10071894720 10517606400 1105754512 11.892 97 40 78 0 1137303136 23326912 8221712
12:12:39 35863 8192000000 10065774896 10515722240 1109869136 11.931 98 32 57 0 1145855200 29573872 6412192
12:14:16 92522 8192000000 10110773280 10555768832 1131081680 12.132 98 23 43 0 1191853120 55636288 5135152
12:16:55 177941 8192000000 10124079872 10580402176 1105011808 11.885 96 11 22 0 1157082528 49824144 2246576
12:20:59 38618 8192000000 10127252352 10590658560 1137231296 12.189 99 10 20 0 1231093440 91262016 2600128
12:22:44 108746 8192000000 10106755952 10604892160 1145847456 12.271 100 32 64 1 1241440528 88925552 6667520
12:25:35 88236 8192000000 10073684800 10530590720 1119048464 12.018 97 16 32 0 1185302544 62579840 3674240
12:28:29 59179 8192000000 10068615680 10525519872 1108657536 11.920 97 11 22 0 1141644080 30513728 2472816
12:30:28 34679 8192000000 10086946704 10537209856 1126354640 12.087 99 16 31 0 1154729856 25068848 3306368
12:32:08 45931 8192000000 10060285232 10517213184 1099286000 11.831 98 23 45 0 1164776720 60929808 4560912
12:33:55 22087 8192000000 10099258896 10541367296 1128345696 12.106 100 23 46 0 1183512912 50604656 4562560
12:35:18 70789 8192000000 10076841792 10524852224 1116534960 11.994 98 20 40 0 1177355984 56887632 3933392
12:37:31 56911 8192000000 10074753232 10518589440 1064467552 11.499 94 24 48 0 1137786512 68358704 4960256
12:39:30 76308 8192000000 10091584592 10534457344 1113840976 11.969 99 17 31 0 1181504544 65126112 2537456
12:41:50 40692 8192000000 10122315104 10567491584 1115075712 11.980 98 27 54 0 1193154784 72955648 5123424
12:43:32 102649 8192000000 10115092304 10575945728 1116883872 11.998 98 36 70 0 1165415728 40755936 7775920
12:46:19 35231 8192000000 10038353984 10500657152 1112080208 11.952 98 15 30 0 1151296688 36001040 3215440
12:47:55 54465 8192000000 10051096272 10498129920 1120822448 12.035 99 14 27 0 1156284112 32840144 2621520
12:49:50 54734 8192000000 10068387344 10518405120 1126090368 12.084 99 41 80 0 1171780672 37110848 8579456
12:51:45 66818 8192000000 10098855296 10582638592 1116580576 11.995 98 10 19 0 1199818896 81249808 1988512
12:54:36 53653 8192000000 10096564688 10556710912 1110257104 11.935 98 25 50 0 1180519504 64964320 5298080
12:56:43 36002 8192000000 10111543296 10562060288 1096679664 11.806 96 25 50 0 1189834224 87353520 5801040
12:58:23 36849 8192000000 10155283120 10601590784 1136768528 12.185 100 32 64 0 1257152880 113102208 7282144
13:00:00 31046 8192000000 10189015456 10638143488 1131045856 12.131 99 34 66 0 1265193488 126889232 7258400
13:01:37 49235 8192000000 10199465040 10634805248 1118856272 12.016 98 31 62 0 1275846320 149835616 7154432
13:03:32 64852 8192000000 10105724016 10618265600 1098808000 11.826 97 27 54 1 1246716848 142061696 5847152
13:05:43 11480 8192000000 10090610240 10513367040 1128462928 12.107 100 79 156 2 1194170576 50478816 15228832
13:07:00 50270 8192000000 10079774464 10548899840 1128726960 12.109 99 29 58 0 1201556512 67016624 5812928
13:08:52 55720 8192000000 10108117008 10594533376 1132893840 12.149 99 12 22 0 1171571664 36583232 2094592
13:10:54 87778 8192000000 10068615040 10548592640 1104927040 11.884 97 9 18 0 1146780096 40140576 1712480
13:13:22 53747 8192000000 10070007088 10509942784 1104484112 11.880 97 24 46 1 1147991504 38824352 4683040
13:15:17 58004 8192000000 10080118480 10534121472 1081659408 11.663 95 26 51 0 1122916672 36362992 4894272
13:17:21 27960 8192000000 10105692256 10528415744 1099187248 11.830 97 46 88 0 1161772432 53424752 9160432
13:18:51 10784 8192000000 10093121424 10525073408 1115951632 11.989 99 43 86 0 1172796416 48467952 8376832
13:20:05 23016 8192000000 10087989136 10529169408 1127027424 12.093 100 29 56 0 1190209216 57096592 6085200
13:21:46 11001 8192000000 10102762736 10520338432 1115461520 11.984 99 64 128 4 1186295984 58261456 12573008
13:22:59 119698 8192000000 10104868992 10606120960 1119256448 12.020 100 39 75 0 1243579808 116477488 7845872
13:26:23 57883 8192000000 10106553488 10555490304 1093208608 11.773 97 14 28 0 1175191024 79101424 2880992
13:28:21 61016 8192000000 10042786784 10538713088 1079495168 11.643 97 15 30 0 1137177632 54554800 3127664
13:30:25 79155 8192000000 10077772976 10547105792 1121098208 12.037 99 18 36 0 1173183936 48215008 3870720
13:33:00 61726 8192000000 10134576816 10588102656 1122886608 12.054 99 23 46 1 1224972848 97139632 4946608
13:35:13 95403 8192000000 10066680320 10555478016 1103156416 11.868 98 16 32 0 1158477024 52183072 3137536

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 14-08-2022 13:38:46 to 14-08-2022 13:39:02.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 141 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 14-08-2022 13:39:03 to 14-08-2022 13:39:05.

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          218277
MON$OLDEST_ACTIVE               219045
MON$OLDEST_SNAPSHOT             219045
MON$NEXT_TRANSACTION            219045
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                       2033248
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             776
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2816 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2816 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2816 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2816 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 ---
:264: 
:265: mscs-test-161.budzdorov.ru    Sun Aug 14 13:37:22 2022
:266:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:267: 
--- end of diff output ---

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

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