Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.01.2021 13:27
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID e0e7b46250ba4f9795ceb55e0face73f
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2020-09-25 13:42
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.00
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16398520 kB
MemAvailable 31231808 kB
Buffers 0 kB
Cached 15146568 kB
SwapCached 4864 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-01-12 13:26:55
DTS_END                         2021-01-12 13:26:55.3250
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/54128
TRN_ID                          248272
ATT_ID                          633
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 9173.85 17075 1100862
customer order: creation 189.51 350 22741
customer order: refuse 15.72 293 1886
order to supplier: creation 49.52 1281 5942
order to supplier: removal 3.52 97490 422
invoice (draft): creation 50.49 3166 6059
invoice (draft): removal 49.16 906 5899
invoice accept: apply 44.84 15427 5381
invoice accept: cancel 43.84 879 5261
customer reserve: creation 4064.78 154 487774
customer reserve: removal 4364.08 62 523689
realization accept: apply 91.42 157 10970
realization accept: cancel 68.63 132 8236
payment from customer: creation 63.99 89 7679
payment from customer: removal 6.24 75 749
payment to supplier: creation 52.71 132 6325
payment to supplier: removal 3.63 66 436
service: total inventory turnovers 4.25 2857 510
service: total monetary turnovers 6.41 472 769
service: aggregate perf. data 1.08 11030 130
service: refresh index statistics 0.03 206485 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-01-2021 13:27:50 to 12-01-2021 13:27:52.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2596
TEST_TIME 2 4072
TEST_TIME 3 4854
TEST_TIME 4 4819
TEST_TIME 5 4956
TEST_TIME 6 4970
TEST_TIME 7 5428
TEST_TIME 8 5541
TEST_TIME 9 5665
TEST_TIME 10 5758
TEST_TIME 11 5994
TEST_TIME 12 6193
TEST_TIME 13 6072
TEST_TIME 14 6591
TEST_TIME 15 7084
TEST_TIME 16 7547
TEST_TIME 17 7896
TEST_TIME 18 8010
TEST_TIME 19 8218
TEST_TIME 20 8727
TEST_TIME 21 9005
TEST_TIME 22 8967
TEST_TIME 23 9034
TEST_TIME 24 9148
TEST_TIME 25 9316
TEST_TIME 26 9348
TEST_TIME 27 9205
TEST_TIME 28 10778
TEST_TIME 29 10691
TEST_TIME 30 10535
TEST_TIME 31 10497
TEST_TIME 32 10296
TEST_TIME 33 10569
TEST_TIME 34 10577
TEST_TIME 35 10476
TEST_TIME 36 10542
TEST_TIME 37 10541
TEST_TIME 38 10645
TEST_TIME 39 10529
TEST_TIME 40 10452
TEST_TIME 41 10211
TEST_TIME 42 9972
TEST_TIME 43 9986
TEST_TIME 44 9942
TEST_TIME 45 9914
TEST_TIME 46 9919
TEST_TIME 47 9875
TEST_TIME 48 9827
TEST_TIME 49 9723
TEST_TIME 50 9687
TEST_TIME 51 9735
TEST_TIME 52 9565
TEST_TIME 53 9608
TEST_TIME 54 9554
TEST_TIME 55 9583
TEST_TIME 56 9556
TEST_TIME 57 9588
TEST_TIME 58 9558
TEST_TIME 59 9674
TEST_TIME 60 9618
TEST_TIME 61 9573
TEST_TIME 62 9764
TEST_TIME 63 9805
TEST_TIME 64 9866
TEST_TIME 65 9869
TEST_TIME 66 9784
TEST_TIME 67 9674
TEST_TIME 68 9587
TEST_TIME 69 9625
TEST_TIME 70 9549
TEST_TIME 71 9495
TEST_TIME 72 9415
TEST_TIME 73 9396
TEST_TIME 74 9362
TEST_TIME 75 9334
TEST_TIME 76 9379
TEST_TIME 77 9319
TEST_TIME 78 9383
TEST_TIME 79 9396
TEST_TIME 80 9458
TEST_TIME 81 9495
TEST_TIME 82 9493
TEST_TIME 83 9500
TEST_TIME 84 9533
TEST_TIME 85 9453
TEST_TIME 86 9374
TEST_TIME 87 9332
TEST_TIME 88 9328
TEST_TIME 89 9295
TEST_TIME 90 9269
TEST_TIME 91 9239
TEST_TIME 92 9250
TEST_TIME 93 9233
TEST_TIME 94 9245
TEST_TIME 95 9254
TEST_TIME 96 9277
TEST_TIME 97 9242
TEST_TIME 98 9293
TEST_TIME 99 9267
TEST_TIME 100 9327
TEST_TIME 101 9294
TEST_TIME 102 9362
TEST_TIME 103 9367
TEST_TIME 104 9370
TEST_TIME 105 9288
TEST_TIME 106 9264
TEST_TIME 107 9205
TEST_TIME 108 9178
TEST_TIME 109 9132
TEST_TIME 110 9162
TEST_TIME 111 9142
TEST_TIME 112 9118
TEST_TIME 113 9128
TEST_TIME 114 9129
TEST_TIME 115 9115
TEST_TIME 116 9176
TEST_TIME 117 9194
TEST_TIME 118 9163
TEST_TIME 119 9188
TEST_TIME 120 9179

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-01-2021 13:27:54 to 12-01-2021 13:27:57.

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 1553794 1553794 0 0.00 0 43405 7 0 0
doc_list_biud 1553790 1553790 0 0.00 0 96315 27 0 0
sp_add_invoice_to_stock 5463 5381 82 1.50 55 198811 15427 0 82
sp_cancel_adding_invoice 5261 5261 0 0.00 2 71261 880 0 0
sp_cancel_client_order 1886 1886 0 0.00 4 12929 293 0 0
sp_cancel_customer_reserve 523689 523689 0 0.00 1 28411 63 0 0
sp_cancel_pay_from_customer 749 749 0 0.00 3 5263 75 0 0
sp_cancel_pay_to_supplier 436 436 0 0.00 2 6879 66 0 0
sp_cancel_supplier_invoice 5906 5899 7 0.11 1 96323 907 0 7
sp_cancel_supplier_order 422 422 0 0.00 10 971235 97491 0 0
sp_cancel_write_off 8236 8236 0 0.00 1 29940 132 0 0
sp_client_order 22741 22741 0 0.00 4 21043 351 0 0
sp_customer_reserve 487774 487774 0 0.00 0 64823 155 0 0
sp_fill_shopping_cart 34734 34734 0 0.00 1 15741 38 0 0
sp_fill_shopping_cart_clo_res 487756 487756 0 0.00 0 16543 14 0 0
sp_get_clo_for_invoice 5363 5363 0 0.00 0 36103 342 0 0
sp_kill_cost_storno 14682 14682 0 0.00 0 12602 53 0 0
sp_kill_qstorno_ret_qs2qd 1061935 1061935 0 0.00 0 96314 34 0 0
sp_kill_qty_storno 561726 561726 0 0.00 0 96315 74 0 0
sp_lock_selected_doc 22508 22508 0 0.00 0 9521 4 0 0
sp_make_cost_storno 30337 30337 0 0.00 0 14948 46 0 0
sp_multiply_rows_for_pdistr 30337 30337 0 0.00 0 7618 27 0 0
sp_multiply_rows_for_qdistr 22741 22741 0 0.00 0 21033 190 0 0
sp_pay_from_customer 7679 7679 0 0.00 4 11430 90 0 0
sp_pay_to_supplier 6325 6325 0 0.00 5 12405 133 0 0
sp_payment_common 14004 14004 0 0.00 1 10709 22 0 0
sp_qd_handle_on_cancel_clo 1886 1886 0 0.00 0 9791 266 0 0
sp_qd_handle_on_invoice_upd_sts 10624 10624 0 0.00 0 22399 353 0 0
sp_qd_handle_on_reserve_upd_sts 10970 10970 0 0.00 0 18450 48 0 0
sp_reserve_write_off 10970 10970 0 0.00 7 18473 158 0 0
sp_supplier_invoice 6059 6059 0 0.00 6 111042 3166 0 0
sp_supplier_order 5942 5942 0 0.00 5 37143 1281 0 0
srv_aggregate_perf_data 130 130 0 0.00 2162 194400 11031 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1830 162906 46263 0 0
srv_find_qd_qs_mism 1050178 1050178 0 0.00 0 43403 6 0 0
srv_make_invnt_saldo 510 510 0 0.00 34 112857 2857 0 0
srv_make_money_saldo 769 769 0 0.00 0 21550 472 0 0
srv_recalc_idx_stat 4 4 0 0.00 144457 310563 206485 0 0
t$perf-abend:sp_add_invoice_to_stock 82 82 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5381 5381 0 0.00 0 16957 137 0 0
t$perf-norm:sp_cancel_adding_invoice 439 439 0 0.00 0 9325 88 0 0
t$perf-norm:sp_cancel_client_order 1886 1886 0 0.00 0 2675 9 0 0
t$perf-norm:sp_cancel_customer_reserve 692 692 0 0.00 0 1987 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 749 749 0 0.00 0 2955 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 436 436 0 0.00 0 6306 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 465 465 0 0.00 0 1194 16 0 0
t$perf-norm:sp_cancel_supplier_order 422 422 0 0.00 0 21338 1248 0 0
t$perf-norm:sp_cancel_write_off 1086 1086 0 0.00 0 1160 4 0 0
t$perf-norm:sp_client_order 22741 22741 0 0.00 0 7776 16 0 0
t$perf-norm:sp_customer_reserve 1083 1083 0 0.00 0 12057 24 0 0
t$perf-norm:sp_pay_from_customer 7679 7679 0 0.00 0 12078 10 0 0
t$perf-norm:sp_pay_to_supplier 6325 6325 0 0.00 0 13266 15 0 0
t$perf-norm:sp_reserve_write_off 10970 10970 0 0.00 0 9727 10 0 0
t$perf-norm:sp_supplier_invoice 6059 6059 0 0.00 0 4090 15 0 0
t$perf-norm:sp_supplier_order 5942 5942 0 0.00 0 10075 18 0 0
t$perf-norm:srv_aggregate_perf_data 130 130 0 0.00 0 109 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 430 8 0 0
t$perf-norm:srv_make_invnt_saldo 510 510 0 0.00 0 254 3 0 0
t$perf-norm:srv_make_money_saldo 769 769 0 0.00 0 4035 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 1227 309 0 0
v_add_invoice_to_stock 10890 10726 164 1.50 0 5932 14 0 164
v_all_customers 40968 40968 0 0.00 0 105 1 0 0
v_all_suppliers 32964 32964 0 0.00 0 76 1 0 0
v_all_wares 45468 45468 0 0.00 0 14 1 0 0
v_cancel_adding_invoice 878 878 0 0.00 0 1539 9 0 0
v_cancel_client_order 3772 3772 0 0.00 0 3513 5 0 0
v_cancel_customer_prepayment 1498 1498 0 0.00 0 5249 7 0 0
v_cancel_customer_reserve 1384 1384 0 0.00 0 2072 5 0 0
v_cancel_payment_to_supplier 872 872 0 0.00 0 6868 12 0 0
v_cancel_supplier_invoice 944 930 14 1.48 0 1881 11 0 14
v_cancel_supplier_order 844 844 0 0.00 0 6869 16 0 0
v_cancel_write_off 2172 2172 0 0.00 0 1573 13 0 0
v_max_id_clo_ord 5942 5942 0 0.00 0 169 1 0 0
v_max_id_clo_res 1083 1083 0 0.00 0 3094 17 0 0
v_max_id_ord_sup 6057 6057 0 0.00 0 4138 4 0 0
v_max_non_paid_invoice 1837 1837 0 0.00 0 450 2 0 0
v_max_non_paid_realizn 4283 4283 0 0.00 0 1050 3 0 0
v_min_id_clo_ord 5942 5942 0 0.00 0 3152 6 0 0
v_min_id_clo_res 1083 1083 0 0.00 0 5218 23 0 0
v_min_id_ord_sup 6057 6057 0 0.00 0 8254 5 0 0
v_min_non_paid_invoice 6325 6325 0 0.00 0 6518 20 0 0
v_min_non_paid_realizn 7679 7679 0 0.00 0 8335 13 0 0
v_our_firm 11292 11292 0 0.00 0 15 1 0 0
v_reserve_write_off 21940 21940 0 0.00 0 6895 5 0 0
x_lock_depdocs_on_canc_invoice 10695 10695 0 0.00 0 10623 104 0 0
x_lock_depdocs_on_canc_sup_ord 422 422 0 0.00 0 12859 298 0 0
x_make_qstorno_1000_1200 5942 5942 0 0.00 2 37018 1213 0 0
x_make_qstorno_1200_2000 6059 6059 0 0.00 2 111034 3023 0 0
x_make_qstorno_2100_3300 487774 487774 0 0.00 0 64822 138 0 0

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

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
10:56:59 7 8192000000 8796638048 8807108608 3168016 0.038 1 1 2 0 3265632 61840 35776
10:58:00 25273 8192000000 9697482368 9989033984 770355504 8.595 100 82 164 0 798387840 7689952 20342384
10:59:35 24456 8192000000 9741806832 10044592128 818570016 9.084 100 86 172 0 851138144 11706464 20861664
11:01:07 21587 8192000000 9794603120 10112249856 851937360 9.419 100 76 152 1 897165328 27359664 17868304
11:02:32 12259 8192000000 9855137024 10186493952 910731776 10.005 100 78 156 0 961665936 33344224 17589936
11:03:49 8394 8192000000 9925115904 10267144192 967202576 10.559 100 83 166 2 1031606400 47392688 17011136
11:04:58 4815 8192000000 9980532688 10332237824 992046832 10.801 100 79 159 0 1100436016 92973408 15415776
11:06:05 2917 8192000000 9993471120 10352984064 1020504464 11.077 100 82 164 0 1108561296 71121824 16935008
11:07:10 2012 8192000000 10033511248 10397638656 1043084800 11.294 100 93 185 0 1148127312 85293248 19749264
11:08:14 2062 8192000000 10038692480 10404962304 1060344384 11.460 100 90 178 2 1147172736 68357952 18470400
11:09:16 3646 8192000000 10006414384 10393518080 1065594192 11.510 100 69 137 1 1112611584 33869616 13147776
11:10:22 7421 8192000000 10005451408 10391707648 1067968752 11.533 100 36 71 1 1105998032 30793120 7236160
11:11:30 4601 8192000000 9999263008 10401673216 1079729232 11.645 100 39 74 3 1111390704 23690560 7970912
11:12:35 59198 8192000000 9989480800 10389315584 1082891920 11.675 100 38 76 1 1108002976 15987168 9123888
11:14:35 5654 8192000000 10005169088 10414354432 1092434224 11.766 100 29 58 1 1127590944 28737904 6418816
11:15:42 5767 8192000000 10025730544 10424659968 1097837472 11.817 100 40 82 0 1127942560 21686704 8418384
11:16:51 4727 8192000000 10008255088 10402131968 1084746304 11.693 99 32 64 0 1126101360 33840176 7514880
11:17:56 36356 8192000000 9979258400 10388467712 997567616 10.855 92 28 56 0 1021588576 18766464 5254496
11:19:34 4980 8192000000 9945531632 10319745024 1024314400 11.114 97 40 77 2 1047473584 13854048 9305136
11:20:40 9146 8192000000 9938140304 10317733888 1001945232 10.897 97 32 64 1 1032760128 23741328 7073568
11:21:52 26988 8192000000 9924037408 10318241792 1023188256 11.103 100 32 64 2 1050101072 19101760 7811056
11:23:22 9099 8192000000 9936662752 10333806592 1020707376 11.079 100 37 74 3 1050759600 21993488 8058736
11:24:33 12715 8192000000 9962060752 10348728320 1046577600 11.328 100 35 70 4 1072480832 17757440 8145792
11:25:49 7787 8192000000 9988297440 10381029376 1059621808 11.453 100 39 77 1 1091242400 22927744 8692848
11:26:57 61202 8192000000 10009061072 10399580160 1075431152 11.604 100 45 89 1 1107518688 22396288 9691248
11:29:21 7617 8192000000 10046749936 10445201408 1103765648 11.873 100 41 83 1 1161899728 49698320 8435760
11:30:34 42722 8192000000 10083710832 10487545856 1118302864 12.011 100 45 89 1 1197803936 70086544 9414528
11:32:22 16081 8192000000 10112421856 10523082752 1133588112 12.155 100 45 90 2 1231137440 87463824 10085504
11:33:48 22082 8192000000 10158062176 10570395648 1145055648 12.263 100 50 100 0 1246873200 90701296 11116256
11:35:21 10183 8192000000 10174287376 10582953984 1157335728 12.378 100 60 117 4 1278386032 107319136 13731168
11:36:35 4093 8192000000 10190686800 10602098688 1161771936 12.420 100 53 105 0 1298268720 124846656 11650128
11:37:43 8997 8192000000 10233892400 10635710464 1166342784 12.463 100 82 163 2 1329659504 144811104 18505616
11:38:55 6634 8192000000 10245470032 10653437952 1164068384 12.441 99 66 133 1 1343746800 164193600 15484816
11:40:08 2193 8192000000 10281703296 10685718528 1155065744 12.357 100 77 154 0 1388075136 215201488 17807904
11:41:15 14277 8192000000 10251009936 10697302016 1153895760 12.346 100 41 80 1 1370494512 206711552 9887200
11:42:35 3236 8192000000 10252485216 10661163008 1152731616 12.335 99 75 150 1 1349150288 178928752 17489920
11:43:43 1828 8192000000 10220301264 10646917120 1149730848 12.307 100 61 122 0 1327499504 163505552 14263104
11:44:49 5102 8192000000 10246720096 10655449088 1125116816 12.075 100 64 128 1 1355017808 215262144 14638848
11:46:02 3318 8192000000 10253861824 10665029632 1138872656 12.205 100 72 144 1 1355395536 199805968 16716912
11:47:09 6540 8192000000 10205835328 10651078656 1127176192 12.095 99 45 89 2 1304357408 167177456 10003760
11:48:24 3274 8192000000 10259464704 10669260800 1140065296 12.216 99 67 134 2 1355853632 199859024 15929312
11:49:30 9130 8192000000 10288930016 10712084480 1143353920 12.247 100 37 75 0 1392684512 240636720 8693872
11:50:45 17907 8192000000 10344428432 10776293376 1144304656 12.256 100 31 60 1 1438426976 286744896 7377424
11:52:04 25980 8192000000 10362476704 10822406144 1137500928 12.192 100 24 48 2 1453615440 309857392 6257120
11:53:56 20972 8192000000 10289440608 10754117632 1113190640 11.963 100 23 44 2 1435869344 316480592 6198112
11:55:28 58286 8192000000 10067242768 10492055552 1068764128 11.540 95 14 28 0 1099971744 27824624 3382992
11:57:27 73279 8192000000 10108873200 10549235712 1086710384 11.711 98 15 30 0 1170941936 81352656 2878896
11:59:59 77202 8192000000 10061591024 10525331456 1116307888 11.992 100 13 26 1 1184178608 65566864 2303856
12:02:17 162905 8192000000 10077839888 10548359168 1101241824 11.849 97 6 12 0 1154566736 52263168 1061744
12:06:08 156637 8192000000 10098256976 10533302272 1135966416 12.178 99 13 26 0 1189318688 50495360 2856912
12:10:11 43676 8192000000 10113420496 10572582912 1145310944 12.265 100 37 72 2 1209745744 57385808 7048992
12:12:00 31127 8192000000 10074302272 10548895744 1148274336 12.293 100 19 39 1 1176836704 24845152 3717216
12:13:31 28097 8192000000 10097294864 10548772864 1142182576 12.236 99 16 32 1 1190663696 45327888 3153232
12:15:00 37978 8192000000 10105982064 10554748928 1146254576 12.274 100 7 14 1 1208731440 60989472 1487392
12:16:39 41291 8192000000 10108797328 10579140608 1123683296 12.062 98 11 22 0 1194275088 68268592 2323200
12:18:21 60314 8192000000 10110591664 10590998528 1130001872 12.121 99 17 34 0 1195207440 61613664 3591904
12:20:43 61754 8192000000 10057192288 10526580736 1105063136 11.886 97 15 30 0 1151594480 43198256 3333088
12:22:47 18693 8192000000 10097290528 10528432128 1128061872 12.103 99 51 102 1 1166494544 28103600 10329072
12:24:08 35746 8192000000 10098416384 10532999168 1132604352 12.146 100 21 42 2 1188741264 51823184 4313728
12:25:44 40380 8192000000 10119345856 10566172672 1128615840 12.108 99 21 42 1 1200978272 67640768 4721664
12:27:25 64188 8192000000 10086674816 10583855104 1093018032 11.771 97 24 47 1 1194962912 97105472 4839408
12:29:35 81311 8192000000 10086031408 10584358912 1083546976 11.681 95 11 22 0 1151057632 65224336 2286320
12:31:57 31208 8192000000 10080007248 10507624448 1094624528 11.787 97 39 76 2 1132325184 29193120 8507536
12:33:35 75245 8192000000 10082104464 10513518592 1100739152 11.845 98 25 50 0 1170542704 63953232 5850320
12:36:02 32628 8192000000 10027367520 10492510208 1116866256 11.997 100 7 14 1 1138208832 20282640 1059936
12:38:14 74750 8192000000 10049346336 10516860928 1103465040 11.871 98 11 22 0 1146042384 40302032 2275312
12:41:17 33707 8192000000 10080812224 10523877376 1095114464 11.791 98 30 60 1 1149506768 48007168 6385136
12:43:12 34403 8192000000 10095273216 10532171776 1113362784 11.964 99 28 55 2 1177646480 58029472 6254224
12:44:59 82066 8192000000 10088473280 10572881920 1085826160 11.703 97 24 48 0 1185571776 94492496 5253120
12:47:24 54389 8192000000 10055480864 10478952448 1105516672 11.890 98 43 86 2 1137386096 22349600 9519824
12:49:18 20553 8192000000 10126497808 10556964864 1130080304 12.122 100 41 82 0 1201248000 63335968 7831728
12:50:40 109071 8192000000 10046339152 10496937984 1117257056 12.001 99 23 46 1 1166562848 44698896 4606896
12:53:40 58350 8192000000 10074521904 10552602624 1132308528 12.143 100 10 17 0 1201914064 67140304 2465232
12:55:39 77671 8192000000 10060129328 10511536128 1112895312 11.960 98 21 42 1 1148869856 31484784 4489760
12:58:03 48317 8192000000 10057021376 10509484032 1085292688 11.698 96 12 24 1 1118651968 31164192 2195088
12:59:51 33254 8192000000 10086639280 10533105664 1111663296 11.948 98 35 66 0 1176123808 57737568 6722944
13:01:33 68919 8192000000 10099692656 10541363200 1102868592 11.865 97 31 62 1 1157426320 48506880 6050848
13:03:47 70329 8192000000 10054755360 10524635136 1115953616 11.989 98 23 46 0 1172421040 51898752 4568672
13:06:23 108851 8192000000 10094577952 10568507392 1081432800 11.661 95 9 18 0 1132719648 49298672 1988176
13:09:12 46833 8192000000 10087076960 10552205312 1098519232 11.824 96 17 34 2 1154470208 52540864 3410112
13:11:02 112083 8192000000 10040655648 10504658944 1088925616 11.732 97 18 36 1 1128691680 36100944 3665120
13:13:55 73219 8192000000 10063398320 10533355520 1134923312 12.168 100 11 22 0 1171694288 34079248 2691728
13:16:08 74770 8192000000 10054105856 10504323072 1045140736 11.314 92 21 42 1 1100179232 50309456 4729040
13:18:26 34160 8192000000 10073363664 10524856320 1103864288 11.874 98 16 32 2 1148316576 41043472 3408816
13:20:08 42737 8192000000 10080409600 10522464256 1113006912 11.961 99 27 54 1 1180284752 61804544 5473296
13:22:14 105067 8192000000 10050064624 10508025856 1062211456 11.478 94 20 40 0 1156366448 90209488 3945504
13:25:01 45661 8192000000 10048753360 10497077248 1064240976 11.497 95 36 72 1 1135961280 64654656 7065648

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 12-01-2021 13:28:04 to 12-01-2021 13:28:19.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 82 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-01-2021 13:28:20 to 12-01-2021 13:28:22.

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          244838
MON$OLDEST_ACTIVE               248662
MON$OLDEST_SNAPSHOT             248662
MON$NEXT_TRANSACTION            248662
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                       2119424
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                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             831
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2324 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2324 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2324 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.2324 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 ---
:152: 
:153: mscs-test-161.budzdorov.ru    Tue Jan 12 13:27:03 2021
:154:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:155: 
:156: 
:157: mscs-test-161.budzdorov.ru    Tue Jan 12 13:27:03 2021
:158:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:159: 
:160: 
:161: mscs-test-161.budzdorov.ru    Tue Jan 12 13:27:06 2021
:162:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:163: 
--- end of diff output ---

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

12.01.21 13:28:23. 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-01-12 13:28:26 No severe PSQL-related problems occured
12.01.21 13:28:27. End of report.