Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.09.2021 04:37
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 15826276 kB
MemAvailable 30807296 kB
Buffers 20 kB
Cached 15794684 kB
SwapCached 396 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-09-13 04:37:06
DTS_END                         2021-09-13 04:37:06.4270
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> X_MAKE_QSTORNO_1000_1200(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/35528
TRN_ID                          230544
ATT_ID                          678
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: 8580.69 24895 1029683
customer order: creation 164.54 410 19745
customer order: refuse 14.50 433 1740
order to supplier: creation 43.80 1442 5256
order to supplier: removal 3.31 124592 397
invoice (draft): creation 44.29 4422 5315
invoice (draft): removal 47.88 1122 5745
invoice accept: apply 39.85 20441 4782
invoice accept: cancel 43.69 1067 5243
customer reserve: creation 3648.18 200 437781
customer reserve: removal 4254.73 75 510568
realization accept: apply 79.73 223 9568
realization accept: cancel 75.74 124 9089
payment from customer: creation 55.83 127 6699
payment from customer: removal 5.38 111 646
payment to supplier: creation 45.83 148 5500
payment to supplier: removal 3.23 141 387
service: total inventory turnovers 3.30 4396 396
service: total monetary turnovers 5.79 749 695
service: aggregate perf. data 1.05 14890 126
service: refresh index statistics 0.04 322796 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-09-2021 04:37:44 to 13-09-2021 04:37:46.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3968
TEST_TIME 2 4572
TEST_TIME 3 4797
TEST_TIME 4 4902
TEST_TIME 5 5095
TEST_TIME 6 4896
TEST_TIME 7 5196
TEST_TIME 8 5156
TEST_TIME 9 5213
TEST_TIME 10 5537
TEST_TIME 11 5508
TEST_TIME 12 6040
TEST_TIME 13 6158
TEST_TIME 14 6317
TEST_TIME 15 6637
TEST_TIME 16 6673
TEST_TIME 17 7255
TEST_TIME 18 7659
TEST_TIME 19 7808
TEST_TIME 20 8008
TEST_TIME 21 7896
TEST_TIME 22 7994
TEST_TIME 23 8614
TEST_TIME 24 8594
TEST_TIME 25 8875
TEST_TIME 26 8926
TEST_TIME 27 8837
TEST_TIME 28 9191
TEST_TIME 29 9443
TEST_TIME 30 9333
TEST_TIME 31 10271
TEST_TIME 32 10765
TEST_TIME 33 10485
TEST_TIME 34 10291
TEST_TIME 35 10393
TEST_TIME 36 10221
TEST_TIME 37 10057
TEST_TIME 38 9962
TEST_TIME 39 9785
TEST_TIME 40 9932
TEST_TIME 41 9794
TEST_TIME 42 9687
TEST_TIME 43 9562
TEST_TIME 44 9515
TEST_TIME 45 9440
TEST_TIME 46 9466
TEST_TIME 47 9325
TEST_TIME 48 9279
TEST_TIME 49 9197
TEST_TIME 50 9102
TEST_TIME 51 9051
TEST_TIME 52 8996
TEST_TIME 53 8987
TEST_TIME 54 8904
TEST_TIME 55 8814
TEST_TIME 56 8831
TEST_TIME 57 8817
TEST_TIME 58 8838
TEST_TIME 59 8831
TEST_TIME 60 8801
TEST_TIME 61 8735
TEST_TIME 62 8794
TEST_TIME 63 8740
TEST_TIME 64 8701
TEST_TIME 65 8684
TEST_TIME 66 8740
TEST_TIME 67 8950
TEST_TIME 68 8910
TEST_TIME 69 9026
TEST_TIME 70 9011
TEST_TIME 71 8989
TEST_TIME 72 8952
TEST_TIME 73 9020
TEST_TIME 74 9079
TEST_TIME 75 8983
TEST_TIME 76 8878
TEST_TIME 77 8801
TEST_TIME 78 8826
TEST_TIME 79 8757
TEST_TIME 80 8683
TEST_TIME 81 8631
TEST_TIME 82 8567
TEST_TIME 83 8609
TEST_TIME 84 8551
TEST_TIME 85 8541
TEST_TIME 86 8489
TEST_TIME 87 8502
TEST_TIME 88 8468
TEST_TIME 89 8486
TEST_TIME 90 8487
TEST_TIME 91 8562
TEST_TIME 92 8573
TEST_TIME 93 8543
TEST_TIME 94 8602
TEST_TIME 95 8569
TEST_TIME 96 8544
TEST_TIME 97 8592
TEST_TIME 98 8638
TEST_TIME 99 8644
TEST_TIME 100 8650
TEST_TIME 101 8684
TEST_TIME 102 8641
TEST_TIME 103 8617
TEST_TIME 104 8571
TEST_TIME 105 8534
TEST_TIME 106 8527
TEST_TIME 107 8504
TEST_TIME 108 8513
TEST_TIME 109 8494
TEST_TIME 110 8467
TEST_TIME 111 8473
TEST_TIME 112 8453
TEST_TIME 113 8460
TEST_TIME 114 8463
TEST_TIME 115 8455
TEST_TIME 116 8505
TEST_TIME 117 8515
TEST_TIME 118 8586
TEST_TIME 119 8647
TEST_TIME 120 8584

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 13-09-2021 04:37:47 to 13-09-2021 04:37:50.

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 1434572 1434572 0 0.00 0 25506 7 0 0
doc_list_biud 1434563 1434563 0 0.00 0 35328 35 0 0
sp_add_invoice_to_stock 4903 4782 121 2.46 36 182062 20442 0 121
sp_cancel_adding_invoice 5243 5243 0 0.00 4 73563 1068 0 0
sp_cancel_client_order 1740 1740 0 0.00 5 18004 434 0 0
sp_cancel_customer_reserve 510568 510568 0 0.00 1 21684 76 0 0
sp_cancel_pay_from_customer 646 646 0 0.00 3 4725 112 0 0
sp_cancel_pay_to_supplier 387 387 0 0.00 3 11991 142 0 0
sp_cancel_supplier_invoice 5759 5745 14 0.24 1 22402 1122 0 14
sp_cancel_supplier_order 397 397 0 0.00 9 1308124 124592 0 0
sp_cancel_write_off 9089 9089 0 0.00 1 12463 124 0 0
sp_client_order 19745 19745 0 0.00 4 32875 411 0 0
sp_customer_reserve 437781 437781 0 0.00 0 42686 200 0 0
sp_fill_shopping_cart 30309 30309 0 0.00 1 35479 60 0 0
sp_fill_shopping_cart_clo_res 437776 437776 0 0.00 0 19685 20 0 0
sp_get_clo_for_invoice 4777 4777 0 0.00 0 36011 457 0 0
sp_kill_cost_storno 15365 15365 0 0.00 0 16714 78 0 0
sp_kill_qstorno_ret_qs2qd 1036367 1036367 0 0.00 0 23460 41 0 0
sp_kill_qty_storno 547122 547122 0 0.00 0 35327 90 0 0
sp_lock_selected_doc 19921 19921 0 0.00 0 8872 5 0 0
sp_make_cost_storno 26543 26543 0 0.00 0 20530 74 0 0
sp_multiply_rows_for_pdistr 26536 26536 0 0.00 0 9598 35 0 0
sp_multiply_rows_for_qdistr 19745 19745 0 0.00 0 32856 206 0 0
sp_pay_from_customer 6699 6699 0 0.00 4 41808 127 0 0
sp_pay_to_supplier 5500 5500 0 0.00 5 11832 148 0 0
sp_payment_common 12196 12196 0 0.00 1 41803 27 0 0
sp_qd_handle_on_cancel_clo 1740 1740 0 0.00 0 17996 406 0 0
sp_qd_handle_on_invoice_upd_sts 10019 10019 0 0.00 0 35324 471 0 0
sp_qd_handle_on_reserve_upd_sts 9564 9564 0 0.00 0 16262 69 0 0
sp_reserve_write_off 9568 9568 0 0.00 7 20726 224 0 0
sp_supplier_invoice 5315 5315 0 0.00 6 117494 4423 0 0
sp_supplier_order 5256 5256 0 0.00 6 46295 1443 0 0
srv_aggregate_perf_data 126 126 0 0.00 3103 155530 14891 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1443 150844 45342 0 0
srv_find_qd_qs_mism 984233 984233 0 0.00 0 10652 6 0 0
srv_make_invnt_saldo 396 396 0 0.00 52 122995 4397 0 0
srv_make_money_saldo 695 695 0 0.00 1 49102 750 0 0
srv_recalc_idx_stat 5 5 0 0.00 134155 450884 322797 0 0
t$perf-abend:sp_add_invoice_to_stock 121 121 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4782 4782 0 0.00 0 15046 206 0 0
t$perf-norm:sp_cancel_adding_invoice 415 415 0 0.00 0 2873 105 0 0
t$perf-norm:sp_cancel_client_order 1740 1740 0 0.00 0 4189 21 0 0
t$perf-norm:sp_cancel_customer_reserve 694 694 0 0.00 0 6067 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 646 646 0 0.00 0 9654 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 387 387 0 0.00 0 259 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 367 367 0 0.00 0 3672 39 0 0
t$perf-norm:sp_cancel_supplier_order 397 397 0 0.00 0 33258 1929 0 0
t$perf-norm:sp_cancel_write_off 935 935 0 0.00 0 7813 21 0 0
t$perf-norm:sp_client_order 19745 19745 0 0.00 0 12290 23 0 0
t$perf-norm:sp_customer_reserve 987 987 0 0.00 0 10518 33 0 0
t$perf-norm:sp_pay_from_customer 6699 6699 0 0.00 0 8659 14 0 0
t$perf-norm:sp_pay_to_supplier 5500 5500 0 0.00 0 3979 11 0 0
t$perf-norm:sp_reserve_write_off 9568 9568 0 0.00 0 13340 13 0 0
t$perf-norm:sp_supplier_invoice 5315 5315 0 0.00 0 14768 32 0 0
t$perf-norm:sp_supplier_order 5256 5256 0 0.00 0 6932 24 0 0
t$perf-norm:srv_aggregate_perf_data 126 126 0 0.00 0 529 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 5043 82 0 0
t$perf-norm:srv_make_invnt_saldo 396 396 0 0.00 0 3584 18 0 0
t$perf-norm:srv_make_money_saldo 695 695 0 0.00 0 483 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 10 6 0 0
v_add_invoice_to_stock 9794 9552 242 2.47 0 5577 18 0 242
v_all_customers 35598 35598 0 0.00 0 19 1 0 0
v_all_suppliers 29130 29130 0 0.00 0 21 1 0 0
v_all_wares 39484 39484 0 0.00 0 35 1 0 0
v_cancel_adding_invoice 830 830 0 0.00 0 1772 9 0 0
v_cancel_client_order 3480 3480 0 0.00 0 3430 7 0 0
v_cancel_customer_prepayment 1292 1292 0 0.00 0 946 5 0 0
v_cancel_customer_reserve 1388 1388 0 0.00 0 1837 6 0 0
v_cancel_payment_to_supplier 774 774 0 0.00 0 1342 7 0 0
v_cancel_supplier_invoice 762 734 28 3.67 0 927 13 0 28
v_cancel_supplier_order 794 794 0 0.00 0 4082 10 0 0
v_cancel_write_off 1870 1870 0 0.00 0 1107 13 0 0
v_max_id_clo_ord 5254 5254 0 0.00 0 395 1 0 0
v_max_id_clo_res 987 987 0 0.00 0 2268 8 0 0
v_max_id_ord_sup 5312 5312 0 0.00 0 3043 5 0 0
v_max_non_paid_invoice 1498 1498 0 0.00 0 213 2 0 0
v_max_non_paid_realizn 3700 3700 0 0.00 0 2369 4 0 0
v_min_id_clo_ord 5254 5254 0 0.00 0 612 4 0 0
v_min_id_clo_res 987 987 0 0.00 0 7338 20 0 0
v_min_id_ord_sup 5312 5312 0 0.00 0 8639 15 0 0
v_min_non_paid_invoice 5497 5497 0 0.00 0 6406 17 0 0
v_min_non_paid_realizn 6699 6699 0 0.00 0 41797 22 0 0
v_our_firm 9884 9884 0 0.00 0 13 1 0 0
v_reserve_write_off 19128 19128 0 0.00 0 5581 6 0 0
x_lock_depdocs_on_canc_invoice 10621 10621 0 0.00 0 25966 130 0 0
x_lock_depdocs_on_canc_sup_ord 397 397 0 0.00 0 12300 392 0 0
x_make_qstorno_1000_1200 5256 5256 0 0.00 2 46289 1347 0 0
x_make_qstorno_1200_2000 5315 5315 0 0.00 3 109081 4182 0 0
x_make_qstorno_2100_3300 437781 437781 0 0.00 0 42683 178 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-09-2021 04:37:51 to 13-09-2021 04:37:55.

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
02:07:10 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:11 40847 8192000000 9734939664 10036244480 808740944 8.985 100 85 170 0 836531920 7733984 20056992
02:09:56 22248 8192000000 9766284960 10070900736 838049792 9.280 100 92 184 0 873055936 13278880 21727264
02:11:27 23883 8192000000 9813498272 10133123072 873684720 9.637 100 80 160 1 920833184 28690288 18458176
02:12:57 13697 8192000000 9880399520 10214150144 926398368 10.159 100 77 155 1 987238720 43837056 17003296
02:14:14 10549 8192000000 9918272784 10262708224 962782784 10.516 100 71 141 0 1031051072 53540592 14727696
02:15:27 6559 8192000000 9968166256 10329260032 1000301056 10.881 100 75 149 0 1085761440 70328816 15131568
02:16:36 4476 8192000000 9996359600 10359328768 1027479648 11.144 100 71 142 1 1104418064 62458288 14480128
02:17:43 1853 8192000000 10019416464 10390224896 1053864784 11.398 100 87 172 0 1134723952 63774624 17084544
02:18:47 5792 8192000000 10033902416 10406129664 1062451248 11.480 100 84 166 1 1149921872 69549920 17920704
02:19:54 14855 8192000000 10034035248 10418229248 1072212720 11.573 100 77 154 2 1153784496 64973680 16598096
02:21:09 8195 8192000000 9987881488 10384203776 1077988208 11.628 100 40 81 2 1112094288 24533120 9572960
02:22:18 6905 8192000000 9998302080 10396389376 1083147472 11.677 100 42 81 1 1112815536 20159024 9509040
02:23:27 29607 8192000000 9992882048 10392956928 1086576544 11.710 100 43 85 3 1106584224 8894640 11113040
02:25:11 7069 8192000000 10007476608 10412294144 1090537904 11.748 100 36 73 0 1125707872 26778736 8391232
02:26:18 8934 8192000000 10008011280 10403319808 1091705488 11.759 100 24 48 1 1124698384 26989536 6003360
02:27:31 12135 8192000000 9999894160 10396553216 1089363152 11.737 100 46 90 4 1116811648 16316752 11131744
02:28:44 8296 8192000000 10013690304 10420969472 1084258032 11.688 100 28 54 1 1110862928 21467200 5137696
02:29:56 7830 8192000000 9973814672 10363219968 1020440320 11.076 98 40 80 0 1061276784 32768448 8068016
02:31:06 4933 8192000000 9965990640 10347155456 1036545632 11.231 99 44 88 2 1078226416 31576432 10104352
02:32:11 52279 8192000000 9943539248 10316120064 988754704 10.769 96 52 102 2 1017258384 16476048 12027632
02:34:04 6718 8192000000 9954392816 10342617088 1041581120 11.280 100 55 111 3 1071422112 17790528 12050464
02:35:13 5966 8192000000 9985115056 10377502720 1063212800 11.487 100 46 92 0 1082655264 8925424 10517040
02:36:22 6025 8192000000 10001935664 10395152384 1073377280 11.584 100 39 77 1 1098686320 16718176 8590864
02:37:31 93104 8192000000 10036483760 10444607488 1100517280 11.843 100 25 50 0 1128538576 22545456 5475840
02:40:12 8558 8192000000 10068720384 10476949504 1126132656 12.085 100 48 95 1 1182789008 46804656 9851696
02:41:27 25615 8192000000 10096748688 10518360064 1136243664 12.180 100 36 70 0 1194182336 49930992 8007680
02:42:58 16630 8192000000 10135554560 10548703232 1154698848 12.354 100 60 118 2 1238491328 70325136 13467344
02:44:20 12319 8192000000 10194917056 10607603712 1154604960 12.353 100 56 112 0 1306027312 138803952 12618400
02:45:37 45235 8192000000 10197554480 10643030016 1164959744 12.450 100 42 84 2 1306206608 131681376 9565488
02:47:48 5767 8192000000 10235152736 10653450240 1175859552 12.552 100 67 134 1 1339442096 148864048 14718496
02:49:01 14996 8192000000 10253236704 10690154496 1175869424 12.552 100 54 108 1 1366500864 178135840 12495600
02:50:26 4291 8192000000 10297148032 10708774912 1187028064 12.656 100 80 158 2 1392209600 187845424 17336112
02:51:37 4538 8192000000 10298868784 10713104384 1183386320 12.622 100 80 160 1 1399679760 197739824 18553616
02:52:47 9550 8192000000 10323115088 10736979968 1186249152 12.648 100 73 140 1 1428110464 226393872 15467440
02:54:01 1441 8192000000 10373604240 10777559040 1173084048 12.526 99 88 176 1 1465879840 272921696 19874096
02:55:06 6491 8192000000 10344216528 10746343424 1142105648 12.235 99 87 170 2 1432949888 272250304 18593936
02:56:14 2872 8192000000 10313471584 10729041920 1142484000 12.239 100 71 141 0 1414019344 255368544 16166800
02:57:23 4431 8192000000 10333215152 10750550016 1138728416 12.204 99 69 136 1 1428362224 273650064 15983744
02:58:37 1548 8192000000 10347628192 10743390208 1128305696 12.105 100 83 163 1 1443887360 296253952 19327712
02:59:47 2388 8192000000 10339414320 10777321472 1123762896 12.063 100 84 162 1 1446974496 303311856 19899744
03:00:55 6548 8192000000 10353463376 10781642752 1134997440 12.168 100 53 103 0 1459473024 311513040 12962544
03:02:15 10581 8192000000 10363235712 10805727232 1138432208 12.201 100 36 72 0 1451234192 303252368 9549616
03:03:33 17790 8192000000 10356971632 10788478976 1138006512 12.197 100 32 64 0 1452127744 305815456 8305776
03:04:55 10194 8192000000 10380349536 10802343936 1137043312 12.188 100 51 98 2 1473360992 324522928 11794752
03:06:08 23233 8192000000 10398304080 10877231104 1138245856 12.199 99 26 52 0 1514394560 369005824 7142880
03:07:35 8300 8192000000 10373742128 10827661312 1143533456 12.249 100 30 60 2 1482095632 331158464 7403712
03:08:57 25402 8192000000 10203328048 10653949952 1132867920 12.148 100 20 40 2 1278544096 141364656 4311520
03:10:45 79270 8192000000 10127515584 10574319616 1135624016 12.174 99 29 58 1 1234526640 92676080 6226544
03:13:08 85696 8192000000 10100276912 10540732416 1126860704 12.092 98 20 40 1 1181671392 50785568 4025120
03:15:51 89533 8192000000 10148784544 10618703872 1123671568 12.062 98 22 45 2 1213858416 85018304 5168544
03:18:25 30918 8192000000 10086614512 10524422144 1128656416 12.109 98 20 40 1 1185041568 52166048 4219104
03:19:57 63921 8192000000 10148832768 10603614208 1147311808 12.284 100 15 30 1 1230857968 79605296 3940864
03:22:06 150844 8192000000 10108445952 10587475968 1133253648 12.152 98 23 46 1 1198756720 60901072 4602000
03:25:48 18374 8192000000 10063226944 10527354880 1137710272 12.194 99 20 40 0 1187689104 45445392 4533440
03:27:14 22190 8192000000 10103370640 10541715456 1152691568 12.335 100 50 99 0 1205236160 41653392 10891200
03:28:40 86930 8192000000 10110718512 10574086144 1134491792 12.164 99 15 30 0 1189100320 51058512 3550016
03:31:22 62899 8192000000 10127382688 10597220352 1147057728 12.282 99 23 46 0 1223820288 71558416 5204144
03:33:35 70652 8192000000 10102211488 10585907200 1147250208 12.284 100 34 67 1 1243634944 88747200 7637536
03:35:49 68099 8192000000 10110508976 10609709056 1137699392 12.194 98 27 54 0 1212426656 69030912 5696352
03:38:39 64683 8192000000 10104854256 10593808384 1143261168 12.246 99 18 35 0 1212368144 65014176 4092800
03:41:19 39470 8192000000 10148522336 10598719488 1146161632 12.273 99 25 50 2 1234703984 83220592 5321760
03:43:51 86060 8192000000 10106019392 10622115840 1109508496 11.928 96 13 26 0 1199167152 87000240 2658416
03:46:24 75740 8192000000 10045918112 10486333440 1039171776 11.257 91 8 16 0 1071961072 31527696 1261600
03:48:41 35823 8192000000 10082811632 10500968448 1090831088 11.751 96 43 86 0 1146691952 46732160 9128704
03:50:37 123011 8192000000 10031353424 10505900032 1035507216 11.221 92 11 22 0 1105753984 68111616 2135152
03:53:42 87538 8192000000 10069516176 10509037568 1085936736 11.704 98 35 70 0 1134573376 41863344 6773296
03:56:22 59132 8192000000 10032107040 10495463424 1085677968 11.702 97 10 19 0 1110646944 23258896 1710080
03:58:22 27766 8192000000 10064678304 10523652096 1095416256 11.794 98 11 23 0 1147466208 49548896 2501056
03:59:55 87589 8192000000 10035564976 10532884480 1078596192 11.634 97 16 32 0 1126351312 44363888 3391232
04:02:27 24840 8192000000 10044337392 10490150912 1082880160 11.675 98 20 40 0 1136230928 49356720 3994048
04:03:52 46716 8192000000 10032199488 10506895360 1083752000 11.683 99 18 36 0 1170398896 82551600 4095296
04:05:51 65885 8192000000 10066543040 10508775424 1095832704 11.798 98 17 34 0 1163603392 63911024 3859664
04:07:57 67949 8192000000 10079707152 10579976192 1098476784 11.823 98 24 48 0 1165287568 61742832 5067952
04:10:18 23507 8192000000 10049956592 10482401280 1114451312 11.975 99 38 76 0 1149717056 27946880 7318864
04:11:44 60201 8192000000 10057526320 10486808576 1101675808 11.854 97 28 56 0 1131962160 24561088 5725264
04:13:48 50944 8192000000 10117046912 10590187520 1141992112 12.234 99 50 100 2 1209171104 57370144 9808848
04:15:46 73490 8192000000 10105678912 10576269312 1127268192 12.096 98 20 40 0 1181078080 49581904 4227984
04:18:08 54758 8192000000 10094848160 10579677184 1118759120 12.015 97 12 24 0 1155450480 34246208 2445152
04:20:08 90339 8192000000 10069962144 10542088192 1149982816 12.309 100 18 36 1 1168070976 14118304 3969856
04:22:42 73404 8192000000 10106183472 10586722304 1141835232 12.233 99 13 26 0 1194530640 49964896 2730512
04:25:58 61813 8192000000 10061694640 10529398784 1148941696 12.300 100 12 23 0 1171967488 20689536 2336256
04:28:00 59800 8192000000 10090731200 10545770496 1142434656 12.238 99 28 56 0 1173217280 25239952 5542672
04:30:16 56035 8192000000 10141951776 10614181888 1136669232 12.184 98 27 54 1 1227089424 84419168 6001024
04:32:14 57995 8192000000 10187274432 10648424448 1134151616 12.160 99 21 42 1 1260805040 122106224 4547200
04:34:18 83664 8192000000 10133831872 10620809216 1076803168 11.617 94 23 45 1 1212808224 130524240 5480816
04:36:50 27116 8192000000 9064577568 9308459008 211308112 2.514 19 4 6 1 220229488 8297504 623872

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 13-09-2021 04:37:57 to 13-09-2021 04:38:12.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 121 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 13-09-2021 04:38:13 to 13-09-2021 04:38:15.

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          230852
MON$OLDEST_ACTIVE               230853
MON$OLDEST_SNAPSHOT             230853
MON$NEXT_TRANSACTION            230853
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                       2063456
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             788
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

13.09.21 04:38:16. 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-09-13 04:38:18 No severe PSQL-related problems occured
13.09.21 04:38:19. End of report.