Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.03.2021 10: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 16947896 kB
MemAvailable 31456784 kB
Buffers 0 kB
Cached 14684240 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-29 10:26:58
DTS_END                         2021-03-29 10:26:58.8310
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/46092
TRN_ID                          235865
ATT_ID                          630
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: 8738.13 21540 1048575
customer order: creation 174.78 384 20973
customer order: refuse 15.20 294 1824
order to supplier: creation 45.72 1410 5486
order to supplier: removal 3.25 110624 390
invoice (draft): creation 47.23 3459 5668
invoice (draft): removal 46.98 1090 5637
invoice accept: apply 42.53 17111 5104
invoice accept: cancel 42.31 888 5077
customer reserve: creation 3868.76 169 464251
customer reserve: removal 4161.59 67 499391
realization accept: apply 84.07 155 10088
realization accept: cancel 77.00 120 9240
payment from customer: creation 60.78 103 7293
payment from customer: removal 5.38 87 646
payment to supplier: creation 48.57 106 5828
payment to supplier: removal 3.19 73 383
service: total inventory turnovers 3.72 3328 446
service: total monetary turnovers 6.23 504 748
service: aggregate perf. data 0.82 20369 98
service: refresh index statistics 0.03 270463 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 29-03-2021 10:28:23 to 29-03-2021 10:28:26.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2842
TEST_TIME 2 3197
TEST_TIME 3 3749
TEST_TIME 4 4954
TEST_TIME 5 5446
TEST_TIME 6 5413
TEST_TIME 7 5392
TEST_TIME 8 5498
TEST_TIME 9 5545
TEST_TIME 10 5441
TEST_TIME 11 5662
TEST_TIME 12 6051
TEST_TIME 13 6116
TEST_TIME 14 6354
TEST_TIME 15 6458
TEST_TIME 16 6975
TEST_TIME 17 7104
TEST_TIME 18 7943
TEST_TIME 19 8207
TEST_TIME 20 8232
TEST_TIME 21 8169
TEST_TIME 22 8077
TEST_TIME 23 8263
TEST_TIME 24 8064
TEST_TIME 25 8517
TEST_TIME 26 9694
TEST_TIME 27 10429
TEST_TIME 28 10907
TEST_TIME 29 10615
TEST_TIME 30 10397
TEST_TIME 31 10245
TEST_TIME 32 10140
TEST_TIME 33 9989
TEST_TIME 34 9857
TEST_TIME 35 9748
TEST_TIME 36 9601
TEST_TIME 37 9469
TEST_TIME 38 9410
TEST_TIME 39 9335
TEST_TIME 40 9504
TEST_TIME 41 9402
TEST_TIME 42 9327
TEST_TIME 43 9570
TEST_TIME 44 9514
TEST_TIME 45 9436
TEST_TIME 46 9485
TEST_TIME 47 9399
TEST_TIME 48 9494
TEST_TIME 49 9485
TEST_TIME 50 9663
TEST_TIME 51 9585
TEST_TIME 52 9450
TEST_TIME 53 9323
TEST_TIME 54 9249
TEST_TIME 55 9167
TEST_TIME 56 9136
TEST_TIME 57 9052
TEST_TIME 58 9039
TEST_TIME 59 8995
TEST_TIME 60 8997
TEST_TIME 61 8989
TEST_TIME 62 8965
TEST_TIME 63 8979
TEST_TIME 64 9038
TEST_TIME 65 9005
TEST_TIME 66 9005
TEST_TIME 67 9114
TEST_TIME 68 9121
TEST_TIME 69 9193
TEST_TIME 70 9180
TEST_TIME 71 9281
TEST_TIME 72 9268
TEST_TIME 73 9149
TEST_TIME 74 9079
TEST_TIME 75 9040
TEST_TIME 76 8983
TEST_TIME 77 8924
TEST_TIME 78 8877
TEST_TIME 79 8845
TEST_TIME 80 8850
TEST_TIME 81 8869
TEST_TIME 82 8893
TEST_TIME 83 8928
TEST_TIME 84 8962
TEST_TIME 85 8920
TEST_TIME 86 8952
TEST_TIME 87 8986
TEST_TIME 88 8962
TEST_TIME 89 9033
TEST_TIME 90 9038
TEST_TIME 91 8993
TEST_TIME 92 8910
TEST_TIME 93 8885
TEST_TIME 94 8869
TEST_TIME 95 8846
TEST_TIME 96 8827
TEST_TIME 97 8849
TEST_TIME 98 8844
TEST_TIME 99 8853
TEST_TIME 100 8837
TEST_TIME 101 8877
TEST_TIME 102 8922
TEST_TIME 103 8930
TEST_TIME 104 8977
TEST_TIME 105 8988
TEST_TIME 106 9026
TEST_TIME 107 9045
TEST_TIME 108 9072
TEST_TIME 109 9002
TEST_TIME 110 8931
TEST_TIME 111 8880
TEST_TIME 112 8854
TEST_TIME 113 8822
TEST_TIME 114 8786
TEST_TIME 115 8761
TEST_TIME 116 8738
TEST_TIME 117 8716
TEST_TIME 118 8698
TEST_TIME 119 8702
TEST_TIME 120 8739

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 29-03-2021 10:28:27 to 29-03-2021 10:28:30.

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 1478627 1478627 0 0.00 0 10988 7 0 0
doc_list_biud 1478627 1478627 0 0.00 0 79999 30 0 0
sp_add_invoice_to_stock 5231 5104 127 2.42 27 171561 17112 0 127
sp_cancel_adding_invoice 5077 5077 0 0.00 5 53061 888 0 0
sp_cancel_client_order 1824 1824 0 0.00 4 12109 295 0 0
sp_cancel_customer_reserve 499391 499391 0 0.00 1 38740 68 0 0
sp_cancel_pay_from_customer 646 646 0 0.00 2 13338 87 0 0
sp_cancel_pay_to_supplier 383 383 0 0.00 3 2669 74 0 0
sp_cancel_supplier_invoice 5642 5637 5 0.08 2 80004 1090 0 5
sp_cancel_supplier_order 390 390 0 0.00 6 1024029 110625 0 0
sp_cancel_write_off 9240 9240 0 0.00 1 8136 121 0 0
sp_client_order 20973 20973 0 0.00 4 48047 385 0 0
sp_customer_reserve 464251 464251 0 0.00 0 100959 170 0 0
sp_fill_shopping_cart 32108 32108 0 0.00 1 26649 52 0 0
sp_fill_shopping_cart_clo_res 464248 464248 0 0.00 0 17615 17 0 0
sp_get_clo_for_invoice 5093 5093 0 0.00 0 43519 458 0 0
sp_kill_cost_storno 15346 15346 0 0.00 0 6161 53 0 0
sp_kill_qstorno_ret_qs2qd 1014049 1014049 0 0.00 0 79998 38 0 0
sp_kill_qty_storno 536735 536735 0 0.00 0 79999 81 0 0
sp_lock_selected_doc 20942 20942 0 0.00 0 8196 5 0 0
sp_make_cost_storno 28297 28297 0 0.00 0 10929 48 0 0
sp_multiply_rows_for_pdistr 28297 28297 0 0.00 0 8132 26 0 0
sp_multiply_rows_for_qdistr 20973 20973 0 0.00 0 48035 237 0 0
sp_pay_from_customer 7293 7293 0 0.00 4 11720 104 0 0
sp_pay_to_supplier 5828 5828 0 0.00 5 12286 106 0 0
sp_payment_common 13121 13121 0 0.00 1 9315 18 0 0
sp_qd_handle_on_cancel_clo 1824 1824 0 0.00 0 12103 270 0 0
sp_qd_handle_on_invoice_upd_sts 10165 10165 0 0.00 0 20817 364 0 0
sp_qd_handle_on_reserve_upd_sts 10088 10088 0 0.00 0 13989 43 0 0
sp_reserve_write_off 10088 10088 0 0.00 7 16827 155 0 0
sp_supplier_invoice 5668 5668 0 0.00 6 108273 3459 0 0
sp_supplier_order 5486 5486 0 0.00 6 93490 1410 0 0
srv_aggregate_perf_data 98 98 0 0.00 2521 559271 20370 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 2096 152803 51897 0 0
srv_find_qd_qs_mism 1000615 1000615 0 0.00 0 9578 6 0 0
srv_make_invnt_saldo 446 446 0 0.00 48 101481 3329 0 0
srv_make_money_saldo 748 748 0 0.00 1 13943 504 0 0
srv_recalc_idx_stat 4 4 0 0.00 190724 353144 270464 0 0
t$perf-abend:sp_add_invoice_to_stock 127 127 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5104 5104 0 0.00 0 15427 155 0 0
t$perf-norm:sp_cancel_adding_invoice 419 419 0 0.00 0 5780 90 0 0
t$perf-norm:sp_cancel_client_order 1824 1824 0 0.00 0 1514 8 0 0
t$perf-norm:sp_cancel_customer_reserve 724 724 0 0.00 0 5143 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 646 646 0 0.00 0 184 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 383 383 0 0.00 0 608 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 385 385 0 0.00 0 753 14 0 0
t$perf-norm:sp_cancel_supplier_order 390 390 0 0.00 0 20166 1320 0 0
t$perf-norm:sp_cancel_write_off 995 995 0 0.00 0 3099 8 0 0
t$perf-norm:sp_client_order 20973 20973 0 0.00 0 6950 15 0 0
t$perf-norm:sp_customer_reserve 997 997 0 0.00 0 1396 4 0 0
t$perf-norm:sp_pay_from_customer 7293 7293 0 0.00 0 5072 8 0 0
t$perf-norm:sp_pay_to_supplier 5828 5828 0 0.00 0 2642 5 0 0
t$perf-norm:sp_reserve_write_off 10088 10088 0 0.00 0 3974 8 0 0
t$perf-norm:sp_supplier_invoice 5668 5668 0 0.00 0 4064 12 0 0
t$perf-norm:sp_supplier_order 5486 5486 0 0.00 0 9171 14 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 141 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 137 3 0 0
t$perf-norm:srv_make_invnt_saldo 446 446 0 0.00 0 1429 7 0 0
t$perf-norm:srv_make_money_saldo 748 748 0 0.00 0 1944 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 10430 10176 254 2.43 0 5433 16 0 254
v_all_customers 38342 38342 0 0.00 0 14 1 0 0
v_all_suppliers 30366 30366 0 0.00 0 38 1 0 0
v_all_wares 41946 41946 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 838 838 0 0.00 0 1080 7 0 0
v_cancel_client_order 3648 3648 0 0.00 0 2223 5 0 0
v_cancel_customer_prepayment 1292 1292 0 0.00 0 2592 7 0 0
v_cancel_customer_reserve 1448 1448 0 0.00 0 4035 10 0 0
v_cancel_payment_to_supplier 766 766 0 0.00 0 1235 8 0 0
v_cancel_supplier_invoice 780 770 10 1.28 0 1826 14 0 10
v_cancel_supplier_order 780 780 0 0.00 0 4129 12 0 0
v_cancel_write_off 1990 1990 0 0.00 0 1225 12 0 0
v_max_id_clo_ord 5486 5486 0 0.00 0 1491 2 0 0
v_max_id_clo_res 997 997 0 0.00 0 1964 14 0 0
v_max_id_ord_sup 5649 5649 0 0.00 0 3864 5 0 0
v_max_non_paid_invoice 1780 1780 0 0.00 0 1928 3 0 0
v_max_non_paid_realizn 3850 3850 0 0.00 0 2909 4 0 0
v_min_id_clo_ord 5486 5486 0 0.00 0 2788 6 0 0
v_min_id_clo_res 997 997 0 0.00 0 3240 14 0 0
v_min_id_ord_sup 5649 5649 0 0.00 0 8929 12 0 0
v_min_non_paid_invoice 5828 5828 0 0.00 0 4116 8 0 0
v_min_non_paid_realizn 7293 7293 0 0.00 0 9310 15 0 0
v_our_firm 10490 10490 0 0.00 0 17 1 0 0
v_reserve_write_off 20176 20176 0 0.00 0 6925 6 0 0
x_lock_depdocs_on_canc_invoice 10329 10329 0 0.00 0 22798 110 0 0
x_lock_depdocs_on_canc_sup_ord 390 390 0 0.00 0 10122 352 0 0
x_make_qstorno_1000_1200 5486 5486 0 0.00 2 91126 1331 0 0
x_make_qstorno_1200_2000 5668 5668 0 0.00 3 108236 3244 0 0
x_make_qstorno_2100_3300 464251 464251 0 0.00 0 100932 150 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 29-03-2021 10:28:31 to 29-03-2021 10:28:36.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
07:57:02 1 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
07:58:03 13126 8192000000 9720210000 10015928320 804716208 8.944 100 86 172 0 833258000 7984304 20557488
07:59:21 31818 8192000000 9775155888 10082623488 847701616 9.377 100 90 180 2 881361392 12206032 21453744
08:00:56 18821 8192000000 9808598672 10132549632 883516560 9.735 100 81 162 0 919536640 18092480 17927600
08:02:19 21711 8192000000 9869747328 10205118464 917668752 10.073 100 76 150 2 976132080 41806512 16656816
08:03:42 11304 8192000000 9927349760 10273120256 962093856 10.509 100 80 160 0 1039418768 60746736 16578176
08:05:00 5562 8192000000 9953943296 10309459968 1001399296 10.892 100 73 146 1 1067828304 51900400 14528608
08:06:14 2719 8192000000 9998558880 10363060224 1035563600 11.222 100 79 158 0 1109991808 57772624 16655584
08:07:18 2245 8192000000 10031660800 10398494720 1052016208 11.380 100 92 184 2 1134428880 63644448 18768224
08:08:21 28158 8192000000 9977738800 10380369920 1060027776 11.457 100 46 90 1 1122699856 52898000 9774080
08:09:53 8175 8192000000 9997425008 10391138304 1072238368 11.573 100 44 88 0 1098452192 16859344 9354480
08:11:06 6718 8192000000 9995097952 10399305728 1080113568 11.649 100 39 75 2 1122114976 33819712 8181696
08:12:14 54598 8192000000 9999186304 10406535168 1085420640 11.699 100 19 38 0 1100819168 11124032 4274496
08:14:10 7607 8192000000 10004095088 10411761664 1096006912 11.800 100 40 76 1 1124064848 18977120 9080816
08:15:18 4754 8192000000 10019922128 10421018624 1096628704 11.806 100 38 75 0 1127008688 22333264 8046720
08:16:26 6632 8192000000 10013849888 10420113408 1074824096 11.598 98 30 61 0 1114231872 33130272 6277504
08:17:37 30228 8192000000 10002868448 10417844224 1075109008 11.601 99 35 69 1 1106084800 23205984 7769808
08:19:08 4755 8192000000 9970692352 10375749632 1046449408 11.327 98 37 74 3 1072866048 18753984 7662656
08:20:13 5133 8192000000 9947566640 10329407488 1009143616 10.967 97 58 120 2 1035795936 13483488 13168832
08:21:19 7002 8192000000 9916926240 10303795200 994984960 10.830 98 47 92 0 1015658480 10219600 10453920
08:22:30 25380 8192000000 9922787296 10317234176 1002532896 10.903 98 27 54 1 1026162960 18239568 5390496
08:23:59 10464 8192000000 9958409216 10361139200 1043523648 11.299 100 44 88 5 1073116400 19699904 9892848
08:25:11 6220 8192000000 9981570736 10370916352 1059429488 11.451 100 51 102 1 1088029600 17461136 11138976
08:26:19 7537 8192000000 9988087344 10392567808 1066581104 11.519 100 38 74 0 1095790032 20802960 8405968
08:27:28 33268 8192000000 10011688944 10407337984 1079845888 11.646 100 58 116 1 1110099488 17247504 13006096
08:29:07 18095 8192000000 10045879296 10444947456 1096408144 11.804 100 54 108 0 1154433776 47387760 10637872
08:30:29 3058 8192000000 10079264896 10476982272 1112200352 11.953 100 64 128 2 1192353824 67370224 12783248
08:31:38 6829 8192000000 10083697200 10490757120 1129870752 12.120 100 54 109 2 1203941824 62524224 11546848
08:32:50 13198 8192000000 10136873904 10558517248 1137409792 12.191 100 51 102 1 1252302976 103627040 11266144
08:34:06 22331 8192000000 10178623664 10593710080 1147486784 12.286 100 51 102 1 1279446944 120944848 11015312
08:35:46 4746 8192000000 10196567280 10611015680 1165467760 12.454 100 54 106 1 1305554496 127973536 12113200
08:36:57 3337 8192000000 10298573648 10711322624 1178699024 12.578 100 71 142 2 1397199008 203378944 15121040
08:38:05 3051 8192000000 10298742176 10715586560 1178105984 12.573 100 53 104 0 1401616480 211757680 11752816
08:39:14 2692 8192000000 10312167008 10716266496 1180633664 12.596 100 90 180 0 1407661152 207582976 19444512
08:40:20 2687 8192000000 10350607264 10759073792 1177449440 12.566 100 76 153 1 1450611392 255183024 17978928
08:41:31 2089 8192000000 10358181120 10762346496 1165511536 12.455 100 84 168 0 1458966384 274210304 19244544
08:42:38 3635 8192000000 10348690656 10753421312 1153439936 12.342 100 77 152 0 1443130912 272173792 17517184
08:43:48 8486 8192000000 10363546608 10765070336 1138371936 12.200 99 73 145 1 1454495280 299467568 16655776
08:45:01 7869 8192000000 10271285808 10710822912 1129511120 12.117 98 35 70 0 1382986240 244895120 8580000
08:46:13 7813 8192000000 10301391216 10718756864 1150872992 12.318 100 42 82 3 1403927568 242642976 10411600
08:47:24 13138 8192000000 10351035392 10774134784 1148149472 12.292 99 34 66 1 1437146208 280555184 8441552
08:48:43 13361 8192000000 10413551024 10832781312 1145691632 12.269 100 37 74 0 1505550128 350189040 9669456
08:49:58 16178 8192000000 10429912704 10855505920 1116976704 11.998 99 38 76 1 1514018016 386747936 10293376
08:51:20 23417 8192000000 10450837440 10902790144 1105934880 11.894 100 26 52 1 1567882880 453955840 7992160
08:52:53 42648 8192000000 10165124272 10666209280 1107639456 11.910 100 15 30 0 1281009584 169103056 4267072
08:54:39 149589 8192000000 10049033456 10477301760 1093067536 11.772 98 53 105 2 1130686416 26579904 11038976
08:58:12 57885 8192000000 10037205184 10465746944 1087348496 11.717 97 29 58 1 1119774560 26471152 5954912
09:00:17 40013 8192000000 10075689056 10516938752 1110332144 11.936 99 17 34 1 1158478912 44617024 3529744
09:02:04 152802 8192000000 10112225216 10557820928 1082975888 11.676 96 13 28 0 1150754656 64791696 2987072
09:06:03 51556 8192000000 10057167536 10488074240 1126957792 12.093 100 22 44 0 1173935328 42248912 4728624
09:08:06 78643 8192000000 10100666128 10604535808 1127538080 12.098 99 24 47 0 1226796800 94127744 5130976
09:10:34 24341 8192000000 10073855856 10511028224 1126097936 12.085 99 34 68 1 1156600368 23449808 7052624
09:11:59 40196 8192000000 10115470880 10593497088 1148994928 12.300 100 32 64 3 1208437936 53116320 6326688
09:13:39 42787 8192000000 10101771968 10562322432 1131181760 12.133 98 25 50 0 1225848688 89281840 5385088
09:15:28 90741 8192000000 10090426384 10603962368 1114849456 11.978 97 9 18 0 1189215760 72697824 1668480
09:18:01 130561 8192000000 10061922848 10518720512 1124384080 12.068 99 26 52 1 1172751184 43310768 5056336
09:21:42 64628 8192000000 10073595184 10529931264 1117889696 12.007 98 8 16 1 1161791760 41657504 2244560
09:24:01 74890 8192000000 10057024352 10530861056 1131318496 12.134 99 16 32 2 1170863216 36308560 3236160
09:26:17 49280 8192000000 10069044112 10521243648 1104073008 11.876 96 26 50 1 1143219696 34116336 5030352
09:28:06 26980 8192000000 10093588720 10530791424 1092288992 11.764 95 22 44 0 1136156288 39384992 4482304
09:29:39 110329 8192000000 10114257472 10569986048 1109764112 11.930 97 19 38 2 1236006032 121790480 4451440
09:32:35 42430 8192000000 10070597808 10576523264 1116183104 11.991 99 18 36 2 1191963856 71669344 4111408
09:34:20 98921 8192000000 10053353360 10530734080 1004145344 10.919 91 16 32 0 1049910624 42515968 3249312
09:37:12 59607 8192000000 10059952992 10539208704 1086203552 11.707 98 28 56 0 1152278704 60517728 5557424
09:39:16 114423 8192000000 10026130464 10480881664 1084885312 11.694 98 19 38 1 1129478480 40244960 4348208
09:42:11 57575 8192000000 10015977104 10456694784 1047606848 11.338 95 8 16 0 1070260784 20935280 1718656
09:44:55 69137 8192000000 10059875648 10484776960 1075228160 11.602 97 19 39 1 1111499568 31568592 4702816
09:47:21 87408 8192000000 10072048352 10521718784 1100216944 11.840 98 19 38 0 1198129040 93602336 4309760
09:49:48 74783 8192000000 10054327600 10522136576 1111874480 11.950 99 15 30 0 1157675568 42745808 3055280
09:52:03 41908 8192000000 10100505360 10562138112 1090233024 11.745 97 43 87 1 1158888928 59900576 8755328
09:53:50 43492 8192000000 10100932496 10525945856 1115718624 11.987 100 38 76 0 1182150688 57998048 8434016
09:55:34 79967 8192000000 10097608160 10599493632 1088040352 11.724 97 2 4 0 1129334608 40715744 578512
09:57:55 143269 8192000000 10059882448 10585522176 1098932448 11.828 98 11 22 1 1147539392 46396016 2210928
10:01:27 54929 8192000000 10108189024 10573643776 1120903856 12.036 99 21 43 1 1165136688 40142288 4090544
10:03:23 53122 8192000000 10058681360 10509529088 1130344032 12.125 100 23 43 1 1162256656 27559648 4352976
10:05:19 107929 8192000000 10132642400 10579443712 1112558240 11.957 97 22 44 0 1170783600 53477152 4748208
10:08:30 55053 8192000000 10130486128 10630750208 1143411200 12.248 100 19 38 1 1238858608 91537488 3909920
10:10:25 112091 8192000000 10122973856 10593177600 1122109568 12.047 98 7 14 0 1171732416 48296640 1326208
10:13:37 100572 8192000000 10070059280 10552524800 1076864112 11.618 94 17 34 2 1123543296 43381264 3297920
10:16:18 83899 8192000000 10075708352 10526375936 1118155888 12.010 99 9 18 0 1189277296 69226160 1895248
10:18:56 24729 8192000000 10045374832 10520895488 1127878640 12.101 100 3 6 0 1151767360 23397200 491520
10:20:21 40730 8192000000 10048795344 10503970816 1133320224 12.153 100 12 24 0 1161659584 26132240 2207120
10:22:09 73622 8192000000 10097888128 10560876544 1085576048 11.701 95 17 35 0 1137802032 48335872 3890112
10:24:25 32667 8192000000 10144132608 10579042304 1128433312 12.107 99 27 53 1 1237188128 102907008 5847808
10:26:04 76185 8192000000 9255144720 9643438080 373534752 4.360 33 8 16 1 477273776 101773200 1965824

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 29-03-2021 10:28:37 to 29-03-2021 10:28:52.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 127 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 29-03-2021 10:28:53 to 29-03-2021 10:28:55.

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          236294
MON$OLDEST_ACTIVE               236295
MON$OLDEST_SNAPSHOT             236295
MON$NEXT_TRANSACTION            236295
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                       2082976
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             799
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2394 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2394 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2394 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.2394 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 ---
:27: 
:28: mscs-test-161.budzdorov.ru    Mon Mar 29 10:27:22 2021
:29:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:30: 
:31: 
:32: mscs-test-161.budzdorov.ru    Mon Mar 29 10:27:48 2021
:33:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:34: 
--- end of diff output ---

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

29.03.21 10:28:56. 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-29 10:28:58 No severe PSQL-related problems occured
29.03.21 10:29:00. End of report.