Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.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 16842308 kB
MemAvailable 31476440 kB
Buffers 0 kB
Cached 14788780 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-26 13:26:59
DTS_END                         2021-03-26 13:26:59.1310
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> X_MAKE_QSTORNO_1000_1200(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/57802
TRN_ID                          233122
ATT_ID                          622
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: 9084.02 25826 1090082
customer order: creation 165.86 472 19903
customer order: refuse 14.50 328 1740
order to supplier: creation 44.64 1550 5357
order to supplier: removal 3.41 115497 409
invoice (draft): creation 44.83 3459 5380
invoice (draft): removal 51.79 842 6215
invoice accept: apply 39.83 17681 4779
invoice accept: cancel 47.01 869 5641
customer reserve: creation 3732.17 171 447860
customer reserve: removal 4646.04 68 557525
realization accept: apply 82.28 195 9873
realization accept: cancel 85.53 121 10264
payment from customer: creation 57.49 130 6899
payment from customer: removal 5.92 87 710
payment to supplier: creation 48.77 147 5852
payment to supplier: removal 3.20 100 384
service: total inventory turnovers 3.95 3297 474
service: total monetary turnovers 5.83 591 699
service: aggregate perf. data 0.95 12424 114
service: refresh index statistics 0.03 358487 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 26-03-2021 13:27:57 to 26-03-2021 13:28:00.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1665
TEST_TIME 2 2117
TEST_TIME 3 2329
TEST_TIME 4 3628
TEST_TIME 5 3380
TEST_TIME 6 3784
TEST_TIME 7 4429
TEST_TIME 8 5006
TEST_TIME 9 5185
TEST_TIME 10 5362
TEST_TIME 11 5721
TEST_TIME 12 5862
TEST_TIME 13 5996
TEST_TIME 14 6028
TEST_TIME 15 6189
TEST_TIME 16 6537
TEST_TIME 17 6638
TEST_TIME 18 6888
TEST_TIME 19 7316
TEST_TIME 20 7364
TEST_TIME 21 8270
TEST_TIME 22 9140
TEST_TIME 23 9434
TEST_TIME 24 9393
TEST_TIME 25 9677
TEST_TIME 26 9780
TEST_TIME 27 10643
TEST_TIME 28 10937
TEST_TIME 29 10867
TEST_TIME 30 10716
TEST_TIME 31 10708
TEST_TIME 32 10649
TEST_TIME 33 10402
TEST_TIME 34 10199
TEST_TIME 35 10177
TEST_TIME 36 10098
TEST_TIME 37 9982
TEST_TIME 38 10039
TEST_TIME 39 9872
TEST_TIME 40 9781
TEST_TIME 41 9796
TEST_TIME 42 9734
TEST_TIME 43 9714
TEST_TIME 44 9738
TEST_TIME 45 9857
TEST_TIME 46 9979
TEST_TIME 47 9977
TEST_TIME 48 10023
TEST_TIME 49 10093
TEST_TIME 50 10070
TEST_TIME 51 10179
TEST_TIME 52 10115
TEST_TIME 53 10066
TEST_TIME 54 9883
TEST_TIME 55 9813
TEST_TIME 56 9733
TEST_TIME 57 9666
TEST_TIME 58 9562
TEST_TIME 59 9513
TEST_TIME 60 9606
TEST_TIME 61 9561
TEST_TIME 62 9525
TEST_TIME 63 9495
TEST_TIME 64 9512
TEST_TIME 65 9556
TEST_TIME 66 9540
TEST_TIME 67 9548
TEST_TIME 68 9645
TEST_TIME 69 9597
TEST_TIME 70 9575
TEST_TIME 71 9578
TEST_TIME 72 9674
TEST_TIME 73 9642
TEST_TIME 74 9517
TEST_TIME 75 9478
TEST_TIME 76 9414
TEST_TIME 77 9341
TEST_TIME 78 9364
TEST_TIME 79 9311
TEST_TIME 80 9322
TEST_TIME 81 9306
TEST_TIME 82 9305
TEST_TIME 83 9271
TEST_TIME 84 9283
TEST_TIME 85 9357
TEST_TIME 86 9488
TEST_TIME 87 9471
TEST_TIME 88 9429
TEST_TIME 89 9471
TEST_TIME 90 9540
TEST_TIME 91 9438
TEST_TIME 92 9365
TEST_TIME 93 9315
TEST_TIME 94 9321
TEST_TIME 95 9307
TEST_TIME 96 9281
TEST_TIME 97 9270
TEST_TIME 98 9241
TEST_TIME 99 9281
TEST_TIME 100 9330
TEST_TIME 101 9357
TEST_TIME 102 9319
TEST_TIME 103 9282
TEST_TIME 104 9314
TEST_TIME 105 9330
TEST_TIME 106 9373
TEST_TIME 107 9336
TEST_TIME 108 9338
TEST_TIME 109 9304
TEST_TIME 110 9241
TEST_TIME 111 9212
TEST_TIME 112 9171
TEST_TIME 113 9132
TEST_TIME 114 9094
TEST_TIME 115 9110
TEST_TIME 116 9098
TEST_TIME 117 9078
TEST_TIME 118 9093
TEST_TIME 119 9080
TEST_TIME 120 9085
TEST_TIME 121 9010

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 26-03-2021 13:28:01 to 26-03-2021 13:28:04.

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 1504296 1504296 0 0.00 0 19435 7 0 0
doc_list_biud 1504266 1504266 0 0.00 0 71065 31 0 0
sp_add_invoice_to_stock 4878 4779 99 2.02 27 217931 17682 0 99
sp_cancel_adding_invoice 5641 5641 0 0.00 6 95239 869 0 0
sp_cancel_client_order 1740 1740 0 0.00 5 12960 329 0 0
sp_cancel_customer_reserve 557525 557525 0 0.00 1 71068 69 0 0
sp_cancel_pay_from_customer 710 710 0 0.00 3 6320 88 0 0
sp_cancel_pay_to_supplier 384 384 0 0.00 3 3010 101 0 0
sp_cancel_supplier_invoice 6222 6215 7 0.11 3 16817 842 0 7
sp_cancel_supplier_order 409 409 0 0.00 11 1131900 115497 0 0
sp_cancel_write_off 10264 10264 0 0.00 1 12596 121 0 0
sp_client_order 19903 19903 0 0.00 4 66117 473 0 0
sp_customer_reserve 447860 447860 0 0.00 0 63404 171 0 0
sp_fill_shopping_cart 30627 30627 0 0.00 1 16291 44 0 0
sp_fill_shopping_cart_clo_res 447856 447856 0 0.00 0 37186 18 0 0
sp_get_clo_for_invoice 4773 4773 0 0.00 0 27208 419 0 0
sp_kill_cost_storno 16999 16999 0 0.00 0 7701 51 0 0
sp_kill_qstorno_ret_qs2qd 1131938 1131938 0 0.00 0 71053 37 0 0
sp_kill_qty_storno 596430 596430 0 0.00 0 71064 78 0 0
sp_lock_selected_doc 20473 20473 0 0.00 0 10208 6 0 0
sp_make_cost_storno 27397 27397 0 0.00 0 14047 72 0 0
sp_multiply_rows_for_pdistr 27393 27393 0 0.00 0 11477 30 0 0
sp_multiply_rows_for_qdistr 19902 19902 0 0.00 0 66102 267 0 0
sp_pay_from_customer 6899 6899 0 0.00 4 14959 131 0 0
sp_pay_to_supplier 5852 5852 0 0.00 5 11740 148 0 0
sp_payment_common 12748 12748 0 0.00 1 7870 21 0 0
sp_qd_handle_on_cancel_clo 1740 1740 0 0.00 0 12928 297 0 0
sp_qd_handle_on_invoice_upd_sts 10412 10412 0 0.00 0 27749 344 0 0
sp_qd_handle_on_reserve_upd_sts 9865 9865 0 0.00 0 13581 53 0 0
sp_reserve_write_off 9873 9873 0 0.00 7 14555 196 0 0
sp_supplier_invoice 5380 5380 0 0.00 6 87927 3459 0 0
sp_supplier_order 5357 5357 0 0.00 6 27308 1551 0 0
srv_aggregate_perf_data 114 114 0 0.00 2646 103840 12424 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1355 128855 47914 0 0
srv_find_qd_qs_mism 1043436 1043436 0 0.00 0 19435 5 0 0
srv_make_invnt_saldo 474 474 0 0.00 65 104451 3298 0 0
srv_make_money_saldo 699 699 0 0.00 1 15480 592 0 0
srv_recalc_idx_stat 4 4 0 0.00 198351 443303 358488 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 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4779 4779 0 0.00 0 16022 169 0 0
t$perf-norm:sp_cancel_adding_invoice 413 413 0 0.00 1 8147 110 0 0
t$perf-norm:sp_cancel_client_order 1740 1740 0 0.00 0 1832 10 0 0
t$perf-norm:sp_cancel_customer_reserve 703 703 0 0.00 0 388 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 710 710 0 0.00 0 8023 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 384 384 0 0.00 0 292 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 399 399 0 0.00 0 1787 16 0 0
t$perf-norm:sp_cancel_supplier_order 409 409 0 0.00 0 15518 1350 0 0
t$perf-norm:sp_cancel_write_off 1079 1079 0 0.00 0 3199 11 0 0
t$perf-norm:sp_client_order 19903 19903 0 0.00 0 7706 15 0 0
t$perf-norm:sp_customer_reserve 988 988 0 0.00 0 2264 7 0 0
t$perf-norm:sp_pay_from_customer 6899 6899 0 0.00 0 7469 10 0 0
t$perf-norm:sp_pay_to_supplier 5852 5852 0 0.00 0 7193 9 0 0
t$perf-norm:sp_reserve_write_off 9873 9873 0 0.00 0 5800 10 0 0
t$perf-norm:sp_supplier_invoice 5380 5380 0 0.00 0 5360 16 0 0
t$perf-norm:sp_supplier_order 5357 5357 0 0.00 0 5408 15 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 4450 53 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 53 2 0 0
t$perf-norm:srv_make_invnt_saldo 474 474 0 0.00 0 466 7 0 0
t$perf-norm:srv_make_money_saldo 699 699 0 0.00 0 236 3 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 9736 9538 198 2.03 0 6409 15 0 198
v_all_customers 36486 36486 0 0.00 0 143 1 0 0
v_all_suppliers 30296 30296 0 0.00 0 143 1 0 0
v_all_wares 39786 39786 0 0.00 0 76 1 0 0
v_cancel_adding_invoice 826 826 0 0.00 0 1139 8 0 0
v_cancel_client_order 3480 3480 0 0.00 0 4920 9 0 0
v_cancel_customer_prepayment 1420 1420 0 0.00 0 3637 7 0 0
v_cancel_customer_reserve 1406 1406 0 0.00 0 3680 6 0 0
v_cancel_payment_to_supplier 768 768 0 0.00 0 2875 14 0 0
v_cancel_supplier_invoice 812 798 14 1.72 0 4548 26 0 14
v_cancel_supplier_order 818 818 0 0.00 0 3887 16 0 0
v_cancel_write_off 2158 2158 0 0.00 0 3848 15 0 0
v_max_id_clo_ord 5355 5355 0 0.00 0 781 1 0 0
v_max_id_clo_res 988 988 0 0.00 0 22365 52 0 0
v_max_id_ord_sup 5379 5379 0 0.00 0 5952 4 0 0
v_max_non_paid_invoice 1437 1437 0 0.00 0 4244 5 0 0
v_max_non_paid_realizn 3630 3630 0 0.00 0 5612 4 0 0
v_min_id_clo_ord 5355 5355 0 0.00 0 1723 6 0 0
v_min_id_clo_res 988 988 0 0.00 0 5777 21 0 0
v_min_id_ord_sup 5379 5379 0 0.00 0 16278 17 0 0
v_min_non_paid_invoice 5851 5851 0 0.00 0 6637 10 0 0
v_min_non_paid_realizn 6896 6896 0 0.00 0 5105 13 0 0
v_our_firm 9832 9832 0 0.00 0 11 1 0 0
v_reserve_write_off 19730 19730 0 0.00 0 5019 9 0 0
x_lock_depdocs_on_canc_invoice 11457 11457 0 0.00 0 22263 128 0 0
x_lock_depdocs_on_canc_sup_ord 409 409 0 0.00 0 23816 393 0 0
x_make_qstorno_1000_1200 5357 5357 0 0.00 2 27292 1477 0 0
x_make_qstorno_1200_2000 5380 5380 0 0.00 3 87915 3287 0 0
x_make_qstorno_2100_3300 447860 447860 0 0.00 0 63403 151 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 26-03-2021 13:28:05 to 26-03-2021 13:28:10.

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:02 1 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
10:58:03 14423 8192000000 9709625248 9999802368 786161072 8.756 100 89 179 1 815486976 7339600 21986304
10:59:24 28072 8192000000 9754884480 10059681792 827812064 9.177 100 89 178 0 860956144 11499072 21645008
11:00:56 15872 8192000000 9795154896 10109575168 856390960 9.464 100 80 160 0 896676992 21327808 18958224
11:02:19 14992 8192000000 9878609504 10214735872 924797984 10.143 100 82 164 0 987487808 44746992 17942832
11:03:37 9769 8192000000 9913634480 10255536128 964832704 10.536 100 81 162 1 1030390832 48794736 16763392
11:04:51 4573 8192000000 9970010864 10327883776 994170544 10.822 100 78 156 0 1088455952 78459888 15825520
11:06:01 2687 8192000000 9999490912 10359476224 1026083536 11.131 100 86 172 1 1107491360 62946336 18461488
11:07:06 1810 8192000000 10027438080 10393743360 1051263904 11.373 100 93 186 0 1144609616 74479264 18866448
11:08:10 1780 8192000000 10030239904 10397855744 1061592416 11.472 100 90 180 0 1133106096 53185248 18328432
11:09:14 27089 8192000000 9986546496 10373713920 1071449408 11.566 100 47 94 1 1163764112 82546848 9767856
11:10:42 5060 8192000000 9972549456 10372251648 1074732688 11.597 100 27 50 5 1088779808 8582640 5464480
11:11:50 9685 8192000000 9998897696 10400972800 1081067376 11.658 100 33 65 3 1122236144 33256992 7911776
11:13:02 47291 8192000000 10013101440 10424733696 1089748816 11.740 100 27 54 1 1118854272 23652256 5453200
11:14:52 4141 8192000000 10021707568 10411847680 1093057312 11.772 100 41 82 4 1132062064 30110256 8894496
11:15:56 5580 8192000000 10002575632 10406559744 1091347792 11.755 100 38 76 2 1117578720 18520016 7710912
11:17:05 5611 8192000000 10004937408 10404536320 1080047648 11.648 99 44 90 0 1109333312 19181760 10103904
11:18:15 34996 8192000000 9971160816 10377728000 1012036304 10.995 93 18 36 0 1031755600 15614384 4104912
11:19:50 9289 8192000000 9933004640 10326044672 992274672 10.804 94 30 58 5 1022336144 23362672 6698800
11:21:01 6901 8192000000 9952788800 10331746304 1007883792 10.955 97 36 72 1 1044790320 29271552 7634976
11:22:08 55441 8192000000 9945798768 10327527424 1005036128 10.927 97 27 53 2 1029416304 18419984 5960192
11:24:07 9825 8192000000 9954765504 10334289920 1043452992 11.298 100 39 81 0 1065341584 12880992 9007600
11:25:19 3532 8192000000 9975093680 10361790464 1054440640 11.403 100 57 113 2 1083300992 15639728 13220624
11:26:24 13993 8192000000 9977147840 10387697664 1065027808 11.505 100 18 36 0 1096875408 27769824 4077776
11:27:41 61268 8192000000 10015703248 10418417664 1088722768 11.731 100 38 73 1 1128992976 32584016 7686192
11:29:59 8901 8192000000 10075829232 10479767552 1110435568 11.937 100 42 83 2 1180186048 60928832 8821648
11:31:12 34340 8192000000 10101470240 10510229504 1127564640 12.098 100 36 71 0 1191546688 56877504 7104544
11:32:53 25295 8192000000 10157085072 10568286208 1136371008 12.181 100 41 82 0 1271418080 126039392 9007680
11:34:25 11660 8192000000 10190533024 10604875776 1151221792 12.321 100 53 106 1 1297132752 133662928 12248032
11:35:44 8727 8192000000 10227253600 10643554304 1163919888 12.440 100 57 113 0 1335458528 159129568 12409072
11:36:55 5261 8192000000 10256009920 10664996864 1170679088 12.503 100 79 158 2 1360527008 171529392 18318528
11:38:04 3837 8192000000 10267082816 10689081344 1179321664 12.584 100 59 116 0 1381643984 189256784 13065536
11:39:12 8373 8192000000 10334864592 10753523712 1190322432 12.686 100 61 121 0 1436232144 232156816 13752896
11:40:32 3002 8192000000 10369260720 10779717632 1179435440 12.585 100 80 158 1 1468561696 270981072 18145184
11:41:42 3390 8192000000 10395827376 10801152000 1161198768 12.414 99 85 167 0 1488199216 307647504 19352944
11:42:50 1745 8192000000 10409196400 10820300800 1158704704 12.391 100 69 139 0 1512993792 338787648 15501440
11:43:57 1353 8192000000 10447468992 10852347904 1157470480 12.380 100 91 182 2 1541442400 362273360 21698560
11:45:06 2502 8192000000 10451086304 10859724800 1151497536 12.324 100 82 164 0 1545590912 374167088 19926288
11:46:14 9845 8192000000 10427698432 10867974144 1137193360 12.189 99 40 80 0 1531636672 383431440 11011872
11:47:29 3523 8192000000 10381727712 10811469824 1137871232 12.196 100 45 88 3 1484106048 334914640 11320176
11:48:34 26166 8192000000 10263026544 10742005760 1123248016 12.058 98 28 53 2 1383228544 252524192 7456336
11:50:07 17559 8192000000 10267440624 10736349184 1143439056 12.248 100 26 52 2 1370784496 221083232 6262208
11:51:26 33944 8192000000 10260264528 10705928192 1136443472 12.182 100 18 36 4 1361922512 220813008 4666032
11:53:01 24560 8192000000 10225113808 10747715584 1134982928 12.168 100 16 32 0 1388019552 248930144 4106480
11:54:35 60430 8192000000 10056789120 10523791360 1125022688 12.074 100 7 14 0 1198296032 71862640 1410704
11:56:38 87378 8192000000 10075335472 10567196672 1116611264 11.995 99 22 44 1 1197046960 76113664 4322032
11:59:50 69409 8192000000 10064813056 10504364032 1128283312 12.105 99 20 40 1 1160618704 28485600 3849792
12:02:01 35513 8192000000 10096348368 10553827328 1134673136 12.165 99 41 83 2 1203702496 59839440 9189920
12:03:47 52871 8192000000 10131281536 10619465728 1143264144 12.246 100 21 42 0 1260015680 111637296 5114240
12:05:45 83614 8192000000 10148956688 10613010432 1141706512 12.232 99 22 45 1 1258745696 111087600 5951584
12:08:12 75118 8192000000 10158369392 10616143872 1123882224 12.064 98 19 38 1 1225403120 97155232 4365664
12:10:30 49847 8192000000 10151544496 10642509824 1150540624 12.315 100 19 38 1 1293883440 138396592 4946224
12:12:28 60128 8192000000 10148317936 10646679552 1139136192 12.207 98 28 56 2 1270006416 124626256 6243968
12:14:29 55700 8192000000 10128315024 10585964544 1139755392 12.213 99 26 52 0 1231368944 85546976 6066576
12:16:25 98891 8192000000 10113963600 10617245696 1153629008 12.344 100 9 18 1 1207930944 52672848 1629088
12:19:04 13536 8192000000 10111541952 10550882304 1156127968 12.367 100 37 74 0 1216526400 52954272 7444160
12:20:20 74769 8192000000 10093728848 10542772224 1142879344 12.243 99 46 92 0 1189033008 35687808 10465856
12:23:16 43296 8192000000 10109365056 10589888512 1162747888 12.429 100 8 16 0 1214154912 49628464 1778560
12:25:17 71683 8192000000 10141185376 10612121600 1152147776 12.330 99 18 36 1 1242526992 86364912 4014304
12:27:28 45595 8192000000 10090002864 10547838976 1119739376 12.025 97 16 32 0 1159656560 36804160 3113024
12:29:18 43307 8192000000 10094125984 10565586944 1115483616 11.984 97 14 27 0 1181278192 63151024 2643552
12:31:07 49971 8192000000 10061820224 10502238208 1069837616 11.551 94 19 37 2 1122011168 48494512 3679040
12:32:58 127660 8192000000 10066274736 10523262976 1032113328 11.189 91 14 28 0 1076661856 41697024 2851504
12:36:06 28615 8192000000 10082165872 10496856064 1108977040 11.923 99 45 88 1 1154261072 36423152 8860880
12:37:36 71430 8192000000 10062039296 10550886400 1075964416 11.609 96 17 34 0 1140872896 61786240 3122240
12:39:48 86079 8192000000 10044649520 10491523072 1107217792 11.906 99 23 46 0 1158289968 46393264 4678912
12:42:41 54926 8192000000 10079040832 10530783232 1116781824 11.997 99 7 14 1 1151043024 33094864 1166336
12:44:36 84426 8192000000 10100238224 10553483264 1088749024 11.731 96 14 28 0 1142880480 51040960 3090496
12:47:11 52943 8192000000 10134214848 10583097344 1124994272 12.074 99 34 69 5 1202964592 69737328 8232992
12:49:32 50153 8192000000 10176521760 10630598656 1129919056 12.121 99 23 46 1 1245361152 109874208 5567888
12:51:23 85936 8192000000 10101385472 10662567936 1126009184 12.084 99 8 16 0 1216592816 88689376 1894256
12:53:53 52151 8192000000 10096501728 10542366720 1120395424 12.031 99 31 62 2 1156929680 29393952 7140304
12:55:50 95328 8192000000 10070653728 10600501248 1093859360 11.779 97 14 28 0 1168891232 72440592 2591280
12:58:38 70338 8192000000 10088422912 10547052544 1099659440 11.834 97 15 29 5 1147542976 45252624 2630912
13:00:58 39181 8192000000 10082748256 10584711168 1135412832 12.172 100 4 8 0 1170280720 34329648 538240
13:02:39 30988 8192000000 10107076352 10544254976 1132259888 12.143 99 35 72 0 1184328096 45294800 6773408
13:04:11 57047 8192000000 10163624224 10607292416 1153331632 12.341 100 22 44 1 1225609888 67446320 4831936
13:06:09 98727 8192000000 10117372960 10600816640 1153633808 12.344 100 10 19 0 1206951904 51149296 2168800
13:08:52 36800 8192000000 10139387680 10574929920 1152842416 12.336 100 48 95 0 1191489200 28495152 10151632
13:10:34 45591 8192000000 10125914864 10605199360 1153384352 12.341 100 28 56 0 1211386784 52165408 5837024
13:12:22 75520 8192000000 10105404464 10570522624 1087329296 11.717 94 15 30 1 1152811744 62150912 3331536
13:14:38 128855 8192000000 10064622080 10508296192 1079965824 11.647 94 23 46 0 1110834832 25848400 5020608
13:17:47 51407 8192000000 10056357584 10527789056 1118402960 12.012 99 9 18 1 1150824512 30551552 1870000
13:19:40 63078 8192000000 10112723072 10590953472 1129950544 12.121 99 11 21 1 1187790224 55468224 2371456
13:21:44 31442 8192000000 10067372320 10540707840 1101811184 11.855 98 12 24 0 1156166576 51920912 2434480
13:23:24 52440 8192000000 10095179328 10540883968 1118347776 12.011 99 19 38 0 1167421616 45638912 3434928
13:25:23 81055 8192000000 10108421968 10575949824 1112867808 11.960 98 24 48 1 1184271024 65872736 5530480

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 26-03-2021 13:28:27 to 26-03-2021 13:28:29.

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          232047
MON$OLDEST_ACTIVE               233407
MON$OLDEST_SNAPSHOT             233407
MON$NEXT_TRANSACTION            233407
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                       2090176
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             796
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 ---
:376: 
:377: mscs-test-161.budzdorov.ru    Fri Mar 26 13:27:08 2021
:378:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:379: 
:380: 
:381: mscs-test-161.budzdorov.ru    Fri Mar 26 13:27:08 2021
:382:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:383: 
--- end of diff output ---

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

26.03.21 13:28:30. 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-26 13:28:33 No severe PSQL-related problems occured
26.03.21 13:28:34. End of report.