Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.03.2021 10:27
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 16946008 kB
MemAvailable 31554656 kB
Buffers 0 kB
Cached 14684924 kB
SwapCached 0 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-03-12 10:26:57
DTS_END                         2021-03-12 10:26:57.2420
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_MAKE_INVNT_SALDO(40:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/37308
TRN_ID                          249085
ATT_ID                          692
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: 8985.68 23309 1078282
customer order: creation 182.88 428 21946
customer order: refuse 15.70 300 1884
order to supplier: creation 48.07 1373 5768
order to supplier: removal 3.21 113129 385
invoice (draft): creation 49.30 3810 5916
invoice (draft): removal 47.94 948 5753
invoice accept: apply 44.33 15857 5319
invoice accept: cancel 42.84 910 5141
customer reserve: creation 4030.27 157 483632
customer reserve: removal 4225.93 69 507111
realization accept: apply 88.53 156 10624
realization accept: cancel 69.79 129 8375
payment from customer: creation 63.53 106 7623
payment from customer: removal 6.22 73 746
payment to supplier: creation 51.42 108 6170
payment to supplier: removal 3.67 87 440
service: total inventory turnovers 4.52 2560 542
service: total monetary turnovers 6.53 443 784
service: aggregate perf. data 0.99 14164 119
service: refresh index statistics 0.03 311368 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-03-2021 10:27:39 to 12-03-2021 10:27:41.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3704
TEST_TIME 2 2442
TEST_TIME 3 2364
TEST_TIME 4 3052
TEST_TIME 5 3136
TEST_TIME 6 3560
TEST_TIME 7 4095
TEST_TIME 8 4116
TEST_TIME 9 4450
TEST_TIME 10 4723
TEST_TIME 11 4950
TEST_TIME 12 5258
TEST_TIME 13 5679
TEST_TIME 14 5977
TEST_TIME 15 6172
TEST_TIME 16 6617
TEST_TIME 17 7093
TEST_TIME 18 7592
TEST_TIME 19 7932
TEST_TIME 20 8240
TEST_TIME 21 8354
TEST_TIME 22 8824
TEST_TIME 23 9096
TEST_TIME 24 9266
TEST_TIME 25 9748
TEST_TIME 26 9950
TEST_TIME 27 10983
TEST_TIME 28 10774
TEST_TIME 29 10560
TEST_TIME 30 10216
TEST_TIME 31 10193
TEST_TIME 32 10090
TEST_TIME 33 9970
TEST_TIME 34 9811
TEST_TIME 35 9645
TEST_TIME 36 9494
TEST_TIME 37 9741
TEST_TIME 38 9662
TEST_TIME 39 9630
TEST_TIME 40 9606
TEST_TIME 41 9488
TEST_TIME 42 9594
TEST_TIME 43 9587
TEST_TIME 44 9563
TEST_TIME 45 9599
TEST_TIME 46 9498
TEST_TIME 47 9568
TEST_TIME 48 9661
TEST_TIME 49 9653
TEST_TIME 50 9718
TEST_TIME 51 9764
TEST_TIME 52 9718
TEST_TIME 53 9583
TEST_TIME 54 9461
TEST_TIME 55 9399
TEST_TIME 56 9308
TEST_TIME 57 9236
TEST_TIME 58 9165
TEST_TIME 59 9146
TEST_TIME 60 9113
TEST_TIME 61 9084
TEST_TIME 62 9045
TEST_TIME 63 9104
TEST_TIME 64 9049
TEST_TIME 65 9151
TEST_TIME 66 9186
TEST_TIME 67 9222
TEST_TIME 68 9241
TEST_TIME 69 9278
TEST_TIME 70 9253
TEST_TIME 71 9297
TEST_TIME 72 9215
TEST_TIME 73 9139
TEST_TIME 74 9094
TEST_TIME 75 9035
TEST_TIME 76 9051
TEST_TIME 77 9049
TEST_TIME 78 9045
TEST_TIME 79 9011
TEST_TIME 80 9089
TEST_TIME 81 9080
TEST_TIME 82 9074
TEST_TIME 83 9113
TEST_TIME 84 9086
TEST_TIME 85 9046
TEST_TIME 86 9150
TEST_TIME 87 9178
TEST_TIME 88 9092
TEST_TIME 89 9016
TEST_TIME 90 8974
TEST_TIME 91 8912
TEST_TIME 92 8880
TEST_TIME 93 8902
TEST_TIME 94 8981
TEST_TIME 95 8947
TEST_TIME 96 8952
TEST_TIME 97 8935
TEST_TIME 98 9005
TEST_TIME 99 9063
TEST_TIME 100 9037
TEST_TIME 101 9087
TEST_TIME 102 9092
TEST_TIME 103 9155
TEST_TIME 104 9141
TEST_TIME 105 9137
TEST_TIME 106 9053
TEST_TIME 107 9010
TEST_TIME 108 8962
TEST_TIME 109 8943
TEST_TIME 110 8926
TEST_TIME 111 8888
TEST_TIME 112 8872
TEST_TIME 113 8853
TEST_TIME 114 8855
TEST_TIME 115 8824
TEST_TIME 116 8865
TEST_TIME 117 8953
TEST_TIME 118 8984
TEST_TIME 119 8958
TEST_TIME 120 8988
TEST_TIME 121 8914

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 12-03-2021 10:27:42 to 12-03-2021 10:27:46.

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 1524253 1524253 0 0.00 0 28349 6 0 0
doc_list_biud 1524253 1524253 0 0.00 0 76525 29 0 0
sp_add_invoice_to_stock 5479 5319 160 2.92 29 177259 15858 0 160
sp_cancel_adding_invoice 5141 5141 0 0.00 6 60263 910 0 0
sp_cancel_client_order 1884 1884 0 0.00 5 9738 301 0 0
sp_cancel_customer_reserve 507111 507111 0 0.00 1 20418 70 0 0
sp_cancel_pay_from_customer 746 746 0 0.00 3 4473 73 0 0
sp_cancel_pay_to_supplier 440 440 0 0.00 3 4385 88 0 0
sp_cancel_supplier_invoice 5766 5753 13 0.22 2 76598 949 0 13
sp_cancel_supplier_order 385 385 0 0.00 13 1234007 113130 0 0
sp_cancel_write_off 8375 8375 0 0.00 1 13045 130 0 0
sp_client_order 21946 21946 0 0.00 4 28188 429 0 0
sp_customer_reserve 483632 483632 0 0.00 0 63529 158 0 0
sp_fill_shopping_cart 33611 33611 0 0.00 1 30690 48 0 0
sp_fill_shopping_cart_clo_res 483614 483614 0 0.00 0 18189 18 0 0
sp_get_clo_for_invoice 5301 5301 0 0.00 0 40842 392 0 0
sp_kill_cost_storno 14702 14702 0 0.00 0 5263 53 0 0
sp_kill_qstorno_ret_qs2qd 1028735 1028735 0 0.00 0 76522 38 0 0
sp_kill_qty_storno 544574 544574 0 0.00 0 76523 81 0 0
sp_lock_selected_doc 22011 22011 0 0.00 0 8647 6 0 0
sp_make_cost_storno 29718 29718 0 0.00 0 13271 46 0 0
sp_multiply_rows_for_pdistr 29718 29718 0 0.00 0 10765 25 0 0
sp_multiply_rows_for_qdistr 21946 21946 0 0.00 0 25676 241 0 0
sp_pay_from_customer 7623 7623 0 0.00 4 17038 106 0 0
sp_pay_to_supplier 6170 6170 0 0.00 4 16967 108 0 0
sp_payment_common 13793 13793 0 0.00 1 9739 17 0 0
sp_qd_handle_on_cancel_clo 1884 1884 0 0.00 0 9731 276 0 0
sp_qd_handle_on_invoice_upd_sts 10442 10442 0 0.00 0 66360 348 0 0
sp_qd_handle_on_reserve_upd_sts 10624 10624 0 0.00 0 19965 49 0 0
sp_reserve_write_off 10624 10624 0 0.00 7 20000 156 0 0
sp_supplier_invoice 5916 5916 0 0.00 5 141157 3811 0 0
sp_supplier_order 5768 5768 0 0.00 5 35868 1374 0 0
srv_aggregate_perf_data 120 119 1 0.83 2450 263352 14164 0 1
srv_fill_mon_cache_memory 63 63 0 0.00 2384 123819 50838 0 0
srv_find_qd_qs_mism 1026942 1026942 0 0.00 0 28348 6 0 0
srv_make_invnt_saldo 542 542 0 0.00 30 95262 2560 0 0
srv_make_money_saldo 784 784 0 0.00 1 17452 443 0 0
srv_recalc_idx_stat 4 4 0 0.00 215165 413083 311368 0 0
t$perf-abend:sp_add_invoice_to_stock 160 160 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5319 5319 0 0.00 0 10348 135 0 0
t$perf-norm:sp_cancel_adding_invoice 421 421 0 0.00 0 3499 96 0 0
t$perf-norm:sp_cancel_client_order 1884 1884 0 0.00 0 2101 9 0 0
t$perf-norm:sp_cancel_customer_reserve 708 708 0 0.00 0 3232 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 746 746 0 0.00 0 415 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 440 440 0 0.00 0 430 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 440 440 0 0.00 0 2306 12 0 0
t$perf-norm:sp_cancel_supplier_order 385 385 0 0.00 0 15016 1251 0 0
t$perf-norm:sp_cancel_write_off 1063 1063 0 0.00 0 645 5 0 0
t$perf-norm:sp_client_order 21946 21946 0 0.00 0 6168 13 0 0
t$perf-norm:sp_customer_reserve 1026 1026 0 0.00 0 841 3 0 0
t$perf-norm:sp_pay_from_customer 7623 7623 0 0.00 0 7801 6 0 0
t$perf-norm:sp_pay_to_supplier 6170 6170 0 0.00 0 6237 6 0 0
t$perf-norm:sp_reserve_write_off 10624 10624 0 0.00 0 10233 8 0 0
t$perf-norm:sp_supplier_invoice 5916 5916 0 0.00 0 4313 15 0 0
t$perf-norm:sp_supplier_order 5768 5768 0 0.00 0 4334 12 0 0
t$perf-norm:srv_aggregate_perf_data 119 119 0 0.00 0 122 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 1820 35 0 0
t$perf-norm:srv_make_invnt_saldo 542 542 0 0.00 0 977 8 0 0
t$perf-norm:srv_make_money_saldo 784 784 0 0.00 0 619 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 60 17 0 0
v_add_invoice_to_stock 10922 10602 320 2.92 0 8077 16 0 320
v_all_customers 39990 39990 0 0.00 0 16 1 0 0
v_all_suppliers 32040 32040 0 0.00 0 17 1 0 0
v_all_wares 43868 43868 0 0.00 0 36 1 0 0
v_cancel_adding_invoice 842 842 0 0.00 0 305 6 0 0
v_cancel_client_order 3768 3768 0 0.00 0 323 2 0 0
v_cancel_customer_prepayment 1492 1492 0 0.00 0 2354 5 0 0
v_cancel_customer_reserve 1416 1416 0 0.00 0 1487 6 0 0
v_cancel_payment_to_supplier 880 880 0 0.00 0 3849 17 0 0
v_cancel_supplier_invoice 906 880 26 2.86 0 1251 15 0 26
v_cancel_supplier_order 770 770 0 0.00 0 1302 5 0 0
v_cancel_write_off 2126 2126 0 0.00 0 6858 24 0 0
v_max_id_clo_ord 5767 5767 0 0.00 0 237 1 0 0
v_max_id_clo_res 1026 1026 0 0.00 0 1572 15 0 0
v_max_id_ord_sup 5909 5909 0 0.00 0 6815 9 0 0
v_max_non_paid_invoice 1821 1821 0 0.00 0 5183 5 0 0
v_max_non_paid_realizn 4114 4114 0 0.00 0 6119 5 0 0
v_min_id_clo_ord 5767 5767 0 0.00 0 1836 6 0 0
v_min_id_clo_res 1026 1026 0 0.00 0 1539 6 0 0
v_min_id_ord_sup 5909 5909 0 0.00 0 9336 13 0 0
v_min_non_paid_invoice 6170 6170 0 0.00 0 5439 10 0 0
v_min_non_paid_realizn 7623 7623 0 0.00 0 4287 10 0 0
v_our_firm 10896 10896 0 0.00 0 21 1 0 0
v_reserve_write_off 21246 21246 0 0.00 0 4195 5 0 0
x_lock_depdocs_on_canc_invoice 10454 10454 0 0.00 0 18838 113 0 0
x_lock_depdocs_on_canc_sup_ord 385 385 0 0.00 0 5317 353 0 0
x_make_qstorno_1000_1200 5768 5768 0 0.00 2 35859 1298 0 0
x_make_qstorno_1200_2000 5916 5916 0 0.00 2 141120 3619 0 0
x_make_qstorno_2100_3300 483632 483632 0 0.00 0 57579 138 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-03-2021 10:27:47 to 12-03-2021 10:27:51.

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:00 2 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
07:58:01 34580 8192000000 9725190544 10020896768 798141712 8.877 100 90 180 0 828450768 7820832 22488224
07:59:40 22389 8192000000 9756475760 10059034624 830526736 9.205 100 90 180 0 865803440 13354256 21922448
08:01:08 32583 8192000000 9826373360 10145136640 868334320 9.583 100 88 174 1 921205888 32326736 20544832
08:02:44 9485 8192000000 9878342528 10206957568 919949728 10.096 100 79 158 1 983039472 46095472 16994272
08:03:56 7905 8192000000 9937816704 10283352064 963951584 10.528 100 78 156 1 1043605488 63592192 16061712
08:05:07 5457 8192000000 9976316304 10330247168 1006257888 10.939 100 78 156 1 1080167168 58437536 15471744
08:06:14 2361 8192000000 10023534288 10392473600 1032020704 11.188 100 84 167 2 1137404736 88208928 17175104
08:07:19 1793 8192000000 10043457472 10414526464 1053443344 11.394 100 89 177 0 1149846992 78586576 17817072
08:08:21 4393 8192000000 10010388928 10393604096 1065617360 11.510 100 52 103 0 1127522480 50754080 11151040
08:09:27 21381 8192000000 9968141648 10370707456 1067189088 11.525 100 39 76 1 1110162544 33953584 9019872
08:10:52 6412 8192000000 9997165984 10384633856 1072896368 11.580 100 35 70 5 1116172528 35744624 7531536
08:12:03 6935 8192000000 9990082000 10399559680 1083333488 11.679 100 35 71 2 1112162896 20272480 8556928
08:13:14 15719 8192000000 10000886720 10411405312 1090849584 11.751 100 31 62 0 1125807104 28193008 6764512
08:14:30 14788 8192000000 10015818480 10407919616 1094605008 11.786 100 65 130 3 1118938096 10158560 14174528
08:15:45 5239 8192000000 10018268704 10419367936 1086776512 11.712 99 48 96 3 1123754064 26034160 10943392
08:16:51 5455 8192000000 10016143936 10408050688 1092016080 11.762 100 37 76 2 1132088176 32153024 7919072
08:17:58 28441 8192000000 10001229920 10384257024 1029200032 11.161 95 53 107 2 1058786544 17919696 11666816
08:19:31 5522 8192000000 9954912656 10342563840 1032587072 11.193 99 55 107 3 1064973008 20180464 12205472
08:20:40 6682 8192000000 9952087920 10334920704 1025690992 11.127 98 31 62 3 1053899136 20688256 7519888
08:21:49 7238 8192000000 9933820912 10325086208 990932624 10.791 96 23 46 1 1021590960 25822496 4835840
08:23:01 35272 8192000000 9953172576 10351063040 1032816416 11.196 99 16 32 0 1051471216 15203584 3451216
08:24:38 7641 8192000000 9960719920 10361643008 1058243328 11.440 100 40 77 3 1082808624 15320608 9244688
08:25:48 5698 8192000000 9999836992 10392444928 1072748704 11.578 100 47 92 2 1097323536 14233184 10341648
08:26:56 123819 8192000000 10011711056 10421661696 1087493968 11.719 100 44 87 5 1116434304 19696560 9243776
08:30:02 21588 8192000000 10092465296 10505031680 1115252192 11.982 100 57 111 2 1197349712 70282752 11814768
08:31:28 15849 8192000000 10121948448 10525884416 1132685936 12.147 100 40 79 0 1227373152 85233056 9454160
08:32:46 4996 8192000000 10184286960 10596646912 1148827616 12.298 100 57 114 0 1295760000 133943808 12988576
08:33:55 9681 8192000000 10206497024 10615103488 1161813408 12.420 100 60 116 3 1312668288 137700864 13154016
08:35:15 8639 8192000000 10227560752 10644434944 1164965488 12.450 100 47 92 2 1329942768 154187328 10789952
08:36:28 9367 8192000000 10243157744 10655379456 1176562352 12.558 100 57 113 0 1348758944 159798832 12397760
08:37:44 5952 8192000000 10324616656 10739855360 1178127776 12.573 100 60 118 1 1424339568 232424928 13786864
08:38:55 12874 8192000000 10338342464 10752663552 1178681680 12.578 100 61 122 0 1436209472 243389072 14138720
08:40:12 2828 8192000000 10322867136 10725765120 1171840576 12.514 99 82 161 0 1414068496 224437488 17790432
08:41:20 3212 8192000000 10339845296 10746392576 1164848752 12.449 100 85 169 2 1444120688 260341680 18930256
08:42:28 4911 8192000000 10383019488 10791096320 1155935104 12.365 100 77 151 0 1484396704 311067248 17394352
08:43:40 2383 8192000000 10355138288 10752569344 1129968768 12.121 99 89 177 1 1447967888 297575888 20423232
08:44:46 7700 8192000000 10307492384 10727034880 1131966272 12.140 100 44 88 0 1408958832 266002336 10990224
08:45:58 9808 8192000000 10308119264 10733731840 1106243392 11.897 99 34 68 0 1403855120 288975920 8635808
08:47:10 16506 8192000000 10356176208 10774454272 1123682256 12.062 99 31 62 1 1429272912 297368144 8222512
08:48:30 14846 8192000000 10312225280 10767278080 1133867152 12.158 100 36 72 3 1407056448 264247296 8942000
08:49:48 21664 8192000000 10304072432 10760347648 1136080512 12.179 100 28 56 0 1413944976 270616608 7247856
08:51:14 44678 8192000000 10241707504 10739290112 1126436496 12.088 100 21 42 0 1382506992 250642096 5428400
08:53:00 34683 8192000000 10107749584 10643902464 1090377104 11.746 98 18 36 2 1292914432 198088912 4448416
08:54:35 98909 8192000000 10061801152 10515591168 1112339888 11.955 100 33 66 3 1164994240 45132640 7521712
08:58:08 36753 8192000000 10069300032 10499919872 1105147248 11.886 98 50 98 1 1139586624 24714512 9724864
08:59:45 49955 8192000000 10090114192 10520465408 1111476064 11.946 98 13 26 0 1177104432 62864784 2763584
09:01:36 57017 8192000000 10176550832 10619867136 1131723232 12.138 100 23 46 3 1278544576 141717552 5103792
09:03:35 100443 8192000000 10075185200 10584444928 1119946944 12.026 99 20 40 2 1207212656 83161504 4104208
09:06:23 34618 8192000000 10079384032 10529107968 1143469888 12.248 100 16 32 0 1178770320 31670656 3629776
09:08:05 116617 8192000000 10097308736 10571059200 1148922976 12.299 100 12 24 0 1207962352 56631792 2407584
09:11:02 82425 8192000000 10124935648 10563158016 1140096864 12.216 99 15 30 0 1186920640 43654528 3169248
09:13:30 47159 8192000000 10160493920 10602573824 1141069280 12.226 99 26 50 0 1215028224 68211568 5747376
09:15:18 56518 8192000000 10091474608 10576637952 1106816544 11.902 96 26 52 1 1189542928 77042016 5684368
09:17:17 83422 8192000000 10071931872 10507825152 1083793616 11.684 94 18 36 0 1128684896 40775360 4115920
09:19:41 97558 8192000000 10075054720 10523934720 1122668832 12.052 98 26 52 1 1156979376 28943040 5367504
09:22:28 49927 8192000000 10068825056 10522468352 1147999360 12.291 100 6 12 0 1164787616 15720368 1067888
09:24:24 58096 8192000000 10063445824 10531561472 1116490656 11.994 97 11 22 1 1155185600 36456592 2238352
09:26:44 63106 8192000000 10045812752 10483142656 1078858752 11.637 95 17 34 1 1117112912 34881984 3372176
09:28:47 60172 8192000000 10092044112 10553106432 1116329872 11.992 98 29 58 3 1191032080 68949248 5752960
09:30:50 42780 8192000000 10116553968 10571014144 1096519488 11.805 98 33 65 3 1207529760 103581280 7428992
09:32:34 39691 8192000000 10060217376 10475077632 1055828272 11.417 95 37 74 0 1107181920 43206608 8147040
09:34:21 76239 8192000000 10042134672 10516115456 1109851008 11.931 100 15 30 0 1155309232 42481392 2976832
09:36:37 9086 8192000000 10097239728 10516275200 1123225776 12.057 100 54 108 1 1173320800 39480976 10614048
09:37:50 120867 8192000000 10064396800 10537975808 1126477072 12.088 100 15 30 0 1168093888 38170144 3446672
09:40:52 60992 8192000000 10073207232 10535866368 1102600640 11.862 98 6 12 1 1124011968 20168624 1242704
09:42:54 48838 8192000000 10054660032 10500231168 1092629328 11.768 99 22 44 1 1127957296 30650368 4677600
09:44:45 57943 8192000000 10103944560 10552541184 1109844624 11.931 99 25 50 0 1166489424 51401600 5243200
09:46:43 57070 8192000000 10074186032 10532323328 1113724832 11.968 99 21 42 0 1170680944 52484832 4471280
09:48:41 48022 8192000000 10088275616 10530230272 1124803936 12.072 100 24 48 1 1196657200 66425312 5427952
09:50:30 43124 8192000000 10085934960 10527645696 1121892384 12.045 99 24 48 0 1167947408 41207392 4847632
09:52:18 83698 8192000000 10073803360 10531983360 1072965312 11.580 95 20 40 1 1116180768 39015600 4199856
09:54:51 87875 8192000000 10063554784 10520002560 1115335888 11.983 99 24 48 0 1160423520 40441392 4646240
09:57:20 59955 8192000000 10112180128 10577604608 1125290048 12.077 100 10 20 0 1195212576 67909616 2012912
09:59:20 79374 8192000000 10091892928 10609729536 1123199472 12.057 99 12 24 0 1216429360 90540432 2689456
10:01:43 61579 8192000000 10136763376 10603233280 1140868528 12.224 99 24 48 0 1221194288 74331520 5994240
10:03:48 63001 8192000000 10139024128 10651246592 1136801408 12.185 99 19 38 1 1267057088 125830336 4425344
10:05:52 20941 8192000000 10078151696 10507632640 1104503504 11.880 97 41 82 1 1135601072 22738944 8358624
10:07:15 64522 8192000000 10061763248 10553839616 1080222208 11.650 96 24 48 1 1129558272 44512816 4823248
10:09:20 121487 8192000000 10076709216 10563665920 1105368064 11.889 98 9 18 0 1149611104 42852816 1390224
10:12:21 79122 8192000000 10078342496 10539610112 1111042144 11.942 98 21 42 0 1157497504 41927248 4528112
10:14:50 96521 8192000000 10069321616 10547281920 1096921888 11.808 97 5 10 0 1134772240 36821424 1028928
10:17:44 43853 8192000000 10099141840 10539782144 1098513440 11.824 98 27 54 1 1165524592 60314928 6696224
10:19:28 77992 8192000000 10147269840 10587058176 1105177632 11.887 98 19 38 0 1200492080 91189648 4124800
10:21:46 38259 8192000000 10197268416 10641235968 1138662896 12.203 100 39 76 1 1291146976 143960688 8523392
10:23:31 108884 8192000000 10074108272 10504601600 1058127584 11.439 94 14 28 1 1100669376 39605376 2936416
10:26:23 42370 8192000000 9270378576 9567690752 393506272 4.583 36 14 26 1 421133760 24667216 2960272

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 12-03-2021 10:27:53 to 12-03-2021 10:28:08.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 160 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 12-03-2021 10:28:09 to 12-03-2021 10:28:10.

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          249389
MON$OLDEST_ACTIVE               249390
MON$OLDEST_SNAPSHOT             249390
MON$NEXT_TRANSACTION            249390
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                       2096256
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             829
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2386 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2386 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2386 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.2386 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'

12.03.21 10:28:11. 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-03-12 10:28:14 No severe PSQL-related problems occured
12.03.21 10:28:15. End of report.