Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.09.2021 16:44
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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 16254464 kB
MemAvailable 30852500 kB
Buffers 0 kB
Cached 15461456 kB
SwapCached 416 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 3.0.8
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-09-16 16:43:17
DTS_END                         2021-09-16 16:43:17.4900
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(57:17)
IP                              ::1/54872
TRN_ID                          233725
ATT_ID                          681
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: 9061.97 18799 1087436
customer order: creation 174.53 369 20943
customer order: refuse 14.60 376 1752
order to supplier: creation 46.88 1301 5626
order to supplier: removal 3.33 104394 400
invoice (draft): creation 46.46 3462 5575
invoice (draft): removal 50.91 837 6109
invoice accept: apply 41.80 18116 5016
invoice accept: cancel 45.38 889 5446
customer reserve: creation 3884.82 178 466178
customer reserve: removal 4460.79 61 535295
realization accept: apply 83.94 171 10073
realization accept: cancel 80.88 123 9705
payment from customer: creation 58.92 118 7070
payment from customer: removal 5.55 64 666
payment to supplier: creation 47.92 127 5750
payment to supplier: removal 3.41 82 409
service: total inventory turnovers 4.43 1202 532
service: total monetary turnovers 6.62 25 794
service: aggregate perf. data 0.78 23716 93
service: refresh index statistics 0.03 220368 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-09-2021 16:44:54 to 16-09-2021 16:44:56.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3741
TEST_TIME 2 3654
TEST_TIME 3 4898
TEST_TIME 4 4962
TEST_TIME 5 5182
TEST_TIME 6 5080
TEST_TIME 7 4965
TEST_TIME 8 5096
TEST_TIME 9 5528
TEST_TIME 10 5390
TEST_TIME 11 5802
TEST_TIME 12 5806
TEST_TIME 13 5750
TEST_TIME 14 6017
TEST_TIME 15 6131
TEST_TIME 16 6496
TEST_TIME 17 6694
TEST_TIME 18 6701
TEST_TIME 19 7083
TEST_TIME 20 7230
TEST_TIME 21 7309
TEST_TIME 22 8014
TEST_TIME 23 7971
TEST_TIME 24 8270
TEST_TIME 25 8348
TEST_TIME 26 8891
TEST_TIME 27 8975
TEST_TIME 28 9246
TEST_TIME 29 9754
TEST_TIME 30 10274
TEST_TIME 31 10150
TEST_TIME 32 9991
TEST_TIME 33 9770
TEST_TIME 34 9778
TEST_TIME 35 9704
TEST_TIME 36 9821
TEST_TIME 37 9712
TEST_TIME 38 9560
TEST_TIME 39 9487
TEST_TIME 40 9401
TEST_TIME 41 9361
TEST_TIME 42 9467
TEST_TIME 43 9602
TEST_TIME 44 9447
TEST_TIME 45 9438
TEST_TIME 46 9398
TEST_TIME 47 9315
TEST_TIME 48 9237
TEST_TIME 49 9219
TEST_TIME 50 9185
TEST_TIME 51 9179
TEST_TIME 52 9033
TEST_TIME 53 9082
TEST_TIME 54 9003
TEST_TIME 55 9084
TEST_TIME 56 9048
TEST_TIME 57 9101
TEST_TIME 58 9065
TEST_TIME 59 9041
TEST_TIME 60 9225
TEST_TIME 61 9243
TEST_TIME 62 9303
TEST_TIME 63 9273
TEST_TIME 64 9386
TEST_TIME 65 9330
TEST_TIME 66 9472
TEST_TIME 67 9522
TEST_TIME 68 9525
TEST_TIME 69 9473
TEST_TIME 70 9409
TEST_TIME 71 9363
TEST_TIME 72 9302
TEST_TIME 73 9241
TEST_TIME 74 9292
TEST_TIME 75 9346
TEST_TIME 76 9289
TEST_TIME 77 9279
TEST_TIME 78 9256
TEST_TIME 79 9156
TEST_TIME 80 9164
TEST_TIME 81 9167
TEST_TIME 82 9158
TEST_TIME 83 9166
TEST_TIME 84 9130
TEST_TIME 85 9180
TEST_TIME 86 9264
TEST_TIME 87 9296
TEST_TIME 88 9289
TEST_TIME 89 9290
TEST_TIME 90 9338
TEST_TIME 91 9359
TEST_TIME 92 9338
TEST_TIME 93 9409
TEST_TIME 94 9379
TEST_TIME 95 9288
TEST_TIME 96 9262
TEST_TIME 97 9223
TEST_TIME 98 9240
TEST_TIME 99 9190
TEST_TIME 100 9160
TEST_TIME 101 9179
TEST_TIME 102 9194
TEST_TIME 103 9142
TEST_TIME 104 9196
TEST_TIME 105 9258
TEST_TIME 106 9258
TEST_TIME 107 9230
TEST_TIME 108 9253
TEST_TIME 109 9226
TEST_TIME 110 9249
TEST_TIME 111 9297
TEST_TIME 112 9278
TEST_TIME 113 9278
TEST_TIME 114 9204
TEST_TIME 115 9178
TEST_TIME 116 9164
TEST_TIME 117 9180
TEST_TIME 118 9150
TEST_TIME 119 9122
TEST_TIME 120 9065

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-09-2021 16:44:58 to 16-09-2021 16:45:01.

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 1517770 1517770 0 0.00 0 36595 6 0 0
doc_list_biud 1517769 1517769 0 0.00 0 36438 28 0 0
sp_add_invoice_to_stock 5100 5016 84 1.64 30 186661 18116 0 84
sp_cancel_adding_invoice 5446 5446 0 0.00 5 98689 889 0 0
sp_cancel_client_order 1752 1752 0 0.00 4 15272 377 0 0
sp_cancel_customer_reserve 535295 535295 0 0.00 1 36440 62 0 0
sp_cancel_pay_from_customer 666 666 0 0.00 3 2688 65 0 0
sp_cancel_pay_to_supplier 409 409 0 0.00 2 3399 82 0 0
sp_cancel_supplier_invoice 6115 6109 6 0.09 1 14931 837 0 6
sp_cancel_supplier_order 400 400 0 0.00 6 1255450 104395 0 0
sp_cancel_write_off 9705 9705 0 0.00 1 17472 124 0 0
sp_client_order 20943 20943 0 0.00 3 69505 370 0 0
sp_customer_reserve 466178 466178 0 0.00 0 104505 178 0 0
sp_fill_shopping_cart 32139 32139 0 0.00 1 10348 39 0 0
sp_fill_shopping_cart_clo_res 466170 466170 0 0.00 0 16345 14 0 0
sp_get_clo_for_invoice 5008 5008 0 0.00 0 33450 292 0 0
sp_kill_cost_storno 16226 16226 0 0.00 0 35441 64 0 0
sp_kill_qstorno_ret_qs2qd 1086804 1086804 0 0.00 0 36436 34 0 0
sp_kill_qty_storno 573787 573787 0 0.00 0 36438 73 0 0
sp_lock_selected_doc 20842 20842 0 0.00 0 6623 5 0 0
sp_make_cost_storno 27901 27901 0 0.00 0 14916 53 0 0
sp_multiply_rows_for_pdistr 27901 27901 0 0.00 0 7827 30 0 0
sp_multiply_rows_for_qdistr 20943 20943 0 0.00 0 69486 152 0 0
sp_pay_from_customer 7070 7070 0 0.00 4 11785 118 0 0
sp_pay_to_supplier 5750 5750 0 0.00 4 11355 128 0 0
sp_payment_common 12820 12820 0 0.00 1 11758 37 0 0
sp_qd_handle_on_cancel_clo 1752 1752 0 0.00 0 15237 344 0 0
sp_qd_handle_on_invoice_upd_sts 10453 10453 0 0.00 0 14701 349 0 0
sp_qd_handle_on_reserve_upd_sts 10073 10073 0 0.00 0 12614 53 0 0
sp_reserve_write_off 10073 10073 0 0.00 6 15923 171 0 0
sp_supplier_invoice 5575 5575 0 0.00 6 86651 3463 0 0
sp_supplier_order 5626 5626 0 0.00 6 65412 1302 0 0
srv_aggregate_perf_data 93 93 0 0.00 2686 403640 23717 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1837 157358 48578 0 0
srv_find_qd_qs_mism 1038834 1038834 0 0.00 0 36594 6 0 0
srv_make_invnt_saldo 532 532 0 0.00 29 122337 1203 0 0
srv_make_money_saldo 794 794 0 0.00 1 960 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 127222 384152 220369 0 0
t$perf-abend:sp_add_invoice_to_stock 84 84 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5016 5016 0 0.00 0 13905 178 0 0
t$perf-norm:sp_cancel_adding_invoice 413 413 0 0.00 0 10047 120 0 0
t$perf-norm:sp_cancel_client_order 1752 1752 0 0.00 0 13214 27 0 0
t$perf-norm:sp_cancel_customer_reserve 720 720 0 0.00 0 14636 33 0 0
t$perf-norm:sp_cancel_pay_from_customer 666 666 0 0.00 0 3450 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 409 409 0 0.00 0 2066 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 390 390 0 0.00 0 837 14 0 0
t$perf-norm:sp_cancel_supplier_order 400 400 0 0.00 0 24811 1595 0 0
t$perf-norm:sp_cancel_write_off 1012 1012 0 0.00 0 2302 9 0 0
t$perf-norm:sp_client_order 20943 20943 0 0.00 0 14548 20 0 0
t$perf-norm:sp_customer_reserve 1024 1024 0 0.00 0 1500 8 0 0
t$perf-norm:sp_pay_from_customer 7070 7070 0 0.00 0 16939 14 0 0
t$perf-norm:sp_pay_to_supplier 5750 5750 0 0.00 0 8610 14 0 0
t$perf-norm:sp_reserve_write_off 10073 10073 0 0.00 0 8456 11 0 0
t$perf-norm:sp_supplier_invoice 5575 5575 0 0.00 0 8429 20 0 0
t$perf-norm:sp_supplier_order 5626 5626 0 0.00 0 3872 14 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 246 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 47 1 0 0
t$perf-norm:srv_make_invnt_saldo 532 532 0 0.00 0 1405 24 0 0
t$perf-norm:srv_make_money_saldo 794 794 0 0.00 0 4543 24 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 4 0 0
v_add_invoice_to_stock 10182 10014 168 1.64 0 7729 19 0 168
v_all_customers 38024 38024 0 0.00 0 21 1 0 0
v_all_suppliers 30916 30916 0 0.00 0 19 1 0 0
v_all_wares 41886 41886 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 826 826 0 0.00 0 3080 17 0 0
v_cancel_client_order 3504 3504 0 0.00 0 2422 7 0 0
v_cancel_customer_prepayment 1332 1332 0 0.00 0 2591 5 0 0
v_cancel_customer_reserve 1440 1440 0 0.00 0 2176 7 0 0
v_cancel_payment_to_supplier 818 818 0 0.00 0 1555 7 0 0
v_cancel_supplier_invoice 792 780 12 1.51 0 1680 15 0 12
v_cancel_supplier_order 800 800 0 0.00 0 1720 8 0 0
v_cancel_write_off 2024 2024 0 0.00 0 4716 17 0 0
v_max_id_clo_ord 5625 5625 0 0.00 0 6541 3 0 0
v_max_id_clo_res 1024 1024 0 0.00 0 1187 9 0 0
v_max_id_ord_sup 5571 5571 0 0.00 0 3304 2 0 0
v_max_non_paid_invoice 1488 1488 0 0.00 0 697 3 0 0
v_max_non_paid_realizn 3786 3786 0 0.00 0 2300 4 0 0
v_min_id_clo_ord 5625 5625 0 0.00 0 5516 9 0 0
v_min_id_clo_res 1024 1024 0 0.00 0 4039 11 0 0
v_min_id_ord_sup 5571 5571 0 0.00 0 6882 7 0 0
v_min_non_paid_invoice 5750 5750 0 0.00 0 9180 25 0 0
v_min_non_paid_realizn 7070 7070 0 0.00 0 11750 24 0 0
v_our_firm 10430 10430 0 0.00 0 13 1 0 0
v_reserve_write_off 20146 20146 0 0.00 0 4130 7 0 0
x_lock_depdocs_on_canc_invoice 11165 11165 0 0.00 0 25250 117 0 0
x_lock_depdocs_on_canc_sup_ord 400 400 0 0.00 0 24527 406 0 0
x_make_qstorno_1000_1200 5626 5626 0 0.00 1 65315 1212 0 0
x_make_qstorno_1200_2000 5575 5575 0 0.00 3 86627 3329 0 0
x_make_qstorno_2100_3300 466178 466178 0 0.00 0 104504 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-09-2021 16:45:02 to 16-09-2021 16:45:06.

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
14:13:21 1 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
14:14:22 29502 8192000000 10344301008 10663329792 1398895920 14.585 100 84 168 0 1432807184 5931488 27979776
14:15:54 5843 8192000000 10417756960 10745823232 1478641056 15.290 100 92 184 0 1516672544 7944160 30087328
14:17:08 18057 8192000000 10479787712 10822725632 1533698704 15.769 100 88 176 0 1570660880 9450752 27511424
14:18:32 15637 8192000000 10550900144 10903556096 1602783696 16.363 100 75 150 0 1636058560 9766464 23508400
14:19:52 12765 8192000000 10628893104 10995789824 1678587808 17.005 100 78 157 1 1712729680 11670624 22471248
14:21:09 8089 8192000000 10683914288 11064311808 1750636352 17.607 100 80 160 1 1786228368 12444048 23147968
14:22:21 10310 8192000000 10710664496 11102740480 1786064864 17.899 100 66 130 1 1815988816 12210640 17713312
14:23:34 3585 8192000000 10787936128 11169763328 1840231632 18.343 100 81 163 1 1876425520 13939744 22254144
14:24:39 2872 8192000000 10804270976 11192832000 1864772496 18.542 100 88 177 0 1902030992 13561360 23697136
14:25:45 6822 8192000000 10813581216 11207581696 1876040000 18.633 100 85 169 1 1912329888 12075328 24214560
14:26:54 3249 8192000000 10806729072 11221430272 1888680768 18.735 100 44 88 0 1910592880 8559488 13352624
14:28:01 3380 8192000000 10816996912 11236532224 1899975584 18.826 100 41 83 1 1920177200 8909408 11292208
14:29:05 24359 8192000000 10804521600 11240632320 1897197952 18.804 100 19 38 1 1908475472 5309344 5968176
14:30:31 8853 8192000000 10812542000 11249471488 1903813024 18.857 100 24 46 2 1916330256 5871456 6645776
14:31:41 5649 8192000000 10837364416 11264385024 1915615056 18.952 100 36 72 1 1931526400 5674448 10236896
14:32:51 10543 8192000000 10827328608 11260694528 1920855120 18.994 100 27 54 0 1934810208 5693808 8261280
14:34:04 9908 8192000000 10874427296 11280674816 1928544144 19.055 100 72 145 1 1955926800 5672272 21710384
14:35:15 8990 8192000000 10782822352 11211165696 1815523328 18.141 96 23 46 0 1828224912 6515472 6186112
14:36:29 7307 8192000000 10745295712 11167105024 1816582944 18.150 99 26 52 1 1829920912 5883824 7454144
14:37:38 21235 8192000000 10683345952 11105075200 1714153904 17.303 95 37 74 1 1730279056 5364480 10760672
14:39:01 9980 8192000000 10723860576 11140984832 1803007696 18.039 99 28 56 0 1816218368 5595920 7614752
14:40:14 8287 8192000000 10749643280 11173171200 1819259856 18.172 99 18 36 0 1829814032 5293920 5260256
14:41:22 9774 8192000000 10782417264 11208036352 1864942528 18.543 100 24 48 2 1878383232 6735472 6705232
14:42:33 41920 8192000000 10789236112 11219218432 1861836224 18.518 99 37 74 2 1878749856 5475952 11437680
14:44:20 64464 8192000000 10846734096 11273199616 1920565024 18.991 100 54 107 0 1947588288 12140112 14883152
14:46:46 13950 8192000000 10886542768 11308367872 1949375856 19.222 100 54 108 0 1979245168 14008320 15860992
14:48:05 68012 8192000000 10895704976 11336650752 1961588896 19.319 100 39 78 0 1988199568 15578656 11032016
14:50:19 20563 8192000000 10912049600 11358138368 1982933264 19.488 100 41 82 3 2016608256 21006480 12668512
14:51:50 20150 8192000000 10938637760 11378610176 1995643408 19.588 100 50 98 2 2030233776 19829184 14761184
14:53:16 9736 8192000000 10951830320 11389542400 2008917296 19.693 100 58 116 0 2047457824 21425040 17115488
14:54:32 5159 8192000000 10981538448 11414507520 2026225776 19.829 100 66 130 0 2071276112 25921360 19128976
14:55:45 6896 8192000000 10959198864 11398971392 2001644048 19.636 99 59 116 1 2040067760 21178864 17244848
14:56:57 3634 8192000000 11005950064 11436208128 2037026720 19.914 100 73 145 0 2090700640 30259328 23414592
14:58:04 5049 8192000000 11004544800 11440640000 2039678064 19.934 100 64 128 0 2093145936 32879312 20588560
14:59:16 2562 8192000000 11011215296 11435659264 2033826656 19.889 100 74 146 0 2091830208 35569456 22434096
15:00:23 4740 8192000000 11000330544 11433963520 2027519936 19.839 100 64 128 1 2085893888 38676096 19697856
15:01:35 2327 8192000000 10992030768 11408269312 2002469072 19.642 100 91 182 3 2071006432 40560304 27977056
15:02:45 2423 8192000000 11019865504 11435319296 2024355024 19.814 100 89 179 1 2095123056 42662800 28105232
15:03:53 8616 8192000000 10985077536 11413032960 2000241776 19.625 100 52 103 2 2062019280 44953824 16823680
15:05:08 1837 8192000000 10996109632 11405692928 1989316336 19.538 100 83 166 0 2065508464 49941808 26250320
15:06:11 25942 8192000000 10933559888 11370369024 1917812912 18.969 97 49 96 0 1973376128 39904672 15658544
15:07:50 22082 8192000000 10953377760 11405967360 1983395408 19.492 100 28 54 0 2035053904 42017312 9641184
15:09:22 9298 8192000000 10936206400 11389464576 1965979952 19.354 100 33 66 1 2026111216 48888240 11243024
15:10:36 23963 8192000000 10933375872 11392245760 1964880416 19.345 100 23 46 1 2015111872 41956256 8275200
15:12:00 23652 8192000000 10901246576 11375034368 1933151904 19.092 99 18 36 1 1982538944 42567040 6820000
15:13:25 31732 8192000000 10884485248 11340623872 1930002880 19.067 98 27 53 2 1951778880 13826560 7949440
15:15:02 139100 8192000000 10880447856 11345698816 1901123280 18.835 96 33 66 0 1917871456 6783456 9964720
15:18:24 157357 8192000000 10891406064 11373162496 1909748432 18.905 97 17 34 1 1934772800 19639760 5384608
15:22:04 84149 8192000000 10922924096 11362230272 1927444688 19.046 97 31 62 3 1948687152 12355344 8887120
15:24:29 36752 8192000000 10897260816 11352756224 1943201184 19.172 98 53 103 1 1973720848 14036144 16483520
15:26:11 59427 8192000000 10903177728 11384680448 1972458176 19.405 100 13 26 0 2000717776 24583280 3676320
15:28:16 83680 8192000000 10877666736 11356758016 1916237600 18.957 97 10 20 0 1932637936 13191808 3208528
15:31:43 88731 8192000000 10890158480 11365670912 1929444480 19.062 98 16 32 1 1947096208 13396320 4255408
15:34:23 65664 8192000000 10873859344 11347341312 1957249056 19.284 100 9 16 1 1971802384 12517760 2035568
15:36:37 42968 8192000000 10879654336 11345133568 1951864224 19.241 99 7 14 0 1965188992 11251040 2073728
15:38:23 50539 8192000000 10889243664 11367653376 1962991136 19.330 100 15 29 1 1976174464 9576240 3607088
15:40:16 45895 8192000000 10891741968 11362721792 1929330672 19.062 98 22 45 0 1949485424 13540592 6614160
15:42:04 37829 8192000000 10908199680 11378343936 1964972848 19.346 99 27 54 1 1986611024 13631520 8006656
15:43:43 69506 8192000000 10921667104 11378782208 1976685888 19.438 99 25 50 0 1996219600 12589584 6944128
15:46:01 108560 8192000000 10891697792 11368923136 1931984864 19.083 97 10 20 1 1949934416 15150240 2799312
15:49:03 107226 8192000000 10908944112 11378388992 1892348736 18.765 95 20 40 0 1911589040 13936384 5303920
15:51:56 35199 8192000000 10901455488 11347210240 1924605504 19.024 98 22 44 0 1940321872 9540160 6176208
15:53:35 68443 8192000000 10855920560 11315929088 1873784224 18.615 96 13 25 1 1884016000 6569472 3662304
15:55:44 87604 8192000000 10884314992 11355361280 1891707072 18.760 96 29 58 0 1918872864 18738736 8427056
15:58:12 97566 8192000000 10848664272 11311800320 1858099488 18.488 96 14 28 0 1876125504 13550000 4476016
16:00:51 64444 8192000000 10854431952 11332526080 1923627488 19.016 99 14 28 0 1938397808 10362464 4407856
16:03:18 24242 8192000000 10837782224 11304980480 1897576384 18.807 98 29 58 3 1920154368 13746608 8831376
16:04:42 72446 8192000000 10852823920 11323777024 1892059200 18.762 98 7 14 0 1906297600 11796064 2442336
16:07:01 40118 8192000000 10900809920 11358302208 1960073040 19.307 100 41 82 0 1984655040 12445456 12136544
16:08:50 48548 8192000000 10871261104 11345756160 1913740240 18.937 98 13 26 1 1931487184 13854752 3892192
16:10:45 25385 8192000000 10864254160 11317686272 1889542912 18.742 97 26 51 1 1905075280 8559648 6972720
16:12:19 47228 8192000000 10878226224 11335409664 1891538752 18.758 97 35 70 1 1912939712 11205968 10194992
16:14:08 107535 8192000000 10906777728 11351838720 1877029840 18.641 96 21 42 0 1894295856 11635200 5630816
16:16:57 68185 8192000000 10853959792 11319513088 1852056016 18.439 95 18 34 0 1870857024 14280272 4520736
16:19:43 94891 8192000000 10864445952 11344285696 1904205232 18.860 98 12 24 0 1923140368 15584576 3350560
16:22:19 57778 8192000000 10853780272 11327750144 1868672480 18.574 96 17 34 0 1886646912 12794304 5180128
16:24:19 43259 8192000000 10869640112 11344248832 1949479328 19.222 100 18 36 2 1963617360 8955280 5182752
16:26:02 39554 8192000000 10924203552 11383762944 1981116528 19.474 100 51 102 0 2011991424 15482512 15392384
16:27:53 88146 8192000000 10897171728 11384184832 1928709808 19.057 98 10 20 0 1956947920 25598000 2640112
16:30:21 25262 8192000000 10913009872 11372052480 1976739968 19.439 100 24 48 0 1991353168 7707136 6906064
16:31:48 62331 8192000000 10893696336 11348819968 1906455888 18.878 97 22 44 1 1922541536 10113824 5971824
16:33:55 23172 8192000000 10919325296 11389460480 1975732464 19.431 100 24 48 1 1992219952 10048832 6438656
16:35:26 20734 8192000000 10875098736 11327889408 1870960112 18.592 95 22 44 1 1883626320 7244768 5421440
16:36:49 141381 8192000000 10871525616 11339317248 1890622960 18.751 97 21 42 0 1910101376 13190800 6287616
16:40:19 25512 8192000000 10866917440 11344015360 1933594992 19.096 99 14 26 1 1946967728 10346640 3026096

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 16-09-2021 16:45:08 to 16-09-2021 16:45:23.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 84 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-09-2021 16:45:24 to 16-09-2021 16:45:26.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          231493
MON$OLDEST_ACTIVE               233877
MON$OLDEST_SNAPSHOT             233877
MON$NEXT_TRANSACTION            233877
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-03 16:41:55.3470
MON$PAGES                       2016192
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33497 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33497 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33497 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33497 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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    Thu Sep 16 16:43:19 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Thu Sep 16 16:43:27 2021
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Thu Sep 16 16:43:29 2021
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:17: 
--- end of diff output ---

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

16.09.21 16:45:27. There are 3 logs in /var/tmp/logs-oltp30 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-09-16 16:45:29 No severe PSQL-related problems occured
16.09.21 16:45:30. End of report.