Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.09.2021 19:38
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 16123840 kB
MemAvailable 30826792 kB
Buffers 0 kB
Cached 15530480 kB
SwapCached 396 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-08 19:37:51
DTS_END                         2021-09-08 19:37:51.9870
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/45666
TRN_ID                          246783
ATT_ID                          667
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: 9197.61 17026 1103713
customer order: creation 180.43 368 21651
customer order: refuse 15.82 350 1898
order to supplier: creation 48.01 1363 5761
order to supplier: removal 3.54 95029 425
invoice (draft): creation 47.13 3583 5655
invoice (draft): removal 50.53 798 6063
invoice accept: apply 43.03 18205 5164
invoice accept: cancel 45.03 860 5404
customer reserve: creation 3982.79 180 477935
customer reserve: removal 4486.94 59 538433
realization accept: apply 87.60 177 10512
realization accept: cancel 71.42 107 8570
payment from customer: creation 62.20 129 7464
payment from customer: removal 5.88 85 706
payment to supplier: creation 51.21 133 6145
payment to supplier: removal 3.65 93 438
service: total inventory turnovers 4.70 1105 564
service: total monetary turnovers 6.73 33 808
service: aggregate perf. data 0.94 18500 113
service: refresh index statistics 0.03 199365 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-09-2021 19:38:50 to 08-09-2021 19:38:52.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2767
TEST_TIME 2 3084
TEST_TIME 3 4196
TEST_TIME 4 5411
TEST_TIME 5 5422
TEST_TIME 6 5891
TEST_TIME 7 5793
TEST_TIME 8 5668
TEST_TIME 9 5562
TEST_TIME 10 5466
TEST_TIME 11 5488
TEST_TIME 12 5466
TEST_TIME 13 5590
TEST_TIME 14 5763
TEST_TIME 15 6094
TEST_TIME 16 6721
TEST_TIME 17 6663
TEST_TIME 18 6971
TEST_TIME 19 7598
TEST_TIME 20 8065
TEST_TIME 21 8604
TEST_TIME 22 8637
TEST_TIME 23 8872
TEST_TIME 24 9208
TEST_TIME 25 9149
TEST_TIME 26 9342
TEST_TIME 27 9840
TEST_TIME 28 10242
TEST_TIME 29 10423
TEST_TIME 30 10202
TEST_TIME 31 10123
TEST_TIME 32 9932
TEST_TIME 33 9902
TEST_TIME 34 9968
TEST_TIME 35 9942
TEST_TIME 36 9697
TEST_TIME 37 9669
TEST_TIME 38 9580
TEST_TIME 39 9566
TEST_TIME 40 9492
TEST_TIME 41 9608
TEST_TIME 42 9553
TEST_TIME 43 9519
TEST_TIME 44 9692
TEST_TIME 45 9569
TEST_TIME 46 9493
TEST_TIME 47 9500
TEST_TIME 48 9421
TEST_TIME 49 9291
TEST_TIME 50 9312
TEST_TIME 51 9344
TEST_TIME 52 9317
TEST_TIME 53 9366
TEST_TIME 54 9412
TEST_TIME 55 9399
TEST_TIME 56 9468
TEST_TIME 57 9524
TEST_TIME 58 9501
TEST_TIME 59 9487
TEST_TIME 60 9511
TEST_TIME 61 9580
TEST_TIME 62 9574
TEST_TIME 63 9642
TEST_TIME 64 9696
TEST_TIME 65 9563
TEST_TIME 66 9561
TEST_TIME 67 9522
TEST_TIME 68 9501
TEST_TIME 69 9449
TEST_TIME 70 9384
TEST_TIME 71 9336
TEST_TIME 72 9215
TEST_TIME 73 9245
TEST_TIME 74 9246
TEST_TIME 75 9240
TEST_TIME 76 9268
TEST_TIME 77 9275
TEST_TIME 78 9333
TEST_TIME 79 9401
TEST_TIME 80 9362
TEST_TIME 81 9347
TEST_TIME 82 9450
TEST_TIME 83 9506
TEST_TIME 84 9473
TEST_TIME 85 9511
TEST_TIME 86 9506
TEST_TIME 87 9423
TEST_TIME 88 9408
TEST_TIME 89 9387
TEST_TIME 90 9413
TEST_TIME 91 9360
TEST_TIME 92 9344
TEST_TIME 93 9326
TEST_TIME 94 9340
TEST_TIME 95 9319
TEST_TIME 96 9282
TEST_TIME 97 9398
TEST_TIME 98 9395
TEST_TIME 99 9375
TEST_TIME 100 9380
TEST_TIME 101 9365
TEST_TIME 102 9435
TEST_TIME 103 9478
TEST_TIME 104 9509
TEST_TIME 105 9491
TEST_TIME 106 9491
TEST_TIME 107 9462
TEST_TIME 108 9450
TEST_TIME 109 9379
TEST_TIME 110 9349
TEST_TIME 111 9321
TEST_TIME 112 9265
TEST_TIME 113 9259
TEST_TIME 114 9216
TEST_TIME 115 9203
TEST_TIME 116 9154
TEST_TIME 117 9176
TEST_TIME 118 9192
TEST_TIME 119 9185
TEST_TIME 120 9199

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 08-09-2021 19:38:54 to 08-09-2021 19:38:57.

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 1546378 1546378 0 0.00 0 19300 6 0 0
doc_list_biud 1546377 1546377 0 0.00 0 21732 26 0 0
sp_add_invoice_to_stock 5313 5164 149 2.80 22 188196 18206 0 149
sp_cancel_adding_invoice 5404 5404 0 0.00 5 58265 860 0 0
sp_cancel_client_order 1898 1898 0 0.00 4 10654 350 0 0
sp_cancel_customer_reserve 538433 538433 0 0.00 1 21734 60 0 0
sp_cancel_pay_from_customer 706 706 0 0.00 3 5117 85 0 0
sp_cancel_pay_to_supplier 438 438 0 0.00 2 4214 94 0 0
sp_cancel_supplier_invoice 6075 6063 12 0.19 2 14066 799 0 12
sp_cancel_supplier_order 425 425 0 0.00 12 1216497 95030 0 0
sp_cancel_write_off 8570 8570 0 0.00 1 12621 107 0 0
sp_client_order 21651 21651 0 0.00 4 16702 369 0 0
sp_customer_reserve 477935 477935 0 0.00 0 101508 180 0 0
sp_fill_shopping_cart 33061 33061 0 0.00 1 9090 38 0 0
sp_fill_shopping_cart_clo_res 477933 477933 0 0.00 0 15751 14 0 0
sp_get_clo_for_invoice 5162 5162 0 0.00 0 22647 273 0 0
sp_kill_cost_storno 15118 15118 0 0.00 0 10074 55 0 0
sp_kill_qstorno_ret_qs2qd 1091924 1091924 0 0.00 0 21730 32 0 0
sp_kill_qty_storno 576466 576466 0 0.00 0 21732 70 0 0
sp_lock_selected_doc 21739 21739 0 0.00 0 8479 6 0 0
sp_make_cost_storno 29283 29283 0 0.00 0 19296 54 0 0
sp_multiply_rows_for_pdistr 29283 29283 0 0.00 0 13649 32 0 0
sp_multiply_rows_for_qdistr 21651 21651 0 0.00 0 13176 176 0 0
sp_pay_from_customer 7464 7464 0 0.00 4 13673 129 0 0
sp_pay_to_supplier 6145 6145 0 0.00 5 14184 133 0 0
sp_payment_common 13609 13609 0 0.00 1 9924 32 0 0
sp_qd_handle_on_cancel_clo 1898 1898 0 0.00 0 10648 323 0 0
sp_qd_handle_on_invoice_upd_sts 10565 10565 0 0.00 0 15399 354 0 0
sp_qd_handle_on_reserve_upd_sts 10512 10512 0 0.00 0 13306 54 0 0
sp_reserve_write_off 10512 10512 0 0.00 6 13478 177 0 0
sp_supplier_invoice 5655 5655 0 0.00 6 96690 3583 0 0
sp_supplier_order 5761 5761 0 0.00 5 37181 1364 0 0
srv_aggregate_perf_data 113 113 0 0.00 2228 218569 18500 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1786 114861 44023 0 0
srv_find_qd_qs_mism 1054049 1054049 0 0.00 0 12840 5 0 0
srv_make_invnt_saldo 564 564 0 0.00 22 43741 1105 0 0
srv_make_money_saldo 808 808 0 0.00 1 4010 33 0 0
srv_recalc_idx_stat 4 4 0 0.00 83966 293101 199366 0 0
t$perf-abend:sp_add_invoice_to_stock 149 149 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5164 5164 0 0.00 0 22667 169 0 0
t$perf-norm:sp_cancel_adding_invoice 406 406 0 0.00 1 2445 68 0 0
t$perf-norm:sp_cancel_client_order 1898 1898 0 0.00 0 2817 14 0 0
t$perf-norm:sp_cancel_customer_reserve 718 718 0 0.00 0 11454 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 706 706 0 0.00 0 5492 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 438 438 0 0.00 0 133 1 0 0
t$perf-norm:sp_cancel_supplier_invoice 417 417 0 0.00 0 1329 17 0 0
t$perf-norm:sp_cancel_supplier_order 425 425 0 0.00 0 22450 1495 0 0
t$perf-norm:sp_cancel_write_off 1059 1059 0 0.00 0 5559 10 0 0
t$perf-norm:sp_client_order 21651 21651 0 0.00 0 6174 16 0 0
t$perf-norm:sp_customer_reserve 995 995 0 0.00 0 538 4 0 0
t$perf-norm:sp_pay_from_customer 7464 7464 0 0.00 0 10220 11 0 0
t$perf-norm:sp_pay_to_supplier 6145 6145 0 0.00 0 4733 9 0 0
t$perf-norm:sp_reserve_write_off 10512 10512 0 0.00 0 15148 14 0 0
t$perf-norm:sp_supplier_invoice 5655 5655 0 0.00 0 5469 15 0 0
t$perf-norm:sp_supplier_order 5761 5761 0 0.00 0 3437 16 0 0
t$perf-norm:srv_aggregate_perf_data 113 113 0 0.00 0 1573 21 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 15 0 0 0
t$perf-norm:srv_make_invnt_saldo 564 564 0 0.00 0 1574 19 0 0
t$perf-norm:srv_make_money_saldo 808 808 0 0.00 0 1070 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 128 34 0 0
v_add_invoice_to_stock 10618 10320 298 2.80 0 4578 22 0 298
v_all_customers 38804 38804 0 0.00 0 71 1 0 0
v_all_suppliers 31636 31636 0 0.00 0 97 1 0 0
v_all_wares 43302 43302 0 0.00 0 92 1 0 0
v_cancel_adding_invoice 812 812 0 0.00 0 2220 10 0 0
v_cancel_client_order 3796 3796 0 0.00 0 4233 8 0 0
v_cancel_customer_prepayment 1412 1412 0 0.00 0 4421 9 0 0
v_cancel_customer_reserve 1436 1436 0 0.00 0 1845 5 0 0
v_cancel_payment_to_supplier 876 876 0 0.00 0 4191 17 0 0
v_cancel_supplier_invoice 858 834 24 2.79 0 4260 21 0 24
v_cancel_supplier_order 850 850 0 0.00 0 608 3 0 0
v_cancel_write_off 2118 2118 0 0.00 0 2578 14 0 0
v_max_id_clo_ord 5760 5760 0 0.00 0 521 1 0 0
v_max_id_clo_res 995 995 0 0.00 0 3763 17 0 0
v_max_id_ord_sup 5650 5650 0 0.00 0 4174 5 0 0
v_max_non_paid_invoice 1737 1737 0 0.00 0 1327 3 0 0
v_max_non_paid_realizn 4186 4186 0 0.00 0 1214 4 0 0
v_min_id_clo_ord 5760 5760 0 0.00 0 3036 8 0 0
v_min_id_clo_res 995 995 0 0.00 0 5284 13 0 0
v_min_id_ord_sup 5650 5650 0 0.00 0 7320 7 0 0
v_min_non_paid_invoice 6145 6145 0 0.00 0 6272 16 0 0
v_min_non_paid_realizn 7464 7464 0 0.00 0 9920 24 0 0
v_our_firm 11054 11054 0 0.00 0 66 1 0 0
v_reserve_write_off 21024 21024 0 0.00 0 4453 7 0 0
x_lock_depdocs_on_canc_invoice 11050 11050 0 0.00 0 17172 117 0 0
x_lock_depdocs_on_canc_sup_ord 425 425 0 0.00 0 6699 334 0 0
x_make_qstorno_1000_1200 5761 5761 0 0.00 2 37169 1279 0 0
x_make_qstorno_1200_2000 5655 5655 0 0.00 3 96668 3450 0 0
x_make_qstorno_2100_3300 477935 477935 0 0.00 0 101508 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-09-2021 19:38:58 to 08-09-2021 19:39:02.

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
17:07:55 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
17:08:56 34702 8192000000 10377569392 10698047488 1422849488 14.798 100 85 170 0 1457060480 6308096 27902896
17:10:42 24520 8192000000 10442262656 10771046400 1484005184 15.336 100 86 172 1 1521094176 8822688 28266304
17:12:15 21563 8192000000 10494315072 10846552064 1551800576 15.926 100 73 146 0 1585224544 10275056 23148912
17:13:41 12591 8192000000 10606394464 10971746304 1653906832 16.797 100 82 164 0 1690507072 11969952 24630288
17:14:56 6299 8192000000 10661091984 11040096256 1726908272 17.410 100 75 150 0 1760813344 11752320 22152752
17:16:07 8660 8192000000 10738810128 11122446336 1800204832 18.016 100 82 162 3 1836979232 13968544 22805856
17:17:17 3008 8192000000 10789611104 11178766336 1846180352 18.391 100 85 172 0 1884636192 13434368 25021472
17:18:22 1882 8192000000 10813723264 11203305472 1872137136 18.602 100 92 183 1 1912595248 14449472 26008640
17:19:26 11870 8192000000 10818310640 11222020096 1890853008 18.753 100 71 142 1 1924696016 14486624 19356384
17:20:39 4817 8192000000 10812556752 11231760384 1895117856 18.787 100 60 117 2 1921751024 9946032 16687136
17:21:45 7050 8192000000 10809522896 11243048960 1903217280 18.852 100 24 49 1 1917086464 6906192 6962992
17:22:56 6480 8192000000 10821487440 11253944320 1912969072 18.930 100 28 56 0 1927329088 6679264 7680752
17:24:04 57352 8192000000 10851095456 11282128896 1930812640 19.073 100 42 84 1 1950129136 5236080 14080416
17:26:09 9780 8192000000 10848588304 11282972672 1930448144 19.070 100 31 58 3 1945394976 5677504 9269328
17:27:19 7107 8192000000 10806372112 11241926656 1871364608 18.595 98 23 47 0 1883724368 5768512 6591248
17:28:27 20145 8192000000 10740058496 11170328576 1680119040 17.018 89 10 19 1 1687217984 4767696 2331248
17:29:48 29308 8192000000 10732135680 11163643904 1736837520 17.492 93 48 96 1 1756744016 5683888 14222608
17:31:18 7035 8192000000 10733263952 11133988864 1726463264 17.406 95 28 55 3 1740180768 5555584 8161920
17:32:28 9149 8192000000 10751513808 11167076352 1808847776 18.086 99 33 66 1 1823628160 5238080 9542304
17:33:40 55340 8192000000 10752688512 11189211136 1849329280 18.417 100 28 56 1 1863128688 5643056 8156352
17:35:38 7978 8192000000 10773798720 11195924480 1853807456 18.453 100 21 42 2 1865668352 5726656 6134240
17:36:47 9261 8192000000 10789220704 11211329536 1869408720 18.579 100 21 42 1 1880665712 5247248 6009744
17:38:05 13009 8192000000 10839629968 11253272576 1901495152 18.838 100 43 86 0 1925183552 9973424 13714976
17:39:21 66991 8192000000 10846590016 11280150528 1923727136 19.017 100 43 83 1 1944813488 9010368 12075984
17:41:37 9306 8192000000 10874719376 11306434560 1939407856 19.142 100 42 84 1 1963275968 11606176 12261936
17:42:57 21877 8192000000 10899704336 11324239872 1959529696 19.302 100 55 110 0 1988781280 13276256 15975328
17:44:29 11583 8192000000 10923851536 11346612224 1970995456 19.393 100 62 124 0 2005677280 17225120 17456704
17:45:50 19452 8192000000 10900026256 11334426624 1964456592 19.341 100 46 92 0 1995820512 18132592 13231328
17:47:20 5462 8192000000 10931707968 11363704832 1991640208 19.557 100 51 102 1 2025525968 18521328 15364432
17:48:35 8491 8192000000 10945803280 11379752960 1995783104 19.589 100 48 96 2 2032132944 21220304 15129536
17:49:56 4918 8192000000 10943410784 11368214528 1986320352 19.515 100 64 126 1 2028381664 23030768 19030544
17:51:11 1784 8192000000 10985728288 11396820992 2008978528 19.693 100 88 177 3 2063728928 28751040 25999360
17:52:19 2172 8192000000 10972819392 11384754176 1995430624 19.587 100 85 169 1 2054003744 33702240 24870880
17:53:27 7510 8192000000 10959646112 11371577344 1981015664 19.473 100 80 161 1 2038724416 33136784 24571968
17:54:40 4080 8192000000 10967578336 11385360384 1982523136 19.485 100 67 134 2 2044160096 39235296 22401664
17:55:53 3000 8192000000 10969135168 11394674688 1985255632 19.506 100 68 135 3 2057212896 49418816 22538448
17:57:01 5091 8192000000 10948332032 11374239744 1970877344 19.392 100 60 120 1 2033743104 43342368 19523392
17:58:13 7567 8192000000 10957797776 11377590272 1971120016 19.394 100 72 143 0 2035598480 42205552 22272912
17:59:24 17719 8192000000 10915611232 11362770944 1936994816 19.123 99 36 72 0 1987576208 39036016 11545376
18:00:44 11714 8192000000 10909093808 11347517440 1939195376 19.140 100 34 68 0 1990924816 40234032 11495408
18:02:09 46335 8192000000 10896850624 11345338368 1919247600 18.981 99 23 46 2 1969072384 42440704 7384080
18:03:59 9006 8192000000 10926733392 11358961664 1925300480 19.029 99 54 106 2 1981293024 39727824 16264720
18:05:11 44554 8192000000 10861198224 11314569216 1894067584 18.779 98 21 41 1 1916166128 15901088 6197456
18:06:59 47259 8192000000 10874685232 11311304704 1900803184 18.833 98 34 68 1 1919152032 9108160 9240688
18:09:08 96658 8192000000 10891360960 11344515072 1934771824 19.105 99 28 56 2 1952361200 9342512 8246864
18:11:45 87249 8192000000 10887283088 11328962560 1914744224 18.945 98 19 38 0 1939588224 19133232 5710768
18:14:19 8387 8192000000 10926471760 11368574976 1977477776 19.445 100 38 76 1 2005164320 16839664 10846880
18:15:47 29925 8192000000 10917354240 11356528640 1970474432 19.389 99 41 80 1 1997648832 14870048 12304352
18:17:22 5178 8192000000 10947297632 11383783424 1995652384 19.588 100 70 135 2 2033550000 17928000 19969616
18:18:29 114828 8192000000 10910663920 11385536512 1950313984 19.229 98 9 18 0 1971073536 18099632 2659920
18:21:26 66997 8192000000 10918261936 11382878208 1991331904 19.554 100 16 32 0 2003369216 7495504 4541808
18:23:36 25444 8192000000 10909780192 11381358592 1980365344 19.468 99 34 68 0 2000944912 11491008 9088560
18:25:10 64418 8192000000 10909235040 11397885952 1970943120 19.393 99 10 20 1 1993422496 19738400 2740976
18:27:26 46123 8192000000 10910897888 11397869568 1969420608 19.381 99 8 16 0 1988416144 17027664 1967872
18:29:19 96900 8192000000 10914567024 11390197760 1933609248 19.096 97 15 30 0 1956952080 19229408 4113424
18:32:07 44140 8192000000 10936357760 11390783488 1976190416 19.435 99 20 40 1 1997230624 15470816 5569392
18:33:56 51292 8192000000 10912371440 11380203520 1962341776 19.325 99 22 44 0 1983399888 15443696 5614416
18:35:49 43617 8192000000 10915271600 11375882240 1949740816 19.224 98 34 68 1 1975165008 15872256 9551936
18:37:33 20672 8192000000 10909613232 11366473728 1952783888 19.249 99 29 58 1 1969766752 9426672 7556192
18:38:55 98566 8192000000 10888589232 11357388800 1787457120 17.911 90 13 26 0 1805822608 14802624 3562864
18:41:34 79765 8192000000 10885023824 11341594624 1934078976 19.099 99 18 37 0 1950717968 11443200 5195792
18:44:05 24253 8192000000 10907211024 11365392384 1969102784 19.378 100 41 82 1 1992021440 11734432 11184224
18:45:30 52421 8192000000 10856049824 11338260480 1945969616 19.194 100 17 34 1 1959337824 8534992 4833216
18:48:19 76501 8192000000 10872899824 11338919936 1893001408 18.770 96 13 26 0 1905746560 9106112 3639040
18:50:40 33135 8192000000 10860787264 11349233664 1946658256 19.200 100 6 12 0 1960435632 11918064 1859312
18:52:13 45696 8192000000 10884371648 11351977984 1941643952 19.160 99 21 42 0 1956972688 8859376 6469360
18:54:02 94334 8192000000 10852039616 11327594496 1863350464 18.530 95 19 38 1 1878936368 9985344 5600560
18:56:39 78762 8192000000 10888953776 11353509888 1934301808 19.101 99 14 28 0 1953827616 15941904 3583904
18:58:58 30954 8192000000 10885876704 11350548480 1935447680 19.110 99 40 80 1 1958792368 11519696 11824992
19:00:44 63615 8192000000 10853343696 11318247424 1861954960 18.519 96 12 24 1 1880454832 15183296 3316576
19:02:48 33425 8192000000 10883450816 11341590528 1909145184 18.900 98 26 52 0 1928715264 12109920 7460160
19:04:23 104137 8192000000 10886200544 11354935296 1932607536 19.088 99 33 66 1 1956213536 13951648 9654352
19:08:01 89996 8192000000 10887150688 11369140224 1969799712 19.384 100 9 18 0 1979410368 7327616 2283040
19:11:44 34157 8192000000 10909652592 11377508352 1959469920 19.302 99 13 27 0 1973061264 9755504 3835840
19:13:25 67260 8192000000 10901622688 11394646016 1949288544 19.221 98 20 40 0 1966291248 11039376 5963328
19:15:33 80111 8192000000 10899835136 11366600704 1930122640 19.068 97 13 26 1 1944025456 10011568 3891248
19:17:55 36423 8192000000 10903582096 11368849408 1956924896 19.282 99 36 73 0 1979477584 11949728 10602960
19:19:32 72590 8192000000 10923001008 11399168000 1967516992 19.366 99 14 28 0 1986919520 15908384 3494144
19:22:48 57354 8192000000 10905847312 11367075840 1938033872 19.131 98 30 60 0 1957074784 11329808 7711104
19:24:48 68557 8192000000 10870558416 11352367104 1906676640 18.880 97 20 40 0 1926472896 14077568 5718688
19:27:20 23901 8192000000 10865861520 11334955008 1914181744 18.940 98 31 62 0 1930423616 7428624 8813248
19:28:44 43078 8192000000 10834544032 11313729536 1909565056 18.903 99 4 8 0 1917120128 6503664 1051408
19:30:36 101228 8192000000 10836918064 11308929024 1882641344 18.686 98 10 20 1 1892545616 7059008 2845264
19:33:26 65651 8192000000 10848519536 11322884096 1895194032 18.788 98 17 35 0 1918171232 17955536 5021664
19:35:33 68411 8192000000 10862433424 11339976704 1924709872 19.025 99 19 38 1 1947858496 17708960 5439664
19:37:42 22359 8192000000 9143311104 9381122048 307021952 3.612 16 2 4 0 313821600 6386848 412800

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 08-09-2021 19:39:04 to 08-09-2021 19:39:19.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-09-2021 19:39:20 to 08-09-2021 19:39:22.

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          248078
MON$OLDEST_ACTIVE               248079
MON$OLDEST_SNAPSHOT             248079
MON$NEXT_TRANSACTION            248079
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                       2026080
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.33493 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33493 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33493 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33493 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 ---
:47: 
:48: mscs-test-161.budzdorov.ru    Wed Sep  8 19:38:06 2021
:49:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:50: 
:51: 
:52: mscs-test-161.budzdorov.ru    Wed Sep  8 19:38:06 2021
:53:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:54: 
:55: 
:56: mscs-test-161.budzdorov.ru    Wed Sep  8 19:38:06 2021
:57:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:58: 
:59: 
:60: mscs-test-161.budzdorov.ru    Wed Sep  8 19:38:06 2021
:61:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:62: 
:63: 
:64: mscs-test-161.budzdorov.ru    Wed Sep  8 19:38:06 2021
:65:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:66: 
:67: 
:68: mscs-test-161.budzdorov.ru    Wed Sep  8 19:38:07 2021
:69:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:70: 
:71: 
:72: mscs-test-161.budzdorov.ru    Wed Sep  8 19:38:07 2021
:73:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:74: 
--- end of diff output ---

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

08.09.21 19:39:23. 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-08 19:39:25 No severe PSQL-related problems occured
08.09.21 19:39:26. End of report.