Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.03.2021 04:27
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 df9f488d08424df294c459d6c283e9c3
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-03-04 16:07
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.26
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 16960076 kB
MemAvailable 31496388 kB
Buffers 20 kB
Cached 14615576 kB
SwapCached 0 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.0
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-03-16 04:26:55
DTS_END                         2021-03-16 04:26:55.9190
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/39292
TRN_ID                          224904
ATT_ID                          630
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: 8603.85 26123 1032462
customer order: creation 158.31 434 18997
customer order: refuse 13.70 399 1644
order to supplier: creation 43.34 1444 5201
order to supplier: removal 3.09 140177 371
invoice (draft): creation 42.93 3907 5151
invoice (draft): removal 48.58 1119 5829
invoice accept: apply 38.84 17098 4661
invoice accept: cancel 44.63 1045 5355
customer reserve: creation 3553.81 166 426457
customer reserve: removal 4378.61 78 525433
realization accept: apply 77.69 182 9323
realization accept: cancel 79.92 124 9590
payment from customer: creation 55.60 136 6672
payment from customer: removal 5.68 101 681
payment to supplier: creation 45.67 137 5480
payment to supplier: removal 3.20 79 384
service: total inventory turnovers 3.60 4116 432
service: total monetary turnovers 5.71 668 685
service: aggregate perf. data 0.93 15827 112
service: refresh index statistics 0.03 335224 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-03-2021 04:28:00 to 16-03-2021 04:28:02.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1149
TEST_TIME 2 1078
TEST_TIME 3 2382
TEST_TIME 4 3494
TEST_TIME 5 4381
TEST_TIME 6 4957
TEST_TIME 7 4893
TEST_TIME 8 4994
TEST_TIME 9 5062
TEST_TIME 10 4947
TEST_TIME 11 4956
TEST_TIME 12 4863
TEST_TIME 13 5066
TEST_TIME 14 5182
TEST_TIME 15 5322
TEST_TIME 16 5509
TEST_TIME 17 5526
TEST_TIME 18 5959
TEST_TIME 19 6566
TEST_TIME 20 6743
TEST_TIME 21 6939
TEST_TIME 22 7091
TEST_TIME 23 7887
TEST_TIME 24 8256
TEST_TIME 25 8273
TEST_TIME 26 8929
TEST_TIME 27 9001
TEST_TIME 28 9233
TEST_TIME 29 9363
TEST_TIME 30 10475
TEST_TIME 31 10283
TEST_TIME 32 10182
TEST_TIME 33 9975
TEST_TIME 34 9961
TEST_TIME 35 9872
TEST_TIME 36 9726
TEST_TIME 37 9553
TEST_TIME 38 9507
TEST_TIME 39 9312
TEST_TIME 40 9277
TEST_TIME 41 9137
TEST_TIME 42 9149
TEST_TIME 43 9199
TEST_TIME 44 9240
TEST_TIME 45 9155
TEST_TIME 46 9060
TEST_TIME 47 9156
TEST_TIME 48 9199
TEST_TIME 49 9150
TEST_TIME 50 9164
TEST_TIME 51 9303
TEST_TIME 52 9478
TEST_TIME 53 9449
TEST_TIME 54 9483
TEST_TIME 55 9505
TEST_TIME 56 9337
TEST_TIME 57 9231
TEST_TIME 58 9180
TEST_TIME 59 9064
TEST_TIME 60 8979
TEST_TIME 61 8972
TEST_TIME 62 8955
TEST_TIME 63 8915
TEST_TIME 64 8888
TEST_TIME 65 8891
TEST_TIME 66 8870
TEST_TIME 67 8906
TEST_TIME 68 8944
TEST_TIME 69 8928
TEST_TIME 70 8983
TEST_TIME 71 8959
TEST_TIME 72 8938
TEST_TIME 73 8916
TEST_TIME 74 9052
TEST_TIME 75 9118
TEST_TIME 76 9116
TEST_TIME 77 9014
TEST_TIME 78 8924
TEST_TIME 79 8877
TEST_TIME 80 8837
TEST_TIME 81 8781
TEST_TIME 82 8717
TEST_TIME 83 8675
TEST_TIME 84 8619
TEST_TIME 85 8666
TEST_TIME 86 8690
TEST_TIME 87 8685
TEST_TIME 88 8686
TEST_TIME 89 8698
TEST_TIME 90 8714
TEST_TIME 91 8780
TEST_TIME 92 8819
TEST_TIME 93 8861
TEST_TIME 94 8839
TEST_TIME 95 8825
TEST_TIME 96 8748
TEST_TIME 97 8717
TEST_TIME 98 8722
TEST_TIME 99 8661
TEST_TIME 100 8647
TEST_TIME 101 8656
TEST_TIME 102 8648
TEST_TIME 103 8666
TEST_TIME 104 8679
TEST_TIME 105 8664
TEST_TIME 106 8647
TEST_TIME 107 8622
TEST_TIME 108 8662
TEST_TIME 109 8756
TEST_TIME 110 8715
TEST_TIME 111 8769
TEST_TIME 112 8777
TEST_TIME 113 8712
TEST_TIME 114 8689
TEST_TIME 115 8666
TEST_TIME 116 8648
TEST_TIME 117 8639
TEST_TIME 118 8623
TEST_TIME 119 8637
TEST_TIME 120 8604

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 16-03-2021 04:28:03 to 16-03-2021 04:28:07.

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 1427474 1427474 0 0.00 0 19016 6 0 0
doc_list_biud 1427473 1427473 0 0.00 0 93355 36 0 0
sp_add_invoice_to_stock 4760 4661 99 2.07 19 176104 17098 0 99
sp_cancel_adding_invoice 5355 5355 0 0.00 5 71075 1046 0 0
sp_cancel_client_order 1644 1644 0 0.00 5 33763 399 0 0
sp_cancel_customer_reserve 525433 525433 0 0.00 1 66963 79 0 0
sp_cancel_pay_from_customer 681 681 0 0.00 3 8844 102 0 0
sp_cancel_pay_to_supplier 384 384 0 0.00 3 5346 80 0 0
sp_cancel_supplier_invoice 5834 5829 5 0.08 2 93361 1120 0 5
sp_cancel_supplier_order 371 371 0 0.00 8 1303210 140178 0 0
sp_cancel_write_off 9590 9590 0 0.00 1 9598 124 0 0
sp_client_order 18997 18997 0 0.00 4 37090 434 0 0
sp_customer_reserve 426457 426457 0 0.00 0 68501 167 0 0
sp_fill_shopping_cart 29333 29333 0 0.00 1 28812 50 0 0
sp_fill_shopping_cart_clo_res 426456 426456 0 0.00 0 27049 19 0 0
sp_get_clo_for_invoice 4659 4659 0 0.00 0 27485 493 0 0
sp_kill_cost_storno 16010 16010 0 0.00 0 12004 71 0 0
sp_kill_qstorno_ret_qs2qd 1066656 1066656 0 0.00 0 93355 43 0 0
sp_kill_qty_storno 562201 562201 0 0.00 0 93355 92 0 0
sp_lock_selected_doc 19425 19425 0 0.00 0 7204 4 0 0
sp_make_cost_storno 26132 26132 0 0.00 0 16768 60 0 0
sp_multiply_rows_for_pdistr 26131 26131 0 0.00 0 8189 33 0 0
sp_multiply_rows_for_qdistr 18997 18997 0 0.00 0 36921 264 0 0
sp_pay_from_customer 6672 6672 0 0.00 4 16683 137 0 0
sp_pay_to_supplier 5480 5480 0 0.00 4 19021 137 0 0
sp_payment_common 12152 12152 0 0.00 1 12333 27 0 0
sp_qd_handle_on_cancel_clo 1644 1644 0 0.00 0 33754 375 0 0
sp_qd_handle_on_invoice_upd_sts 10011 10011 0 0.00 0 24556 423 0 0
sp_qd_handle_on_reserve_upd_sts 9323 9323 0 0.00 0 33669 53 0 0
sp_reserve_write_off 9323 9323 0 0.00 7 33724 182 0 0
sp_supplier_invoice 5151 5151 0 0.00 6 125950 3908 0 0
sp_supplier_order 5201 5201 0 0.00 5 81783 1445 0 0
srv_aggregate_perf_data 112 112 0 0.00 2738 222956 15828 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1506 136225 47086 0 0
srv_find_qd_qs_mism 988236 988236 0 0.00 0 13716 5 0 0
srv_make_invnt_saldo 432 432 0 0.00 20 124629 4117 0 0
srv_make_money_saldo 685 685 0 0.00 1 14194 669 0 0
srv_recalc_idx_stat 4 4 0 0.00 208621 594735 335224 0 0
t$perf-abend:sp_add_invoice_to_stock 99 99 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4661 4661 0 0.00 0 15569 191 0 0
t$perf-norm:sp_cancel_adding_invoice 395 395 0 0.00 0 4810 106 0 0
t$perf-norm:sp_cancel_client_order 1644 1644 0 0.00 0 1768 12 0 0
t$perf-norm:sp_cancel_customer_reserve 642 642 0 0.00 0 2970 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 681 681 0 0.00 0 1960 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 384 384 0 0.00 0 78 1 0 0
t$perf-norm:sp_cancel_supplier_invoice 353 353 0 0.00 0 8274 40 0 0
t$perf-norm:sp_cancel_supplier_order 371 371 0 0.00 0 21265 1754 0 0
t$perf-norm:sp_cancel_write_off 976 976 0 0.00 0 2605 12 0 0
t$perf-norm:sp_client_order 18997 18997 0 0.00 0 19105 21 0 0
t$perf-norm:sp_customer_reserve 909 909 0 0.00 0 771 3 0 0
t$perf-norm:sp_pay_from_customer 6672 6672 0 0.00 0 6393 9 0 0
t$perf-norm:sp_pay_to_supplier 5480 5480 0 0.00 0 7094 11 0 0
t$perf-norm:sp_reserve_write_off 9323 9323 0 0.00 0 6392 9 0 0
t$perf-norm:sp_supplier_invoice 5151 5151 0 0.00 0 10517 24 0 0
t$perf-norm:sp_supplier_order 5201 5201 0 0.00 0 7228 16 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 451 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 734 12 0 0
t$perf-norm:srv_make_invnt_saldo 432 432 0 0.00 0 505 6 0 0
t$perf-norm:srv_make_money_saldo 685 685 0 0.00 0 630 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 2 0 0
v_add_invoice_to_stock 9510 9312 198 2.08 0 6437 16 0 198
v_all_customers 34616 34616 0 0.00 0 93 1 0 0
v_all_suppliers 28676 28676 0 0.00 0 17 1 0 0
v_all_wares 37994 37994 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 790 790 0 0.00 0 3541 14 0 0
v_cancel_client_order 3288 3288 0 0.00 0 2665 4 0 0
v_cancel_customer_prepayment 1362 1362 0 0.00 0 1640 10 0 0
v_cancel_customer_reserve 1284 1284 0 0.00 0 307 3 0 0
v_cancel_payment_to_supplier 768 768 0 0.00 0 653 4 0 0
v_cancel_supplier_invoice 716 706 10 1.39 0 10535 26 0 10
v_cancel_supplier_order 742 742 0 0.00 0 1233 6 0 0
v_cancel_write_off 1952 1952 0 0.00 0 3292 15 0 0
v_max_id_clo_ord 5196 5196 0 0.00 0 214 1 0 0
v_max_id_clo_res 909 909 0 0.00 0 1232 9 0 0
v_max_id_ord_sup 5137 5137 0 0.00 0 3485 4 0 0
v_max_non_paid_invoice 1475 1475 0 0.00 0 387 2 0 0
v_max_non_paid_realizn 3625 3625 0 0.00 0 4593 6 0 0
v_min_id_clo_ord 5196 5196 0 0.00 0 1940 4 0 0
v_min_id_clo_res 909 909 0 0.00 0 1220 6 0 0
v_min_id_ord_sup 5137 5137 0 0.00 0 5674 12 0 0
v_min_non_paid_invoice 5480 5480 0 0.00 0 7636 15 0 0
v_min_non_paid_realizn 6672 6672 0 0.00 0 12328 20 0 0
v_our_firm 9472 9472 0 0.00 0 11 1 0 0
v_reserve_write_off 18646 18646 0 0.00 0 12364 6 0 0
x_lock_depdocs_on_canc_invoice 10831 10831 0 0.00 0 51405 112 0 0
x_lock_depdocs_on_canc_sup_ord 371 371 0 0.00 0 3142 339 0 0
x_make_qstorno_1000_1200 5201 5201 0 0.00 2 81777 1369 0 0
x_make_qstorno_1200_2000 5151 5151 0 0.00 2 125915 3700 0 0
x_make_qstorno_2100_3300 426457 426457 0 0.00 0 63425 145 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-03-2021 04:28:08 to 16-03-2021 04:28:12.

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
01:56:59 5 8192000000 8799343088 8810844160 5349280 0.065 2 2 2 0 5450768 65712 35776
01:58:01 11720 8192000000 9730341024 10026311680 811575296 9.013 100 86 172 0 839420080 7402208 20442576
01:59:24 15150 8192000000 9759184640 10063474688 837690432 9.277 100 92 184 0 870797200 11143920 21962848
02:00:49 17890 8192000000 9797950560 10112106496 870445072 9.604 100 87 174 0 906613264 16008912 20159280
02:02:10 15766 8192000000 9865152256 10193956864 919609408 10.092 100 85 171 1 975708048 37677888 18420752
02:03:31 9861 8192000000 9913096512 10254151680 949746880 10.389 100 80 160 1 1022354912 55623712 16984320
02:04:45 7937 8192000000 9949255872 10299265024 975736592 10.643 100 84 168 1 1063552448 71110400 16705456
02:05:56 4534 8192000000 9989116800 10342989824 1004407344 10.921 100 86 172 2 1100759280 78912768 17439168
02:07:02 2184 8192000000 10006856400 10367483904 1027430672 11.144 100 91 177 2 1122638864 77376880 17831312
02:08:06 1881 8192000000 10011305104 10376396800 1048496192 11.346 100 89 178 1 1118780224 52334208 17949824
02:09:09 23899 8192000000 9991643184 10371387392 1062121680 11.477 100 57 113 3 1103140432 29040512 11978240
02:10:33 5572 8192000000 10011040448 10395316224 1076504416 11.614 100 41 80 2 1127307648 42755760 8047472
02:11:42 9463 8192000000 10000693584 10409406464 1084198672 11.687 100 29 58 5 1123142480 33176768 5767040
02:12:55 23436 8192000000 10003236816 10414039040 1096647312 11.806 100 24 48 0 1126436928 24970736 4818880
02:14:38 2060 8192000000 10040949088 10442760192 1096167728 11.801 100 41 82 1 1147078544 43306704 7604112
02:15:41 8057 8192000000 10021329520 10415841280 1090719696 11.750 99 23 47 1 1111289680 15485120 5084864
02:16:50 8939 8192000000 10007936656 10414469120 1078816880 11.636 98 32 63 1 1110169296 22826288 8526128
02:18:02 34468 8192000000 10006097824 10418466816 1077275072 11.621 98 27 55 2 1111176080 28051392 5849616
02:19:39 4570 8192000000 9967707024 10355507200 1015286384 11.026 95 48 96 0 1045392320 19433536 10672400
02:20:45 6310 8192000000 9940803600 10319228928 1026312496 11.133 100 31 61 1 1045701824 12458816 6930512
02:21:52 6200 8192000000 9942692704 10320416768 1008426992 10.960 98 40 80 2 1042846800 25915904 8503904
02:23:02 21390 8192000000 9924826688 10319790080 974607920 10.632 95 20 40 0 1000167968 21168960 4391088
02:24:24 4034 8192000000 9949920592 10338971648 1024362192 11.114 99 40 78 1 1056899952 24294304 8243456
02:25:31 7559 8192000000 9971498208 10373206016 1055535952 11.414 100 47 93 3 1085691856 19522304 10633600
02:26:44 63217 8192000000 9986887552 10381492224 1068164624 11.535 100 40 78 0 1089265776 11900752 9200400
02:29:04 7155 8192000000 10040812560 10437120000 1093300272 11.774 100 48 95 2 1142304672 39846112 9158288
02:30:15 18075 8192000000 10064990544 10464489472 1109587760 11.929 100 43 85 0 1176088864 57552992 8948112
02:31:37 18001 8192000000 10103912624 10519560192 1131026400 12.131 100 53 106 2 1217213280 74647584 11539296
02:33:01 11966 8192000000 10157170896 10565062656 1154289216 12.350 100 65 130 1 1268844416 100953248 13601952
02:34:19 7017 8192000000 10199616448 10611204096 1167645424 12.475 100 55 109 1 1307058784 127996368 11416992
02:35:29 10509 8192000000 10231466048 10644897792 1174254720 12.537 100 53 106 3 1338552848 152463808 11834320
02:36:52 8770 8192000000 10273210240 10686541824 1174309232 12.537 99 68 135 1 1367169472 176921040 15939200
02:38:09 7701 8192000000 10279437392 10690789376 1173710144 12.531 99 64 127 1 1378331328 189950384 14670800
02:39:29 6043 8192000000 10371389488 10780188672 1190057120 12.684 100 83 166 1 1470192480 260871104 19264256
02:40:39 3617 8192000000 10429340240 10833637376 1195387024 12.733 100 80 160 0 1532386912 319188688 17811200
02:41:48 1987 8192000000 10453121296 10848313344 1165286032 12.453 99 91 181 2 1546264064 360111728 20866304
02:42:56 1505 8192000000 10464132432 10866511872 1184573808 12.633 100 81 163 0 1560315008 356989312 18751888
02:44:00 2579 8192000000 10488392064 10918309888 1177064048 12.563 100 68 134 2 1588376192 394377296 16934848
02:45:10 4820 8192000000 10469430544 10869628928 1155052992 12.357 99 83 164 0 1558418384 384319040 19046352
02:46:19 2632 8192000000 10488142240 10895032320 1180570704 12.596 100 84 169 0 1586654752 386607248 19476800
02:47:30 1887 8192000000 10529598736 10937692160 1176885888 12.561 100 82 164 0 1626897344 430008336 20003120
02:48:40 4610 8192000000 10512041200 10929897472 1142714928 12.241 100 63 125 1 1610253696 450961520 16577248
02:49:47 14088 8192000000 10433000288 10877206528 1140805936 12.223 100 33 67 2 1550022944 400360352 8856656
02:51:09 22132 8192000000 10403192848 10836598784 1127203184 12.095 99 29 58 0 1483096720 347513072 8380464
02:52:47 19008 8192000000 10320035264 10753064960 1138577328 12.202 99 22 44 2 1420746400 276411312 5757760
02:54:08 27378 8192000000 10307270224 10776354816 1116886976 11.998 99 18 36 0 1426034528 304320512 4827040
02:55:37 15352 8192000000 10187752304 10788704256 1137090416 12.188 100 35 68 1 1422602624 277565792 7946416
02:56:56 38937 8192000000 10065880688 10508804096 1117932032 12.007 99 42 85 3 1162560320 35913136 8715152
02:58:41 46411 8192000000 10125617888 10567557120 1142225136 12.236 100 21 42 1 1228013936 81432128 4356672
03:00:44 59408 8192000000 10092305488 10560974848 1121354560 12.040 99 13 26 0 1176058576 52590416 2113600
03:02:49 39789 8192000000 10108281440 10549620736 1125707216 12.081 99 17 34 0 1187815360 58356976 3751168
03:05:09 47396 8192000000 10088761264 10532347904 1142284352 12.237 100 50 98 1 1187512352 34043360 11184640
03:07:02 52260 8192000000 10147146848 10604105728 1145838928 12.270 99 18 36 0 1228601680 78319536 4443216
03:08:55 58677 8192000000 10138594608 10643345408 1137394992 12.191 99 19 38 1 1216906288 74928912 4582384
03:11:00 94314 8192000000 10147839632 10595545088 1137664032 12.194 99 11 22 3 1222674960 82110496 2900432
03:13:36 45438 8192000000 10120188128 10608963584 1152988864 12.338 100 19 38 1 1235573648 78528048 4056736
03:15:24 50829 8192000000 10161347216 10623307776 1144879504 12.261 99 34 67 0 1264011104 111774064 7357536
03:17:20 66900 8192000000 10149126112 10685489152 1129998224 12.121 98 17 34 2 1243941056 110395792 3547040
03:19:28 86260 8192000000 10131733152 10615869440 1122338800 12.049 98 16 31 0 1158922672 32991792 3592080
03:21:55 78925 8192000000 10069235824 10561916928 1138009328 12.197 100 9 18 0 1182798848 43048432 1741088
03:24:33 75296 8192000000 10089443344 10555506688 1126037632 12.084 98 7 14 0 1167644384 40119552 1487200
03:26:49 42343 8192000000 10099497072 10580869120 1141990304 12.234 99 10 20 1 1211287664 67083584 2213776
03:28:32 61083 8192000000 10072083888 10524889088 1128176912 12.104 98 15 30 1 1167093520 35374112 3542496
03:30:37 49611 8192000000 10067751568 10506395648 1053738832 11.397 92 12 24 0 1083058208 27006720 2312656
03:32:30 57657 8192000000 10083462464 10538135552 1116647440 11.995 98 13 26 0 1185796336 65986752 3162144
03:34:33 53833 8192000000 10076671792 10510708736 1078675744 11.635 96 26 52 0 1116897184 32894048 5327392
03:36:27 99237 8192000000 10107226976 10563862528 1103670368 11.872 98 20 40 0 1151912464 44115968 4126128
03:39:08 73914 8192000000 10163131168 10626224128 1120666864 12.033 98 27 54 0 1238929616 112426336 5836416
03:41:31 62455 8192000000 10084682224 10559647744 1137485728 12.192 100 8 16 0 1191518160 52556512 1475920
03:43:37 136224 8192000000 10070326352 10542637056 1112608496 11.957 99 20 41 1 1165956976 48962928 4385552
03:46:56 35033 8192000000 10066668400 10554458112 1125557408 12.079 100 13 26 0 1170203744 42036576 2609760
03:48:31 30953 8192000000 10088784960 10582315008 1129877712 12.120 100 7 14 0 1160331088 29017712 1435664
03:50:03 66667 8192000000 10136506688 10579951616 1135944256 12.177 99 28 56 0 1214303824 71136992 7222576
03:52:11 39873 8192000000 10122909456 10575273984 1125485648 12.079 98 19 38 1 1188666592 58578896 4602048
03:53:52 56560 8192000000 10116539104 10578911232 1118894320 12.017 98 20 40 0 1185209472 61910352 4404800
03:56:08 103309 8192000000 10112172320 10607480832 1135465840 12.173 99 23 46 1 1214365648 74263136 4636672
03:59:00 43547 8192000000 10114195808 10595422208 1153451856 12.342 100 18 36 0 1219071664 62118768 3501040
04:00:48 43462 8192000000 10100034768 10551943168 1109880016 11.931 97 23 45 1 1148678880 33523248 5275616
04:02:38 106541 8192000000 10106152832 10568159232 1129759328 12.119 99 24 48 0 1230308224 95389856 5159040
04:05:29 52576 8192000000 10090128160 10549350400 1141078704 12.226 100 10 20 0 1191977424 48475344 2423376
04:07:22 105599 8192000000 10080141008 10569138176 1131894912 12.139 99 11 22 0 1197644368 63216688 2532768
04:10:11 84419 8192000000 10060691472 10503254016 1108990160 11.923 97 14 28 1 1145920640 33898864 3031616
04:12:40 90637 8192000000 10158737232 10598572032 1117782080 12.006 97 33 66 1 1214235200 89450032 7003088
04:15:21 83770 8192000000 10089972464 10528542720 1104748528 11.883 97 9 18 0 1157541616 51212128 1580960
04:17:45 111264 8192000000 10041743952 10529763328 1081476736 11.662 96 7 15 0 1124555344 41571472 1507136
04:20:36 76362 8192000000 10050291408 10535587840 1104412896 11.879 99 9 18 0 1135725072 29714976 1597200
04:22:53 50023 8192000000 10065467984 10513534976 1128708464 12.109 100 20 40 0 1164239872 31206704 4324704
04:24:55 75552 8192000000 10037546352 10504507392 1061485440 11.471 95 8 16 0 1095685296 32907456 1292400

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 16-03-2021 04:28:14 to 16-03-2021 04:28:29.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 99 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-03-2021 04:28:30 to 16-03-2021 04:28:32.

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          224001
MON$OLDEST_ACTIVE               225212
MON$OLDEST_SNAPSHOT             225212
MON$NEXT_TRANSACTION            225212
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                       2084832
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             786
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2387 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2387 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2387 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2387 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16: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 ---
:149: 
:150: mscs-test-161.budzdorov.ru    Tue Mar 16 04:26:59 2021
:151:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:152: 
:153: 
:154: mscs-test-161.budzdorov.ru    Tue Mar 16 04:27:00 2021
:155:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:156: 
:157: 
:158: mscs-test-161.budzdorov.ru    Tue Mar 16 04:27:00 2021
:159:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:160: 
:161: 
:162: mscs-test-161.budzdorov.ru    Tue Mar 16 04:27:00 2021
:163:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:164: 
--- end of diff output ---

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

16.03.21 04:28:33. 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-03-16 04:28:35 No severe PSQL-related problems occured
16.03.21 04:28:36. End of report.