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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 15900544 kB
MemAvailable 30821304 kB
Buffers 0 kB
Cached 15764656 kB
SwapCached 412 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.1
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 2021-09-14 07:37:05
DTS_END                         2021-09-14 07:37:05.2250
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/53034
TRN_ID                          236333
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: 9382.42 24872 1125891
customer order: creation 168.44 377 20213
customer order: refuse 13.53 329 1624
order to supplier: creation 46.12 1363 5534
order to supplier: removal 3.40 105820 408
invoice (draft): creation 45.18 3315 5422
invoice (draft): removal 54.37 896 6524
invoice accept: apply 41.63 17542 4995
invoice accept: cancel 48.92 807 5870
customer reserve: creation 3883.55 171 466026
customer reserve: removal 4784.62 58 574154
realization accept: apply 82.65 181 9918
realization accept: cancel 85.10 114 10212
payment from customer: creation 58.26 140 6991
payment from customer: removal 5.50 62 660
payment to supplier: creation 47.00 138 5640
payment to supplier: removal 3.22 91 386
service: total inventory turnovers 3.93 3291 471
service: total monetary turnovers 6.03 561 723
service: aggregate perf. data 0.97 13260 116
service: refresh index statistics 0.03 348932 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-09-2021 07:37:49 to 14-09-2021 07:37:51.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5945
TEST_TIME 2 4050
TEST_TIME 3 3935
TEST_TIME 4 3702
TEST_TIME 5 4625
TEST_TIME 6 4431
TEST_TIME 7 4930
TEST_TIME 8 5157
TEST_TIME 9 5197
TEST_TIME 10 5465
TEST_TIME 11 6138
TEST_TIME 12 6443
TEST_TIME 13 6608
TEST_TIME 14 6953
TEST_TIME 15 7486
TEST_TIME 16 7608
TEST_TIME 17 8356
TEST_TIME 18 8431
TEST_TIME 19 8492
TEST_TIME 20 8584
TEST_TIME 21 8523
TEST_TIME 22 8934
TEST_TIME 23 8830
TEST_TIME 24 9227
TEST_TIME 25 9572
TEST_TIME 26 9862
TEST_TIME 27 10610
TEST_TIME 28 10909
TEST_TIME 29 11049
TEST_TIME 30 10979
TEST_TIME 31 10784
TEST_TIME 32 10557
TEST_TIME 33 10473
TEST_TIME 34 10222
TEST_TIME 35 10125
TEST_TIME 36 10319
TEST_TIME 37 10245
TEST_TIME 38 10173
TEST_TIME 39 10168
TEST_TIME 40 10185
TEST_TIME 41 10190
TEST_TIME 42 10086
TEST_TIME 43 9982
TEST_TIME 44 9937
TEST_TIME 45 9762
TEST_TIME 46 9759
TEST_TIME 47 9667
TEST_TIME 48 9656
TEST_TIME 49 9693
TEST_TIME 50 9654
TEST_TIME 51 9747
TEST_TIME 52 9677
TEST_TIME 53 9718
TEST_TIME 54 9653
TEST_TIME 55 9778
TEST_TIME 56 9898
TEST_TIME 57 9979
TEST_TIME 58 9926
TEST_TIME 59 9944
TEST_TIME 60 9927
TEST_TIME 61 9977
TEST_TIME 62 9867
TEST_TIME 63 9816
TEST_TIME 64 9772
TEST_TIME 65 9660
TEST_TIME 66 9595
TEST_TIME 67 9611
TEST_TIME 68 9589
TEST_TIME 69 9595
TEST_TIME 70 9616
TEST_TIME 71 9592
TEST_TIME 72 9598
TEST_TIME 73 9620
TEST_TIME 74 9626
TEST_TIME 75 9615
TEST_TIME 76 9639
TEST_TIME 77 9648
TEST_TIME 78 9779
TEST_TIME 79 9749
TEST_TIME 80 9722
TEST_TIME 81 9632
TEST_TIME 82 9632
TEST_TIME 83 9615
TEST_TIME 84 9565
TEST_TIME 85 9537
TEST_TIME 86 9474
TEST_TIME 87 9425
TEST_TIME 88 9361
TEST_TIME 89 9351
TEST_TIME 90 9319
TEST_TIME 91 9309
TEST_TIME 92 9315
TEST_TIME 93 9352
TEST_TIME 94 9394
TEST_TIME 95 9374
TEST_TIME 96 9384
TEST_TIME 97 9387
TEST_TIME 98 9397
TEST_TIME 99 9418
TEST_TIME 100 9470
TEST_TIME 101 9474
TEST_TIME 102 9450
TEST_TIME 103 9508
TEST_TIME 104 9419
TEST_TIME 105 9387
TEST_TIME 106 9363
TEST_TIME 107 9295
TEST_TIME 108 9300
TEST_TIME 109 9322
TEST_TIME 110 9310
TEST_TIME 111 9282
TEST_TIME 112 9261
TEST_TIME 113 9263
TEST_TIME 114 9270
TEST_TIME 115 9240
TEST_TIME 116 9299
TEST_TIME 117 9414
TEST_TIME 118 9431
TEST_TIME 119 9461
TEST_TIME 120 9384

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 14-09-2021 07:37:52 to 14-09-2021 07:37:56.

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 1555850 1555850 0 0.00 0 28193 7 0 0
doc_list_biud 1555848 1555848 0 0.00 0 47786 28 0 0
sp_add_invoice_to_stock 5118 4995 123 2.40 33 169896 17543 0 123
sp_cancel_adding_invoice 5870 5870 0 0.00 5 60434 808 0 0
sp_cancel_client_order 1624 1624 0 0.00 5 7436 330 0 0
sp_cancel_customer_reserve 574154 574154 0 0.00 1 36423 58 0 0
sp_cancel_pay_from_customer 660 660 0 0.00 3 2027 62 0 0
sp_cancel_pay_to_supplier 386 386 0 0.00 3 4791 91 0 0
sp_cancel_supplier_invoice 6535 6524 11 0.16 2 38011 897 0 11
sp_cancel_supplier_order 408 408 0 0.00 6 844778 105821 0 0
sp_cancel_write_off 10212 10212 0 0.00 1 11624 115 0 0
sp_client_order 20213 20213 0 0.00 4 36562 378 0 0
sp_customer_reserve 466026 466026 0 0.00 0 105659 172 0 0
sp_fill_shopping_cart 31152 31152 0 0.00 1 19072 44 0 0
sp_fill_shopping_cart_clo_res 466019 466019 0 0.00 0 25346 15 0 0
sp_get_clo_for_invoice 4987 4987 0 0.00 0 24422 362 0 0
sp_kill_cost_storno 17128 17128 0 0.00 0 16110 57 0 0
sp_kill_qstorno_ret_qs2qd 1165452 1165452 0 0.00 0 37986 32 0 0
sp_kill_qty_storno 613696 613696 0 0.00 0 47786 69 0 0
sp_lock_selected_doc 20484 20484 0 0.00 0 9134 4 0 0
sp_make_cost_storno 27536 27536 0 0.00 0 12928 53 0 0
sp_multiply_rows_for_pdistr 27535 27535 0 0.00 0 28179 43 0 0
sp_multiply_rows_for_qdistr 20213 20213 0 0.00 0 22473 208 0 0
sp_pay_from_customer 6991 6991 0 0.00 4 28197 141 0 0
sp_pay_to_supplier 5640 5640 0 0.00 5 18307 138 0 0
sp_payment_common 12631 12631 0 0.00 1 18438 27 0 0
sp_qd_handle_on_cancel_clo 1624 1624 0 0.00 0 6527 298 0 0
sp_qd_handle_on_invoice_upd_sts 10856 10856 0 0.00 0 47785 350 0 0
sp_qd_handle_on_reserve_upd_sts 9918 9918 0 0.00 0 16749 50 0 0
sp_reserve_write_off 9918 9918 0 0.00 7 19099 182 0 0
sp_supplier_invoice 5422 5422 0 0.00 7 104608 3315 0 0
sp_supplier_order 5534 5534 0 0.00 5 71931 1364 0 0
srv_aggregate_perf_data 116 116 0 0.00 2799 145317 13261 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2121 128595 47292 0 0
srv_find_qd_qs_mism 1077942 1077942 0 0.00 0 13306 6 0 0
srv_make_invnt_saldo 471 471 0 0.00 34 111303 3291 0 0
srv_make_money_saldo 723 723 0 0.00 1 19809 562 0 0
srv_recalc_idx_stat 4 4 0 0.00 188983 476734 348932 0 0
t$perf-abend:sp_add_invoice_to_stock 123 123 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4995 4995 0 0.00 0 7418 151 0 0
t$perf-norm:sp_cancel_adding_invoice 424 424 0 0.00 0 2715 77 0 0
t$perf-norm:sp_cancel_client_order 1624 1624 0 0.00 0 3571 12 0 0
t$perf-norm:sp_cancel_customer_reserve 655 655 0 0.00 0 4177 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 660 660 0 0.00 0 563 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 386 386 0 0.00 0 3426 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 402 402 0 0.00 0 629 13 0 0
t$perf-norm:sp_cancel_supplier_order 408 408 0 0.00 0 18400 1576 0 0
t$perf-norm:sp_cancel_write_off 1021 1021 0 0.00 0 3262 13 0 0
t$perf-norm:sp_client_order 20213 20213 0 0.00 0 9691 16 0 0
t$perf-norm:sp_customer_reserve 1017 1017 0 0.00 0 4406 11 0 0
t$perf-norm:sp_pay_from_customer 6991 6991 0 0.00 0 5039 9 0 0
t$perf-norm:sp_pay_to_supplier 5640 5640 0 0.00 0 5411 12 0 0
t$perf-norm:sp_reserve_write_off 9918 9918 0 0.00 0 5697 10 0 0
t$perf-norm:sp_supplier_invoice 5422 5422 0 0.00 0 5437 14 0 0
t$perf-norm:sp_supplier_order 5534 5534 0 0.00 0 3978 12 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 1761 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 510 10 0 0
t$perf-norm:srv_make_invnt_saldo 471 471 0 0.00 0 1755 10 0 0
t$perf-norm:srv_make_money_saldo 723 723 0 0.00 0 2745 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 2 0 0
v_add_invoice_to_stock 10218 9972 246 2.40 0 9689 22 0 246
v_all_customers 37290 37290 0 0.00 0 26 1 0 0
v_all_suppliers 30256 30256 0 0.00 0 24 1 0 0
v_all_wares 40424 40424 0 0.00 0 113 1 0 0
v_cancel_adding_invoice 848 848 0 0.00 0 454 6 0 0
v_cancel_client_order 3248 3248 0 0.00 0 1835 4 0 0
v_cancel_customer_prepayment 1320 1320 0 0.00 0 2022 7 0 0
v_cancel_customer_reserve 1310 1310 0 0.00 0 521 3 0 0
v_cancel_payment_to_supplier 772 772 0 0.00 0 4779 12 0 0
v_cancel_supplier_invoice 826 804 22 2.66 0 1403 14 0 22
v_cancel_supplier_order 816 816 0 0.00 0 614 3 0 0
v_cancel_write_off 2042 2042 0 0.00 0 1524 13 0 0
v_max_id_clo_ord 5531 5531 0 0.00 0 464 1 0 0
v_max_id_clo_res 1017 1017 0 0.00 0 7257 21 0 0
v_max_id_ord_sup 5409 5409 0 0.00 0 9175 7 0 0
v_max_non_paid_invoice 1452 1452 0 0.00 0 130 2 0 0
v_max_non_paid_realizn 3581 3581 0 0.00 0 758 3 0 0
v_min_id_clo_ord 5531 5531 0 0.00 0 7150 12 0 0
v_min_id_clo_res 1017 1017 0 0.00 0 3225 14 0 0
v_min_id_ord_sup 5409 5409 0 0.00 0 10741 8 0 0
v_min_non_paid_invoice 5640 5640 0 0.00 0 18280 20 0 0
v_min_non_paid_realizn 6991 6991 0 0.00 0 9527 19 0 0
v_our_firm 9954 9954 0 0.00 0 23 1 0 0
v_reserve_write_off 19836 19836 0 0.00 0 11401 8 0 0
x_lock_depdocs_on_canc_invoice 11992 11992 0 0.00 0 18514 107 0 0
x_lock_depdocs_on_canc_sup_ord 408 408 0 0.00 0 21607 381 0 0
x_make_qstorno_1000_1200 5534 5534 0 0.00 2 71920 1277 0 0
x_make_qstorno_1200_2000 5422 5422 0 0.00 3 104577 3155 0 0
x_make_qstorno_2100_3300 466026 466026 0 0.00 0 105647 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-09-2021 07:37:57 to 14-09-2021 07:38:01.

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:09 4 8192000000 8798580928 8809734144 4478816 0.054 2 1 2 0 4578208 63616 35776
05:08:09 28847 8192000000 9718746480 10015526912 792753408 8.823 100 87 175 0 821372000 7291344 21327248
05:09:40 13067 8192000000 9751677520 10055745536 826761712 9.167 100 87 174 0 860034080 12258736 21013632
05:11:02 20815 8192000000 9792629360 10109054976 862243936 9.523 100 83 166 0 900828176 19479632 19104608
05:12:29 18601 8192000000 9867953328 10199359488 905660224 9.954 100 82 162 0 974000096 50327968 18011904
05:13:54 6564 8192000000 9915910208 10258624512 941979520 10.312 100 84 167 0 1034957568 75095264 17882784
05:15:03 4956 8192000000 9949827600 10299871232 984681600 10.730 100 80 160 1 1060861744 60571216 15608928
05:16:10 2960 8192000000 9979692816 10337562624 1015726480 11.031 100 83 165 1 1087581696 55416272 16438944
05:17:15 1703 8192000000 10008241936 10376863744 1046369840 11.326 100 85 167 1 1125407824 61603776 17434208
05:18:18 1349 8192000000 10047546864 10417233920 1065942512 11.513 100 92 184 0 1156768736 72697120 18129104
05:19:20 13766 8192000000 10018654736 10433060864 1068726896 11.540 100 26 52 0 1127110656 53402208 4981552
05:20:50 6878 8192000000 10001434880 10388770816 1075342624 11.603 100 37 72 2 1112478704 29826480 7309600
05:21:58 7726 8192000000 9998394528 10407198720 1083407440 11.680 100 36 70 0 1117268528 27048112 6812976
05:23:10 7374 8192000000 10007670592 10399141888 1092987200 11.771 100 30 60 0 1123844928 24114704 6743024
05:24:19 24331 8192000000 10021873408 10413236224 1096468272 11.804 100 67 134 2 1127209680 17239424 13501984
05:25:44 7373 8192000000 10034734032 10436182016 1102040272 11.857 100 43 85 1 1121004976 10035136 8929568
05:26:55 4347 8192000000 10014032304 10419781632 1090464704 11.747 100 40 79 1 1124991696 25765040 8761952
05:28:03 43599 8192000000 9959429424 10341515264 1029171696 11.160 97 26 53 1 1058240544 23431744 5637104
05:29:50 6275 8192000000 9960041152 10353659904 1027803280 11.147 98 44 88 3 1058645280 21522784 9319216
05:30:59 6868 8192000000 9928720592 10311172096 1000562832 10.884 97 32 67 0 1025123184 17360704 7199648
05:32:06 7619 8192000000 9929964704 10320805888 1026638240 11.136 100 28 56 2 1046698624 13025920 7034464
05:33:14 33243 8192000000 9949000336 10338828288 1029379936 11.162 99 35 69 2 1056034816 19084336 7570544
05:34:49 9020 8192000000 9955621712 10361716736 1049295728 11.354 100 33 64 2 1081970208 25000144 7674336
05:35:59 10190 8192000000 9978175408 10387595264 1060274736 11.459 100 37 74 0 1089062512 19448960 9338816
05:37:13 40597 8192000000 10008462384 10401366016 1087882480 11.723 100 44 87 2 1114813952 17653984 9277488
05:39:02 84766 8192000000 10077834144 10487357440 1115901808 11.988 100 36 72 1 1191569104 68053488 7613808
05:41:30 33219 8192000000 10121492816 10547200000 1126130528 12.085 100 39 78 2 1222145888 87100176 8915184
05:43:09 9336 8192000000 10128398064 10542055424 1139349360 12.209 100 52 103 0 1241650304 90868144 11432800
05:44:24 17411 8192000000 10199838368 10617393152 1146650576 12.278 100 55 109 1 1313086480 154712720 11723184
05:45:51 19823 8192000000 10170893872 10595172352 1153922304 12.346 100 46 91 1 1278545664 114436352 10187008
05:47:14 3390 8192000000 10233856288 10644938752 1163698160 12.438 100 65 131 0 1337321056 159081536 14541360
05:48:20 3741 8192000000 10280670512 10691690496 1161440736 12.417 99 62 124 0 1379874832 204593328 13840768
05:49:28 4638 8192000000 10271381856 10698387456 1173150832 12.526 100 55 108 0 1378920528 192906896 12862800
05:50:39 2118 8192000000 10337765184 10747461632 1176208416 12.555 100 89 173 1 1437739120 241504144 20026560
05:51:45 13000 8192000000 10317320656 10734301184 1135422864 12.172 99 62 124 0 1410269696 260550608 14296224
05:53:05 5442 8192000000 10298359184 10710949888 1145450208 12.267 99 76 151 2 1396136512 232839664 17846640
05:54:15 3174 8192000000 10329445744 10742685696 1139381024 12.210 99 77 152 1 1426679632 269644192 17654416
05:55:21 13719 8192000000 10298372144 10741551104 1143151440 12.245 100 33 64 1 1396914672 245889168 7874064
05:56:41 10373 8192000000 10300888352 10728194048 1133717840 12.156 100 36 72 1 1405851520 263448416 8685264
05:57:58 8510 8192000000 10316484480 10741727232 1138814576 12.204 100 35 70 0 1422282928 274430848 9037504
05:59:10 10048 8192000000 10363524768 10803122176 1120434448 12.031 98 35 70 0 1438618784 309555168 8629168
06:00:45 16574 8192000000 10360821728 10801377280 1114257184 11.973 99 38 76 0 1460257712 336332368 9668160
06:02:02 31500 8192000000 10320715376 10821001216 1128369520 12.106 100 23 46 2 1462846016 328131664 6344832
06:03:53 25889 8192000000 10278167008 10716209152 1109868576 11.931 99 20 40 0 1363395616 248250112 5276928
06:05:24 27645 8192000000 10073851840 10502643712 1113114432 11.962 99 30 59 2 1167633392 47792608 6726352
06:06:57 95758 8192000000 10064844240 10485559296 1067924672 11.532 95 18 36 0 1106624656 35111344 3588640
06:09:39 63217 8192000000 10131559792 10589478912 1135602384 12.174 100 26 53 2 1221296752 80297504 5396864
06:11:42 96493 8192000000 10141358976 10567667712 1116988048 11.999 98 34 68 0 1235486400 110503104 7995248
06:14:20 55923 8192000000 10105672160 10554777600 1128056992 12.103 98 23 47 2 1188499408 55235840 5206576
06:16:30 56173 8192000000 10111887088 10607546368 1141495584 12.230 100 12 24 2 1225267296 80904464 2867248
06:18:54 38012 8192000000 10093848192 10560892928 1154911184 12.356 100 14 28 0 1206137520 47936656 3289680
06:20:47 90342 8192000000 10113070176 10575089664 1143314032 12.247 99 20 38 2 1219115008 71356544 4444432
06:24:12 10390 8192000000 10136537840 10600673280 1159953408 12.403 100 30 60 1 1248206608 82329840 5923360
06:25:23 59044 8192000000 10112830544 10589597696 1147347840 12.285 99 12 24 0 1230577632 80736096 2493696
06:27:22 63883 8192000000 10078917776 10593406976 1142269872 12.237 99 15 30 0 1214274384 68935056 3069456
06:29:32 35075 8192000000 10132379408 10574254080 1142352192 12.238 99 34 66 2 1208090432 58930928 6807312
06:31:11 19549 8192000000 10140394544 10568982528 1156148368 12.367 100 46 92 0 1238156240 72735136 9272736
06:33:04 61294 8192000000 10131646416 10614108160 1163416336 12.435 100 14 28 0 1258517696 91670976 3430384
06:35:05 56062 8192000000 10104839840 10550837248 1123481632 12.060 97 30 60 0 1181153072 51607840 6063600
06:37:04 62117 8192000000 10129470528 10570014720 1112881248 11.960 96 30 60 0 1168422560 49917664 5623648
06:39:09 105148 8192000000 10083863520 10525810688 1109945728 11.932 96 29 58 0 1164516832 48335904 6235200
06:41:57 118915 8192000000 10132786672 10606866432 1135247776 12.171 98 10 20 1 1201644752 64297312 2099664
06:45:02 37148 8192000000 10119998336 10598404096 1147570048 12.287 100 10 20 0 1240464448 90850496 2043904
06:46:50 66099 8192000000 10099042144 10575130624 1123206736 12.057 97 26 52 0 1181237792 52401712 5629344
06:49:00 68563 8192000000 10056020944 10541441024 1060211152 11.459 93 18 36 0 1143795744 79500896 4083696
06:51:29 86875 8192000000 10077740480 10529886208 1071281824 11.564 95 21 42 1 1132148432 56457664 4408944
06:53:58 42540 8192000000 10122787024 10552918016 1086716224 11.711 97 34 68 0 1167326112 73841408 6768480
06:55:41 45547 8192000000 10088997408 10577932288 1098742960 11.826 98 8 16 0 1195934432 95473200 1718272
06:57:28 128595 8192000000 10077997040 10525597696 1098295888 11.821 98 27 53 1 1165741456 61370064 6075504
07:00:57 33128 8192000000 10041064640 10494500864 1096965104 11.809 98 9 18 2 1135059136 36377312 1716720
07:02:31 72823 8192000000 10022521280 10467291136 1088082624 11.724 97 12 24 1 1135559776 44874176 2602976
07:05:48 49166 8192000000 10053341952 10491072512 1089034224 11.733 98 16 32 1 1145656752 53287424 3335104
07:07:38 40145 8192000000 10096304224 10547077120 1132960928 12.149 100 24 48 0 1175952336 38111616 4879792
07:09:18 43254 8192000000 10108072368 10543943680 1120326704 12.030 98 26 52 2 1179455232 53765616 5362912
07:11:04 51921 8192000000 10053069248 10500055040 1107495936 11.909 98 17 34 0 1157912128 46551552 3864640
07:12:56 19465 8192000000 10093921840 10526502912 1136544256 12.183 100 38 76 0 1190372080 46663376 7164448
07:14:18 28800 8192000000 10089516592 10519121920 1134101456 12.160 100 45 90 0 1186450416 43478320 8870640
07:15:48 109486 8192000000 10079778080 10549391360 1092615888 11.768 95 25 50 0 1163095888 65702640 4777360
07:18:44 45946 8192000000 10098978016 10543722496 1142364336 12.238 100 22 43 2 1198841904 51302480 5175088
07:20:34 110289 8192000000 10059043408 10506539008 1130630288 12.127 99 17 32 0 1160182288 25842080 3709920
07:23:25 65596 8192000000 10117877136 10604171264 1149364736 12.304 100 4 8 0 1196147248 45947344 835168
07:25:31 94663 8192000000 10098453520 10601172992 1131613664 12.137 98 17 33 0 1218927312 83892224 3421424
07:28:10 57957 8192000000 10107515200 10566184960 1118034320 12.008 97 24 48 0 1157777840 34926480 4817040
07:30:36 82619 8192000000 10170708352 10654314496 1127731376 12.100 98 24 48 1 1266013312 132241040 6040896
07:32:58 56182 8192000000 10164473936 10638462976 1126140672 12.085 99 21 42 1 1242649344 111648928 4859744
07:34:56 57928 8192000000 10102503568 10554261504 1094828960 11.789 96 22 44 0 1132106192 32574336 4702896
07:37:02 12287 8192000000 8852744752 9041813504 3288160 0.040 1 1 2 0 3391680 68112 35408

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 14-09-2021 07:38:02 to 14-09-2021 07:38:17.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 123 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-09-2021 07:38:18 to 14-09-2021 07:38:20.

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          236876
MON$OLDEST_ACTIVE               236877
MON$OLDEST_SNAPSHOT             236877
MON$NEXT_TRANSACTION            236877
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                       2059104
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             799
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

14.09.21 07:38:21. 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
2021-09-14 07:38:23 No severe PSQL-related problems occured
14.09.21 07:38:24. End of report.