Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.11.2021 10:38
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 15756384 kB
MemAvailable 30837504 kB
Buffers 0 kB
Cached 15981932 kB
SwapCached 2452 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-11-09 10:37:08
DTS_END                         2021-11-09 10:37:08.4020
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/51736
TRN_ID                          228802
ATT_ID                          588
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: 9260.04 23940 1111205
customer order: creation 168.35 435 20202
customer order: refuse 14.47 391 1736
order to supplier: creation 44.35 1475 5322
order to supplier: removal 3.33 134841 399
invoice (draft): creation 44.21 3283 5305
invoice (draft): removal 53.29 1038 6395
invoice accept: apply 40.27 19101 4832
invoice accept: cancel 48.47 838 5816
customer reserve: creation 3771.67 184 452600
customer reserve: removal 4782.73 73 573928
realization accept: apply 80.44 197 9653
realization accept: cancel 84.03 129 10083
payment from customer: creation 57.34 119 6881
payment from customer: removal 5.38 71 645
payment to supplier: creation 47.88 127 5746
payment to supplier: removal 3.16 72 379
service: total inventory turnovers 3.68 3814 441
service: total monetary turnovers 6.17 635 740
service: aggregate perf. data 0.82 20475 98
service: refresh index statistics 0.03 291501 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-11-2021 10:39:18 to 09-11-2021 10:39:20.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8328
TEST_TIME 2 6744
TEST_TIME 3 7730
TEST_TIME 4 6941
TEST_TIME 5 6368
TEST_TIME 6 6076
TEST_TIME 7 6075
TEST_TIME 8 6372
TEST_TIME 9 6255
TEST_TIME 10 6397
TEST_TIME 11 6253
TEST_TIME 12 6304
TEST_TIME 13 6487
TEST_TIME 14 7114
TEST_TIME 15 7296
TEST_TIME 16 7292
TEST_TIME 17 7681
TEST_TIME 18 8085
TEST_TIME 19 8238
TEST_TIME 20 8718
TEST_TIME 21 8786
TEST_TIME 22 9334
TEST_TIME 23 9585
TEST_TIME 24 9790
TEST_TIME 25 9546
TEST_TIME 26 9779
TEST_TIME 27 10497
TEST_TIME 28 11276
TEST_TIME 29 11236
TEST_TIME 30 10914
TEST_TIME 31 11021
TEST_TIME 32 10994
TEST_TIME 33 10805
TEST_TIME 34 10596
TEST_TIME 35 10438
TEST_TIME 36 10224
TEST_TIME 37 10341
TEST_TIME 38 10274
TEST_TIME 39 10293
TEST_TIME 40 10346
TEST_TIME 41 10281
TEST_TIME 42 10280
TEST_TIME 43 10119
TEST_TIME 44 10243
TEST_TIME 45 10195
TEST_TIME 46 10202
TEST_TIME 47 10177
TEST_TIME 48 10068
TEST_TIME 49 9892
TEST_TIME 50 9809
TEST_TIME 51 9879
TEST_TIME 52 9740
TEST_TIME 53 9695
TEST_TIME 54 9580
TEST_TIME 55 9634
TEST_TIME 56 9611
TEST_TIME 57 9608
TEST_TIME 58 9542
TEST_TIME 59 9517
TEST_TIME 60 9409
TEST_TIME 61 9382
TEST_TIME 62 9410
TEST_TIME 63 9420
TEST_TIME 64 9421
TEST_TIME 65 9419
TEST_TIME 66 9378
TEST_TIME 67 9453
TEST_TIME 68 9463
TEST_TIME 69 9481
TEST_TIME 70 9466
TEST_TIME 71 9468
TEST_TIME 72 9532
TEST_TIME 73 9556
TEST_TIME 74 9592
TEST_TIME 75 9637
TEST_TIME 76 9551
TEST_TIME 77 9492
TEST_TIME 78 9458
TEST_TIME 79 9459
TEST_TIME 80 9387
TEST_TIME 81 9368
TEST_TIME 82 9315
TEST_TIME 83 9260
TEST_TIME 84 9213
TEST_TIME 85 9228
TEST_TIME 86 9189
TEST_TIME 87 9171
TEST_TIME 88 9154
TEST_TIME 89 9164
TEST_TIME 90 9193
TEST_TIME 91 9192
TEST_TIME 92 9260
TEST_TIME 93 9333
TEST_TIME 94 9315
TEST_TIME 95 9296
TEST_TIME 96 9313
TEST_TIME 97 9345
TEST_TIME 98 9355
TEST_TIME 99 9372
TEST_TIME 100 9297
TEST_TIME 101 9251
TEST_TIME 102 9255
TEST_TIME 103 9219
TEST_TIME 104 9201
TEST_TIME 105 9151
TEST_TIME 106 9125
TEST_TIME 107 9106
TEST_TIME 108 9109
TEST_TIME 109 9082
TEST_TIME 110 9101
TEST_TIME 111 9086
TEST_TIME 112 9068
TEST_TIME 113 9041
TEST_TIME 114 9202
TEST_TIME 115 9193
TEST_TIME 116 9230
TEST_TIME 117 9259
TEST_TIME 118 9274
TEST_TIME 119 9288
TEST_TIME 120 9270

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 09-11-2021 10:39:21 to 09-11-2021 10:39:25.

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 1530749 1530749 0 0.00 0 34225 7 0 0
doc_list_biud 1530749 1530749 0 0.00 0 33762 35 0 0
sp_add_invoice_to_stock 4966 4832 134 2.69 30 217752 19102 0 134
sp_cancel_adding_invoice 5816 5816 0 0.00 6 58781 838 0 0
sp_cancel_client_order 1736 1736 0 0.00 4 15646 392 0 0
sp_cancel_customer_reserve 573928 573928 0 0.00 1 25573 73 0 0
sp_cancel_pay_from_customer 645 645 0 0.00 3 2665 71 0 0
sp_cancel_pay_to_supplier 379 379 0 0.00 3 3124 72 0 0
sp_cancel_supplier_invoice 6402 6395 7 0.10 3 26311 1039 0 7
sp_cancel_supplier_order 399 399 0 0.00 9 1066538 134842 0 0
sp_cancel_write_off 10083 10083 0 0.00 1 33854 130 0 0
sp_client_order 20202 20202 0 0.00 4 31903 436 0 0
sp_customer_reserve 452600 452600 0 0.00 0 76506 185 0 0
sp_fill_shopping_cart 30828 30828 0 0.00 1 19580 43 0 0
sp_fill_shopping_cart_clo_res 452591 452591 0 0.00 0 24059 18 0 0
sp_get_clo_for_invoice 4817 4817 0 0.00 0 31161 386 0 0
sp_kill_cost_storno 16923 16923 0 0.00 0 24685 63 0 0
sp_kill_qstorno_ret_qs2qd 1164733 1164733 0 0.00 0 33761 40 0 0
sp_kill_qty_storno 612825 612825 0 0.00 0 33761 85 0 0
sp_lock_selected_doc 20067 20067 0 0.00 0 8791 6 0 0
sp_make_cost_storno 27095 27095 0 0.00 0 20474 57 0 0
sp_multiply_rows_for_pdistr 27095 27095 0 0.00 0 9562 34 0 0
sp_multiply_rows_for_qdistr 20202 20202 0 0.00 0 31897 225 0 0
sp_pay_from_customer 6881 6881 0 0.00 4 20559 119 0 0
sp_pay_to_supplier 5746 5746 0 0.00 4 20468 128 0 0
sp_payment_common 12627 12627 0 0.00 1 8742 24 0 0
sp_qd_handle_on_cancel_clo 1736 1736 0 0.00 0 15638 356 0 0
sp_qd_handle_on_invoice_upd_sts 10631 10631 0 0.00 0 26610 405 0 0
sp_qd_handle_on_reserve_upd_sts 9653 9653 0 0.00 0 20537 66 0 0
sp_reserve_write_off 9653 9653 0 0.00 7 20639 198 0 0
sp_supplier_invoice 5305 5305 0 0.00 8 76926 3284 0 0
sp_supplier_order 5322 5322 0 0.00 5 65652 1475 0 0
srv_aggregate_perf_data 98 98 0 0.00 3367 377543 20475 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1112 129189 42774 0 0
srv_find_qd_qs_mism 1064966 1064966 0 0.00 0 34225 6 0 0
srv_make_invnt_saldo 441 441 0 0.00 82 100348 3814 0 0
srv_make_money_saldo 740 740 0 0.00 0 12717 635 0 0
srv_recalc_idx_stat 4 4 0 0.00 125100 600654 291501 0 0
t$perf-abend:sp_add_invoice_to_stock 134 134 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 4832 4832 0 0.00 0 21180 167 0 0
t$perf-norm:sp_cancel_adding_invoice 358 358 0 0.00 1 5624 85 0 0
t$perf-norm:sp_cancel_client_order 1736 1736 0 0.00 0 5884 16 0 0
t$perf-norm:sp_cancel_customer_reserve 673 673 0 0.00 0 2203 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 645 645 0 0.00 0 7080 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 379 379 0 0.00 0 3224 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 373 373 0 0.00 0 776 14 0 0
t$perf-norm:sp_cancel_supplier_order 399 399 0 0.00 0 19616 1807 0 0
t$perf-norm:sp_cancel_write_off 1036 1036 0 0.00 0 1638 6 0 0
t$perf-norm:sp_client_order 20202 20202 0 0.00 0 9814 21 0 0
t$perf-norm:sp_customer_reserve 1032 1032 0 0.00 0 8478 17 0 0
t$perf-norm:sp_pay_from_customer 6881 6881 0 0.00 0 16801 16 0 0
t$perf-norm:sp_pay_to_supplier 5746 5746 0 0.00 0 8790 12 0 0
t$perf-norm:sp_reserve_write_off 9653 9653 0 0.00 0 11310 10 0 0
t$perf-norm:sp_supplier_invoice 5305 5305 0 0.00 0 4217 16 0 0
t$perf-norm:sp_supplier_order 5322 5322 0 0.00 0 11909 19 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 2161 24 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 23 1 0 0
t$perf-norm:srv_make_invnt_saldo 441 441 0 0.00 0 438 3 0 0
t$perf-norm:srv_make_money_saldo 740 740 0 0.00 0 16060 27 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 9898 9630 268 2.70 0 6967 18 0 268
v_all_customers 36614 36614 0 0.00 0 200 1 0 0
v_all_suppliers 29920 29920 0 0.00 0 205 1 0 0
v_all_wares 40404 40404 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 716 716 0 0.00 0 485 6 0 0
v_cancel_client_order 3472 3472 0 0.00 0 4410 9 0 0
v_cancel_customer_prepayment 1290 1290 0 0.00 0 1292 6 0 0
v_cancel_customer_reserve 1346 1346 0 0.00 0 4464 8 0 0
v_cancel_payment_to_supplier 758 758 0 0.00 0 3106 9 0 0
v_cancel_supplier_invoice 760 746 14 1.84 0 2485 19 0 14
v_cancel_supplier_order 798 798 0 0.00 0 1155 5 0 0
v_cancel_write_off 2072 2072 0 0.00 0 2913 16 0 0
v_max_id_clo_ord 5321 5321 0 0.00 0 760 1 0 0
v_max_id_clo_res 1032 1032 0 0.00 0 3472 29 0 0
v_max_id_ord_sup 5305 5305 0 0.00 0 11017 5 0 0
v_max_non_paid_invoice 1412 1412 0 0.00 0 131 2 0 0
v_max_non_paid_realizn 3682 3682 0 0.00 0 1611 2 0 0
v_min_id_clo_ord 5321 5321 0 0.00 0 4403 5 0 0
v_min_id_clo_res 1032 1032 0 0.00 0 2646 8 0 0
v_min_id_ord_sup 5305 5305 0 0.00 0 5689 9 0 0
v_min_non_paid_invoice 5746 5746 0 0.00 0 7981 15 0 0
v_min_non_paid_realizn 6881 6881 0 0.00 0 7691 16 0 0
v_our_firm 10188 10188 0 0.00 0 26 1 0 0
v_reserve_write_off 19306 19306 0 0.00 0 5912 7 0 0
x_lock_depdocs_on_canc_invoice 11838 11838 0 0.00 0 16840 116 0 0
x_lock_depdocs_on_canc_sup_ord 399 399 0 0.00 0 8844 361 0 0
x_make_qstorno_1000_1200 5322 5322 0 0.00 2 65631 1391 0 0
x_make_qstorno_1200_2000 5305 5305 0 0.00 4 76897 3123 0 0
x_make_qstorno_2100_3300 452600 452600 0 0.00 0 76502 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-11-2021 10:39:25 to 09-11-2021 10:39:29.

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
08:07:12 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:12 28377 8192000000 9710499088 10007887872 789582544 8.791 100 85 170 0 818818080 7645600 21589936
08:09:44 19289 8192000000 9759706064 10066128896 836397792 9.264 100 92 184 0 870687440 12276656 22012992
08:11:06 9980 8192000000 9795005904 10109968384 863922304 9.539 100 88 176 0 905803904 21456864 20424736
08:12:24 10597 8192000000 9873140656 10208673792 923564112 10.131 100 77 154 0 982722336 42473696 16684528
08:13:38 9444 8192000000 9925267888 10272075776 961759248 10.506 100 73 146 0 1038548480 61079984 15709248
08:14:50 5646 8192000000 9981341888 10335391744 1001125648 10.889 100 83 167 2 1093675408 75329712 17220048
08:15:58 2198 8192000000 9999689344 10363322368 1024223856 11.113 100 80 159 0 1115431712 75695680 15512176
08:17:01 2578 8192000000 10012687392 10382565376 1060578192 11.462 100 87 174 1 1131530832 52472032 18480608
08:18:05 1687 8192000000 10038362544 10409504768 1075766272 11.607 100 94 191 1 1154945488 60077792 19101424
08:19:07 1732 8192000000 10036769392 10414067712 1078769232 11.636 100 87 174 2 1146159248 49741408 17648608
08:20:09 7570 8192000000 10021172784 10424791040 1085196880 11.697 100 37 72 0 1123084704 30582592 7305232
08:21:19 45250 8192000000 10011750768 10397802496 1093608400 11.777 100 50 98 0 1121077504 15329536 12139568
08:23:05 7176 8192000000 10024147248 10432606208 1099486688 11.833 100 38 76 1 1146151680 38255648 8409344
08:24:15 7530 8192000000 10009573568 10418212864 1100533216 11.843 100 34 68 1 1124360832 15912720 7914896
08:25:27 7516 8192000000 10012704288 10408665088 1092537728 11.767 99 33 63 1 1119006368 19258464 7210176
08:26:37 24874 8192000000 10037218432 10431795200 1106991248 11.904 100 61 122 4 1138160784 18652080 12517456
08:28:03 12398 8192000000 10020172640 10442301440 1100885664 11.846 100 44 87 3 1136927424 26914128 9127632
08:29:18 7625 8192000000 9971339024 10362855424 1020570144 11.078 96 34 67 2 1052494176 24733536 7190496
08:30:28 7449 8192000000 9948450032 10331742208 991983424 10.801 96 33 65 0 1014532128 15720208 6828496
08:31:40 18505 8192000000 9940815808 10337513472 1005050848 10.927 99 58 116 0 1043450784 25610816 12789120
08:33:01 5066 8192000000 9954289152 10332311552 1028879632 11.158 100 42 84 1 1057329168 19890736 8558800
08:34:09 4588 8192000000 9957844976 10341744640 1033771600 11.205 99 43 85 3 1058261392 14740448 9749344
08:35:18 5706 8192000000 9975566864 10365042688 1059943472 11.456 100 26 51 0 1087622880 22777616 4901792
08:36:28 44500 8192000000 9999293648 10393104384 1068464544 11.537 100 44 88 0 1095187760 16977888 9745328
08:38:30 5187 8192000000 10057924352 10450808832 1098251664 11.821 100 69 135 1 1167920928 55591808 14077456
08:39:41 24627 8192000000 10086147120 10486226944 1117129872 12.000 100 51 102 3 1191029712 63572144 10327696
08:41:16 32862 8192000000 10122412880 10530246656 1137539408 12.192 100 36 70 1 1220090752 74132144 8419200
08:42:52 3685 8192000000 10176403584 10576437248 1147430112 12.285 100 65 130 2 1284111008 121452320 15228576
08:44:01 13539 8192000000 10248170832 10654027776 1156666400 12.372 100 58 114 0 1339667424 170218064 12782960
08:45:19 20192 8192000000 10260647344 10682679296 1165511824 12.455 100 54 108 0 1375658624 198047936 12098864
08:46:42 14503 8192000000 10256280960 10681262080 1164610400 12.446 99 59 119 0 1365438272 187097296 13730576
08:48:09 2048 8192000000 10297738704 10699796480 1184335600 12.631 100 83 166 2 1400506768 197451424 18719744
08:49:15 5673 8192000000 10381178736 10788937728 1192510576 12.707 100 76 150 2 1490330688 281143360 16676752
08:50:28 2190 8192000000 10406383328 10818801664 1194131344 12.722 100 67 135 0 1506285872 295616704 16537824
08:51:32 1111 8192000000 10419964768 10822025216 1185194752 12.639 100 93 188 0 1521414544 315563904 20655888
08:52:38 2712 8192000000 10420027248 10829443072 1180686432 12.597 100 81 164 0 1520354208 321290896 18376880
08:53:47 3366 8192000000 10464859168 10873257984 1163302352 12.434 100 80 160 0 1563997712 381422592 19272768
08:54:59 1963 8192000000 10433937600 10854653952 1170788528 12.504 100 71 142 0 1538671808 350738336 17144944
08:56:11 3272 8192000000 10423896240 10843533312 1169079424 12.488 100 43 86 0 1525246672 344393952 11773296
08:57:18 12542 8192000000 10362049936 10804031488 1150559696 12.315 99 43 85 0 1482145424 320770160 10815568
08:58:39 2860 8192000000 10412316928 10830110720 1163772992 12.439 100 80 160 3 1509397680 326970288 18654400
08:59:43 6945 8192000000 10333381904 10774020096 1135120000 12.170 99 41 81 0 1434600048 289063248 10416800
09:01:12 13940 8192000000 10346156736 10794455040 1139207232 12.208 99 39 78 1 1440105024 290894704 10003088
09:02:32 16477 8192000000 10392605904 10820702208 1122159936 12.047 99 29 57 0 1487270432 357870944 7239552
09:03:58 17274 8192000000 10314066048 10758721536 1104021200 11.876 99 29 58 0 1433516624 322201536 7293888
09:05:21 12186 8192000000 10226730000 10743418880 1128916384 12.111 100 30 60 1 1376358288 239841040 7600864
09:06:57 68954 8192000000 10155109840 10635628544 1115486192 11.984 99 23 46 1 1252844336 132466240 4891904
09:09:25 59550 8192000000 10057308272 10520166400 1080193648 11.649 96 14 28 0 1134248992 51291216 2764128
09:11:27 55708 8192000000 10111557840 10550947840 1087646624 11.720 96 22 42 0 1155084192 63174176 4263392
09:13:32 61279 8192000000 10142469104 10573901824 1094726864 11.788 97 36 69 1 1208538320 106456016 7355440
09:15:35 76616 8192000000 10084372912 10545238016 1132049664 12.141 100 14 28 0 1213117392 78214000 2853728
09:17:53 113840 8192000000 10089616832 10550419456 1118546384 12.013 98 9 18 0 1164403760 44138704 1718672
09:20:47 112093 8192000000 10084211728 10527055872 1109938272 11.932 97 12 24 0 1161245712 47989168 3318272
09:23:43 90296 8192000000 10078267200 10519126016 1108169360 11.915 97 14 28 0 1134370944 23535392 2666192
09:26:13 129189 8192000000 10116254432 10572754944 1149615504 12.306 100 35 69 0 1222912720 66011584 7285632
09:29:30 36182 8192000000 10080125632 10514018304 1128821440 12.110 98 43 85 2 1161571872 23804128 8946304
09:31:31 71478 8192000000 10149035952 10630217728 1123533248 12.060 97 27 54 1 1225114736 95817616 5763872
09:33:48 56763 8192000000 10098534064 10581696512 1131272288 12.133 98 17 34 1 1197591280 62383904 3935088
09:36:00 42478 8192000000 10107163184 10577379328 1155624272 12.362 100 15 30 2 1216969808 58182640 3162896
09:37:48 25036 8192000000 10097248720 10565627904 1157053024 12.376 100 19 38 1 1192109152 30790976 4265152
09:39:13 86233 8192000000 10076604816 10525851648 1138392800 12.200 98 13 26 1 1198878464 58144224 2341440
09:41:39 20301 8192000000 10085179088 10546913280 1122004448 12.046 97 24 43 3 1164137824 37737296 4396080
09:43:01 8931 8192000000 10119525600 10550505472 1147782928 12.289 99 53 104 0 1193110544 34146848 11180768
09:44:12 63966 8192000000 10089902800 10569723904 1081930800 11.666 94 14 28 0 1143431520 58609504 2891216
09:46:18 52048 8192000000 10116083232 10560282624 1121687920 12.043 99 23 46 0 1187714320 61326752 4699648
09:48:10 60784 8192000000 10107858816 10603802624 1141027216 12.225 100 25 50 1 1240003072 93695728 5280128
09:50:15 55957 8192000000 10067538928 10559291392 1078534128 11.634 94 10 20 2 1143767360 63248288 1984944
09:52:11 81582 8192000000 10083146544 10584047616 1089916480 11.742 96 16 32 0 1198108624 104450016 3742128
09:54:45 79938 8192000000 10063948448 10527961088 1066834624 11.522 95 35 70 0 1146129856 72366480 6928752
09:57:10 68895 8192000000 10033166288 10519031808 1110110624 11.933 100 11 22 0 1157390848 45184128 2096096
09:59:19 20147 8192000000 10047868848 10476040192 1093830240 11.779 99 28 56 0 1136512000 36217936 6463824
10:00:41 59411 8192000000 10069743264 10515922944 1101887200 11.856 99 17 34 0 1160787024 55215184 3684640
10:02:55 42365 8192000000 10036518080 10483888128 1085983440 11.704 98 20 38 1 1111456368 21593328 3879600
10:04:51 55683 8192000000 10067048576 10507337728 1103167168 11.868 99 28 56 0 1158710368 49700736 5842464
10:06:48 43756 8192000000 10109477232 10569457664 1088936352 11.733 97 19 38 0 1182799936 89983888 3879696
10:08:35 39173 8192000000 10133296144 10575044608 1131463904 12.135 100 32 64 3 1215501088 77817920 6219264
10:10:15 61101 8192000000 10060153488 10517479424 1107754080 11.911 98 13 26 0 1157988208 47688016 2546112
10:12:19 22181 8192000000 10065845504 10480984064 1117547744 12.004 99 40 80 1 1164727488 39095984 8083760
10:13:44 78014 8192000000 10096365312 10541432832 1111624240 11.948 98 29 58 0 1172993184 55587200 5781744
10:16:05 52466 8192000000 10104355360 10552524800 1143863264 12.252 100 28 55 4 1185812720 35921888 6027568
10:17:58 82522 8192000000 10074545024 10561445888 1138467136 12.201 99 39 78 1 1206027536 58899984 8660416
10:21:09 57166 8192000000 10082377584 10539790336 1133626832 12.156 99 14 28 0 1163423616 27190496 2606288
10:23:07 110066 8192000000 10122334656 10615095296 1155022128 12.357 100 17 34 0 1239490432 80168416 4299888
10:25:58 35139 8192000000 10120454496 10577190912 1146893360 12.280 99 22 44 0 1214523568 62691472 4938736
10:27:33 35812 8192000000 10162338672 10646679552 1154052208 12.348 99 33 66 0 1280988192 119516528 7419456
10:29:20 59187 8192000000 10218753520 10658316288 1083123856 11.677 93 22 44 0 1240144912 151956176 5064880
10:31:19 64182 8192000000 10075407952 10565799936 1093093920 11.772 97 21 42 0 1175418896 77563808 4761168
10:33:30 25726 8192000000 10120606528 10572341248 1136062000 12.178 100 30 60 1 1199268096 57008272 6197824
10:35:05 50028 8192000000 10090773920 10545455104 1099014560 11.828 97 18 36 1 1162107888 59742688 3350640
10:36:59 29926 8192000000 8970711056 9184186368 127796272 1.536 12 1 2 0 138576288 10744608 35408

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 09-11-2021 10:39:31 to 09-11-2021 10:39:46.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 134 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 09-11-2021 10:39:47 to 09-11-2021 10:39:49.

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          229191
MON$OLDEST_ACTIVE               229192
MON$OLDEST_SNAPSHOT             229192
MON$NEXT_TRANSACTION            229192
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                       2069280
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             789
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

09.11.21 10:39:50. 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-11-09 10:39:53 No severe PSQL-related problems occured
09.11.21 10:39:54. End of report.