Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.02.2021 10:29
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 16509208 kB
MemAvailable 31154184 kB
Buffers 0 kB
Cached 15201080 kB
SwapCached 3892 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-22 10:26:58
DTS_END                         2021-02-22 10:26:58.9640
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/59948
TRN_ID                          241051
ATT_ID                          645
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: 9003.41 25380 1080409
customer order: creation 176.82 368 21218
customer order: refuse 15.38 299 1845
order to supplier: creation 47.82 1316 5738
order to supplier: removal 3.37 103288 404
invoice (draft): creation 48.76 3451 5851
invoice (draft): removal 49.24 859 5909
invoice accept: apply 43.08 18835 5169
invoice accept: cancel 43.76 859 5251
customer reserve: creation 3996.41 185 479569
customer reserve: removal 4284.98 64 514197
realization accept: apply 85.32 177 10238
realization accept: cancel 74.35 123 8922
payment from customer: creation 62.92 109 7550
payment from customer: removal 6.05 98 726
payment to supplier: creation 50.38 129 6046
payment to supplier: removal 3.38 102 405
service: total inventory turnovers 3.99 3296 479
service: total monetary turnovers 6.40 534 768
service: aggregate perf. data 1.00 17474 120
service: refresh index statistics 0.03 356027 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 22-02-2021 10:29:22 to 22-02-2021 10:29:25.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4109
TEST_TIME 2 4359
TEST_TIME 3 5088
TEST_TIME 4 5226
TEST_TIME 5 5469
TEST_TIME 6 5727
TEST_TIME 7 5851
TEST_TIME 8 6011
TEST_TIME 9 6097
TEST_TIME 10 6064
TEST_TIME 11 6241
TEST_TIME 12 6499
TEST_TIME 13 6612
TEST_TIME 14 6922
TEST_TIME 15 6956
TEST_TIME 16 7058
TEST_TIME 17 7473
TEST_TIME 18 7849
TEST_TIME 19 8260
TEST_TIME 20 8470
TEST_TIME 21 8810
TEST_TIME 22 9079
TEST_TIME 23 9760
TEST_TIME 24 9844
TEST_TIME 25 10356
TEST_TIME 26 10505
TEST_TIME 27 10925
TEST_TIME 28 11027
TEST_TIME 29 10925
TEST_TIME 30 10696
TEST_TIME 31 10992
TEST_TIME 32 10722
TEST_TIME 33 10569
TEST_TIME 34 10308
TEST_TIME 35 10124
TEST_TIME 36 10044
TEST_TIME 37 9946
TEST_TIME 38 10033
TEST_TIME 39 9905
TEST_TIME 40 9867
TEST_TIME 41 9848
TEST_TIME 42 9821
TEST_TIME 43 9791
TEST_TIME 44 9672
TEST_TIME 45 9728
TEST_TIME 46 9699
TEST_TIME 47 9741
TEST_TIME 48 9716
TEST_TIME 49 9713
TEST_TIME 50 9696
TEST_TIME 51 9758
TEST_TIME 52 9709
TEST_TIME 53 9761
TEST_TIME 54 9749
TEST_TIME 55 9844
TEST_TIME 56 9800
TEST_TIME 57 9888
TEST_TIME 58 9828
TEST_TIME 59 9702
TEST_TIME 60 9594
TEST_TIME 61 9707
TEST_TIME 62 9664
TEST_TIME 63 9598
TEST_TIME 64 9541
TEST_TIME 65 9428
TEST_TIME 66 9327
TEST_TIME 67 9333
TEST_TIME 68 9303
TEST_TIME 69 9381
TEST_TIME 70 9444
TEST_TIME 71 9386
TEST_TIME 72 9442
TEST_TIME 73 9407
TEST_TIME 74 9534
TEST_TIME 75 9525
TEST_TIME 76 9507
TEST_TIME 77 9467
TEST_TIME 78 9539
TEST_TIME 79 9502
TEST_TIME 80 9534
TEST_TIME 81 9521
TEST_TIME 82 9433
TEST_TIME 83 9361
TEST_TIME 84 9294
TEST_TIME 85 9296
TEST_TIME 86 9270
TEST_TIME 87 9246
TEST_TIME 88 9203
TEST_TIME 89 9199
TEST_TIME 90 9163
TEST_TIME 91 9131
TEST_TIME 92 9154
TEST_TIME 93 9234
TEST_TIME 94 9225
TEST_TIME 95 9258
TEST_TIME 96 9262
TEST_TIME 97 9306
TEST_TIME 98 9308
TEST_TIME 99 9234
TEST_TIME 100 9162
TEST_TIME 101 9140
TEST_TIME 102 9101
TEST_TIME 103 9069
TEST_TIME 104 9080
TEST_TIME 105 9059
TEST_TIME 106 9040
TEST_TIME 107 9023
TEST_TIME 108 8992
TEST_TIME 109 9057
TEST_TIME 110 9099
TEST_TIME 111 9104
TEST_TIME 112 9092
TEST_TIME 113 9106
TEST_TIME 114 9123
TEST_TIME 115 9154
TEST_TIME 116 9156
TEST_TIME 117 9175
TEST_TIME 118 9117
TEST_TIME 119 9064
TEST_TIME 120 9011

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 22-02-2021 10:29:26 to 22-02-2021 10:29:29.

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 1522172 1522172 0 0.00 0 21684 8 0 0
doc_list_biud 1522172 1522172 0 0.00 0 52967 28 0 0
sp_add_invoice_to_stock 5311 5169 142 2.67 39 205211 18836 0 142
sp_cancel_adding_invoice 5251 5251 0 0.00 5 66974 859 0 0
sp_cancel_client_order 1845 1845 0 0.00 4 11347 300 0 0
sp_cancel_customer_reserve 514197 514197 0 0.00 1 52971 64 0 0
sp_cancel_pay_from_customer 726 726 0 0.00 3 7726 99 0 0
sp_cancel_pay_to_supplier 405 405 0 0.00 3 4176 102 0 0
sp_cancel_supplier_invoice 5919 5909 10 0.16 2 12928 859 0 10
sp_cancel_supplier_order 404 404 0 0.00 14 1213069 103289 0 0
sp_cancel_write_off 8922 8922 0 0.00 1 7223 123 0 0
sp_client_order 21218 21218 0 0.00 4 46347 369 0 0
sp_customer_reserve 479569 479569 0 0.00 0 92035 185 0 0
sp_fill_shopping_cart 32796 32796 0 0.00 1 23305 38 0 0
sp_fill_shopping_cart_clo_res 479558 479558 0 0.00 0 20277 15 0 0
sp_get_clo_for_invoice 5158 5158 0 0.00 0 36612 358 0 0
sp_kill_cost_storno 15304 15304 0 0.00 0 7219 56 0 0
sp_kill_qstorno_ret_qs2qd 1043629 1043629 0 0.00 0 52958 35 0 0
sp_kill_qty_storno 551924 551924 0 0.00 0 52967 75 0 0
sp_lock_selected_doc 21357 21357 0 0.00 0 7446 4 0 0
sp_make_cost_storno 28992 28992 0 0.00 0 21231 56 0 0
sp_multiply_rows_for_pdistr 28992 28992 0 0.00 0 7765 33 0 0
sp_multiply_rows_for_qdistr 21218 21218 0 0.00 0 46327 193 0 0
sp_pay_from_customer 7550 7550 0 0.00 4 14274 110 0 0
sp_pay_to_supplier 6046 6046 0 0.00 5 15715 130 0 0
sp_payment_common 13596 13596 0 0.00 1 8109 25 0 0
sp_qd_handle_on_cancel_clo 1845 1845 0 0.00 0 11320 273 0 0
sp_qd_handle_on_invoice_upd_sts 10409 10409 0 0.00 0 29998 357 0 0
sp_qd_handle_on_reserve_upd_sts 10238 10238 0 0.00 0 22819 51 0 0
sp_reserve_write_off 10238 10238 0 0.00 7 22834 178 0 0
sp_supplier_invoice 5851 5851 0 0.00 5 95520 3451 0 0
sp_supplier_order 5738 5738 0 0.00 6 22206 1317 0 0
srv_aggregate_perf_data 120 120 0 0.00 2602 713272 17474 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 2225 119286 44739 0 0
srv_find_qd_qs_mism 1029691 1029691 0 0.00 0 21683 7 0 0
srv_make_invnt_saldo 479 479 0 0.00 61 102712 3296 0 0
srv_make_money_saldo 768 768 0 0.00 1 13354 535 0 0
srv_recalc_idx_stat 4 4 0 0.00 126313 549582 356027 0 0
t$perf-abend:sp_add_invoice_to_stock 142 142 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5170 5170 0 0.00 0 8552 195 0 0
t$perf-norm:sp_cancel_adding_invoice 389 389 0 0.00 0 1611 73 0 0
t$perf-norm:sp_cancel_client_order 1845 1845 0 0.00 0 2456 13 0 0
t$perf-norm:sp_cancel_customer_reserve 729 729 0 0.00 0 4631 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 726 726 0 0.00 0 1311 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 405 405 0 0.00 0 319 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 431 431 0 0.00 0 450 9 0 0
t$perf-norm:sp_cancel_supplier_order 404 404 0 0.00 0 21509 1410 0 0
t$perf-norm:sp_cancel_write_off 1033 1033 0 0.00 0 873 4 0 0
t$perf-norm:sp_client_order 21219 21219 0 0.00 0 7551 18 0 0
t$perf-norm:sp_customer_reserve 1060 1060 0 0.00 0 2958 9 0 0
t$perf-norm:sp_pay_from_customer 7550 7550 0 0.00 0 7428 10 0 0
t$perf-norm:sp_pay_to_supplier 6046 6046 0 0.00 0 3863 9 0 0
t$perf-norm:sp_reserve_write_off 10238 10238 0 0.00 0 8236 12 0 0
t$perf-norm:sp_supplier_invoice 5851 5851 0 0.00 0 5075 20 0 0
t$perf-norm:sp_supplier_order 5738 5738 0 0.00 0 2797 15 0 0
t$perf-norm:srv_aggregate_perf_data 120 120 0 0.00 0 565 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 1307 28 0 0
t$perf-norm:srv_make_invnt_saldo 479 479 0 0.00 0 351 8 0 0
t$perf-norm:srv_make_money_saldo 768 768 0 0.00 0 7066 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10598 10314 284 2.67 0 5027 19 0 284
v_all_customers 38844 38844 0 0.00 0 70 1 0 0
v_all_suppliers 31868 31868 0 0.00 0 23 1 0 0
v_all_wares 42434 42434 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 778 778 0 0.00 0 3582 13 0 0
v_cancel_client_order 3690 3690 0 0.00 0 4013 7 0 0
v_cancel_customer_prepayment 1452 1452 0 0.00 0 5200 19 0 0
v_cancel_customer_reserve 1458 1458 0 0.00 0 437 3 0 0
v_cancel_payment_to_supplier 810 810 0 0.00 0 1798 15 0 0
v_cancel_supplier_invoice 882 862 20 2.26 0 1888 17 0 20
v_cancel_supplier_order 808 808 0 0.00 0 1655 8 0 0
v_cancel_write_off 2066 2066 0 0.00 0 3783 15 0 0
v_max_id_clo_ord 5736 5736 0 0.00 0 3856 3 0 0
v_max_id_clo_res 1060 1060 0 0.00 0 2619 12 0 0
v_max_id_ord_sup 5843 5843 0 0.00 0 7231 4 0 0
v_max_non_paid_invoice 1691 1691 0 0.00 0 827 3 0 0
v_max_non_paid_realizn 3937 3937 0 0.00 0 1810 3 0 0
v_min_id_clo_ord 5736 5736 0 0.00 0 4052 6 0 0
v_min_id_clo_res 1060 1060 0 0.00 0 4020 6 0 0
v_min_id_ord_sup 5843 5843 0 0.00 0 7305 8 0 0
v_min_non_paid_invoice 6046 6046 0 0.00 0 7685 16 0 0
v_min_non_paid_realizn 7550 7550 0 0.00 0 6747 17 0 0
v_our_firm 10814 10814 0 0.00 0 31 1 0 0
v_reserve_write_off 20476 20476 0 0.00 0 5068 7 0 0
x_lock_depdocs_on_canc_invoice 10729 10729 0 0.00 0 14627 115 0 0
x_lock_depdocs_on_canc_sup_ord 404 404 0 0.00 0 4738 308 0 0
x_make_qstorno_1000_1200 5738 5738 0 0.00 2 22198 1239 0 0
x_make_qstorno_1200_2000 5851 5851 0 0.00 2 95462 3314 0 0
x_make_qstorno_2100_3300 479569 479569 0 0.00 0 92034 168 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 22-02-2021 10:29:30 to 22-02-2021 10:29:35.

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
07:57:02 2 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
07:58:04 31719 8192000000 9723254192 10019966976 797170016 8.868 100 90 179 0 826757888 7754192 21833680
07:59:44 20118 8192000000 9771954064 10078220288 841409680 9.314 100 87 173 1 876120304 14073152 20637472
08:01:10 20587 8192000000 9815677056 10133692416 872275808 9.623 100 90 180 0 921278928 28075360 20927760
08:02:38 11975 8192000000 9872851216 10203803648 915551856 10.052 100 83 166 1 981253632 47258656 18443120
08:03:54 14070 8192000000 9912920864 10256830464 961933136 10.508 100 78 154 1 1027752976 50046512 15773328
08:05:12 4642 8192000000 9968946704 10320375808 998134352 10.860 100 86 172 0 1076498080 61388016 16975712
08:06:20 2487 8192000000 9999504896 10360422400 1030395424 11.172 100 92 184 1 1113633856 63809408 19429024
08:07:24 1883 8192000000 10028884144 10396868608 1055648160 11.415 100 90 179 1 1147616592 73879696 18088736
08:08:28 6014 8192000000 10036623136 10410733568 1071647472 11.568 100 87 175 1 1150259568 60736592 17875504
08:09:34 5339 8192000000 10004073392 10388058112 1081318368 11.660 100 51 99 0 1121981952 30296400 10367184
08:10:40 30421 8192000000 9993364240 10393001984 1083328144 11.679 100 42 84 0 1106071952 13992608 8751200
08:12:11 5550 8192000000 10005792000 10414366720 1092242176 11.764 100 30 60 0 1123151872 25069968 5839728
08:13:17 6370 8192000000 9996620944 10400243712 1096661152 11.806 100 31 60 1 1118521696 15479872 6380672
08:14:25 6801 8192000000 10038358592 10438737920 1101086432 11.848 100 45 90 1 1136186016 25370880 9728704
08:15:35 23460 8192000000 10017152736 10414678016 1106241168 11.897 100 52 104 3 1135913312 18043632 11628512
08:17:00 7049 8192000000 10019647664 10423980032 1080223712 11.650 98 36 72 0 1114176864 26552560 7400592
08:18:09 8680 8192000000 9965275696 10360766464 1011818240 10.993 94 33 63 2 1043713728 25063200 6832288
08:19:18 7340 8192000000 9983917088 10370179072 1022033536 11.092 97 36 71 0 1051334208 22025008 7275664
08:20:26 49274 8192000000 9960599520 10359029760 991707232 10.798 96 31 62 0 1030876272 32905824 6263216
08:22:21 14586 8192000000 9912251360 10297253888 976805136 10.653 96 30 60 1 1002909392 19169040 6935216
08:23:37 5164 8192000000 9940269152 10329899008 1021556032 11.087 99 51 102 4 1057340112 24531040 11253040
08:24:43 10389 8192000000 9962403888 10362146816 1048983120 11.351 100 23 42 2 1069554624 16310992 4260512
08:25:56 45017 8192000000 9969634656 10381021184 1057280880 11.430 100 30 60 0 1088152544 24557296 6314368
08:27:44 20238 8192000000 9999309728 10399436800 1077388528 11.623 100 52 104 2 1116884448 28453344 11042576
08:29:15 11200 8192000000 10049282528 10447081472 1084885168 11.694 100 57 112 2 1155638272 58843632 11909472
08:30:30 3999 8192000000 10075771376 10469826560 1101263536 11.850 100 62 120 2 1185107648 71575888 12268224
08:31:36 8570 8192000000 10089454272 10492346368 1113797200 11.968 100 45 89 0 1202957968 79285296 9875472
08:32:50 40603 8192000000 10140526992 10547560448 1117023456 11.999 100 45 90 1 1233187104 106157888 10005760
08:34:37 6101 8192000000 10162968080 10568409088 1135863968 12.177 100 57 114 2 1266327712 117430960 13032784
08:35:47 10398 8192000000 10198123168 10612367360 1145521984 12.267 100 50 99 0 1311037568 154561824 10953760
08:37:06 6754 8192000000 10241729952 10650886144 1152787168 12.336 100 53 106 0 1342507152 177789472 11930512
08:38:17 2605 8192000000 10255492384 10649800704 1152831680 12.336 99 80 160 1 1346879312 176743632 17304000
08:39:23 3103 8192000000 10308433536 10711646208 1175818608 12.551 100 80 157 2 1415466528 222213808 17434112
08:40:32 3107 8192000000 10319456256 10722779136 1169514528 12.492 100 86 171 1 1423679408 234718080 19446800
08:41:43 3313 8192000000 10375229120 10780876800 1172726160 12.522 100 86 171 0 1481706512 288126592 20853760
08:42:51 7582 8192000000 10393798272 10807689216 1162744640 12.429 100 79 158 1 1493617792 312450432 18422720
08:44:06 3793 8192000000 10408069872 10815078400 1152686016 12.335 100 68 135 1 1510742064 342710832 15345216
08:45:16 2223 8192000000 10372542992 10771025920 1129233248 12.114 100 82 159 4 1469575280 321585248 18756784
08:46:21 7319 8192000000 10367173264 10783137792 1136747216 12.185 99 49 98 2 1464681552 315868448 12065888
08:47:35 6890 8192000000 10366010096 10815508480 1150495200 12.314 100 39 76 1 1466346112 305641088 10209824
08:48:43 17625 8192000000 10356507952 10805063680 1132653728 12.146 100 36 72 1 1466402640 325052928 8695984
08:50:03 27071 8192000000 10235098784 10691231744 1120240896 12.029 99 20 40 0 1342790192 217262784 5286512
08:51:34 16884 8192000000 10198934448 10637340672 1104278272 11.878 99 18 36 1 1294120656 185462128 4380256
08:52:53 20442 8192000000 10128552464 10634743808 1113262528 11.963 100 8 16 0 1229476960 113907360 2307072
08:54:54 89278 8192000000 10113031504 10559193088 1100494640 11.842 97 22 44 0 1140886704 35297920 5094144
08:57:41 107439 8192000000 10062326048 10525839360 1118703328 12.015 99 12 23 1 1158047120 37415344 1928448
09:01:10 69840 8192000000 10089062336 10526007296 1122343072 12.049 99 27 54 1 1163450848 35768192 5339584
09:03:20 37924 8192000000 10131482784 10577600512 1117180208 12.000 98 42 84 0 1227686912 101223216 9283488
09:04:59 69079 8192000000 10093556656 10563428352 1123307968 12.058 99 25 50 0 1203671472 74856640 5506864
09:07:09 40261 8192000000 10093248448 10564993024 1141191776 12.227 100 9 18 0 1200467312 57219120 2056416
09:08:49 64235 8192000000 10106762928 10589650944 1147019200 12.282 99 11 23 1 1199062688 49965696 2077792
09:10:55 78491 8192000000 10082062272 10549682176 1137172336 12.189 98 15 29 1 1189496848 49594704 2729808
09:13:14 55423 8192000000 10104521568 10585538560 1150069376 12.310 99 39 79 0 1208821840 50527104 8225360
09:15:11 35482 8192000000 10090253680 10551795712 1151224112 12.321 100 21 42 1 1192616544 37088512 4303920
09:16:48 42508 8192000000 10115136912 10590179328 1161387376 12.416 100 27 54 0 1245446912 78358848 5700688
09:18:30 58548 8192000000 10059330000 10497622016 1094270096 11.783 95 18 36 0 1143080352 45356720 3453536
09:20:30 66955 8192000000 10118780080 10595627008 1119912544 12.026 97 34 68 2 1223425056 96388976 7123536
09:23:47 43090 8192000000 10098059024 10546253824 1098666112 11.825 96 13 26 0 1150511728 49208432 2637184
09:25:30 119286 8192000000 10124617008 10609754112 1126060576 12.084 99 19 37 2 1226895296 96826608 4008112
09:28:37 27216 8192000000 10050559280 10494390272 1122190016 12.048 99 14 28 0 1153122384 28260208 2672160
09:30:15 43741 8192000000 10077788016 10547589120 1121122224 12.038 99 8 16 0 1172742064 49557728 2062112
09:31:59 55059 8192000000 10121920000 10575523840 1118810128 12.016 99 15 30 0 1203972544 81685296 3477120
09:33:55 49924 8192000000 10112333280 10577670144 1070396960 11.556 95 13 26 1 1160635424 87286048 2952416
09:35:46 51575 8192000000 10058864656 10534776832 1076474416 11.614 97 16 32 0 1190268832 109739408 4055008
09:37:42 40358 8192000000 10057912720 10511335424 1083168272 11.678 97 35 70 1 1143351040 53179392 7003376
09:39:23 16222 8192000000 10109699984 10525077504 1116095888 11.990 99 45 90 0 1185446112 59894048 9456176
09:40:42 104454 8192000000 10068910752 10500923392 1095053712 11.791 97 27 54 0 1172595232 72145120 5396400
09:43:26 78555 8192000000 10067291136 10541527040 1114812768 11.978 98 27 53 2 1189392000 69172912 5406320
09:45:45 50519 8192000000 10118591792 10544017408 1093329984 11.774 96 35 70 1 1176791616 76635392 6826240
09:47:39 76562 8192000000 10067510512 10571808768 1116847952 11.997 99 18 36 1 1188147632 67793136 3506544
09:50:22 49488 8192000000 10072355264 10529734656 1120387696 12.031 99 6 12 0 1166678064 45045248 1245120
09:52:18 53193 8192000000 10054575840 10514395136 1124820112 12.073 99 7 13 1 1155499136 29389584 1289440
09:54:16 22172 8192000000 10080575280 10520870912 1127236048 12.095 99 48 96 1 1168464240 30620864 10607328
09:55:41 37332 8192000000 10118116048 10556522496 1122289136 12.049 98 23 46 2 1187311904 60336736 4686032
09:57:25 52701 8192000000 10149171360 10620088320 1133293248 12.152 98 35 69 1 1251564800 111102640 7168912
09:59:20 77859 8192000000 10095609328 10570375168 1143172576 12.245 100 19 38 0 1216705600 68388880 5144144
10:01:43 115405 8192000000 10102223856 10618703872 1117404496 12.002 97 10 20 0 1169971392 50084880 2482016
10:04:38 112141 8192000000 10093124576 10546855936 1121980848 12.046 98 30 58 2 1156167232 28473136 5713248
10:07:39 40239 8192000000 10091957952 10575839232 1151286416 12.322 100 10 20 1 1195236256 42242480 1707360
10:09:24 46475 8192000000 10093502000 10578661376 1145400624 12.266 100 5 10 1 1194085488 47788768 896096
10:11:11 49357 8192000000 10098019376 10556825600 1102671408 11.863 95 18 36 1 1161090848 55120352 3299088
10:13:03 61839 8192000000 10131155360 10586312704 1114122880 11.971 96 30 59 1 1195379712 74626224 6630608
10:15:19 97752 8192000000 10101732912 10579337216 1087126896 11.715 95 11 22 0 1142256784 52842352 2287536
10:18:09 79260 8192000000 10091212304 10553442304 1096024192 11.800 96 28 56 0 1154153376 52505504 5623680
10:20:28 32820 8192000000 10135205488 10603888640 1124084960 12.066 99 35 70 0 1218866736 87804048 6977728
10:22:08 29041 8192000000 10135937920 10565578752 1143817936 12.251 100 45 90 0 1201907376 49154608 8934832
10:23:40 117417 8192000000 10029907600 10467667968 1094244736 11.783 97 23 46 1 1125569920 26251824 5073360
10:26:47 31625 8192000000 9637240400 10031820800 748403120 8.371 67 1 2 0 772221616 23783088 35408

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 22-02-2021 10:29:36 to 22-02-2021 10:29:52.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 142 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-02-2021 10:29:53 to 22-02-2021 10:29:55.

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          241235
MON$OLDEST_ACTIVE               241236
MON$OLDEST_SNAPSHOT             241236
MON$NEXT_TRANSACTION            241236
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                       2095296
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             811
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2372 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2372 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2372 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.2372 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 ---
:79: 
:80: mscs-test-161.budzdorov.ru    Mon Feb 22 10:27:23 2021
:81:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:82: 
:83: 
:84: mscs-test-161.budzdorov.ru    Mon Feb 22 10:27:31 2021
:85:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:86: 
:87: 
:88: mscs-test-161.budzdorov.ru    Mon Feb 22 10:27:37 2021
:89:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:90: 
--- end of diff output ---

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

22.02.21 10:29:56. 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-22 10:29:58 No severe PSQL-related problems occured
22.02.21 10:30:00. End of report.