Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.07.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 15297852 kB
MemAvailable 31026932 kB
Buffers 0 kB
Cached 16363824 kB
SwapCached 2500 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.2
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-07-21 13:37:17
DTS_END                         2022-07-21 13:37:17.2550
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/35192
TRN_ID                          219382
ATT_ID                          617
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: 8656.31 24152 1038757
customer order: creation 170.48 568 20457
customer order: refuse 14.42 492 1730
order to supplier: creation 45.12 1587 5414
order to supplier: removal 3.31 197302 397
invoice (draft): creation 44.78 3909 5373
invoice (draft): removal 47.24 1315 5669
invoice accept: apply 40.33 28591 4840
invoice accept: cancel 42.30 1508 5076
customer reserve: creation 3788.62 265 454634
customer reserve: removal 4173.94 119 500873
realization accept: apply 82.41 354 9889
realization accept: cancel 76.15 214 9138
payment from customer: creation 58.39 201 7007
payment from customer: removal 5.23 184 627
payment to supplier: creation 48.18 230 5782
payment to supplier: removal 3.15 265 378
service: total inventory turnovers 3.59 5354 431
service: total monetary turnovers 7.76 428 931
service: aggregate perf. data 0.89 17336 107
service: refresh index statistics 0.03 222816 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-07-2022 13:38:35 to 21-07-2022 13:38:37.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9292
TEST_TIME 2 7306
TEST_TIME 3 7389
TEST_TIME 4 6437
TEST_TIME 5 6169
TEST_TIME 6 5876
TEST_TIME 7 6276
TEST_TIME 8 6836
TEST_TIME 9 6844
TEST_TIME 10 6808
TEST_TIME 11 7288
TEST_TIME 12 7293
TEST_TIME 13 7249
TEST_TIME 14 7452
TEST_TIME 15 7410
TEST_TIME 16 7368
TEST_TIME 17 7325
TEST_TIME 18 7598
TEST_TIME 19 7779
TEST_TIME 20 8435
TEST_TIME 21 8251
TEST_TIME 22 8159
TEST_TIME 23 8115
TEST_TIME 24 8170
TEST_TIME 25 8126
TEST_TIME 26 8209
TEST_TIME 27 8329
TEST_TIME 28 8533
TEST_TIME 29 8454
TEST_TIME 30 8525
TEST_TIME 31 8621
TEST_TIME 32 8910
TEST_TIME 33 9023
TEST_TIME 34 9058
TEST_TIME 35 9892
TEST_TIME 36 10046
TEST_TIME 37 10204
TEST_TIME 38 10187
TEST_TIME 39 10372
TEST_TIME 40 10773
TEST_TIME 41 10652
TEST_TIME 42 10444
TEST_TIME 43 10329
TEST_TIME 44 10314
TEST_TIME 45 10324
TEST_TIME 46 10191
TEST_TIME 47 10174
TEST_TIME 48 10182
TEST_TIME 49 10072
TEST_TIME 50 9916
TEST_TIME 51 9858
TEST_TIME 52 9725
TEST_TIME 53 9594
TEST_TIME 54 9597
TEST_TIME 55 9556
TEST_TIME 56 9459
TEST_TIME 57 9425
TEST_TIME 58 9365
TEST_TIME 59 9361
TEST_TIME 60 9228
TEST_TIME 61 9282
TEST_TIME 62 9355
TEST_TIME 63 9329
TEST_TIME 64 9226
TEST_TIME 65 9181
TEST_TIME 66 9110
TEST_TIME 67 9136
TEST_TIME 68 9135
TEST_TIME 69 9136
TEST_TIME 70 9178
TEST_TIME 71 9101
TEST_TIME 72 9115
TEST_TIME 73 9148
TEST_TIME 74 9138
TEST_TIME 75 9196
TEST_TIME 76 9190
TEST_TIME 77 9278
TEST_TIME 78 9234
TEST_TIME 79 9145
TEST_TIME 80 9080
TEST_TIME 81 9037
TEST_TIME 82 8984
TEST_TIME 83 8950
TEST_TIME 84 8923
TEST_TIME 85 8881
TEST_TIME 86 8884
TEST_TIME 87 8850
TEST_TIME 88 8814
TEST_TIME 89 8841
TEST_TIME 90 8808
TEST_TIME 91 8789
TEST_TIME 92 8889
TEST_TIME 93 8851
TEST_TIME 94 8851
TEST_TIME 95 8827
TEST_TIME 96 8857
TEST_TIME 97 8947
TEST_TIME 98 8976
TEST_TIME 99 9067
TEST_TIME 100 9024
TEST_TIME 101 9035
TEST_TIME 102 9037
TEST_TIME 103 9016
TEST_TIME 104 8952
TEST_TIME 105 8918
TEST_TIME 106 8919
TEST_TIME 107 8877
TEST_TIME 108 8832
TEST_TIME 109 8812
TEST_TIME 110 8781
TEST_TIME 111 8714
TEST_TIME 112 8720
TEST_TIME 113 8735
TEST_TIME 114 8762
TEST_TIME 115 8773
TEST_TIME 116 8742
TEST_TIME 117 8733
TEST_TIME 118 8729
TEST_TIME 119 8726
TEST_TIME 120 8680

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-07-2022 13:38:39 to 21-07-2022 13:38:42.

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 1457762 1457762 0 0.00 0 44800 15 0 0
doc_list_biud 1457754 1457754 0 0.00 0 50917 49 0 0
sp_add_invoice_to_stock 4980 4840 140 2.81 60 216199 28592 0 140
sp_cancel_adding_invoice 5076 5076 0 0.00 6 76934 1508 0 0
sp_cancel_client_order 1730 1730 0 0.00 6 10385 493 0 0
sp_cancel_customer_reserve 500873 500873 0 0.00 1 50347 119 0 0
sp_cancel_pay_from_customer 627 627 0 0.00 4 7232 185 0 0
sp_cancel_pay_to_supplier 378 378 0 0.00 4 36543 265 0 0
sp_cancel_supplier_invoice 5683 5669 14 0.24 2 50925 1315 0 14
sp_cancel_supplier_order 397 397 0 0.00 8 1409335 197303 0 0
sp_cancel_write_off 9138 9138 0 0.00 2 44090 214 0 0
sp_client_order 20457 20457 0 0.00 5 50818 568 0 0
sp_customer_reserve 454634 454634 0 0.00 0 94504 265 0 0
sp_fill_shopping_cart 31232 31232 0 0.00 1 22749 99 0 0
sp_fill_shopping_cart_clo_res 454607 454607 0 0.00 0 21241 21 0 0
sp_get_clo_for_invoice 4803 4803 0 0.00 0 31487 343 0 0
sp_kill_cost_storno 15219 15219 0 0.00 0 15067 100 0 0
sp_kill_qstorno_ret_qs2qd 1016950 1016950 0 0.00 0 50915 52 0 0
sp_kill_qty_storno 537573 537573 0 0.00 0 50917 122 0 0
sp_lock_selected_doc 20242 20242 0 0.00 0 12334 10 0 0
sp_make_cost_storno 27481 27481 0 0.00 0 44789 87 0 0
sp_multiply_rows_for_pdistr 27480 27480 0 0.00 0 7305 41 0 0
sp_multiply_rows_for_qdistr 20457 20457 0 0.00 0 25230 204 0 0
sp_pay_from_customer 7007 7007 0 0.00 5 11352 202 0 0
sp_pay_to_supplier 5782 5782 0 0.00 6 44813 231 0 0
sp_payment_common 12789 12789 0 0.00 2 9220 53 0 0
sp_qd_handle_on_cancel_clo 1730 1730 0 0.00 1 10358 387 0 0
sp_qd_handle_on_invoice_upd_sts 9877 9877 0 0.00 0 36875 682 0 0
sp_qd_handle_on_reserve_upd_sts 9889 9889 0 0.00 0 36656 104 0 0
sp_reserve_write_off 9889 9889 0 0.00 8 36877 354 0 0
sp_supplier_invoice 5373 5373 0 0.00 10 119686 3910 0 0
sp_supplier_order 5414 5414 0 0.00 7 63186 1588 0 0
srv_aggregate_perf_data 107 107 0 0.00 2046 290655 17336 0 0
srv_fill_mon_cache_memory 73 73 0 0.00 703 106779 33530 0 0
srv_find_qd_qs_mism 990779 990779 0 0.00 0 36914 11 0 0
srv_make_invnt_saldo 431 431 0 0.00 41 135234 5354 0 0
srv_make_money_saldo 931 931 0 0.00 1 10740 429 0 0
srv_recalc_idx_stat 4 4 0 0.00 82224 376667 222817 0 0
t$perf-abend:sp_add_invoice_to_stock 140 140 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4849 4849 0 0.00 1 10930 1132 0 0
t$perf-norm:sp_cancel_adding_invoice 373 373 0 0.00 1 6827 514 0 0
t$perf-norm:sp_cancel_client_order 1730 1730 0 0.00 0 3076 31 0 0
t$perf-norm:sp_cancel_customer_reserve 673 673 0 0.00 0 2854 36 0 0
t$perf-norm:sp_cancel_pay_from_customer 627 627 0 0.00 0 2211 25 0 0
t$perf-norm:sp_cancel_pay_to_supplier 378 378 0 0.00 0 915 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 392 392 0 0.00 0 1598 42 0 0
t$perf-norm:sp_cancel_supplier_order 397 397 0 0.00 0 104013 9791 0 0
t$perf-norm:sp_cancel_write_off 986 986 0 0.00 0 3302 29 0 0
t$perf-norm:sp_client_order 20459 20459 0 0.00 0 6136 42 0 0
t$perf-norm:sp_customer_reserve 974 974 0 0.00 0 662 17 0 0
t$perf-norm:sp_pay_from_customer 7007 7007 0 0.00 0 5149 23 0 0
t$perf-norm:sp_pay_to_supplier 5783 5783 0 0.00 0 9233 24 0 0
t$perf-norm:sp_reserve_write_off 9889 9889 0 0.00 0 7098 28 0 0
t$perf-norm:sp_supplier_invoice 5373 5373 0 0.00 0 3323 37 0 0
t$perf-norm:sp_supplier_order 5414 5414 0 0.00 0 4106 38 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 420 18 0 0
t$perf-norm:srv_fill_mon_cache_memory 73 73 0 0.00 0 23 4 0 0
t$perf-norm:srv_make_invnt_saldo 431 431 0 0.00 0 1634 29 0 0
t$perf-norm:srv_make_money_saldo 931 931 0 0.00 0 243 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 8 122 45 0 0
v_add_invoice_to_stock 9874 9594 280 2.83 0 36626 43 0 280
v_all_customers 37584 37584 0 0.00 0 390 4 0 0
v_all_suppliers 30304 30304 0 0.00 0 139 5 0 0
v_all_wares 40906 40906 0 0.00 0 125 4 0 0
v_cancel_adding_invoice 746 746 0 0.00 0 1573 18 0 0
v_cancel_client_order 3460 3460 0 0.00 0 8307 20 0 0
v_cancel_customer_prepayment 1254 1254 0 0.00 0 5547 23 0 0
v_cancel_customer_reserve 1346 1346 0 0.00 1 2081 13 0 0
v_cancel_payment_to_supplier 756 756 0 0.00 0 36406 73 0 0
v_cancel_supplier_invoice 812 784 28 3.44 0 7257 48 0 28
v_cancel_supplier_order 794 794 0 0.00 0 7035 27 0 0
v_cancel_write_off 1972 1972 0 0.00 0 2897 31 0 0
v_max_id_clo_ord 5413 5413 0 0.00 0 230 6 0 0
v_max_id_clo_res 974 974 0 0.00 0 4077 24 0 0
v_max_id_ord_sup 5366 5366 0 0.00 0 5509 10 0 0
v_max_non_paid_invoice 1406 1406 0 0.00 0 675 9 0 0
v_max_non_paid_realizn 3514 3514 0 0.00 0 1909 10 0 0
v_min_id_clo_ord 5411 5411 0 0.00 0 3380 10 0 0
v_min_id_clo_res 974 974 0 0.00 0 107 8 0 0
v_min_id_ord_sup 5366 5366 0 0.00 0 7580 9 0 0
v_min_non_paid_invoice 5780 5780 0 0.00 0 7029 17 0 0
v_min_non_paid_realizn 7007 7007 0 0.00 0 8289 20 0 0
v_our_firm 10306 10306 0 0.00 0 63 5 0 0
v_reserve_write_off 19776 19776 0 0.00 0 7914 16 0 0
x_lock_depdocs_on_canc_invoice 10353 10353 0 0.00 0 17302 151 0 0
x_lock_depdocs_on_canc_sup_ord 397 397 0 0.00 0 11655 503 0 0
x_make_qstorno_1000_1200 5414 5414 0 0.00 3 51606 1384 0 0
x_make_qstorno_1200_2000 5373 5373 0 0.00 5 119367 3630 0 0
x_make_qstorno_2100_3300 454633 454633 0 0.00 0 94498 229 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-07-2022 13:38:43 to 21-07-2022 13:38:47.

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:20 1 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:22 18881 8192000000 9713532432 10010603520 801494000 8.911 100 90 180 0 830600384 7533552 21572832
11:09:45 22545 8192000000 9759649280 10065457152 829092320 9.190 100 83 166 0 860683520 11804352 19786848
11:11:13 21949 8192000000 9791187536 10107326464 858315232 9.483 100 88 176 0 897911984 19148784 20447968
11:12:43 7197 8192000000 9855634224 10185195520 910489344 10.002 100 82 162 0 967866672 39664016 17713312
11:13:53 4141 8192000000 9893283040 10235420672 939941504 10.292 100 89 174 0 1017431008 58811216 18678288
11:15:01 1548 8192000000 9940441408 10288455680 983183168 10.715 100 93 180 1 1055161296 53115584 18862544
11:16:05 1203 8192000000 9981127888 10337808384 1005332960 10.930 100 86 164 0 1100806160 79084048 16389152
11:17:09 2960 8192000000 9983068768 10348765184 1027282016 11.142 100 87 168 0 1108185536 64044112 16859408
11:18:14 995 8192000000 10032598560 10405072896 1056730768 11.425 100 97 185 0 1148828304 74365504 17732032
11:19:16 927 8192000000 10036647120 10416115712 1062037936 11.476 100 91 174 0 1154240368 74954896 17247536
11:20:19 1051 8192000000 10060822256 10442395648 1067598240 11.529 100 92 182 0 1182401008 97093232 17709536
11:21:22 872 8192000000 10053681408 10435112960 1076143408 11.611 100 89 176 0 1180100016 85825232 18131376
11:22:24 4639 8192000000 10044985440 10426064896 1080353088 11.651 100 82 148 2 1164542752 69895936 14293728
11:23:30 3589 8192000000 10038545520 10424987648 1080518800 11.652 100 81 154 2 1160206624 65504592 14183232
11:24:34 1077 8192000000 10062670256 10449080320 1085667648 11.701 100 98 181 2 1186407840 81865776 18874416
11:25:36 3380 8192000000 10038665824 10429337600 1086746080 11.712 100 72 140 3 1158426016 58050896 13629040
11:26:41 5009 8192000000 10039451840 10437148672 1091726304 11.759 100 67 131 1 1165080368 60771120 12582944
11:27:48 989 8192000000 10055115728 10446561280 1098219328 11.821 100 92 179 2 1177899280 59285840 20394112
11:28:49 836 8192000000 10021002272 10405629952 1077033408 11.619 100 90 166 4 1142924416 47828256 18062752
11:29:51 1100 8192000000 10048022512 10433912832 1075883728 11.608 99 82 162 0 1169085920 76377408 16824784
11:30:54 978 8192000000 10026478848 10411266048 1072153936 11.573 100 90 170 1 1143921504 55263232 16504336
11:31:56 3241 8192000000 10031829472 10413338624 1057273008 11.430 100 79 149 5 1159246320 87779248 14194064
11:33:00 870 8192000000 9989113728 10367807488 1045580480 11.318 100 83 147 0 1109651472 48869648 15201344
11:34:02 2172 8192000000 9993381152 10372141056 1034635760 11.213 100 82 150 1 1114976416 64099504 16241152
11:35:06 1171 8192000000 9980869280 10355224576 1034165456 11.209 99 91 177 3 1097213808 44782352 18266000
11:36:09 956 8192000000 9993876176 10364952576 1037253008 11.238 99 93 178 1 1119808320 64007152 18548160
11:37:11 1240 8192000000 9998217504 10380734464 1050645904 11.367 100 78 147 4 1125947584 59465472 15836208
11:38:21 1959 8192000000 10025179920 10409009152 1063094800 11.486 100 84 151 7 1144265680 66303264 14867616
11:39:28 4320 8192000000 10040075648 10425823232 1081216336 11.659 100 71 132 1 1156410400 62467248 12726816
11:40:38 2056 8192000000 10057394512 10443735040 1100852704 11.846 100 74 143 1 1177739360 61841392 15045264
11:41:44 15379 8192000000 10118157392 10516152320 1117407824 12.002 100 63 126 0 1233856912 102945072 13504016
11:43:13 17151 8192000000 10160217456 10562019328 1126580096 12.089 100 65 126 0 1275465200 135244640 13640464
11:44:40 1339 8192000000 10214789168 10611154944 1140518288 12.220 100 92 177 1 1323897120 164446320 18932512
11:45:45 2006 8192000000 10214221360 10611216384 1144628608 12.259 100 79 150 2 1320102880 158862880 16611392
11:46:50 2191 8192000000 10240839472 10640498688 1148437136 12.295 100 86 163 0 1353718176 188070496 17210544
11:47:57 762 8192000000 10239387456 10651942912 1157878688 12.383 100 98 191 3 1353947152 173508208 22560256
11:49:01 1393 8192000000 10270028720 10669477888 1158877552 12.393 100 93 176 1 1381541808 203451776 19212480
11:50:08 699 8192000000 10294259936 10696826880 1172503952 12.520 100 93 173 2 1400892032 208436272 19951808
11:51:11 961 8192000000 10331179072 10748776448 1175842672 12.551 100 96 187 0 1440424176 243869296 20712208
11:52:14 1677 8192000000 10354247552 10763096064 1167257824 12.471 100 93 183 0 1460566976 272250048 21059104
11:53:20 3901 8192000000 10338415056 10746400768 1162596768 12.428 99 79 154 2 1448847216 268456208 17794240
11:54:30 1651 8192000000 10393951712 10815004672 1178992176 12.581 100 96 187 1 1507378848 305172992 23213680
11:55:35 1047 8192000000 10360332848 10819227648 1173927472 12.534 100 96 189 2 1467208464 271371536 21909456
11:56:39 900 8192000000 10326412496 10793791488 1163723280 12.438 99 91 176 2 1432184240 248408944 20052016
11:57:44 2947 8192000000 10327943696 10743078912 1173948736 12.534 100 76 146 0 1441407408 250701600 16757072
11:58:50 1738 8192000000 10399642416 10806435840 1192375920 12.705 100 90 177 1 1501177232 287540448 21260864
11:59:56 905 8192000000 10386811728 10793000960 1161015632 12.413 98 91 173 0 1489116832 306780768 21320432
12:01:00 1287 8192000000 10433752432 10854162432 1181354768 12.603 100 99 190 1 1539766208 335255952 23155488
12:02:07 1123 8192000000 10485627808 10902376448 1189084992 12.675 100 99 193 0 1589258704 377754352 22419360
12:03:11 1152 8192000000 10520934560 10938843136 1175836992 12.551 100 98 190 0 1623701600 424177616 23686992
12:04:14 758 8192000000 10545853680 10980270080 1175715456 12.550 100 98 189 1 1649282288 449932176 23634656
12:05:17 7478 8192000000 10555434400 11008028672 1187581920 12.661 100 60 120 1 1665748064 462427376 15738768
12:06:43 2358 8192000000 10564263632 11005566976 1189687584 12.680 100 64 126 1 1667748576 461617984 16443008
12:07:52 4128 8192000000 10622960608 11047587840 1190500400 12.688 100 66 132 0 1727238752 520367472 16370880
12:09:01 5431 8192000000 10588290208 11061256192 1193241824 12.714 100 57 114 1 1686878928 479208368 14428736
12:10:16 6393 8192000000 10518250880 10982129664 1187114608 12.657 100 44 85 1 1626999504 428923616 10961280
12:11:25 6910 8192000000 10497688576 10991742976 1165520000 12.455 99 61 107 0 1596902672 418175104 13207568
12:12:35 22930 8192000000 10369164496 10873057280 1165341984 12.453 99 37 75 1 1469444000 295061760 9040256
12:14:03 31410 8192000000 10300949648 10763702272 1174330080 12.537 100 28 55 1 1405978736 224465360 7183296
12:15:36 11965 8192000000 10318242624 10792431616 1170744640 12.504 100 41 78 1 1418680512 238892032 9043840
12:16:50 1491 8192000000 10205006944 10758750208 1168061744 12.479 100 70 138 0 1302529152 119975120 14492288
12:17:53 106775 8192000000 10188354352 10630643712 1163118368 12.432 100 62 116 0 1289028272 111813008 14096896
12:20:45 65539 8192000000 10173707680 10632437760 1165758528 12.457 100 23 42 0 1278112352 107575424 4778400
12:22:53 75824 8192000000 10166747536 10621693952 1156963152 12.375 99 39 72 1 1260212112 95307952 7941008
12:25:13 74227 8192000000 10069080496 10521636864 1104246304 11.878 95 23 46 0 1128246272 18754112 5245856
12:27:41 93052 8192000000 10094093952 10539646976 1097167520 11.811 95 19 37 0 1142142912 40725920 4249472
12:30:41 94287 8192000000 10137210656 10613706752 1140923952 12.224 99 33 65 0 1228193584 79825264 7444368
12:33:25 73023 8192000000 10157197168 10609692672 1137997296 12.197 99 34 67 0 1235481408 89569584 7914528
12:35:44 77127 8192000000 10123390528 10578055168 1134443584 12.163 99 31 62 0 1205856416 64823520 6589312
12:38:06 11193 8192000000 10158600432 10634379264 1158644976 12.391 100 52 102 0 1258051040 88463200 10942864
12:39:24 54564 8192000000 10078992864 10573725696 1128027104 12.103 98 14 28 0 1213011632 82098688 2885840
12:41:20 78403 8192000000 10070221568 10546520064 1134018032 12.159 99 6 12 0 1181631024 46314528 1298464
12:43:41 34926 8192000000 10102655808 10561572864 1131687296 12.137 99 30 60 0 1196057120 58267856 6101968
12:45:23 46025 8192000000 10080574816 10531528704 1108205808 11.915 97 25 50 0 1149311136 35445728 5659600
12:47:11 83551 8192000000 10081774656 10516967424 1070286592 11.555 94 26 50 1 1146881776 71106512 5488672
12:49:35 27473 8192000000 10094426512 10556116992 1102935600 11.865 98 25 50 0 1158898336 50337808 5624928
12:51:04 54586 8192000000 10123900656 10583465984 1117400720 12.002 99 31 61 0 1221969088 97931280 6637088
12:53:03 61406 8192000000 10126279040 10590720000 1099463568 11.833 98 31 62 0 1192556576 86136576 6956432
12:55:05 25488 8192000000 10066677552 10517733376 1100632528 11.844 98 23 46 0 1154267504 48839136 4795840
12:56:31 84702 8192000000 10070045152 10503966720 1108120032 11.915 99 55 103 1 1167821392 48973056 10728304
12:59:00 22351 8192000000 10076221168 10521534464 1119310512 12.020 100 44 77 1 1166639184 39612288 7716384
13:00:23 23364 8192000000 10088347312 10540924928 1117760240 12.006 99 45 90 1 1201300080 73758528 9781312
13:01:48 54220 8192000000 10096323616 10540597248 1082566528 11.672 96 21 42 0 1158825072 71988592 4269952
13:03:43 80707 8192000000 10099896656 10575290368 1099893952 11.837 97 15 30 0 1194707376 91463472 3349952
13:06:24 35324 8192000000 10087348688 10538696704 1126923248 12.092 100 21 41 0 1208784672 77399904 4461520
13:08:09 65801 8192000000 10146021648 10641301504 1137622880 12.193 99 24 47 0 1256035680 113566192 4846608
13:10:17 43177 8192000000 10145433232 10586882048 1155731472 12.363 100 31 59 0 1227131920 65455712 5944736
13:12:05 36808 8192000000 10186080976 10629070848 1149233120 12.302 99 23 46 0 1258726512 104247328 5246064
13:13:44 73188 8192000000 10160670816 10685566976 1120622448 12.033 96 21 42 0 1263499584 137805792 5071344
13:16:00 73448 8192000000 10084514512 10580353024 1127814848 12.101 98 16 32 0 1211055824 80095136 3145840
13:18:17 14770 8192000000 10120985248 10571804672 1143880096 12.252 100 52 104 0 1201388368 46939952 10568320
13:19:35 78468 8192000000 10114661424 10621390848 1135300336 12.171 99 23 46 1 1211250272 71412816 4537120
13:22:21 45564 8192000000 10081670544 10540212224 1144900864 12.262 100 32 59 1 1188986304 37448816 6636624
13:24:08 70101 8192000000 10072226240 10542592000 1146285248 12.275 100 13 26 0 1187670976 38848576 2537152
13:26:23 52065 8192000000 10106186512 10566832128 1141889280 12.233 100 19 38 0 1211942464 65753888 4299296
13:28:16 99677 8192000000 10135124608 10624004096 1150327088 12.313 100 17 33 0 1250930656 96849584 3753984
13:31:01 64991 8192000000 10082753024 10531495936 1133864640 12.158 99 17 35 0 1162990832 25106944 4019248
13:33:25 44296 8192000000 10078855632 10513948672 1112095808 11.952 97 44 84 0 1151109808 30230000 8784000
13:35:13 50990 8192000000 10117577168 10576240640 1110388064 11.936 99 35 69 0 1180771760 63420288 6963408
13:37:08 26018 8192000000 9046804640 9338855424 192199664 2.292 18 3 6 0 248943008 56150032 593312

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 21-07-2022 13:38:49 to 21-07-2022 13:39:05.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 140 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-07-2022 13:39:06 to 21-07-2022 13:39:08.

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          219655
MON$OLDEST_ACTIVE               219656
MON$OLDEST_SNAPSHOT             219656
MON$NEXT_TRANSACTION            219656
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                       2124160
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             772
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

21.07.22 13:39:09. 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-07-21 13:39:12 No severe PSQL-related problems occured
21.07.22 13:39:13. End of report.