Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.05.2022 04:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16330076 kB
MemAvailable 31495920 kB
Buffers 20 kB
Cached 15349956 kB
SwapCached 2556 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.2
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 2022-05-07 04:37:16
DTS_END                         2022-05-07 04:37:16.6070
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> X_MAKE_QSTORNO_1000_1200(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/41528
TRN_ID                          208763
ATT_ID                          620
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: 8859.28 31163 1063114
customer order: creation 153.50 505 18420
customer order: refuse 13.14 436 1577
order to supplier: creation 40.82 1415 4898
order to supplier: removal 3.26 174873 391
invoice (draft): creation 40.95 3315 4914
invoice (draft): removal 51.68 944 6202
invoice accept: apply 38.03 27594 4563
invoice accept: cancel 47.28 1195 5673
customer reserve: creation 3541.78 261 425013
customer reserve: removal 4662.27 96 559472
realization accept: apply 72.46 306 8695
realization accept: cancel 78.41 164 9409
payment from customer: creation 52.33 194 6279
payment from customer: removal 5.37 141 644
payment to supplier: creation 43.82 231 5258
payment to supplier: removal 3.23 151 387
service: total inventory turnovers 3.27 4764 392
service: total monetary turnovers 6.98 377 838
service: aggregate perf. data 0.71 19666 85
service: refresh index statistics 0.03 386627 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 07-05-2022 04:39:35 to 07-05-2022 04:39:38.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8452
TEST_TIME 2 6460
TEST_TIME 3 6820
TEST_TIME 4 7043
TEST_TIME 5 6704
TEST_TIME 6 7129
TEST_TIME 7 7098
TEST_TIME 8 7107
TEST_TIME 9 7056
TEST_TIME 10 7103
TEST_TIME 11 7090
TEST_TIME 12 7157
TEST_TIME 13 7002
TEST_TIME 14 6993
TEST_TIME 15 7027
TEST_TIME 16 7118
TEST_TIME 17 7332
TEST_TIME 18 7227
TEST_TIME 19 7236
TEST_TIME 20 7171
TEST_TIME 21 7556
TEST_TIME 22 8080
TEST_TIME 23 8664
TEST_TIME 24 8717
TEST_TIME 25 8785
TEST_TIME 26 8622
TEST_TIME 27 8719
TEST_TIME 28 9590
TEST_TIME 29 9797
TEST_TIME 30 10600
TEST_TIME 31 10990
TEST_TIME 32 10891
TEST_TIME 33 10880
TEST_TIME 34 11099
TEST_TIME 35 10898
TEST_TIME 36 10724
TEST_TIME 37 10463
TEST_TIME 38 10526
TEST_TIME 39 10330
TEST_TIME 40 10201
TEST_TIME 41 10198
TEST_TIME 42 10142
TEST_TIME 43 10125
TEST_TIME 44 10105
TEST_TIME 45 10009
TEST_TIME 46 9909
TEST_TIME 47 9857
TEST_TIME 48 9760
TEST_TIME 49 9758
TEST_TIME 50 9989
TEST_TIME 51 9876
TEST_TIME 52 9935
TEST_TIME 53 9926
TEST_TIME 54 9857
TEST_TIME 55 9894
TEST_TIME 56 9963
TEST_TIME 57 9916
TEST_TIME 58 9956
TEST_TIME 59 9882
TEST_TIME 60 9995
TEST_TIME 61 9981
TEST_TIME 62 9839
TEST_TIME 63 9750
TEST_TIME 64 9763
TEST_TIME 65 9734
TEST_TIME 66 9640
TEST_TIME 67 9560
TEST_TIME 68 9492
TEST_TIME 69 9410
TEST_TIME 70 9389
TEST_TIME 71 9368
TEST_TIME 72 9401
TEST_TIME 73 9345
TEST_TIME 74 9399
TEST_TIME 75 9412
TEST_TIME 76 9438
TEST_TIME 77 9456
TEST_TIME 78 9462
TEST_TIME 79 9598
TEST_TIME 80 9701
TEST_TIME 81 9643
TEST_TIME 82 9593
TEST_TIME 83 9542
TEST_TIME 84 9464
TEST_TIME 85 9418
TEST_TIME 86 9418
TEST_TIME 87 9357
TEST_TIME 88 9304
TEST_TIME 89 9258
TEST_TIME 90 9311
TEST_TIME 91 9254
TEST_TIME 92 9248
TEST_TIME 93 9207
TEST_TIME 94 9183
TEST_TIME 95 9213
TEST_TIME 96 9183
TEST_TIME 97 9192
TEST_TIME 98 9197
TEST_TIME 99 9194
TEST_TIME 100 9174
TEST_TIME 101 9213
TEST_TIME 102 9205
TEST_TIME 103 9195
TEST_TIME 104 9175
TEST_TIME 105 9236
TEST_TIME 106 9242
TEST_TIME 107 9247
TEST_TIME 108 9217
TEST_TIME 109 9155
TEST_TIME 110 9163
TEST_TIME 111 9206
TEST_TIME 112 9153
TEST_TIME 113 9122
TEST_TIME 114 9070
TEST_TIME 115 9023
TEST_TIME 116 9014
TEST_TIME 117 8980
TEST_TIME 118 8933
TEST_TIME 119 8958
TEST_TIME 120 8886

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 07-05-2022 04:39:39 to 07-05-2022 04:39:43.

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 1455478 1455478 0 0.00 0 25847 14 0 0
doc_list_biud 1455462 1455462 0 0.00 0 59544 43 0 0
sp_add_invoice_to_stock 4722 4563 159 3.36 57 213449 27595 0 159
sp_cancel_adding_invoice 5673 5673 0 0.00 6 94144 1196 0 0
sp_cancel_client_order 1577 1577 0 0.00 6 10943 437 0 0
sp_cancel_customer_reserve 559472 559472 0 0.00 1 59545 97 0 0
sp_cancel_pay_from_customer 644 644 0 0.00 4 5579 142 0 0
sp_cancel_pay_to_supplier 387 387 0 0.00 4 6573 151 0 0
sp_cancel_supplier_invoice 6215 6202 13 0.20 2 34937 945 0 13
sp_cancel_supplier_order 391 391 0 0.00 13 1474303 174874 0 0
sp_cancel_write_off 9409 9409 0 0.00 2 8195 165 0 0
sp_client_order 18420 18420 0 0.00 6 34937 505 0 0
sp_customer_reserve 425013 425013 0 0.00 1 90621 261 0 0
sp_fill_shopping_cart 28212 28212 0 0.00 2 11714 85 0 0
sp_fill_shopping_cart_clo_res 424981 424981 0 0.00 0 31061 22 0 0
sp_get_clo_for_invoice 4520 4520 0 0.00 0 26960 384 0 0
sp_kill_cost_storno 16113 16113 0 0.00 0 15383 77 0 0
sp_kill_qstorno_ret_qs2qd 1134946 1134946 0 0.00 0 59541 43 0 0
sp_kill_qty_storno 595934 595934 0 0.00 0 59544 97 0 0
sp_lock_selected_doc 18446 18446 0 0.00 0 8434 8 0 0
sp_make_cost_storno 24751 24751 0 0.00 0 17592 95 0 0
sp_multiply_rows_for_pdistr 24749 24749 0 0.00 0 15866 43 0 0
sp_multiply_rows_for_qdistr 18420 18420 0 0.00 0 21082 221 0 0
sp_pay_from_customer 6279 6279 0 0.00 5 22459 195 0 0
sp_pay_to_supplier 5258 5258 0 0.00 7 19543 231 0 0
sp_payment_common 11537 11537 0 0.00 2 22416 52 0 0
sp_qd_handle_on_cancel_clo 1577 1577 0 0.00 0 10934 340 0 0
sp_qd_handle_on_invoice_upd_sts 10190 10190 0 0.00 0 34929 499 0 0
sp_qd_handle_on_reserve_upd_sts 8693 8693 0 0.00 0 10571 68 0 0
sp_reserve_write_off 8695 8695 0 0.00 9 23433 307 0 0
sp_supplier_invoice 4914 4914 0 0.00 11 91568 3315 0 0
sp_supplier_order 4898 4898 0 0.00 8 67538 1416 0 0
srv_aggregate_perf_data 85 85 0 0.00 2734 143322 19667 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 851 123446 39638 0 0
srv_find_qd_qs_mism 1019433 1019433 0 0.00 0 25846 10 0 0
srv_make_invnt_saldo 392 392 0 0.00 67 108368 4765 0 0
srv_make_money_saldo 838 838 0 0.00 1 19287 377 0 0
srv_recalc_idx_stat 4 4 0 0.00 62034 585106 386627 0 0
t$perf-abend:sp_add_invoice_to_stock 159 159 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4564 4564 0 0.00 1 13184 971 0 0
t$perf-norm:sp_cancel_adding_invoice 394 394 0 0.00 1 6578 475 0 0
t$perf-norm:sp_cancel_client_order 1577 1577 0 0.00 0 5201 39 0 0
t$perf-norm:sp_cancel_customer_reserve 596 596 0 0.00 0 7675 46 0 0
t$perf-norm:sp_cancel_pay_from_customer 644 644 0 0.00 0 2109 19 0 0
t$perf-norm:sp_cancel_pay_to_supplier 387 387 0 0.00 0 798 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 320 320 0 0.00 0 959 43 0 0
t$perf-norm:sp_cancel_supplier_order 391 391 0 0.00 0 102534 7000 0 0
t$perf-norm:sp_cancel_write_off 933 933 0 0.00 0 4762 32 0 0
t$perf-norm:sp_client_order 18421 18421 0 0.00 0 8141 42 0 0
t$perf-norm:sp_customer_reserve 916 916 0 0.00 0 3344 16 0 0
t$perf-norm:sp_pay_from_customer 6279 6279 0 0.00 0 10293 27 0 0
t$perf-norm:sp_pay_to_supplier 5258 5258 0 0.00 0 10293 23 0 0
t$perf-norm:sp_reserve_write_off 8695 8695 0 0.00 0 9805 23 0 0
t$perf-norm:sp_supplier_invoice 4914 4914 0 0.00 0 15120 39 0 0
t$perf-norm:sp_supplier_order 4898 4898 0 0.00 0 19367 40 0 0
t$perf-norm:srv_aggregate_perf_data 85 85 0 0.00 0 408 29 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 19 3 0 0
t$perf-norm:srv_make_invnt_saldo 392 392 0 0.00 0 1122 20 0 0
t$perf-norm:srv_make_money_saldo 838 838 0 0.00 0 2297 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 9 149 56 0 0
v_add_invoice_to_stock 9344 9026 318 3.40 0 15192 29 0 318
v_all_customers 33498 33498 0 0.00 0 128 4 0 0
v_all_suppliers 27408 27408 0 0.00 0 124 4 0 0
v_all_wares 36825 36825 0 0.00 0 102 4 0 0
v_cancel_adding_invoice 788 788 0 0.00 0 189 12 0 0
v_cancel_client_order 3154 3154 0 0.00 0 10356 22 0 0
v_cancel_customer_prepayment 1288 1288 0 0.00 0 5564 23 0 0
v_cancel_customer_reserve 1192 1192 0 0.00 0 2654 14 0 0
v_cancel_payment_to_supplier 774 774 0 0.00 0 1584 14 0 0
v_cancel_supplier_invoice 666 640 26 3.90 0 4883 38 0 26
v_cancel_supplier_order 782 782 0 0.00 0 358 9 0 0
v_cancel_write_off 1866 1866 0 0.00 0 3785 25 0 0
v_max_id_clo_ord 4891 4891 0 0.00 0 112 5 0 0
v_max_id_clo_res 916 916 0 0.00 0 969 14 0 0
v_max_id_ord_sup 4905 4905 0 0.00 0 2202 5 0 0
v_max_non_paid_invoice 1347 1347 0 0.00 0 3807 10 0 0
v_max_non_paid_realizn 3333 3333 0 0.00 0 5410 10 0 0
v_min_id_clo_ord 4889 4889 0 0.00 0 2674 9 0 0
v_min_id_clo_res 916 916 0 0.00 0 5834 22 0 0
v_min_id_ord_sup 4905 4905 0 0.00 0 5238 9 0 0
v_min_non_paid_invoice 5258 5258 0 0.00 0 8186 20 0 0
v_min_non_paid_realizn 6279 6279 0 0.00 0 22410 25 0 0
v_our_firm 9218 9218 0 0.00 0 72 5 0 0
v_reserve_write_off 17380 17380 0 0.00 0 16951 14 0 0
x_lock_depdocs_on_canc_invoice 11555 11555 0 0.00 0 18393 119 0 0
x_lock_depdocs_on_canc_sup_ord 391 391 0 0.00 0 3106 359 0 0
x_make_qstorno_1000_1200 4898 4898 0 0.00 2 67519 1237 0 0
x_make_qstorno_1200_2000 4914 4914 0 0.00 3 88508 3093 0 0
x_make_qstorno_2100_3300 425011 425011 0 0.00 0 90618 227 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-05-2022 04:39:44 to 07-05-2022 04:39:48.

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
02:07:20 5 8192000000 8801966160 8814452736 7242912 0.088 3 2 3 0 7385504 81232 61360
02:08:21 28673 8192000000 9730253568 10028756992 800751536 8.904 100 86 172 0 829567200 7882576 20933088
02:09:53 17680 8192000000 9763857632 10072645632 837084736 9.270 100 84 168 0 871102272 14001264 20016272
02:11:18 15540 8192000000 9810719664 10131042304 870721904 9.607 100 83 166 0 917973424 27977248 19274272
02:12:39 8000 8192000000 9875920912 10211016704 914933568 10.046 100 83 163 1 985070944 52124816 18012560
02:13:49 4932 8192000000 9912631296 10259394560 957815920 10.468 100 84 167 0 1029483760 54057072 17610768
02:15:01 1947 8192000000 9974267312 10328088576 999273408 10.871 100 94 184 2 1089653216 70757296 19622512
02:16:05 1504 8192000000 10007773456 10368385024 1023673520 11.107 100 96 192 1 1132290880 89330096 19287264
02:17:08 907 8192000000 10027118816 10397347840 1050084976 11.361 100 95 182 1 1147984640 78807616 19092048
02:18:10 1049 8192000000 10018918800 10391805952 1062163056 11.477 100 97 194 0 1137817968 56052752 19602160
02:19:13 1077 8192000000 10042564208 10420776960 1068046736 11.533 100 92 181 1 1162748144 76220032 18481376
02:20:15 3666 8192000000 10041506416 10421059584 1072483696 11.576 100 95 182 0 1159029984 69134272 17412016
02:21:20 1187 8192000000 10051394848 10440163328 1083062672 11.677 100 95 190 0 1176061712 73642768 19356272
02:22:22 1216 8192000000 10064228016 10453487616 1086463936 11.709 100 97 188 0 1188740304 82857872 19418496
02:23:25 1516 8192000000 10069304720 10457726976 1092561248 11.767 100 86 161 0 1187372752 77506768 17304736
02:24:27 1398 8192000000 10085408576 10468990976 1093840528 11.779 100 98 192 0 1203234944 90968384 18426032
02:25:30 1004 8192000000 10071830432 10459942912 1096077664 11.800 100 97 175 1 1196421280 81067648 19275968
02:26:33 1211 8192000000 10050856256 10435338240 1092375696 11.765 99 91 172 1 1177218448 66881424 17961328
02:27:35 5578 8192000000 10044533024 10452500480 1095573696 11.796 100 43 86 1 1176958272 72626240 8758336
02:28:42 1028 8192000000 10061589200 10449494016 1094821328 11.788 100 90 177 0 1181476112 69272064 17382720
02:29:44 1090 8192000000 10033438304 10413953024 1082276928 11.669 100 86 168 0 1156072320 57647472 16147920
02:30:46 1259 8192000000 10052319984 10437607424 1084053664 11.686 100 99 185 2 1176603344 73209328 19340352
02:31:49 1244 8192000000 10026716560 10404052992 1061622576 11.472 100 90 171 1 1149655568 70556848 17476144
02:32:51 1015 8192000000 10042427696 10423607296 1069430032 11.547 100 96 173 1 1161091504 74227168 17434304
02:33:53 981 8192000000 10007998416 10383290368 1051397552 11.374 100 90 175 0 1129320400 59441184 18481664
02:34:55 1655 8192000000 9979473056 10353426432 1022941728 11.100 99 90 165 2 1101463040 62768384 15752928
02:35:58 2530 8192000000 9990270080 10368020480 1044573920 11.309 100 75 139 1 1112881504 53280208 15027376
02:37:01 5805 8192000000 9988447664 10364534784 1041330064 11.277 99 78 142 5 1110796176 54631728 14834384
02:38:11 2359 8192000000 10015417216 10391191552 1060910992 11.465 100 87 160 0 1132829536 56171136 15747408
02:39:21 2164 8192000000 10035289088 10421493760 1081837584 11.665 100 87 158 0 1157969232 60690800 15440848
02:40:28 1797 8192000000 10068999504 10456875008 1097260016 11.812 100 96 186 2 1193548784 76392816 19895952
02:41:33 1501 8192000000 10094137456 10479988736 1114692720 11.977 100 95 183 0 1216061952 80483312 20885920
02:42:37 17732 8192000000 10142852352 10541473792 1119225632 12.020 100 69 131 1 1247662624 114595216 13841776
02:44:00 9036 8192000000 10147885536 10551152640 1128201712 12.104 100 68 132 2 1260875616 118802896 13871008
02:45:14 1230 8192000000 10158694480 10567561216 1137836528 12.195 100 83 160 1 1272854112 117776928 17240656
02:46:20 1109 8192000000 10201233808 10597011456 1147903696 12.290 100 96 188 1 1318451440 150097488 20450256
02:47:26 875 8192000000 10236966112 10634752000 1159788864 12.401 100 95 182 0 1353717760 173379312 20549584
02:48:30 907 8192000000 10244067824 10652557312 1160681584 12.410 100 83 156 1 1362254016 184371040 17201392
02:49:35 1936 8192000000 10294780320 10699366400 1171469984 12.511 100 76 151 0 1405422768 217297136 16655648
02:50:48 1260 8192000000 10340412016 10740383744 1190406096 12.687 100 90 172 2 1450358016 237997424 21954496
02:51:52 1093 8192000000 10410539840 10813648896 1203762208 12.811 100 92 179 1 1521526960 295703584 22061168
02:52:55 996 8192000000 10456510192 10866839552 1196880256 12.747 100 97 188 0 1567281920 347224816 23176848
02:54:02 1211 8192000000 10472466384 10910662656 1204853344 12.821 100 90 172 0 1583275072 357654064 20767664
02:55:08 1954 8192000000 10504957056 10909552640 1194005264 12.721 100 92 182 2 1614549952 397410384 23134304
02:56:14 864 8192000000 10574745152 10994663424 1207242368 12.844 100 96 190 2 1684214592 453160160 23812064
02:57:19 1520 8192000000 10619929248 11035385856 1207737344 12.848 100 96 188 0 1731597440 500286896 23573200
02:58:24 842 8192000000 10577629904 11060097024 1205841824 12.831 100 96 188 0 1680792304 450909904 24040576
02:59:28 971 8192000000 10574242640 11067318272 1200479424 12.781 100 97 194 0 1682972336 459190976 23301936
03:00:31 14384 8192000000 10503099264 10974720000 1192910912 12.710 100 45 90 0 1640385264 436002528 11471824
03:02:05 27719 8192000000 10496929536 10947911680 1175269696 12.546 99 43 83 0 1609012080 423212288 10530096
03:04:16 27305 8192000000 10539163776 11012186112 1145717536 12.269 98 35 68 1 1622631984 467507536 9406912
03:05:48 23362 8192000000 10337373296 10845863936 1125078672 12.075 97 32 62 1 1450564336 316999696 8485968
03:07:20 51288 8192000000 10128366544 10628390912 1119177696 12.019 98 18 36 1 1285360288 161531776 4650816
03:09:13 6542 8192000000 10101469760 10525659136 1148628128 12.297 99 63 110 1 1192588896 32399472 11561296
03:10:21 46644 8192000000 10132345200 10640617472 1153491760 12.342 100 12 24 1 1257146912 100999216 2655936
03:13:12 100566 8192000000 10125618768 10581172224 1142968128 12.243 100 37 72 2 1232426464 81356144 8102192
03:16:05 88613 8192000000 10094680848 10544529408 1118541824 12.013 98 32 63 1 1183996752 59258992 6195936
03:18:41 40313 8192000000 10091234288 10526711808 1107515680 11.909 98 28 52 1 1178295408 66368432 4411296
03:20:22 15630 8192000000 10123152000 10564636672 1117933088 12.007 99 61 120 0 1228554640 98294560 12326992
03:21:41 45113 8192000000 10116002832 10563035136 1129927664 12.121 99 17 34 0 1211078128 77382752 3767712
03:23:26 92732 8192000000 10144794096 10613448704 1135239392 12.171 99 19 38 1 1246972064 107471584 4261088
03:26:08 69736 8192000000 10132558880 10601443328 1093764176 11.778 96 21 41 1 1191259904 92283712 5212016
03:28:33 47962 8192000000 10138439232 10585657344 1141217744 12.227 100 38 77 0 1206679536 56660544 8801248
03:30:24 36498 8192000000 10094283520 10532528128 1128169008 12.104 99 37 74 0 1178414160 41952912 8292240
03:32:02 34294 8192000000 10126756144 10592854016 1120859056 12.035 99 18 36 0 1222311232 97815104 3637072
03:33:52 29460 8192000000 10098681472 10550005760 1119230992 12.020 99 22 44 1 1196459680 72864624 4364064
03:35:27 109992 8192000000 10143187328 10603347968 1122638592 12.052 98 12 24 0 1239598288 114321104 2638592
03:38:18 112625 8192000000 10131294000 10580824064 1137651344 12.193 99 30 60 3 1186056704 42000432 6404928
03:41:12 61765 8192000000 10053784432 10545704960 1071447824 11.566 94 29 56 1 1153319328 76159568 5711936
03:43:16 72471 8192000000 10089979696 10559471616 1132290240 12.143 100 12 24 0 1176528832 41913072 2325520
03:45:29 72371 8192000000 10161947360 10620096512 1125978112 12.083 98 15 30 0 1209581488 80371408 3231968
03:47:48 30317 8192000000 10174238992 10610610176 1139429360 12.210 100 28 55 0 1273222096 127628032 6164704
03:49:32 32896 8192000000 10172076368 10613866496 1139680688 12.213 99 23 46 1 1269269728 123878848 5710192
03:51:06 27165 8192000000 10210871120 10653265920 1132148976 12.142 98 31 62 0 1292263664 153062064 7052624
03:52:40 62343 8192000000 10163432176 10626830336 1110531744 11.937 97 31 62 1 1243353552 125827248 6994560
03:54:48 23014 8192000000 10179204160 10649690112 1120283552 12.030 99 48 96 1 1269218752 138537664 10397536
03:56:12 39529 8192000000 10153014816 10629705728 1131493824 12.135 100 27 53 2 1254871072 117915648 5461600
03:57:55 114257 8192000000 10055668512 10525413376 1091068000 11.753 96 22 43 3 1157861024 62034112 4758912
04:00:50 50443 8192000000 10089737056 10547286016 1126457392 12.088 99 5 10 1 1181015200 53614224 943584
04:02:41 123255 8192000000 10070699408 10558963712 1132824336 12.148 100 12 24 0 1194867216 59657840 2385040
04:05:45 33731 8192000000 10135930736 10559893504 1124846848 12.073 98 59 116 0 1224404224 87730000 11827376
04:07:35 80835 8192000000 10104262528 10556502016 1127810304 12.101 99 20 40 0 1199964512 68513824 3640384
04:09:58 43670 8192000000 10133617104 10584182784 1119886016 12.026 98 34 68 0 1204697392 77882672 6928704
04:12:02 70284 8192000000 10075898832 10596020224 1114206288 11.972 99 11 22 1 1205166064 88540240 2419536
04:14:23 123446 8192000000 10092069296 10577584128 1125209520 12.076 98 14 28 0 1215895840 87612160 3074160
04:17:26 52202 8192000000 10110644496 10595614720 1119517664 12.022 98 31 62 1 1184079296 58455088 6106544
04:19:23 30413 8192000000 10120950144 10563366912 1127136896 12.094 98 38 71 1 1179342864 44478592 7727376
04:20:56 39504 8192000000 10128490400 10581975040 1127232912 12.095 99 43 83 4 1220112912 84341104 8538896
04:22:35 40123 8192000000 10095836896 10534383616 1098264208 11.821 97 37 74 1 1177307616 71287360 7756048
04:24:17 31747 8192000000 10172068672 10614988800 1137008352 12.187 100 45 90 0 1270660128 124680400 8971376
04:25:53 99399 8192000000 10094273376 10597416960 1138121936 12.198 100 19 38 1 1223690480 81745008 3823536
04:28:33 47838 8192000000 10069525008 10583748608 1135860320 12.177 100 11 22 0 1180475296 41834608 2780368
04:32:18 60839 8192000000 10140935840 10602741760 1146794448 12.279 100 17 34 0 1241328976 90541904 3992624
04:34:20 35011 8192000000 10126250960 10571669504 1131970032 12.140 99 27 50 1 1207644336 70239232 5435072
04:35:56 84552 8192000000 9859285952 10309885952 908652176 9.984 79 17 34 0 996866608 84411360 3803072

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 07-05-2022 04:39:49 to 07-05-2022 04:40:05.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 159 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-05-2022 04:40:06 to 07-05-2022 04:40:08.

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          204856
MON$OLDEST_ACTIVE               208990
MON$OLDEST_SNAPSHOT             208990
MON$NEXT_TRANSACTION            208990
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                       2060576
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             754
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

Database statistics

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

Database validation

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

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

--- start of diff output ---
:33: 
:34: mscs-test-161.budzdorov.ru    Sat May  7 04:37:27 2022
:35:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:36: 
--- end of diff output ---

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

07.05.22 04:40:09. 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
2022-05-07 04:40:12 No severe PSQL-related problems occured
07.05.22 04:40:13. End of report.