Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.04.2021 13:29
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 df9f488d08424df294c459d6c283e9c3
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-03-04 16:07
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 4794.26
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 16921312 kB
MemAvailable 31464320 kB
Buffers 0 kB
Cached 14766172 kB
SwapCached 96 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.0
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-04-07 13:26:57
DTS_END                         2021-04-07 13:26:57.7830
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/47742
TRN_ID                          235839
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: 8764.94 22191 1051793
customer order: creation 169.64 423 20357
customer order: refuse 14.11 314 1693
order to supplier: creation 45.43 1428 5452
order to supplier: removal 3.48 113997 417
invoice (draft): creation 45.18 3479 5422
invoice (draft): removal 50.19 986 6023
invoice accept: apply 41.04 17721 4925
invoice accept: cancel 45.07 963 5408
customer reserve: creation 3668.29 179 440195
customer reserve: removal 4397.30 71 527676
realization accept: apply 83.81 178 10057
realization accept: cancel 74.77 121 8972
payment from customer: creation 57.83 106 6940
payment from customer: removal 5.64 79 677
payment to supplier: creation 48.74 111 5849
payment to supplier: removal 3.68 73 441
service: total inventory turnovers 4.05 3160 486
service: total monetary turnovers 5.66 510 679
service: aggregate perf. data 1.00 12614 120
service: refresh index statistics 0.03 287301 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-04-2021 13:30:09 to 07-04-2021 13:30:11.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4392
TEST_TIME 2 4922
TEST_TIME 3 4872
TEST_TIME 4 4846
TEST_TIME 5 4660
TEST_TIME 6 4736
TEST_TIME 7 4973
TEST_TIME 8 5504
TEST_TIME 9 5480
TEST_TIME 10 6153
TEST_TIME 11 6227
TEST_TIME 12 6580
TEST_TIME 13 7228
TEST_TIME 14 7412
TEST_TIME 15 7451
TEST_TIME 16 7591
TEST_TIME 17 7602
TEST_TIME 18 7661
TEST_TIME 19 7667
TEST_TIME 20 8089
TEST_TIME 21 8214
TEST_TIME 22 8270
TEST_TIME 23 8229
TEST_TIME 24 8415
TEST_TIME 25 8415
TEST_TIME 26 8499
TEST_TIME 27 8756
TEST_TIME 28 9189
TEST_TIME 29 10086
TEST_TIME 30 10670
TEST_TIME 31 10546
TEST_TIME 32 10454
TEST_TIME 33 10228
TEST_TIME 34 9947
TEST_TIME 35 9719
TEST_TIME 36 10015
TEST_TIME 37 9906
TEST_TIME 38 9722
TEST_TIME 39 9627
TEST_TIME 40 9582
TEST_TIME 41 9495
TEST_TIME 42 9565
TEST_TIME 43 9533
TEST_TIME 44 9369
TEST_TIME 45 9281
TEST_TIME 46 9246
TEST_TIME 47 9139
TEST_TIME 48 9150
TEST_TIME 49 9097
TEST_TIME 50 9084
TEST_TIME 51 9023
TEST_TIME 52 9111
TEST_TIME 53 9219
TEST_TIME 54 9271
TEST_TIME 55 9244
TEST_TIME 56 9214
TEST_TIME 57 9251
TEST_TIME 58 9279
TEST_TIME 59 9400
TEST_TIME 60 9307
TEST_TIME 61 9181
TEST_TIME 62 9142
TEST_TIME 63 9045
TEST_TIME 64 9030
TEST_TIME 65 8962
TEST_TIME 66 9005
TEST_TIME 67 8988
TEST_TIME 68 9030
TEST_TIME 69 9052
TEST_TIME 70 9004
TEST_TIME 71 8957
TEST_TIME 72 8960
TEST_TIME 73 9081
TEST_TIME 74 9154
TEST_TIME 75 9277
TEST_TIME 76 9290
TEST_TIME 77 9366
TEST_TIME 78 9445
TEST_TIME 79 9443
TEST_TIME 80 9329
TEST_TIME 81 9277
TEST_TIME 82 9221
TEST_TIME 83 9148
TEST_TIME 84 9122
TEST_TIME 85 9056
TEST_TIME 86 8975
TEST_TIME 87 9033
TEST_TIME 88 9004
TEST_TIME 89 8999
TEST_TIME 90 9015
TEST_TIME 91 8979
TEST_TIME 92 9034
TEST_TIME 93 9036
TEST_TIME 94 9030
TEST_TIME 95 9003
TEST_TIME 96 9017
TEST_TIME 97 9045
TEST_TIME 98 9021
TEST_TIME 99 9128
TEST_TIME 100 9111
TEST_TIME 101 9046
TEST_TIME 102 8984
TEST_TIME 103 8932
TEST_TIME 104 8874
TEST_TIME 105 8853
TEST_TIME 106 8826
TEST_TIME 107 8805
TEST_TIME 108 8826
TEST_TIME 109 8794
TEST_TIME 110 8794
TEST_TIME 111 8762
TEST_TIME 112 8795
TEST_TIME 113 8800
TEST_TIME 114 8792
TEST_TIME 115 8783
TEST_TIME 116 8894
TEST_TIME 117 8857
TEST_TIME 118 8857
TEST_TIME 119 8816
TEST_TIME 120 8773

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 07-04-2021 13:30:13 to 07-04-2021 13:30:16.

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 1461007 1461007 0 0.00 0 18155 7 0 0
doc_list_biud 1461006 1461006 0 0.00 0 40805 33 0 0
sp_add_invoice_to_stock 5047 4925 122 2.41 29 160152 17722 0 122
sp_cancel_adding_invoice 5408 5408 0 0.00 5 68882 964 0 0
sp_cancel_client_order 1693 1693 0 0.00 4 8064 314 0 0
sp_cancel_customer_reserve 527676 527676 0 0.00 1 32899 72 0 0
sp_cancel_pay_from_customer 677 677 0 0.00 3 2501 79 0 0
sp_cancel_pay_to_supplier 441 441 0 0.00 3 7022 74 0 0
sp_cancel_supplier_invoice 6028 6023 5 0.08 2 32447 987 0 5
sp_cancel_supplier_order 417 417 0 0.00 11 1028577 113998 0 0
sp_cancel_write_off 8972 8972 0 0.00 1 13400 122 0 0
sp_client_order 20357 20357 0 0.00 4 25194 424 0 0
sp_customer_reserve 440195 440195 0 0.00 0 53816 179 0 0
sp_fill_shopping_cart 31217 31217 0 0.00 1 21435 45 0 0
sp_fill_shopping_cart_clo_res 440181 440181 0 0.00 0 23707 18 0 0
sp_get_clo_for_invoice 4911 4911 0 0.00 0 39919 424 0 0
sp_kill_cost_storno 15498 15498 0 0.00 0 6656 53 0 0
sp_kill_qstorno_ret_qs2qd 1070764 1070764 0 0.00 0 32894 39 0 0
sp_kill_qty_storno 565157 565157 0 0.00 0 40805 84 0 0
sp_lock_selected_doc 20698 20698 0 0.00 0 7370 5 0 0
sp_make_cost_storno 27757 27757 0 0.00 0 14241 56 0 0
sp_multiply_rows_for_pdistr 27757 27757 0 0.00 0 10744 32 0 0
sp_multiply_rows_for_qdistr 20356 20356 0 0.00 0 22100 243 0 0
sp_pay_from_customer 6940 6940 0 0.00 4 15476 106 0 0
sp_pay_to_supplier 5849 5849 0 0.00 4 12131 111 0 0
sp_payment_common 12789 12789 0 0.00 1 15442 19 0 0
sp_qd_handle_on_cancel_clo 1693 1693 0 0.00 0 8052 290 0 0
sp_qd_handle_on_invoice_upd_sts 10319 10319 0 0.00 0 40805 411 0 0
sp_qd_handle_on_reserve_upd_sts 10057 10057 0 0.00 0 20342 65 0 0
sp_reserve_write_off 10057 10057 0 0.00 7 20353 178 0 0
sp_supplier_invoice 5422 5422 0 0.00 6 98128 3480 0 0
sp_supplier_order 5452 5452 0 0.00 5 35626 1428 0 0
srv_aggregate_perf_data 120 120 0 0.00 2874 137375 12614 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1827 135078 47065 0 0
srv_find_qd_qs_mism 1006136 1006136 0 0.00 0 13300 6 0 0
srv_make_invnt_saldo 486 486 0 0.00 54 106455 3161 0 0
srv_make_money_saldo 679 679 0 0.00 1 12600 511 0 0
srv_recalc_idx_stat 4 4 0 0.00 102481 442158 287302 0 0
t$perf-abend:sp_add_invoice_to_stock 122 122 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4926 4926 0 0.00 0 9015 159 0 0
t$perf-norm:sp_cancel_adding_invoice 412 412 0 0.00 0 3935 121 0 0
t$perf-norm:sp_cancel_client_order 1693 1693 0 0.00 0 9287 14 0 0
t$perf-norm:sp_cancel_customer_reserve 682 682 0 0.00 0 8731 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 677 677 0 0.00 0 1932 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 441 441 0 0.00 0 3034 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 389 389 0 0.00 0 738 19 0 0
t$perf-norm:sp_cancel_supplier_order 417 417 0 0.00 0 25262 1473 0 0
t$perf-norm:sp_cancel_write_off 1019 1019 0 0.00 0 3149 8 0 0
t$perf-norm:sp_client_order 20357 20357 0 0.00 0 20263 20 0 0
t$perf-norm:sp_customer_reserve 1027 1027 0 0.00 0 6069 8 0 0
t$perf-norm:sp_pay_from_customer 6940 6940 0 0.00 0 11838 10 0 0
t$perf-norm:sp_pay_to_supplier 5850 5850 0 0.00 0 6796 8 0 0
t$perf-norm:sp_reserve_write_off 10057 10057 0 0.00 0 6652 10 0 0
t$perf-norm:sp_supplier_invoice 5422 5422 0 0.00 0 6790 18 0 0
t$perf-norm:sp_supplier_order 5452 5452 0 0.00 0 4805 16 0 0
t$perf-norm:srv_aggregate_perf_data 120 120 0 0.00 0 329 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 89 2 0 0
t$perf-norm:srv_make_invnt_saldo 486 486 0 0.00 0 4318 18 0 0
t$perf-norm:srv_make_money_saldo 679 679 0 0.00 0 1080 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 10 5 0 0
v_add_invoice_to_stock 10066 9822 244 2.42 0 2451 15 0 244
v_all_customers 36858 36858 0 0.00 0 18 1 0 0
v_all_suppliers 30080 30080 0 0.00 0 17 1 0 0
v_all_wares 40710 40710 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 824 824 0 0.00 0 361 6 0 0
v_cancel_client_order 3386 3386 0 0.00 0 2361 5 0 0
v_cancel_customer_prepayment 1354 1354 0 0.00 0 2492 6 0 0
v_cancel_customer_reserve 1364 1364 0 0.00 0 106 3 0 0
v_cancel_payment_to_supplier 882 882 0 0.00 0 1915 7 0 0
v_cancel_supplier_invoice 788 778 10 1.26 0 1350 15 0 10
v_cancel_supplier_order 834 834 0 0.00 0 2492 6 0 0
v_cancel_write_off 2038 2038 0 0.00 0 845 12 0 0
v_max_id_clo_ord 5449 5449 0 0.00 0 818 2 0 0
v_max_id_clo_res 1027 1027 0 0.00 0 1573 14 0 0
v_max_id_ord_sup 5413 5413 0 0.00 0 5393 8 0 0
v_max_non_paid_invoice 1671 1671 0 0.00 0 890 2 0 0
v_max_non_paid_realizn 3807 3807 0 0.00 0 1262 3 0 0
v_min_id_clo_ord 5449 5449 0 0.00 0 20299 12 0 0
v_min_id_clo_res 1027 1027 0 0.00 0 13349 23 0 0
v_min_id_ord_sup 5413 5413 0 0.00 0 2907 4 0 0
v_min_non_paid_invoice 5849 5849 0 0.00 0 2224 8 0 0
v_min_non_paid_realizn 6940 6940 0 0.00 0 5649 14 0 0
v_our_firm 10118 10118 0 0.00 0 16 1 0 0
v_reserve_write_off 20114 20114 0 0.00 0 2459 4 0 0
x_lock_depdocs_on_canc_invoice 11042 11042 0 0.00 0 11180 111 0 0
x_lock_depdocs_on_canc_sup_ord 417 417 0 0.00 0 14773 339 0 0
x_make_qstorno_1000_1200 5452 5452 0 0.00 2 35601 1343 0 0
x_make_qstorno_1200_2000 5422 5422 0 0.00 2 98112 3310 0 0
x_make_qstorno_2100_3300 440195 440195 0 0.00 0 46078 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-04-2021 13:30:17 to 07-04-2021 13:30:21.

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
10:57:01 9 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
10:58:01 3375 8192000000 9711512992 10008752128 800041008 8.897 100 85 170 0 827998576 7439904 20517664
10:59:10 23773 8192000000 9777410416 10084429824 849676544 9.397 100 92 184 0 883057280 11647904 21732832
11:00:39 21312 8192000000 9803602016 10122231808 874612272 9.646 100 89 178 0 915418512 20642688 20163552
11:02:08 10872 8192000000 9863250976 10195296256 909397440 9.991 100 79 158 1 972645024 46219600 17027984
11:03:30 7519 8192000000 9926365280 10269917184 960193728 10.491 100 84 168 1 1042322912 64603584 17525600
11:04:42 4787 8192000000 9952461376 10305458176 995216048 10.832 100 76 152 1 1065609216 54388848 16004320
11:05:47 2524 8192000000 10019002624 10378805248 1028281440 11.152 100 88 175 2 1129042496 83857408 16903648
11:06:51 1768 8192000000 10033748208 10400456704 1052519440 11.385 100 92 182 2 1141408528 71079504 17809584
11:07:55 2393 8192000000 10041302016 10420715520 1067207488 11.525 100 79 159 2 1159545600 76814704 15523408
11:08:58 20542 8192000000 9996342320 10412179456 1071710368 11.568 100 46 92 0 1112064432 30904640 9449424
11:10:27 5279 8192000000 10006160608 10404634624 1081482288 11.662 100 43 86 1 1120598160 30241904 8873968
11:11:33 6542 8192000000 10009492160 10409197568 1087431456 11.718 100 42 86 1 1123398928 26924960 9042512
11:12:42 8460 8192000000 10002847968 10404442112 1096320608 11.803 100 35 70 1 1122894544 18964048 7609888
11:13:51 66270 8192000000 9998581216 10409082880 1100085936 11.838 100 28 55 2 1132220096 26488896 5645264
11:16:00 8044 8192000000 10022126240 10432335872 1102001360 11.857 100 32 64 4 1131846288 23001520 6843408
11:17:12 4537 8192000000 10011517888 10401550336 1085499216 11.700 99 44 87 3 1112251984 16772848 9979920
11:18:19 9994 8192000000 9977520432 10375598080 1047897584 11.341 98 15 30 1 1074093216 22806768 3388864
11:19:33 39029 8192000000 9915267488 10305130496 967952960 10.567 93 22 44 1 991169632 18631440 4585232
11:21:16 3316 8192000000 9952795216 10331742208 1035625408 11.223 100 42 80 1 1067849648 20763696 11460544
11:22:21 4974 8192000000 9953236528 10338021376 1041645664 11.280 100 46 93 0 1065571152 13534896 10390592
11:23:28 11643 8192000000 9975323216 10358353920 1056307632 11.421 100 35 68 0 1085082400 20824368 7950400
11:24:41 29521 8192000000 9967518736 10370772992 1057307824 11.431 100 15 30 0 1078538240 17725040 3505376
11:26:11 9482 8192000000 9970370496 10378694656 1066303808 11.517 100 26 52 2 1097855728 25105856 6446064
11:27:22 20022 8192000000 10013894432 10407518208 1088610336 11.729 100 47 94 1 1113945728 15512128 9823264
11:28:49 35647 8192000000 10053146464 10458103808 1106253408 11.897 100 56 110 2 1158240816 39492656 12494752
11:30:45 29720 8192000000 10073239360 10480496640 1123336720 12.059 100 36 72 1 1178559312 47214640 8007952
11:32:20 7814 8192000000 10134264160 10541277184 1138281728 12.199 100 54 107 0 1240848448 90948064 11618656
11:33:41 5114 8192000000 10145491984 10555899904 1144937040 12.262 100 57 109 6 1262401024 104318496 13145488
11:34:50 17370 8192000000 10167128240 10573873152 1158512496 12.389 100 57 114 0 1271596464 100502096 12581872
11:36:13 2290 8192000000 10200195904 10613956608 1160505280 12.408 100 65 131 2 1310402752 134611632 15285840
11:37:19 4187 8192000000 10249580752 10651803648 1160329536 12.406 100 76 151 1 1345984192 169504528 16150128
11:38:28 5753 8192000000 10238059280 10651295744 1144508112 12.258 100 55 110 3 1343537424 186665296 12364016
11:39:39 7623 8192000000 10192408576 10599067648 1151977008 12.328 100 63 127 1 1296961536 130881360 14103168
11:40:52 7227 8192000000 10166805168 10570018816 1121967424 12.046 100 47 94 1 1270999696 138345520 10686752
11:42:10 5470 8192000000 10233972672 10638028800 1145872016 12.271 100 56 113 0 1337974864 178916336 13186512
11:43:25 7263 8192000000 10249094608 10650959872 1145956272 12.272 99 66 128 2 1345264752 184477504 14830976
11:44:40 1824 8192000000 10340642176 10737086464 1163493520 12.436 100 89 177 2 1437741968 253340800 20907648
11:45:47 3037 8192000000 10393368624 10803658752 1168656720 12.484 100 75 150 0 1497938592 311431392 17850480
11:46:54 13829 8192000000 10346406560 10780446720 1156211888 12.368 100 45 90 2 1454942768 287217904 11512976
11:48:14 9411 8192000000 10344229184 10789203968 1158765152 12.392 100 47 94 1 1466086272 295839408 11481712
11:49:54 9661 8192000000 10398039552 10825543680 1151543376 12.324 99 39 78 2 1492493104 331195088 9754640
11:51:07 15444 8192000000 10432869376 10862956544 1153960016 12.347 99 39 76 1 1536351904 372553088 9838800
11:52:31 14774 8192000000 10461611616 10896232448 1155248256 12.359 100 37 74 1 1558661808 393770800 9642752
11:53:47 19950 8192000000 10489465792 10948190208 1139103872 12.207 100 37 74 1 1580475776 432075184 9296720
11:55:07 27176 8192000000 10351089696 10816303104 1122946800 12.055 100 17 34 1 1498808096 370274672 5586624
11:56:40 23461 8192000000 10053985024 10481545216 1110030784 11.933 99 47 93 2 1149317616 29992416 9294416
11:58:12 135077 8192000000 10132928768 10573983744 1115308368 11.983 98 18 36 0 1172079616 53437600 3333648
12:02:24 94421 8192000000 10065415264 10521346048 1124857008 12.073 98 18 35 0 1160734144 32755616 3121520
12:05:00 27175 8192000000 10107234320 10553085952 1155388400 12.360 100 31 62 0 1211894448 49860656 6645392
12:06:29 58713 8192000000 10119180912 10566467584 1143208688 12.246 99 23 46 1 1220820096 72512496 5098912
12:08:39 26701 8192000000 10062188160 10538299392 1141036320 12.225 99 28 54 1 1195303808 48770672 5496816
12:10:12 56313 8192000000 10099035744 10551697408 1140307008 12.218 99 11 22 0 1193611584 50959472 2345104
12:12:18 106917 8192000000 10085890672 10546974720 1127413008 12.097 98 10 20 0 1158699296 29330608 1955680
12:15:28 80935 8192000000 10098601520 10561462272 1125449600 12.078 97 19 38 1 1157772208 28743552 3579056
12:17:49 52701 8192000000 10168064592 10625716224 1128672592 12.109 97 27 54 0 1232514016 97879232 5962192
12:19:43 54102 8192000000 10107489104 10566250496 1139177104 12.208 99 15 30 1 1213429488 70970416 3281968
12:21:58 25044 8192000000 10097052624 10537418752 1141753168 12.232 99 45 90 1 1183064176 31866192 9444816
12:23:28 77663 8192000000 10132746208 10563768320 1124088592 12.066 98 24 48 1 1225569712 96526096 4955024
12:25:46 116995 8192000000 10064271536 10510602240 1117128336 12.000 98 20 40 0 1163216576 41847296 4240944
12:28:49 44767 8192000000 10088746240 10559946752 1129027872 12.112 98 10 20 0 1185361056 54651920 1681264
12:31:06 49275 8192000000 10109722592 10556751872 1123311952 12.058 98 8 17 1 1177566720 52732560 1522208
12:33:02 46811 8192000000 10124446096 10572931072 1144608896 12.259 99 27 54 2 1208685728 58654784 5422048
12:35:25 26165 8192000000 10138343840 10582233088 1107652912 11.910 97 45 90 1 1184330912 67592144 9085856
12:36:54 46089 8192000000 10169717376 10602266624 1100002928 11.838 96 23 46 0 1215677376 110091216 5583232
12:38:42 64453 8192000000 10143486848 10574802944 1122979872 12.055 98 25 50 4 1272746992 144010592 5756528
12:40:48 50858 8192000000 10104663776 10601635840 1063278240 11.488 94 16 32 0 1132306080 65669408 3358432
12:42:46 39480 8192000000 10089788944 10564775936 1101852528 11.855 99 11 22 0 1170326208 65982368 2491312
12:44:38 97891 8192000000 10029089696 10525933568 1060708864 11.463 96 6 11 1 1110747648 48926928 1111856
12:47:43 79117 8192000000 10033948720 10497032192 1100109312 11.839 99 19 38 2 1125379776 21691616 3578848
12:50:03 60785 8192000000 10066888976 10522009600 1076837376 11.617 97 13 26 0 1140529888 60798736 2893776
12:52:07 67752 8192000000 10096601504 10532995072 1076100784 11.610 97 19 38 0 1167016448 86302400 4613264
12:54:17 48180 8192000000 10066560528 10513575936 1115881040 11.988 100 30 60 2 1140970160 18802576 6286544
12:56:07 79779 8192000000 10081748048 10510168064 1071318896 11.565 95 17 34 1 1133583232 58864480 3399856
12:58:29 47535 8192000000 10071648480 10518966272 1132931904 12.149 100 16 32 0 1190598304 54497360 3169040
13:00:17 75227 8192000000 10074803744 10507272192 1124077808 12.065 99 13 25 1 1159077648 32332064 2667776
13:02:34 61849 8192000000 10093996928 10557841408 1142141056 12.236 100 21 42 1 1198164880 52085744 3938080
13:04:42 75373 8192000000 10088692080 10606985216 1138637920 12.203 99 12 24 0 1189739520 48349168 2752432
13:06:58 125709 8192000000 10065444336 10534756352 1144568240 12.258 100 18 36 1 1181907616 33429984 3909392
13:10:13 57702 8192000000 10080850400 10553819136 1144403792 12.257 100 10 20 0 1196174560 49463744 2307024
13:12:16 41427 8192000000 10048034752 10508734464 1123906240 12.064 98 3 6 0 1156284112 31898832 479040
13:14:00 66494 8192000000 10083134384 10579730432 1110004384 11.932 98 10 20 0 1161865632 49601968 2259280
13:16:07 63824 8192000000 10086784080 10527256576 1125803760 12.082 99 21 42 0 1171272080 40963216 4505104
13:18:22 52733 8192000000 10098235024 10542211072 1126397440 12.087 99 28 57 0 1197814032 65222208 6194384
13:20:16 68710 8192000000 10126481664 10556243968 1099766512 11.835 96 29 58 1 1140152944 34604192 5782240
13:22:25 86413 8192000000 10093626384 10548838400 1127207360 12.095 99 16 32 0 1186677504 55849568 3620576
13:25:02 133799 8192000000 9605851392 10000416768 706348512 7.937 62 20 39 1 779137360 68607440 4181408

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 07-04-2021 13:30:23 to 07-04-2021 13:30:38.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 122 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-04-2021 13:30:39 to 07-04-2021 13:30:41.

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          236507
MON$OLDEST_ACTIVE               236508
MON$OLDEST_SNAPSHOT             236508
MON$NEXT_TRANSACTION            236508
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                       2085088
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             798
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

Database statistics

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

Database validation

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

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

--- start of diff output ---
--- end of diff output ---

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

07.04.21 13:30:42. 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-04-07 13:30:45 No severe PSQL-related problems occured
07.04.21 13:30:46. End of report.