Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.11.2021 07:47
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 2a7b53d1c5ed44b9bb2712fffac5d8c6
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-11-11 18:28
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.33
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 16640980 kB
MemAvailable 31639968 kB
Buffers 0 kB
Cached 15068840 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.1
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-11-17 07:46:22
DTS_END                         2021-11-17 07:46:22.3250
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/58280
TRN_ID                          228959
ATT_ID                          635
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: 8742.14 24928 1049057
customer order: creation 161.13 454 19336
customer order: refuse 13.75 351 1650
order to supplier: creation 41.48 1594 4977
order to supplier: removal 3.30 121564 396
invoice (draft): creation 42.83 3824 5139
invoice (draft): removal 51.45 952 6174
invoice accept: apply 38.86 19141 4663
invoice accept: cancel 45.76 844 5491
customer reserve: creation 3592.14 187 431057
customer reserve: removal 4478.01 70 537361
realization accept: apply 77.88 224 9346
realization accept: cancel 75.62 127 9074
payment from customer: creation 55.78 136 6694
payment from customer: removal 5.24 100 629
payment to supplier: creation 45.43 165 5451
payment to supplier: removal 3.13 97 375
service: total inventory turnovers 3.58 3738 429
service: total monetary turnovers 5.90 539 708
service: aggregate perf. data 0.85 17926 102
service: refresh index statistics 0.04 326523 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-11-2021 07:47:46 to 17-11-2021 07:47:48.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6395
TEST_TIME 2 4498
TEST_TIME 3 3683
TEST_TIME 4 4472
TEST_TIME 5 4761
TEST_TIME 6 4460
TEST_TIME 7 4474
TEST_TIME 8 4483
TEST_TIME 9 4836
TEST_TIME 10 5367
TEST_TIME 11 5445
TEST_TIME 12 5734
TEST_TIME 13 6282
TEST_TIME 14 6346
TEST_TIME 15 6344
TEST_TIME 16 6412
TEST_TIME 17 6604
TEST_TIME 18 6959
TEST_TIME 19 7381
TEST_TIME 20 7690
TEST_TIME 21 8129
TEST_TIME 22 8524
TEST_TIME 23 8634
TEST_TIME 24 8703
TEST_TIME 25 8893
TEST_TIME 26 8982
TEST_TIME 27 9383
TEST_TIME 28 9422
TEST_TIME 29 9614
TEST_TIME 30 9858
TEST_TIME 31 9988
TEST_TIME 32 10784
TEST_TIME 33 10765
TEST_TIME 34 10523
TEST_TIME 35 10405
TEST_TIME 36 10150
TEST_TIME 37 10154
TEST_TIME 38 10068
TEST_TIME 39 9910
TEST_TIME 40 9769
TEST_TIME 41 9621
TEST_TIME 42 9684
TEST_TIME 43 9726
TEST_TIME 44 9646
TEST_TIME 45 9640
TEST_TIME 46 9554
TEST_TIME 47 9573
TEST_TIME 48 9436
TEST_TIME 49 9407
TEST_TIME 50 9275
TEST_TIME 51 9281
TEST_TIME 52 9252
TEST_TIME 53 9213
TEST_TIME 54 9112
TEST_TIME 55 8994
TEST_TIME 56 8950
TEST_TIME 57 9025
TEST_TIME 58 9028
TEST_TIME 59 9044
TEST_TIME 60 9004
TEST_TIME 61 9013
TEST_TIME 62 8991
TEST_TIME 63 9104
TEST_TIME 64 9085
TEST_TIME 65 9144
TEST_TIME 66 9178
TEST_TIME 67 9125
TEST_TIME 68 9240
TEST_TIME 69 9225
TEST_TIME 70 9269
TEST_TIME 71 9361
TEST_TIME 72 9356
TEST_TIME 73 9245
TEST_TIME 74 9212
TEST_TIME 75 9175
TEST_TIME 76 9183
TEST_TIME 77 9118
TEST_TIME 78 9085
TEST_TIME 79 8990
TEST_TIME 80 9009
TEST_TIME 81 8975
TEST_TIME 82 8936
TEST_TIME 83 8965
TEST_TIME 84 8986
TEST_TIME 85 8961
TEST_TIME 86 8998
TEST_TIME 87 9035
TEST_TIME 88 8984
TEST_TIME 89 9032
TEST_TIME 90 9072
TEST_TIME 91 9078
TEST_TIME 92 9146
TEST_TIME 93 9093
TEST_TIME 94 9007
TEST_TIME 95 8946
TEST_TIME 96 8958
TEST_TIME 97 8951
TEST_TIME 98 8898
TEST_TIME 99 8863
TEST_TIME 100 8844
TEST_TIME 101 8886
TEST_TIME 102 8870
TEST_TIME 103 8848
TEST_TIME 104 8830
TEST_TIME 105 8972
TEST_TIME 106 8985
TEST_TIME 107 8974
TEST_TIME 108 9034
TEST_TIME 109 9019
TEST_TIME 110 8974
TEST_TIME 111 8919
TEST_TIME 112 8871
TEST_TIME 113 8847
TEST_TIME 114 8841
TEST_TIME 115 8827
TEST_TIME 116 8807
TEST_TIME 117 8787
TEST_TIME 118 8803
TEST_TIME 119 8804
TEST_TIME 120 8744

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-11-2021 07:47:50 to 17-11-2021 07:47:53.

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 1447111 1447111 0 0.00 0 42214 7 0 0
doc_list_biud 1447111 1447111 0 0.00 0 45782 33 0 0
sp_add_invoice_to_stock 4766 4663 103 2.16 46 229739 19141 0 103
sp_cancel_adding_invoice 5491 5491 0 0.00 5 78669 844 0 0
sp_cancel_client_order 1650 1650 0 0.00 4 12185 351 0 0
sp_cancel_customer_reserve 537361 537361 0 0.00 1 37459 71 0 0
sp_cancel_pay_from_customer 629 629 0 0.00 3 5492 101 0 0
sp_cancel_pay_to_supplier 375 375 0 0.00 3 5558 97 0 0
sp_cancel_supplier_invoice 6181 6174 7 0.11 2 22108 952 0 7
sp_cancel_supplier_order 396 396 0 0.00 9 1035625 121564 0 0
sp_cancel_write_off 9074 9074 0 0.00 1 15314 127 0 0
sp_client_order 19336 19336 0 0.00 4 47620 454 0 0
sp_customer_reserve 431057 431057 0 0.00 0 49828 187 0 0
sp_fill_shopping_cart 29434 29434 0 0.00 1 19904 51 0 0
sp_fill_shopping_cart_clo_res 431054 431054 0 0.00 0 17868 17 0 0
sp_get_clo_for_invoice 4660 4660 0 0.00 0 22909 386 0 0
sp_kill_cost_storno 15569 15569 0 0.00 0 30353 68 0 0
sp_kill_qstorno_ret_qs2qd 1090366 1090366 0 0.00 0 45779 38 0 0
sp_kill_qty_storno 574152 574152 0 0.00 0 45781 82 0 0
sp_lock_selected_doc 19408 19408 0 0.00 0 11751 4 0 0
sp_make_cost_storno 26151 26151 0 0.00 0 17514 77 0 0
sp_multiply_rows_for_pdistr 26151 26151 0 0.00 0 10437 40 0 0
sp_multiply_rows_for_qdistr 19336 19336 0 0.00 0 16562 241 0 0
sp_pay_from_customer 6694 6694 0 0.00 4 19733 137 0 0
sp_pay_to_supplier 5451 5451 0 0.00 4 24434 165 0 0
sp_payment_common 12145 12145 0 0.00 1 8491 26 0 0
sp_qd_handle_on_cancel_clo 1650 1650 0 0.00 0 12177 323 0 0
sp_qd_handle_on_invoice_upd_sts 10151 10151 0 0.00 0 35385 375 0 0
sp_qd_handle_on_reserve_upd_sts 9346 9346 0 0.00 0 25463 75 0 0
sp_reserve_write_off 9346 9346 0 0.00 7 31926 225 0 0
sp_supplier_invoice 5139 5139 0 0.00 7 79046 3824 0 0
sp_supplier_order 4977 4977 0 0.00 6 42231 1595 0 0
srv_aggregate_perf_data 102 102 0 0.00 2827 129642 17927 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1294 140440 47848 0 0
srv_find_qd_qs_mism 1004066 1004066 0 0.00 0 42213 6 0 0
srv_make_invnt_saldo 429 429 0 0.00 51 117891 3739 0 0
srv_make_money_saldo 708 708 0 0.00 1 12913 540 0 0
srv_recalc_idx_stat 5 5 0 0.00 92099 533887 326523 0 0
t$perf-abend:sp_add_invoice_to_stock 103 103 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4663 4663 0 0.00 0 14659 182 0 0
t$perf-norm:sp_cancel_adding_invoice 390 390 0 0.00 1 10517 174 0 0
t$perf-norm:sp_cancel_client_order 1650 1650 0 0.00 0 4005 15 0 0
t$perf-norm:sp_cancel_customer_reserve 674 674 0 0.00 0 2587 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 629 629 0 0.00 0 4882 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 375 375 0 0.00 0 644 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 361 361 0 0.00 0 451 12 0 0
t$perf-norm:sp_cancel_supplier_order 396 396 0 0.00 0 26799 1690 0 0
t$perf-norm:sp_cancel_write_off 927 927 0 0.00 0 8066 16 0 0
t$perf-norm:sp_client_order 19336 19336 0 0.00 0 11968 21 0 0
t$perf-norm:sp_customer_reserve 969 969 0 0.00 0 8182 16 0 0
t$perf-norm:sp_pay_from_customer 6694 6694 0 0.00 0 9944 12 0 0
t$perf-norm:sp_pay_to_supplier 5451 5451 0 0.00 0 10769 13 0 0
t$perf-norm:sp_reserve_write_off 9346 9346 0 0.00 0 16056 17 0 0
t$perf-norm:sp_supplier_invoice 5139 5139 0 0.00 0 8994 22 0 0
t$perf-norm:sp_supplier_order 4977 4977 0 0.00 0 5257 17 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 619 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 1737 31 0 0
t$perf-norm:srv_make_invnt_saldo 429 429 0 0.00 0 2781 20 0 0
t$perf-norm:srv_make_money_saldo 708 708 0 0.00 0 5649 23 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 8 4 0 0
v_add_invoice_to_stock 9526 9320 206 2.16 0 3945 15 0 206
v_all_customers 34728 34728 0 0.00 0 20 1 0 0
v_all_suppliers 28260 28260 0 0.00 0 20 1 0 0
v_all_wares 38670 38670 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 780 780 0 0.00 0 1431 9 0 0
v_cancel_client_order 3300 3300 0 0.00 0 5882 6 0 0
v_cancel_customer_prepayment 1258 1258 0 0.00 0 5487 10 0 0
v_cancel_customer_reserve 1348 1348 0 0.00 0 1013 5 0 0
v_cancel_payment_to_supplier 750 750 0 0.00 0 505 4 0 0
v_cancel_supplier_invoice 736 722 14 1.90 0 635 13 0 14
v_cancel_supplier_order 792 792 0 0.00 0 1234 7 0 0
v_cancel_write_off 1854 1854 0 0.00 0 2038 13 0 0
v_max_id_clo_ord 4975 4975 0 0.00 0 8100 3 0 0
v_max_id_clo_res 969 969 0 0.00 0 3540 20 0 0
v_max_id_ord_sup 5123 5123 0 0.00 0 6193 7 0 0
v_max_non_paid_invoice 1419 1419 0 0.00 0 70 2 0 0
v_max_non_paid_realizn 3769 3769 0 0.00 0 7142 5 0 0
v_min_id_clo_ord 4975 4975 0 0.00 0 1812 6 0 0
v_min_id_clo_res 969 969 0 0.00 0 8290 24 0 0
v_min_id_ord_sup 5123 5123 0 0.00 0 5648 14 0 0
v_min_non_paid_invoice 5451 5451 0 0.00 0 6041 17 0 0
v_min_non_paid_realizn 6694 6694 0 0.00 0 8460 22 0 0
v_our_firm 9792 9792 0 0.00 0 18 1 0 0
v_reserve_write_off 18692 18692 0 0.00 0 9107 6 0 0
x_lock_depdocs_on_canc_invoice 11304 11304 0 0.00 0 18252 126 0 0
x_lock_depdocs_on_canc_sup_ord 396 396 0 0.00 0 7803 394 0 0
x_make_qstorno_1000_1200 4977 4977 0 0.00 2 42222 1514 0 0
x_make_qstorno_1200_2000 5139 5139 0 0.00 3 72102 3616 0 0
x_make_qstorno_2100_3300 431057 431057 0 0.00 0 42733 168 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-11-2021 07:47:54 to 17-11-2021 07:47:58.

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:16:26 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:17:27 8602 8192000000 9710074176 10008891392 794365696 8.839 100 82 164 0 822470256 7395088 20709472
05:18:39 12989 8192000000 9772870752 10081431552 845172160 9.352 100 88 176 0 876619792 10695424 20752208
05:20:08 26259 8192000000 9802624688 10121474048 875728400 9.657 100 88 175 2 914813888 18917680 20167808
05:21:40 13141 8192000000 9864297552 10197487616 917424336 10.071 100 79 158 2 972213360 37436416 17352608
05:22:58 9237 8192000000 9925880576 10270023680 964193328 10.530 100 79 157 0 1030663344 49969104 16500912
05:24:10 5424 8192000000 9963037344 10318618624 998073888 10.860 100 75 151 1 1079210464 65191200 15945376
05:25:18 5368 8192000000 9986187472 10352230400 1025224208 11.122 100 75 148 1 1099429136 59186208 15018720
05:26:26 1941 8192000000 10009649344 10377256960 1053140608 11.391 100 81 163 0 1120034192 50761216 16132368
05:27:30 35643 8192000000 9996396304 10384150528 1069710496 11.549 100 47 93 1 1142413968 62624336 10079136
05:29:06 5476 8192000000 9987477024 10378575872 1073436608 11.585 100 39 78 1 1105908080 23956928 8514544
05:30:12 5981 8192000000 9998053232 10387025920 1079780544 11.645 100 33 66 1 1113796320 26200512 7815264
05:31:19 7579 8192000000 9999200448 10399973376 1085588848 11.701 100 27 54 2 1105116208 13861920 5665440
05:32:32 50854 8192000000 10014656240 10412974080 1097272960 11.812 100 57 114 1 1121041168 11191936 12576272
05:34:23 8423 8192000000 10028750080 10432229376 1102101456 11.858 100 43 86 0 1160537632 49372320 9063856
05:35:32 7560 8192000000 10015614112 10414735360 1085976992 11.704 99 30 61 1 1119945376 26963472 7004912
05:36:46 7240 8192000000 9986636448 10388148224 1043861184 11.302 97 37 74 1 1071882768 19804544 8217040
05:37:58 23339 8192000000 9980176608 10349965312 1008150752 10.957 94 57 115 1 1035865312 15095072 12619488
05:39:22 4941 8192000000 9961289360 10337267712 1026146080 11.131 99 44 89 0 1052627680 17307440 9174160
05:40:28 9116 8192000000 9944223504 10333192192 1030577344 11.174 100 31 63 0 1056802832 19089776 7135712
05:41:38 6661 8192000000 9945849424 10330394624 1035674384 11.223 99 40 78 1 1065548640 21297680 8576576
05:42:46 53433 8192000000 9967594880 10364428288 1058834880 11.445 100 23 45 1 1086593728 23052320 4706528
05:44:40 4341 8192000000 9996035824 10398502912 1071466496 11.566 100 54 106 1 1118450832 35347504 11636832
05:45:48 11621 8192000000 9991025328 10381299712 1075225328 11.602 100 50 95 1 1092534880 6335328 10974224
05:47:05 22467 8192000000 10040544608 10434969600 1104583072 11.881 100 40 80 0 1137667376 24834048 8250256
05:48:34 87203 8192000000 10103191056 10506489856 1122615328 12.052 100 51 100 2 1199424960 65597680 11211952
05:51:16 53041 8192000000 10119627152 10548158464 1142657936 12.241 100 35 69 2 1231478880 80684720 8136224
05:53:14 12183 8192000000 10181577520 10589315072 1155900608 12.365 100 56 112 0 1286763344 118823488 12039248
05:54:30 8766 8192000000 10233327232 10641469440 1159018208 12.394 100 50 100 0 1333054656 162471872 11564576
05:55:48 10099 8192000000 10242378416 10658836480 1170475344 12.501 100 51 102 1 1352899920 170707776 11716800
05:57:01 9233 8192000000 10248024352 10665943040 1169741264 12.494 100 48 96 1 1355208496 174286544 11180688
05:58:21 3439 8192000000 10295860208 10700709888 1179789584 12.588 100 70 138 1 1392859520 197736928 15333008
05:59:30 4016 8192000000 10326391152 10736406528 1189433936 12.678 100 73 147 0 1417259616 211853616 15972064
06:00:41 4201 8192000000 10303503408 10708701184 1183941440 12.627 100 75 149 2 1412233808 211908000 16384368
06:01:49 1857 8192000000 10337148688 10739843072 1173989424 12.534 100 84 169 1 1440256032 247159488 19107120
06:03:02 1288 8192000000 10349386160 10753564672 1161816416 12.420 100 73 143 0 1445741088 267504656 16420016
06:04:08 2484 8192000000 10399197040 10799677440 1166571600 12.465 100 91 180 0 1501753840 314479632 20702608
06:05:15 10887 8192000000 10347012880 10788597760 1144072928 12.254 99 43 86 0 1467661328 312482976 11105424
06:06:36 8340 8192000000 10353190112 10783801344 1139972448 12.215 100 40 80 1 1462396256 312176608 10247200
06:07:47 9570 8192000000 10292859248 10705596416 1098467968 11.823 98 36 72 1 1371318032 264085056 8765008
06:09:09 10315 8192000000 10274611904 10756861952 1127923664 12.102 100 24 48 1 1382195232 248496528 5775040
06:10:27 23762 8192000000 10298635152 10739826688 1139665568 12.212 100 31 63 2 1407213920 259591312 7957040
06:11:53 23015 8192000000 10313284912 10756112384 1138655264 12.203 100 26 52 1 1413958160 268872000 6430896
06:13:33 21618 8192000000 10282003136 10730086400 1146376160 12.275 100 32 64 2 1367275360 213193488 7705712
06:15:12 31060 8192000000 10265298752 10736857088 1138504752 12.201 99 17 34 0 1373763328 231003168 4255408
06:16:54 17462 8192000000 10280779168 10790678528 1151746144 12.326 100 20 40 2 1390028272 233427344 4854784
06:18:14 21780 8192000000 10213579904 10700992512 1138651776 12.203 99 14 26 1 1338773424 196971712 3149936
06:19:37 31694 8192000000 10095251232 10512990208 1126149648 12.085 97 48 90 1 1147023568 11626432 9247488
06:21:18 102710 8192000000 10112243760 10555346944 1137230688 12.189 99 32 64 1 1206841200 63292944 6317568
06:24:12 63376 8192000000 10109777568 10580402176 1155521504 12.361 100 23 45 0 1203399504 42892720 4985280
06:26:16 64636 8192000000 10156256608 10610102272 1148489328 12.295 99 16 32 0 1244431472 92247840 3694304
06:28:24 46916 8192000000 10140093168 10638815232 1140859920 12.224 100 12 24 0 1241155568 97376240 2919408
06:30:12 66741 8192000000 10118824608 10571460608 1119056496 12.018 97 20 38 3 1166581184 43464080 4060608
06:32:31 85423 8192000000 10082109712 10565644288 1145907424 12.271 100 26 52 1 1204320976 52578976 5834576
06:35:05 92952 8192000000 10097345200 10557636608 1139848016 12.214 99 17 34 0 1181530032 38358384 3323632
06:37:50 9310 8192000000 10089050880 10519728128 1144630752 12.259 99 57 107 4 1174190576 17194528 12365296
06:39:02 81676 8192000000 10110581936 10591072256 1160600208 12.409 100 13 25 1 1204418768 40885840 2932720
06:41:55 77587 8192000000 10110930080 10611191808 1164474784 12.445 100 25 50 1 1238973232 69240384 5258064
06:44:13 73339 8192000000 10083748672 10564456448 1135560848 12.174 97 18 36 0 1168412032 29254768 3596416
06:46:40 26245 8192000000 10091881488 10539819008 1155442256 12.361 99 36 71 1 1178167184 15532080 7192848
06:48:07 65651 8192000000 10116484496 10624663552 1128384992 12.106 97 34 68 0 1175770320 39768800 7616528
06:50:21 62656 8192000000 10101886208 10569572352 1089085584 11.734 95 19 38 2 1131151952 38439216 3627152
06:52:26 37418 8192000000 10061462240 10493976576 1074786240 11.598 96 30 60 0 1122235280 41098752 6350288
06:54:06 49115 8192000000 10087980288 10553769984 1112189616 11.953 99 33 66 0 1189294368 70548752 6556000
06:55:57 25600 8192000000 10099996848 10530750464 1074988736 11.600 97 37 73 1 1150984528 68366480 7629312
06:57:23 69504 8192000000 10046351392 10534092800 1036457744 11.231 94 9 18 0 1119613232 81196448 1959040
07:00:52 43204 8192000000 10041304288 10517852160 1094750480 11.788 99 16 32 0 1152389904 54397456 3241968
07:02:41 52394 8192000000 10013157520 10476019712 1064827488 11.503 97 11 22 0 1094277568 27267664 2182416
07:04:33 54401 8192000000 10060866288 10504228864 1094839312 11.789 99 12 24 0 1150369168 52533792 2996064
07:06:33 91482 8192000000 10052833232 10504937472 1088038944 11.724 98 15 30 0 1135529136 44539216 2950976
07:09:09 38142 8192000000 10083208720 10523189248 1113261072 11.963 99 25 50 1 1183480224 64613920 5605232
07:10:47 41219 8192000000 10095650080 10537451520 1130025232 12.122 100 30 60 2 1190482256 54440832 6016192
07:12:29 84258 8192000000 10064783744 10542657536 1094248272 11.783 97 27 54 0 1164098000 64546576 5303152
07:14:56 75172 8192000000 10097535488 10561343488 1114515936 11.975 99 42 83 1 1209620608 86663040 8441632
07:17:12 50614 8192000000 10103402000 10530349056 1136054432 12.178 100 24 48 0 1175522016 34897680 4569904
07:19:04 140439 8192000000 10077530672 10521317376 1075666448 11.606 95 19 38 0 1124297104 45112960 3517696
07:22:25 65101 8192000000 10108466704 10614800384 1131167024 12.132 100 16 32 0 1212292512 77488880 3636608
07:24:30 73239 8192000000 10123978736 10564239360 1125653072 12.080 99 21 41 2 1204925904 74995392 4277440
07:26:47 67584 8192000000 10155823792 10655547392 1135201392 12.170 99 27 54 0 1260783360 119312336 6269632
07:28:59 68130 8192000000 10187405280 10632945664 1146434288 12.276 99 27 54 1 1272353456 119764848 6154320
07:31:08 70529 8192000000 10106574064 10569789440 1138732272 12.204 99 34 67 1 1246002224 99802784 7467168
07:33:26 94665 8192000000 10088989776 10583281664 1124498384 12.069 98 11 22 0 1209738096 81972448 3267264
07:36:29 110225 8192000000 10087498304 10540900352 1108597248 11.919 96 41 81 1 1170424336 53263744 8563344
07:39:34 90840 8192000000 10103536400 10595102720 1119429888 12.022 98 13 26 0 1209755824 87563040 2762896
07:42:17 63150 8192000000 10106921856 10560761856 1140523568 12.220 100 20 39 0 1189528288 45044000 3960720
07:44:43 61020 8192000000 10089070992 10574340096 1101124896 11.848 97 7 14 0 1165328800 62709504 1494400

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 17-11-2021 07:48:00 to 17-11-2021 07:48:15.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 103 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 17-11-2021 07:48:16 to 17-11-2021 07:48:17.

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          226878
MON$OLDEST_ACTIVE               229130
MON$OLDEST_SNAPSHOT             229130
MON$NEXT_TRANSACTION            229130
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                       2060704
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             789
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

Database statistics

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

Database validation

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

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

--- start of diff output ---
:6: 
:7: mscs-test-161.budzdorov.ru    Wed Nov 17 07:46:26 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Wed Nov 17 07:46:29 2021
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Wed Nov 17 07:46:29 2021
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
--- end of diff output ---

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

17.11.21 07:48:18. 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-11-17 07:48:21 No severe PSQL-related problems occured
17.11.21 07:48:22. End of report.