Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.03.2021 13: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 17000672 kB
MemAvailable 31499600 kB
Buffers 0 kB
Cached 14634128 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-23 13:26:57
DTS_END                         2021-03-23 13:26:57.0710
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/41212
TRN_ID                          231485
ATT_ID                          669
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: 8660.92 23496 1039311
customer order: creation 168.32 415 20198
customer order: refuse 13.47 300 1616
order to supplier: creation 43.75 1486 5250
order to supplier: removal 3.30 110319 396
invoice (draft): creation 45.56 3714 5467
invoice (draft): removal 48.28 939 5793
invoice accept: apply 40.49 18338 4859
invoice accept: cancel 43.23 929 5188
customer reserve: creation 3737.18 178 448462
customer reserve: removal 4232.38 68 507886
realization accept: apply 79.97 191 9597
realization accept: cancel 81.42 116 9770
payment from customer: creation 56.86 131 6823
payment from customer: removal 5.43 93 652
payment to supplier: creation 47.23 129 5667
payment to supplier: removal 3.28 74 394
service: total inventory turnovers 4.00 3244 480
service: total monetary turnovers 5.72 582 686
service: aggregate perf. data 1.03 12648 123
service: refresh index statistics 0.03 316031 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 23-03-2021 13:28:13 to 23-03-2021 13:28:15.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2564
TEST_TIME 2 3212
TEST_TIME 3 3593
TEST_TIME 4 3594
TEST_TIME 5 3743
TEST_TIME 6 4553
TEST_TIME 7 4677
TEST_TIME 8 4879
TEST_TIME 9 5228
TEST_TIME 10 5532
TEST_TIME 11 5642
TEST_TIME 12 5910
TEST_TIME 13 6025
TEST_TIME 14 6335
TEST_TIME 15 6538
TEST_TIME 16 6667
TEST_TIME 17 7167
TEST_TIME 18 7507
TEST_TIME 19 7576
TEST_TIME 20 7860
TEST_TIME 21 8500
TEST_TIME 22 8583
TEST_TIME 23 9109
TEST_TIME 24 9175
TEST_TIME 25 9212
TEST_TIME 26 9503
TEST_TIME 27 9855
TEST_TIME 28 11027
TEST_TIME 29 10793
TEST_TIME 30 10656
TEST_TIME 31 10508
TEST_TIME 32 10381
TEST_TIME 33 10258
TEST_TIME 34 10166
TEST_TIME 35 10047
TEST_TIME 36 9927
TEST_TIME 37 9768
TEST_TIME 38 9640
TEST_TIME 39 9508
TEST_TIME 40 9401
TEST_TIME 41 9302
TEST_TIME 42 9182
TEST_TIME 43 9092
TEST_TIME 44 9120
TEST_TIME 45 9214
TEST_TIME 46 9171
TEST_TIME 47 9134
TEST_TIME 48 9103
TEST_TIME 49 9062
TEST_TIME 50 9074
TEST_TIME 51 9169
TEST_TIME 52 9162
TEST_TIME 53 9350
TEST_TIME 54 9294
TEST_TIME 55 9241
TEST_TIME 56 9437
TEST_TIME 57 9436
TEST_TIME 58 9328
TEST_TIME 59 9232
TEST_TIME 60 9240
TEST_TIME 61 9112
TEST_TIME 62 9154
TEST_TIME 63 9109
TEST_TIME 64 9123
TEST_TIME 65 9095
TEST_TIME 66 9121
TEST_TIME 67 9201
TEST_TIME 68 9204
TEST_TIME 69 9196
TEST_TIME 70 9147
TEST_TIME 71 9142
TEST_TIME 72 9179
TEST_TIME 73 9261
TEST_TIME 74 9225
TEST_TIME 75 9283
TEST_TIME 76 9206
TEST_TIME 77 9129
TEST_TIME 78 9056
TEST_TIME 79 9031
TEST_TIME 80 8970
TEST_TIME 81 8931
TEST_TIME 82 8889
TEST_TIME 83 8838
TEST_TIME 84 8879
TEST_TIME 85 8898
TEST_TIME 86 8877
TEST_TIME 87 8899
TEST_TIME 88 8921
TEST_TIME 89 8876
TEST_TIME 90 8936
TEST_TIME 91 8966
TEST_TIME 92 8951
TEST_TIME 93 9029
TEST_TIME 94 9026
TEST_TIME 95 9006
TEST_TIME 96 9030
TEST_TIME 97 8956
TEST_TIME 98 8906
TEST_TIME 99 8869
TEST_TIME 100 8817
TEST_TIME 101 8761
TEST_TIME 102 8712
TEST_TIME 103 8720
TEST_TIME 104 8752
TEST_TIME 105 8743
TEST_TIME 106 8739
TEST_TIME 107 8725
TEST_TIME 108 8760
TEST_TIME 109 8778
TEST_TIME 110 8820
TEST_TIME 111 8792
TEST_TIME 112 8783
TEST_TIME 113 8812
TEST_TIME 114 8774
TEST_TIME 115 8715
TEST_TIME 116 8699
TEST_TIME 117 8696
TEST_TIME 118 8669
TEST_TIME 119 8675
TEST_TIME 120 8663

