Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.02.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 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 16606768 kB
MemAvailable 31068636 kB
Buffers 0 kB
Cached 15046668 kB
SwapCached 3268 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-26 13:26:56
DTS_END                         2021-02-26 13:26:56.0890
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/39350
TRN_ID                          220329
ATT_ID                          641
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: 8732.65 28399 1047918
customer order: creation 153.55 457 18426
customer order: refuse 13.40 433 1608
order to supplier: creation 42.12 1616 5054
order to supplier: removal 3.18 145091 381
invoice (draft): creation 42.38 4147 5085
invoice (draft): removal 50.79 1012 6095
invoice accept: apply 38.14 19971 4577
invoice accept: cancel 45.92 1007 5510
customer reserve: creation 3546.48 192 425577
customer reserve: removal 4522.39 80 542687
realization accept: apply 75.20 188 9024
realization accept: cancel 80.10 139 9612
payment from customer: creation 54.36 134 6523
payment from customer: removal 4.97 107 596
payment to supplier: creation 45.88 158 5506
payment to supplier: removal 3.07 90 368
service: total inventory turnovers 3.53 4114 423
service: total monetary turnovers 6.33 584 759
service: aggregate perf. data 0.85 21358 102
service: refresh index statistics 0.04 367098 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-02-2021 13:27:53 to 26-02-2021 13:27:55.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2602
TEST_TIME 2 3437
TEST_TIME 3 5041
TEST_TIME 4 6097
TEST_TIME 5 6076
TEST_TIME 6 5678
TEST_TIME 7 5917
TEST_TIME 8 5914
TEST_TIME 9 6214
TEST_TIME 10 6125
TEST_TIME 11 6093
TEST_TIME 12 6075
TEST_TIME 13 6311
TEST_TIME 14 6495
TEST_TIME 15 6724
TEST_TIME 16 6788
TEST_TIME 17 6851
TEST_TIME 18 6988
TEST_TIME 19 7078
TEST_TIME 20 7236
TEST_TIME 21 7568
TEST_TIME 22 7535
TEST_TIME 23 7490
TEST_TIME 24 8086
TEST_TIME 25 8776
TEST_TIME 26 8908
TEST_TIME 27 9233
TEST_TIME 28 9269
TEST_TIME 29 10415
TEST_TIME 30 11027
TEST_TIME 31 10685
TEST_TIME 32 10536
TEST_TIME 33 10513
TEST_TIME 34 10321
TEST_TIME 35 10141
TEST_TIME 36 9968
TEST_TIME 37 9952
TEST_TIME 38 9825
TEST_TIME 39 9883
TEST_TIME 40 9864
TEST_TIME 41 9748
TEST_TIME 42 9582
TEST_TIME 43 9558
TEST_TIME 44 9391
TEST_TIME 45 9307
TEST_TIME 46 9188
TEST_TIME 47 9144
TEST_TIME 48 9136
TEST_TIME 49 9011
TEST_TIME 50 8949
TEST_TIME 51 8858
TEST_TIME 52 8727
TEST_TIME 53 8808
TEST_TIME 54 8911
TEST_TIME 55 8911
TEST_TIME 56 8858
TEST_TIME 57 8848
TEST_TIME 58 8799
TEST_TIME 59 8942
TEST_TIME 60 8969
TEST_TIME 61 8956
TEST_TIME 62 9017
TEST_TIME 63 9012
TEST_TIME 64 8967
TEST_TIME 65 9054
TEST_TIME 66 9075
TEST_TIME 67 8991
TEST_TIME 68 8864
TEST_TIME 69 8847
TEST_TIME 70 8795
TEST_TIME 71 8771
TEST_TIME 72 8733
TEST_TIME 73 8728
TEST_TIME 74 8714
TEST_TIME 75 8677
TEST_TIME 76 8628
TEST_TIME 77 8726
TEST_TIME 78 8706
TEST_TIME 79 8737
TEST_TIME 80 8835
TEST_TIME 81 8830
TEST_TIME 82 8822
TEST_TIME 83 8904
TEST_TIME 84 8917
TEST_TIME 85 8943
TEST_TIME 86 8956
TEST_TIME 87 8883
TEST_TIME 88 8798
TEST_TIME 89 8773
TEST_TIME 90 8713
TEST_TIME 91 8737
TEST_TIME 92 8743
TEST_TIME 93 8708
TEST_TIME 94 8701
TEST_TIME 95 8695
TEST_TIME 96 8672
TEST_TIME 97 8634
TEST_TIME 98 8640
TEST_TIME 99 8786
TEST_TIME 100 8749
TEST_TIME 101 8792
TEST_TIME 102 8827
TEST_TIME 103 8865
TEST_TIME 104 8848
TEST_TIME 105 8767
TEST_TIME 106 8742
TEST_TIME 107 8678
TEST_TIME 108 8616
TEST_TIME 109 8570
TEST_TIME 110 8573
TEST_TIME 111 8585
TEST_TIME 112 8580
TEST_TIME 113 8546
TEST_TIME 114 8548
TEST_TIME 115 8612
TEST_TIME 116 8682
TEST_TIME 117 8643
TEST_TIME 118 8727
TEST_TIME 119 8698
TEST_TIME 120 8738

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 26-02-2021 13:27:57 to 26-02-2021 13:28:00.

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 1441734 1441734 0 0.00 0 18313 7 0 0
doc_list_biud 1441729 1441729 0 0.00 0 75578 38 0 0
sp_add_invoice_to_stock 4703 4577 126 2.67 41 187758 19972 0 126
sp_cancel_adding_invoice 5510 5510 0 0.00 5 97614 1008 0 0
sp_cancel_client_order 1608 1608 0 0.00 4 23042 434 0 0
sp_cancel_customer_reserve 542687 542687 0 0.00 1 29402 81 0 0
sp_cancel_pay_from_customer 596 596 0 0.00 3 7161 107 0 0
sp_cancel_pay_to_supplier 368 368 0 0.00 3 6295 90 0 0
sp_cancel_supplier_invoice 6099 6095 4 0.06 2 75588 1012 0 4
sp_cancel_supplier_order 381 381 0 0.00 14 1464690 145092 0 0
sp_cancel_write_off 9612 9612 0 0.00 1 14723 139 0 0
sp_client_order 18426 18426 0 0.00 4 76594 457 0 0
sp_customer_reserve 425577 425577 0 0.00 0 69853 193 0 0
sp_fill_shopping_cart 28558 28558 0 0.00 1 33236 59 0 0
sp_fill_shopping_cart_clo_res 425567 425567 0 0.00 0 22756 18 0 0
sp_get_clo_for_invoice 4567 4567 0 0.00 0 34249 501 0 0
sp_kill_cost_storno 16086 16086 0 0.00 0 13075 68 0 0
sp_kill_qstorno_ret_qs2qd 1101462 1101462 0 0.00 0 75577 43 0 0
sp_kill_qty_storno 579481 579481 0 0.00 0 75578 92 0 0
sp_lock_selected_doc 18798 18798 0 0.00 0 8172 4 0 0
sp_make_cost_storno 25620 25620 0 0.00 0 12509 63 0 0
sp_multiply_rows_for_pdistr 25618 25618 0 0.00 0 8219 36 0 0
sp_multiply_rows_for_qdistr 18426 18426 0 0.00 0 76585 256 0 0
sp_pay_from_customer 6523 6523 0 0.00 4 14697 135 0 0
sp_pay_to_supplier 5506 5506 0 0.00 5 13555 158 0 0
sp_payment_common 12028 12028 0 0.00 1 11521 22 0 0
sp_qd_handle_on_cancel_clo 1608 1608 0 0.00 0 22634 388 0 0
sp_qd_handle_on_invoice_upd_sts 10075 10075 0 0.00 0 38129 452 0 0
sp_qd_handle_on_reserve_upd_sts 9023 9023 0 0.00 0 8708 53 0 0
sp_reserve_write_off 9024 9024 0 0.00 7 15620 188 0 0
sp_supplier_invoice 5085 5085 0 0.00 7 103306 4148 0 0
sp_supplier_order 5054 5054 0 0.00 5 76898 1616 0 0
srv_aggregate_perf_data 102 102 0 0.00 2458 346545 21358 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1395 155619 48444 0 0
srv_find_qd_qs_mism 1004118 1004118 0 0.00 0 18310 6 0 0
srv_make_invnt_saldo 423 423 0 0.00 65 137552 4114 0 0
srv_make_money_saldo 759 759 0 0.00 1 17576 584 0 0
srv_recalc_idx_stat 5 5 0 0.00 181704 676476 367098 0 0
t$perf-abend:sp_add_invoice_to_stock 126 126 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 4578 4578 0 0.00 0 12131 192 0 0
t$perf-norm:sp_cancel_adding_invoice 373 373 0 0.00 0 12480 191 0 0
t$perf-norm:sp_cancel_client_order 1608 1608 0 0.00 0 8906 15 0 0
t$perf-norm:sp_cancel_customer_reserve 628 628 0 0.00 0 1254 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 596 596 0 0.00 0 2450 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 368 368 0 0.00 0 1259 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 371 371 0 0.00 0 1322 13 0 0
t$perf-norm:sp_cancel_supplier_order 381 381 0 0.00 0 20919 1830 0 0
t$perf-norm:sp_cancel_write_off 885 885 0 0.00 0 2181 7 0 0
t$perf-norm:sp_client_order 18426 18426 0 0.00 0 6401 20 0 0
t$perf-norm:sp_customer_reserve 918 918 0 0.00 0 2718 9 0 0
t$perf-norm:sp_pay_from_customer 6523 6523 0 0.00 0 4089 12 0 0
t$perf-norm:sp_pay_to_supplier 5506 5506 0 0.00 0 2441 8 0 0
t$perf-norm:sp_reserve_write_off 9024 9024 0 0.00 0 5367 11 0 0
t$perf-norm:sp_supplier_invoice 5085 5085 0 0.00 0 9379 23 0 0
t$perf-norm:sp_supplier_order 5054 5054 0 0.00 0 5002 21 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 415 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 36 2 0 0
t$perf-norm:srv_make_invnt_saldo 423 423 0 0.00 0 887 11 0 0
t$perf-norm:srv_make_money_saldo 759 759 0 0.00 0 1157 7 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 37 11 0 0
v_add_invoice_to_stock 9382 9130 252 2.68 0 8734 21 0 252
v_all_customers 34108 34108 0 0.00 0 70 1 0 0
v_all_suppliers 28596 28596 0 0.00 0 26 1 0 0
v_all_wares 36852 36852 0 0.00 0 46 1 0 0
v_cancel_adding_invoice 746 746 0 0.00 0 159 7 0 0
v_cancel_client_order 3216 3216 0 0.00 0 9213 8 0 0
v_cancel_customer_prepayment 1192 1192 0 0.00 0 7094 18 0 0
v_cancel_customer_reserve 1256 1256 0 0.00 0 10590 13 0 0
v_cancel_payment_to_supplier 736 736 0 0.00 0 287 4 0 0
v_cancel_supplier_invoice 750 742 8 1.06 0 3352 26 0 8
v_cancel_supplier_order 762 762 0 0.00 0 6219 18 0 0
v_cancel_write_off 1770 1770 0 0.00 0 1484 15 0 0
v_max_id_clo_ord 5051 5051 0 0.00 0 3307 2 0 0
v_max_id_clo_res 918 918 0 0.00 0 4493 24 0 0
v_max_id_ord_sup 5080 5080 0 0.00 0 3528 4 0 0
v_max_non_paid_invoice 1338 1338 0 0.00 0 5949 7 0 0
v_max_non_paid_realizn 3311 3311 0 0.00 0 6154 5 0 0
v_min_id_clo_ord 5051 5051 0 0.00 0 8162 19 0 0
v_min_id_clo_res 918 918 0 0.00 0 2664 15 0 0
v_min_id_ord_sup 5080 5080 0 0.00 0 6982 7 0 0
v_min_non_paid_invoice 5505 5505 0 0.00 0 11516 15 0 0
v_min_non_paid_realizn 6523 6523 0 0.00 0 8714 13 0 0
v_our_firm 9168 9168 0 0.00 0 20 1 0 0
v_reserve_write_off 18046 18046 0 0.00 0 7957 8 0 0
x_lock_depdocs_on_canc_invoice 11234 11234 0 0.00 0 16419 136 0 0
x_lock_depdocs_on_canc_sup_ord 381 381 0 0.00 0 8212 472 0 0
x_make_qstorno_1000_1200 5054 5054 0 0.00 2 76888 1502 0 0
x_make_qstorno_1200_2000 5085 5085 0 0.00 2 85988 3935 0 0
x_make_qstorno_2100_3300 425577 425577 0 0.00 0 69851 171 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-02-2021 13:28:01 to 26-02-2021 13:28:05.

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:56:59 9 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
10:58:01 28425 8192000000 9722264080 10016563200 792583760 8.821 100 85 170 0 821049680 7447952 21017968
10:59:41 21436 8192000000 9763047312 10070704128 837653264 9.276 100 88 176 0 871201296 12543920 21004112
11:01:04 19480 8192000000 9810746368 10125029376 875644736 9.656 100 89 178 0 917498176 21205856 20647584
11:02:27 15419 8192000000 9852467456 10182418432 909728656 9.995 100 79 158 0 962928736 35438736 17761344
11:03:45 8646 8192000000 9920479520 10261880832 960618480 10.495 100 77 152 0 1030283248 53942992 15721776
11:05:00 4962 8192000000 9962740944 10313166848 994917984 10.829 100 92 185 2 1075091664 61190224 18983456
11:06:06 2931 8192000000 10008644144 10374561792 1022538064 11.097 100 82 162 1 1129509280 90244384 16726832
11:07:11 2879 8192000000 10005727984 10374303744 1038017360 11.246 100 74 148 0 1119462832 67300816 14144656
11:08:16 1336 8192000000 10017063568 10385170432 1054558208 11.404 100 92 184 0 1137994864 64305696 19130960
11:09:20 1920 8192000000 10021446784 10390118400 1063993728 11.495 100 94 188 4 1135272688 51723968 19554992
11:10:23 4717 8192000000 9993787120 10382184448 1072060960 11.572 100 49 98 0 1109227728 26517920 10648848
11:11:30 54873 8192000000 9976331104 10379780096 1074496976 11.595 100 32 63 1 1111112752 29743808 6871968
11:13:26 5445 8192000000 9994438592 10395037696 1079944384 11.647 100 37 75 1 1114688400 27181840 7562176
11:14:33 8367 8192000000 10005457472 10405277696 1087568304 11.720 100 36 72 1 1120533040 25046544 7918192
11:15:41 8672 8192000000 10007028080 10411851776 1090563232 11.748 100 31 62 0 1125830848 28366352 6901264
11:16:53 22160 8192000000 10045699488 10460651520 1084320704 11.689 99 38 75 2 1117063008 25629920 7112384
11:18:17 6436 8192000000 10007825344 10405089280 1091915504 11.761 100 23 45 1 1127780864 30829408 5035952
11:19:27 8132 8192000000 9964417616 10361315328 1031992432 11.188 96 32 63 2 1057901808 18736256 7173120
11:20:35 7621 8192000000 9951249136 10337259520 1014454640 11.018 98 38 76 0 1049396192 26635968 8305584
11:21:43 20794 8192000000 9942960560 10326626304 964129424 10.529 93 38 74 1 993670032 21631952 7908656
11:23:09 8412 8192000000 9944368160 10334474240 1014775680 11.022 99 50 97 1 1045280096 19456272 11048144
11:24:19 5631 8192000000 9953541264 10342617088 1046866448 11.331 100 33 66 1 1079786864 25967040 6953376
11:25:27 67064 8192000000 9975666112 10380808192 1055671424 11.415 100 23 46 1 1083700912 23489680 4539808
11:27:40 14149 8192000000 9993578016 10389131264 1068414624 11.537 100 51 101 1 1099013952 20168528 10430800
11:29:06 10931 8192000000 10053687376 10451161088 1086412000 11.709 100 60 120 1 1169426320 70569392 12444928
11:30:26 11339 8192000000 10104640832 10501038080 1105115568 11.886 100 65 131 2 1206491568 88062608 13313392
11:31:56 5470 8192000000 10109537392 10511912960 1115805072 11.987 100 52 104 1 1223787616 97275024 10707520
11:33:09 9062 8192000000 10138737072 10543206400 1124435520 12.069 100 55 108 0 1242006768 106043184 11528064
11:34:23 22808 8192000000 10181326464 10589368320 1140987312 12.225 100 59 116 2 1301643392 147329760 13326320
11:35:58 13560 8192000000 10192292272 10605297664 1150367920 12.313 100 50 100 0 1286451776 124651792 11432064
11:37:21 5514 8192000000 10267065248 10672087040 1173003232 12.525 100 72 142 1 1369083776 180408000 15672544
11:38:32 10435 8192000000 10297729024 10699354112 1166000832 12.459 99 74 147 0 1393846896 211638016 16208048
11:39:54 1392 8192000000 10340420176 10742960128 1177484432 12.567 100 77 152 0 1441834880 247180080 17170368
11:41:00 5364 8192000000 10359906544 10775408640 1192518608 12.707 100 69 135 2 1463014128 255100000 15395520
11:42:13 6262 8192000000 10392259232 10795483136 1192424048 12.706 100 89 177 0 1486860224 274993456 19442720
11:43:25 3454 8192000000 10414355680 10821971968 1194288144 12.723 100 87 175 1 1505849904 291821024 19740736
11:44:37 5509 8192000000 10434121760 10854047744 1182997920 12.618 100 81 162 0 1528071856 326593488 18480448
11:45:48 3557 8192000000 10464852192 10884800512 1171378240 12.510 100 78 155 3 1572417184 382965648 18073296
11:47:02 2636 8192000000 10543792864 10947117056 1182733152 12.616 100 93 185 4 1639984272 435786304 21464816
11:48:09 7519 8192000000 10525679280 10958082048 1184504272 12.632 100 52 104 0 1628802896 430901360 13397264
11:49:24 1621 8192000000 10617824096 11027263488 1186578912 12.652 100 91 180 2 1717182480 508104464 22499104
11:50:29 4759 8192000000 10657409728 11091132416 1185891808 12.645 100 65 130 0 1758725312 555945360 16888144
11:51:40 13568 8192000000 10515458672 11005022208 1185659424 12.643 100 40 80 0 1611689824 416012848 10017552
11:52:55 18849 8192000000 10477023808 10969317376 1165675568 12.456 100 30 60 1 1589292352 415685472 7931312
11:54:48 18063 8192000000 10366520144 10882203648 1128354608 12.106 99 32 64 0 1518012400 381472048 8185744
11:56:10 99425 8192000000 10103434976 10542768128 1143055984 12.244 100 25 52 0 1197453088 48672592 5724512
11:58:51 69990 8192000000 10060288816 10545913856 1089536464 11.738 96 17 34 1 1159097456 65778048 3782944
12:01:02 101591 8192000000 10088979264 10554036224 1116497488 11.994 99 10 20 0 1171396816 52913600 1985728
12:03:52 68506 8192000000 10113237152 10577735680 1129303328 12.115 99 18 35 0 1183401168 50673360 3424480
12:06:12 127752 8192000000 10075922080 10543693824 1098958480 11.828 97 12 24 0 1150191264 48693104 2539680
12:10:06 118344 8192000000 10049762544 10485723136 1110727040 11.939 98 29 57 2 1154873104 37887104 6258960
12:13:15 13985 8192000000 10050496656 10499026944 1126154736 12.085 100 40 79 0 1166438912 32097168 8187008
12:14:37 61658 8192000000 10099694288 10564177920 1134154256 12.161 100 23 46 0 1202871168 63844192 4872720
12:16:44 58660 8192000000 10133691696 10610909184 1140813296 12.223 100 14 28 0 1242979376 98573088 3592992
12:18:43 67634 8192000000 10161249952 10660052992 1139568000 12.211 99 20 40 0 1275181776 131020240 4593536
12:20:52 56377 8192000000 10063956656 10505261056 1130843232 12.129 99 24 47 0 1161299296 26075792 4380272
12:22:50 69549 8192000000 10130826000 10584399872 1133571376 12.155 98 26 52 1 1203097664 63889440 5636848
12:25:00 78740 8192000000 10079890336 10507071488 1097573456 11.815 96 19 38 0 1154200720 51728560 4898704
12:27:36 41728 8192000000 10117031568 10560331776 1128985376 12.112 99 21 42 1 1196735600 63335056 4415168
12:29:26 68246 8192000000 10079974192 10517008384 1109177632 11.925 97 26 52 0 1162814160 48173600 5462928
12:31:35 67961 8192000000 10077341328 10549137408 1145645104 12.269 100 22 44 2 1194698528 44214000 4839424
12:33:46 155619 8192000000 10083065968 10530689024 1132407584 12.144 99 21 42 0 1199208672 62218144 4582944
12:38:20 66954 8192000000 10099933856 10553925632 1114475776 11.975 98 13 26 0 1199168624 81890720 2802128
12:40:29 56335 8192000000 10122391104 10620448768 1128184192 12.104 99 9 18 0 1217786368 87544080 2058096
12:42:25 49244 8192000000 10115009568 10580463616 1079643104 11.644 96 25 51 2 1202341840 116754176 5944560
12:44:26 83670 8192000000 10143756240 10589573120 1104834272 11.883 98 20 40 1 1211392800 101918608 4639920
12:47:01 47370 8192000000 10088955984 10538508288 1109120160 11.924 98 30 60 1 1158978720 43948528 5910032
12:48:54 113708 8192000000 10064406736 10536095744 1066997632 11.523 94 12 24 0 1098807200 29652672 2156896
12:51:49 73263 8192000000 10087514656 10550870016 1086759760 11.712 96 21 42 1 1147523184 56423376 4340048
12:55:35 53797 8192000000 10075269648 10548740096 1113604128 11.967 99 5 10 0 1173016336 58640624 771584
12:57:29 108473 8192000000 10058847392 10521190400 1083610592 11.682 96 9 18 1 1117818880 32448864 1759424
13:00:18 37223 8192000000 10089864544 10522636288 1107493408 11.909 98 21 42 0 1134466688 22788368 4184912
13:02:06 37287 8192000000 10096269728 10525437952 1116989008 11.999 99 26 52 1 1191263456 68504208 5770240
13:03:45 87180 8192000000 10182434192 10663034880 1134188704 12.161 99 27 54 1 1250076976 109903520 5984752
13:06:16 53922 8192000000 10101021808 10547654656 1107536256 11.909 97 21 42 0 1156378720 44491744 4350720
13:08:10 42607 8192000000 10140885008 10599239680 1127511776 12.098 99 24 48 1 1198569248 66398240 4659232
13:10:23 118374 8192000000 10066884656 10510327808 1112544032 11.956 98 15 30 0 1145935344 30371024 3020288
13:13:43 46651 8192000000 10074923184 10532069376 1120229808 12.029 99 6 12 0 1163167168 41795264 1142096
13:15:44 52091 8192000000 10090878064 10573729792 1129312160 12.115 100 10 20 1 1185101872 53543424 2246288
13:17:36 47753 8192000000 10106030368 10543972352 1141312912 12.228 100 20 40 0 1205101504 59389920 4398672
13:19:32 64627 8192000000 10183048992 10626797568 1130370336 12.125 99 27 52 1 1226654848 90657008 5627504
13:21:48 76963 8192000000 10083859664 10567245824 1122608352 12.052 98 13 26 1 1228523184 102999616 2915216
13:24:06 81735 8192000000 10093680624 10527596544 1138020016 12.197 99 19 36 3 1202430640 60682768 3727856
13:26:33 34120 8192000000 9320390608 9665073152 438301520 5.078 39 7 14 1 502378464 62518480 1558464

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-02-2021 13:28:07 to 26-02-2021 13:28:22.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 126 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 26-02-2021 13:28:23 to 26-02-2021 13:28:25.

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          220572
MON$OLDEST_ACTIVE               220573
MON$OLDEST_SNAPSHOT             220573
MON$NEXT_TRANSACTION            220573
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                       2077024
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             769
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2375 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2375 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2375 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.2375 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 ---
:40: 
:41: mscs-test-161.budzdorov.ru    Fri Feb 26 13:27:08 2021
:42:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:43: 
:44: 
:45: mscs-test-161.budzdorov.ru    Fri Feb 26 13:27:19 2021
:46:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:47: 
:48: 
:49: mscs-test-161.budzdorov.ru    Fri Feb 26 13:27:21 2021
:50:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:51: 
--- end of diff output ---

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

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