Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.03.2021 13:28
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 df9f488d08424df294c459d6c283e9c3
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-03-04 16:07
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.26
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 17119028 kB
MemAvailable 31659440 kB
Buffers 0 kB
Cached 14586644 kB
SwapCached 0 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.0
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-03-06 13:26:57
DTS_END                         2021-03-06 13:26:57.5380
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(68:17)
IP                              ::1/58480
TRN_ID                          229353
ATT_ID                          673
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: 8745.98 20414 1049518
customer order: creation 167.02 384 20042
customer order: refuse 13.73 302 1647
order to supplier: creation 43.73 1395 5248
order to supplier: removal 3.42 101594 410
invoice (draft): creation 45.11 3596 5413
invoice (draft): removal 49.59 883 5951
invoice accept: apply 40.98 17644 4917
invoice accept: cancel 43.76 853 5251
customer reserve: creation 3774.08 173 452890
customer reserve: removal 4284.91 64 514189
realization accept: apply 81.80 164 9816
realization accept: cancel 72.66 109 8719
payment from customer: creation 58.40 121 7008
payment from customer: removal 5.63 87 675
payment to supplier: creation 47.27 120 5672
payment to supplier: removal 3.21 66 385
service: total inventory turnovers 3.35 3794 402
service: total monetary turnovers 6.50 476 780
service: aggregate perf. data 0.83 16938 99
service: refresh index statistics 0.03 259508 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-03-2021 13:28:28 to 06-03-2021 13:28:30.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1530
TEST_TIME 2 3021
TEST_TIME 3 3846
TEST_TIME 4 4284
TEST_TIME 5 4856
TEST_TIME 6 5205
TEST_TIME 7 5288
TEST_TIME 8 5783
TEST_TIME 9 5818
TEST_TIME 10 5912
TEST_TIME 11 6332
TEST_TIME 12 6318
TEST_TIME 13 6584
TEST_TIME 14 6682
TEST_TIME 15 6856
TEST_TIME 16 7530
TEST_TIME 17 7889
TEST_TIME 18 8325
TEST_TIME 19 8411
TEST_TIME 20 8641
TEST_TIME 21 9352
TEST_TIME 22 9590
TEST_TIME 23 9742
TEST_TIME 24 9945
TEST_TIME 25 10011
TEST_TIME 26 10551
TEST_TIME 27 10656
TEST_TIME 28 10818
TEST_TIME 29 10576
TEST_TIME 30 10601
TEST_TIME 31 10498
TEST_TIME 32 10448
TEST_TIME 33 10344
TEST_TIME 34 10331
TEST_TIME 35 10156
TEST_TIME 36 10019
TEST_TIME 37 9842
TEST_TIME 38 9889
TEST_TIME 39 9784
TEST_TIME 40 9817
TEST_TIME 41 9680
TEST_TIME 42 9711
TEST_TIME 43 9681
TEST_TIME 44 9595
TEST_TIME 45 9524
TEST_TIME 46 9506
TEST_TIME 47 9437
TEST_TIME 48 9407
TEST_TIME 49 9432
TEST_TIME 50 9488
TEST_TIME 51 9620
TEST_TIME 52 9567
TEST_TIME 53 9651
TEST_TIME 54 9651
TEST_TIME 55 9588
TEST_TIME 56 9706
TEST_TIME 57 9681
TEST_TIME 58 9750
TEST_TIME 59 9658
TEST_TIME 60 9534
TEST_TIME 61 9435
TEST_TIME 62 9373
TEST_TIME 63 9255
TEST_TIME 64 9239
TEST_TIME 65 9224
TEST_TIME 66 9195
TEST_TIME 67 9163
TEST_TIME 68 9183
TEST_TIME 69 9214
TEST_TIME 70 9304
TEST_TIME 71 9412
TEST_TIME 72 9366
TEST_TIME 73 9344
TEST_TIME 74 9333
TEST_TIME 75 9308
TEST_TIME 76 9290
TEST_TIME 77 9415
TEST_TIME 78 9432
TEST_TIME 79 9327
TEST_TIME 80 9227
TEST_TIME 81 9195
TEST_TIME 82 9168
TEST_TIME 83 9147
TEST_TIME 84 9084
TEST_TIME 85 9074
TEST_TIME 86 9049
TEST_TIME 87 9027
TEST_TIME 88 9018
TEST_TIME 89 9074
TEST_TIME 90 9059
TEST_TIME 91 9144
TEST_TIME 92 9177
TEST_TIME 93 9150
TEST_TIME 94 9133
TEST_TIME 95 9082
TEST_TIME 96 9065
TEST_TIME 97 9009
TEST_TIME 98 8976
TEST_TIME 99 8953
TEST_TIME 100 8943
TEST_TIME 101 8915
TEST_TIME 102 8895
TEST_TIME 103 8887
TEST_TIME 104 8850
TEST_TIME 105 8884
TEST_TIME 106 8956
TEST_TIME 107 8962
TEST_TIME 108 8957
TEST_TIME 109 8969
TEST_TIME 110 8929
TEST_TIME 111 9019
TEST_TIME 112 9009
TEST_TIME 113 8933
TEST_TIME 114 8887
TEST_TIME 115 8847
TEST_TIME 116 8793
TEST_TIME 117 8815
TEST_TIME 118 8782
TEST_TIME 119 8749
TEST_TIME 120 8748

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 06-03-2021 13:28:31 to 06-03-2021 13:28:35.

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 1468043 1468043 0 0.00 0 14798 6 0 0
doc_list_biud 1468043 1468043 0 0.00 0 20608 29 0 0
sp_add_invoice_to_stock 5018 4917 101 2.01 27 168075 17645 0 101
sp_cancel_adding_invoice 5251 5251 0 0.00 5 58492 854 0 0
sp_cancel_client_order 1647 1647 0 0.00 4 8330 303 0 0
sp_cancel_customer_reserve 514189 514189 0 0.00 1 20611 65 0 0
sp_cancel_pay_from_customer 675 675 0 0.00 2 10490 87 0 0
sp_cancel_pay_to_supplier 385 385 0 0.00 3 6582 66 0 0
sp_cancel_supplier_invoice 5965 5951 14 0.23 2 19728 884 0 14
sp_cancel_supplier_order 410 410 0 0.00 13 926331 101595 0 0
sp_cancel_write_off 8719 8719 0 0.00 1 8133 110 0 0
sp_client_order 20042 20042 0 0.00 4 33758 385 0 0
sp_customer_reserve 452890 452890 0 0.00 0 73650 174 0 0
sp_fill_shopping_cart 30670 30670 0 0.00 1 14612 44 0 0
sp_fill_shopping_cart_clo_res 452885 452885 0 0.00 0 24359 15 0 0
sp_get_clo_for_invoice 4912 4912 0 0.00 0 39099 407 0 0
sp_kill_cost_storno 15030 15030 0 0.00 0 6569 53 0 0
sp_kill_qstorno_ret_qs2qd 1043458 1043458 0 0.00 0 19724 35 0 0
sp_kill_qty_storno 550895 550895 0 0.00 0 20608 76 0 0
sp_lock_selected_doc 20316 20316 0 0.00 0 6570 5 0 0
sp_make_cost_storno 27408 27408 0 0.00 0 14785 51 0 0
sp_multiply_rows_for_pdistr 27408 27408 0 0.00 0 7899 29 0 0
sp_multiply_rows_for_qdistr 20042 20042 0 0.00 0 33748 214 0 0
sp_pay_from_customer 7008 7008 0 0.00 4 14050 121 0 0
sp_pay_to_supplier 5672 5672 0 0.00 5 17427 121 0 0
sp_payment_common 12680 12680 0 0.00 1 6628 19 0 0
sp_qd_handle_on_cancel_clo 1647 1647 0 0.00 0 8322 281 0 0
sp_qd_handle_on_invoice_upd_sts 10163 10163 0 0.00 0 17940 356 0 0
sp_qd_handle_on_reserve_upd_sts 9816 9816 0 0.00 0 20321 60 0 0
sp_reserve_write_off 9816 9816 0 0.00 6 20349 165 0 0
sp_supplier_invoice 5413 5413 0 0.00 7 95354 3597 0 0
sp_supplier_order 5248 5248 0 0.00 6 28836 1396 0 0
srv_aggregate_perf_data 99 99 0 0.00 2422 262338 16938 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1762 134941 45120 0 0
srv_find_qd_qs_mism 1002618 1002618 0 0.00 0 12491 6 0 0
srv_make_invnt_saldo 402 402 0 0.00 63 97620 3795 0 0
srv_make_money_saldo 780 780 0 0.00 1 15619 477 0 0
srv_recalc_idx_stat 4 4 0 0.00 163311 431314 259509 0 0
t$perf-abend:sp_add_invoice_to_stock 101 101 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4917 4917 0 0.00 0 11226 161 0 0
t$perf-norm:sp_cancel_adding_invoice 401 401 0 0.00 1 2308 67 0 0
t$perf-norm:sp_cancel_client_order 1647 1647 0 0.00 0 3455 10 0 0
t$perf-norm:sp_cancel_customer_reserve 681 681 0 0.00 0 2156 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 675 675 0 0.00 0 1813 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 385 385 0 0.00 0 2228 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 415 415 0 0.00 0 1978 15 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 19582 1346 0 0
t$perf-norm:sp_cancel_write_off 981 981 0 0.00 0 13779 20 0 0
t$perf-norm:sp_client_order 20042 20042 0 0.00 0 10109 16 0 0
t$perf-norm:sp_customer_reserve 993 993 0 0.00 0 6550 13 0 0
t$perf-norm:sp_pay_from_customer 7008 7008 0 0.00 0 16338 11 0 0
t$perf-norm:sp_pay_to_supplier 5672 5672 0 0.00 0 7102 11 0 0
t$perf-norm:sp_reserve_write_off 9816 9816 0 0.00 0 10576 9 0 0
t$perf-norm:sp_supplier_invoice 5413 5413 0 0.00 0 4501 16 0 0
t$perf-norm:sp_supplier_order 5248 5248 0 0.00 0 6625 16 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 232 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 257 5 0 0
t$perf-norm:srv_make_invnt_saldo 402 402 0 0.00 0 1160 8 0 0
t$perf-norm:srv_make_money_saldo 780 780 0 0.00 0 800 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 2 0 0
v_add_invoice_to_stock 10020 9818 202 2.01 0 5174 14 0 202
v_all_customers 36214 36214 0 0.00 0 19 1 0 0
v_all_suppliers 29272 29272 0 0.00 0 20 1 0 0
v_all_wares 40074 40074 0 0.00 0 24 1 0 0
v_cancel_adding_invoice 802 802 0 0.00 0 373 6 0 0
v_cancel_client_order 3294 3294 0 0.00 0 2487 5 0 0
v_cancel_customer_prepayment 1350 1350 0 0.00 0 6168 12 0 0
v_cancel_customer_reserve 1362 1362 0 0.00 0 2486 4 0 0
v_cancel_payment_to_supplier 770 770 0 0.00 0 332 3 0 0
v_cancel_supplier_invoice 858 830 28 3.26 0 3655 25 0 28
v_cancel_supplier_order 820 820 0 0.00 0 2453 7 0 0
v_cancel_write_off 1962 1962 0 0.00 0 519 11 0 0
v_max_id_clo_ord 5247 5247 0 0.00 0 759 1 0 0
v_max_id_clo_res 993 993 0 0.00 0 9855 28 0 0
v_max_id_ord_sup 5386 5386 0 0.00 0 5881 7 0 0
v_max_non_paid_invoice 1669 1669 0 0.00 0 633 2 0 0
v_max_non_paid_realizn 3833 3833 0 0.00 0 4041 5 0 0
v_min_id_clo_ord 5247 5247 0 0.00 0 3223 6 0 0
v_min_id_clo_res 993 993 0 0.00 0 4229 17 0 0
v_min_id_ord_sup 5386 5386 0 0.00 0 5016 7 0 0
v_min_non_paid_invoice 5672 5672 0 0.00 0 4439 12 0 0
v_min_non_paid_realizn 7008 7008 0 0.00 0 4186 14 0 0
v_our_firm 10210 10210 0 0.00 0 83 1 0 0
v_reserve_write_off 19624 19624 0 0.00 0 2495 4 0 0
x_lock_depdocs_on_canc_invoice 10787 10787 0 0.00 0 24453 113 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 20040 372 0 0
x_make_qstorno_1000_1200 5248 5248 0 0.00 2 28805 1322 0 0
x_make_qstorno_1200_2000 5413 5413 0 0.00 3 95347 3422 0 0
x_make_qstorno_2100_3300 452890 452890 0 0.00 0 73649 156 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-03-2021 13:28:36 to 06-03-2021 13:28:40.

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
10:57:01 2 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
10:58:02 44797 8192000000 9746426400 10046201856 811705872 9.015 100 89 178 1 841939504 8368416 21865216
10:59:50 29750 8192000000 9789638640 10100285440 861964288 9.520 100 91 182 0 897448896 14062832 21421776
11:01:25 18762 8192000000 9822450480 10149498880 892390656 9.823 100 81 162 2 936367696 26051680 17925360
11:02:49 15923 8192000000 9883967648 10221010944 934743104 10.241 100 84 168 0 997356208 44197696 18415408
11:04:10 9385 8192000000 9949607328 10299473920 974893712 10.634 100 78 156 0 1061522048 70673760 15954576
11:05:22 4818 8192000000 9980577504 10342109184 1012029824 10.995 100 70 140 0 1086092624 59817664 14245136
11:06:29 2585 8192000000 10002576992 10371239936 1042953152 11.293 100 71 143 1 1118174032 60406656 14814224
11:07:32 1792 8192000000 10036310064 10408534016 1063531488 11.490 100 86 173 1 1146640688 65821664 17287536
11:08:35 12881 8192000000 9990654032 10383523840 1072087760 11.572 100 35 70 2 1103825936 23039728 8698448
11:09:50 5472 8192000000 10001006912 10398736384 1081826672 11.665 100 27 54 1 1124404208 37235904 5341632
11:10:57 8277 8192000000 9994588816 10397818880 1093428960 11.775 100 23 46 1 1117989472 19083648 5476864
11:12:08 53956 8192000000 10007173664 10428112896 1095860496 11.798 100 24 47 1 1121781200 18867504 7053200
11:14:03 6702 8192000000 10022373232 10435428352 1101686016 11.854 100 39 78 1 1150186624 39904736 8595872
11:15:11 10913 8192000000 10014549792 10429054976 1105782256 11.892 100 31 62 1 1127624560 14995200 6847104
11:16:22 4368 8192000000 10022468496 10419642368 1099536720 11.833 100 46 91 3 1128490128 18901824 10051584
11:17:33 17772 8192000000 9993785984 10405400576 1090649456 11.749 99 20 38 2 1116769680 22114352 4005872
11:18:52 6622 8192000000 9955884320 10341871616 1029059168 11.159 97 31 61 2 1064769872 28198352 7512352
11:19:59 6809 8192000000 9950418992 10337746944 1020835952 11.080 99 33 66 1 1058854960 31007136 7011872
11:21:07 5581 8192000000 9940862448 10326900736 1033063952 11.198 100 37 72 1 1056095520 14601664 8429904
11:22:14 21341 8192000000 9943245264 10348756992 1004937104 10.926 97 22 44 0 1035601744 26212304 4452336
11:23:36 4981 8192000000 9967340608 10349608960 1039117632 11.256 100 44 84 2 1066528592 17636560 9774400
11:24:41 8650 8192000000 9971359968 10367229952 1058406384 11.441 100 40 80 2 1089765584 21860144 9499056
11:25:50 4425 8192000000 9968204704 10365177856 1065305008 11.507 100 45 90 1 1090734320 15819024 9610288
11:26:57 43940 8192000000 10004018336 10399674368 1077475168 11.623 100 32 64 2 1108859488 23830976 7553344
11:28:53 10171 8192000000 10058384672 10458324992 1105829072 11.893 100 56 112 0 1172979008 54928896 12221040
11:30:06 15409 8192000000 10072444144 10478899200 1122345184 12.049 100 44 86 0 1190552576 59019200 9188192
11:31:25 8345 8192000000 10104679680 10505342976 1133768960 12.157 100 48 95 0 1211527600 67139024 10619616
11:32:37 14832 8192000000 10131214592 10551463936 1143275968 12.246 100 49 98 1 1241716528 87175888 11264672
11:34:02 7990 8192000000 10172479120 10584363008 1157171776 12.377 100 61 120 1 1285194656 115381808 12641072
11:35:15 12537 8192000000 10208269920 10625097728 1168049456 12.479 100 56 111 0 1309424752 128859072 12516224
11:36:33 9531 8192000000 10225968096 10650480640 1184444880 12.632 100 47 94 1 1329854048 134476128 10933040
11:37:46 4179 8192000000 10247255920 10666348544 1192696096 12.708 100 64 127 1 1356734880 150062576 13976208
11:38:56 2470 8192000000 10278742992 10694598656 1181938944 12.608 100 60 117 2 1377728784 182780496 13009344
11:40:01 4353 8192000000 10318470416 10730242048 1191686128 12.699 100 79 158 1 1423659024 214014880 17958016
11:41:15 3871 8192000000 10343596704 10749837312 1152352096 12.332 99 78 157 1 1441489408 271036080 18101232
11:42:27 1759 8192000000 10337771280 10742521856 1158999296 12.394 100 83 166 0 1436510576 257696288 19814992
11:43:31 3761 8192000000 10317437856 10735116288 1139111088 12.207 99 64 128 0 1422361136 268143664 15106384
11:44:40 11034 8192000000 10343197856 10781405184 1138065136 12.197 99 45 90 1 1451186672 302212896 10908640
11:45:54 8513 8192000000 10319575472 10760486912 1143317232 12.247 100 37 73 1 1435019968 282137936 9564800
11:47:08 7835 8192000000 10308857952 10734206976 1125005504 12.074 100 43 80 1 1405568352 269620320 10942528
11:48:18 21351 8192000000 10216694064 10644582400 1081950128 11.666 96 24 48 0 1287142784 199556400 5636256
11:49:41 29287 8192000000 10189287968 10623635456 1092111264 11.763 99 24 48 2 1274648000 176404128 6132608
11:51:26 31437 8192000000 10162516320 10618474496 1099434480 11.832 99 10 20 0 1274647504 172743248 2469776
11:52:59 20950 8192000000 10072833008 10570084352 1089831248 11.741 98 10 20 0 1169428288 77388016 2209024
11:54:22 73786 8192000000 10045080416 10464419840 1073226640 11.583 97 12 23 1 1106648272 30995344 2426288
11:56:38 24530 8192000000 10051942624 10473566208 1112333072 11.955 100 42 82 1 1155053184 33389424 9330688
11:58:02 43882 8192000000 10097642816 10552119296 1118610624 12.014 99 15 28 2 1184809728 63281248 2917856
11:59:47 134940 8192000000 10071833152 10528534528 1135159744 12.170 100 32 65 2 1190236352 48424224 6652384
12:03:39 36044 8192000000 10091499696 10548072448 1123454272 12.060 99 39 79 2 1192739872 59644816 9640784
12:05:17 104953 8192000000 10118522608 10606448640 1130187376 12.123 99 22 43 1 1195688736 60020112 5481248
12:09:37 36407 8192000000 10096937328 10574704640 1146204816 12.274 100 14 28 2 1200126640 51012384 2909440
12:11:19 40775 8192000000 10075475088 10550898688 1129974112 12.121 99 7 14 0 1161080048 29917056 1188880
12:13:00 67047 8192000000 10100256400 10568863744 1146610160 12.278 100 19 37 1 1192503360 42125008 3768192
12:15:08 43487 8192000000 10130209344 10580439040 1152397968 12.332 100 18 36 0 1221675552 65190352 4087232
12:16:53 45613 8192000000 10090405088 10590445568 1148193920 12.293 100 22 44 0 1230026128 76975024 4857184
12:18:44 52672 8192000000 10097471936 10597134336 1104424848 11.880 96 19 38 2 1172346528 63901680 4020000
12:21:45 44566 8192000000 10117220240 10607947776 1116154832 11.991 97 41 82 1 1196771616 72293984 8322800
12:23:30 73230 8192000000 10142521648 10585243648 1127953344 12.102 99 34 68 1 1178921232 44408272 6559616
12:25:56 75818 8192000000 10063733344 10528448512 1138598032 12.202 100 6 12 1 1179144992 39386176 1160784
12:28:16 24822 8192000000 10052190576 10514243584 1124351344 12.068 99 14 25 0 1162259664 34048560 3859760
12:29:41 87008 8192000000 10056898992 10525659136 1085807760 11.703 96 14 28 0 1135398832 46761552 2829520
12:32:09 42181 8192000000 10100867856 10545160192 1111714432 11.949 98 22 44 0 1159852096 43449424 4688240
12:33:59 57558 8192000000 10168213584 10625622016 1100354960 11.841 96 28 56 1 1195230384 88340656 6534768
12:35:57 68187 8192000000 10118831440 10643689472 1083795632 11.684 96 21 42 1 1183079664 94650864 4633168
12:38:54 30915 8192000000 10075770976 10510241792 1105270544 11.888 98 43 86 1 1145136672 31223904 8642224
12:40:26 16456 8192000000 10076972896 10496708608 1095175968 11.792 98 53 106 0 1156238320 50882224 10180128
12:41:48 76171 8192000000 10113983552 10554359808 1098856192 11.827 98 42 82 0 1177909632 70598528 8454912
12:44:09 95960 8192000000 10049293536 10520096768 1075543120 11.605 96 15 30 1 1118258704 39204848 3510736
12:47:11 52533 8192000000 10078288560 10548883456 1118685616 12.015 100 8 16 0 1183271616 63026528 1559472
12:49:04 66282 8192000000 10053912976 10544865280 1123893008 12.064 100 7 15 0 1169321984 43924464 1504512
12:51:14 52356 8192000000 10073723024 10510090240 1087285920 11.717 97 26 52 1 1168096176 75191440 5618816
12:53:33 57148 8192000000 10128087392 10566885376 1111230208 11.944 98 22 44 0 1182939216 67068784 4640224
12:55:39 57984 8192000000 10107190800 10551672832 1134314688 12.162 100 29 58 3 1202803744 61995488 6493568
12:57:46 73341 8192000000 10082664528 10555830272 1127440320 12.097 99 14 28 0 1185177600 55104304 2632976
12:59:59 15450 8192000000 10097078368 10537709568 1147924512 12.290 100 35 70 0 1193713296 38949744 6839040
13:01:15 97842 8192000000 10096351216 10583666688 1138474080 12.201 99 17 34 0 1205018432 63024208 3520144
13:04:18 72212 8192000000 10075163728 10538106880 1122954736 12.055 98 7 14 0 1157836976 33626896 1255344
13:07:03 85275 8192000000 10097333424 10529128448 1146516464 12.277 100 25 50 1 1190392160 38703696 5172000
13:09:30 50515 8192000000 10130419040 10579369984 1150891680 12.318 99 20 37 2 1210172336 54971712 4308944
13:11:21 108624 8192000000 10137278848 10660605952 1151970736 12.328 99 13 26 0 1257861952 102884496 3006720
13:14:10 36937 8192000000 10086171920 10529357824 1122171904 12.048 97 22 44 0 1153005888 26570720 4263264
13:15:49 63074 8192000000 10109553936 10541981696 1098098240 11.820 96 21 42 1 1168808032 66284512 4425280
13:17:56 102155 8192000000 10061052208 10544189440 1109486752 11.928 98 15 29 1 1149783520 37154064 3142704
13:20:53 56102 8192000000 10043364144 10500452352 1096887312 11.808 98 8 16 0 1140959216 42422816 1649088
13:22:54 55735 8192000000 10059138368 10546442240 1106583312 11.900 99 18 35 1 1185899696 75120320 4196064
13:24:52 68388 8192000000 10064025872 10513391616 1062733744 11.483 95 9 18 0 1106113376 41448784 1930848

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 06-03-2021 13:28:42 to 06-03-2021 13:28:56.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 101 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-03-2021 13:28:57 to 06-03-2021 13:28:59.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          229352
MON$OLDEST_ACTIVE               231596
MON$OLDEST_SNAPSHOT             231596
MON$NEXT_TRANSACTION            231596
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-30 07:30:08.6030 GMT
MON$PAGES                       2087968
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             795
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

Database statistics

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

Database validation

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

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

--- start of diff output ---
:125: 
:126: mscs-test-161.budzdorov.ru    Sat Mar  6 13:27:01 2021
:127:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:128: 
:129: 
:130: mscs-test-161.budzdorov.ru    Sat Mar  6 13:27:01 2021
:131:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:132: 
--- end of diff output ---

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

06.03.21 13:29:00. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-03-06 13:29:03 No severe PSQL-related problems occured
06.03.21 13:29:04. End of report.