Performance per minute, chart


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

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 1455483 1455483 0 0.00 0 22684 7 0 0
doc_list_biud 1455481 1455481 0 0.00 0 76624 30 0 0
sp_add_invoice_to_stock 4970 4859 111 2.23 25 248321 18338 0 111
sp_cancel_adding_invoice 5188 5188 0 0.00 7 80372 929 0 0
sp_cancel_client_order 1616 1616 0 0.00 4 11872 300 0 0
sp_cancel_customer_reserve 507886 507886 0 0.00 1 17621 69 0 0
sp_cancel_pay_from_customer 652 652 0 0.00 3 6569 93 0 0
sp_cancel_pay_to_supplier 394 394 0 0.00 3 4112 74 0 0
sp_cancel_supplier_invoice 5804 5793 11 0.18 2 76629 939 0 11
sp_cancel_supplier_order 396 396 0 0.00 10 1281127 110319 0 0
sp_cancel_write_off 9770 9770 0 0.00 1 9837 116 0 0
sp_client_order 20198 20198 0 0.00 4 33083 415 0 0
sp_customer_reserve 448462 448462 0 0.00 0 119464 179 0 0
sp_fill_shopping_cart 30897 30897 0 0.00 1 15607 48 0 0
sp_fill_shopping_cart_clo_res 448456 448456 0 0.00 0 24624 19 0 0
sp_get_clo_for_invoice 4853 4853 0 0.00 0 33667 477 0 0
sp_kill_cost_storno 16004 16004 0 0.00 0 14700 58 0 0
sp_kill_qstorno_ret_qs2qd 1031731 1031731 0 0.00 0 76622 37 0 0
sp_kill_qty_storno 545099 545099 0 0.00 0 76622 80 0 0
sp_lock_selected_doc 19876 19876 0 0.00 0 7568 4 0 0
sp_make_cost_storno 26940 26940 0 0.00 0 22679 66 0 0
sp_multiply_rows_for_pdistr 26940 26940 0 0.00 0 11543 33 0 0
sp_multiply_rows_for_qdistr 20198 20198 0 0.00 0 33069 219 0 0
sp_pay_from_customer 6823 6823 0 0.00 4 13679 131 0 0
sp_pay_to_supplier 5667 5667 0 0.00 5 12328 129 0 0
sp_payment_common 12490 12490 0 0.00 1 10385 26 0 0
sp_qd_handle_on_cancel_clo 1616 1616 0 0.00 0 11860 268 0 0
sp_qd_handle_on_invoice_upd_sts 10041 10041 0 0.00 0 21969 368 0 0
sp_qd_handle_on_reserve_upd_sts 9597 9597 0 0.00 0 11069 53 0 0
sp_reserve_write_off 9597 9597 0 0.00 7 23128 191 0 0
sp_supplier_invoice 5467 5467 0 0.00 6 76020 3714 0 0
sp_supplier_order 5250 5250 0 0.00 5 52324 1486 0 0
srv_aggregate_perf_data 123 123 0 0.00 2812 102690 12648 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2356 204537 48618 0 0
srv_find_qd_qs_mism 993533 993533 0 0.00 0 16070 6 0 0
srv_make_invnt_saldo 480 480 0 0.00 62 92034 3244 0 0
srv_make_money_saldo 686 686 0 0.00 1 14792 583 0 0
srv_recalc_idx_stat 4 4 0 0.00 234206 432157 316031 0 0
t$perf-abend:sp_add_invoice_to_stock 111 111 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 4859 4859 0 0.00 0 25067 201 0 0
t$perf-norm:sp_cancel_adding_invoice 392 392 0 0.00 0 9814 118 0 0
t$perf-norm:sp_cancel_client_order 1616 1616 0 0.00 0 3202 11 0 0
t$perf-norm:sp_cancel_customer_reserve 657 657 0 0.00 0 536 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 652 652 0 0.00 0 8488 22 0 0
t$perf-norm:sp_cancel_pay_to_supplier 394 394 0 0.00 0 3138 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 397 397 0 0.00 0 2415 22 0 0
t$perf-norm:sp_cancel_supplier_order 396 396 0 0.00 0 25882 1386 0 0
t$perf-norm:sp_cancel_write_off 922 922 0 0.00 0 1094 7 0 0
t$perf-norm:sp_client_order 20198 20198 0 0.00 0 6650 18 0 0
t$perf-norm:sp_customer_reserve 1026 1026 0 0.00 0 249 3 0 0
t$perf-norm:sp_pay_from_customer 6823 6823 0 0.00 0 15096 10 0 0
t$perf-norm:sp_pay_to_supplier 5667 5667 0 0.00 0 15533 14 0 0
t$perf-norm:sp_reserve_write_off 9597 9597 0 0.00 0 16616 15 0 0
t$perf-norm:sp_supplier_invoice 5467 5467 0 0.00 0 8799 22 0 0
t$perf-norm:sp_supplier_order 5250 5250 0 0.00 0 7203 17 0 0
t$perf-norm:srv_aggregate_perf_data 123 123 0 0.00 0 766 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 953 20 0 0
t$perf-norm:srv_make_invnt_saldo 480 480 0 0.00 0 7848 23 0 0
t$perf-norm:srv_make_money_saldo 686 686 0 0.00 0 1724 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 4 0 0
v_add_invoice_to_stock 9928 9706 222 2.23 0 9681 20 0 222
v_all_customers 37098 37098 0 0.00 0 17 1 0 0
v_all_suppliers 29484 29484 0 0.00 0 23 1 0 0
v_all_wares 40396 40396 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 784 784 0 0.00 0 2147 10 0 0
v_cancel_client_order 3232 3232 0 0.00 0 2599 6 0 0
v_cancel_customer_prepayment 1304 1304 0 0.00 0 1836 8 0 0
v_cancel_customer_reserve 1314 1314 0 0.00 0 1662 4 0 0
v_cancel_payment_to_supplier 788 788 0 0.00 0 4086 12 0 0
v_cancel_supplier_invoice 816 794 22 2.69 0 771 15 0 22
v_cancel_supplier_order 792 792 0 0.00 0 2520 10 0 0
v_cancel_write_off 1844 1844 0 0.00 0 1154 13 0 0
v_max_id_clo_ord 5250 5250 0 0.00 0 614 1 0 0
v_max_id_clo_res 1026 1026 0 0.00 0 2575 16 0 0
v_max_id_ord_sup 5447 5447 0 0.00 0 3499 3 0 0
v_max_non_paid_invoice 1622 1622 0 0.00 0 306 2 0 0
v_max_non_paid_realizn 3479 3479 0 0.00 0 2212 4 0 0
v_min_id_clo_ord 5250 5250 0 0.00 0 8034 13 0 0
v_min_id_clo_res 1026 1026 0 0.00 0 10518 26 0 0
v_min_id_ord_sup 5447 5447 0 0.00 0 4033 6 0 0
v_min_non_paid_invoice 5667 5667 0 0.00 0 9195 11 0 0
v_min_non_paid_realizn 6823 6823 0 0.00 0 9168 24 0 0
v_our_firm 9986 9986 0 0.00 0 14 1 0 0
v_reserve_write_off 19194 19194 0 0.00 0 6082 7 0 0
x_lock_depdocs_on_canc_invoice 10584 10584 0 0.00 0 24849 111 0 0
x_lock_depdocs_on_canc_sup_ord 396 396 0 0.00 0 2618 285 0 0
x_make_qstorno_1000_1200 5250 5250 0 0.00 2 50723 1395 0 0
x_make_qstorno_1200_2000 5467 5467 0 0.00 3 72740 3535 0 0
x_make_qstorno_2100_3300 448462 448462 0 0.00 0 119463 157 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-03-2021 13:28:21 to 23-03-2021 13:28:25.

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
10:57:00 1 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
10:58:01 30649 8192000000 9722016288 10017099776 794370416 8.839 100 85 170 0 823175200 7711088 21093696
10:59:36 21474 8192000000 9763658624 10068361216 835994832 9.260 100 89 176 2 868997616 11927936 21074848
11:01:01 16597 8192000000 9804314944 10121060352 870588432 9.606 100 88 176 0 909716512 18341984 20786096
11:02:24 11161 8192000000 9881583216 10212384768 923885088 10.134 100 86 172 1 980051504 37456448 18709968
11:03:40 11846 8192000000 9918230384 10265923584 960694624 10.496 100 76 152 4 1038861088 62020960 16145504
11:04:54 5879 8192000000 9964244144 10319470592 1001949472 10.897 100 82 164 0 1084679392 66211712 16518208
11:06:03 3402 8192000000 10000041168 10360066048 1027198624 11.141 100 87 172 2 1120978928 76904336 16875968
11:07:08 2196 8192000000 10030685472 10399166464 1052935728 11.389 100 91 182 0 1147908272 76422224 18550320
11:08:12 1807 8192000000 10051499440 10422538240 1072755536 11.578 100 92 184 0 1163283392 71281088 19246768
11:09:15 7282 8192000000 10003390944 10397130752 1073626432 11.587 100 35 70 0 1129601856 49196320 6779104
11:10:23 6685 8192000000 10002186416 10401275904 1081141344 11.658 100 36 68 0 1120051280 31440448 7469488
11:11:34 11219 8192000000 10005490032 10407026688 1087426256 11.718 100 30 59 2 1121303808 27838592 6038960
11:12:46 7218 8192000000 10008283888 10422337536 1095545408 11.795 100 20 40 0 1123334240 23718384 4070448
11:13:53 31479 8192000000 10006594608 10406969344 1098888928 11.827 100 39 77 1 1114857552 7018704 8949920
11:15:25 3791 8192000000 10054992640 10452217856 1103415152 11.870 100 45 90 0 1165029552 51963152 9651248
11:16:30 5049 8192000000 10008892064 10402172928 1060060304 11.457 97 34 69 2 1106021600 38983888 6977408
11:17:36 4852 8192000000 10012870464 10406846464 1087937040 11.723 100 41 76 1 1118556512 22705968 7913504
11:18:42 45302 8192000000 9969847008 10374373376 1033162656 11.199 96 37 74 2 1062653952 21343632 8147664
11:20:27 7334 8192000000 9930875360 10318520320 992858160 10.809 96 35 68 2 1024876448 24706752 7311536
11:21:35 8982 8192000000 9951082896 10326405120 1006673056 10.943 99 46 90 3 1032110864 14546560 10891248
11:22:47 5554 8192000000 9964431392 10348179456 1040533504 11.270 100 44 88 0 1073878976 23419936 9925536
11:23:54 23925 8192000000 9950317664 10350071808 1036008656 11.226 99 19 36 1 1064601792 24506576 4086560
11:25:21 2963 8192000000 9984880320 10372661248 1065207616 11.506 100 62 123 2 1104959968 26280992 13471360
11:26:25 5330 8192000000 9998042672 10394771456 1071512144 11.567 100 51 102 1 1101883632 19587168 10784320
11:27:38 4692 8192000000 10032958272 10416701440 1092196400 11.764 100 61 123 2 1131079520 25944464 12938656
11:28:46 20631 8192000000 10056477392 10454339584 1103853392 11.874 100 54 109 1 1166657504 51672864 11131248
11:30:13 33167 8192000000 10051930048 10463129600 1111916192 11.951 100 34 69 1 1168057456 49007376 7133888
11:31:50 10831 8192000000 10128360976 10538041344 1127215136 12.095 100 61 118 3 1230426176 90776400 12434640
11:33:06 14252 8192000000 10122534448 10533158912 1133919632 12.158 100 48 93 1 1221985584 78605872 9460080
11:34:24 12572 8192000000 10190424256 10600812544 1145438032 12.267 100 60 120 0 1300334144 141380816 13515296
11:35:39 7382 8192000000 10226799600 10640408576 1157005184 12.375 100 62 122 0 1329041664 159049200 12987280
11:36:49 18872 8192000000 10227507680 10644279296 1164125888 12.442 100 45 89 1 1329520288 155287440 10106960
11:38:10 6717 8192000000 10243964272 10656071680 1174121744 12.535 100 67 132 0 1348361552 159531408 14708400
11:39:24 3464 8192000000 10269804560 10669961216 1144245776 12.255 97 72 141 1 1356882016 196870944 15765296
11:40:34 6647 8192000000 10337117392 10745602048 1183038304 12.619 100 81 158 1 1435167792 234601136 17528352
11:41:45 12397 8192000000 10354503120 10764693504 1172287504 12.518 99 70 140 1 1451078144 262676816 16113824
11:43:03 4457 8192000000 10405066832 10813812736 1166974608 12.469 99 82 164 1 1504407184 318690064 18742512
11:44:13 3264 8192000000 10393060400 10796130304 1157930128 12.384 100 81 161 0 1494852976 318805552 18117296
11:45:20 2355 8192000000 10388512880 10795995136 1141559568 12.230 99 74 148 0 1478738512 320139920 17039024
11:46:26 2962 8192000000 10442744128 10847227904 1158601392 12.390 100 84 166 3 1536682656 358813744 19267520
11:47:36 5423 8192000000 10389739040 10851459072 1147852592 12.289 100 47 94 0 1510406096 350540016 12013488
11:48:50 10234 8192000000 10411514704 10838233088 1147536880 12.286 100 45 91 1 1496183888 337321840 11325168
11:50:05 23199 8192000000 10366148576 10814144512 1155725200 12.363 100 37 74 0 1462874192 297438048 9710944
11:51:44 27309 8192000000 10324549808 10764042240 1138791648 12.204 100 35 70 1 1483496304 335834800 8869856
11:53:21 38814 8192000000 10279509008 10732462080 1092304752 11.765 97 16 32 0 1365043088 268503136 4235200
11:55:09 23426 8192000000 10048623424 10468098048 1082393392 11.670 98 26 51 0 1133363568 45159296 5810880
11:56:32 34982 8192000000 10055314528 10511876096 1085896576 11.704 98 29 57 1 1118264000 26412432 5954992
11:58:22 204537 8192000000 10064885904 10529304576 1110938768 11.941 98 13 23 2 1159579696 46264864 2376064
12:02:50 83160 8192000000 10063872112 10499420160 1143324560 12.247 100 20 38 1 1181599488 34771376 3503552
12:05:14 20950 8192000000 10084199968 10529714176 1151182960 12.321 100 25 50 0 1192952768 36667024 5102784
12:06:40 30757 8192000000 10106191200 10555793408 1138376224 12.200 99 43 85 1 1196346176 47536576 10433376
12:08:11 81842 8192000000 10123021200 10585260032 1148713744 12.297 100 19 37 2 1236171120 83128320 4329056
12:10:36 84876 8192000000 10056596432 10571010048 1116954448 11.998 97 12 25 2 1230972464 111194672 2823344
12:13:18 14932 8192000000 10062257232 10495713280 1134014336 12.159 99 49 96 2 1157675792 13235840 10425616
12:14:35 41813 8192000000 10087494640 10531897344 1109106384 11.924 96 18 36 1 1147524064 34807088 3610592
12:17:26 117389 8192000000 10139235632 10602459136 1106403840 11.898 96 11 22 0 1182243280 72930176 2909264
12:20:23 60561 8192000000 10125654176 10571472896 1089452128 11.737 95 29 57 1 1136510944 40451584 6607232
12:22:26 111830 8192000000 10076545312 10518069248 1089449856 11.737 95 22 44 2 1136554592 43016528 4088208
12:25:59 91481 8192000000 10083263792 10564767744 1126078608 12.084 99 11 22 0 1203783264 75355440 2349216
12:28:30 41965 8192000000 10057926352 10533470208 1123102272 12.056 99 7 14 0 1163549312 39266080 1180960
12:30:17 42194 8192000000 10068145712 10523271168 1106528416 11.900 97 21 42 1 1174768368 64277632 3962320
12:32:00 118728 8192000000 10066585696 10517622784 1029164880 11.160 91 14 28 1 1111995536 79465600 3365056
12:34:59 25177 8192000000 10061473920 10500542464 1094880976 11.789 97 38 77 3 1127894160 25391344 7621840
12:36:25 38625 8192000000 10071464304 10495270912 1086471984 11.709 98 45 89 2 1139623776 43931456 9220336
12:38:09 20355 8192000000 10088373088 10521423872 1095880160 11.799 98 42 84 2 1175241952 70881056 8480736
12:39:32 146782 8192000000 10033515984 10481508352 1058126528 11.439 94 20 41 1 1103106992 40040448 4940016
12:42:59 109998 8192000000 10058448128 10488803328 1102508160 11.861 99 24 48 1 1144170064 36551536 5110368
12:46:06 42995 8192000000 10048862720 10508447744 1110238256 11.935 99 7 14 0 1146073616 34359664 1475696
12:47:51 56690 8192000000 10098352320 10581086208 1111718400 11.949 98 10 20 0 1186930432 72994560 2217472
12:50:14 56849 8192000000 10033568656 10516279296 1084546912 11.691 97 9 18 1 1154847072 68289200 2010960
12:52:15 47936 8192000000 10099297552 10542563328 1094884928 11.789 98 25 50 1 1164843440 63981232 5977280
12:54:04 30223 8192000000 10091887152 10531942400 1111183552 11.944 99 44 88 2 1177253472 56856272 9213648
12:55:36 27789 8192000000 10134248864 10574626816 1128731968 12.109 100 43 86 0 1224107840 86410576 8965296
12:57:04 34236 8192000000 10066549488 10532110336 1117149968 12.000 99 13 26 0 1163877360 44375392 2352000
12:59:01 62805 8192000000 10125871568 10648244224 1147670704 12.288 100 17 34 1 1251633168 99918960 4043504
13:01:16 28291 8192000000 10138798960 10582085632 1144156624 12.255 100 29 58 1 1191246720 41554512 5535584
13:02:52 121873 8192000000 10062819664 10517852160 1144672496 12.259 100 19 38 1 1189386752 40563648 4150608
13:05:55 22373 8192000000 10064422208 10533740544 1149333344 12.303 100 10 20 0 1172755456 21137120 2284992
13:07:22 76634 8192000000 10134054896 10589933568 1134224496 12.161 98 8 17 0 1191718848 56019776 1474576
13:09:49 65417 8192000000 10103441824 10574983168 1133380832 12.153 98 22 44 0 1214791312 76537040 4873440
13:11:55 68991 8192000000 10113282816 10574270464 1109888048 11.931 96 22 44 1 1193696240 79275424 4532768
13:14:07 85223 8192000000 10122549856 10656595968 1134328880 12.162 98 26 53 3 1210733328 70398320 6006128
13:16:44 68330 8192000000 10074226624 10505846784 1078318368 11.631 94 26 52 2 1128095264 44504960 5271936
13:18:54 77657 8192000000 10073938320 10529464320 1096632880 11.806 96 19 38 0 1137923008 37674816 3615312
13:21:12 152799 8192000000 10111424608 10610024448 1116135296 11.990 98 14 28 1 1179480384 60768368 2576720
13:25:03 71053 8192000000 10072303776 10532679680 1073286544 11.583 94 16 32 1 1111554192 34915024 3352624

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 23-03-2021 13:28:27 to 23-03-2021 13:28:42.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 111 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:01, from 23-03-2021 13:28:43 to 23-03-2021 13:28:44.

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          231484
MON$OLDEST_ACTIVE               232758
MON$OLDEST_SNAPSHOT             232758
MON$NEXT_TRANSACTION            232758
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                       2083936
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             793
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2390 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2390 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2390 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.2390 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 ---
:256: 
:257: mscs-test-161.budzdorov.ru    Tue Mar 23 13:26:58 2021
:258:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:259: 
:260: 
:261: mscs-test-161.budzdorov.ru    Tue Mar 23 13:27:00 2021
:262:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:263: 
--- end of diff output ---

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

23.03.21 13:28:46. 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-23 13:28:48 No severe PSQL-related problems occured
23.03.21 13:28:49. End of report.