Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.06.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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 16377376 kB
MemAvailable 31307292 kB
Buffers 20 kB
Cached 15185196 kB
SwapCached 2416 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.1
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-06-22 04:27:17
DTS_END                         2021-06-22 04:27:00.4040
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/53700
TRN_ID                          226053
ATT_ID                          259
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: 9123.35 24349 1094802
customer order: creation 163.35 411 19602
customer order: refuse 13.91 366 1669
order to supplier: creation 44.13 1492 5295
order to supplier: removal 3.29 122934 395
invoice (draft): creation 43.94 3773 5273
invoice (draft): removal 52.79 954 6335
invoice accept: apply 39.82 18029 4778
invoice accept: cancel 48.58 986 5829
customer reserve: creation 3694.92 175 443390
customer reserve: removal 4720.81 68 566497
realization accept: apply 79.78 201 9574
realization accept: cancel 96.33 120 11559
payment from customer: creation 56.30 115 6756
payment from customer: removal 5.63 103 676
payment to supplier: creation 46.22 139 5546
payment to supplier: removal 3.13 113 376
service: total inventory turnovers 3.64 4042 437
service: total monetary turnovers 6.05 664 726
service: aggregate perf. data 0.71 22158 85
service: refresh index statistics 0.03 310144 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-06-2021 04:27:46 to 22-06-2021 04:27:48.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8349
TEST_TIME 2 5593
TEST_TIME 3 4660
TEST_TIME 4 5004
TEST_TIME 5 4857
TEST_TIME 6 4798
TEST_TIME 7 5306
TEST_TIME 8 5395
TEST_TIME 9 5407
TEST_TIME 10 5705
TEST_TIME 11 5821
TEST_TIME 12 5855
TEST_TIME 13 6049
TEST_TIME 14 6153
TEST_TIME 15 6303
TEST_TIME 16 6589
TEST_TIME 17 6901
TEST_TIME 18 6917
TEST_TIME 19 7080
TEST_TIME 20 7134
TEST_TIME 21 7302
TEST_TIME 22 7809
TEST_TIME 23 7955
TEST_TIME 24 8645
TEST_TIME 25 8802
TEST_TIME 26 8848
TEST_TIME 27 8944
TEST_TIME 28 9199
TEST_TIME 29 9803
TEST_TIME 30 10354
TEST_TIME 31 10510
TEST_TIME 32 10552
TEST_TIME 33 10398
TEST_TIME 34 10431
TEST_TIME 35 10342
TEST_TIME 36 10131
TEST_TIME 37 10074
TEST_TIME 38 9901
TEST_TIME 39 9688
TEST_TIME 40 9684
TEST_TIME 41 9648
TEST_TIME 42 9567
TEST_TIME 43 9583
TEST_TIME 44 9580
TEST_TIME 45 9572
TEST_TIME 46 9473
TEST_TIME 47 9477
TEST_TIME 48 9344
TEST_TIME 49 9346
TEST_TIME 50 9480
TEST_TIME 51 9427
TEST_TIME 52 9448
TEST_TIME 53 9475
TEST_TIME 54 9498
TEST_TIME 55 9541
TEST_TIME 56 9545
TEST_TIME 57 9574
TEST_TIME 58 9543
TEST_TIME 59 9676
TEST_TIME 60 9699
TEST_TIME 61 9648
TEST_TIME 62 9611
TEST_TIME 63 9464
TEST_TIME 64 9416
TEST_TIME 65 9348
TEST_TIME 66 9303
TEST_TIME 67 9208
TEST_TIME 68 9259
TEST_TIME 69 9231
TEST_TIME 70 9206
TEST_TIME 71 9182
TEST_TIME 72 9305
TEST_TIME 73 9220
TEST_TIME 74 9386
TEST_TIME 75 9359
TEST_TIME 76 9372
TEST_TIME 77 9401
TEST_TIME 78 9356
TEST_TIME 79 9402
TEST_TIME 80 9459
TEST_TIME 81 9384
TEST_TIME 82 9281
TEST_TIME 83 9227
TEST_TIME 84 9170
TEST_TIME 85 9104
TEST_TIME 86 9050
TEST_TIME 87 9024
TEST_TIME 88 9054
TEST_TIME 89 9062
TEST_TIME 90 9053
TEST_TIME 91 9091
TEST_TIME 92 9089
TEST_TIME 93 9059
TEST_TIME 94 9138
TEST_TIME 95 9129
TEST_TIME 96 9116
TEST_TIME 97 9140
TEST_TIME 98 9191
TEST_TIME 99 9178
TEST_TIME 100 9239
TEST_TIME 101 9266
TEST_TIME 102 9282
TEST_TIME 103 9252
TEST_TIME 104 9176
TEST_TIME 105 9141
TEST_TIME 106 9086
TEST_TIME 107 9025
TEST_TIME 108 8981
TEST_TIME 109 9057
TEST_TIME 110 9015
TEST_TIME 111 9061
TEST_TIME 112 9052
TEST_TIME 113 9055
TEST_TIME 114 9031
TEST_TIME 115 9082
TEST_TIME 116 9184
TEST_TIME 117 9220
TEST_TIME 118 9227
TEST_TIME 119 9205
TEST_TIME 120 9128

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 22-06-2021 04:27:49 to 22-06-2021 04:27:53.

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 1504402 1504402 0 0.00 0 11665 7 0 0
doc_list_biud 1504398 1504398 0 0.00 0 27689 33 0 0
sp_add_invoice_to_stock 4879 4778 101 2.07 31 178945 18029 0 101
sp_cancel_adding_invoice 5829 5829 0 0.00 5 84608 986 0 0
sp_cancel_client_order 1669 1669 0 0.00 4 9353 367 0 0
sp_cancel_customer_reserve 566497 566497 0 0.00 1 17159 69 0 0
sp_cancel_pay_from_customer 676 676 0 0.00 3 7753 103 0 0
sp_cancel_pay_to_supplier 376 376 0 0.00 3 5966 114 0 0
sp_cancel_supplier_invoice 6348 6335 13 0.20 2 15525 955 0 13
sp_cancel_supplier_order 395 395 0 0.00 8 1215159 122935 0 0
sp_cancel_write_off 11559 11559 0 0.00 1 9487 120 0 0
sp_client_order 19602 19602 0 0.00 4 20154 411 0 0
sp_customer_reserve 443390 443390 0 0.00 0 50246 176 0 0
sp_fill_shopping_cart 30159 30159 0 0.00 1 16784 46 0 0
sp_fill_shopping_cart_clo_res 443380 443380 0 0.00 0 27472 16 0 0
sp_get_clo_for_invoice 4766 4766 0 0.00 0 29184 351 0 0
sp_kill_cost_storno 18440 18440 0 0.00 0 8375 59 0 0
sp_kill_qstorno_ret_qs2qd 1151283 1151283 0 0.00 0 16332 37 0 0
sp_kill_qty_storno 606623 606623 0 0.00 0 27688 80 0 0
sp_lock_selected_doc 19857 19857 0 0.00 0 11319 7 0 0
sp_make_cost_storno 26642 26642 0 0.00 0 10077 60 0 0
sp_multiply_rows_for_pdistr 26641 26641 0 0.00 0 7701 33 0 0
sp_multiply_rows_for_qdistr 19602 19602 0 0.00 0 18431 213 0 0
sp_pay_from_customer 6756 6756 0 0.00 4 8669 116 0 0
sp_pay_to_supplier 5546 5546 0 0.00 4 18569 139 0 0
sp_payment_common 12299 12299 0 0.00 1 13945 23 0 0
sp_qd_handle_on_cancel_clo 1669 1669 0 0.00 0 9344 337 0 0
sp_qd_handle_on_invoice_upd_sts 10595 10595 0 0.00 0 27688 393 0 0
sp_qd_handle_on_reserve_upd_sts 9573 9573 0 0.00 0 11214 67 0 0
sp_reserve_write_off 9574 9574 0 0.00 6 16264 201 0 0
sp_supplier_invoice 5273 5273 0 0.00 7 88875 3773 0 0
sp_supplier_order 5295 5295 0 0.00 6 24279 1492 0 0
srv_aggregate_perf_data 86 85 1 1.16 3008 633283 22158 0 1
srv_fill_mon_cache_memory 64 64 0 0.00 1543 137497 45653 0 0
srv_find_qd_qs_mism 1048841 1048841 0 0.00 0 9390 5 0 0
srv_make_invnt_saldo 437 437 0 0.00 47 95121 4042 0 0
srv_make_money_saldo 726 726 0 0.00 1 13303 665 0 0
srv_recalc_idx_stat 4 4 0 0.00 133120 635428 310145 0 0
t$perf-abend:sp_add_invoice_to_stock 101 101 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4778 4778 0 0.00 0 17456 177 0 0
t$perf-norm:sp_cancel_adding_invoice 442 442 0 0.00 0 4409 116 0 0
t$perf-norm:sp_cancel_client_order 1669 1669 0 0.00 0 2621 14 0 0
t$perf-norm:sp_cancel_customer_reserve 643 643 0 0.00 0 1510 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 676 676 0 0.00 0 6925 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 376 376 0 0.00 0 3268 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 377 377 0 0.00 0 1152 28 0 0
t$perf-norm:sp_cancel_supplier_order 395 395 0 0.00 0 20743 1738 0 0
t$perf-norm:sp_cancel_write_off 942 942 0 0.00 0 2433 9 0 0
t$perf-norm:sp_client_order 19602 19602 0 0.00 0 7248 18 0 0
t$perf-norm:sp_customer_reserve 942 942 0 0.00 0 729 5 0 0
t$perf-norm:sp_pay_from_customer 6756 6756 0 0.00 0 15811 11 0 0
t$perf-norm:sp_pay_to_supplier 5546 5546 0 0.00 0 4117 6 0 0
t$perf-norm:sp_reserve_write_off 9574 9574 0 0.00 0 7569 10 0 0
t$perf-norm:sp_supplier_invoice 5273 5273 0 0.00 0 3055 17 0 0
t$perf-norm:sp_supplier_order 5295 5295 0 0.00 0 6196 21 0 0
t$perf-norm:srv_aggregate_perf_data 85 85 0 0.00 0 582 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 81 3 0 0
t$perf-norm:srv_make_invnt_saldo 437 437 0 0.00 0 1272 10 0 0
t$perf-norm:srv_make_money_saldo 726 726 0 0.00 0 397 3 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 103 28 0 0
v_add_invoice_to_stock 9732 9530 202 2.07 0 8024 15 0 202
v_all_customers 35506 35506 0 0.00 0 22 1 0 0
v_all_suppliers 29332 29332 0 0.00 0 26 1 0 0
v_all_wares 39200 39200 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 884 884 0 0.00 0 677 8 0 0
v_cancel_client_order 3338 3338 0 0.00 0 4130 5 0 0
v_cancel_customer_prepayment 1352 1352 0 0.00 0 4247 11 0 0
v_cancel_customer_reserve 1286 1286 0 0.00 0 4323 10 0 0
v_cancel_payment_to_supplier 752 752 0 0.00 0 184 3 0 0
v_cancel_supplier_invoice 780 754 26 3.33 0 11031 25 0 26
v_cancel_supplier_order 790 790 0 0.00 0 1293 5 0 0
v_cancel_write_off 1884 1884 0 0.00 0 2491 15 0 0
v_max_id_clo_ord 5293 5293 0 0.00 0 1657 2 0 0
v_max_id_clo_res 941 941 0 0.00 0 2079 15 0 0
v_max_id_ord_sup 5266 5266 0 0.00 0 6589 6 0 0
v_max_non_paid_invoice 1438 1438 0 0.00 0 664 2 0 0
v_max_non_paid_realizn 3640 3640 0 0.00 0 1937 3 0 0
v_min_id_clo_ord 5293 5293 0 0.00 0 5021 12 0 0
v_min_id_clo_res 941 941 0 0.00 0 1069 4 0 0
v_min_id_ord_sup 5266 5266 0 0.00 0 2443 5 0 0
v_min_non_paid_invoice 5545 5545 0 0.00 0 13942 19 0 0
v_min_non_paid_realizn 6754 6754 0 0.00 0 4433 14 0 0
v_our_firm 9922 9922 0 0.00 0 16 1 0 0
v_reserve_write_off 19144 19144 0 0.00 0 4583 6 0 0
x_lock_depdocs_on_canc_invoice 11787 11787 0 0.00 0 12543 106 0 0
x_lock_depdocs_on_canc_sup_ord 395 395 0 0.00 0 17846 332 0 0
x_make_qstorno_1000_1200 5295 5295 0 0.00 2 24236 1399 0 0
x_make_qstorno_1200_2000 5273 5273 0 0.00 3 88862 3608 0 0
x_make_qstorno_2100_3300 443390 443390 0 0.00 0 47254 157 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-06-2021 04:27:54 to 22-06-2021 04:27:58.

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:04 2 8192000000 8796668864 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
01:58:05 25818 8192000000 9713080416 10011303936 793660512 8.832 100 89 178 1 823299440 7589552 22049376
01:59:35 13133 8192000000 9755798624 10061041664 831647296 9.216 100 88 176 0 863031264 10229104 21154864
02:00:53 25514 8192000000 9802316944 10119544832 869067008 9.591 100 82 164 1 902300848 14306768 18927072
02:02:28 15310 8192000000 9855023088 10185998336 918967136 10.086 100 83 166 1 968720464 31608832 18144496
02:03:52 10600 8192000000 9915121872 10261241856 957144768 10.461 100 76 152 0 1025828992 52999072 15685152
02:05:08 4642 8192000000 9972576224 10322157568 991497824 10.796 100 86 172 2 1079135888 70150528 17487536
02:06:18 2293 8192000000 9995666112 10348883968 1011087264 10.986 100 89 178 2 1108768400 79963664 17717472
02:07:24 2703 8192000000 10006262864 10371330048 1036055088 11.227 100 92 178 2 1123259216 69625936 17578192
02:08:33 1500 8192000000 10043553200 10416439296 1061662448 11.472 100 91 182 0 1153213584 73264880 18286256
02:09:36 25933 8192000000 9993827792 10391080960 1072069184 11.572 100 53 106 1 1124514720 40568800 11876736
02:11:03 6034 8192000000 9995345360 10388807680 1083199184 11.678 100 32 63 1 1108525968 18386688 6940096
02:12:10 5163 8192000000 10011944352 10401005568 1087356704 11.718 100 48 96 1 1126644720 28880672 10407344
02:13:19 62804 8192000000 9988443744 10400849920 1095680304 11.797 100 42 83 1 1117813472 13030272 9102896
02:15:28 7686 8192000000 10016012880 10427637760 1104391808 11.879 100 31 60 0 1138610208 27294688 6923712
02:16:36 5563 8192000000 10027261168 10424401920 1101890944 11.856 100 32 64 1 1136473024 27478480 7103600
02:17:43 5955 8192000000 10015048704 10418565120 1104748352 11.883 100 40 79 0 1129371184 16540048 8082784
02:18:50 36624 8192000000 9989443264 10400751616 1028202544 11.151 94 29 57 0 1054587760 19998352 6386864
02:20:29 3562 8192000000 9974858000 10360934400 1038587936 11.251 99 58 115 2 1081024576 28857840 13578800
02:21:36 7420 8192000000 9931879072 10318057472 988018160 10.762 97 43 86 0 1017639840 20139584 9482096
02:22:44 17224 8192000000 9934978576 10323603456 1018769904 11.060 98 30 56 1 1054501952 29555504 6176544
02:24:02 26626 8192000000 9951512128 10356514816 1021218960 11.084 98 16 32 0 1047338656 22504688 3615008
02:25:31 5384 8192000000 9972267648 10363641856 1062011408 11.476 100 50 100 0 1086248896 13053504 11183984
02:26:38 9010 8192000000 9992051456 10398457856 1068812720 11.541 100 27 54 0 1100766784 25500640 6453424
02:28:00 17562 8192000000 10044423280 10444783616 1098956000 11.828 100 59 117 2 1160987824 50630960 11400864
02:29:47 49019 8192000000 10064974528 10479263744 1115924944 11.988 100 31 62 1 1181204752 59366032 5913776
02:31:44 8239 8192000000 10094049792 10498809856 1132424368 12.144 100 52 105 0 1200870000 56469728 11975904
02:32:56 2181 8192000000 10148037888 10557440000 1145655696 12.269 100 52 102 1 1253152576 97078144 10418736
02:34:01 6468 8192000000 10160659136 10573836288 1154150160 12.348 100 46 90 1 1266526512 102209312 10167040
02:35:14 7375 8192000000 10222264560 10631847936 1164712816 12.447 100 65 130 1 1330146896 151508448 13925632
02:36:36 7306 8192000000 10219094624 10642755584 1169179216 12.489 100 45 90 2 1327157328 148582336 9395776
02:37:49 8922 8192000000 10238067872 10656518144 1177569328 12.568 100 50 99 0 1345119696 156578752 10971616
02:39:06 6343 8192000000 10270464784 10686603264 1185193472 12.639 100 69 138 2 1376131648 176297056 14641120
02:40:18 3207 8192000000 10322253664 10733924352 1194561888 12.726 100 77 154 0 1427768352 215479360 17727104
02:41:24 1885 8192000000 10341393744 10754973696 1191140048 12.694 100 82 163 0 1441179824 230356752 19683024
02:42:31 3567 8192000000 10360530064 10773057536 1179811600 12.588 100 81 162 0 1461243888 262501088 18931200
02:43:39 2229 8192000000 10351218304 10759122944 1156345984 12.369 98 87 171 0 1443535776 266598400 20591392
02:44:47 1542 8192000000 10321162144 10721841152 1148130736 12.292 100 78 155 2 1416040064 248639424 19269904
02:45:52 4298 8192000000 10367119104 10776035328 1160109216 12.404 100 68 134 0 1456924160 281177648 15637296
02:47:03 1856 8192000000 10395096304 10805751808 1167267216 12.471 100 75 150 0 1492811024 308006784 17537024
02:48:11 1966 8192000000 10413009744 10842345472 1155705184 12.363 100 77 152 2 1509334992 335706368 17923440
02:49:22 2145 8192000000 10409862384 10850775040 1154515824 12.352 99 83 165 2 1508379920 334089104 19774992
02:50:27 3341 8192000000 10400129520 10823335936 1149031792 12.300 100 52 104 1 1510095888 348288784 12775312
02:51:43 16817 8192000000 10324002848 10770239488 1150190624 12.311 100 36 71 1 1437567600 278688496 8688480
02:53:05 11557 8192000000 10316207360 10743140352 1139939184 12.215 100 30 60 0 1419566608 272474464 7152960
02:54:19 26771 8192000000 10323845152 10812735488 1120842448 12.035 100 19 38 2 1423354016 297201040 5310528
02:55:50 26024 8192000000 10221032400 10742104064 1112089872 11.952 99 22 44 0 1391426768 273451920 5884976
02:57:36 27938 8192000000 10117589680 10623127552 1120688208 12.033 100 7 14 1 1212467616 90446224 1333184
02:59:05 92374 8192000000 10052443264 10481987584 1077125200 11.620 95 22 46 0 1104166576 22912384 4128992
03:01:47 84360 8192000000 10080257696 10510737408 1137437312 12.191 100 21 39 0 1192740688 50812368 4491008
03:04:14 88331 8192000000 10090660416 10572001280 1122050896 12.046 99 39 77 2 1183486368 53479952 7955520
03:06:47 48754 8192000000 10074029776 10529095680 1126484496 12.088 100 41 82 1 1169111552 33649040 8978016
03:08:39 30486 8192000000 10077854224 10516955136 1127010688 12.093 99 54 107 2 1185059840 47098688 10950464
03:10:12 23812 8192000000 10102620032 10557456384 1145316912 12.266 100 32 64 0 1214226960 62283504 6626544
03:11:44 46756 8192000000 10100099888 10563301376 1134353968 12.162 99 15 30 1 1191408032 54159536 2894528
03:13:37 27844 8192000000 10077997840 10518642688 1121121248 12.038 98 11 22 1 1176209040 52596384 2491408
03:15:06 69747 8192000000 10150129280 10606956544 1131352256 12.134 99 16 32 0 1229278864 94080784 3845824
03:17:16 59558 8192000000 10059495808 10526343168 1122597872 12.052 99 11 22 0 1171479216 46487472 2393872
03:19:20 30174 8192000000 10124016464 10587238400 1147003248 12.281 100 24 47 0 1235533424 83497392 5032784
03:20:57 66189 8192000000 10111183472 10597019648 1140110704 12.217 100 15 30 1 1221375936 77997264 3267968
03:23:03 92470 8192000000 10141493632 10589073408 1124431680 12.069 98 11 22 0 1192070160 65115680 2522800
03:25:41 79712 8192000000 10142953280 10674733056 1140428192 12.220 99 18 36 0 1249248720 104843712 3976816
03:28:01 137497 8192000000 10054183104 10519867392 1081961952 11.666 94 14 28 1 1137868592 52931568 2975072
03:31:34 78497 8192000000 10082844880 10564034560 1129507488 12.117 99 9 18 0 1182800704 51437696 1855520
03:33:54 90919 8192000000 10129904288 10573967360 1106493264 11.899 97 15 30 0 1186635824 76581952 3560608
03:36:25 64363 8192000000 10119402144 10589659136 1099298864 11.831 96 20 40 1 1165670400 62313296 4058240
03:38:43 62605 8192000000 10047206896 10509762560 1062229520 11.478 95 11 22 1 1139272784 74584576 2458688
03:40:52 34010 8192000000 10164571232 10622058496 1127960208 12.102 100 54 107 2 1244319888 105052736 11306944
03:42:30 28572 8192000000 10050309792 10485932032 1054653424 11.405 94 27 52 0 1075199744 15270160 5276160
03:44:01 77328 8192000000 10075218880 10488188928 1057125104 11.429 94 33 66 2 1105902800 41880720 6896976
03:46:20 86105 8192000000 10086894912 10580848640 1079321760 11.641 96 19 38 0 1142404560 59487040 3595760
03:48:49 71262 8192000000 10053333648 10509365248 1116914592 11.998 100 8 16 0 1159946688 41334208 1697888
03:51:01 60540 8192000000 10064454576 10519928832 1109474336 11.927 99 7 14 0 1144482240 33722704 1285200
03:53:05 53137 8192000000 10091888544 10584584192 1127557216 12.098 100 16 32 0 1221116224 89796336 3762672
03:55:00 58178 8192000000 10084060480 10587930624 1129988112 12.121 100 12 23 1 1202925520 70140992 2796416
03:57:05 41097 8192000000 10097891104 10553200640 1110517280 11.937 98 17 34 1 1174554528 60717456 3319792
03:58:47 45455 8192000000 10055662048 10515005440 1122785200 12.053 99 12 24 2 1148297344 23076480 2435664
04:00:48 82341 8192000000 10089632576 10592755712 1122830608 12.054 99 26 52 1 1220608160 91859168 5918384
04:03:11 67597 8192000000 10128272816 10556723200 1099964752 11.837 96 24 48 2 1170972992 66050432 4957808
04:05:24 66561 8192000000 10125505152 10581843968 1113608848 11.967 99 24 48 0 1161287072 43250048 4428176
04:07:34 104856 8192000000 10080178800 10554740736 1069073616 11.543 94 11 22 1 1111847440 40572080 2201744
04:10:19 122669 8192000000 10050864384 10526031872 1119275664 12.020 99 11 22 1 1150361024 28115744 2969616
04:13:28 117026 8192000000 10149583472 10602369024 1130312384 12.124 99 11 22 1 1212855552 80009728 2533440
04:16:35 56996 8192000000 10098266928 10595790848 1117184176 12.000 98 10 21 0 1207692816 88116272 2392368
04:18:37 45039 8192000000 10111300672 10554503168 1144593680 12.259 100 20 37 2 1207456928 58108320 4754928
04:20:39 51243 8192000000 10155621392 10599837696 1122748576 12.053 97 24 48 0 1201605776 73770688 5086512
04:22:50 64491 8192000000 10114797248 10678177792 1139649120 12.212 100 16 31 0 1286383728 142952352 3782256
04:25:13 64222 8192000000 10064680608 10522677248 1117064032 11.999 98 12 23 1 1137502928 18154336 2284560

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-06-2021 04:28:00 to 22-06-2021 04:28:15.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 101 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

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

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          232238
MON$OLDEST_ACTIVE               232239
MON$OLDEST_SNAPSHOT             232239
MON$NEXT_TRANSACTION            232239
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                       2120704
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             778
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2519 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2519 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2519 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2519 Firebird 4.0/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 ---
--- end of diff output ---

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

22.06.21 04:28:19. 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-06-22 04:28:21 No severe PSQL-related problems occured
22.06.21 04:28:22. End of report.