Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.02.2021 10: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 e0e7b46250ba4f9795ceb55e0face73f
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 2020-09-25 13:42
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.00
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 16432656 kB
MemAvailable 31146472 kB
Buffers 0 kB
Cached 15217904 kB
SwapCached 4772 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-02-14 10:26:57
DTS_END                         2021-02-14 10:26:57.1700
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/48518
TRN_ID                          224567
ATT_ID                          602
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: 8986.17 21264 1078340
customer order: creation 164.79 402 19775
customer order: refuse 14.47 357 1736
order to supplier: creation 43.02 1370 5162
order to supplier: removal 3.53 121187 424
invoice (draft): creation 43.43 3573 5211
invoice (draft): removal 52.32 1041 6278
invoice accept: apply 38.45 18663 4614
invoice accept: cancel 47.64 911 5717
customer reserve: creation 3589.53 180 430744
customer reserve: removal 4705.44 71 564653
realization accept: apply 79.96 182 9595
realization accept: cancel 81.48 130 9778
payment from customer: creation 56.38 108 6765
payment from customer: removal 5.58 79 670
payment to supplier: creation 46.91 155 5629
payment to supplier: removal 3.31 97 397
service: total inventory turnovers 3.48 4020 418
service: total monetary turnovers 5.68 520 681
service: aggregate perf. data 0.74 19398 89
service: refresh index statistics 0.03 252828 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3714
TEST_TIME 2 3544
TEST_TIME 3 3939
TEST_TIME 4 4099
TEST_TIME 5 4171
TEST_TIME 6 4107
TEST_TIME 7 3833
TEST_TIME 8 3919
TEST_TIME 9 4293
TEST_TIME 10 4466
TEST_TIME 11 4807
TEST_TIME 12 5331
TEST_TIME 13 5605
TEST_TIME 14 5691
TEST_TIME 15 5941
TEST_TIME 16 6162
TEST_TIME 17 6528
TEST_TIME 18 6747
TEST_TIME 19 6978
TEST_TIME 20 7120
TEST_TIME 21 7181
TEST_TIME 22 7170
TEST_TIME 23 7165
TEST_TIME 24 7592
TEST_TIME 25 7920
TEST_TIME 26 7991
TEST_TIME 27 8263
TEST_TIME 28 8768
TEST_TIME 29 9853
TEST_TIME 30 10381
TEST_TIME 31 10447
TEST_TIME 32 10254
TEST_TIME 33 10247
TEST_TIME 34 10323
TEST_TIME 35 10364
TEST_TIME 36 10306
TEST_TIME 37 10212
TEST_TIME 38 10027
TEST_TIME 39 9922
TEST_TIME 40 9724
TEST_TIME 41 9702
TEST_TIME 42 9656
TEST_TIME 43 9628
TEST_TIME 44 9552
TEST_TIME 45 9608
TEST_TIME 46 9536
TEST_TIME 47 9456
TEST_TIME 48 9429
TEST_TIME 49 9427
TEST_TIME 50 9453
TEST_TIME 51 9393
TEST_TIME 52 9421
TEST_TIME 53 9405
TEST_TIME 54 9369
TEST_TIME 55 9288
TEST_TIME 56 9326
TEST_TIME 57 9429
TEST_TIME 58 9461
TEST_TIME 59 9475
TEST_TIME 60 9411
TEST_TIME 61 9622
TEST_TIME 62 9636
TEST_TIME 63 9620
TEST_TIME 64 9550
TEST_TIME 65 9471
TEST_TIME 66 9404
TEST_TIME 67 9376
TEST_TIME 68 9314
TEST_TIME 69 9266
TEST_TIME 70 9225
TEST_TIME 71 9256
TEST_TIME 72 9297
TEST_TIME 73 9234
TEST_TIME 74 9199
TEST_TIME 75 9209
TEST_TIME 76 9253
TEST_TIME 77 9269
TEST_TIME 78 9299
TEST_TIME 79 9372
TEST_TIME 80 9427
TEST_TIME 81 9456
TEST_TIME 82 9374
TEST_TIME 83 9318
TEST_TIME 84 9267
TEST_TIME 85 9263
TEST_TIME 86 9205
TEST_TIME 87 9162
TEST_TIME 88 9117
TEST_TIME 89 9042
TEST_TIME 90 9010
TEST_TIME 91 9041
TEST_TIME 92 8999
TEST_TIME 93 9027
TEST_TIME 94 9017
TEST_TIME 95 9038
TEST_TIME 96 9079
TEST_TIME 97 9097
TEST_TIME 98 9145
TEST_TIME 99 9108
TEST_TIME 100 9150
TEST_TIME 101 9092
TEST_TIME 102 9180
TEST_TIME 103 9134
TEST_TIME 104 9066
TEST_TIME 105 9017
TEST_TIME 106 8955
TEST_TIME 107 8905
TEST_TIME 108 8938
TEST_TIME 109 8890
TEST_TIME 110 8940
TEST_TIME 111 8906
TEST_TIME 112 8938
TEST_TIME 113 8914
TEST_TIME 114 8919
TEST_TIME 115 8933
TEST_TIME 116 8951
TEST_TIME 117 8947
TEST_TIME 118 8975
TEST_TIME 119 9026
TEST_TIME 120 8990

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-02-2021 10:28:06 to 14-02-2021 10:28:09.

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 1479214 1479214 0 0.00 0 32832 7 0 0
doc_list_biud 1479212 1479212 0 0.00 0 58814 34 0 0
sp_add_invoice_to_stock 4738 4614 124 2.61 29 198827 18663 0 124
sp_cancel_adding_invoice 5717 5717 0 0.00 6 103536 912 0 0
sp_cancel_client_order 1736 1736 0 0.00 5 10876 358 0 0
sp_cancel_customer_reserve 564653 564653 0 0.00 1 28905 71 0 0
sp_cancel_pay_from_customer 670 670 0 0.00 3 2085 79 0 0
sp_cancel_pay_to_supplier 397 397 0 0.00 3 5628 97 0 0
sp_cancel_supplier_invoice 6287 6278 9 0.14 3 36065 1042 0 9
sp_cancel_supplier_order 424 424 0 0.00 11 1287417 121187 0 0
sp_cancel_write_off 9778 9778 0 0.00 1 16474 131 0 0
sp_client_order 19775 19775 0 0.00 4 51530 403 0 0
sp_customer_reserve 430744 430744 0 0.00 0 111821 181 0 0
sp_fill_shopping_cart 30117 30117 0 0.00 1 19947 42 0 0
sp_fill_shopping_cart_clo_res 430730 430730 0 0.00 0 28904 17 0 0
sp_get_clo_for_invoice 4600 4600 0 0.00 0 27909 384 0 0
sp_kill_cost_storno 16562 16562 0 0.00 0 32824 69 0 0
sp_kill_qstorno_ret_qs2qd 1145786 1145786 0 0.00 0 58813 39 0 0
sp_kill_qty_storno 602781 602781 0 0.00 0 58813 83 0 0
sp_lock_selected_doc 19812 19812 0 0.00 0 9558 5 0 0
sp_make_cost_storno 26589 26589 0 0.00 0 17608 61 0 0
sp_multiply_rows_for_pdistr 26589 26589 0 0.00 0 18193 35 0 0
sp_multiply_rows_for_qdistr 19775 19775 0 0.00 0 10362 225 0 0
sp_pay_from_customer 6765 6765 0 0.00 4 17116 108 0 0
sp_pay_to_supplier 5629 5629 0 0.00 5 18126 155 0 0
sp_payment_common 12394 12394 0 0.00 1 16846 30 0 0
sp_qd_handle_on_cancel_clo 1736 1736 0 0.00 0 10870 328 0 0
sp_qd_handle_on_invoice_upd_sts 10317 10317 0 0.00 0 22592 383 0 0
sp_qd_handle_on_reserve_upd_sts 9595 9595 0 0.00 0 20573 52 0 0
sp_reserve_write_off 9595 9595 0 0.00 7 20641 183 0 0
sp_supplier_invoice 5211 5211 0 0.00 7 149726 3573 0 0
sp_supplier_order 5162 5162 0 0.00 5 29134 1371 0 0
srv_aggregate_perf_data 89 89 0 0.00 3102 136632 19399 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1929 153793 47920 0 0
srv_find_qd_qs_mism 1034279 1034279 0 0.00 0 9820 6 0 0
srv_make_invnt_saldo 418 418 0 0.00 88 137156 4020 0 0
srv_make_money_saldo 681 681 0 0.00 1 12292 521 0 0
srv_recalc_idx_stat 4 4 0 0.00 180827 353414 252828 0 0
t$perf-abend:sp_add_invoice_to_stock 124 124 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4614 4614 0 0.00 0 15583 182 0 0
t$perf-norm:sp_cancel_adding_invoice 422 422 0 0.00 0 2875 90 0 0
t$perf-norm:sp_cancel_client_order 1736 1736 0 0.00 0 6386 15 0 0
t$perf-norm:sp_cancel_customer_reserve 646 646 0 0.00 0 660 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 670 670 0 0.00 0 769 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 397 397 0 0.00 0 3144 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 367 367 0 0.00 0 1042 14 0 0
t$perf-norm:sp_cancel_supplier_order 424 424 0 0.00 0 40460 1584 0 0
t$perf-norm:sp_cancel_write_off 961 961 0 0.00 0 1215 6 0 0
t$perf-norm:sp_client_order 19775 19775 0 0.00 0 14871 22 0 0
t$perf-norm:sp_customer_reserve 1006 1006 0 0.00 0 2686 7 0 0
t$perf-norm:sp_pay_from_customer 6765 6765 0 0.00 0 6995 10 0 0
t$perf-norm:sp_pay_to_supplier 5629 5629 0 0.00 0 3730 7 0 0
t$perf-norm:sp_reserve_write_off 9595 9595 0 0.00 0 15528 14 0 0
t$perf-norm:sp_supplier_invoice 5211 5211 0 0.00 0 15905 25 0 0
t$perf-norm:sp_supplier_order 5162 5162 0 0.00 0 13080 22 0 0
t$perf-norm:srv_aggregate_perf_data 89 89 0 0.00 0 97 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 2050 38 0 0
t$perf-norm:srv_make_invnt_saldo 418 418 0 0.00 0 669 6 0 0
t$perf-norm:srv_make_money_saldo 681 681 0 0.00 0 2452 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 12 4 0 0
v_add_invoice_to_stock 9434 9186 248 2.62 0 10721 19 0 248
v_all_customers 36080 36080 0 0.00 0 153 1 0 0
v_all_suppliers 29148 29148 0 0.00 0 25 1 0 0
v_all_wares 39546 39546 0 0.00 0 24 1 0 0
v_cancel_adding_invoice 844 844 0 0.00 0 1173 9 0 0
v_cancel_client_order 3472 3472 0 0.00 0 5278 8 0 0
v_cancel_customer_prepayment 1340 1340 0 0.00 0 1630 7 0 0
v_cancel_customer_reserve 1292 1292 0 0.00 0 2384 8 0 0
v_cancel_payment_to_supplier 794 794 0 0.00 0 1045 8 0 0
v_cancel_supplier_invoice 752 734 18 2.39 0 1486 19 0 18
v_cancel_supplier_order 848 848 0 0.00 0 3883 15 0 0
v_cancel_write_off 1922 1922 0 0.00 0 16464 24 0 0
v_max_id_clo_ord 5145 5145 0 0.00 0 239 1 0 0
v_max_id_clo_res 1006 1006 0 0.00 0 2013 14 0 0
v_max_id_ord_sup 5198 5198 0 0.00 0 2050 3 0 0
v_max_non_paid_invoice 1398 1398 0 0.00 0 48 2 0 0
v_max_non_paid_realizn 3576 3576 0 0.00 0 1748 3 0 0
v_min_id_clo_ord 5145 5145 0 0.00 0 4453 8 0 0
v_min_id_clo_res 1006 1006 0 0.00 0 5356 15 0 0
v_min_id_ord_sup 5198 5198 0 0.00 0 10978 12 0 0
v_min_non_paid_invoice 5629 5629 0 0.00 0 15627 31 0 0
v_min_non_paid_realizn 6765 6765 0 0.00 0 16798 18 0 0
v_our_firm 9844 9844 0 0.00 0 14 1 0 0
v_reserve_write_off 19188 19188 0 0.00 0 4561 6 0 0
x_lock_depdocs_on_canc_invoice 11628 11628 0 0.00 0 12051 126 0 0
x_lock_depdocs_on_canc_sup_ord 424 424 0 0.00 0 16359 417 0 0
x_make_qstorno_1000_1200 5162 5162 0 0.00 2 29111 1284 0 0
x_make_qstorno_1200_2000 5211 5211 0 0.00 4 149681 3417 0 0
x_make_qstorno_2100_3300 430744 430744 0 0.00 0 98536 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-02-2021 10:28:10 to 14-02-2021 10:28:14.

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
07:57:00 4 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
07:58:01 17102 8192000000 9731739296 10029096960 808910688 8.986 100 90 180 0 837369632 7278688 21180256
07:59:29 15711 8192000000 9766326080 10073059328 840209856 9.302 100 90 180 0 874595456 13338528 21047072
08:00:52 20325 8192000000 9797745888 10115993600 863824496 9.538 100 85 171 0 904734672 21257120 19653056
08:02:16 12620 8192000000 9866255584 10194980864 905110768 9.949 100 87 174 0 976194768 51633088 19450912
08:03:34 4828 8192000000 9903151008 10240634880 944958176 10.342 100 83 167 0 1017039888 54388016 17693696
08:04:40 9501 8192000000 9962395040 10310885376 982071872 10.704 100 88 174 2 1075344048 75114016 18158160
08:05:52 5582 8192000000 9983013136 10340585472 1012019904 10.995 100 87 175 3 1090761344 61323440 17418000
08:07:00 2848 8192000000 10018527808 10381021184 1031578416 11.184 100 81 161 1 1124748944 76979328 16191200
08:08:05 2061 8192000000 10024960080 10394542080 1053728384 11.396 100 83 166 0 1137897328 67081648 17087296
08:09:10 2641 8192000000 10044716912 10418417664 1066789376 11.521 100 82 164 0 1155739728 72239392 16710960
08:10:15 2014 8192000000 10018907952 10392649728 1070222448 11.554 100 90 179 2 1133403792 44693328 18488016
08:11:20 5431 8192000000 10013447696 10410754048 1077859680 11.627 100 46 92 1 1132801856 45492224 9449952
08:12:26 6306 8192000000 9995054208 10385403904 1080873840 11.656 100 41 82 2 1112752784 22643184 9235760
08:13:37 4485 8192000000 10027865360 10420977664 1092285536 11.764 100 55 109 2 1150412256 46422160 11704560
08:14:42 43372 8192000000 10010642752 10397356032 1081548336 11.662 99 49 98 1 1104566944 12897312 10121296
08:16:27 5827 8192000000 10005380176 10406850560 1090162288 11.744 100 35 71 2 1118442544 20796624 7483632
08:17:35 7788 8192000000 10005416160 10412085248 1088576240 11.729 99 23 46 1 1114985104 21531344 4877520
08:18:46 8592 8192000000 9988332448 10370842624 1062712688 11.482 99 27 54 1 1091243104 21698736 6831680
08:19:57 43501 8192000000 9937925008 10296143872 963604944 10.524 92 70 143 1 999545856 20416848 15524064
08:21:40 7253 8192000000 9952432128 10354192384 1032720608 11.195 100 53 104 2 1082165296 38159248 11285440
08:22:50 7655 8192000000 9927668832 10315800576 976740992 10.652 94 27 53 0 1000344672 17809392 5794288
08:24:02 13460 8192000000 9943397008 10338541568 1004435072 10.922 96 41 82 2 1032810640 19222064 9153504
08:25:16 33982 8192000000 9959976112 10361745408 1053601344 11.395 100 18 34 2 1087895488 30687872 3606272
08:26:53 5497 8192000000 9998453664 10391339008 1071517328 11.567 100 45 87 1 1096931200 15449120 9964752
08:28:29 29755 8192000000 10035896640 10437697536 1108437024 11.918 100 48 93 3 1156860544 37482784 10940736
08:30:07 116854 8192000000 10097945840 10536067072 1130343088 12.125 100 26 52 0 1199579440 63734800 5501552
08:33:09 26632 8192000000 10120055456 10534866944 1142850368 12.242 100 42 82 2 1227952320 75667488 9434464
08:34:48 10261 8192000000 10180486704 10593267712 1152941056 12.337 100 53 104 2 1284657024 120470800 11245168
08:36:07 4183 8192000000 10201830768 10615263232 1151987776 12.328 100 56 112 0 1311528208 147473760 12066672
08:37:16 3982 8192000000 10246082688 10655588352 1160752368 12.410 100 68 136 0 1346548128 171379616 14416144
08:38:25 9819 8192000000 10246179376 10663067648 1176291968 12.556 100 58 114 3 1350957040 162295520 12369552
08:39:39 1928 8192000000 10314098528 10722062336 1178882176 12.580 100 83 166 0 1403924880 206334784 18707920
08:40:48 4612 8192000000 10326617312 10739298304 1191485440 12.697 100 71 141 1 1424588992 217959408 15144144
08:42:02 2194 8192000000 10351410912 10760003584 1176517424 12.558 99 79 155 2 1448721488 255054848 17149216
08:43:11 2464 8192000000 10409746448 10821562368 1197499200 12.753 100 82 162 1 1513262160 296580032 19182928
08:44:20 2324 8192000000 10406090208 10814169088 1193040576 12.712 100 90 180 3 1502029968 288500912 20488480
08:45:28 2368 8192000000 10435478048 10839339008 1173366800 12.528 100 88 176 0 1527365888 334833840 19165248
08:46:36 17974 8192000000 10438243984 10862026752 1172172544 12.517 100 41 82 1 1530664992 347970192 10522256
08:48:01 4016 8192000000 10461958720 10877845504 1163450880 12.436 100 59 117 2 1560036560 382184720 14400960
08:49:18 4479 8192000000 10535489456 10966114304 1167437136 12.473 100 55 109 1 1633747104 452019072 14290896
08:50:37 3466 8192000000 10521674160 10949079040 1164118768 12.442 100 51 103 2 1620829072 443667136 13043168
08:51:49 4759 8192000000 10504089008 10970890240 1158321792 12.388 100 44 86 1 1612071792 442260256 11489744
08:53:03 12531 8192000000 10547887968 10991271936 1152577168 12.334 100 39 78 0 1629954672 466958880 10418624
08:54:26 18321 8192000000 10476138736 10948165632 1149651680 12.306 100 28 56 1 1576332432 418777344 7903408
08:55:48 36036 8192000000 10182955248 10671800320 1112298192 11.954 98 18 36 1 1282093152 165536080 4258880
08:57:38 90968 8192000000 10082645392 10518732800 1036802640 11.234 91 28 56 0 1079162208 36301440 6058128
09:00:19 81682 8192000000 10100781040 10560176128 1133518288 12.155 100 12 24 1 1204601584 68476640 2606656
09:02:42 83947 8192000000 10080376480 10502049792 1100681840 11.844 97 16 30 0 1140127776 36063776 3382160
09:05:08 153792 8192000000 10091035504 10516611072 1115332496 11.983 98 45 91 0 1166824400 41814928 9676976
09:08:44 51100 8192000000 10104395568 10569895936 1140801552 12.223 100 43 85 2 1211033984 60978624 9253808
09:10:45 46460 8192000000 10095011136 10588553216 1144670208 12.259 100 18 36 0 1210185504 61362288 4153008
09:12:37 40052 8192000000 10103601680 10576723968 1134300848 12.162 99 10 21 0 1196789840 60268304 2220688
09:14:17 41832 8192000000 10138637136 10598809600 1132899472 12.149 99 25 50 0 1217588096 78201376 6487248
09:16:02 52784 8192000000 10100596624 10597859328 1147457952 12.286 100 12 24 0 1228754624 78487952 2808720
09:18:59 33199 8192000000 10078010000 10533355520 1149994832 12.309 100 16 31 1 1178559216 25431168 3133216
09:20:36 28177 8192000000 10074827168 10523549696 1111404128 11.946 97 13 26 0 1155161632 41008144 2749360
09:22:29 60666 8192000000 10129995712 10621018112 1121847264 12.044 98 22 44 1 1224427280 97619888 4960128
09:24:31 49040 8192000000 10131550816 10591285248 1142246608 12.237 99 23 46 3 1222895520 75338768 5310144
09:26:37 50353 8192000000 10158170848 10649341952 1154563840 12.352 100 27 54 0 1277838000 117705808 5568352
09:28:35 67869 8192000000 10046303680 10490818560 1042193600 11.286 91 18 32 2 1058911216 13034560 3683056
09:30:50 84659 8192000000 10091552704 10554994688 1133113632 12.151 100 16 32 0 1208080816 71201520 3765664
09:33:23 32987 8192000000 10066519216 10559856640 1123163104 12.057 99 11 22 0 1195487408 70040352 2283952
09:34:56 95221 8192000000 10101985776 10564026368 1130473344 12.126 99 14 28 1 1178186272 44678096 3034832
09:37:39 92408 8192000000 10080159152 10567348224 1109889120 11.931 97 10 20 0 1169291136 57127456 2274560
09:40:32 52228 8192000000 10082394368 10557095936 1097085200 11.810 96 19 38 0 1162083152 60584032 4413920
09:42:27 45402 8192000000 10063802256 10517241856 1071973216 11.571 95 19 38 1 1125139424 49267904 3898304
09:44:27 31364 8192000000 10131465696 10576154624 1118259280 12.011 100 40 80 0 1203127936 76600256 8268400
09:46:08 121862 8192000000 10109328928 10587140096 1103638176 11.872 98 17 34 1 1158495120 51468336 3388608
09:49:15 79495 8192000000 10055241472 10492477440 1116652160 11.995 99 19 37 2 1170440784 49834352 3954272
09:51:35 81797 8192000000 10050524944 10543161344 1105106816 11.886 98 8 16 2 1151697824 45007472 1583536
09:53:57 100750 8192000000 10095618224 10556846080 1122138592 12.047 99 10 20 0 1183430512 59300432 1991488
09:56:40 56380 8192000000 10061391728 10546159616 1116341904 11.992 99 18 36 1 1192989872 72984800 3663168
09:58:44 38355 8192000000 10125022960 10573877248 1117239728 12.001 98 22 44 2 1193224464 71589472 4395264
10:00:43 66111 8192000000 10067424272 10511818752 1097531456 11.814 97 19 38 1 1148935952 47500272 3904224
10:02:51 39580 8192000000 10123088688 10561736704 1137868608 12.195 100 23 46 0 1201389504 58760880 4760016
10:04:31 83573 8192000000 10076600368 10501599232 1085657392 11.701 95 31 62 1 1139679024 47515648 6505984
10:07:04 47928 8192000000 10115857840 10551865344 1132329200 12.143 99 25 49 0 1184864832 47699312 4836320
10:09:02 99682 8192000000 10058882128 10523701248 1112498240 11.956 98 23 46 1 1164231712 47064144 4669328
10:11:46 108023 8192000000 10107392896 10578403328 1115536848 11.985 98 11 22 0 1192417232 74295552 2584832
10:14:40 55701 8192000000 10099168384 10578403328 1135493872 12.173 100 9 18 0 1209543648 71863792 2185984
10:16:36 53050 8192000000 10088576544 10566008832 1119009568 12.018 98 25 50 0 1186404752 61858080 5537104
10:18:34 60532 8192000000 10068799584 10506256384 1086344288 11.708 96 18 36 0 1123689520 33447488 3897744
10:20:44 44398 8192000000 10126608240 10563284992 1138163280 12.198 100 26 52 1 1194811200 51168448 5479472
10:22:31 48342 8192000000 10079115824 10512531456 1107403040 11.908 97 30 60 0 1131711232 18150240 6157952
10:24:23 94754 8192000000 10081555696 10615046144 1097276624 11.812 97 9 18 0 1156577296 57489632 1811040

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-02-2021 10:28:16 to 14-02-2021 10:28:31.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 124 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-02-2021 10:28:32 to 14-02-2021 10:28:34.

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          223384
MON$OLDEST_ACTIVE               224822
MON$OLDEST_SNAPSHOT             224822
MON$NEXT_TRANSACTION            224822
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                       2108416
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             779
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2365 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2365 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2365 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.2365 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 ---
:423: 
:424: mscs-test-161.budzdorov.ru    Sun Feb 14 10:26:59 2021
:425:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:426: 
:427: 
:428: mscs-test-161.budzdorov.ru    Sun Feb 14 10:27:00 2021
:429:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:430: 
:431: 
:432: mscs-test-161.budzdorov.ru    Sun Feb 14 10:27:01 2021
:433:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:434: 
:435: 
:436: mscs-test-161.budzdorov.ru    Sun Feb 14 10:27:01 2021
:437:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:438: 
--- end of diff output ---

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

14.02.21 10:28:35. 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-02-14 10:28:37 No severe PSQL-related problems occured
14.02.21 10:28:38. End of report.