Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.02.2021 04: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 16321312 kB
MemAvailable 31069568 kB
Buffers 20 kB
Cached 15278256 kB
SwapCached 3892 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-22 04:26:56
DTS_END                         2021-02-22 04:26:56.4120
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/56334
TRN_ID                          226569
ATT_ID                          673
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: 8966.29 24819 1075955
customer order: creation 163.31 382 19597
customer order: refuse 13.43 334 1612
order to supplier: creation 43.14 1487 5177
order to supplier: removal 3.30 118075 396
invoice (draft): creation 43.26 3762 5191
invoice (draft): removal 52.33 970 6280
invoice accept: apply 39.00 18623 4680
invoice accept: cancel 47.40 879 5688
customer reserve: creation 3650.37 179 438044
customer reserve: removal 4631.42 66 555770
realization accept: apply 78.27 170 9392
realization accept: cancel 79.33 104 9519
payment from customer: creation 55.89 111 6707
payment from customer: removal 5.31 92 637
payment to supplier: creation 46.56 136 5587
payment to supplier: removal 3.44 90 413
service: total inventory turnovers 3.74 3383 449
service: total monetary turnovers 5.97 486 716
service: aggregate perf. data 0.80 13280 96
service: refresh index statistics 0.03 333775 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-02-2021 04:28:09 to 22-02-2021 04:28:11.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3949
TEST_TIME 2 3481
TEST_TIME 3 3396
TEST_TIME 4 3322
TEST_TIME 5 3396
TEST_TIME 6 3691
TEST_TIME 7 4189
TEST_TIME 8 4812
TEST_TIME 9 4927
TEST_TIME 10 5115
TEST_TIME 11 5598
TEST_TIME 12 6192
TEST_TIME 13 6676
TEST_TIME 14 6934
TEST_TIME 15 7239
TEST_TIME 16 7339
TEST_TIME 17 7489
TEST_TIME 18 7712
TEST_TIME 19 8060
TEST_TIME 20 8599
TEST_TIME 21 8478
TEST_TIME 22 8469
TEST_TIME 23 8858
TEST_TIME 24 8718
TEST_TIME 25 8631
TEST_TIME 26 9251
TEST_TIME 27 9304
TEST_TIME 28 10420
TEST_TIME 29 10803
TEST_TIME 30 11103
TEST_TIME 31 11069
TEST_TIME 32 10791
TEST_TIME 33 10655
TEST_TIME 34 10367
TEST_TIME 35 10323
TEST_TIME 36 10249
TEST_TIME 37 10166
TEST_TIME 38 10119
TEST_TIME 39 10033
TEST_TIME 40 9942
TEST_TIME 41 9931
TEST_TIME 42 9815
TEST_TIME 43 9800
TEST_TIME 44 9616
TEST_TIME 45 9592
TEST_TIME 46 9733
TEST_TIME 47 9725
TEST_TIME 48 9707
TEST_TIME 49 9794
TEST_TIME 50 9733
TEST_TIME 51 9784
TEST_TIME 52 9796
TEST_TIME 53 9833
TEST_TIME 54 9979
TEST_TIME 55 9895
TEST_TIME 56 9912
TEST_TIME 57 9938
TEST_TIME 58 9812
TEST_TIME 59 9717
TEST_TIME 60 9744
TEST_TIME 61 9675
TEST_TIME 62 9559
TEST_TIME 63 9542
TEST_TIME 64 9446
TEST_TIME 65 9509
TEST_TIME 66 9447
TEST_TIME 67 9404
TEST_TIME 68 9382
TEST_TIME 69 9426
TEST_TIME 70 9524
TEST_TIME 71 9511
TEST_TIME 72 9543
TEST_TIME 73 9577
TEST_TIME 74 9632
TEST_TIME 75 9568
TEST_TIME 76 9547
TEST_TIME 77 9548
TEST_TIME 78 9491
TEST_TIME 79 9407
TEST_TIME 80 9339
TEST_TIME 81 9295
TEST_TIME 82 9220
TEST_TIME 83 9163
TEST_TIME 84 9116
TEST_TIME 85 9098
TEST_TIME 86 9102
TEST_TIME 87 9131
TEST_TIME 88 9082
TEST_TIME 89 9204
TEST_TIME 90 9211
TEST_TIME 91 9185
TEST_TIME 92 9181
TEST_TIME 93 9267
TEST_TIME 94 9244
TEST_TIME 95 9214
TEST_TIME 96 9141
TEST_TIME 97 9130
TEST_TIME 98 9094
TEST_TIME 99 9080
TEST_TIME 100 9087
TEST_TIME 101 9107
TEST_TIME 102 9081
TEST_TIME 103 9158
TEST_TIME 104 9165
TEST_TIME 105 9154
TEST_TIME 106 9153
TEST_TIME 107 9171
TEST_TIME 108 9181
TEST_TIME 109 9238
TEST_TIME 110 9281
TEST_TIME 111 9201
TEST_TIME 112 9130
TEST_TIME 113 9099
TEST_TIME 114 9075
TEST_TIME 115 9030
TEST_TIME 116 9037
TEST_TIME 117 9021
TEST_TIME 118 9006
TEST_TIME 119 8969
TEST_TIME 120 8967

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 22-02-2021 04:28:12 to 22-02-2021 04:28:16.

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 1481293 1481293 0 0.00 0 34113 7 0 0
doc_list_biud 1481290 1481290 0 0.00 0 86736 31 0 0
sp_add_invoice_to_stock 4804 4680 124 2.58 35 236541 18623 0 124
sp_cancel_adding_invoice 5688 5688 0 0.00 4 64684 879 0 0
sp_cancel_client_order 1612 1612 0 0.00 5 15694 335 0 0
sp_cancel_customer_reserve 555770 555770 0 0.00 1 31631 66 0 0
sp_cancel_pay_from_customer 637 637 0 0.00 3 6914 93 0 0
sp_cancel_pay_to_supplier 413 413 0 0.00 3 4454 91 0 0
sp_cancel_supplier_invoice 6289 6280 9 0.14 1 86739 970 0 9
sp_cancel_supplier_order 396 396 0 0.00 6 1013171 118075 0 0
sp_cancel_write_off 9519 9519 0 0.00 1 17759 104 0 0
sp_client_order 19597 19597 0 0.00 4 32394 383 0 0
sp_customer_reserve 438044 438044 0 0.00 0 93626 180 0 0
sp_fill_shopping_cart 29964 29964 0 0.00 1 26223 46 0 0
sp_fill_shopping_cart_clo_res 438041 438041 0 0.00 0 25731 17 0 0
sp_get_clo_for_invoice 4677 4677 0 0.00 0 38188 416 0 0
sp_kill_cost_storno 16257 16257 0 0.00 0 7052 52 0 0
sp_kill_qstorno_ret_qs2qd 1127735 1127735 0 0.00 0 86734 36 0 0
sp_kill_qty_storno 593334 593334 0 0.00 0 86735 77 0 0
sp_lock_selected_doc 19494 19494 0 0.00 0 8279 6 0 0
sp_make_cost_storno 26363 26363 0 0.00 0 29273 58 0 0
sp_multiply_rows_for_pdistr 26363 26363 0 0.00 0 7822 28 0 0
sp_multiply_rows_for_qdistr 19597 19597 0 0.00 0 32385 219 0 0
sp_pay_from_customer 6707 6707 0 0.00 4 23817 111 0 0
sp_pay_to_supplier 5587 5587 0 0.00 5 28066 136 0 0
sp_payment_common 12294 12294 0 0.00 1 10403 19 0 0
sp_qd_handle_on_cancel_clo 1612 1612 0 0.00 0 15664 310 0 0
sp_qd_handle_on_invoice_upd_sts 10365 10365 0 0.00 0 35892 374 0 0
sp_qd_handle_on_reserve_upd_sts 9392 9392 0 0.00 0 7702 46 0 0
sp_reserve_write_off 9392 9392 0 0.00 7 29408 170 0 0
sp_supplier_invoice 5191 5191 0 0.00 7 99493 3762 0 0
sp_supplier_order 5177 5177 0 0.00 5 102163 1488 0 0
srv_aggregate_perf_data 96 96 0 0.00 2975 79341 13281 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 2339 197942 50832 0 0
srv_find_qd_qs_mism 1030642 1030642 0 0.00 0 34110 6 0 0
srv_make_invnt_saldo 449 449 0 0.00 40 81917 3383 0 0
srv_make_money_saldo 716 716 0 0.00 1 16609 486 0 0
srv_recalc_idx_stat 4 4 0 0.00 236668 423649 333775 0 0
t$perf-abend:sp_add_invoice_to_stock 124 124 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4680 4680 0 0.00 0 13694 172 0 0
t$perf-norm:sp_cancel_adding_invoice 422 422 0 0.00 0 3125 87 0 0
t$perf-norm:sp_cancel_client_order 1612 1612 0 0.00 0 1892 9 0 0
t$perf-norm:sp_cancel_customer_reserve 642 642 0 0.00 0 505 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 637 637 0 0.00 0 1703 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 413 413 0 0.00 0 372 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 381 381 0 0.00 0 465 11 0 0
t$perf-norm:sp_cancel_supplier_order 396 396 0 0.00 0 22253 1379 0 0
t$perf-norm:sp_cancel_write_off 923 923 0 0.00 0 1425 7 0 0
t$perf-norm:sp_client_order 19597 19597 0 0.00 0 8636 18 0 0
t$perf-norm:sp_customer_reserve 959 959 0 0.00 0 716 5 0 0
t$perf-norm:sp_pay_from_customer 6707 6707 0 0.00 0 8046 9 0 0
t$perf-norm:sp_pay_to_supplier 5587 5587 0 0.00 0 6433 10 0 0
t$perf-norm:sp_reserve_write_off 9392 9392 0 0.00 0 6100 8 0 0
t$perf-norm:sp_supplier_invoice 5191 5191 0 0.00 0 6962 18 0 0
t$perf-norm:sp_supplier_order 5177 5177 0 0.00 0 4220 15 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 624 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 1015 19 0 0
t$perf-norm:srv_make_invnt_saldo 449 449 0 0.00 0 5064 27 0 0
t$perf-norm:srv_make_money_saldo 716 716 0 0.00 0 814 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 2207 554 0 0
v_add_invoice_to_stock 9602 9354 248 2.58 0 13704 18 0 248
v_all_customers 35718 35718 0 0.00 0 17 1 0 0
v_all_suppliers 28984 28984 0 0.00 0 15 1 0 0
v_all_wares 39194 39194 0 0.00 0 44 1 0 0
v_cancel_adding_invoice 844 844 0 0.00 0 1507 8 0 0
v_cancel_client_order 3224 3224 0 0.00 0 657 3 0 0
v_cancel_customer_prepayment 1274 1274 0 0.00 0 1247 7 0 0
v_cancel_customer_reserve 1284 1284 0 0.00 0 2086 5 0 0
v_cancel_payment_to_supplier 826 826 0 0.00 0 1369 7 0 0
v_cancel_supplier_invoice 780 762 18 2.30 0 1347 14 0 18
v_cancel_supplier_order 792 792 0 0.00 0 2584 8 0 0
v_cancel_write_off 1846 1846 0 0.00 0 4318 15 0 0
v_max_id_clo_ord 5177 5177 0 0.00 0 505 1 0 0
v_max_id_clo_res 959 959 0 0.00 0 3514 20 0 0
v_max_id_ord_sup 5190 5190 0 0.00 0 3412 3 0 0
v_max_non_paid_invoice 1462 1462 0 0.00 0 291 2 0 0
v_max_non_paid_realizn 3553 3553 0 0.00 0 4002 4 0 0
v_min_id_clo_ord 5177 5177 0 0.00 0 24734 14 0 0
v_min_id_clo_res 959 959 0 0.00 0 5492 17 0 0
v_min_id_ord_sup 5190 5190 0 0.00 0 5804 7 0 0
v_min_non_paid_invoice 5587 5587 0 0.00 0 6392 12 0 0
v_min_non_paid_realizn 6707 6707 0 0.00 0 4418 10 0 0
v_our_firm 9784 9784 0 0.00 0 21 1 0 0
v_reserve_write_off 18782 18782 0 0.00 0 6087 5 0 0
x_lock_depdocs_on_canc_invoice 11587 11587 0 0.00 0 28346 128 0 0
x_lock_depdocs_on_canc_sup_ord 396 396 0 0.00 0 7675 304 0 0
x_make_qstorno_1000_1200 5177 5177 0 0.00 2 102114 1402 0 0
x_make_qstorno_1200_2000 5191 5191 0 0.00 4 99482 3587 0 0
x_make_qstorno_2100_3300 438044 438044 0 0.00 0 93620 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-02-2021 04:28:17 to 22-02-2021 04:28:21.

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
01:57:00 18 8192000000 8801748064 8814190592 5768576 0.070 3 1 2 0 5869744 65392 35776
01:58:00 39085 8192000000 9721840128 10017153024 793769760 8.833 100 89 176 2 822364192 7874816 20719616
01:59:46 13550 8192000000 9756332768 10057814016 832501728 9.224 100 91 182 0 867120080 13021056 21597296
02:01:03 20056 8192000000 9789107168 10099130368 850566896 9.406 100 84 168 0 895815216 25850960 19397360
02:02:26 12310 8192000000 9854373904 10182627328 903813472 9.936 100 76 152 1 966286048 45709936 16762640
02:03:41 10531 8192000000 9908652256 10253164544 951627264 10.407 100 69 137 1 1019624336 53919328 14077744
02:04:54 5341 8192000000 9977883632 10327035904 988603152 10.768 100 87 173 0 1088533184 82410384 17519648
02:06:01 2506 8192000000 9977748848 10337939456 1012754176 11.002 100 75 150 0 1098761232 70478304 15528752
02:07:06 2012 8192000000 9998465648 10367057920 1046192816 11.324 100 85 170 0 1113771936 50217024 17362096
02:08:09 30099 8192000000 9963609760 10358341632 1064636608 11.501 100 40 79 2 1108815648 35793952 8385088
02:09:42 7078 8192000000 9990592848 10367803392 1073137376 11.582 100 32 62 0 1108773504 28928448 6707680
02:10:50 4456 8192000000 10022019344 10406240256 1080485248 11.652 100 41 82 0 1128111760 39247872 8378640
02:11:54 45401 8192000000 10007157072 10415636480 1086409520 11.709 100 40 78 1 1105760272 11151648 8199104
02:13:40 9396 8192000000 9994337584 10408984576 1095089184 11.791 100 35 70 1 1122740400 19876224 7774992
02:14:51 12474 8192000000 10009975168 10416242688 1099687712 11.835 100 25 50 0 1122884512 17631424 5565376
02:16:04 5488 8192000000 10005902416 10406150144 1090154944 11.744 99 39 75 2 1117540896 18851440 8534512
02:17:13 51184 8192000000 10008252272 10396667904 1077312560 11.622 99 52 107 2 1105197008 16670336 11214112
02:19:04 7587 8192000000 9977872736 10361651200 1038101536 11.246 97 34 69 0 1066645520 21162992 7380992
02:20:14 6434 8192000000 9932463568 10322432000 990004688 10.782 96 35 70 2 1011983712 14318016 7661008
02:21:23 11835 8192000000 9914980064 10306818048 952915712 10.420 94 36 72 1 988264144 27813024 7535408
02:22:35 60350 8192000000 9936514976 10334048256 1014500880 11.019 98 34 68 0 1027756528 5775360 7480288
02:24:35 7893 8192000000 9951295008 10344796160 1048016448 11.342 100 41 81 2 1074990384 17703296 9270640
02:25:48 6829 8192000000 9979123168 10377351168 1061169504 11.468 100 37 74 3 1085708704 16643296 7895904
02:26:55 7548 8192000000 9979052000 10371796992 1070140096 11.553 100 33 63 0 1090738368 13668160 6930112
02:28:09 119057 8192000000 10051309728 10471424000 1101315744 11.850 100 41 82 0 1144970736 34549072 9105920
02:31:14 35576 8192000000 10097888368 10512551936 1125029712 12.074 100 48 96 3 1208978160 72685136 11263312
02:32:58 11889 8192000000 10145403728 10558894080 1136659456 12.184 100 52 104 2 1263495008 114963264 11872288
02:34:15 20795 8192000000 10161245168 10593280000 1148548512 12.296 100 45 90 2 1294786736 136041152 10197072
02:35:38 8811 8192000000 10223581056 10644725760 1152469280 12.333 100 59 118 3 1332673200 167537664 12666256
02:36:50 3837 8192000000 10228374256 10644287488 1146741024 12.279 99 65 129 0 1329245120 167967552 14536544
02:37:56 3302 8192000000 10229904912 10652897280 1161870656 12.421 100 78 157 0 1327021360 148251248 16899456
02:39:02 2623 8192000000 10247893744 10649579520 1168322112 12.481 100 86 172 1 1346816496 159979520 18514864
02:40:08 4367 8192000000 10260976496 10665267200 1147710992 12.288 100 79 155 0 1359980384 194758496 17510896
02:41:20 3329 8192000000 10252121936 10691534848 1164961680 12.450 100 67 133 1 1362954704 182154352 15838672
02:42:33 2816 8192000000 10278842576 10682109952 1152501344 12.333 100 66 131 0 1378718544 210715616 15501584
02:43:40 2337 8192000000 10298270192 10715140096 1127151344 12.094 99 73 145 0 1392806640 248353312 17301984
02:44:48 4131 8192000000 10300288560 10700029952 1126562848 12.089 99 77 154 0 1395226240 250553776 18109616
02:45:58 2535 8192000000 10356543744 10758483968 1167179504 12.470 100 88 175 0 1453413728 265414720 20819504
02:47:06 5295 8192000000 10332809360 10748858368 1180981840 12.599 100 56 112 1 1428723968 233987216 13754912
02:48:18 9977 8192000000 10349848192 10775576576 1148735760 12.298 99 39 78 0 1439782944 280873216 10173968
02:49:38 21964 8192000000 10346620656 10772713472 1148895856 12.299 99 35 70 0 1430373200 272752656 8724688
02:51:03 15067 8192000000 10415640656 10856927232 1140264608 12.218 100 34 68 0 1520978464 371668720 9045136
02:52:30 9858 8192000000 10378894608 10853994496 1121445280 12.041 98 40 80 2 1483452160 351168896 10837984
02:54:38 33173 8192000000 10162312416 10711400448 1122714176 12.053 100 12 24 0 1309179648 183374512 3090960
02:56:13 75850 8192000000 10051971552 10524106752 1086938128 11.714 97 7 14 0 1166294320 78106176 1250016
02:58:32 15116 8192000000 10089464928 10508038144 1123085824 12.056 100 49 98 1 1170584272 37748064 9750384
02:59:54 130298 8192000000 10068698720 10527670272 1117375680 12.002 99 45 87 0 1181359888 55724208 8260000
03:03:06 25708 8192000000 10060122560 10497163264 1097475584 11.814 97 35 72 1 1142020064 36904416 7640064
03:04:37 72665 8192000000 10071401984 10525540352 1126242896 12.086 100 15 30 1 1173317184 43964112 3110176
03:06:52 33212 8192000000 10082887264 10555858944 1134990848 12.168 100 11 22 1 1205650352 68249744 2409760
03:08:27 64965 8192000000 10089361760 10531352576 1140471184 12.220 100 17 34 1 1208403872 63648240 4284448
03:10:47 67735 8192000000 10159066688 10654363648 1142248672 12.237 99 14 28 0 1262178512 116801696 3128144
03:13:10 40243 8192000000 10086214304 10531766272 1149165376 12.302 100 29 58 1 1187443248 31595136 6682736
03:14:51 97916 8192000000 10097289488 10563739648 1095799680 11.798 95 24 48 0 1141257408 40459808 4997920
03:17:32 61842 8192000000 10140934912 10598350848 1141693456 12.231 99 15 30 0 1209280432 63880080 3706896
03:19:37 72633 8192000000 10106800704 10602233856 1114400176 11.974 97 18 36 3 1185445136 67361552 3683408
03:21:50 69248 8192000000 10127966848 10621050880 1130457280 12.126 98 25 50 0 1226605440 90897216 5250944
03:24:19 121140 8192000000 10110154064 10592669696 1139079680 12.207 99 22 44 0 1234390384 90456784 4853920
03:27:55 55980 8192000000 10093709744 10557599744 1143037456 12.244 100 8 16 0 1188166384 43289456 1839472
03:29:54 71556 8192000000 10128459232 10581405696 1144614448 12.259 99 9 18 0 1224832288 78375856 1841984
03:32:08 45602 8192000000 10129700224 10576781312 1135953168 12.177 98 27 54 0 1200214656 58723392 5538096
03:33:54 36425 8192000000 10168047024 10607366144 1121072592 12.037 97 33 66 0 1238451184 110049136 7329456
03:35:30 81974 8192000000 10102177376 10563895296 1131335440 12.134 99 13 26 0 1204899568 70703776 2860352
03:37:52 63055 8192000000 10102887056 10558164992 1090361520 11.746 96 14 28 0 1185014832 91651600 3001712
03:39:57 39705 8192000000 10081000048 10566447104 1099514368 11.833 97 35 70 2 1187722832 81112992 7095472
03:41:45 14815 8192000000 10088081296 10501664768 1121592528 12.042 99 56 112 2 1158603184 26034384 10976272
03:43:04 9917 8192000000 10106153488 10524078080 1114987040 11.980 99 45 88 1 1173687984 49764656 8936288
03:44:22 197942 8192000000 10056758976 10567708672 1100681808 11.844 98 14 28 0 1174052240 70505840 2864592
03:48:41 54295 8192000000 10025000480 10465718272 1059835648 11.455 95 5 10 1 1086332688 25574624 922416
03:50:39 70517 8192000000 10090657648 10532917248 1091640512 11.758 98 21 40 3 1139391440 43420288 4330640
03:52:55 61063 8192000000 10120104144 10599493632 1119392128 12.021 99 22 44 0 1204864448 80837936 4634384
03:54:56 68139 8192000000 10080316096 10520211456 1119667024 12.024 99 17 33 2 1199372256 75654816 4050416
03:57:10 18530 8192000000 10065659952 10495623168 1108495536 11.918 98 36 73 1 1134202064 17918848 7787680
03:58:31 61622 8192000000 10103071888 10596548608 1132045536 12.141 99 21 42 0 1233516704 97219328 4251840
04:00:35 106161 8192000000 10097635584 10569523200 1081414400 11.661 95 21 42 1 1170503488 85198032 3891056
04:03:21 54973 8192000000 10059274656 10541371392 1127452672 12.097 100 5 10 0 1159948640 31739776 756192
04:05:16 82314 8192000000 10159680288 10640683008 1118084368 12.009 98 25 50 1 1230275104 106534576 5656160
04:09:25 89224 8192000000 10081459088 10550235136 1129522992 12.117 99 10 20 0 1223827328 92078656 2225680
04:12:04 55259 8192000000 10114987744 10603122688 1087128336 11.715 95 18 36 2 1154392720 63612368 3652016
04:14:08 168591 8192000000 10117751152 10631172096 1109835312 11.931 97 7 14 0 1191592800 80543008 1214480
04:17:56 94515 8192000000 10081179616 10530435072 1106721808 11.901 97 7 14 0 1164319024 56182240 1414976
04:20:40 67216 8192000000 10104731712 10585784320 1140256128 12.218 100 12 24 0 1206967120 64317248 2393744
04:22:47 103597 8192000000 10114368656 10595213312 1134559136 12.164 99 15 30 0 1169883216 32724080 2600000
04:25:33 50862 8192000000 10118917008 10584637440 1110741104 11.939 97 20 40 0 1172572912 57462352 4369456

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 22-02-2021 04:28:23 to 22-02-2021 04:28:38.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 124 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-02-2021 04:28:39 to 22-02-2021 04:28:41.

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          222934
MON$OLDEST_ACTIVE               226889
MON$OLDEST_SNAPSHOT             226889
MON$NEXT_TRANSACTION            226889
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                       2107840
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             782
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2372 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2372 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2372 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.2372 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 ---
:61: 
:62: mscs-test-161.budzdorov.ru    Mon Feb 22 04:27:03 2021
:63:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:64: 
:65: 
:66: mscs-test-161.budzdorov.ru    Mon Feb 22 04:27:04 2021
:67:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:68: 
--- end of diff output ---

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

22.02.21 04:28:42. 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-22 04:28:44 No severe PSQL-related problems occured
22.02.21 04:28:46. End of report.