Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.02.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 e0e7b46250ba4f9795ceb55e0face73f
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 2020-09-25 13:42
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.00
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 16500664 kB
MemAvailable 31208912 kB
Buffers 0 kB
Cached 15203696 kB
SwapCached 4772 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-02-11 13:26:57
DTS_END                         2021-02-11 13:26:57.0390
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/60420
TRN_ID                          229955
ATT_ID                          594
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: 8728.75 22401 1047450
customer order: creation 170.98 433 20517
customer order: refuse 14.68 352 1761
order to supplier: creation 47.15 1346 5658
order to supplier: removal 3.37 119810 404
invoice (draft): creation 45.29 3435 5435
invoice (draft): removal 47.64 950 5717
invoice accept: apply 41.08 19150 4929
invoice accept: cancel 43.08 1013 5169
customer reserve: creation 3776.54 188 453185
customer reserve: removal 4254.58 76 510549
realization accept: apply 83.25 183 9990
realization accept: cancel 73.23 139 8788
payment from customer: creation 58.37 106 7004
payment from customer: removal 6.13 93 736
payment to supplier: creation 48.81 126 5857
payment to supplier: removal 3.44 84 413
service: total inventory turnovers 3.93 3628 472
service: total monetary turnovers 6.23 634 748
service: aggregate perf. data 0.95 14008 114
service: refresh index statistics 0.03 282263 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1120
TEST_TIME 2 2603
TEST_TIME 3 4047
TEST_TIME 4 3450
TEST_TIME 5 3866
TEST_TIME 6 4060
TEST_TIME 7 4185
TEST_TIME 8 4419
TEST_TIME 9 4776
TEST_TIME 10 4948
TEST_TIME 11 5265
TEST_TIME 12 5377
TEST_TIME 13 5602
TEST_TIME 14 5772
TEST_TIME 15 6088
TEST_TIME 16 6088
TEST_TIME 17 6398
TEST_TIME 18 6634
TEST_TIME 19 6916
TEST_TIME 20 6962
TEST_TIME 21 7012
TEST_TIME 22 7550
TEST_TIME 23 7711
TEST_TIME 24 8232
TEST_TIME 25 8168
TEST_TIME 26 8851
TEST_TIME 27 8814
TEST_TIME 28 8734
TEST_TIME 29 9094
TEST_TIME 30 9850
TEST_TIME 31 10380
TEST_TIME 32 10238
TEST_TIME 33 10222
TEST_TIME 34 10392
TEST_TIME 35 10450
TEST_TIME 36 10304
TEST_TIME 37 10205
TEST_TIME 38 10275
TEST_TIME 39 10104
TEST_TIME 40 9863
TEST_TIME 41 9751
TEST_TIME 42 9801
TEST_TIME 43 9738
TEST_TIME 44 9676
TEST_TIME 45 9615
TEST_TIME 46 9720
TEST_TIME 47 9645
TEST_TIME 48 9582
TEST_TIME 49 9585
TEST_TIME 50 9498
TEST_TIME 51 9406
TEST_TIME 52 9398
TEST_TIME 53 9298
TEST_TIME 54 9305
TEST_TIME 55 9273
TEST_TIME 56 9316
TEST_TIME 57 9283
TEST_TIME 58 9370
TEST_TIME 59 9344
TEST_TIME 60 9404
TEST_TIME 61 9412
TEST_TIME 62 9509
TEST_TIME 63 9492
TEST_TIME 64 9530
TEST_TIME 65 9605
TEST_TIME 66 9602
TEST_TIME 67 9504
TEST_TIME 68 9433
TEST_TIME 69 9378
TEST_TIME 70 9274
TEST_TIME 71 9195
TEST_TIME 72 9149
TEST_TIME 73 9156
TEST_TIME 74 9138
TEST_TIME 75 9089
TEST_TIME 76 9030
TEST_TIME 77 8983
TEST_TIME 78 9101
TEST_TIME 79 9087
TEST_TIME 80 9263
TEST_TIME 81 9237
TEST_TIME 82 9213
TEST_TIME 83 9231
TEST_TIME 84 9232
TEST_TIME 85 9311
TEST_TIME 86 9264
TEST_TIME 87 9168
TEST_TIME 88 9148
TEST_TIME 89 9089
TEST_TIME 90 9043
TEST_TIME 91 9015
TEST_TIME 92 9013
TEST_TIME 93 8975
TEST_TIME 94 8961
TEST_TIME 95 8966
TEST_TIME 96 8984
TEST_TIME 97 8965
TEST_TIME 98 8934
TEST_TIME 99 8956
TEST_TIME 100 9005
TEST_TIME 101 8997
TEST_TIME 102 8979
TEST_TIME 103 8996
TEST_TIME 104 9014
TEST_TIME 105 9039
TEST_TIME 106 8965
TEST_TIME 107 8908
TEST_TIME 108 8875
TEST_TIME 109 8828
TEST_TIME 110 8776
TEST_TIME 111 8758
TEST_TIME 112 8749
TEST_TIME 113 8732
TEST_TIME 114 8755
TEST_TIME 115 8745
TEST_TIME 116 8753
TEST_TIME 117 8741
TEST_TIME 118 8732
TEST_TIME 119 8722
TEST_TIME 120 8729

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-02-2021 13:28:32 to 11-02-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 1467292 1467292 0 0.00 0 15103 7 0 0
doc_list_biud 1467291 1467291 0 0.00 0 40134 33 0 0
sp_add_invoice_to_stock 5118 4929 189 3.69 38 215451 19151 0 189
sp_cancel_adding_invoice 5169 5169 0 0.00 6 84102 1014 0 0
sp_cancel_client_order 1761 1761 0 0.00 4 8529 353 0 0
sp_cancel_customer_reserve 510549 510549 0 0.00 1 23565 77 0 0
sp_cancel_pay_from_customer 736 736 0 0.00 3 7756 93 0 0
sp_cancel_pay_to_supplier 413 413 0 0.00 3 4447 84 0 0
sp_cancel_supplier_invoice 5732 5717 15 0.26 2 17825 951 0 15
sp_cancel_supplier_order 404 404 0 0.00 14 1156878 119811 0 0
sp_cancel_write_off 8788 8788 0 0.00 1 40146 140 0 0
sp_client_order 20517 20517 0 0.00 4 17071 434 0 0
sp_customer_reserve 453185 453185 0 0.00 0 57859 189 0 0
sp_fill_shopping_cart 31603 31603 0 0.00 1 13395 42 0 0
sp_fill_shopping_cart_clo_res 453183 453183 0 0.00 0 33545 18 0 0
sp_get_clo_for_invoice 4927 4927 0 0.00 0 39816 386 0 0
sp_kill_cost_storno 15106 15106 0 0.00 0 10009 66 0 0
sp_kill_qstorno_ret_qs2qd 1036007 1036007 0 0.00 0 40132 41 0 0
sp_kill_qty_storno 547304 547304 0 0.00 0 40133 88 0 0
sp_lock_selected_doc 20748 20748 0 0.00 0 9375 5 0 0
sp_make_cost_storno 27778 27778 0 0.00 0 11902 60 0 0
sp_multiply_rows_for_pdistr 27777 27777 0 0.00 0 9108 34 0 0
sp_multiply_rows_for_qdistr 20517 20517 0 0.00 0 14816 252 0 0
sp_pay_from_customer 7004 7004 0 0.00 4 9120 107 0 0
sp_pay_to_supplier 5857 5857 0 0.00 5 9479 126 0 0
sp_payment_common 12861 12861 0 0.00 1 6225 18 0 0
sp_qd_handle_on_cancel_clo 1761 1761 0 0.00 0 8522 327 0 0
sp_qd_handle_on_invoice_upd_sts 10096 10096 0 0.00 0 25728 411 0 0
sp_qd_handle_on_reserve_upd_sts 9989 9989 0 0.00 0 11454 50 0 0
sp_reserve_write_off 9990 9990 0 0.00 7 12993 184 0 0
sp_supplier_invoice 5435 5435 0 0.00 7 116124 3435 0 0
sp_supplier_order 5658 5658 0 0.00 6 84530 1347 0 0
srv_aggregate_perf_data 114 114 0 0.00 2736 159737 14008 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1557 135831 40539 0 0
srv_find_qd_qs_mism 1000297 1000297 0 0.00 0 15103 6 0 0
srv_make_invnt_saldo 472 472 0 0.00 40 104641 3628 0 0
srv_make_money_saldo 748 748 0 0.00 1 21683 635 0 0
srv_recalc_idx_stat 4 4 0 0.00 128262 355530 282264 0 0
t$perf-abend:sp_add_invoice_to_stock 189 189 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4929 4929 0 0.00 0 21565 188 0 0
t$perf-norm:sp_cancel_adding_invoice 419 419 0 0.00 1 8674 112 0 0
t$perf-norm:sp_cancel_client_order 1761 1761 0 0.00 0 8763 21 0 0
t$perf-norm:sp_cancel_customer_reserve 678 678 0 0.00 0 2459 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 736 736 0 0.00 0 582 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 413 413 0 0.00 0 597 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 404 404 0 0.00 0 900 13 0 0
t$perf-norm:sp_cancel_supplier_order 404 404 0 0.00 0 23598 1640 0 0
t$perf-norm:sp_cancel_write_off 1017 1017 0 0.00 0 3630 15 0 0
t$perf-norm:sp_client_order 20517 20517 0 0.00 0 3926 18 0 0
t$perf-norm:sp_customer_reserve 976 976 0 0.00 0 2415 11 0 0
t$perf-norm:sp_pay_from_customer 7004 7004 0 0.00 0 6587 11 0 0
t$perf-norm:sp_pay_to_supplier 5857 5857 0 0.00 0 12333 10 0 0
t$perf-norm:sp_reserve_write_off 9990 9990 0 0.00 0 3598 10 0 0
t$perf-norm:sp_supplier_invoice 5435 5435 0 0.00 0 4479 20 0 0
t$perf-norm:sp_supplier_order 5658 5658 0 0.00 0 6226 19 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 1440 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 251 6 0 0
t$perf-norm:srv_make_invnt_saldo 472 472 0 0.00 0 974 8 0 0
t$perf-norm:srv_make_money_saldo 748 748 0 0.00 0 892 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 18 8 0 0
v_add_invoice_to_stock 10232 9854 378 3.69 0 6643 20 0 378
v_all_customers 36968 36968 0 0.00 0 23 1 0 0
v_all_suppliers 30754 30754 0 0.00 0 146 1 0 0
v_all_wares 41032 41032 0 0.00 0 169 1 0 0
v_cancel_adding_invoice 838 838 0 0.00 0 867 8 0 0
v_cancel_client_order 3522 3522 0 0.00 0 1081 4 0 0
v_cancel_customer_prepayment 1472 1472 0 0.00 0 2107 9 0 0
v_cancel_customer_reserve 1356 1356 0 0.00 0 264 3 0 0
v_cancel_payment_to_supplier 826 826 0 0.00 0 685 4 0 0
v_cancel_supplier_invoice 838 808 30 3.57 0 551 12 0 30
v_cancel_supplier_order 808 808 0 0.00 0 236 4 0 0
v_cancel_write_off 2034 2034 0 0.00 0 1614 14 0 0
v_max_id_clo_ord 5656 5656 0 0.00 0 4095 2 0 0
v_max_id_clo_res 976 976 0 0.00 0 3636 17 0 0
v_max_id_ord_sup 5431 5431 0 0.00 0 7227 5 0 0
v_max_non_paid_invoice 1567 1567 0 0.00 0 307 2 0 0
v_max_non_paid_realizn 3935 3935 0 0.00 0 4321 5 0 0
v_min_id_clo_ord 5656 5656 0 0.00 0 9918 8 0 0
v_min_id_clo_res 976 976 0 0.00 0 5862 12 0 0
v_min_id_ord_sup 5431 5431 0 0.00 0 6289 6 0 0
v_min_non_paid_invoice 5857 5857 0 0.00 0 2435 8 0 0
v_min_non_paid_realizn 7004 7004 0 0.00 0 4314 12 0 0
v_our_firm 10202 10202 0 0.00 0 47 1 0 0
v_reserve_write_off 19978 19978 0 0.00 0 6101 6 0 0
x_lock_depdocs_on_canc_invoice 10482 10482 0 0.00 0 8073 129 0 0
x_lock_depdocs_on_canc_sup_ord 404 404 0 0.00 0 15853 382 0 0
x_make_qstorno_1000_1200 5658 5658 0 0.00 2 84473 1259 0 0
x_make_qstorno_1200_2000 5435 5435 0 0.00 3 116119 3287 0 0
x_make_qstorno_2100_3300 453185 453185 0 0.00 0 57853 168 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-02-2021 13:28:36 to 11-02-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:00 7 8192000000 8800067696 8811831296 5697920 0.069 2 2 3 0 5838736 79456 61360
10:58:01 31600 8192000000 9722242624 10018660352 789202688 8.787 100 84 168 0 817709104 7898016 20608400
10:59:38 20746 8192000000 9762767136 10068750336 833805984 9.238 100 83 166 1 866082560 12422208 19854368
11:01:03 14550 8192000000 9800455952 10115223552 871136032 9.611 100 85 169 0 908292480 17723712 19432736
11:02:22 15962 8192000000 9865677232 10195726336 906754272 9.965 100 73 142 0 959536176 37885296 14896608
11:03:41 14348 8192000000 9904125424 10246262784 951791728 10.409 100 74 148 0 1022544144 55416032 15336384
11:04:58 6142 8192000000 9960735280 10308186112 990064560 10.782 100 86 170 0 1083627008 76484160 17078288
11:06:06 3441 8192000000 9993137648 10352291840 1013879616 11.013 100 86 170 1 1104783408 73557440 17346352
11:07:11 2977 8192000000 10010843696 10373943296 1031655120 11.184 100 78 156 0 1122147600 74571568 15920912
11:08:16 1285 8192000000 10042764528 10410336256 1056518768 11.423 100 93 187 1 1144873552 69272560 19082224
11:09:18 1538 8192000000 10015054320 10390573056 1067851088 11.532 100 82 161 1 1133790816 49008032 16931696
11:10:22 3534 8192000000 10009224144 10396389376 1071737296 11.569 100 57 112 1 1127723120 43738112 12247712
11:11:27 12353 8192000000 9995137984 10396803072 1080341616 11.651 100 28 56 1 1109306576 22893104 6071856
11:12:42 5385 8192000000 10001356160 10394947584 1085802384 11.703 100 39 75 1 1116612176 22805648 8004144
11:13:50 53959 8192000000 10015391920 10422435840 1088866960 11.732 100 24 48 1 1106301104 11793664 5640480
11:15:50 3186 8192000000 10045444128 10441478144 1102242496 11.859 100 55 112 0 1159019456 44703264 12073696
11:16:55 8718 8192000000 10036317104 10444120064 1103655744 11.872 100 38 75 1 1133516560 21349728 8511088
11:18:08 8789 8192000000 9986746896 10380873728 1064861856 11.503 98 43 86 1 1100553712 26400128 9291728
11:19:21 46217 8192000000 9927848512 10323628032 949636208 10.388 89 25 49 0 971365184 16187840 5541136
11:21:08 9039 8192000000 9928924352 10319384576 964313120 10.531 94 43 86 2 995808224 21777456 9717648
11:22:19 16977 8192000000 9945038784 10337955840 1002194704 10.900 97 40 80 2 1039099280 27862784 9041792
11:23:37 6838 8192000000 9958589536 10332258304 1041898976 11.283 100 49 94 4 1071388816 19040848 10448992
11:24:45 18849 8192000000 9965115856 10363174912 1046361648 11.326 100 29 57 4 1089652144 37562960 5727536
11:26:09 7863 8192000000 9967531440 10362474496 1062275344 11.478 100 50 96 2 1085420704 11602752 11542608
11:27:24 10119 8192000000 9989728144 10380877824 1077496160 11.624 100 44 85 2 1101629792 15898528 8235104
11:28:38 9777 8192000000 10064671824 10456801280 1089791152 11.741 100 59 116 2 1183754432 82081872 11881408
11:30:04 74106 8192000000 10064481216 10491662336 1107417504 11.908 100 36 72 1 1169927504 55473232 7036768
11:32:21 19068 8192000000 10076356912 10488524800 1122943248 12.055 100 37 73 0 1189761680 59031808 7786624
11:33:45 8814 8192000000 10149868032 10560368640 1138524704 12.202 100 50 99 0 1251009808 101941264 10543840
11:35:03 2770 8192000000 10185635376 10593976320 1149758848 12.307 100 68 137 1 1293977280 129216432 15002000
11:36:12 22710 8192000000 10190897792 10605367296 1157544768 12.380 100 43 85 1 1306469904 138843376 10081760
11:37:40 1711 8192000000 10244260080 10661085184 1168261616 12.481 100 71 141 2 1349226992 165302592 15662784
11:38:49 3271 8192000000 10316091392 10723655680 1183751072 12.625 100 84 167 0 1416844560 214265088 18828400
11:39:59 6586 8192000000 10300848624 10719178752 1173243248 12.527 99 66 127 0 1403516864 216662224 13611392
11:41:17 6948 8192000000 10350952176 10771804160 1196114656 12.740 100 75 147 1 1454990640 242477712 16398272
11:42:30 2850 8192000000 10345545680 10763010048 1172157168 12.517 99 77 152 1 1437999872 248182304 17660400
11:43:38 3266 8192000000 10395026736 10800177152 1181463760 12.604 100 81 160 0 1494121456 294222624 18435072
11:44:46 2933 8192000000 10403437312 10809827328 1180149184 12.592 99 85 168 0 1499710112 300467520 19093408
11:45:55 4382 8192000000 10396203744 10817552384 1167205216 12.471 100 88 175 0 1497365360 310216464 19943680
11:47:08 1555 8192000000 10411091104 10817466368 1163354976 12.435 100 84 168 0 1508367360 324262592 20749792
11:48:16 1791 8192000000 10462790720 10893770752 1176930944 12.562 100 79 158 0 1569980320 374494784 18554592
11:49:21 8899 8192000000 10451730448 10880987136 1159930384 12.403 99 43 86 2 1542057520 371540192 10586944
11:50:38 13819 8192000000 10416155888 10883862528 1149986160 12.309 99 35 70 1 1515361616 355881456 9494000
11:52:07 29728 8192000000 10370825664 10842755072 1142740272 12.241 100 31 62 0 1497956480 347384336 7831872
11:53:39 28736 8192000000 10358536560 10829058048 1140076704 12.216 100 20 40 3 1439875856 294470864 5328288
11:55:50 19945 8192000000 10334469632 10806353920 1122285120 12.049 100 18 36 0 1435138688 307716224 5137344
11:57:36 77294 8192000000 10049216016 10463858688 1077941376 11.628 96 19 36 1 1151302288 69745920 3614992
11:59:56 38179 8192000000 10115227440 10574016512 1123792048 12.063 100 18 36 2 1213264112 85651472 3820592
12:01:35 78769 8192000000 10081292192 10513592320 1086527536 11.710 96 33 67 2 1130718816 37268384 6922896
12:03:54 38148 8192000000 10092700592 10540748800 1116542800 11.994 99 31 62 4 1202754368 79346480 6865088
12:05:33 135830 8192000000 10075974720 10511732736 1102858144 11.865 98 33 66 2 1161291536 51810688 6622704
12:08:50 39356 8192000000 10089740112 10541764608 1126986288 12.093 100 33 67 0 1188615984 54023872 7605824
12:10:32 4028 8192000000 10097056848 10523914240 1135360208 12.172 100 66 129 3 1195283840 45969264 13954368
12:11:37 39272 8192000000 10117898928 10565812224 1136153232 12.179 100 15 30 2 1229181376 89642736 3385408
12:13:24 41994 8192000000 10091887040 10551283712 1148574352 12.296 100 22 43 0 1198214976 45412016 4228608
12:15:08 31944 8192000000 10054665312 10505629696 1137149536 12.189 100 19 37 1 1186140448 45323984 3666928
12:16:47 44436 8192000000 10092711152 10569461760 1143183296 12.245 100 14 28 0 1206506992 60077824 3245872
12:18:41 40136 8192000000 10089870336 10575757312 1141290304 12.228 100 9 18 0 1187679184 44588528 1800352
12:20:37 37728 8192000000 10063191008 10513317888 1121227552 12.039 98 23 45 2 1155026512 28684352 5114608
12:22:17 44221 8192000000 10085887136 10556309504 1143732032 12.251 100 16 32 1 1212030160 64565488 3732640
12:24:03 43666 8192000000 10110438384 10604191744 1142550592 12.240 99 25 50 0 1250260384 102556224 5153568
12:25:47 44896 8192000000 10101153136 10577780736 1143782992 12.251 100 30 60 0 1218608736 68597424 6228320
12:27:38 41153 8192000000 10099109712 10577666048 1115827040 11.988 97 17 34 1 1192760000 73036880 3896080
12:29:21 10450 8192000000 10074587824 10495979520 1108301568 11.916 99 66 131 1 1142699488 21171360 13226560
12:30:35 73467 8192000000 10053386096 10527764480 1076746464 11.616 95 16 32 0 1130154288 50479296 2928528
12:32:50 55184 8192000000 10070101808 10525093888 1074607904 11.596 95 20 40 1 1109083280 29728592 4746784
12:35:29 39613 8192000000 10027862400 10502942720 1088832384 11.732 97 3 6 0 1116232832 27142304 258144
12:37:11 79820 8192000000 10072669952 10544775168 1120773504 12.034 99 9 18 0 1172458432 49742064 1942864
12:39:32 69866 8192000000 10059010288 10509217792 1071324448 11.565 95 24 48 0 1171538304 94572016 5641840
12:41:42 32466 8192000000 10091587024 10524147712 1100790624 11.845 98 28 56 2 1171217888 64451568 5975696
12:43:15 31534 8192000000 10151857360 10582818816 1095621456 11.796 97 27 53 0 1214463792 112428608 6413728
12:44:47 51493 8192000000 10166890704 10669502464 1116933872 11.998 99 11 22 0 1250697808 130876080 2887856
12:46:43 61044 8192000000 10077338096 10509373440 1101321312 11.850 98 22 44 0 1142906000 37389504 4195184
12:49:00 20575 8192000000 10081027680 10504933376 1101764688 11.854 99 36 72 0 1151961984 43288576 6908720
12:50:22 78164 8192000000 10071285008 10547642368 1104780080 11.883 98 35 70 1 1199732240 88298896 6653264
12:52:40 84842 8192000000 10077225664 10547757056 1124099056 12.066 100 18 36 1 1181206240 53569136 3538048
12:56:07 43568 8192000000 10029738288 10487361536 1102138576 11.858 98 9 18 0 1128471184 24788880 1543728
12:57:58 37125 8192000000 10043538352 10491887616 1099680624 11.835 98 23 46 0 1132238880 27616688 4941568
12:59:41 66751 8192000000 10091991936 10554220544 1122602976 12.052 99 22 44 1 1202966528 75788464 4575088
13:01:47 60471 8192000000 10097395952 10542903296 1145008784 12.263 100 19 36 0 1196244288 47959248 3276256
13:03:48 56725 8192000000 10112745168 10556002304 1116217472 11.991 97 32 64 0 1165341408 42728112 6395824
13:05:46 116969 8192000000 10095322896 10567991296 1127988448 12.102 98 13 26 0 1199331024 68789632 2552944
13:08:46 46365 8192000000 10098565648 10577735680 1119481024 12.022 98 22 44 1 1162026256 38096176 4449056
13:10:36 34569 8192000000 10082027152 10523643904 1119129504 12.019 98 20 40 0 1150643552 27508720 4005328
13:12:26 68455 8192000000 10071247520 10534756352 1124150160 12.066 99 24 48 2 1181339312 52656432 4532720
13:14:45 50504 8192000000 10064202816 10531561472 1134078896 12.160 99 13 27 1 1175454592 38036416 3339280
13:16:35 60662 8192000000 10101294864 10566746112 1135198208 12.170 99 17 34 1 1188459184 49772608 3488368
13:18:48 85533 8192000000 10099287488 10581917696 1104187392 11.877 96 31 63 0 1195060560 83980128 6893040
13:21:18 88682 8192000000 10120387520 10597363712 1113719024 11.968 97 9 18 0 1173725792 57785392 2221376
13:23:47 45880 8192000000 10190939344 10653511680 1150438352 12.314 100 32 62 0 1266425968 109415792 6571824
13:25:35 60385 8192000000 10211573808 10698534912 1134119792 12.160 98 33 66 1 1294235600 152431920 7683888

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 11-02-2021 13:28:42 to 11-02-2021 13:28:57.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 189 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-02-2021 13:28:58 to 11-02-2021 13:29:00.

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          226198
MON$OLDEST_ACTIVE               232847
MON$OLDEST_SNAPSHOT             232847
MON$NEXT_TRANSACTION            232847
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                       2106944
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.2365 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2365 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2365 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.2365 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 ---
:276: 
:277: mscs-test-161.budzdorov.ru    Thu Feb 11 13:27:05 2021
:278:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:279: 
:280: 
:281: mscs-test-161.budzdorov.ru    Thu Feb 11 13:27:08 2021
:282:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:283: 
:284: 
:285: mscs-test-161.budzdorov.ru    Thu Feb 11 13:27:09 2021
:286:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:287: 
:288: 
:289: mscs-test-161.budzdorov.ru    Thu Feb 11 13:27:10 2021
:290:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:291: 
--- end of diff output ---

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

11.02.21 13:29:01. 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-02-11 13:29:04 No severe PSQL-related problems occured
11.02.21 13:29:05. End of report.