Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.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 16572636 kB
MemAvailable 31579844 kB
Buffers 0 kB
Cached 15141988 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-28 07:46:45
DTS_END                         2021-11-28 07:46:45.9860
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/32986
TRN_ID                          231352
ATT_ID                          619
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: 9063.60 20480 1087632
customer order: creation 171.38 399 20566
customer order: refuse 14.32 336 1718
order to supplier: creation 45.30 1344 5436
order to supplier: removal 3.09 120708 371
invoice (draft): creation 45.55 3211 5466
invoice (draft): removal 49.31 887 5917
invoice accept: apply 42.71 20152 5125
invoice accept: cancel 44.73 921 5368
customer reserve: creation 3936.53 201 472384
customer reserve: removal 4425.80 68 531096
realization accept: apply 82.71 190 9925
realization accept: cancel 75.76 124 9091
payment from customer: creation 59.18 116 7102
payment from customer: removal 5.52 86 662
payment to supplier: creation 47.65 121 5718
payment to supplier: removal 3.23 65 388
service: total inventory turnovers 3.58 3490 429
service: total monetary turnovers 6.35 550 762
service: aggregate perf. data 0.87 15284 104
service: refresh index statistics 0.03 241344 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-11-2021 07:48:23 to 28-11-2021 07:48:25.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7459
TEST_TIME 2 6524
TEST_TIME 3 6816
TEST_TIME 4 6090
TEST_TIME 5 5879
TEST_TIME 6 5499
TEST_TIME 7 5855
TEST_TIME 8 5794
TEST_TIME 9 6013
TEST_TIME 10 6533
TEST_TIME 11 6968
TEST_TIME 12 6958
TEST_TIME 13 7048
TEST_TIME 14 7522
TEST_TIME 15 7751
TEST_TIME 16 8069
TEST_TIME 17 8276
TEST_TIME 18 8652
TEST_TIME 19 8797
TEST_TIME 20 8778
TEST_TIME 21 8983
TEST_TIME 22 9306
TEST_TIME 23 9658
TEST_TIME 24 9557
TEST_TIME 25 9724
TEST_TIME 26 10090
TEST_TIME 27 10040
TEST_TIME 28 10329
TEST_TIME 29 11212
TEST_TIME 30 11056
TEST_TIME 31 11004
TEST_TIME 32 10953
TEST_TIME 33 10899
TEST_TIME 34 10910
TEST_TIME 35 10828
TEST_TIME 36 10922
TEST_TIME 37 10810
TEST_TIME 38 10617
TEST_TIME 39 10509
TEST_TIME 40 10429
TEST_TIME 41 10356
TEST_TIME 42 10289
TEST_TIME 43 10200
TEST_TIME 44 10058
TEST_TIME 45 10014
TEST_TIME 46 10036
TEST_TIME 47 10051
TEST_TIME 48 10022
TEST_TIME 49 10019
TEST_TIME 50 10017
TEST_TIME 51 9967
TEST_TIME 52 10189
TEST_TIME 53 10122
TEST_TIME 54 10051
TEST_TIME 55 10043
TEST_TIME 56 10106
TEST_TIME 57 10088
TEST_TIME 58 10133
TEST_TIME 59 10111
TEST_TIME 60 10171
TEST_TIME 61 10212
TEST_TIME 62 10146
TEST_TIME 63 10074
TEST_TIME 64 9944
TEST_TIME 65 9911
TEST_TIME 66 9801
TEST_TIME 67 9750
TEST_TIME 68 9784
TEST_TIME 69 9751
TEST_TIME 70 9681
TEST_TIME 71 9611
TEST_TIME 72 9529
TEST_TIME 73 9635
TEST_TIME 74 9640
TEST_TIME 75 9668
TEST_TIME 76 9631
TEST_TIME 77 9582
TEST_TIME 78 9654
TEST_TIME 79 9598
TEST_TIME 80 9605
TEST_TIME 81 9618
TEST_TIME 82 9633
TEST_TIME 83 9653
TEST_TIME 84 9695
TEST_TIME 85 9663
TEST_TIME 86 9593
TEST_TIME 87 9523
TEST_TIME 88 9483
TEST_TIME 89 9429
TEST_TIME 90 9396
TEST_TIME 91 9360
TEST_TIME 92 9293
TEST_TIME 93 9257
TEST_TIME 94 9210
TEST_TIME 95 9191
TEST_TIME 96 9259
TEST_TIME 97 9287
TEST_TIME 98 9290
TEST_TIME 99 9255
TEST_TIME 100 9284
TEST_TIME 101 9251
TEST_TIME 102 9258
TEST_TIME 103 9275
TEST_TIME 104 9272
TEST_TIME 105 9325
TEST_TIME 106 9322
TEST_TIME 107 9298
TEST_TIME 108 9418
TEST_TIME 109 9341
TEST_TIME 110 9272
TEST_TIME 111 9229
TEST_TIME 112 9229
TEST_TIME 113 9187
TEST_TIME 114 9156
TEST_TIME 115 9096
TEST_TIME 116 9096
TEST_TIME 117 9113
TEST_TIME 118 9088
TEST_TIME 119 9095
TEST_TIME 120 9068

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 28-11-2021 07:48:26 to 28-11-2021 07:48:30.

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 1523381 1523381 0 0.00 0 18202 7 0 0
doc_list_biud 1523378 1523378 0 0.00 0 59523 30 0 0
sp_add_invoice_to_stock 5265 5125 140 2.65 37 188811 20152 0 140
sp_cancel_adding_invoice 5368 5368 0 0.00 4 81103 921 0 0
sp_cancel_client_order 1718 1718 0 0.00 3 14286 336 0 0
sp_cancel_customer_reserve 531096 531096 0 0.00 1 59525 69 0 0
sp_cancel_pay_from_customer 662 662 0 0.00 3 5468 87 0 0
sp_cancel_pay_to_supplier 388 388 0 0.00 3 2807 66 0 0
sp_cancel_supplier_invoice 5927 5917 10 0.16 2 17922 887 0 10
sp_cancel_supplier_order 371 371 0 0.00 9 976147 120709 0 0
sp_cancel_write_off 9091 9091 0 0.00 1 11894 124 0 0
sp_client_order 20566 20566 0 0.00 4 21035 400 0 0
sp_customer_reserve 472384 472384 0 0.00 0 96341 201 0 0
sp_fill_shopping_cart 31462 31462 0 0.00 1 30986 41 0 0
sp_fill_shopping_cart_clo_res 472377 472377 0 0.00 0 14007 16 0 0
sp_get_clo_for_invoice 5118 5118 0 0.00 0 41687 313 0 0
sp_kill_cost_storno 15509 15509 0 0.00 0 16481 59 0 0
sp_kill_qstorno_ret_qs2qd 1077571 1077571 0 0.00 0 59512 37 0 0
sp_kill_qty_storno 568602 568602 0 0.00 0 59523 79 0 0
sp_lock_selected_doc 20703 20703 0 0.00 0 9263 6 0 0
sp_make_cost_storno 27863 27863 0 0.00 0 17810 56 0 0
sp_multiply_rows_for_pdistr 27861 27861 0 0.00 0 7474 32 0 0
sp_multiply_rows_for_qdistr 20566 20566 0 0.00 0 20521 208 0 0
sp_pay_from_customer 7102 7102 0 0.00 4 12738 116 0 0
sp_pay_to_supplier 5718 5718 0 0.00 5 17901 122 0 0
sp_payment_common 12820 12820 0 0.00 1 12723 22 0 0
sp_qd_handle_on_cancel_clo 1718 1718 0 0.00 0 14269 305 0 0
sp_qd_handle_on_invoice_upd_sts 10484 10484 0 0.00 0 38798 338 0 0
sp_qd_handle_on_reserve_upd_sts 9925 9925 0 0.00 0 21488 62 0 0
sp_reserve_write_off 9925 9925 0 0.00 6 21612 191 0 0
sp_supplier_invoice 5466 5466 0 0.00 7 105576 3211 0 0
sp_supplier_order 5436 5436 0 0.00 5 18986 1345 0 0
srv_aggregate_perf_data 104 104 0 0.00 2809 170468 15285 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1950 136164 41201 0 0
srv_find_qd_qs_mism 1039060 1039060 0 0.00 0 18202 7 0 0
srv_make_invnt_saldo 429 429 0 0.00 76 106000 3490 0 0
srv_make_money_saldo 762 762 0 0.00 1 18108 550 0 0
srv_recalc_idx_stat 4 4 0 0.00 92602 438300 241344 0 0
t$perf-abend:sp_add_invoice_to_stock 140 140 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5125 5125 0 0.00 0 15971 157 0 0
t$perf-norm:sp_cancel_adding_invoice 428 428 0 0.00 0 4367 102 0 0
t$perf-norm:sp_cancel_client_order 1718 1718 0 0.00 0 5081 16 0 0
t$perf-norm:sp_cancel_customer_reserve 689 689 0 0.00 0 1681 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 662 662 0 0.00 0 1911 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 388 388 0 0.00 0 387 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 388 388 0 0.00 0 1681 20 0 0
t$perf-norm:sp_cancel_supplier_order 371 371 0 0.00 0 40963 1656 0 0
t$perf-norm:sp_cancel_write_off 1019 1019 0 0.00 0 1329 8 0 0
t$perf-norm:sp_client_order 20566 20566 0 0.00 0 20442 21 0 0
t$perf-norm:sp_customer_reserve 964 964 0 0.00 0 1460 4 0 0
t$perf-norm:sp_pay_from_customer 7102 7102 0 0.00 0 8128 14 0 0
t$perf-norm:sp_pay_to_supplier 5718 5718 0 0.00 0 7582 11 0 0
t$perf-norm:sp_reserve_write_off 9925 9925 0 0.00 0 6688 9 0 0
t$perf-norm:sp_supplier_invoice 5466 5466 0 0.00 0 16919 22 0 0
t$perf-norm:sp_supplier_order 5436 5436 0 0.00 0 5347 14 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 21 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 98 3 0 0
t$perf-norm:srv_make_invnt_saldo 429 429 0 0.00 0 2097 15 0 0
t$perf-norm:srv_make_money_saldo 762 762 0 0.00 0 2281 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 121 33 0 0
v_add_invoice_to_stock 10510 10230 280 2.66 0 3977 15 0 280
v_all_customers 37308 37308 0 0.00 0 305 1 0 0
v_all_suppliers 29940 29940 0 0.00 0 21 1 0 0
v_all_wares 41132 41132 0 0.00 0 272 1 0 0
v_cancel_adding_invoice 856 856 0 0.00 0 1154 7 0 0
v_cancel_client_order 3436 3436 0 0.00 0 2797 6 0 0
v_cancel_customer_prepayment 1324 1324 0 0.00 0 4528 10 0 0
v_cancel_customer_reserve 1378 1378 0 0.00 0 2358 6 0 0
v_cancel_payment_to_supplier 776 776 0 0.00 0 394 4 0 0
v_cancel_supplier_invoice 796 776 20 2.51 0 4530 22 0 20
v_cancel_supplier_order 742 742 0 0.00 0 4608 11 0 0
v_cancel_write_off 2038 2038 0 0.00 0 2674 15 0 0
v_max_id_clo_ord 5434 5434 0 0.00 0 1558 2 0 0
v_max_id_clo_res 964 964 0 0.00 0 2150 15 0 0
v_max_id_ord_sup 5460 5460 0 0.00 0 4928 5 0 0
v_max_non_paid_invoice 1642 1642 0 0.00 0 86 2 0 0
v_max_non_paid_realizn 3840 3840 0 0.00 0 1378 4 0 0
v_min_id_clo_ord 5434 5434 0 0.00 0 2487 5 0 0
v_min_id_clo_res 964 964 0 0.00 0 2910 6 0 0
v_min_id_ord_sup 5460 5460 0 0.00 0 8846 7 0 0
v_min_non_paid_invoice 5718 5718 0 0.00 0 10617 12 0 0
v_min_non_paid_realizn 7102 7102 0 0.00 0 12719 17 0 0
v_our_firm 10348 10348 0 0.00 0 19 1 0 0
v_reserve_write_off 19850 19850 0 0.00 0 4428 6 0 0
x_lock_depdocs_on_canc_invoice 10897 10897 0 0.00 0 17007 120 0 0
x_lock_depdocs_on_canc_sup_ord 371 371 0 0.00 0 18079 374 0 0
x_make_qstorno_1000_1200 5436 5436 0 0.00 2 18978 1263 0 0
x_make_qstorno_1200_2000 5466 5466 0 0.00 3 99738 3059 0 0
x_make_qstorno_2100_3300 472384 472384 0 0.00 0 96338 183 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-11-2021 07:48:31 to 28-11-2021 07:48:35.

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:49 3 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:17:50 31806 8192000000 9732899024 10028941312 806355536 8.961 100 92 184 0 836625184 8369456 21900192
05:19:26 26696 8192000000 9765535904 10072416256 836983648 9.269 100 85 170 0 870414544 13459504 19971392
05:20:56 4736 8192000000 9803071040 10118971392 869729600 9.597 100 85 170 0 914077984 24941104 19407280
05:22:04 14188 8192000000 9868464992 10199744512 909639568 9.994 100 76 152 0 972644576 46790208 16214800
05:23:21 9428 8192000000 9927572288 10269315072 955446656 10.444 100 76 152 1 1032941264 61732144 15762464
05:24:32 5435 8192000000 9968673488 10324729856 997107648 10.850 100 75 148 0 1083420272 71505600 14807024
05:25:40 6246 8192000000 9986909968 10353053696 1017169168 11.045 100 68 134 2 1099544512 69423840 12951504
05:26:51 2239 8192000000 10009047008 10374066176 1039736544 11.262 100 79 158 1 1126609184 70368752 16503888
05:27:55 2180 8192000000 10034690384 10403848192 1052025568 11.380 100 90 176 2 1150971136 80952464 17993104
05:28:59 10770 8192000000 10018376976 10394959872 1056987232 11.428 100 76 152 0 1136758880 62947440 16824208
05:30:12 1950 8192000000 10017807600 10407669760 1066664448 11.520 100 55 111 0 1133623600 56325120 10634032
05:31:16 2398 8192000000 10021200336 10402451456 1071710272 11.568 100 73 148 3 1132824608 45020064 16094272
05:32:19 50916 8192000000 9998229072 10392051712 1081852784 11.665 100 34 66 1 1100246720 10963520 7430416
05:34:10 5864 8192000000 9998657936 10396909568 1089496272 11.738 100 57 114 1 1127127216 25854160 11776784
05:35:21 6110 8192000000 10001046336 10394497024 1087712864 11.721 100 28 54 1 1114475680 20510480 6252336
05:36:29 6833 8192000000 9998757280 10406780928 1078887440 11.637 99 47 94 2 1099355040 9786448 10681152
05:37:38 10627 8192000000 9993310336 10404593664 1067023744 11.524 98 23 46 1 1102785632 30847408 4914480
05:38:53 3309 8192000000 9977204256 10352533504 1034864704 11.215 97 70 139 0 1057751504 6508480 16378320
05:39:57 5494 8192000000 9936399088 10304647168 985681360 10.739 95 42 85 0 1017123504 22013184 9428960
05:41:05 34767 8192000000 9923196352 10289897472 943630320 10.329 92 40 81 0 966371040 14417120 8323600
05:42:40 8621 8192000000 9957753200 10345517056 1010933888 10.984 98 36 72 0 1041423248 23128816 7360544
05:43:53 9985 8192000000 9969518128 10373824512 1052159504 11.381 100 53 106 1 1080744064 16546224 12038336
05:45:05 27076 8192000000 9959596592 10362671104 1058816368 11.445 100 26 52 0 1090965392 26247184 5901840
05:47:03 18954 8192000000 10009078896 10399973376 1082421360 11.671 100 39 75 1 1116679344 26817696 7440288
05:48:25 3812 8192000000 10107001696 10495356928 1109462288 11.927 100 68 136 0 1212536064 89451232 13622544
05:49:34 43131 8192000000 10094219824 10496323584 1123204656 12.057 100 47 95 0 1214676816 81041584 10430576
05:51:20 18490 8192000000 10134400672 10540015616 1135760560 12.176 100 54 106 1 1243551584 95722832 12068192
05:52:47 15794 8192000000 10190012032 10607296512 1149058320 12.301 100 56 108 0 1303463680 142280112 12125248
05:54:14 13791 8192000000 10224033552 10632671232 1158357984 12.388 100 53 106 1 1332617504 162772576 11486944
05:55:34 5931 8192000000 10282348352 10692739072 1166961760 12.468 100 58 111 0 1388876736 209932400 11982576
05:56:47 7332 8192000000 10281328368 10700546048 1171595536 12.512 100 76 150 3 1387399536 197958656 17845344
05:57:56 7898 8192000000 10303711728 10713284608 1173992352 12.534 100 69 137 1 1404658576 214854448 15811776
05:59:14 3590 8192000000 10305344736 10722217984 1172898496 12.524 100 66 132 1 1412276880 224748784 14629600
06:00:21 2280 8192000000 10306273552 10718425088 1179478800 12.585 100 66 131 0 1405150048 210915184 14756064
06:01:28 8678 8192000000 10287360640 10708156416 1148859472 12.299 98 54 106 1 1389782016 228941584 11980960
06:02:43 13910 8192000000 10278856048 10703007744 1160370944 12.407 99 34 68 4 1384881552 216453776 8056832
06:04:07 4335 8192000000 10249422400 10676457472 1137872672 12.196 98 46 93 0 1348681280 199648560 11160048
06:05:22 3539 8192000000 10262605072 10675007488 1129813648 12.120 100 43 86 1 1369439072 229816720 9808704
06:06:30 13641 8192000000 10293275584 10707189760 1128724688 12.109 100 41 78 2 1385630896 247359584 9546624
06:07:51 1950 8192000000 10311980160 10728951808 1120530064 12.032 100 43 86 0 1419065712 287839920 10695728
06:09:18 12489 8192000000 10309555520 10760802304 1137924512 12.196 100 36 72 2 1426862368 279506560 9431296
06:10:34 9130 8192000000 10328281008 10752430080 1141090944 12.226 100 35 70 2 1431580848 282220688 8269216
06:11:51 15631 8192000000 10344988560 10815737856 1116925472 11.998 99 25 50 0 1430037712 306750960 6361280
06:13:09 41137 8192000000 10242116208 10674147328 1100646896 11.844 98 26 53 0 1337397424 230088928 6661600
06:14:52 14555 8192000000 10143978144 10698514432 1113038608 11.961 100 17 34 1 1294027712 176493360 4495744
06:16:15 39707 8192000000 10072485344 10518265856 1110332640 11.936 99 20 39 1 1132858320 18661360 3864320
06:18:13 53636 8192000000 10079222336 10566242304 1118785680 12.016 99 17 34 0 1184865392 62559168 3520544
06:20:11 51949 8192000000 10070022304 10555506688 1065408480 11.508 94 12 24 1 1122916704 55115952 2392272
06:22:04 44474 8192000000 10059640512 10513248256 1115576864 11.985 99 19 38 0 1159683920 40237376 3869680
06:23:49 136164 8192000000 10088678768 10511347712 1125539920 12.079 99 36 72 0 1173201792 40316976 7344896
06:27:19 63242 8192000000 10057296176 10488946688 1111906512 11.950 98 22 43 0 1145282768 28864992 4511264
06:29:24 27030 8192000000 10147127680 10592391168 1153196480 12.339 100 21 42 0 1233993776 76101024 4696272
06:30:52 60845 8192000000 10233757232 10684813312 1154392144 12.351 98 59 118 1 1320375680 152888544 13094992
06:32:55 90096 8192000000 10200871184 10671136768 1150825360 12.317 99 20 40 0 1289781120 134209936 4745824
06:35:26 61689 8192000000 10162476336 10662899712 1133799296 12.157 98 23 46 1 1279208544 140030400 5378848
06:37:35 69074 8192000000 10103744848 10661027840 1151686864 12.325 100 10 20 1 1274327392 120191216 2449312
06:39:44 23917 8192000000 10087136768 10532052992 1144991616 12.262 100 42 83 2 1184510304 31184032 8334656
06:41:12 29034 8192000000 10126034080 10589421568 1144724032 12.260 100 50 100 1 1217067984 61874240 10469712
06:42:42 79747 8192000000 10128550608 10600505344 1132237712 12.142 99 15 30 0 1213192176 77636400 3318064
06:45:08 57863 8192000000 10124728112 10584231936 1121716864 12.043 98 29 58 1 1203714944 76278896 5719184
06:47:10 52330 8192000000 10064296432 10560876544 1118579776 12.014 98 8 16 0 1164282048 44398048 1304224
06:49:03 13275 8192000000 10085095920 10508398592 1112077824 11.952 98 38 76 1 1149271840 29828704 7365312
06:50:20 56014 8192000000 10097067040 10535800832 1109288272 11.926 100 51 102 1 1189778112 70073040 10416800
06:52:21 43008 8192000000 10081730016 10519851008 1098600640 11.824 98 35 70 0 1143693088 38148640 6943808
06:54:04 70163 8192000000 10096191344 10586537984 1130655664 12.128 100 16 32 2 1213952352 79856080 3440608
06:56:14 41016 8192000000 10085920336 10520981504 1074076528 11.591 95 22 45 1 1146513040 66843424 5593088
06:57:56 63511 8192000000 10140444560 10592129024 1126041808 12.084 100 24 47 2 1238765040 107325056 5398176
07:01:16 39309 8192000000 10060196608 10511503360 1105917824 11.894 98 14 26 2 1167026576 57815312 3293440
07:03:02 61217 8192000000 10074849312 10513375232 1061003904 11.466 94 27 54 0 1112231792 45929520 5298368
07:05:04 31114 8192000000 10052723808 10474123264 1079948992 11.647 97 28 56 1 1124057552 38327136 5781424
07:06:37 55638 8192000000 10065459680 10522116096 1113114624 11.962 100 28 56 0 1157001056 38657280 5229152
07:08:36 52483 8192000000 10105643968 10564542464 1112167008 11.953 99 40 80 1 1174805248 54868688 7769552
07:10:32 122059 8192000000 10068170944 10522357760 1070864864 11.560 95 21 42 1 1119965488 44679696 4420928
07:14:14 39246 8192000000 10075258752 10522595328 1125351808 12.078 100 37 74 0 1181015728 47777008 7886912
07:16:55 48818 8192000000 10037338784 10533462016 1122163152 12.047 100 5 10 1 1154136816 30806608 1167056
07:18:46 84180 8192000000 10088094576 10542993408 1110652672 11.939 98 20 40 2 1151754624 36854608 4247344
07:21:17 80778 8192000000 10120752864 10613710848 1131101312 12.132 99 24 48 2 1232252352 95756192 5394848
07:23:44 24408 8192000000 10068375808 10504110080 1113823952 11.969 98 27 54 0 1149639856 30489648 5326256
07:25:12 33840 8192000000 10111919632 10550738944 1138270032 12.199 100 34 69 0 1199990592 54659280 7061280
07:26:48 43050 8192000000 10098760832 10551115776 1144316416 12.256 100 27 54 0 1186945600 37289040 5340144
07:28:32 54817 8192000000 10111144256 10606567424 1139088544 12.207 99 20 40 0 1216635184 72842352 4704288
07:30:31 75355 8192000000 10111253760 10628808704 1130301504 12.124 99 15 30 0 1214621136 81190272 3129360
07:33:06 29761 8192000000 10141170256 10572840960 1116893104 11.998 99 40 77 0 1182647808 58091200 7663504
07:34:45 99419 8192000000 10035923280 10485571584 1093937216 11.780 97 13 26 0 1129535504 32797984 2800304
07:37:35 21541 8192000000 10081729664 10511110144 1116964304 11.998 99 56 112 1 1165048768 36482688 11601776
07:39:10 67413 8192000000 10041150352 10507173888 1077520624 11.624 96 7 14 0 1132351008 53160080 1670304
07:41:20 41996 8192000000 10087590224 10540707840 1105883840 11.893 99 16 32 0 1174200080 64773600 3542640
07:43:18 47587 8192000000 10071908048 10542452736 1093927616 11.780 98 11 22 1 1134566608 38270720 2368272
07:45:07 57393 8192000000 10128045040 10602733568 1109301664 11.926 98 22 44 0 1192057296 77838576 4917056

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 140 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-11-2021 07:48:53 to 28-11-2021 07:48: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          227754
MON$OLDEST_ACTIVE               231475
MON$OLDEST_SNAPSHOT             231475
MON$NEXT_TRANSACTION            231475
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                       2063200
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             794
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2672 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2672 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2672 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2672 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    Sun Nov 28 07:47:02 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Sun Nov 28 07:47:02 2021
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
--- end of diff output ---

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

28.11.21 07:48: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
2021-11-28 07:48:58 No severe PSQL-related problems occured
28.11.21 07:48:59. End of report.