Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.12.2020 04:27
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 16525648 kB
MemAvailable 31353492 kB
Buffers 20 kB
Cached 14962284 kB
SwapCached 3712 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 2020-12-17 04:26:55
DTS_END                         2020-12-17 04:26:54.9680
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/34302
TRN_ID                          227706
ATT_ID                          674
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 9104.83 22357 1092579
customer order: creation 163.13 462 19575
customer order: refuse 14.63 327 1756
order to supplier: creation 42.99 1412 5159
order to supplier: removal 3.29 119601 395
invoice (draft): creation 42.95 3421 5154
invoice (draft): removal 54.24 901 6509
invoice accept: apply 38.69 17192 4643
invoice accept: cancel 48.14 836 5777
customer reserve: creation 3660.35 163 439242
customer reserve: removal 4757.12 66 570854
realization accept: apply 78.17 186 9380
realization accept: cancel 82.68 122 9921
payment from customer: creation 54.55 125 6546
payment from customer: removal 5.33 156 639
payment to supplier: creation 45.41 145 5449
payment to supplier: removal 2.98 111 358
service: total inventory turnovers 3.43 3434 411
service: total monetary turnovers 6.07 453 728
service: aggregate perf. data 0.64 24215 77
service: refresh index statistics 0.05 273817 6

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-12-2020 04:28:14 to 17-12-2020 04:28:16.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2175
TEST_TIME 2 2871
TEST_TIME 3 3791
TEST_TIME 4 3864
TEST_TIME 5 3840
TEST_TIME 6 3897
TEST_TIME 7 4306
TEST_TIME 8 4977
TEST_TIME 9 5318
TEST_TIME 10 5620
TEST_TIME 11 5620
TEST_TIME 12 6000
TEST_TIME 13 6172
TEST_TIME 14 6540
TEST_TIME 15 6640
TEST_TIME 16 7184
TEST_TIME 17 7430
TEST_TIME 18 8220
TEST_TIME 19 8312
TEST_TIME 20 8874
TEST_TIME 21 9245
TEST_TIME 22 9221
TEST_TIME 23 9317
TEST_TIME 24 9669
TEST_TIME 25 10030
TEST_TIME 26 11091
TEST_TIME 27 10857
TEST_TIME 28 10716
TEST_TIME 29 10721
TEST_TIME 30 10622
TEST_TIME 31 10624
TEST_TIME 32 10396
TEST_TIME 33 10191
TEST_TIME 34 10018
TEST_TIME 35 10005
TEST_TIME 36 9832
TEST_TIME 37 9745
TEST_TIME 38 9786
TEST_TIME 39 9714
TEST_TIME 40 9855
TEST_TIME 41 9820
TEST_TIME 42 9768
TEST_TIME 43 9676
TEST_TIME 44 9875
TEST_TIME 45 9854
TEST_TIME 46 9822
TEST_TIME 47 9937
TEST_TIME 48 9862
TEST_TIME 49 10131
TEST_TIME 50 10089
TEST_TIME 51 10125
TEST_TIME 52 10145
TEST_TIME 53 10022
TEST_TIME 54 9878
TEST_TIME 55 9758
TEST_TIME 56 9759
TEST_TIME 57 9656
TEST_TIME 58 9656
TEST_TIME 59 9570
TEST_TIME 60 9573
TEST_TIME 61 9540
TEST_TIME 62 9470
TEST_TIME 63 9486
TEST_TIME 64 9460
TEST_TIME 65 9615
TEST_TIME 66 9544
TEST_TIME 67 9536
TEST_TIME 68 9559
TEST_TIME 69 9557
TEST_TIME 70 9735
TEST_TIME 71 9665
TEST_TIME 72 9767
TEST_TIME 73 9727
TEST_TIME 74 9600
TEST_TIME 75 9526
TEST_TIME 76 9461
TEST_TIME 77 9413
TEST_TIME 78 9390
TEST_TIME 79 9345
TEST_TIME 80 9366
TEST_TIME 81 9339
TEST_TIME 82 9290
TEST_TIME 83 9226
TEST_TIME 84 9217
TEST_TIME 85 9336
TEST_TIME 86 9299
TEST_TIME 87 9305
TEST_TIME 88 9429
TEST_TIME 89 9426
TEST_TIME 90 9448
TEST_TIME 91 9472
TEST_TIME 92 9381
TEST_TIME 93 9346
TEST_TIME 94 9317
TEST_TIME 95 9267
TEST_TIME 96 9224
TEST_TIME 97 9224
TEST_TIME 98 9194
TEST_TIME 99 9189
TEST_TIME 100 9190
TEST_TIME 101 9140
TEST_TIME 102 9124
TEST_TIME 103 9260
TEST_TIME 104 9403
TEST_TIME 105 9428
TEST_TIME 106 9399
TEST_TIME 107 9436
TEST_TIME 108 9435
TEST_TIME 109 9428
TEST_TIME 110 9354
TEST_TIME 111 9331
TEST_TIME 112 9278
TEST_TIME 113 9227
TEST_TIME 114 9210
TEST_TIME 115 9190
TEST_TIME 116 9177
TEST_TIME 117 9174
TEST_TIME 118 9151
TEST_TIME 119 9142
TEST_TIME 120 9109

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-12-2020 04:28:18 to 17-12-2020 04:28:21.

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 1499296 1499296 0 0.00 0 18025 6 0 0
doc_list_biud 1499293 1499293 0 0.00 0 66753 32 0 0
sp_add_invoice_to_stock 4779 4643 136 2.84 28 157450 17192 0 136
sp_cancel_adding_invoice 5777 5777 0 0.00 4 60077 837 0 0
sp_cancel_client_order 1756 1756 0 0.00 4 10260 327 0 0
sp_cancel_customer_reserve 570854 570854 0 0.00 1 66754 66 0 0
sp_cancel_pay_from_customer 639 639 0 0.00 3 15443 157 0 0
sp_cancel_pay_to_supplier 358 358 0 0.00 3 11138 112 0 0
sp_cancel_supplier_invoice 6517 6509 8 0.12 2 37742 901 0 8
sp_cancel_supplier_order 395 395 0 0.00 8 909760 119602 0 0
sp_cancel_write_off 9921 9921 0 0.00 1 20834 122 0 0
sp_client_order 19575 19575 0 0.00 4 19446 462 0 0
sp_customer_reserve 439242 439242 0 0.00 0 67792 164 0 0
sp_fill_shopping_cart 29872 29872 0 0.00 1 15772 39 0 0
sp_fill_shopping_cart_clo_res 439237 439237 0 0.00 0 28511 20 0 0
sp_get_clo_for_invoice 4638 4638 0 0.00 0 47618 484 0 0
sp_kill_cost_storno 16695 16695 0 0.00 0 15439 59 0 0
sp_kill_qstorno_ret_qs2qd 1158533 1158533 0 0.00 0 66688 36 0 0
sp_kill_qty_storno 609230 609230 0 0.00 0 66753 77 0 0
sp_lock_selected_doc 19497 19497 0 0.00 0 6968 5 0 0
sp_make_cost_storno 26013 26013 0 0.00 0 18023 50 0 0
sp_multiply_rows_for_pdistr 26013 26013 0 0.00 0 16623 37 0 0
sp_multiply_rows_for_qdistr 19572 19572 0 0.00 0 19435 275 0 0
sp_pay_from_customer 6546 6546 0 0.00 4 18288 126 0 0
sp_pay_to_supplier 5449 5449 0 0.00 5 16713 146 0 0
sp_payment_common 11995 11995 0 0.00 1 16636 36 0 0
sp_qd_handle_on_cancel_clo 1756 1756 0 0.00 0 10249 293 0 0
sp_qd_handle_on_invoice_upd_sts 10415 10415 0 0.00 0 18750 370 0 0
sp_qd_handle_on_reserve_upd_sts 9380 9380 0 0.00 0 9398 57 0 0
sp_reserve_write_off 9380 9380 0 0.00 7 20158 186 0 0
sp_supplier_invoice 5154 5154 0 0.00 8 95437 3421 0 0
sp_supplier_order 5159 5159 0 0.00 5 103058 1412 0 0
srv_aggregate_perf_data 77 77 0 0.00 2503 406545 24216 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 3415 129122 45550 0 0
srv_find_qd_qs_mism 1047776 1047776 0 0.00 0 16664 5 0 0
srv_make_invnt_saldo 411 411 0 0.00 70 96929 3435 0 0
srv_make_money_saldo 728 728 0 0.00 1 17874 453 0 0
srv_recalc_idx_stat 6 6 0 0.00 109791 368306 273817 0 0
t$perf-abend:sp_add_invoice_to_stock 136 136 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4643 4643 0 0.00 0 19091 175 0 0
t$perf-norm:sp_cancel_adding_invoice 352 352 0 0.00 1 3147 80 0 0
t$perf-norm:sp_cancel_client_order 1756 1756 0 0.00 0 4679 12 0 0
t$perf-norm:sp_cancel_customer_reserve 623 623 0 0.00 0 4105 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 639 639 0 0.00 0 1939 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 358 358 0 0.00 0 260 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 401 401 0 0.00 0 2458 15 0 0
t$perf-norm:sp_cancel_supplier_order 395 395 0 0.00 0 19559 1581 0 0
t$perf-norm:sp_cancel_write_off 955 955 0 0.00 0 4511 9 0 0
t$perf-norm:sp_client_order 19575 19575 0 0.00 0 5994 14 0 0
t$perf-norm:sp_customer_reserve 862 862 0 0.00 0 3774 9 0 0
t$perf-norm:sp_pay_from_customer 6546 6546 0 0.00 0 4345 9 0 0
t$perf-norm:sp_pay_to_supplier 5449 5449 0 0.00 0 10674 9 0 0
t$perf-norm:sp_reserve_write_off 9380 9380 0 0.00 0 15210 11 0 0
t$perf-norm:sp_supplier_invoice 5154 5154 0 0.00 0 1856 13 0 0
t$perf-norm:sp_supplier_order 5159 5159 0 0.00 0 3506 13 0 0
t$perf-norm:srv_aggregate_perf_data 77 77 0 0.00 0 80 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 2084 38 0 0
t$perf-norm:srv_make_invnt_saldo 411 411 0 0.00 0 1611 13 0 0
t$perf-norm:srv_make_money_saldo 728 728 0 0.00 0 292 3 0 0
t$perf-norm:srv_recalc_idx_stat 6 6 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 9548 9276 272 2.84 0 6245 18 0 272
v_all_customers 35540 35540 0 0.00 0 15 1 0 0
v_all_suppliers 28652 28652 0 0.00 0 103 1 0 0
v_all_wares 39142 39142 0 0.00 0 104 1 0 0
v_cancel_adding_invoice 704 704 0 0.00 0 3774 12 0 0
v_cancel_client_order 3512 3512 0 0.00 0 3359 8 0 0
v_cancel_customer_prepayment 1278 1278 0 0.00 0 2617 8 0 0
v_cancel_customer_reserve 1246 1246 0 0.00 0 3396 9 0 0
v_cancel_payment_to_supplier 716 716 0 0.00 0 6049 12 0 0
v_cancel_supplier_invoice 818 802 16 1.95 0 626 15 0 16
v_cancel_supplier_order 790 790 0 0.00 0 4869 10 0 0
v_cancel_write_off 1910 1910 0 0.00 0 4635 17 0 0
v_max_id_clo_ord 5157 5157 0 0.00 0 3910 2 0 0
v_max_id_clo_res 862 862 0 0.00 0 1704 8 0 0
v_max_id_ord_sup 5142 5142 0 0.00 0 9899 6 0 0
v_max_non_paid_invoice 1421 1421 0 0.00 0 184 2 0 0
v_max_non_paid_realizn 3437 3437 0 0.00 0 998 3 0 0
v_min_id_clo_ord 5157 5157 0 0.00 0 5889 7 0 0
v_min_id_clo_res 862 862 0 0.00 0 7733 24 0 0
v_min_id_ord_sup 5142 5142 0 0.00 0 3110 6 0 0
v_min_non_paid_invoice 5449 5449 0 0.00 0 16622 33 0 0
v_min_non_paid_realizn 6546 6546 0 0.00 0 16531 26 0 0
v_our_firm 9820 9820 0 0.00 0 19 1 0 0
v_reserve_write_off 18760 18760 0 0.00 0 4886 6 0 0
x_lock_depdocs_on_canc_invoice 11885 11885 0 0.00 0 31942 100 0 0
x_lock_depdocs_on_canc_sup_ord 395 395 0 0.00 0 18298 333 0 0
x_make_qstorno_1000_1200 5159 5159 0 0.00 2 98704 1336 0 0
x_make_qstorno_1200_2000 5154 5154 0 0.00 3 95428 3281 0 0
x_make_qstorno_2100_3300 439242 439242 0 0.00 0 67789 141 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-12-2020 04:28:22 to 17-12-2020 04:28:26.

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
01:56:58 6 8192000000 8796641776 8807108608 3168704 0.038 1 1 2 0 3266400 61840 35856
01:57:59 30751 8192000000 9726611488 10022023168 797050144 8.866 100 86 172 0 825849760 8210336 20589280
01:59:36 26825 8192000000 9778607440 10085175296 847293200 9.373 100 92 184 0 882865728 14153600 21418928
02:01:07 5330 8192000000 9810509232 10127896576 876733808 9.667 100 82 164 0 920648016 25060272 18853936
02:02:18 10090 8192000000 9875010592 10207076352 911776064 10.015 100 82 164 0 980577728 50045424 18756240
02:03:30 10360 8192000000 9923429344 10268794880 955758384 10.448 100 81 162 1 1041800384 69313968 16728032
02:04:43 6396 8192000000 9963301504 10316554240 993373088 10.814 100 84 167 1 1075517520 64886656 17257776
02:05:53 2568 8192000000 9983580448 10344980480 1025528064 11.125 100 85 169 0 1104131472 61709568 16893840
02:06:58 1656 8192000000 10026894304 10393620480 1054956736 11.408 100 88 176 1 1143754800 71685056 17113008
02:08:01 1645 8192000000 10040622464 10415570944 1069381744 11.546 100 94 188 0 1153257744 64203664 19672336
02:09:03 10910 8192000000 10012200176 10413424640 1073829264 11.589 100 43 86 1 1142185152 59923472 8432416
02:10:17 6230 8192000000 9993655712 10393698304 1083414960 11.680 100 46 90 0 1110161808 17117472 9629376
02:11:27 5378 8192000000 10005419968 10402234368 1091540320 11.757 100 44 87 4 1117359008 16731872 9086816
02:12:34 18077 8192000000 10006181808 10407030784 1096660768 11.806 100 38 76 4 1134282000 28733968 8887264
02:13:52 25319 8192000000 10008178288 10423549952 1094135216 11.782 100 24 48 0 1125816928 26518704 5163008
02:15:17 6266 8192000000 10004968048 10412584960 1094115424 11.782 100 34 68 2 1126956512 25483392 7357696
02:16:26 5885 8192000000 10012198720 10414354432 1083944416 11.685 99 43 85 3 1111709600 18241152 9524032
02:17:34 60548 8192000000 9973000976 10372755456 1035634320 11.223 95 27 54 1 1071735072 29106096 6994656
02:19:35 6119 8192000000 9953362064 10349887488 1036479488 11.231 98 23 46 1 1072978544 31014976 5484080
02:20:43 4970 8192000000 9939890400 10320400384 1016640016 11.040 99 43 85 1 1037381136 10759392 9981728
02:21:52 6870 8192000000 9945193840 10329706496 1010443824 10.980 99 33 64 2 1039273840 21211728 7618288
02:23:00 70082 8192000000 9941126048 10346274816 1039369936 11.259 100 19 38 1 1062724496 19926352 3428208
02:25:11 8349 8192000000 9967398224 10365558784 1062555120 11.481 100 30 61 2 1082581840 13003648 7023072
02:26:21 6142 8192000000 9990481680 10398347264 1080893776 11.656 100 37 73 1 1100503008 11786432 7822800
02:27:33 8353 8192000000 10029615792 10417754112 1095824864 11.798 100 52 104 0 1129131312 21835120 11471328
02:28:43 58475 8192000000 10101647136 10511953920 1112539376 11.956 100 54 108 3 1207287984 83155296 11593312
02:31:15 17088 8192000000 10111234816 10518564864 1124089440 12.066 100 38 76 0 1209896320 77717856 8089024
02:32:39 19495 8192000000 10171468608 10579795968 1142539984 12.239 100 43 86 0 1273060768 120583760 9937024
02:34:03 10145 8192000000 10220558688 10623307776 1148124992 12.292 100 61 120 3 1311905040 150294048 13486000
02:35:21 9400 8192000000 10237824096 10647891968 1166158592 12.461 100 68 136 3 1340424400 159609392 14656416
02:36:37 5598 8192000000 10251115408 10668699648 1176030192 12.553 100 46 92 2 1351282688 164578960 10673536
02:37:56 6314 8192000000 10345230944 10762305536 1188674336 12.671 100 68 136 0 1446562432 242477616 15410480
02:39:08 3876 8192000000 10364412848 10777759744 1193568384 12.717 100 73 145 0 1459203248 248900032 16734832
02:40:18 5908 8192000000 10405357184 10810523648 1176267088 12.555 99 89 175 0 1502009296 306204528 19537680
02:41:29 11060 8192000000 10414366128 10836795392 1163757504 12.438 99 54 107 1 1504243008 327345904 13139600
02:42:45 3408 8192000000 10416847600 10822455296 1168413952 12.482 99 75 148 3 1510194992 324357152 17423888
02:43:52 5608 8192000000 10380819328 10812948480 1127710944 12.100 98 56 113 0 1474421920 333357744 13353232
02:45:03 12084 8192000000 10355703200 10774384640 1133782432 12.157 100 41 78 1 1444847904 301176416 9889056
02:46:22 7901 8192000000 10344637680 10766606336 1138451408 12.201 100 39 77 2 1450913712 302395520 10066784
02:47:33 14846 8192000000 10301307040 10750136320 1123345136 12.059 99 30 60 0 1417491200 286484048 7662016
02:48:54 16586 8192000000 10317466912 10749599744 1124577504 12.070 100 30 59 1 1418488704 286248752 7662448
02:50:22 24816 8192000000 10262474752 10719539200 1090033744 11.743 98 16 32 0 1360892000 266688016 4170240
02:51:47 25903 8192000000 10191228256 10714857472 1093146000 11.773 99 15 30 1 1309277888 212209552 3922336
02:53:16 21524 8192000000 10040812560 10443517952 1091171424 11.754 99 58 115 0 1120041136 16375696 12494016
02:54:43 43067 8192000000 10038943152 10462466048 1076092064 11.610 97 23 44 1 1127504576 46369584 5042928
02:56:43 71361 8192000000 10014152800 10442747904 1071851152 11.570 97 14 28 0 1133078832 58056752 3170928
02:58:54 61204 8192000000 10022839008 10451230720 1075040608 11.600 97 41 81 1 1112663248 29196480 8426160
03:01:02 4183 8192000000 10055806080 10469056512 1112162432 11.953 100 55 110 1 1147749728 23181808 12405488
03:02:08 18160 8192000000 10103415136 10527580160 1104664480 11.882 99 22 42 1 1190281440 80449072 5167888
03:03:28 22214 8192000000 10190264800 10619531264 1132427536 12.144 100 45 90 2 1280119440 137556080 10135824
03:04:58 33943 8192000000 10152014016 10632916992 1128880240 12.111 100 13 26 1 1258132000 126067424 3184336
03:06:44 40151 8192000000 10117574656 10571464704 1139212944 12.208 100 17 35 0 1234784304 92241344 3330016
03:08:30 41069 8192000000 10142187648 10592862208 1140684400 12.222 100 10 20 0 1232074320 88870400 2519520
03:10:11 84483 8192000000 10129452608 10573467648 1134751664 12.166 99 24 48 2 1269470624 128995728 5723232
03:12:36 36372 8192000000 10154776304 10596786176 1129324672 12.115 98 39 78 0 1218918192 80148608 9444912
03:14:15 101738 8192000000 10111795920 10629349376 1136882928 12.186 99 12 24 0 1221552416 82189040 2480448
03:17:08 26182 8192000000 10114380304 10569289728 1147935536 12.290 100 29 58 2 1199260528 45049760 6275232
03:18:35 77927 8192000000 10104155472 10538016768 1144669568 12.259 99 25 49 3 1187685808 37631936 5384304
03:20:53 72768 8192000000 10116960784 10573070336 1136397008 12.182 98 28 56 1 1213729424 71201824 6130592
03:24:03 71711 8192000000 10084753088 10581082112 1123800096 12.063 99 8 16 1 1165229008 39715328 1713584
03:26:15 74750 8192000000 10075600048 10533109760 1130736576 12.128 99 13 27 1 1176018512 42795760 2486176
03:28:46 54632 8192000000 10145934416 10591506432 1129106224 12.113 98 23 46 1 1204369328 69949584 5313520
03:30:58 95619 8192000000 10074191616 10537312256 1080563920 11.653 95 22 44 2 1134333456 49481680 4287856
03:33:36 61550 8192000000 10055741328 10516336640 1041933792 11.283 93 17 34 0 1115025200 69519056 3572352
03:35:38 84626 8192000000 10042049536 10568282112 1083627296 11.682 97 21 42 0 1177835136 89925120 4282720
03:38:07 68253 8192000000 10027465776 10468990976 1020353600 11.075 92 14 28 3 1103219584 79874592 2991392
03:40:15 91450 8192000000 10021649728 10453463040 1062435680 11.480 97 25 50 1 1086636240 18881920 5318640
03:43:05 72689 8192000000 10080617584 10535223296 1096515840 11.805 99 16 32 2 1157962784 57482000 3964944
03:45:23 72749 8192000000 10051776992 10520764416 1068273648 11.536 97 11 22 0 1142684592 72126960 2283984
03:47:53 38420 8192000000 10074192112 10504658944 1113342864 11.964 100 18 35 1 1157762224 40779088 3640272
03:49:32 68440 8192000000 10125809632 10597281792 1086811600 11.712 97 31 61 1 1223268176 129261616 7194960
03:51:41 45039 8192000000 10092795072 10542198784 1113737616 11.968 100 16 32 0 1195675424 78591984 3345824
03:53:27 55056 8192000000 10098658208 10628001792 1122163040 12.047 100 17 34 0 1210812608 85044192 3605376
03:55:25 92489 8192000000 10030219264 10473431040 1032063280 11.188 92 21 42 1 1072592336 35746016 4783040
03:57:57 129121 8192000000 10060976000 10530627584 1096397840 11.803 99 32 64 0 1153282432 50505680 6378912
04:01:14 30300 8192000000 10050309696 10539098112 1119346336 12.021 100 8 14 3 1161172256 40487536 1338384
04:02:44 45364 8192000000 10081943072 10516471808 1115722128 11.987 99 12 24 1 1159303904 41063440 2518336
04:04:33 40074 8192000000 10098595424 10535550976 1115315568 11.983 99 17 34 0 1182545888 63688576 3541744
04:06:24 69165 8192000000 10188607600 10640605184 1147280976 12.284 100 27 53 0 1278055392 125225216 5549200
04:08:34 47543 8192000000 10235174704 10727272448 1153261152 12.340 100 30 60 0 1347913184 187645872 7006160
04:10:40 74006 8192000000 10105903728 10629120000 1132504560 12.145 100 7 14 0 1201460288 67784032 1171696
04:12:54 21006 8192000000 10125378160 10551730176 1143956336 12.253 100 42 84 0 1194496848 41973264 8567248
04:14:15 40481 8192000000 10104741920 10558521344 1135121712 12.170 99 26 51 0 1182644768 41873216 5649840
04:16:06 99258 8192000000 10088898896 10548486144 1122220992 12.048 99 22 44 2 1183092160 56005024 4866144
04:18:54 74121 8192000000 10090545632 10541420544 1124246864 12.067 99 16 32 1 1182821536 55032960 3541712
04:21:08 80179 8192000000 10105510352 10608857088 1132835072 12.148 100 6 12 1 1195487040 61550208 1101760
04:23:29 34000 8192000000 10055916048 10506158080 1112317136 11.954 97 27 53 1 1139350736 21667488 5366112
04:25:10 59855 8192000000 10109283728 10551201792 1118244336 12.010 98 24 49 1 1179701904 56381760 5075808

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 17-12-2020 04:28:28 to 17-12-2020 04:28:43.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 136 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-12-2020 04:28:44 to 17-12-2020 04:28:46.

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          223970
MON$OLDEST_ACTIVE               228763
MON$OLDEST_SNAPSHOT             228763
MON$NEXT_TRANSACTION            228763
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                       2116480
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                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             783
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2296 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2296 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2296 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.2296 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 ---
:223: 
:224: mscs-test-161.budzdorov.ru    Thu Dec 17 04:27:03 2020
:225:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:226: 
--- end of diff output ---

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

17.12.20 04:28:47. 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
2020-12-17 04:28:49 No severe PSQL-related problems occured
17.12.20 04:28:51. End of report.