Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.03.2021 13:28
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 16624112 kB
MemAvailable 31537268 kB
Buffers 0 kB
Cached 15027336 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-21 13:26:58
DTS_END                         2021-03-21 13:26:58.3570
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/38504
TRN_ID                          233869
ATT_ID                          662
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: 9173.28 26994 1100793
customer order: creation 164.62 358 19754
customer order: refuse 14.34 319 1721
order to supplier: creation 44.22 1430 5306
order to supplier: removal 3.37 110873 404
invoice (draft): creation 44.61 3539 5353
invoice (draft): removal 53.26 973 6391
invoice accept: apply 41.06 15518 4927
invoice accept: cancel 47.87 816 5744
customer reserve: creation 3800.23 151 456027
customer reserve: removal 4668.83 62 560259
realization accept: apply 80.87 191 9704
realization accept: cancel 87.70 113 10524
payment from customer: creation 57.13 131 6855
payment from customer: removal 5.26 96 631
payment to supplier: creation 46.71 151 5605
payment to supplier: removal 3.04 64 365
service: total inventory turnovers 3.39 3597 407
service: total monetary turnovers 5.91 457 709
service: aggregate perf. data 0.86 19072 103
service: refresh index statistics 0.03 381974 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 21-03-2021 13:28:21 to 21-03-2021 13:28:24.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2014
TEST_TIME 2 2661
TEST_TIME 3 3593
TEST_TIME 4 3899
TEST_TIME 5 3986
TEST_TIME 6 3726
TEST_TIME 7 3755
TEST_TIME 8 4005
TEST_TIME 9 4449
TEST_TIME 10 4728
TEST_TIME 11 5288
TEST_TIME 12 5619
TEST_TIME 13 5614
TEST_TIME 14 6979
TEST_TIME 15 7316
TEST_TIME 16 7317
TEST_TIME 17 7536
TEST_TIME 18 8243
TEST_TIME 19 8347
TEST_TIME 20 8259
TEST_TIME 21 8238
TEST_TIME 22 8476
TEST_TIME 23 8515
TEST_TIME 24 8761
TEST_TIME 25 9131
TEST_TIME 26 10359
TEST_TIME 27 10999
TEST_TIME 28 10765
TEST_TIME 29 10828
TEST_TIME 30 10755
TEST_TIME 31 10698
TEST_TIME 32 10598
TEST_TIME 33 10477
TEST_TIME 34 10439
TEST_TIME 35 10324
TEST_TIME 36 10404
TEST_TIME 37 10302
TEST_TIME 38 10515
TEST_TIME 39 10348
TEST_TIME 40 10249
TEST_TIME 41 10196
TEST_TIME 42 10351
TEST_TIME 43 10303
TEST_TIME 44 10272
TEST_TIME 45 10307
TEST_TIME 46 10151
TEST_TIME 47 9977
TEST_TIME 48 9789
TEST_TIME 49 9774
TEST_TIME 50 9757
TEST_TIME 51 9728
TEST_TIME 52 9729
TEST_TIME 53 9712
TEST_TIME 54 9622
TEST_TIME 55 9526
TEST_TIME 56 9509
TEST_TIME 57 9403
TEST_TIME 58 9362
TEST_TIME 59 9380
TEST_TIME 60 9470
TEST_TIME 61 9434
TEST_TIME 62 9448
TEST_TIME 63 9521
TEST_TIME 64 9487
TEST_TIME 65 9510
TEST_TIME 66 9540
TEST_TIME 67 9577
TEST_TIME 68 9556
TEST_TIME 69 9598
TEST_TIME 70 9669
TEST_TIME 71 9625
TEST_TIME 72 9692
TEST_TIME 73 9657
TEST_TIME 74 9558
TEST_TIME 75 9500
TEST_TIME 76 9501
TEST_TIME 77 9435
TEST_TIME 78 9365
TEST_TIME 79 9304
TEST_TIME 80 9272
TEST_TIME 81 9286
TEST_TIME 82 9333
TEST_TIME 83 9296
TEST_TIME 84 9427
TEST_TIME 85 9381
TEST_TIME 86 9345
TEST_TIME 87 9358
TEST_TIME 88 9493
TEST_TIME 89 9489
TEST_TIME 90 9534
TEST_TIME 91 9555
TEST_TIME 92 9517
TEST_TIME 93 9421
TEST_TIME 94 9396
TEST_TIME 95 9328
TEST_TIME 96 9320
TEST_TIME 97 9279
TEST_TIME 98 9257
TEST_TIME 99 9345
TEST_TIME 100 9341
TEST_TIME 101 9322
TEST_TIME 102 9301
TEST_TIME 103 9386
TEST_TIME 104 9373
TEST_TIME 105 9427
TEST_TIME 106 9396
TEST_TIME 107 9448
TEST_TIME 108 9511
TEST_TIME 109 9467
TEST_TIME 110 9459
TEST_TIME 111 9413
TEST_TIME 112 9348
TEST_TIME 113 9306
TEST_TIME 114 9254
TEST_TIME 115 9253
TEST_TIME 116 9247
TEST_TIME 117 9209
TEST_TIME 118 9184
TEST_TIME 119 9144
TEST_TIME 120 9176

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 21-03-2021 13:28:25 to 21-03-2021 13:28:29.

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 1522358 1522358 0 0.00 0 22153 6 0 0
doc_list_biud 1522356 1522356 0 0.00 0 35924 29 0 0
sp_add_invoice_to_stock 5021 4927 94 1.87 33 183519 15519 0 94
sp_cancel_adding_invoice 5744 5744 0 0.00 5 71797 817 0 0
sp_cancel_client_order 1721 1721 0 0.00 4 9328 320 0 0
sp_cancel_customer_reserve 560259 560259 0 0.00 1 28151 62 0 0
sp_cancel_pay_from_customer 631 631 0 0.00 3 10633 96 0 0
sp_cancel_pay_to_supplier 365 365 0 0.00 3 2382 65 0 0
sp_cancel_supplier_invoice 6395 6391 4 0.06 3 31003 973 0 4
sp_cancel_supplier_order 404 404 0 0.00 6 1033397 110873 0 0
sp_cancel_write_off 10524 10524 0 0.00 1 13989 114 0 0
sp_client_order 19754 19754 0 0.00 4 21922 359 0 0
sp_customer_reserve 456027 456027 0 0.00 0 78800 151 0 0
sp_fill_shopping_cart 30386 30386 0 0.00 1 38396 46 0 0
sp_fill_shopping_cart_clo_res 456023 456023 0 0.00 0 26064 14 0 0
sp_get_clo_for_invoice 4922 4922 0 0.00 0 22888 286 0 0
sp_kill_cost_storno 17264 17264 0 0.00 0 10619 57 0 0
sp_kill_qstorno_ret_qs2qd 1137837 1137837 0 0.00 0 30992 34 0 0
sp_kill_qty_storno 599669 599669 0 0.00 0 35922 74 0 0
sp_lock_selected_doc 20196 20196 0 0.00 0 6445 5 0 0
sp_make_cost_storno 27086 27086 0 0.00 0 12406 61 0 0
sp_multiply_rows_for_pdistr 27086 27086 0 0.00 0 18951 32 0 0
sp_multiply_rows_for_qdistr 19754 19754 0 0.00 0 16945 173 0 0
sp_pay_from_customer 6855 6855 0 0.00 4 21555 131 0 0
sp_pay_to_supplier 5605 5605 0 0.00 5 12405 151 0 0
sp_payment_common 12460 12460 0 0.00 1 9808 30 0 0
sp_qd_handle_on_cancel_clo 1721 1721 0 0.00 0 9318 281 0 0
sp_qd_handle_on_invoice_upd_sts 10666 10666 0 0.00 0 35922 363 0 0
sp_qd_handle_on_reserve_upd_sts 9704 9704 0 0.00 0 17228 57 0 0
sp_reserve_write_off 9704 9704 0 0.00 6 17241 191 0 0
sp_supplier_invoice 5353 5353 0 0.00 7 101959 3539 0 0
sp_supplier_order 5306 5306 0 0.00 5 50813 1430 0 0
srv_aggregate_perf_data 103 103 0 0.00 2791 307564 19073 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1772 167485 50024 0 0
srv_find_qd_qs_mism 1054297 1054297 0 0.00 0 22153 5 0 0
srv_make_invnt_saldo 407 407 0 0.00 50 112346 3597 0 0
srv_make_money_saldo 709 709 0 0.00 1 13976 458 0 0
srv_recalc_idx_stat 4 4 0 0.00 138819 631335 381974 0 0
t$perf-abend:sp_add_invoice_to_stock 94 94 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4927 4927 0 0.00 0 25112 145 0 0
t$perf-norm:sp_cancel_adding_invoice 394 394 0 0.00 0 2432 77 0 0
t$perf-norm:sp_cancel_client_order 1721 1721 0 0.00 0 712 6 0 0
t$perf-norm:sp_cancel_customer_reserve 656 656 0 0.00 0 830 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 631 631 0 0.00 0 1617 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 365 365 0 0.00 0 2503 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 411 411 0 0.00 0 949 21 0 0
t$perf-norm:sp_cancel_supplier_order 404 404 0 0.00 0 17288 1668 0 0
t$perf-norm:sp_cancel_write_off 989 989 0 0.00 0 1253 6 0 0
t$perf-norm:sp_client_order 19754 19754 0 0.00 0 14328 18 0 0
t$perf-norm:sp_customer_reserve 978 978 0 0.00 0 12989 19 0 0
t$perf-norm:sp_pay_from_customer 6855 6855 0 0.00 0 11566 11 0 0
t$perf-norm:sp_pay_to_supplier 5605 5605 0 0.00 0 14504 12 0 0
t$perf-norm:sp_reserve_write_off 9704 9704 0 0.00 0 10465 16 0 0
t$perf-norm:sp_supplier_invoice 5353 5353 0 0.00 0 6946 18 0 0
t$perf-norm:sp_supplier_order 5306 5306 0 0.00 0 3438 12 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 651 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 2120 39 0 0
t$perf-norm:srv_make_invnt_saldo 407 407 0 0.00 0 1351 13 0 0
t$perf-norm:srv_make_money_saldo 709 709 0 0.00 0 2222 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10030 9842 188 1.87 0 7232 18 0 188
v_all_customers 36072 36072 0 0.00 0 106 1 0 0
v_all_suppliers 29646 29646 0 0.00 0 74 1 0 0
v_all_wares 39508 39508 0 0.00 0 42 1 0 0
v_cancel_adding_invoice 788 788 0 0.00 0 1267 8 0 0
v_cancel_client_order 3442 3442 0 0.00 0 8872 12 0 0
v_cancel_customer_prepayment 1262 1262 0 0.00 0 1919 8 0 0
v_cancel_customer_reserve 1312 1312 0 0.00 0 1108 5 0 0
v_cancel_payment_to_supplier 730 730 0 0.00 0 2367 6 0 0
v_cancel_supplier_invoice 830 822 8 0.96 0 1969 14 0 8
v_cancel_supplier_order 808 808 0 0.00 0 1210 6 0 0
v_cancel_write_off 1978 1978 0 0.00 0 4920 24 0 0
v_max_id_clo_ord 5301 5301 0 0.00 0 3476 2 0 0
v_max_id_clo_res 978 978 0 0.00 0 3818 16 0 0
v_max_id_ord_sup 5331 5331 0 0.00 0 3629 5 0 0
v_max_non_paid_invoice 1414 1414 0 0.00 0 1930 4 0 0
v_max_non_paid_realizn 3620 3620 0 0.00 0 766 3 0 0
v_min_id_clo_ord 5301 5301 0 0.00 0 3803 7 0 0
v_min_id_clo_res 978 978 0 0.00 0 1718 6 0 0
v_min_id_ord_sup 5331 5331 0 0.00 0 8585 6 0 0
v_min_non_paid_invoice 5605 5605 0 0.00 0 9802 27 0 0
v_min_non_paid_realizn 6855 6855 0 0.00 0 8389 19 0 0
v_our_firm 9906 9906 0 0.00 0 14 1 0 0
v_reserve_write_off 19408 19408 0 0.00 0 8464 7 0 0
x_lock_depdocs_on_canc_invoice 11724 11724 0 0.00 0 28450 113 0 0
x_lock_depdocs_on_canc_sup_ord 404 404 0 0.00 0 13070 384 0 0
x_make_qstorno_1000_1200 5306 5306 0 0.00 2 50789 1351 0 0
x_make_qstorno_1200_2000 5353 5353 0 0.00 3 101948 3361 0 0
x_make_qstorno_2100_3300 456027 456027 0 0.00 0 78798 135 0 0

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

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 5 8192000000 8801557280 8813858816 7019520 0.085 3 1 2 0 7118944 63648 35776
10:58:04 30999 8192000000 9725187312 10019921920 796510704 8.861 100 86 173 0 825443808 7833776 21099328
10:59:40 19505 8192000000 9761557904 10064273408 836685056 9.266 100 93 186 0 872476768 13489520 22302192
11:01:10 10174 8192000000 9804811472 10118934528 867028224 9.570 100 82 164 0 907506880 21616800 18861856
11:02:24 9712 8192000000 9865057904 10197229568 917866816 10.075 100 80 161 1 974558416 39377296 17314304
11:03:39 10162 8192000000 9942389168 10283401216 965965280 10.547 100 89 176 1 1046328128 62600048 17762800
11:04:53 4553 8192000000 9970089088 10324860928 997484848 10.854 100 83 161 1 1094835216 80858384 16491984
11:06:00 2613 8192000000 9997296256 10362695680 1030953760 11.178 100 82 164 0 1118587392 70374304 17259328
11:07:04 1503 8192000000 10044595072 10411790336 1048593600 11.347 100 96 193 0 1150670592 82662880 19414112
11:08:07 17938 8192000000 10014718032 10396610560 1066979888 11.523 100 66 132 1 1137150336 56030384 14140064
11:09:26 6571 8192000000 9987784976 10378330112 1067970464 11.533 100 39 76 4 1103751664 27905648 7875552
11:10:34 6894 8192000000 9988705344 10389692416 1078024112 11.629 100 32 64 1 1109367888 24737456 6606320
11:11:42 6097 8192000000 10003684032 10402525184 1082298176 11.669 100 40 80 2 1112413232 21682416 8432640
11:12:53 54879 8192000000 9978732960 10393686016 1088111776 11.725 100 40 80 1 1129740400 32668208 8960416
11:14:48 9072 8192000000 10001206320 10412802048 1095852336 11.798 100 29 58 0 1129078192 26418864 6806992
11:16:00 7356 8192000000 10011025088 10405707776 1094300992 11.784 100 39 78 1 1120250080 16674368 9274720
11:17:16 9486 8192000000 9971498000 10371452928 1011099840 10.986 94 30 59 3 1043888720 26437488 6351392
11:18:28 35149 8192000000 9955925952 10358988800 1027012032 11.140 96 45 90 1 1060378016 22635696 10730288
11:20:08 8093 8192000000 9934698640 10308284416 955109360 10.441 93 44 89 1 985439184 20892720 9437104
11:21:20 10791 8192000000 9938080672 10339274752 1009606144 10.972 98 41 77 4 1036266656 17865760 8794752
11:22:31 7962 8192000000 9953850208 10348777472 1044988864 11.313 100 46 90 2 1074254304 18822464 10442976
11:23:40 71635 8192000000 9970627200 10363047936 1061243856 11.468 100 28 56 2 1082087184 14661808 6181520
11:25:53 4530 8192000000 10009574096 10404343808 1067865024 11.532 100 60 118 2 1116192576 35588896 12738656
11:26:59 50358 8192000000 9999512912 10396876800 1082436464 11.671 100 39 76 3 1104828192 13579360 8812368
11:28:53 8585 8192000000 10060228544 10459586560 1114532960 11.975 100 52 103 0 1170799984 44662016 11605008
11:30:08 18881 8192000000 10124281648 10531926016 1134761728 12.166 100 52 104 2 1230343712 84472768 11109216
11:31:32 105102 8192000000 10176900112 10601439232 1150927968 12.318 100 29 58 2 1295200016 136956768 7315280
11:34:58 3472 8192000000 10217007264 10628472832 1164884128 12.449 100 70 136 0 1322098192 142838624 14375440
11:36:06 14348 8192000000 10279730640 10696028160 1172934720 12.524 100 61 121 0 1388141568 202198048 13008800
11:37:24 15640 8192000000 10276363520 10713870336 1164475568 12.445 99 54 108 2 1377990224 201182848 12331808
11:38:45 7098 8192000000 10292392112 10709917696 1162564352 12.427 99 66 133 0 1385814848 207706528 15543968
11:39:58 2808 8192000000 10335399056 10785218560 1181272672 12.602 100 85 169 2 1442570000 241702992 19594336
11:41:07 1940 8192000000 10299588704 10707460096 1170297680 12.500 100 66 132 0 1400241808 215157008 14787120
11:42:16 1769 8192000000 10293127136 10695553024 1163664656 12.438 100 82 163 1 1390416368 207637984 19113728
11:43:22 3220 8192000000 10343940848 10752561152 1157754000 12.382 100 65 130 0 1448512016 275078224 15679792
11:44:28 5185 8192000000 10348002960 10769121280 1153702928 12.344 100 61 120 1 1454298256 286203168 14392160
11:45:35 8831 8192000000 10369008528 10791698432 1142238608 12.237 98 53 106 3 1465331696 310726064 12367024
11:46:54 4796 8192000000 10396956672 10809163776 1144014816 12.253 99 52 102 1 1491826480 335150880 12660784
11:48:02 11365 8192000000 10421587280 10877063168 1133395904 12.153 98 39 78 0 1512621216 369600896 9624416
11:49:28 18638 8192000000 10432653120 10855841792 1122340880 12.049 99 33 66 1 1534586016 403232656 9012480
11:50:48 26417 8192000000 10390526688 10829713408 1070240016 11.554 98 24 48 1 1499658016 422209024 7208976
11:52:16 10860 8192000000 10321475248 10779185152 1089635616 11.739 99 37 73 0 1452936672 354117632 9183424
11:53:29 71064 8192000000 10100815696 10539958272 1113765344 11.968 100 28 55 0 1189416080 68960064 6690672
11:56:02 43492 8192000000 10100165248 10569203712 1101338832 11.850 99 7 14 2 1202207504 99245088 1623584
11:57:46 55944 8192000000 10066713952 10532413440 1104411776 11.879 99 14 30 0 1155875472 48559232 2904464
11:59:53 48231 8192000000 10072059392 10519011328 1098066992 11.819 98 30 62 2 1160017968 55534080 6416896
12:01:43 64527 8192000000 10081141696 10567741440 1127296560 12.096 100 10 20 0 1197361568 67908128 2156880
12:04:22 83678 8192000000 10066657328 10511040512 1131332688 12.134 100 18 34 1 1172507408 37324448 3850272
12:06:47 49110 8192000000 10108399392 10525908992 1143220416 12.246 100 34 68 0 1186308368 35877376 7210576
12:08:37 79680 8192000000 10099666256 10573209600 1130910080 12.130 99 9 18 1 1183611152 51016080 1684992
12:10:57 72349 8192000000 10075743120 10529824768 1136736304 12.185 99 18 36 1 1173691744 33051968 3903472
12:13:17 167485 8192000000 10091145648 10538229760 1132111024 12.141 98 27 54 0 1169027888 31307248 5609616
12:17:08 41670 8192000000 10093971536 10562314240 1141975760 12.234 99 22 44 0 1200522768 53808576 4738432
12:18:56 42130 8192000000 10094833200 10550571008 1138767488 12.204 99 19 37 0 1164343648 21167936 4408224
12:20:47 56680 8192000000 10093084720 10548674560 1140862880 12.224 99 12 23 0 1176075184 32462736 2749568
12:22:46 57858 8192000000 10134693072 10597048320 1150194800 12.311 100 16 32 0 1232123200 78257344 3671056
12:25:30 58286 8192000000 10099725888 10597408768 1115994368 11.989 98 11 21 0 1186737920 68560912 2182640
12:27:34 100785 8192000000 10079895648 10579304448 1113880656 11.969 97 19 38 0 1164907888 47240832 3786400
12:30:18 16378 8192000000 10069939984 10503901184 1104807184 11.883 97 58 114 0 1139600848 22863056 11930608
12:31:36 44634 8192000000 10055340384 10502598656 1064868544 11.503 95 25 50 2 1098238848 28050576 5319728
12:33:21 36407 8192000000 10033127328 10457141248 1052816688 11.388 95 15 30 0 1089740928 33922784 3001456
12:35:05 58722 8192000000 10056436416 10521456640 1081615168 11.663 98 12 24 0 1167196416 83026256 2554992
12:37:19 46778 8192000000 10082583168 10517798912 1084874864 11.694 98 17 33 1 1143561088 54863984 3822240
12:39:06 140871 8192000000 10066095472 10511183872 1045466208 11.317 94 31 62 0 1088638080 36404032 6767840
12:42:51 48094 8192000000 10025516112 10492641280 1087575760 11.720 99 5 10 0 1121581984 33017104 989120
12:44:40 79096 8192000000 10071579600 10525081600 1097550272 11.814 99 9 18 0 1160070208 60614832 1905104
12:47:05 70369 8192000000 10089233904 10564182016 1096127872 11.801 99 13 26 1 1166323104 67606192 2589040
12:49:23 72841 8192000000 10091084992 10579800064 1086365408 11.708 97 12 24 0 1186496640 97346384 2784848
12:52:52 64566 8192000000 10151625568 10630205440 1118458480 12.012 99 27 54 0 1229421824 104873024 6090320
12:54:57 47290 8192000000 10094194432 10520690688 1125822864 12.082 100 16 32 0 1176653408 47691584 3138960
12:56:44 63923 8192000000 10063430848 10542497792 1112007904 11.951 98 21 42 1 1162554608 46570688 3976016
12:58:49 98810 8192000000 10072916880 10539130880 1141516112 12.230 100 30 60 0 1194887008 47382816 5988080
13:01:39 56214 8192000000 10117148800 10574155776 1154405200 12.351 100 13 26 0 1208211856 50885504 2921152
13:03:38 122720 8192000000 10095245968 10660376576 1146091552 12.273 99 14 28 0 1234892592 85866848 2934192
13:07:01 51146 8192000000 10112691984 10556411904 1147812416 12.289 99 33 66 0 1194962976 40362128 6788432
13:08:53 72094 8192000000 10107266432 10589065216 1135387168 12.172 98 8 16 0 1208193808 71149248 1657392
13:11:20 39809 8192000000 10092868032 10541068288 1148534256 12.296 99 24 47 2 1176537904 22767040 5236608
13:13:02 15946 8192000000 10152580432 10604810240 1152088192 12.329 100 40 80 0 1238867872 78481568 8298112
13:14:20 62718 8192000000 10104120592 10587299840 1121155520 12.038 97 27 54 1 1193035696 66654496 5225680
13:16:24 116547 8192000000 10103856224 10587140096 1153861904 12.346 100 15 30 1 1200488448 43091792 3534752
13:19:31 58898 8192000000 10110147424 10573897728 1119873232 12.026 97 8 16 0 1169354592 47705856 1775504
13:22:03 51521 8192000000 10050958864 10517020672 1087758368 11.721 95 7 14 0 1129279184 39852144 1668672
13:24:09 68927 8192000000 10086749648 10550050816 1118284048 12.011 99 11 22 0 1182149952 61468944 2396960
13:26:34 33700 8192000000 9251160064 9603665920 376050048 4.388 33 4 8 0 414723904 37855552 818304

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 21-03-2021 13:28:36 to 21-03-2021 13:28:50.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 94 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-03-2021 13:28:51 to 21-03-2021 13:28:53.

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          233607
MON$OLDEST_ACTIVE               234432
MON$OLDEST_SNAPSHOT             234432
MON$NEXT_TRANSACTION            234432
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                       2128256
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 ---
:172: 
:173: mscs-test-161.budzdorov.ru    Sun Mar 21 13:27:10 2021
:174:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:175: 
--- end of diff output ---

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

21.03.21 13:28:54. 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-21 13:28:57 No severe PSQL-related problems occured
21.03.21 13:28:58. End of report.