Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.12.2020 07:39
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 e0e7b46250ba4f9795ceb55e0face73f
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 2020-09-25 13:42
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.00
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 16825132 kB
MemAvailable 31546628 kB
Buffers 0 kB
Cached 14798548 kB
SwapCached 1964 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 2020-12-04 07:37:25
DTS_END                         2020-12-04 07:37:25.8180
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/58642
TRN_ID                          224522
ATT_ID                          621
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: 8749.08 24970 1049889
customer order: creation 163.34 369 19601
customer order: refuse 14.61 389 1753
order to supplier: creation 43.41 1345 5209
order to supplier: removal 3.11 126542 373
invoice (draft): creation 43.22 3494 5186
invoice (draft): removal 48.26 1014 5791
invoice accept: apply 40.87 18155 4904
invoice accept: cancel 43.88 1024 5265
customer reserve: creation 3762.75 178 451530
customer reserve: removal 4306.71 72 516805
realization accept: apply 80.40 192 9648
realization accept: cancel 76.77 128 9212
payment from customer: creation 56.16 103 6739
payment from customer: removal 5.56 94 667
payment to supplier: creation 46.78 124 5613
payment to supplier: removal 3.20 100 384
service: total inventory turnovers 3.63 3802 436
service: total monetary turnovers 5.58 703 670
service: aggregate perf. data 0.82 18691 98
service: refresh index statistics 0.04 322881 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 04-12-2020 07:39:24 to 04-12-2020 07:39:26.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2961
TEST_TIME 2 4261
TEST_TIME 3 4149
TEST_TIME 4 4191
TEST_TIME 5 4321
TEST_TIME 6 4425
TEST_TIME 7 4572
TEST_TIME 8 4692
TEST_TIME 9 4812
TEST_TIME 10 5222
TEST_TIME 11 5237
TEST_TIME 12 5531
TEST_TIME 13 5551
TEST_TIME 14 6391
TEST_TIME 15 6456
TEST_TIME 16 6742
TEST_TIME 17 6805
TEST_TIME 18 7437
TEST_TIME 19 7588
TEST_TIME 20 7539
TEST_TIME 21 7662
TEST_TIME 22 7775
TEST_TIME 23 8377
TEST_TIME 24 8287
TEST_TIME 25 8648
TEST_TIME 26 8880
TEST_TIME 27 9191
TEST_TIME 28 10259
TEST_TIME 29 10471
TEST_TIME 30 10276
TEST_TIME 31 10351
TEST_TIME 32 10399
TEST_TIME 33 10283
TEST_TIME 34 10157
TEST_TIME 35 10052
TEST_TIME 36 9942
TEST_TIME 37 9925
TEST_TIME 38 9805
TEST_TIME 39 9691
TEST_TIME 40 9574
TEST_TIME 41 9960
TEST_TIME 42 9864
TEST_TIME 43 9933
TEST_TIME 44 9931
TEST_TIME 45 9951
TEST_TIME 46 9875
TEST_TIME 47 9895
TEST_TIME 48 9983
TEST_TIME 49 9957
TEST_TIME 50 9946
TEST_TIME 51 9755
TEST_TIME 52 9575
TEST_TIME 53 9536
TEST_TIME 54 9405
TEST_TIME 55 9416
TEST_TIME 56 9350
TEST_TIME 57 9367
TEST_TIME 58 9413
TEST_TIME 59 9337
TEST_TIME 60 9279
TEST_TIME 61 9222
TEST_TIME 62 9281
TEST_TIME 63 9207
TEST_TIME 64 9290
TEST_TIME 65 9222
TEST_TIME 66 9244
TEST_TIME 67 9294
TEST_TIME 68 9276
TEST_TIME 69 9347
TEST_TIME 70 9337
TEST_TIME 71 9364
TEST_TIME 72 9386
TEST_TIME 73 9357
TEST_TIME 74 9319
TEST_TIME 75 9384
TEST_TIME 76 9496
TEST_TIME 77 9433
TEST_TIME 78 9316
TEST_TIME 79 9260
TEST_TIME 80 9191
TEST_TIME 81 9170
TEST_TIME 82 9121
TEST_TIME 83 9067
TEST_TIME 84 8998
TEST_TIME 85 8993
TEST_TIME 86 8972
TEST_TIME 87 8945
TEST_TIME 88 8938
TEST_TIME 89 9004
TEST_TIME 90 9002
TEST_TIME 91 8988
TEST_TIME 92 9008
TEST_TIME 93 9002
TEST_TIME 94 9000
TEST_TIME 95 9016
TEST_TIME 96 9004
TEST_TIME 97 9009
TEST_TIME 98 8935
TEST_TIME 99 8902
TEST_TIME 100 8854
TEST_TIME 101 8837
TEST_TIME 102 8883
TEST_TIME 103 8832
TEST_TIME 104 8874
TEST_TIME 105 8871
TEST_TIME 106 8884
TEST_TIME 107 8895
TEST_TIME 108 8876
TEST_TIME 109 8898
TEST_TIME 110 8913
TEST_TIME 111 8872
TEST_TIME 112 8960
TEST_TIME 113 9002
TEST_TIME 114 9043
TEST_TIME 115 9003
TEST_TIME 116 8933
TEST_TIME 117 8901
TEST_TIME 118 8845
TEST_TIME 119 8817
TEST_TIME 120 8752

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 04-12-2020 07:39:28 to 04-12-2020 07:39:31.

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 1467664 1467664 0 0.00 0 14460 7 0 0
doc_list_biud 1467660 1467660 0 0.00 0 33579 33 0 0
sp_add_invoice_to_stock 5039 4904 135 2.67 40 169177 18155 0 135
sp_cancel_adding_invoice 5265 5265 0 0.00 6 78066 1025 0 0
sp_cancel_client_order 1753 1753 0 0.00 4 18323 389 0 0
sp_cancel_customer_reserve 516805 516805 0 0.00 1 26822 73 0 0
sp_cancel_pay_from_customer 667 667 0 0.00 3 7389 95 0 0
sp_cancel_pay_to_supplier 384 384 0 0.00 3 5341 101 0 0
sp_cancel_supplier_invoice 5803 5791 12 0.20 2 18673 1014 0 12
sp_cancel_supplier_order 373 373 0 0.00 8 1199226 126542 0 0
sp_cancel_write_off 9212 9212 0 0.00 1 9368 128 0 0
sp_client_order 19601 19601 0 0.00 4 23356 370 0 0
sp_customer_reserve 451530 451530 0 0.00 0 58325 179 0 0
sp_fill_shopping_cart 29977 29977 0 0.00 1 13318 49 0 0
sp_fill_shopping_cart_clo_res 451524 451524 0 0.00 0 18097 14 0 0
sp_get_clo_for_invoice 4898 4898 0 0.00 0 43566 384 0 0
sp_kill_cost_storno 15528 15528 0 0.00 0 9131 69 0 0
sp_kill_qstorno_ret_qs2qd 1048986 1048986 0 0.00 0 26767 39 0 0
sp_kill_qty_storno 553739 553739 0 0.00 0 33578 85 0 0
sp_lock_selected_doc 20113 20113 0 0.00 0 10796 4 0 0
sp_make_cost_storno 26894 26894 0 0.00 0 14060 58 0 0
sp_multiply_rows_for_pdistr 26893 26893 0 0.00 0 7049 33 0 0
sp_multiply_rows_for_qdistr 19601 19601 0 0.00 0 23350 187 0 0
sp_pay_from_customer 6739 6739 0 0.00 4 11842 103 0 0
sp_pay_to_supplier 5613 5613 0 0.00 5 9686 125 0 0
sp_payment_common 12352 12352 0 0.00 1 7338 23 0 0
sp_qd_handle_on_cancel_clo 1753 1753 0 0.00 0 18313 369 0 0
sp_qd_handle_on_invoice_upd_sts 10157 10157 0 0.00 0 33578 420 0 0
sp_qd_handle_on_reserve_upd_sts 9648 9648 0 0.00 0 16304 58 0 0
sp_reserve_write_off 9648 9648 0 0.00 6 16356 192 0 0
sp_supplier_invoice 5186 5186 0 0.00 9 101444 3495 0 0
sp_supplier_order 5209 5209 0 0.00 5 59153 1345 0 0
srv_aggregate_perf_data 98 98 0 0.00 2144 201527 18692 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2026 176153 47207 0 0
srv_find_qd_qs_mism 1004011 1004011 0 0.00 0 10301 6 0 0
srv_make_invnt_saldo 436 436 0 0.00 54 124102 3802 0 0
srv_make_money_saldo 670 670 0 0.00 1 21295 703 0 0
srv_recalc_idx_stat 5 5 0 0.00 121460 509999 322882 0 0
t$perf-abend:sp_add_invoice_to_stock 135 135 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4904 4904 0 0.00 0 22548 178 0 0
t$perf-norm:sp_cancel_adding_invoice 414 414 0 0.00 0 21671 168 0 0
t$perf-norm:sp_cancel_client_order 1753 1753 0 0.00 0 2175 13 0 0
t$perf-norm:sp_cancel_customer_reserve 650 650 0 0.00 0 1077 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 667 667 0 0.00 0 1316 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 384 384 0 0.00 0 7190 31 0 0
t$perf-norm:sp_cancel_supplier_invoice 392 392 0 0.00 0 985 12 0 0
t$perf-norm:sp_cancel_supplier_order 373 373 0 0.00 0 23742 1780 0 0
t$perf-norm:sp_cancel_write_off 942 942 0 0.00 0 8190 16 0 0
t$perf-norm:sp_client_order 19601 19601 0 0.00 0 7075 20 0 0
t$perf-norm:sp_customer_reserve 919 919 0 0.00 0 5548 15 0 0
t$perf-norm:sp_pay_from_customer 6739 6739 0 0.00 0 10960 15 0 0
t$perf-norm:sp_pay_to_supplier 5613 5613 0 0.00 0 6056 11 0 0
t$perf-norm:sp_reserve_write_off 9648 9648 0 0.00 0 8463 13 0 0
t$perf-norm:sp_supplier_invoice 5186 5186 0 0.00 0 8138 20 0 0
t$perf-norm:sp_supplier_order 5209 5209 0 0.00 0 8397 18 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 1019 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 3307 54 0 0
t$perf-norm:srv_make_invnt_saldo 436 436 0 0.00 0 6799 29 0 0
t$perf-norm:srv_make_money_saldo 670 670 0 0.00 0 2239 15 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 5 2 0 0
v_add_invoice_to_stock 10052 9782 270 2.68 0 5398 15 0 270
v_all_customers 36014 36014 0 0.00 0 16 1 0 0
v_all_suppliers 29048 29048 0 0.00 0 22 1 0 0
v_all_wares 39192 39192 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 828 828 0 0.00 0 193 6 0 0
v_cancel_client_order 3506 3506 0 0.00 0 982 4 0 0
v_cancel_customer_prepayment 1334 1334 0 0.00 0 958 5 0 0
v_cancel_customer_reserve 1300 1300 0 0.00 0 412 3 0 0
v_cancel_payment_to_supplier 768 768 0 0.00 0 3093 9 0 0
v_cancel_supplier_invoice 808 784 24 2.97 0 590 13 0 24
v_cancel_supplier_order 746 746 0 0.00 0 1219 6 0 0
v_cancel_write_off 1884 1884 0 0.00 0 2000 15 0 0
v_max_id_clo_ord 5206 5206 0 0.00 0 2794 2 0 0
v_max_id_clo_res 919 919 0 0.00 0 1512 11 0 0
v_max_id_ord_sup 5174 5174 0 0.00 0 4316 5 0 0
v_max_non_paid_invoice 1469 1469 0 0.00 0 341 2 0 0
v_max_non_paid_realizn 3506 3506 0 0.00 0 4663 4 0 0
v_min_id_clo_ord 5206 5206 0 0.00 0 11360 14 0 0
v_min_id_clo_res 919 919 0 0.00 0 3068 13 0 0
v_min_id_ord_sup 5174 5174 0 0.00 0 5571 10 0 0
v_min_non_paid_invoice 5613 5613 0 0.00 0 6268 16 0 0
v_min_non_paid_realizn 6739 6739 0 0.00 0 7334 16 0 0
v_our_firm 9644 9644 0 0.00 0 22 1 0 0
v_reserve_write_off 19294 19294 0 0.00 0 5870 4 0 0
x_lock_depdocs_on_canc_invoice 10664 10664 0 0.00 0 12257 105 0 0
x_lock_depdocs_on_canc_sup_ord 373 373 0 0.00 0 5331 350 0 0
x_make_qstorno_1000_1200 5209 5209 0 0.00 2 59129 1251 0 0
x_make_qstorno_1200_2000 5186 5186 0 0.00 4 101434 3310 0 0
x_make_qstorno_2100_3300 451529 451529 0 0.00 0 58324 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-12-2020 07:39:32 to 04-12-2020 07:39:36.

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
05:07:29 4 8192000000 8796641392 8807174144 3168704 0.038 1 1 2 0 3266400 61840 35856
05:08:30 35871 8192000000 9727321232 10024947712 800556816 8.902 100 90 180 0 831091120 8182224 22352080
05:10:09 16438 8192000000 9759996272 10065305600 837164544 9.271 100 94 188 0 872210624 12795584 22250496
05:11:31 24860 8192000000 9806660208 10125336576 864759232 9.548 100 85 168 0 906411152 22051952 19599968
05:12:58 12917 8192000000 9861631184 10194862080 909611744 9.993 100 78 156 1 969744336 42841360 17291232
05:14:16 11654 8192000000 9906254112 10253328384 942775024 10.320 100 78 156 0 1014149696 54769808 16604864
05:15:29 5702 8192000000 9956928640 10306064384 978758160 10.672 100 84 168 1 1062061296 66096064 17207072
05:16:37 8252 8192000000 9974880624 10337669120 1007035776 10.947 100 72 143 2 1089387936 68560240 13791920
05:17:47 4068 8192000000 10021611344 10384519168 1035534128 11.222 100 88 175 1 1133025280 79348928 18142224
05:18:53 1881 8192000000 10026420400 10394849280 1059997200 11.456 100 87 173 1 1138018592 60486160 17535232
05:19:58 2133 8192000000 10013041936 10384007168 1065690208 11.511 100 85 170 0 1126958352 43588416 17679728
05:21:02 3704 8192000000 10015050720 10399780864 1068948368 11.542 100 51 104 4 1142752912 63041216 10763328
05:22:10 7309 8192000000 10008337296 10414698496 1075230464 11.602 100 35 71 3 1122531456 39664272 7636720
05:23:21 44333 8192000000 9994255760 10388922368 1081017888 11.657 100 52 104 2 1100532608 7538480 11976240
05:25:06 10174 8192000000 10002450992 10407354368 1083830048 11.684 100 32 64 0 1123712832 33325728 6557056
05:26:19 7719 8192000000 10019038944 10410602496 1090415328 11.747 100 46 92 1 1118524576 17616768 10492480
05:27:29 8794 8192000000 10012887360 10410434560 1091183376 11.754 100 30 60 1 1119235712 20626848 7425488
05:28:44 47426 8192000000 10005513152 10417672192 1057569552 11.433 97 23 46 0 1093477712 31150752 4757408
05:30:32 5951 8192000000 9972717632 10352312320 1043798480 11.301 100 25 49 2 1066732704 17594736 5339488
05:31:38 12717 8192000000 9926118096 10320560128 1001939232 10.897 97 20 38 1 1021910912 15575168 4396512
05:32:51 6458 8192000000 9942447872 10329853952 1022901872 11.100 100 39 78 2 1062523456 30818192 8803392
05:34:00 51785 8192000000 9944463440 10337132544 1031915008 11.187 99 27 54 0 1063937312 26402336 5619968
05:35:52 5456 8192000000 9972085648 10371420160 1050241168 11.363 100 46 91 0 1084593232 23317184 11034880
05:37:01 8331 8192000000 9980548608 10371026944 1072272208 11.574 100 28 56 0 1099974336 20514768 7187360
05:38:13 13301 8192000000 10035034160 10427797504 1091578656 11.758 100 56 109 1 1138115280 35691984 10844640
05:39:29 58940 8192000000 10072280880 10481750016 1112018592 11.952 100 56 111 1 1189873840 66219440 11635808
05:41:45 10665 8192000000 10105270752 10508464128 1122958496 12.055 100 51 103 2 1214270192 80209232 11102464
05:42:59 27047 8192000000 10141998720 10555609088 1136563872 12.183 100 35 70 1 1241045056 96518944 7962240
05:44:31 5967 8192000000 10192933184 10597085184 1156211824 12.368 100 66 130 1 1296197216 126495904 13489488
05:45:40 6357 8192000000 10223076368 10630242304 1160135376 12.405 100 62 122 1 1324884672 151182768 13566528
05:46:51 4138 8192000000 10274334704 10680102912 1172570112 12.521 100 64 127 5 1378481424 191998384 13912928
05:47:59 2024 8192000000 10317278416 10729062400 1187632224 12.661 100 66 132 2 1425534000 223402784 14498992
05:49:04 10505 8192000000 10348832720 10766303232 1192278352 12.705 100 66 129 2 1451261264 244778368 14204544
05:50:23 5765 8192000000 10390564496 10802401280 1179834384 12.589 99 70 139 2 1491332432 295967936 15530112
05:51:37 5454 8192000000 10388472816 10814189568 1194855568 12.729 100 73 145 1 1489302832 276614400 17832864
05:52:48 4132 8192000000 10378397120 10806677504 1202987184 12.804 100 52 103 1 1479775328 264577984 12210160
05:53:57 3063 8192000000 10400390576 10823716864 1204916768 12.822 100 68 136 0 1505741984 284596720 16228496
05:55:05 3569 8192000000 10392333776 10836557824 1183892400 12.626 99 68 136 1 1491717280 292162512 15662368
05:56:12 7942 8192000000 10401355600 10824482816 1197244992 12.751 100 63 126 1 1501253120 289554688 14453440
05:57:25 8764 8192000000 10440934448 10863636480 1188277168 12.667 99 48 96 1 1526086064 326337984 11470912
05:58:43 9710 8192000000 10459721440 10886524928 1195565600 12.735 99 56 111 2 1553887200 344634512 13687088
05:59:58 2816 8192000000 10512311232 10929799168 1200215200 12.778 100 69 138 2 1610363472 393400800 16747472
06:01:05 19674 8192000000 10449325696 10893545472 1182479184 12.613 100 40 78 1 1557401280 364312576 10609520
06:02:28 15990 8192000000 10461783568 10935967744 1166524496 12.464 100 37 74 1 1579855904 403258928 10072480
06:03:49 25234 8192000000 10402641664 10867253248 1137437920 12.191 99 28 56 0 1523823600 378022016 8363664
06:05:16 4453 8192000000 10288858192 10802757632 1137738912 12.194 99 41 81 1 1375756496 227820416 10197168
06:06:29 28635 8192000000 10183116208 10679574528 1157695888 12.382 100 26 54 0 1301640464 138286512 5658064
06:07:59 17715 8192000000 10174236288 10638200832 1130431296 12.125 99 24 47 2 1271221200 135644528 5145376
06:09:21 61866 8192000000 10073517136 10592862208 1111511728 11.947 98 15 30 0 1201464064 86864512 3087824
06:11:30 76741 8192000000 10050748720 10477285376 1090346176 11.746 96 34 64 1 1126685664 29815984 6523504
06:13:49 92909 8192000000 10109385872 10573185024 1099465024 11.833 96 21 42 0 1188667824 85053920 4148880
06:16:24 126864 8192000000 10124002208 10649931776 1100018336 11.838 97 18 36 0 1223122800 119170656 3933808
06:19:42 66479 8192000000 10062528912 10554392576 1090460960 11.747 97 7 14 1 1132215536 40140768 1613808
06:21:52 169828 8192000000 10066006528 10524082176 1040215200 11.267 92 13 26 1 1106204480 63351472 2637808
06:25:47 176153 8192000000 10037627760 10503823360 1084699008 11.692 96 13 25 2 1154362928 66474400 3189520
06:30:21 85879 8192000000 10113271280 10568986624 1124787008 12.072 99 30 59 1 1202576544 71209216 6580320
06:33:00 48511 8192000000 10103524400 10548219904 1133345056 12.153 100 13 26 0 1196074368 60010592 2718720
06:34:49 61887 8192000000 10101039344 10594914304 1140968880 12.225 100 21 41 0 1214191504 68794672 4427952
06:36:52 59046 8192000000 10141566176 10599165952 1149119712 12.301 100 12 24 0 1231702432 80136656 2446064
06:38:56 55293 8192000000 10089563696 10612125696 1133018272 12.150 99 3 6 0 1196892048 63151200 722576
06:40:52 75312 8192000000 10060511712 10510213120 1121631440 12.042 98 11 20 1 1152315744 28414848 2269456
06:43:08 41014 8192000000 10116185856 10575269888 1128313984 12.105 99 32 64 2 1207358048 72458592 6585472
06:44:52 55602 8192000000 10116066560 10572918784 1106522816 11.899 97 24 47 2 1159873104 48474544 4875744
06:46:48 64098 8192000000 10118162096 10588033024 1126829056 12.091 99 12 24 0 1186961760 57727168 2405536
06:48:53 75634 8192000000 10103813488 10571587584 1139869808 12.214 99 19 38 2 1195151616 51885264 3396544
06:51:13 26968 8192000000 10104418976 10535682048 1137135760 12.189 99 48 96 0 1181850624 34710416 10004448
06:52:41 120749 8192000000 10096016432 10620547072 1104602816 11.881 96 8 16 0 1174309472 68322848 1383808
06:55:42 78845 8192000000 10042777792 10492018688 1117257536 12.001 99 21 42 0 1150613744 28737856 4618352
06:58:24 44464 8192000000 10047962832 10520256512 1120122320 12.028 99 8 16 1 1163208016 41241104 1844592
07:00:11 55782 8192000000 10059004368 10551156736 1138265968 12.199 100 9 18 0 1164341296 24220144 1855184
07:02:59 34645 8192000000 10073757024 10521190400 1119819088 12.025 98 19 38 1 1146461120 22335488 4306544
07:04:40 68934 8192000000 10101310448 10539106304 1085268896 11.698 95 31 62 1 1154512976 62071232 7172848
07:06:53 46158 8192000000 10062023312 10509635584 1106159264 11.896 98 21 42 1 1147285824 36477792 4648768
07:08:46 48468 8192000000 10104611568 10565292032 1094833584 11.789 96 24 48 3 1159393072 59165312 5394176
07:10:36 27519 8192000000 10054491792 10504974336 1080068928 11.648 97 33 66 0 1122341296 35669088 6603280
07:12:13 33138 8192000000 10070354880 10501144576 1092349872 11.765 98 53 106 2 1159730704 57017664 10363168
07:13:48 9107 8192000000 10095002944 10512007168 1101777088 11.854 99 63 127 1 1163595568 49224768 12593712
07:14:59 90367 8192000000 10045276288 10498543616 1110592128 11.938 99 29 57 2 1144065136 27513808 5959200
07:17:33 58951 8192000000 10060339344 10512965632 1104732496 11.883 99 10 20 1 1142025920 35376816 1916608
07:19:34 85630 8192000000 10067521360 10549354496 1111056848 11.942 99 12 22 0 1170267360 57166816 2043696
07:22:08 46316 8192000000 10100347232 10555523072 1130547072 12.127 100 27 54 0 1175692784 39628992 5516720
07:24:04 60808 8192000000 10080956560 10545168384 1112713008 11.958 98 15 30 1 1156868672 40171552 3984112
07:26:06 61855 8192000000 10109839856 10577620992 1128917648 12.111 99 13 26 0 1188400400 56030432 3452320
07:28:08 30570 8192000000 10145929232 10589687808 1144155616 12.255 100 34 68 0 1224173888 72471728 7546544
07:29:49 69503 8192000000 10081002480 10533429248 1107769456 11.911 97 15 29 0 1132852912 22131056 2952400
07:32:08 101259 8192000000 10080307440 10542010368 1138494128 12.201 100 15 30 2 1180355056 38636080 3224848
07:34:57 78955 8192000000 10070791072 10523828224 1115965504 11.989 98 7 14 0 1163311664 45724736 1621424

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 04-12-2020 07:39:38 to 04-12-2020 07:39:52.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 135 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-12-2020 07:39:53 to 04-12-2020 07:39:55.

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          222984
MON$OLDEST_ACTIVE               224650
MON$OLDEST_SNAPSHOT             224650
MON$NEXT_TRANSACTION            224650
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                       2108800
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                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             778
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2288 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2288 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2288 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.2288 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Fri Dec  4 07:37:28 2020
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Fri Dec  4 07:37:37 2020
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Fri Dec  4 07:37:38 2020
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
:18: 
:19: mscs-test-161.budzdorov.ru    Fri Dec  4 07:37:38 2020
:20:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:21: 
--- end of diff output ---

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

04.12.20 07:39:56. 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
2020-12-04 07:39:59 No severe PSQL-related problems occured
04.12.20 07:40:00. End of report.