Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.09.2021 04: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 15964840 kB
MemAvailable 30752680 kB
Buffers 20 kB
Cached 15647776 kB
SwapCached 480 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-19 04:37:08
DTS_END                         2021-09-19 04:37:08.0150
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/43370
TRN_ID                          225167
ATT_ID                          616
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: 8828.98 23839 1059477
customer order: creation 161.86 395 19423
customer order: refuse 13.97 383 1676
order to supplier: creation 43.87 1440 5264
order to supplier: removal 3.32 125465 398
invoice (draft): creation 43.30 3637 5196
invoice (draft): removal 50.43 1020 6052
invoice accept: apply 40.48 21001 4858
invoice accept: cancel 45.53 991 5464
customer reserve: creation 3715.39 206 445847
customer reserve: removal 4430.89 72 531707
realization accept: apply 79.50 184 9540
realization accept: cancel 77.39 139 9287
payment from customer: creation 56.34 122 6761
payment from customer: removal 5.38 88 645
payment to supplier: creation 47.56 134 5707
payment to supplier: removal 3.35 91 402
service: total inventory turnovers 3.69 3818 443
service: total monetary turnovers 5.79 584 695
service: aggregate perf. data 0.89 19283 107
service: refresh index statistics 0.04 297717 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-09-2021 04:38:26 to 19-09-2021 04:38:28.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4693
TEST_TIME 2 5262
TEST_TIME 3 5814
TEST_TIME 4 6105
TEST_TIME 5 5805
TEST_TIME 6 5648
TEST_TIME 7 5799
TEST_TIME 8 5683
TEST_TIME 9 6366
TEST_TIME 10 6336
TEST_TIME 11 6494
TEST_TIME 12 6916
TEST_TIME 13 7245
TEST_TIME 14 7213
TEST_TIME 15 7209
TEST_TIME 16 7439
TEST_TIME 17 7658
TEST_TIME 18 7746
TEST_TIME 19 7813
TEST_TIME 20 8466
TEST_TIME 21 8591
TEST_TIME 22 8931
TEST_TIME 23 8753
TEST_TIME 24 8946
TEST_TIME 25 9176
TEST_TIME 26 9821
TEST_TIME 27 9982
TEST_TIME 28 10878
TEST_TIME 29 10952
TEST_TIME 30 10845
TEST_TIME 31 10641
TEST_TIME 32 10651
TEST_TIME 33 10648
TEST_TIME 34 10522
TEST_TIME 35 10380
TEST_TIME 36 10127
TEST_TIME 37 10290
TEST_TIME 38 10111
TEST_TIME 39 10000
TEST_TIME 40 9900
TEST_TIME 41 9818
TEST_TIME 42 9912
TEST_TIME 43 9939
TEST_TIME 44 9807
TEST_TIME 45 9837
TEST_TIME 46 9744
TEST_TIME 47 9711
TEST_TIME 48 9575
TEST_TIME 49 9641
TEST_TIME 50 9481
TEST_TIME 51 9366
TEST_TIME 52 9329
TEST_TIME 53 9379
TEST_TIME 54 9377
TEST_TIME 55 9317
TEST_TIME 56 9269
TEST_TIME 57 9259
TEST_TIME 58 9425
TEST_TIME 59 9375
TEST_TIME 60 9269
TEST_TIME 61 9331
TEST_TIME 62 9384
TEST_TIME 63 9364
TEST_TIME 64 9294
TEST_TIME 65 9416
TEST_TIME 66 9471
TEST_TIME 67 9454
TEST_TIME 68 9513
TEST_TIME 69 9391
TEST_TIME 70 9303
TEST_TIME 71 9285
TEST_TIME 72 9226
TEST_TIME 73 9202
TEST_TIME 74 9120
TEST_TIME 75 9111
TEST_TIME 76 9068
TEST_TIME 77 9009
TEST_TIME 78 9026
TEST_TIME 79 8969
TEST_TIME 80 9018
TEST_TIME 81 8999
TEST_TIME 82 8977
TEST_TIME 83 9018
TEST_TIME 84 9013
TEST_TIME 85 9020
TEST_TIME 86 9028
TEST_TIME 87 9013
TEST_TIME 88 9004
TEST_TIME 89 9077
TEST_TIME 90 9024
TEST_TIME 91 8970
TEST_TIME 92 8897
TEST_TIME 93 8920
TEST_TIME 94 8893
TEST_TIME 95 8852
TEST_TIME 96 8829
TEST_TIME 97 8851
TEST_TIME 98 8851
TEST_TIME 99 8863
TEST_TIME 100 8889
TEST_TIME 101 8897
TEST_TIME 102 8872
TEST_TIME 103 8893
TEST_TIME 104 8913
TEST_TIME 105 9019
TEST_TIME 106 8987
TEST_TIME 107 9021
TEST_TIME 108 9058
TEST_TIME 109 9051
TEST_TIME 110 8975
TEST_TIME 111 8970
TEST_TIME 112 8919
TEST_TIME 113 8951
TEST_TIME 114 8896
TEST_TIME 115 8870
TEST_TIME 116 8878
TEST_TIME 117 8823
TEST_TIME 118 8831
TEST_TIME 119 8869
TEST_TIME 120 8840

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 19-09-2021 04:38:30 to 19-09-2021 04:38:33.

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 1471715 1471715 0 0.00 0 23174 8 0 0
doc_list_biud 1471713 1471713 0 0.00 0 52050 33 0 0
sp_add_invoice_to_stock 4942 4858 84 1.69 32 184208 21002 0 84
sp_cancel_adding_invoice 5464 5464 0 0.00 6 66013 991 0 0
sp_cancel_client_order 1676 1676 0 0.00 5 8748 383 0 0
sp_cancel_customer_reserve 531707 531707 0 0.00 1 46724 73 0 0
sp_cancel_pay_from_customer 645 645 0 0.00 3 3334 89 0 0
sp_cancel_pay_to_supplier 402 402 0 0.00 3 3896 91 0 0
sp_cancel_supplier_invoice 6061 6052 9 0.14 3 15703 1020 0 9
sp_cancel_supplier_order 398 398 0 0.00 8 1153316 125466 0 0
sp_cancel_write_off 9287 9287 0 0.00 1 12013 139 0 0
sp_client_order 19423 19423 0 0.00 4 43932 396 0 0
sp_customer_reserve 445847 445847 0 0.00 0 60754 207 0 0
sp_fill_shopping_cart 29880 29880 0 0.00 1 13490 46 0 0
sp_fill_shopping_cart_clo_res 445822 445822 0 0.00 0 12719 16 0 0
sp_get_clo_for_invoice 4831 4831 0 0.00 0 38214 434 0 0
sp_kill_cost_storno 15798 15798 0 0.00 0 7361 71 0 0
sp_kill_qstorno_ret_qs2qd 1079151 1079151 0 0.00 0 46719 39 0 0
sp_kill_qty_storno 568955 568955 0 0.00 0 52049 85 0 0
sp_lock_selected_doc 19871 19871 0 0.00 0 9753 5 0 0
sp_make_cost_storno 26839 26839 0 0.00 0 18481 66 0 0
sp_multiply_rows_for_pdistr 26839 26839 0 0.00 0 9898 36 0 0
sp_multiply_rows_for_qdistr 19422 19422 0 0.00 0 43922 210 0 0
sp_pay_from_customer 6761 6761 0 0.00 4 13897 122 0 0
sp_pay_to_supplier 5707 5707 0 0.00 4 14148 134 0 0
sp_payment_common 12468 12468 0 0.00 1 9196 23 0 0
sp_qd_handle_on_cancel_clo 1676 1676 0 0.00 0 8741 346 0 0
sp_qd_handle_on_invoice_upd_sts 10295 10295 0 0.00 0 52049 445 0 0
sp_qd_handle_on_reserve_upd_sts 9540 9540 0 0.00 0 14515 50 0 0
sp_reserve_write_off 9540 9540 0 0.00 7 18704 184 0 0
sp_supplier_invoice 5196 5196 0 0.00 8 98007 3637 0 0
sp_supplier_order 5264 5264 0 0.00 6 53645 1441 0 0
srv_aggregate_perf_data 107 107 0 0.00 2636 304927 19283 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 883 125237 42412 0 0
srv_find_qd_qs_mism 1013591 1013591 0 0.00 0 14682 7 0 0
srv_make_invnt_saldo 443 443 0 0.00 51 122396 3818 0 0
srv_make_money_saldo 695 695 0 0.00 1 11831 585 0 0
srv_recalc_idx_stat 5 5 0 0.00 131588 487670 297718 0 0
t$perf-abend:sp_add_invoice_to_stock 84 84 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4858 4858 0 0.00 0 14553 186 0 0
t$perf-norm:sp_cancel_adding_invoice 403 403 0 0.00 0 16366 150 0 0
t$perf-norm:sp_cancel_client_order 1676 1676 0 0.00 0 4077 14 0 0
t$perf-norm:sp_cancel_customer_reserve 638 638 0 0.00 0 535 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 645 645 0 0.00 0 5422 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 402 402 0 0.00 0 854 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 388 388 0 0.00 0 4297 28 0 0
t$perf-norm:sp_cancel_supplier_order 398 398 0 0.00 0 18987 1718 0 0
t$perf-norm:sp_cancel_write_off 950 950 0 0.00 0 2082 9 0 0
t$perf-norm:sp_client_order 19423 19423 0 0.00 0 12575 20 0 0
t$perf-norm:sp_customer_reserve 944 944 0 0.00 0 3801 16 0 0
t$perf-norm:sp_pay_from_customer 6761 6761 0 0.00 0 12708 10 0 0
t$perf-norm:sp_pay_to_supplier 5707 5707 0 0.00 0 4695 8 0 0
t$perf-norm:sp_reserve_write_off 9540 9540 0 0.00 0 5526 12 0 0
t$perf-norm:sp_supplier_invoice 5196 5196 0 0.00 0 16203 20 0 0
t$perf-norm:sp_supplier_order 5264 5264 0 0.00 0 6472 18 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 165 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 20 1 0 0
t$perf-norm:srv_make_invnt_saldo 443 443 0 0.00 0 12358 42 0 0
t$perf-norm:srv_make_money_saldo 695 695 0 0.00 0 623 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 18 6 0 0
v_add_invoice_to_stock 9828 9660 168 1.70 0 3515 15 0 168
v_all_customers 35476 35476 0 0.00 0 18 1 0 0
v_all_suppliers 29086 29086 0 0.00 0 19 1 0 0
v_all_wares 38842 38842 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 806 806 0 0.00 0 1967 13 0 0
v_cancel_client_order 3352 3352 0 0.00 0 1901 6 0 0
v_cancel_customer_prepayment 1290 1290 0 0.00 0 1687 8 0 0
v_cancel_customer_reserve 1276 1276 0 0.00 0 1737 7 0 0
v_cancel_payment_to_supplier 804 804 0 0.00 0 280 4 0 0
v_cancel_supplier_invoice 794 776 18 2.26 0 462 10 0 18
v_cancel_supplier_order 796 796 0 0.00 0 2369 9 0 0
v_cancel_write_off 1900 1900 0 0.00 0 5557 18 0 0
v_max_id_clo_ord 5263 5263 0 0.00 0 1915 2 0 0
v_max_id_clo_res 944 944 0 0.00 0 5589 25 0 0
v_max_id_ord_sup 5196 5196 0 0.00 0 1985 4 0 0
v_max_non_paid_invoice 1623 1623 0 0.00 0 1827 4 0 0
v_max_non_paid_realizn 3566 3566 0 0.00 0 1750 4 0 0
v_min_id_clo_ord 5263 5263 0 0.00 0 2694 7 0 0
v_min_id_clo_res 944 944 0 0.00 0 4449 21 0 0
v_min_id_ord_sup 5196 5196 0 0.00 0 3845 6 0 0
v_min_non_paid_invoice 5707 5707 0 0.00 0 6484 13 0 0
v_min_non_paid_realizn 6761 6761 0 0.00 0 9190 16 0 0
v_our_firm 9756 9756 0 0.00 0 72 1 0 0
v_reserve_write_off 19080 19080 0 0.00 0 3315 5 0 0
x_lock_depdocs_on_canc_invoice 11128 11128 0 0.00 0 18110 123 0 0
x_lock_depdocs_on_canc_sup_ord 398 398 0 0.00 0 5467 341 0 0
x_make_qstorno_1000_1200 5264 5264 0 0.00 1 53632 1350 0 0
x_make_qstorno_1200_2000 5196 5196 0 0.00 5 90266 3468 0 0
x_make_qstorno_2100_3300 445847 445847 0 0.00 0 60753 188 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-09-2021 04:38:34 to 19-09-2021 04:38:38.

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:11 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:12 41194 8192000000 9721753776 10021830656 803259056 8.929 100 88 176 0 832472336 7861696 21351584
02:09:59 25306 8192000000 9770741488 10079051776 840951200 9.309 100 89 178 0 876164400 14037152 21176048
02:11:29 13939 8192000000 9817989376 10137001984 875772832 9.658 100 85 170 0 923869040 28648480 19447728
02:12:52 11706 8192000000 9874070176 10206089216 923800112 10.134 100 80 160 0 982102576 40740976 17561488
02:14:08 11133 8192000000 9933970672 10279038976 958988480 10.479 100 78 156 0 1034108192 58624064 16495648
02:15:23 5028 8192000000 9975510752 10330062848 998320272 10.862 100 89 177 1 1078369840 61883840 18165728
02:16:30 4141 8192000000 9985943952 10350751744 1022267744 11.094 100 85 167 1 1099492400 60749152 16475504
02:17:36 2334 8192000000 10027006560 10396958720 1043556256 11.299 100 88 177 0 1141142608 79744880 17841472
02:18:39 970 8192000000 10034220288 10409492480 1059204272 11.449 100 78 153 0 1146765728 72234208 15327248
02:19:42 1402 8192000000 10041538800 10419298304 1063523216 11.490 100 81 163 0 1151052864 71380944 16148704
02:20:44 4289 8192000000 10013896464 10399420416 1074135360 11.592 100 49 98 0 1121048192 36257360 10655472
02:21:50 6383 8192000000 9994603504 10386763776 1084241200 11.688 100 34 69 0 1115424464 23968256 7215008
02:22:57 67226 8192000000 10005618816 10398314496 1086642160 11.711 100 50 101 1 1113022016 15612048 10767808
02:25:05 5064 8192000000 10018237760 10417848320 1094312304 11.784 100 61 123 1 1139076000 31331568 13432128
02:26:12 8487 8192000000 10007012640 10408501248 1089135040 11.734 99 38 77 2 1124264992 26460016 8669936
02:27:23 42279 8192000000 9996672336 10408108032 1043428032 11.298 96 27 54 1 1075591376 26427104 5736240
02:29:08 5572 8192000000 9987386816 10373005312 1044460352 11.308 97 42 78 1 1077530784 24129744 8940688
02:30:15 8169 8192000000 9975396656 10368987136 1024378176 11.114 98 35 71 1 1068244144 36306336 7559632
02:31:24 6386 8192000000 9944122912 10332524544 1010124816 10.977 98 30 60 1 1024975024 8435904 6414304
02:32:33 12652 8192000000 9953770928 10341834752 986392816 10.746 95 39 76 2 1025360464 31125840 7841808
02:33:46 6681 8192000000 9952814528 10337562624 1020190768 11.074 99 44 88 4 1038112288 7870560 10050960
02:34:55 8669 8192000000 9973691984 10367336448 1044003088 11.303 100 37 74 1 1077668512 25540784 8124640
02:36:06 64523 8192000000 10002617232 10400473088 1069155664 11.544 100 40 77 2 1090401904 13281280 7964960
02:38:19 11168 8192000000 10053087248 10443694080 1092998048 11.771 100 68 135 1 1159902816 52562000 14342768
02:39:40 24639 8192000000 10075060320 10475757568 1106560384 11.900 100 35 66 1 1182233472 68834432 6838656
02:41:11 31103 8192000000 10103691600 10521333760 1128010912 12.103 100 41 82 0 1211519296 74559376 8949008
02:42:47 32122 8192000000 10170354672 10586427392 1138266976 12.199 100 40 80 0 1254164432 106490912 9406544
02:44:21 17678 8192000000 10170454432 10586021888 1147990480 12.291 100 40 76 3 1273149184 116390096 8768608
02:45:46 2478 8192000000 10243307408 10650238976 1154720160 12.354 100 76 151 0 1347661536 176378048 16563328
02:46:59 1604 8192000000 10214047360 10623508480 1159227280 12.396 100 67 134 0 1316030816 142290624 14512912
02:48:05 12064 8192000000 10243000752 10666434560 1170293552 12.500 100 56 111 2 1363809952 180554704 12961696
02:49:20 2141 8192000000 10259466016 10677346304 1176695552 12.559 100 88 175 0 1355734352 160358672 18680128
02:50:32 2184 8192000000 10305341296 10712150016 1181757488 12.607 100 82 162 1 1402202736 202800544 17644704
02:51:39 2898 8192000000 10299870672 10712166400 1182986048 12.618 100 73 145 1 1403219104 203900448 16332608
02:52:46 2216 8192000000 10352560480 10759180288 1183286448 12.621 100 79 157 0 1446661536 245252128 18122960
02:53:54 880 8192000000 10370954448 10795319296 1197492176 12.753 100 79 159 0 1471081168 255156224 18432768
02:55:00 2038 8192000000 10410881616 10820349952 1188121232 12.666 100 90 179 1 1502271792 293575056 20575504
02:56:08 2459 8192000000 10414200912 10831634432 1172432016 12.520 100 85 169 0 1510978608 317982080 20564512
02:57:16 7472 8192000000 10405654368 10838294528 1153603728 12.343 99 51 102 0 1496585008 330075184 12906096
02:58:30 7441 8192000000 10375380752 10822492160 1170006640 12.497 100 59 117 1 1484474304 299890032 14577632
02:59:39 9143 8192000000 10354136800 10808442880 1165785264 12.457 100 36 71 0 1447376768 273050784 8540720
03:01:20 8544 8192000000 10388382944 10819403776 1141333760 12.228 99 46 92 0 1485920288 333527712 11058816
03:02:50 11812 8192000000 10375731568 10812907520 1140380848 12.219 99 40 78 0 1492589088 342243200 9965040
03:04:04 16605 8192000000 10286296960 10783608832 1128012640 12.103 100 22 44 0 1409131296 275369344 5749312
03:05:24 21620 8192000000 10120457120 10634825728 1070158736 11.554 96 18 34 3 1257300464 182670608 4471120
03:07:06 57462 8192000000 10028012352 10456170496 1054266896 11.402 95 28 55 1 1078417760 17808864 6342000
03:09:06 46895 8192000000 10076392480 10534780928 1103987456 11.875 98 22 44 0 1171357312 63188912 4180944
03:10:57 75071 8192000000 10118497808 10552184832 1089186048 11.735 96 25 48 0 1167433488 72493952 5753488
03:13:15 86996 8192000000 10108708576 10575720448 1120397968 12.031 100 39 74 1 1228428160 100179136 7851056
03:15:48 51496 8192000000 10098656352 10537312256 1114855024 11.978 98 24 48 1 1187984432 67845232 5284176
03:17:42 55997 8192000000 10162225856 10591588352 1116546672 11.994 98 21 42 1 1218960848 97425616 4988560
03:19:42 36945 8192000000 10117367104 10595160064 1139227696 12.208 100 22 44 0 1211218400 67285408 4705296
03:21:21 78856 8192000000 10080100336 10549616640 1128929712 12.111 99 14 29 0 1217581936 85189584 3462640
03:23:40 58062 8192000000 10094333200 10576891904 1142154112 12.236 100 18 36 1 1213564352 67688928 3721312
03:25:51 60601 8192000000 10098867360 10612457472 1152556544 12.333 100 17 32 0 1228722784 72731360 3434880
03:27:52 67105 8192000000 10097300304 10569928704 1129573728 12.117 99 37 74 2 1209198368 71414224 8210416
03:30:50 90760 8192000000 10047287488 10507579392 1104436992 11.880 98 16 32 0 1151219728 43600336 3182400
03:33:21 42128 8192000000 10135489344 10596904960 1138924112 12.205 100 41 82 0 1207220496 60275696 8020688
03:35:05 59166 8192000000 10045072048 10490093568 1075984768 11.609 95 20 40 2 1113988864 33525200 4478896
03:37:07 52082 8192000000 10147295600 10585726976 1130844944 12.129 100 40 80 2 1219832336 80526672 8460720
03:39:00 72059 8192000000 10067283152 10582970368 1108424320 11.917 98 18 36 0 1212338624 100149392 3764912
03:41:43 83925 8192000000 10125080192 10640666624 1142972128 12.243 100 21 42 0 1208447904 61263216 4212560
03:44:08 45889 8192000000 10164625072 10615316480 1128472960 12.107 99 22 44 2 1224477088 91291344 4712784
03:46:16 86615 8192000000 10060977904 10497888256 1120027600 12.027 99 32 63 0 1151944256 25112752 6803904
03:48:44 52572 8192000000 10089155712 10572152832 1130610144 12.127 99 16 32 1 1206917008 72334464 3972400
03:50:42 29680 8192000000 10062881968 10526306304 1092893248 11.770 96 13 26 1 1162217600 66542576 2781776
03:52:12 67142 8192000000 10094880368 10555789312 1138152752 12.198 100 17 34 1 1221747104 79787152 3807200
03:54:28 67451 8192000000 10072773104 10559627264 1135044048 12.169 99 29 57 4 1216893936 76109072 5740816
03:56:38 42202 8192000000 10091050112 10531655680 1114867696 11.978 98 23 46 1 1162012080 42405440 4738944
03:58:28 46314 8192000000 10061620032 10522091520 1116226112 11.991 99 21 42 2 1180005936 59319952 4459872
04:00:17 125236 8192000000 10090772400 10527797248 1066374064 11.517 94 18 34 1 1127227568 57447488 3406016
04:03:22 70195 8192000000 10056551280 10489688064 1092004608 11.762 97 27 54 0 1120331904 23498720 4828576
04:05:36 20621 8192000000 10138710192 10580135936 1133136720 12.151 100 43 86 1 1227565264 85566512 8862032
04:07:01 59621 8192000000 10083619616 10534219776 1122949936 12.055 99 22 44 0 1178574160 50622704 5001520
04:09:00 49272 8192000000 10051990880 10488786944 1063526064 11.490 94 36 71 1 1110449808 40299280 6624464
04:11:16 53314 8192000000 10041881120 10504523776 1114895264 11.979 99 14 28 0 1144449856 26906336 2648256
04:13:11 52107 8192000000 10074419168 10548486144 1111014672 11.942 99 19 38 1 1176586848 61579824 3992352
04:15:06 53817 8192000000 10075177296 10530091008 1103853312 11.874 99 19 38 1 1160886176 53258128 3774736
04:17:03 65468 8192000000 10100127712 10568622080 1107979632 11.913 98 23 46 0 1156405200 43551760 4873808
04:19:09 33062 8192000000 10104408976 10548473856 1107599696 11.910 98 49 98 1 1172300128 53830832 10869600
04:20:46 44343 8192000000 10132477984 10575155200 1103160016 11.868 97 25 49 3 1199655232 91031264 5463952
04:22:54 52886 8192000000 10066160864 10505068544 1073825824 11.589 95 29 57 1 1114711248 34056896 6828528
04:24:47 80390 8192000000 10084367344 10554748928 1099744256 11.835 97 16 32 0 1182771952 79531984 3495712
04:27:10 89979 8192000000 10074101440 10537947136 1131822544 12.139 100 32 64 0 1162422432 23699840 6900048
04:29:43 1781 8192000000 10071403808 10513604608 1118094928 12.009 99 59 118 1 1162091008 31716240 12279840
04:30:45 48103 8192000000 10054070448 10535849984 1133219968 12.152 100 10 20 0 1160161792 24886912 2054912
04:33:20 56177 8192000000 10124478544 10596532224 1150679968 12.316 100 19 38 2 1208173728 53364448 4129312
04:36:40 34351 8192000000 9005893360 9234636800 168485488 2.015 15 2 4 0 177130000 8571440 73072

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 19-09-2021 04:38:40 to 19-09-2021 04:38:55.

Exceptions occured during test was in run

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

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

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          225451
MON$OLDEST_ACTIVE               225452
MON$OLDEST_SNAPSHOT             225452
MON$NEXT_TRANSACTION            225452
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                       2039904
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             781
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

19.09.21 04:38:59. 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-19 04:39:01 No severe PSQL-related problems occured
19.09.21 04:39:03. End of report.