Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.10.2021 16:40
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 15921592 kB
MemAvailable 30688376 kB
Buffers 0 kB
Cached 15794116 kB
SwapCached 3776 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-10-15 16:37:55
DTS_END                         2021-10-15 16:37:55.2940
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/55470
TRN_ID                          246252
ATT_ID                          674
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: 9461.94 23208 1135433
customer order: creation 175.87 372 21104
customer order: refuse 15.68 334 1882
order to supplier: creation 48.19 1168 5783
order to supplier: removal 3.77 88500 452
invoice (draft): creation 47.88 3186 5746
invoice (draft): removal 52.85 760 6342
invoice accept: apply 43.27 18268 5192
invoice accept: cancel 47.53 764 5704
customer reserve: creation 4026.93 180 483232
customer reserve: removal 4693.15 57 563178
realization accept: apply 85.99 167 10319
realization accept: cancel 85.38 96 10246
payment from customer: creation 61.55 103 7386
payment from customer: removal 6.52 86 782
payment to supplier: creation 51.32 122 6158
payment to supplier: removal 3.68 83 441
service: total inventory turnovers 4.63 1265 556
service: total monetary turnovers 6.88 25 826
service: aggregate perf. data 0.83 23791 100
service: refresh index statistics 0.03 324834 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-10-2021 16:40:55 to 15-10-2021 16:40:57.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1888
TEST_TIME 2 3054
TEST_TIME 3 2932
TEST_TIME 4 3290
TEST_TIME 5 3482
TEST_TIME 6 3997
TEST_TIME 7 4128
TEST_TIME 8 4361
TEST_TIME 9 4909
TEST_TIME 10 5703
TEST_TIME 11 5814
TEST_TIME 12 5859
TEST_TIME 13 5980
TEST_TIME 14 6350
TEST_TIME 15 6792
TEST_TIME 16 6774
TEST_TIME 17 7279
TEST_TIME 18 7864
TEST_TIME 19 7659
TEST_TIME 20 8239
TEST_TIME 21 8842
TEST_TIME 22 8803
TEST_TIME 23 8852
TEST_TIME 24 8791
TEST_TIME 25 9108
TEST_TIME 26 9317
TEST_TIME 27 9454
TEST_TIME 28 9884
TEST_TIME 29 10298
TEST_TIME 30 10040
TEST_TIME 31 10046
TEST_TIME 32 9983
TEST_TIME 33 9965
TEST_TIME 34 9893
TEST_TIME 35 9854
TEST_TIME 36 9677
TEST_TIME 37 9617
TEST_TIME 38 9494
TEST_TIME 39 9466
TEST_TIME 40 9750
TEST_TIME 41 9669
TEST_TIME 42 9658
TEST_TIME 43 9494
TEST_TIME 44 9665
TEST_TIME 45 9651
TEST_TIME 46 9586
TEST_TIME 47 9572
TEST_TIME 48 9498
TEST_TIME 49 9397
TEST_TIME 50 9451
TEST_TIME 51 9394
TEST_TIME 52 9278
TEST_TIME 53 9247
TEST_TIME 54 9219
TEST_TIME 55 9240
TEST_TIME 56 9253
TEST_TIME 57 9258
TEST_TIME 58 9269
TEST_TIME 59 9224
TEST_TIME 60 9236
TEST_TIME 61 9453
TEST_TIME 62 9411
TEST_TIME 63 9488
TEST_TIME 64 9492
TEST_TIME 65 9522
TEST_TIME 66 9594
TEST_TIME 67 9682
TEST_TIME 68 9722
TEST_TIME 69 9741
TEST_TIME 70 9777
TEST_TIME 71 9656
TEST_TIME 72 9570
TEST_TIME 73 9659
TEST_TIME 74 9555
TEST_TIME 75 9584
TEST_TIME 76 9563
TEST_TIME 77 9581
TEST_TIME 78 9535
TEST_TIME 79 9454
TEST_TIME 80 9406
TEST_TIME 81 9428
TEST_TIME 82 9494
TEST_TIME 83 9480
TEST_TIME 84 9479
TEST_TIME 85 9495
TEST_TIME 86 9504
TEST_TIME 87 9468
TEST_TIME 88 9491
TEST_TIME 89 9496
TEST_TIME 90 9467
TEST_TIME 91 9532
TEST_TIME 92 9569
TEST_TIME 93 9561
TEST_TIME 94 9540
TEST_TIME 95 9596
TEST_TIME 96 9617
TEST_TIME 97 9596
TEST_TIME 98 9653
TEST_TIME 99 9631
TEST_TIME 100 9606
TEST_TIME 101 9582
TEST_TIME 102 9548
TEST_TIME 103 9515
TEST_TIME 104 9487
TEST_TIME 105 9478
TEST_TIME 106 9407
TEST_TIME 107 9370
TEST_TIME 108 9394
TEST_TIME 109 9364
TEST_TIME 110 9347
TEST_TIME 111 9409
TEST_TIME 112 9453
TEST_TIME 113 9420
TEST_TIME 114 9441
TEST_TIME 115 9442
TEST_TIME 116 9530
TEST_TIME 117 9509
TEST_TIME 118 9516
TEST_TIME 119 9495
TEST_TIME 120 9463

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-10-2021 16:40:59 to 15-10-2021 16:41:02.

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 1582808 1582808 0 0.00 0 19745 6 0 0
doc_list_biud 1582808 1582808 0 0.00 0 44953 26 0 0
sp_add_invoice_to_stock 5373 5192 181 3.36 23 158162 18268 0 181
sp_cancel_adding_invoice 5704 5704 0 0.00 5 65008 764 0 0
sp_cancel_client_order 1882 1882 0 0.00 4 14272 335 0 0
sp_cancel_customer_reserve 563178 563178 0 0.00 1 44955 57 0 0
sp_cancel_pay_from_customer 782 782 0 0.00 2 5471 87 0 0
sp_cancel_pay_to_supplier 441 441 0 0.00 2 5361 83 0 0
sp_cancel_supplier_invoice 6351 6342 9 0.14 1 30225 760 0 9
sp_cancel_supplier_order 452 452 0 0.00 10 812346 88500 0 0
sp_cancel_write_off 10246 10246 0 0.00 1 8022 96 0 0
sp_client_order 21104 21104 0 0.00 4 31601 373 0 0
sp_customer_reserve 483232 483232 0 0.00 0 97265 181 0 0
sp_fill_shopping_cart 32619 32619 0 0.00 1 17453 36 0 0
sp_fill_shopping_cart_clo_res 483226 483226 0 0.00 0 15828 15 0 0
sp_get_clo_for_invoice 5186 5186 0 0.00 0 30658 374 0 0
sp_kill_cost_storno 17173 17173 0 0.00 0 7963 50 0 0
sp_kill_qstorno_ret_qs2qd 1143396 1143396 0 0.00 0 44951 31 0 0
sp_kill_qty_storno 603309 603309 0 0.00 0 44953 67 0 0
sp_lock_selected_doc 21627 21627 0 0.00 0 4732 3 0 0
sp_make_cost_storno 29049 29049 0 0.00 0 19732 46 0 0
sp_multiply_rows_for_pdistr 29049 29049 0 0.00 0 8710 29 0 0
sp_multiply_rows_for_qdistr 21104 21104 0 0.00 0 31569 166 0 0
sp_pay_from_customer 7386 7386 0 0.00 4 11880 104 0 0
sp_pay_to_supplier 6158 6158 0 0.00 4 11721 122 0 0
sp_payment_common 13544 13544 0 0.00 1 7776 25 0 0
sp_qd_handle_on_cancel_clo 1882 1882 0 0.00 0 14184 314 0 0
sp_qd_handle_on_invoice_upd_sts 10890 10890 0 0.00 0 29299 301 0 0
sp_qd_handle_on_reserve_upd_sts 10319 10319 0 0.00 0 25962 60 0 0
sp_reserve_write_off 10319 10319 0 0.00 6 26309 168 0 0
sp_supplier_invoice 5746 5746 0 0.00 7 99029 3186 0 0
sp_supplier_order 5783 5783 0 0.00 5 63127 1169 0 0
srv_aggregate_perf_data 100 100 0 0.00 2207 544611 23792 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 3088 185411 45725 0 0
srv_find_qd_qs_mism 1085685 1085685 0 0.00 0 10833 6 0 0
srv_make_invnt_saldo 556 556 0 0.00 45 126415 1265 0 0
srv_make_money_saldo 826 826 0 0.00 1 4852 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 192138 535720 324835 0 0
t$perf-abend:sp_add_invoice_to_stock 181 181 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5192 5192 0 0.00 0 9055 152 0 0
t$perf-norm:sp_cancel_adding_invoice 436 436 0 0.00 0 6034 92 0 0
t$perf-norm:sp_cancel_client_order 1882 1882 0 0.00 0 7193 13 0 0
t$perf-norm:sp_cancel_customer_reserve 679 679 0 0.00 0 6626 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 782 782 0 0.00 0 1971 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 441 441 0 0.00 0 4546 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 428 428 0 0.00 0 1955 14 0 0
t$perf-norm:sp_cancel_supplier_order 452 452 0 0.00 0 28643 1445 0 0
t$perf-norm:sp_cancel_write_off 1022 1022 0 0.00 0 2952 5 0 0
t$perf-norm:sp_client_order 21104 21104 0 0.00 0 6514 17 0 0
t$perf-norm:sp_customer_reserve 1079 1079 0 0.00 0 3866 9 0 0
t$perf-norm:sp_pay_from_customer 7386 7386 0 0.00 0 4007 8 0 0
t$perf-norm:sp_pay_to_supplier 6158 6158 0 0.00 0 4566 10 0 0
t$perf-norm:sp_reserve_write_off 10319 10319 0 0.00 0 9998 11 0 0
t$perf-norm:sp_supplier_invoice 5746 5746 0 0.00 0 9784 21 0 0
t$perf-norm:sp_supplier_order 5783 5783 0 0.00 0 8714 17 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 544 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 1948 33 0 0
t$perf-norm:srv_make_invnt_saldo 556 556 0 0.00 0 3543 22 0 0
t$perf-norm:srv_make_money_saldo 826 826 0 0.00 0 1651 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 7 5 0 0
v_add_invoice_to_stock 10734 10372 362 3.37 0 4678 20 0 362
v_all_customers 38378 38378 0 0.00 0 19 0 0 0
v_all_suppliers 32226 32226 0 0.00 0 14 1 0 0
v_all_wares 42208 42208 0 0.00 0 107 1 0 0
v_cancel_adding_invoice 872 872 0 0.00 0 3931 12 0 0
v_cancel_client_order 3764 3764 0 0.00 0 2692 5 0 0
v_cancel_customer_prepayment 1564 1564 0 0.00 0 4690 12 0 0
v_cancel_customer_reserve 1358 1358 0 0.00 0 4678 12 0 0
v_cancel_payment_to_supplier 882 882 0 0.00 0 4518 8 0 0
v_cancel_supplier_invoice 874 856 18 2.05 0 3837 18 0 18
v_cancel_supplier_order 904 904 0 0.00 0 371 4 0 0
v_cancel_write_off 2044 2044 0 0.00 0 2702 15 0 0
v_max_id_clo_ord 5783 5783 0 0.00 0 4115 2 0 0
v_max_id_clo_res 1079 1079 0 0.00 0 2559 11 0 0
v_max_id_ord_sup 5731 5731 0 0.00 0 4649 6 0 0
v_max_non_paid_invoice 1559 1559 0 0.00 0 521 2 0 0
v_max_non_paid_realizn 3971 3971 0 0.00 0 7009 6 0 0
v_min_id_clo_ord 5783 5783 0 0.00 0 3153 5 0 0
v_min_id_clo_res 1079 1079 0 0.00 0 3994 11 0 0
v_min_id_ord_sup 5731 5731 0 0.00 0 6921 6 0 0
v_min_non_paid_invoice 6158 6158 0 0.00 0 7518 21 0 0
v_min_non_paid_realizn 7386 7386 0 0.00 0 7772 17 0 0
v_our_firm 10660 10660 0 0.00 0 19 1 0 0
v_reserve_write_off 20638 20638 0 0.00 0 4696 7 0 0
x_lock_depdocs_on_canc_invoice 11618 11618 0 0.00 0 17822 107 0 0
x_lock_depdocs_on_canc_sup_ord 452 452 0 0.00 0 12933 315 0 0
x_make_qstorno_1000_1200 5783 5783 0 0.00 1 63110 1098 0 0
x_make_qstorno_1200_2000 5746 5746 0 0.00 3 90120 3053 0 0
x_make_qstorno_2100_3300 483232 483232 0 0.00 0 97258 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-10-2021 16:41:03 to 15-10-2021 16:41:07.

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:07:58 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:08:59 45355 8192000000 9904973088 10216976384 979960224 10.684 100 92 184 0 1011071632 6363104 24748304
14:10:52 30888 8192000000 9957608992 10281267200 1035957072 11.226 100 87 174 2 1066871584 8701024 22213488
14:12:28 11569 8192000000 9987040112 10326323200 1071831280 11.570 100 75 150 0 1100346928 9901600 18614048
14:13:42 7804 8192000000 10042503680 10390855680 1125797232 12.082 100 84 168 0 1158070848 11911952 20361664
14:14:54 8888 8192000000 10092527472 10456752128 1179838272 12.589 100 78 156 0 1209362736 11626608 17897856
14:16:04 4098 8192000000 10140095152 10512134144 1229954016 13.054 100 74 148 1 1258408144 12211776 16242352
14:17:09 4203 8192000000 10185548000 10565836800 1267472272 13.398 100 79 157 2 1298207936 12723616 18012048
14:18:16 1804 8192000000 10213147328 10595110912 1295195120 13.652 100 84 167 0 1327007680 13462080 18350480
14:19:19 17236 8192000000 10225946128 10613714944 1308734512 13.775 100 69 138 3 1335411744 10587216 16090016
14:20:38 5861 8192000000 10211089152 10621816832 1316582400 13.846 100 36 72 1 1332604704 7745456 8276848
14:21:44 18112 8192000000 10211693952 10629029888 1319745984 13.874 100 32 64 2 1337534592 10625424 7163184
14:23:06 12519 8192000000 10208427312 10631106560 1322745296 13.902 100 26 52 0 1334498480 6322752 5430432
14:24:22 8522 8192000000 10220927840 10641448960 1331690128 13.982 100 21 42 0 1342477776 6352288 4435360
14:25:32 9533 8192000000 10222265280 10640838656 1322375312 13.898 99 22 44 1 1337646768 9875936 5395520
14:26:42 13372 8192000000 10212779152 10625748992 1285361696 13.562 96 34 67 1 1299043408 5774224 7907488
14:28:01 18724 8192000000 10208468016 10629484544 1281278944 13.525 96 18 36 2 1291016608 5596208 4141456
14:29:20 6172 8192000000 10181386752 10587971584 1253287744 13.268 96 36 72 0 1268200352 6526832 8385776
14:30:26 9135 8192000000 10178894416 10586112000 1274177840 13.460 99 22 43 1 1285414576 6465792 4770944
14:31:42 36894 8192000000 10132464928 10529443840 1208892048 12.859 96 21 42 0 1218928768 5077072 4959648
14:33:31 9459 8192000000 10148869824 10547609600 1251676624 13.254 99 24 46 3 1262204096 4476656 6050816
14:34:42 9885 8192000000 10176676944 10585702400 1285459856 13.563 100 23 46 0 1296470784 5576160 5434768
14:35:53 10877 8192000000 10202710576 10608291840 1301032224 13.705 100 27 54 3 1312929632 4920112 6977296
14:37:07 59361 8192000000 10229890576 10640097280 1328685232 13.955 100 20 40 1 1337559040 4373664 4500144
14:39:21 33947 8192000000 10258860752 10674466816 1350247456 14.150 100 45 90 2 1367614624 7337152 10030016
14:40:59 3229 8192000000 10281960064 10696028160 1373840752 14.361 100 58 115 0 1397380464 10141984 13397728
14:42:12 35089 8192000000 10297815808 10716123136 1387591136 14.484 100 53 104 1 1412990816 13178832 12220848
14:44:05 32568 8192000000 10306121440 10734129152 1395467056 14.555 100 46 90 1 1418274976 11841696 10966224
14:45:44 7976 8192000000 10330127584 10749390848 1407112384 14.658 100 64 127 1 1438370128 16256928 15000816
14:47:04 4402 8192000000 10339332528 10763321344 1411968320 14.701 100 67 133 2 1448601840 20622640 16010880
14:48:12 3086 8192000000 10354259872 10775064576 1423094176 14.800 100 74 146 1 1466009472 25456848 17458448
14:49:21 7014 8192000000 10352004944 10776010752 1422980800 14.799 100 66 132 1 1458802400 19479264 16342336
14:50:32 6561 8192000000 10346003840 10765926400 1411093344 14.694 99 66 133 1 1452358352 24834960 16430048
14:51:42 3302 8192000000 10351322512 10769498112 1410652400 14.690 100 69 138 1 1457135328 29487600 16995328
14:52:48 9062 8192000000 10353305424 10778218496 1417004304 14.746 100 50 99 1 1459070048 28892688 13173056
14:54:03 3306 8192000000 10341016704 10756669440 1391841264 14.522 99 68 135 0 1446233936 36382368 18010304
14:55:12 9552 8192000000 10351795872 10777788416 1407373120 14.661 100 51 100 4 1454176896 33509824 13293952
14:56:32 11965 8192000000 10322594864 10744852480 1362988576 14.264 98 44 89 1 1414464432 39339616 12136240
14:57:57 7606 8192000000 10311164576 10742358016 1376423504 14.385 100 38 76 1 1420481584 34075408 9982672
14:59:25 10916 8192000000 10295673168 10720063488 1365958544 14.291 100 35 70 0 1401061040 26226240 8876256
15:00:41 7253 8192000000 10292851984 10716700672 1360870512 14.245 100 39 76 2 1398797456 28031232 9895712
15:01:52 8930 8192000000 10306409456 10731786240 1358908960 14.228 99 36 72 1 1401841520 33646768 9285792
15:03:13 26964 8192000000 10290728000 10728431616 1350008016 14.148 99 23 46 1 1389992624 33769696 6214912
15:04:46 43772 8192000000 10284220928 10724290560 1342503168 14.080 99 17 34 0 1382587072 34844352 5239552
15:06:39 18131 8192000000 10263469632 10702315520 1336232176 14.023 98 47 93 1 1358050048 10898624 10919248
15:08:06 120478 8192000000 10267927168 10710949888 1359650960 14.234 99 34 68 0 1378687280 11033120 8003200
15:11:08 75972 8192000000 10280532384 10723545088 1340692784 14.064 97 26 53 0 1358143648 11825616 5625248
15:13:33 83625 8192000000 10283504544 10721771520 1329888416 13.966 96 33 65 0 1346721312 8899888 7933008
15:16:05 54984 8192000000 10312593664 10752630784 1380804800 14.424 99 54 107 4 1412277936 17186112 14287024
15:18:13 52660 8192000000 10287551808 10740822016 1385772272 14.468 100 10 20 0 1402596528 14329552 2494704
15:20:07 67325 8192000000 10299064864 10750238720 1386517184 14.475 99 16 32 1 1405970864 15033008 4420672
15:22:31 75517 8192000000 10287556528 10735976448 1381196960 14.427 99 29 58 1 1398595120 11207888 6190272
15:24:47 64969 8192000000 10294650928 10749169664 1383693536 14.450 99 24 49 2 1405914064 16749184 5471344
15:26:59 56889 8192000000 10298821152 10760896512 1398475168 14.581 100 16 32 0 1415349552 13429088 3445296
15:28:59 67715 8192000000 10303145088 10768879616 1408531952 14.671 100 21 40 2 1421799904 8885776 4382176
15:31:08 62858 8192000000 10307030368 10774220800 1407473888 14.661 100 20 40 1 1428047136 16089680 4483568
15:34:21 30640 8192000000 10316453232 10772717568 1401896208 14.612 100 14 28 2 1414560304 9673408 2990688
15:35:55 38866 8192000000 10308036240 10764713984 1404639984 14.636 100 26 52 1 1420883008 10379200 5863824
15:37:35 84777 8192000000 10295669584 10770661376 1364034560 14.274 97 10 20 1 1382476704 16501024 1941120
15:40:03 51727 8192000000 10298359360 10753855488 1353272224 14.177 97 24 47 0 1371130416 13017360 4840832
15:41:59 50108 8192000000 10289441056 10737750016 1340463136 14.062 96 21 41 1 1352841872 8197264 4181472
15:44:13 100921 8192000000 10271105680 10726477824 1296318800 13.662 94 13 26 0 1313516992 14857296 2340896
15:47:14 58998 8192000000 10254225248 10707443712 1307111312 13.760 96 14 28 0 1325637904 15478864 3047728
15:49:16 42524 8192000000 10275221840 10717433856 1366197072 14.293 100 58 116 0 1392844784 12970640 13677072
15:51:13 44851 8192000000 10256371904 10687422464 1300567264 13.700 96 34 67 0 1320225984 12253376 7405344
15:53:01 55597 8192000000 10262925904 10720124928 1346902608 14.120 99 18 36 1 1364418544 13894960 3620976
15:55:03 55493 8192000000 10263281664 10714771456 1353029088 14.175 99 31 62 2 1374555600 13721520 7804992
15:56:59 84672 8192000000 10253271712 10703777792 1337190832 14.032 98 14 28 0 1356738096 16030208 3517056
15:59:26 66765 8192000000 10263861520 10717560832 1345358976 14.106 99 8 16 0 1358547536 11073440 2115120
16:01:52 58269 8192000000 10242603296 10692583424 1311567216 13.800 96 28 56 1 1328087632 10269776 6250640
16:03:57 41377 8192000000 10261594896 10701701120 1348438944 14.133 100 28 55 0 1366691184 12404784 5847456
16:05:44 26825 8192000000 10264141824 10702995456 1351530000 14.161 100 32 64 0 1368329856 9975568 6824288
16:07:16 61588 8192000000 10277600144 10732978176 1358728352 14.226 99 22 43 0 1375868928 12656608 4483968
16:09:20 48521 8192000000 10291357376 10746499072 1370520176 14.332 99 18 35 1 1391945808 17812352 3613280
16:11:09 21821 8192000000 10308910464 10742996992 1380054448 14.417 99 59 117 2 1402512160 9724288 12733424
16:12:34 39129 8192000000 10308749168 10750328832 1387114256 14.480 100 35 70 2 1405831376 11228944 7488176
16:15:06 185411 8192000000 10298665408 10757148672 1361056288 14.247 97 23 47 3 1391545872 25119040 5370544
16:19:12 34035 8192000000 10294821728 10744987648 1361743376 14.253 97 16 33 1 1380911760 16038576 3129808
16:20:46 49642 8192000000 10296069776 10756300800 1379940736 14.416 99 12 23 0 1397810896 15631904 2238256
16:22:51 72786 8192000000 10282715472 10732011520 1349908960 14.147 97 17 34 0 1360117216 6125184 4083072
16:25:04 50660 8192000000 10277261056 10742038528 1368233744 14.311 99 16 32 0 1385019696 12745184 4040768
16:26:55 54164 8192000000 10301415744 10758393856 1401070848 14.605 100 17 34 0 1416589952 11267424 4251680
16:29:26 74142 8192000000 10296315872 10743377920 1348064832 14.130 97 16 32 0 1366236448 14650272 3521344
16:31:40 61751 8192000000 10298798544 10731945984 1342153104 14.077 97 26 52 1 1363280192 15048432 6078656
16:33:44 54984 8192000000 10284670176 10717487104 1317104528 13.850 96 17 34 0 1330193776 9459344 3629904
16:35:41 65329 8192000000 10264883520 10693390336 1289927456 13.604 94 23 46 0 1304520992 9857792 4735744
16:37:52 22972 8192000000 9223314016 9484308480 372323424 4.347 27 4 7 0 384998304 11840496 834384

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 15-10-2021 16:41:09 to 15-10-2021 16:41:24.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 181 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-10-2021 16:41:25 to 15-10-2021 16:41:27.

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          245833
MON$OLDEST_ACTIVE               246511
MON$OLDEST_SNAPSHOT             246511
MON$NEXT_TRANSACTION            246511
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                       2031072
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.33520 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33520 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33520 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33520 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 ---
:370: 
:371: mscs-test-161.budzdorov.ru    Fri Oct 15 16:38:40 2021
:372:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:373: 
--- end of diff output ---

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

15.10.21 16:41:28. 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-10-15 16:41:30 No severe PSQL-related problems occured
15.10.21 16:41:31. End of report.