Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.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 17052612 kB
MemAvailable 31552588 kB
Buffers 0 kB
Cached 14610932 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-18 13:26:58
DTS_END                         2021-03-18 13:26:58.4560
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(134:18) ==> SP_CANCEL_SUPPLIER_INVOICE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/48334
TRN_ID                          224226
ATT_ID                          679
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: 8772.43 23664 1052692
customer order: creation 163.02 445 19562
customer order: refuse 14.09 308 1691
order to supplier: creation 43.97 1300 5276
order to supplier: removal 3.33 127308 399
invoice (draft): creation 44.60 3470 5352
invoice (draft): removal 48.82 1029 5858
invoice accept: apply 40.25 18168 4830
invoice accept: cancel 43.93 962 5272
customer reserve: creation 3732.01 177 447841
customer reserve: removal 4363.18 77 523581
realization accept: apply 79.61 198 9553
realization accept: cancel 72.97 115 8757
payment from customer: creation 56.14 110 6737
payment from customer: removal 5.45 162 654
payment to supplier: creation 47.62 145 5714
payment to supplier: removal 3.33 65 399
service: total inventory turnovers 3.44 3846 413
service: total monetary turnovers 5.70 672 684
service: aggregate perf. data 0.96 15106 115
service: refresh index statistics 0.03 299618 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 18-03-2021 13:28:33 to 18-03-2021 13:28:36.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1613
TEST_TIME 2 3045
TEST_TIME 3 4174
TEST_TIME 4 4510
TEST_TIME 5 4940
TEST_TIME 6 5119
TEST_TIME 7 5294
TEST_TIME 8 5430
TEST_TIME 9 5472
TEST_TIME 10 5654
TEST_TIME 11 5983
TEST_TIME 12 6297
TEST_TIME 13 6688
TEST_TIME 14 6596
TEST_TIME 15 6689
TEST_TIME 16 6809
TEST_TIME 17 6954
TEST_TIME 18 7379
TEST_TIME 19 7401
TEST_TIME 20 7777
TEST_TIME 21 8168
TEST_TIME 22 8256
TEST_TIME 23 8875
TEST_TIME 24 8952
TEST_TIME 25 9094
TEST_TIME 26 10490
TEST_TIME 27 11323
TEST_TIME 28 11118
TEST_TIME 29 10982
TEST_TIME 30 10650
TEST_TIME 31 10631
TEST_TIME 32 10451
TEST_TIME 33 10279
TEST_TIME 34 10058
TEST_TIME 35 10052
TEST_TIME 36 9884
TEST_TIME 37 9854
TEST_TIME 38 9754
TEST_TIME 39 9597
TEST_TIME 40 9587
TEST_TIME 41 9419
TEST_TIME 42 9470
TEST_TIME 43 9613
TEST_TIME 44 9465
TEST_TIME 45 9468
TEST_TIME 46 9381
TEST_TIME 47 9391
TEST_TIME 48 9589
TEST_TIME 49 9517
TEST_TIME 50 9519
TEST_TIME 51 9479
TEST_TIME 52 9449
TEST_TIME 53 9485
TEST_TIME 54 9349
TEST_TIME 55 9215
TEST_TIME 56 9131
TEST_TIME 57 9132
TEST_TIME 58 9020
TEST_TIME 59 8950
TEST_TIME 60 8988
TEST_TIME 61 8964
TEST_TIME 62 8930
TEST_TIME 63 8944
TEST_TIME 64 8976
TEST_TIME 65 9000
TEST_TIME 66 9077
TEST_TIME 67 9070
TEST_TIME 68 9061
TEST_TIME 69 9083
TEST_TIME 70 9062
TEST_TIME 71 9081
TEST_TIME 72 9113
TEST_TIME 73 9145
TEST_TIME 74 9097
TEST_TIME 75 8999
TEST_TIME 76 8974
TEST_TIME 77 8924
TEST_TIME 78 8888
TEST_TIME 79 8881
TEST_TIME 80 8907
TEST_TIME 81 8867
TEST_TIME 82 8923
TEST_TIME 83 8887
TEST_TIME 84 8962
TEST_TIME 85 8955
TEST_TIME 86 8931
TEST_TIME 87 8963
TEST_TIME 88 8912
TEST_TIME 89 8952
TEST_TIME 90 8861
TEST_TIME 91 8775
TEST_TIME 92 8803
TEST_TIME 93 8778
TEST_TIME 94 8770
TEST_TIME 95 8768
TEST_TIME 96 8794
TEST_TIME 97 8780
TEST_TIME 98 8754
TEST_TIME 99 8808
TEST_TIME 100 8848
TEST_TIME 101 8859
TEST_TIME 102 8816
TEST_TIME 103 8831
TEST_TIME 104 8844
TEST_TIME 105 8856
TEST_TIME 106 8947
TEST_TIME 107 8933
TEST_TIME 108 8908
TEST_TIME 109 8891
TEST_TIME 110 8846
TEST_TIME 111 8801
TEST_TIME 112 8759
TEST_TIME 113 8733
TEST_TIME 114 8726
TEST_TIME 115 8704
TEST_TIME 116 8665
TEST_TIME 117 8681
TEST_TIME 118 8658
TEST_TIME 119 8691
TEST_TIME 120 8747
TEST_TIME 121 8703

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 18-03-2021 13:28:37 to 18-03-2021 13:28:41.

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 1465580 1465580 0 0.00 0 20143 7 0 0
doc_list_biud 1465580 1465580 0 0.00 0 49743 34 0 0
sp_add_invoice_to_stock 4918 4830 88 1.78 38 179057 18169 0 88
sp_cancel_adding_invoice 5272 5272 0 0.00 4 66146 962 0 0
sp_cancel_client_order 1691 1691 0 0.00 4 12552 309 0 0
sp_cancel_customer_reserve 523581 523581 0 0.00 1 28225 77 0 0
sp_cancel_pay_from_customer 654 654 0 0.00 3 16830 163 0 0
sp_cancel_pay_to_supplier 399 399 0 0.00 3 3768 65 0 0
sp_cancel_supplier_invoice 5862 5858 4 0.06 2 49749 1030 0 4
sp_cancel_supplier_order 399 399 0 0.00 7 1305839 127309 0 0
sp_cancel_write_off 8757 8757 0 0.00 1 7845 115 0 0
sp_client_order 19562 19562 0 0.00 4 22624 446 0 0
sp_customer_reserve 447841 447841 0 0.00 0 55088 177 0 0
sp_fill_shopping_cart 30167 30167 0 0.00 1 16993 45 0 0
sp_fill_shopping_cart_clo_res 447832 447832 0 0.00 0 21912 16 0 0
sp_get_clo_for_invoice 4821 4821 0 0.00 0 32814 457 0 0
sp_kill_cost_storno 15082 15082 0 0.00 0 16815 67 0 0
sp_kill_qstorno_ret_qs2qd 1062176 1062176 0 0.00 0 49742 41 0 0
sp_kill_qty_storno 559932 559932 0 0.00 0 49743 88 0 0
sp_lock_selected_doc 19930 19930 0 0.00 0 9102 5 0 0
sp_make_cost_storno 26825 26825 0 0.00 0 20099 58 0 0
sp_multiply_rows_for_pdistr 26825 26825 0 0.00 0 16763 42 0 0
sp_multiply_rows_for_qdistr 19562 19562 0 0.00 0 22587 273 0 0
sp_pay_from_customer 6737 6737 0 0.00 4 18572 111 0 0
sp_pay_to_supplier 5714 5714 0 0.00 4 19336 145 0 0
sp_payment_common 12451 12451 0 0.00 1 16820 33 0 0
sp_qd_handle_on_cancel_clo 1691 1691 0 0.00 0 12532 288 0 0
sp_qd_handle_on_invoice_upd_sts 10093 10093 0 0.00 0 20708 396 0 0
sp_qd_handle_on_reserve_upd_sts 9553 9553 0 0.00 0 12307 51 0 0
sp_reserve_write_off 9553 9553 0 0.00 7 26444 199 0 0
sp_supplier_invoice 5352 5352 0 0.00 6 102666 3470 0 0
sp_supplier_order 5276 5276 0 0.00 5 91939 1301 0 0
srv_aggregate_perf_data 115 115 0 0.00 2300 163549 15107 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1490 140998 47846 0 0
srv_find_qd_qs_mism 1006020 1006020 0 0.00 0 14820 6 0 0
srv_make_invnt_saldo 413 413 0 0.00 69 115540 3847 0 0
srv_make_money_saldo 684 684 0 0.00 1 17367 672 0 0
srv_recalc_idx_stat 4 4 0 0.00 191691 439625 299619 0 0
t$perf-abend:sp_add_invoice_to_stock 88 88 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 4830 4830 0 0.00 0 13144 172 0 0
t$perf-norm:sp_cancel_adding_invoice 397 397 0 0.00 0 3510 88 0 0
t$perf-norm:sp_cancel_client_order 1691 1691 0 0.00 0 4275 13 0 0
t$perf-norm:sp_cancel_customer_reserve 665 665 0 0.00 0 1802 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 654 654 0 0.00 0 3796 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 399 399 0 0.00 0 439 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 371 371 0 0.00 0 4646 36 0 0
t$perf-norm:sp_cancel_supplier_order 399 399 0 0.00 0 20167 1401 0 0
t$perf-norm:sp_cancel_write_off 980 980 0 0.00 0 5712 8 0 0
t$perf-norm:sp_client_order 19562 19562 0 0.00 0 9036 18 0 0
t$perf-norm:sp_customer_reserve 1002 1002 0 0.00 0 3797 8 0 0
t$perf-norm:sp_pay_from_customer 6737 6737 0 0.00 0 6059 9 0 0
t$perf-norm:sp_pay_to_supplier 5714 5714 0 0.00 0 4510 8 0 0
t$perf-norm:sp_reserve_write_off 9553 9553 0 0.00 0 8003 11 0 0
t$perf-norm:sp_supplier_invoice 5352 5352 0 0.00 0 5232 20 0 0
t$perf-norm:sp_supplier_order 5276 5276 0 0.00 0 4989 14 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 877 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 2203 51 0 0
t$perf-norm:srv_make_invnt_saldo 413 413 0 0.00 0 338 4 0 0
t$perf-norm:srv_make_money_saldo 684 684 0 0.00 0 356 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 9818 9642 176 1.79 0 6990 15 0 176
v_all_customers 35332 35332 0 0.00 0 17 1 0 0
v_all_suppliers 29434 29434 0 0.00 0 18 1 0 0
v_all_wares 39124 39124 0 0.00 0 83 1 0 0
v_cancel_adding_invoice 794 794 0 0.00 0 174 5 0 0
v_cancel_client_order 3382 3382 0 0.00 0 1083 4 0 0
v_cancel_customer_prepayment 1308 1308 0 0.00 0 4919 10 0 0
v_cancel_customer_reserve 1330 1330 0 0.00 0 365 3 0 0
v_cancel_payment_to_supplier 798 798 0 0.00 0 202 3 0 0
v_cancel_supplier_invoice 750 742 8 1.06 0 778 13 0 8
v_cancel_supplier_order 798 798 0 0.00 0 307 3 0 0
v_cancel_write_off 1960 1960 0 0.00 0 826 11 0 0
v_max_id_clo_ord 5276 5276 0 0.00 0 326 1 0 0
v_max_id_clo_res 1002 1002 0 0.00 0 6170 21 0 0
v_max_id_ord_sup 5329 5329 0 0.00 0 4897 6 0 0
v_max_non_paid_invoice 1602 1602 0 0.00 0 374 2 0 0
v_max_non_paid_realizn 3641 3641 0 0.00 0 2903 4 0 0
v_min_id_clo_ord 5276 5276 0 0.00 0 4315 5 0 0
v_min_id_clo_res 1002 1002 0 0.00 0 6801 14 0 0
v_min_id_ord_sup 5329 5329 0 0.00 0 5312 9 0 0
v_min_non_paid_invoice 5714 5714 0 0.00 0 16810 27 0 0
v_min_non_paid_realizn 6737 6737 0 0.00 0 15670 24 0 0
v_our_firm 9984 9984 0 0.00 0 16 1 0 0
v_reserve_write_off 19106 19106 0 0.00 0 2324 5 0 0
x_lock_depdocs_on_canc_invoice 10759 10759 0 0.00 0 38760 121 0 0
x_lock_depdocs_on_canc_sup_ord 399 399 0 0.00 0 10402 344 0 0
x_make_qstorno_1000_1200 5276 5276 0 0.00 1 91000 1226 0 0
x_make_qstorno_1200_2000 5352 5352 0 0.00 3 102658 3292 0 0
x_make_qstorno_2100_3300 447841 447841 0 0.00 0 55086 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-03-2021 13:28:42 to 18-03-2021 13:28:46.

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:02 31634 8192000000 9722937920 10016813056 791523152 8.810 100 88 175 2 821125056 8030944 21570960
10:59:48 30386 8192000000 9770446080 10077351936 836836416 9.268 100 86 172 0 869250624 12225520 20188688
11:01:24 17631 8192000000 9815444128 10138443776 876447824 9.664 100 79 158 0 927871200 33687568 17735808
11:02:49 10346 8192000000 9878580976 10212872192 924819440 10.144 100 76 152 0 986092192 44845200 16427552
11:04:03 9063 8192000000 9933848368 10281730048 971463456 10.601 100 77 154 1 1039150688 51578448 16108784
11:05:14 5950 8192000000 9980040160 10333708288 1002119968 10.899 100 89 178 0 1108667584 87329248 19218368
11:06:22 3173 8192000000 10002592272 10369269760 1024620960 11.117 100 85 170 1 1117279200 75144688 17513552
11:07:26 1918 8192000000 10030641376 10399088640 1051120912 11.371 100 92 184 0 1146550944 76185984 19244048
11:08:29 25348 8192000000 9990438768 10391408640 1064119248 11.496 100 41 82 0 1126556800 53816800 8620752
11:09:58 5841 8192000000 9995781344 10388971520 1075144560 11.601 100 53 109 0 1108103952 21464960 11494432
11:11:06 7707 8192000000 9993770592 10407333888 1080586416 11.653 100 31 62 2 1110426800 23036640 6803744
11:12:20 60324 8192000000 9997453664 10406002688 1086758048 11.712 100 31 62 1 1109756640 16610400 6388192
11:14:20 4745 8192000000 10019360576 10420129792 1095627936 11.796 100 46 92 0 1137125504 31849280 9648288
11:15:28 5632 8192000000 10034596432 10431856640 1086289680 11.707 99 57 115 1 1129326496 29424112 13612704
11:16:36 31467 8192000000 10004450176 10418425856 1088393792 11.727 100 32 64 1 1133819488 39255536 6170160
11:18:35 4840 8192000000 9987424848 10373312512 1060813936 11.464 99 50 99 5 1100755392 28894656 11046800
11:19:44 3859 8192000000 9962925120 10342891520 1026797712 11.138 99 49 98 4 1070098448 32316288 10984448
11:20:48 7380 8192000000 9962918928 10350395392 1020700432 11.079 98 47 94 1 1055223024 23884816 10637776
11:21:56 30325 8192000000 9922092480 10314452992 989476064 10.776 96 24 48 2 1029646608 35076928 5093616
11:23:27 7222 8192000000 9967451936 10348072960 1047262688 11.334 100 37 74 2 1081864256 26222416 8379152
11:24:35 3804 8192000000 9980305520 10360528896 1062885728 11.484 100 52 106 1 1088192544 13726656 11580160
11:25:41 76331 8192000000 9995604144 10397908992 1077532496 11.624 100 55 104 7 1124020128 35107328 11380304
11:28:31 8675 8192000000 10039931776 10440458240 1105918512 11.894 100 33 66 2 1152758848 40067360 6772976
11:29:41 17311 8192000000 10088181392 10495062016 1113802608 11.968 99 47 94 1 1195305696 72025824 9477264
11:31:01 11596 8192000000 10136924672 10538856448 1141867536 12.233 100 58 116 1 1248758352 94325920 12564896
11:32:18 18442 8192000000 10167851936 10578399232 1151621104 12.325 100 56 108 1 1276222624 112752384 11849136
11:33:41 1489 8192000000 10216762576 10622881792 1168061136 12.479 100 74 149 1 1333820832 149454864 16304832
11:34:48 21460 8192000000 10245598416 10660585472 1173870784 12.533 100 56 112 0 1342567984 155961584 12735616
11:36:34 3382 8192000000 10288439392 10698514432 1174038336 12.535 100 77 153 2 1393033840 201094496 17901008
11:37:41 6120 8192000000 10325871104 10735292416 1177361520 12.566 99 73 146 1 1425945808 232322528 16261760
11:38:54 3363 8192000000 10340477408 10752552960 1181795856 12.607 100 79 158 0 1446066624 246253792 18016976
11:40:03 1511 8192000000 10353328688 10757255168 1183775248 12.625 100 79 160 0 1450299824 247615808 18908768
11:41:10 1492 8192000000 10409496704 10817257472 1180382000 12.594 99 89 176 2 1496132912 294839440 20911472
11:42:19 4289 8192000000 10422226496 10830348288 1166753440 12.466 99 72 142 2 1517386928 334003712 16629776
11:43:28 2938 8192000000 10463948848 10893033472 1183727408 12.625 99 77 154 0 1566078896 363461344 18890144
11:44:38 8367 8192000000 10465890912 10890203136 1186317168 12.649 99 72 143 0 1571636720 368339840 16979712
11:45:50 1987 8192000000 10505351456 10917437440 1179084208 12.582 99 88 175 0 1595359984 395534272 20741504
11:46:55 6969 8192000000 10529743024 10974150656 1193419216 12.715 100 61 122 1 1619701040 410308192 15973632
11:48:08 9779 8192000000 10521771232 10955116544 1184062720 12.628 99 39 78 1 1608343232 414015728 10264784
11:49:25 17561 8192000000 10461172656 10962710528 1175921248 12.552 100 32 64 0 1575826752 391208976 8696528
11:50:45 10508 8192000000 10454360512 10920841216 1156183792 12.368 100 31 62 2 1567798112 402784848 8829472
11:51:59 23370 8192000000 10458924224 10920845312 1136156064 12.179 100 25 50 1 1553286336 409443184 7687088
11:53:30 55568 8192000000 10070012976 10571329536 1111156544 11.943 98 5 10 1 1175162048 63189872 815632
11:55:26 126973 8192000000 10053411392 10487558144 1078036496 11.629 96 31 62 0 1133468528 49244560 6187472
11:58:40 112931 8192000000 10069344128 10529345536 1059593552 11.453 95 13 27 0 1082138208 20490624 2054032
12:01:38 56914 8192000000 10060130688 10497196032 1104578464 11.881 99 18 36 1 1162217856 53618928 4020464
12:04:08 60846 8192000000 10072699856 10527645696 1102368608 11.860 98 25 50 0 1156136400 48439856 5327936
12:06:11 24649 8192000000 10062322544 10526359552 1115606912 11.985 99 15 31 0 1145498000 27034032 2857056
12:07:38 76691 8192000000 10134842960 10634362880 1135580560 12.174 100 18 36 0 1221264384 81563024 4120800
12:09:59 56617 8192000000 10120581568 10575069184 1145984016 12.272 100 17 35 0 1220058704 69874688 4200000
12:12:02 51312 8192000000 10147720144 10587639808 1134103200 12.160 99 20 40 0 1225177296 86755520 4318576
12:13:54 53220 8192000000 10068144736 10508591104 1142201760 12.236 100 17 34 0 1250018112 104023120 3793232
12:15:51 53642 8192000000 10099969984 10564194304 1135197776 12.170 99 30 60 0 1215428896 73419008 6812112
12:17:54 16504 8192000000 10094687536 10533949440 1127439056 12.097 99 32 64 2 1177884576 44093264 6352256
12:19:13 140998 8192000000 10062812496 10509037568 1134026880 12.159 99 10 20 0 1158372848 22503584 1842384
12:22:44 46900 8192000000 10067132160 10566692864 1127682272 12.100 99 11 23 0 1183036384 52736256 2617856
12:24:32 38782 8192000000 10068790560 10522992640 1117951792 12.008 98 8 16 0 1161545760 41815760 1778208
12:26:11 54233 8192000000 10061487456 10498908160 1119652496 12.024 98 15 30 0 1146644032 24349904 2641632
12:28:10 43693 8192000000 10103042800 10542125056 1111508288 11.947 97 24 48 0 1157075264 39895024 5671952
12:29:58 57036 8192000000 10105879936 10575552512 1140962896 12.225 100 18 36 1 1243311008 98267664 4080448
12:32:11 121590 8192000000 10074425680 10525003776 1044743296 11.310 91 22 43 2 1087973968 38472096 4758576
12:35:13 62873 8192000000 10080943856 10517856256 1114399728 11.974 98 15 30 0 1150584912 33281136 2904048
12:37:16 32068 8192000000 10120243856 10584932352 1132783472 12.148 99 35 70 2 1214040416 74248368 7008576
12:38:49 75894 8192000000 10096839472 10552598528 1118597232 12.014 98 31 62 1 1184166000 59703840 5864928
12:41:07 72783 8192000000 10114182224 10598174720 1140770752 12.223 100 13 26 0 1224925728 81188112 2966864
12:44:11 49184 8192000000 10127789056 10643980288 1137759360 12.194 99 6 12 0 1198161856 59168224 1234272
12:46:07 82072 8192000000 10096289424 10552672256 1092069040 11.762 95 14 28 0 1159962016 64822832 3070144
12:48:42 70074 8192000000 10101826064 10547474432 1106394080 11.898 98 12 24 0 1158571376 49438960 2738336
12:50:52 67105 8192000000 10040771200 10494287872 1058696208 11.444 94 22 44 0 1096256752 33003600 4556944
12:53:03 87226 8192000000 10061337200 10482565120 1030563568 11.174 92 19 38 0 1071832336 37293712 3975056
12:55:34 92652 8192000000 10061561696 10522955776 1085131584 11.696 98 18 36 1 1140296928 51284192 3881152
12:58:53 51951 8192000000 10062032368 10556841984 1113247088 11.963 100 7 14 0 1162248064 47821184 1179792
13:00:47 110246 8192000000 10070337696 10564120576 1106415664 11.898 98 11 22 0 1169463184 60984416 2063104
13:03:38 65201 8192000000 10102769072 10541023232 1102511792 11.861 97 16 31 0 1162641632 56949488 3180352
13:05:43 64314 8192000000 10069011376 10573479936 1112534448 11.956 98 11 22 1 1185387168 70746128 2106592
13:07:51 48085 8192000000 10111426160 10544226304 1140440720 12.220 100 19 38 1 1187664000 43496880 3726400
13:09:41 30631 8192000000 10110477856 10541989888 1141292608 12.228 99 33 66 0 1188080816 40416208 6372000
13:11:14 54958 8192000000 10149464128 10597429248 1129176672 12.114 99 30 60 0 1253264416 117937504 6150240
13:14:00 101224 8192000000 10129973136 10621956096 1137046080 12.188 99 17 34 1 1218186880 77884656 3256144
13:17:12 63175 8192000000 10050902096 10505834496 1098582304 11.824 96 8 16 0 1134424880 34353184 1489392
13:19:15 39441 8192000000 10049658288 10527141888 1117262624 12.001 99 5 10 0 1154983168 36740288 980256
13:20:56 72610 8192000000 10074834592 10520498176 1094365120 11.784 97 28 56 0 1141535440 41409312 5761008
13:23:16 79403 8192000000 10143702624 10613387264 1120217904 12.029 98 24 48 1 1217163232 91391456 5553872
13:26:12 63472 8192000000 9365812880 9760133120 422065984 4.899 38 8 16 1 531013584 106921584 2026016

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 18-03-2021 13:28:47 to 18-03-2021 13:29:02.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 88 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 18-03-2021 13:29:03 to 18-03-2021 13:29:05.

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          224225
MON$OLDEST_ACTIVE               229689
MON$OLDEST_SNAPSHOT             229689
MON$NEXT_TRANSACTION            229689
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                       2082592
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             789
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2389 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2389 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2389 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.2389 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 ---
:88: 
:89: mscs-test-161.budzdorov.ru    Thu Mar 18 13:27:28 2021
:90:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:91: 
:92: 
:93: mscs-test-161.budzdorov.ru    Thu Mar 18 13:27:31 2021
:94:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:95: 
--- end of diff output ---

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

18.03.21 13:29:06. 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-18 13:29:08 No severe PSQL-related problems occured
18.03.21 13:29:10. End of report.