Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.06.2021 10:28
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 16802596 kB
MemAvailable 31427176 kB
Buffers 0 kB
Cached 14886808 kB
SwapCached 2472 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.1
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-06-17 10:27:00
DTS_END                         2021-06-17 10:27:00.7550
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/36488
TRN_ID                          232371
ATT_ID                          637
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: 8604.79 26540 1032575
customer order: creation 159.62 404 19154
customer order: refuse 14.24 334 1709
order to supplier: creation 44.97 1390 5396
order to supplier: removal 3.27 110072 392
invoice (draft): creation 43.01 3958 5161
invoice (draft): removal 47.19 981 5663
invoice accept: apply 40.42 19472 4851
invoice accept: cancel 42.60 970 5112
customer reserve: creation 3738.84 191 448661
customer reserve: removal 4194.14 66 503297
realization accept: apply 79.53 181 9543
realization accept: cancel 75.28 132 9033
payment from customer: creation 56.90 113 6828
payment from customer: removal 5.26 101 631
payment to supplier: creation 45.94 127 5513
payment to supplier: removal 3.37 86 404
service: total inventory turnovers 3.82 3264 458
service: total monetary turnovers 5.60 556 672
service: aggregate perf. data 0.78 17806 93
service: refresh index statistics 0.03 370591 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-06-2021 10:28:35 to 17-06-2021 10:28:37.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6295
TEST_TIME 2 5512
TEST_TIME 3 5525
TEST_TIME 4 5408
TEST_TIME 5 5005
TEST_TIME 6 4601
TEST_TIME 7 4967
TEST_TIME 8 5319
TEST_TIME 9 5263
TEST_TIME 10 5638
TEST_TIME 11 5859
TEST_TIME 12 6352
TEST_TIME 13 6497
TEST_TIME 14 6783
TEST_TIME 15 7258
TEST_TIME 16 7297
TEST_TIME 17 7764
TEST_TIME 18 8165
TEST_TIME 19 8467
TEST_TIME 20 9008
TEST_TIME 21 9273
TEST_TIME 22 9425
TEST_TIME 23 9934
TEST_TIME 24 10683
TEST_TIME 25 10964
TEST_TIME 26 11016
TEST_TIME 27 10714
TEST_TIME 28 10515
TEST_TIME 29 10404
TEST_TIME 30 10240
TEST_TIME 31 10105
TEST_TIME 32 9956
TEST_TIME 33 9811
TEST_TIME 34 9787
TEST_TIME 35 9692
TEST_TIME 36 9642
TEST_TIME 37 9459
TEST_TIME 38 9353
TEST_TIME 39 9262
TEST_TIME 40 9108
TEST_TIME 41 9347
TEST_TIME 42 9401
TEST_TIME 43 9357
TEST_TIME 44 9365
TEST_TIME 45 9224
TEST_TIME 46 9265
TEST_TIME 47 9352
TEST_TIME 48 9299
TEST_TIME 49 9207
TEST_TIME 50 9293
TEST_TIME 51 9210
TEST_TIME 52 9331
TEST_TIME 53 9328
TEST_TIME 54 9228
TEST_TIME 55 9109
TEST_TIME 56 8999
TEST_TIME 57 9022
TEST_TIME 58 8956
TEST_TIME 59 8996
TEST_TIME 60 8942
TEST_TIME 61 8898
TEST_TIME 62 8819
TEST_TIME 63 8867
TEST_TIME 64 8861
TEST_TIME 65 8837
TEST_TIME 66 8885
TEST_TIME 67 8900
TEST_TIME 68 9040
TEST_TIME 69 9023
TEST_TIME 70 9108
TEST_TIME 71 9144
TEST_TIME 72 9174
TEST_TIME 73 9243
TEST_TIME 74 9202
TEST_TIME 75 9278
TEST_TIME 76 9207
TEST_TIME 77 9094
TEST_TIME 78 9083
TEST_TIME 79 9026
TEST_TIME 80 8971
TEST_TIME 81 8961
TEST_TIME 82 8897
TEST_TIME 83 8879
TEST_TIME 84 8872
TEST_TIME 85 8837
TEST_TIME 86 8837
TEST_TIME 87 8803
TEST_TIME 88 8780
TEST_TIME 89 8919
TEST_TIME 90 8982
TEST_TIME 91 8956
TEST_TIME 92 8963
TEST_TIME 93 8992
TEST_TIME 94 9007
TEST_TIME 95 9068
TEST_TIME 96 9054
TEST_TIME 97 8988
TEST_TIME 98 8959
TEST_TIME 99 8933
TEST_TIME 100 8905
TEST_TIME 101 8898
TEST_TIME 102 8909
TEST_TIME 103 8938
TEST_TIME 104 8923
TEST_TIME 105 8935
TEST_TIME 106 8925
TEST_TIME 107 8913
TEST_TIME 108 8920
TEST_TIME 109 8949
TEST_TIME 110 9057
TEST_TIME 111 8981
TEST_TIME 112 8916
TEST_TIME 113 8903
TEST_TIME 114 8857
TEST_TIME 115 8826
TEST_TIME 116 8786
TEST_TIME 117 8754
TEST_TIME 118 8714
TEST_TIME 119 8685
TEST_TIME 120 8614

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 17-06-2021 10:28:38 to 17-06-2021 10:28:42.

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 1447525 1447525 0 0.00 0 12600 7 0 0
doc_list_biud 1447524 1447524 0 0.00 0 67578 30 0 0
sp_add_invoice_to_stock 4941 4851 90 1.82 34 166437 19472 0 90
sp_cancel_adding_invoice 5112 5112 0 0.00 4 73728 970 0 0
sp_cancel_client_order 1709 1709 0 0.00 5 8711 335 0 0
sp_cancel_customer_reserve 503297 503297 0 0.00 1 21822 67 0 0
sp_cancel_pay_from_customer 631 631 0 0.00 3 13987 102 0 0
sp_cancel_pay_to_supplier 404 404 0 0.00 3 6136 86 0 0
sp_cancel_supplier_invoice 5674 5663 11 0.19 2 67591 981 0 11
sp_cancel_supplier_order 392 392 0 0.00 10 1067301 110073 0 0
sp_cancel_write_off 9033 9033 0 0.00 1 7757 133 0 0
sp_client_order 19154 19154 0 0.00 4 22760 404 0 0
sp_customer_reserve 448661 448661 0 0.00 0 61040 191 0 0
sp_fill_shopping_cart 29689 29689 0 0.00 1 23747 49 0 0
sp_fill_shopping_cart_clo_res 448653 448653 0 0.00 0 28454 17 0 0
sp_get_clo_for_invoice 4838 4838 0 0.00 0 43400 444 0 0
sp_kill_cost_storno 15180 15180 0 0.00 0 8580 61 0 0
sp_kill_qstorno_ret_qs2qd 1021682 1021682 0 0.00 0 67577 36 0 0
sp_kill_qty_storno 539587 539587 0 0.00 0 67578 79 0 0
sp_lock_selected_doc 19960 19960 0 0.00 0 8073 6 0 0
sp_make_cost_storno 26722 26722 0 0.00 0 11710 53 0 0
sp_multiply_rows_for_pdistr 26722 26722 0 0.00 0 4797 30 0 0
sp_multiply_rows_for_qdistr 19154 19154 0 0.00 0 22741 209 0 0
sp_pay_from_customer 6828 6828 0 0.00 4 14194 113 0 0
sp_pay_to_supplier 5513 5513 0 0.00 5 14277 128 0 0
sp_payment_common 12341 12341 0 0.00 1 4563 17 0 0
sp_qd_handle_on_cancel_clo 1709 1709 0 0.00 0 8706 303 0 0
sp_qd_handle_on_invoice_upd_sts 9950 9950 0 0.00 0 31083 411 0 0
sp_qd_handle_on_reserve_upd_sts 9543 9543 0 0.00 0 12244 58 0 0
sp_reserve_write_off 9543 9543 0 0.00 7 15595 181 0 0
sp_supplier_invoice 5161 5161 0 0.00 6 91482 3958 0 0
sp_supplier_order 5396 5396 0 0.00 6 55429 1391 0 0
srv_aggregate_perf_data 93 93 0 0.00 2686 253480 17807 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 986 113952 49766 0 0
srv_find_qd_qs_mism 986882 986882 0 0.00 0 12600 6 0 0
srv_make_invnt_saldo 458 458 0 0.00 51 147550 3265 0 0
srv_make_money_saldo 672 672 0 0.00 1 19147 557 0 0
srv_recalc_idx_stat 4 4 0 0.00 231340 465836 370592 0 0
t$perf-abend:sp_add_invoice_to_stock 90 90 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4851 4851 0 0.00 0 6796 152 0 0
t$perf-norm:sp_cancel_adding_invoice 400 400 0 0.00 0 3994 99 0 0
t$perf-norm:sp_cancel_client_order 1709 1709 0 0.00 0 4950 11 0 0
t$perf-norm:sp_cancel_customer_reserve 672 672 0 0.00 0 3865 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 631 631 0 0.00 0 306 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 404 404 0 0.00 0 975 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 386 386 0 0.00 0 3942 22 0 0
t$perf-norm:sp_cancel_supplier_order 392 392 0 0.00 0 24445 1490 0 0
t$perf-norm:sp_cancel_write_off 985 985 0 0.00 0 1248 6 0 0
t$perf-norm:sp_client_order 19154 19154 0 0.00 0 6725 17 0 0
t$perf-norm:sp_customer_reserve 900 900 0 0.00 0 5701 14 0 0
t$perf-norm:sp_pay_from_customer 6828 6828 0 0.00 0 6123 8 0 0
t$perf-norm:sp_pay_to_supplier 5513 5513 0 0.00 0 5685 8 0 0
t$perf-norm:sp_reserve_write_off 9543 9543 0 0.00 0 14546 11 0 0
t$perf-norm:sp_supplier_invoice 5161 5161 0 0.00 0 4708 17 0 0
t$perf-norm:sp_supplier_order 5396 5396 0 0.00 0 18735 23 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 296 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 368 8 0 0
t$perf-norm:srv_make_invnt_saldo 458 458 0 0.00 0 376 5 0 0
t$perf-norm:srv_make_money_saldo 672 672 0 0.00 0 438 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 9856 9676 180 1.82 0 4707 17 0 180
v_all_customers 35046 35046 0 0.00 0 17 1 0 0
v_all_suppliers 28804 28804 0 0.00 0 76 1 0 0
v_all_wares 38308 38308 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 800 800 0 0.00 0 2239 10 0 0
v_cancel_client_order 3418 3418 0 0.00 0 5772 8 0 0
v_cancel_customer_prepayment 1262 1262 0 0.00 0 2876 8 0 0
v_cancel_customer_reserve 1344 1344 0 0.00 0 717 4 0 0
v_cancel_payment_to_supplier 808 808 0 0.00 0 4025 9 0 0
v_cancel_supplier_invoice 794 772 22 2.77 0 3140 18 0 22
v_cancel_supplier_order 784 784 0 0.00 0 556 4 0 0
v_cancel_write_off 1970 1970 0 0.00 0 2237 15 0 0
v_max_id_clo_ord 5387 5387 0 0.00 0 318 1 0 0
v_max_id_clo_res 900 900 0 0.00 0 3405 9 0 0
v_max_id_ord_sup 5148 5148 0 0.00 0 5858 6 0 0
v_max_non_paid_invoice 1646 1646 0 0.00 0 1531 3 0 0
v_max_non_paid_realizn 3565 3565 0 0.00 0 759 2 0 0
v_min_id_clo_ord 5387 5387 0 0.00 0 4565 9 0 0
v_min_id_clo_res 900 900 0 0.00 0 7931 18 0 0
v_min_id_ord_sup 5148 5148 0 0.00 0 7442 12 0 0
v_min_non_paid_invoice 5513 5513 0 0.00 0 3305 12 0 0
v_min_non_paid_realizn 6828 6828 0 0.00 0 3531 11 0 0
v_our_firm 9788 9788 0 0.00 0 17 1 0 0
v_reserve_write_off 19086 19086 0 0.00 0 6756 6 0 0
x_lock_depdocs_on_canc_invoice 10389 10389 0 0.00 0 19608 124 0 0
x_lock_depdocs_on_canc_sup_ord 392 392 0 0.00 0 20615 435 0 0
x_make_qstorno_1000_1200 5396 5396 0 0.00 2 55412 1307 0 0
x_make_qstorno_1200_2000 5161 5161 0 0.00 3 87218 3773 0 0
x_make_qstorno_2100_3300 448661 448661 0 0.00 0 60507 171 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-06-2021 10:28:43 to 17-06-2021 10:28:47.

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
07:57:04 6 8192000000 8796668864 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
07:58:05 20371 8192000000 9717292272 10011598848 794039920 8.836 100 90 180 0 823868432 7619136 22209376
07:59:31 22459 8192000000 9752410784 10059448320 828676752 9.186 100 87 172 1 861624688 11977808 20970128
08:00:57 20200 8192000000 9790029440 10105913344 856794432 9.468 100 87 172 1 896146400 18540608 20811360
08:02:22 20400 8192000000 9861904912 10193137664 914910384 10.046 100 73 146 0 974302192 43566800 15825008
08:03:47 7850 8192000000 9915998304 10261356544 954986384 10.440 100 73 147 2 1027081136 56261264 15833488
08:04:58 3953 8192000000 9956029312 10317918208 998434192 10.863 100 74 148 1 1073289232 59879792 14975248
08:06:05 2706 8192000000 9998386784 10361954304 1034332368 11.210 100 83 166 1 1111205920 59718768 17154784
08:07:08 2036 8192000000 10049828864 10420473856 1062146208 11.477 100 89 178 1 1161241040 80510720 18584112
08:08:11 4580 8192000000 10011035424 10393985024 1073953472 11.590 100 55 109 0 1129875968 44819600 11102896
08:09:16 5132 8192000000 9993949280 10383089664 1080972464 11.657 100 46 92 0 1109715392 19822192 8920736
08:10:22 5248 8192000000 9998231168 10395160576 1090093744 11.744 100 35 68 4 1128966192 31717168 7155280
08:11:29 34601 8192000000 10002232032 10405658624 1091329888 11.755 100 32 64 3 1116954208 18514432 7109888
08:13:06 4100 8192000000 10028913152 10426327040 1098873936 11.827 100 48 96 0 1136785280 26728128 11183216
08:14:12 7386 8192000000 10026221472 10433298432 1103117168 11.867 100 26 52 2 1137793536 29021408 5654960
08:15:19 28460 8192000000 10023782128 10443624448 1105619040 11.891 100 40 80 2 1134651968 19680176 9352752
08:17:26 7516 8192000000 10012898048 10418573312 1091940112 11.761 100 48 94 1 1120589488 17659760 10989616
08:18:35 5692 8192000000 9957242592 10350661632 1031135392 11.179 98 45 90 2 1064092304 22658960 10297952
08:19:41 48753 8192000000 9926392144 10313256960 919372576 10.090 88 31 62 0 952474224 25994112 7107536
08:21:45 4120 8192000000 9925647424 10300112896 1001935520 10.897 99 45 91 2 1035257088 22301264 11020304
08:22:51 8762 8192000000 9946948992 10344951808 1037593472 11.242 100 36 70 4 1071752608 26190368 7968768
08:24:00 6914 8192000000 9972305072 10366918656 1060382816 11.460 100 29 58 1 1082539616 16093072 6063728
08:25:08 44855 8192000000 9967552800 10377187328 1066364656 11.517 100 20 39 3 1091956304 21713872 3877776
08:26:55 8043 8192000000 10001826688 10409701376 1073630832 11.587 100 39 79 1 1102474288 20694704 8148752
08:28:19 15325 8192000000 10041218480 10436374528 1104821792 11.883 100 52 103 2 1149287888 32971168 11494928
08:29:39 41846 8192000000 10111942448 10524385280 1122839200 12.054 100 43 86 1 1201462736 69970704 8652832
08:31:27 87855 8192000000 10159382112 10585407488 1144074496 12.254 100 51 102 1 1264617808 109128576 11414736
08:34:05 13766 8192000000 10214529664 10635890688 1154193952 12.349 100 44 87 2 1316766688 152934896 9637840
08:35:26 15588 8192000000 10230452736 10649276416 1166063184 12.460 100 52 104 1 1332046768 154026288 11957296
08:36:49 9189 8192000000 10283551120 10697895936 1181736368 12.606 100 60 120 0 1385530928 189965968 13828592
08:38:07 986 8192000000 10338053216 10745712640 1192333968 12.705 100 92 180 2 1442800240 230355520 20110752
08:39:13 1622 8192000000 10387620672 10792349696 1182692688 12.615 100 83 165 0 1482895360 281779120 18423552
08:40:19 1904 8192000000 10405425008 10818113536 1183370576 12.622 100 90 180 1 1505044576 301423888 20250112
08:41:24 3738 8192000000 10415037360 10831065088 1172639120 12.521 100 68 134 1 1524234672 335638208 15957344
08:42:42 4480 8192000000 10398862992 10811129856 1181612832 12.605 100 75 151 0 1496601904 297563232 17425840
08:43:50 2024 8192000000 10438791504 10850177024 1179803392 12.588 100 79 158 0 1533593232 336465040 17324800
08:44:59 2294 8192000000 10410921888 10843856896 1171032048 12.506 100 80 160 1 1510129232 320511696 18585488
08:46:06 15036 8192000000 10387969936 10846978048 1151789920 12.326 100 44 88 1 1491885232 328192608 11902704
08:47:25 5393 8192000000 10379769312 10814570496 1151723776 12.326 100 56 110 2 1491305888 326807168 12774944
08:48:44 15650 8192000000 10319140320 10768125952 1114379536 11.974 99 31 62 1 1404667504 282442880 7845088
08:50:27 36423 8192000000 10263135872 10699780096 1078776816 11.636 96 21 42 1 1361120256 276706768 5636672
08:52:12 47456 8192000000 10120243616 10576404480 1093896768 11.780 98 24 49 0 1182626272 83206896 5522608
08:54:13 70642 8192000000 10067331920 10504974336 1108602704 11.919 99 25 50 1 1163538000 49258672 5676624
08:56:56 12378 8192000000 10041656560 10498015232 1100063824 11.838 99 21 42 1 1135179024 31105824 4009376
08:58:09 60065 8192000000 10034179680 10473254912 1078668400 11.635 96 21 43 0 1110631600 27844448 4118752
09:00:13 38553 8192000000 10084371936 10514542592 1125105088 12.075 100 39 77 1 1184559600 51214624 8239888
09:02:01 77952 8192000000 10074088976 10529153024 1112901840 11.960 98 14 28 1 1173319632 57401616 3016176
09:04:20 72545 8192000000 10138456608 10566066176 1126538400 12.089 99 29 57 1 1218980656 86090592 6351664
09:06:37 89280 8192000000 10198501968 10633449472 1120716160 12.034 99 27 54 1 1269750176 142355760 6678256
09:09:45 68859 8192000000 10099284336 10563866624 1138537808 12.202 100 16 32 0 1183948720 42276960 3133952
09:11:55 34699 8192000000 10058239712 10484203520 1118872608 12.016 99 22 44 1 1140835264 17524896 4437760
09:13:34 107497 8192000000 10069588304 10585309184 1119802272 12.025 99 17 34 1 1174945280 51662880 3480128
09:16:24 44574 8192000000 10110212928 10537869312 1117183696 12.000 98 32 64 2 1169830800 46169280 6477824
09:18:15 35312 8192000000 10094617888 10520707072 1115411248 11.984 98 34 68 2 1161449376 39145664 6892464
09:19:53 83008 8192000000 10104184368 10542477312 1130445072 12.126 99 12 24 0 1185749968 52992576 2312320
09:22:19 104134 8192000000 10093782928 10537844736 1134181072 12.161 99 31 60 2 1183757904 42973712 6603120
09:25:08 46857 8192000000 10113516832 10574196736 1151520368 12.324 100 18 36 0 1207917184 52078736 4318080
09:27:27 60290 8192000000 10116842848 10572656640 1145230592 12.265 99 22 44 2 1209524320 59764832 4528896
09:29:32 67826 8192000000 10090687168 10597912576 1118929088 12.017 97 8 16 1 1186489840 65683360 1877392
09:31:40 88875 8192000000 10128084160 10576916480 1072882208 11.580 93 25 48 3 1151584672 73047168 5655296
09:34:11 74020 8192000000 10142252560 10613596160 1113097232 11.962 98 22 44 2 1244739664 126452032 5190400
09:36:34 90036 8192000000 10111486944 10554449920 1104746592 11.883 97 17 34 0 1173921200 65463104 3711504
09:39:05 45416 8192000000 10100778320 10575609856 1116781072 11.997 99 33 65 0 1204902688 81648896 6472720
09:40:51 49805 8192000000 10053578192 10481762304 1057866496 11.436 94 29 58 1 1091601648 28372784 5362368
09:42:45 80232 8192000000 10040955616 10495508480 1060820112 11.464 95 16 31 2 1104094048 39535712 3738224
09:45:17 69816 8192000000 10043899696 10523250688 1105301408 11.888 100 16 32 0 1167473824 58333472 3838944
09:48:24 52539 8192000000 10041875248 10491441152 1092058992 11.762 98 10 20 3 1125338672 31362288 1917392
09:50:22 74631 8192000000 10028942528 10505347072 1107167456 11.906 99 8 16 1 1148279248 39524176 1587616
09:52:39 39753 8192000000 10048391648 10482262016 1077669488 11.625 97 30 60 1 1127786256 43711424 6405344
09:54:26 34117 8192000000 10112406160 10549637120 1124107120 12.066 100 33 66 1 1204515744 73095712 7312912
09:56:08 113951 8192000000 10065815808 10574872576 1124401296 12.069 100 11 22 1 1247248656 120280064 2567296
09:59:02 21040 8192000000 10088699872 10530852864 1134084688 12.160 99 34 68 3 1162570272 22147648 6337936
10:00:26 25763 8192000000 10076768656 10515337216 1108188720 11.915 97 22 44 1 1130435776 18051584 4195472
10:01:53 85148 8192000000 10091252960 10580328448 1127905376 12.102 99 22 44 0 1204081824 72190672 3985776
10:04:18 70682 8192000000 10049571392 10495926272 1096388928 11.803 96 17 34 1 1129328288 29113584 3825776
10:06:54 67181 8192000000 10050051664 10479616000 1100517136 11.843 97 22 43 1 1143382800 38306048 4559616
10:09:02 113203 8192000000 10079909216 10568974336 1126354736 12.087 99 18 36 0 1169428368 39506192 3567440
10:12:05 67249 8192000000 10098538896 10555203584 1114595184 11.976 98 24 48 1 1151084576 32128640 4360752
10:14:13 13898 8192000000 10091956544 10518511616 1124089408 12.066 99 43 86 1 1180853680 48471440 8292832
10:15:28 28609 8192000000 10105020384 10544218112 1134785072 12.166 100 52 104 2 1220013664 75041104 10187488
10:16:58 111910 8192000000 10092938032 10659790848 1117570832 12.004 98 11 22 1 1199660480 79721888 2367760
10:19:52 70888 8192000000 10053240944 10525265920 1109050592 11.923 98 17 33 2 1140511904 27898032 3563280
10:23:00 73468 8192000000 10072014720 10529611776 1109205440 11.925 98 13 26 0 1132094544 20238592 2650512
10:25:16 70265 8192000000 10045272704 10538496000 1131837360 12.139 100 11 23 0 1170717904 36768848 2111696

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 17-06-2021 10:28:49 to 17-06-2021 10:29:03.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 90 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-06-2021 10:29:04 to 17-06-2021 10:29:06.

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          232014
MON$OLDEST_ACTIVE               232558
MON$OLDEST_SNAPSHOT             232558
MON$NEXT_TRANSACTION            232558
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                       2094528
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             793
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2517 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2517 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2517 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2517 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:123: 
:124: mscs-test-161.budzdorov.ru    Thu Jun 17 10:27:05 2021
:125:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:126: 
--- end of diff output ---

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

17.06.21 10:29:07. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-06-17 10:29:10 No severe PSQL-related problems occured
17.06.21 10:29:11. End of report.