Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.01.2021 10: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 16571240 kB
MemAvailable 31257444 kB
Buffers 0 kB
Cached 14962232 kB
SwapCached 4864 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-01-06 10:26:55
DTS_END                         2021-01-06 10:26:55.5550
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/43520
TRN_ID                          235247
ATT_ID                          653
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: 9019.53 26214 1082343
customer order: creation 170.48 397 20457
customer order: refuse 14.73 282 1768
order to supplier: creation 44.95 1360 5394
order to supplier: removal 3.38 108972 405
invoice (draft): creation 44.88 3598 5385
invoice (draft): removal 51.73 896 6208
invoice accept: apply 40.05 16973 4806
invoice accept: cancel 46.58 885 5589
customer reserve: creation 3707.92 166 444950
customer reserve: removal 4611.53 63 553384
realization accept: apply 82.00 168 9840
realization accept: cancel 73.39 119 8807
payment from customer: creation 58.42 102 7010
payment from customer: removal 5.46 87 655
payment to supplier: creation 49.43 113 5931
payment to supplier: removal 3.33 71 400
service: total inventory turnovers 3.80 3283 456
service: total monetary turnovers 6.47 479 776
service: aggregate perf. data 0.98 11358 118
service: refresh index statistics 0.03 374916 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-01-2021 10:27:51 to 06-01-2021 10:27:53.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 952
TEST_TIME 2 1609
TEST_TIME 3 2619
TEST_TIME 4 3265
TEST_TIME 5 3527
TEST_TIME 6 3549
TEST_TIME 7 4348
TEST_TIME 8 4367
TEST_TIME 9 4707
TEST_TIME 10 5315
TEST_TIME 11 6242
TEST_TIME 12 6475
TEST_TIME 13 6509
TEST_TIME 14 6980
TEST_TIME 15 7150
TEST_TIME 16 7408
TEST_TIME 17 7476
TEST_TIME 18 7561
TEST_TIME 19 8277
TEST_TIME 20 8656
TEST_TIME 21 9022
TEST_TIME 22 8962
TEST_TIME 23 9051
TEST_TIME 24 9276
TEST_TIME 25 9394
TEST_TIME 26 10233
TEST_TIME 27 10696
TEST_TIME 28 10818
TEST_TIME 29 10632
TEST_TIME 30 10491
TEST_TIME 31 10259
TEST_TIME 32 10099
TEST_TIME 33 10036
TEST_TIME 34 9867
TEST_TIME 35 9878
TEST_TIME 36 9974
TEST_TIME 37 9814
TEST_TIME 38 9763
TEST_TIME 39 9652
TEST_TIME 40 9593
TEST_TIME 41 9402
TEST_TIME 42 9381
TEST_TIME 43 9229
TEST_TIME 44 9224
TEST_TIME 45 9243
TEST_TIME 46 9210
TEST_TIME 47 9281
TEST_TIME 48 9275
TEST_TIME 49 9248
TEST_TIME 50 9283
TEST_TIME 51 9476
TEST_TIME 52 9430
TEST_TIME 53 9452
TEST_TIME 54 9451
TEST_TIME 55 9492
TEST_TIME 56 9527
TEST_TIME 57 9448
TEST_TIME 58 9296
TEST_TIME 59 9241
TEST_TIME 60 9134
TEST_TIME 61 9097
TEST_TIME 62 9071
TEST_TIME 63 9169
TEST_TIME 64 9216
TEST_TIME 65 9237
TEST_TIME 66 9335
TEST_TIME 67 9305
TEST_TIME 68 9242
TEST_TIME 69 9275
TEST_TIME 70 9304
TEST_TIME 71 9403
TEST_TIME 72 9420
TEST_TIME 73 9474
TEST_TIME 74 9430
TEST_TIME 75 9351
TEST_TIME 76 9294
TEST_TIME 77 9243
TEST_TIME 78 9166
TEST_TIME 79 9137
TEST_TIME 80 9094
TEST_TIME 81 9043
TEST_TIME 82 9018
TEST_TIME 83 9077
TEST_TIME 84 9118
TEST_TIME 85 9135
TEST_TIME 86 9082
TEST_TIME 87 9110
TEST_TIME 88 9252
TEST_TIME 89 9216
TEST_TIME 90 9240
TEST_TIME 91 9328
TEST_TIME 92 9324
TEST_TIME 93 9259
TEST_TIME 94 9195
TEST_TIME 95 9143
TEST_TIME 96 9123
TEST_TIME 97 9094
TEST_TIME 98 9064
TEST_TIME 99 9051
TEST_TIME 100 9032
TEST_TIME 101 9028
TEST_TIME 102 9022
TEST_TIME 103 9111
TEST_TIME 104 9058
TEST_TIME 105 9159
TEST_TIME 106 9176
TEST_TIME 107 9150
TEST_TIME 108 9186
TEST_TIME 109 9194
TEST_TIME 110 9154
TEST_TIME 111 9083
TEST_TIME 112 9019
TEST_TIME 113 9068
TEST_TIME 114 9050
TEST_TIME 115 9019
TEST_TIME 116 8988
TEST_TIME 117 9024
TEST_TIME 118 9043
TEST_TIME 119 9009
TEST_TIME 120 9020

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 06-01-2021 10:27:55 to 06-01-2021 10:27:58.

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 1494760 1494760 0 0.00 0 12975 6 0 0
doc_list_biud 1494760 1494760 0 0.00 0 61454 30 0 0
sp_add_invoice_to_stock 4928 4806 122 2.47 40 175358 16974 0 122
sp_cancel_adding_invoice 5589 5589 0 0.00 5 79628 885 0 0
sp_cancel_client_order 1768 1768 0 0.00 4 12333 283 0 0
sp_cancel_customer_reserve 553384 553384 0 0.00 1 61458 63 0 0
sp_cancel_pay_from_customer 655 655 0 0.00 3 6468 87 0 0
sp_cancel_pay_to_supplier 400 400 0 0.00 3 2263 72 0 0
sp_cancel_supplier_invoice 6215 6208 7 0.11 2 23393 897 0 7
sp_cancel_supplier_order 405 405 0 0.00 6 1204084 108973 0 0
sp_cancel_write_off 8807 8807 0 0.00 1 7883 120 0 0
sp_client_order 20457 20457 0 0.00 3 17170 398 0 0
sp_customer_reserve 444950 444950 0 0.00 0 78504 166 0 0
sp_fill_shopping_cart 31233 31233 0 0.00 1 15508 41 0 0
sp_fill_shopping_cart_clo_res 444950 444950 0 0.00 0 20336 14 0 0
sp_get_clo_for_invoice 4806 4806 0 0.00 0 32663 345 0 0
sp_kill_cost_storno 15451 15451 0 0.00 0 9797 60 0 0
sp_kill_qstorno_ret_qs2qd 1122188 1122188 0 0.00 0 61450 34 0 0
sp_kill_qty_storno 590807 590807 0 0.00 0 61454 74 0 0
sp_lock_selected_doc 20387 20387 0 0.00 0 9964 6 0 0
sp_make_cost_storno 27587 27587 0 0.00 0 12962 50 0 0
sp_multiply_rows_for_pdistr 27587 27587 0 0.00 0 7306 32 0 0
sp_multiply_rows_for_qdistr 20457 20457 0 0.00 0 17162 239 0 0
sp_pay_from_customer 7010 7010 0 0.00 4 8563 102 0 0
sp_pay_to_supplier 5931 5931 0 0.00 5 10233 113 0 0
sp_payment_common 12941 12941 0 0.00 1 7686 17 0 0
sp_qd_handle_on_cancel_clo 1768 1768 0 0.00 0 12324 257 0 0
sp_qd_handle_on_invoice_upd_sts 10395 10395 0 0.00 0 33105 369 0 0
sp_qd_handle_on_reserve_upd_sts 9840 9840 0 0.00 0 13084 45 0 0
sp_reserve_write_off 9840 9840 0 0.00 6 15246 169 0 0
sp_supplier_invoice 5385 5385 0 0.00 5 93928 3598 0 0
sp_supplier_order 5394 5394 0 0.00 6 37215 1361 0 0
srv_aggregate_perf_data 118 118 0 0.00 2416 84724 11358 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1274 114925 43488 0 0
srv_find_qd_qs_mism 1035787 1035787 0 0.00 0 10573 5 0 0
srv_make_invnt_saldo 456 456 0 0.00 39 87790 3284 0 0
srv_make_money_saldo 776 776 0 0.00 1 12637 480 0 0
srv_recalc_idx_stat 4 4 0 0.00 141879 573253 374917 0 0
t$perf-abend:sp_add_invoice_to_stock 122 122 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4806 4806 0 0.00 0 12732 152 0 0
t$perf-norm:sp_cancel_adding_invoice 378 378 0 0.00 1 2295 81 0 0
t$perf-norm:sp_cancel_client_order 1768 1768 0 0.00 0 4532 16 0 0
t$perf-norm:sp_cancel_customer_reserve 662 662 0 0.00 0 2577 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 655 655 0 0.00 0 458 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 400 400 0 0.00 0 370 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 409 409 0 0.00 0 1601 21 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 19494 1428 0 0
t$perf-norm:sp_cancel_write_off 1064 1064 0 0.00 0 5333 13 0 0
t$perf-norm:sp_client_order 20457 20457 0 0.00 0 6690 17 0 0
t$perf-norm:sp_customer_reserve 1020 1020 0 0.00 0 3877 10 0 0
t$perf-norm:sp_pay_from_customer 7010 7010 0 0.00 0 4968 11 0 0
t$perf-norm:sp_pay_to_supplier 5931 5931 0 0.00 0 6192 10 0 0
t$perf-norm:sp_reserve_write_off 9840 9840 0 0.00 0 6346 13 0 0
t$perf-norm:sp_supplier_invoice 5385 5385 0 0.00 0 7569 22 0 0
t$perf-norm:sp_supplier_order 5394 5394 0 0.00 0 2922 16 0 0
t$perf-norm:srv_aggregate_perf_data 118 118 0 0.00 0 524 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 106 2 0 0
t$perf-norm:srv_make_invnt_saldo 456 456 0 0.00 0 529 8 0 0
t$perf-norm:srv_make_money_saldo 776 776 0 0.00 0 1566 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 103 28 0 0
v_add_invoice_to_stock 9856 9612 244 2.47 0 8147 18 0 244
v_all_customers 37278 37278 0 0.00 0 22 1 0 0
v_all_suppliers 30396 30396 0 0.00 0 27 1 0 0
v_all_wares 40914 40914 0 0.00 0 24 1 0 0
v_cancel_adding_invoice 756 756 0 0.00 0 182 6 0 0
v_cancel_client_order 3536 3536 0 0.00 0 4405 5 0 0
v_cancel_customer_prepayment 1310 1310 0 0.00 0 2984 10 0 0
v_cancel_customer_reserve 1324 1324 0 0.00 0 436 3 0 0
v_cancel_payment_to_supplier 800 800 0 0.00 0 2258 7 0 0
v_cancel_supplier_invoice 832 818 14 1.68 0 1790 15 0 14
v_cancel_supplier_order 810 810 0 0.00 0 753 4 0 0
v_cancel_write_off 2128 2128 0 0.00 0 4244 16 0 0
v_max_id_clo_ord 5394 5394 0 0.00 0 331 1 0 0
v_max_id_clo_res 1020 1020 0 0.00 0 1523 11 0 0
v_max_id_ord_sup 5382 5382 0 0.00 0 4933 10 0 0
v_max_non_paid_invoice 1509 1509 0 0.00 0 477 2 0 0
v_max_non_paid_realizn 3816 3816 0 0.00 0 2779 3 0 0
v_min_id_clo_ord 5394 5394 0 0.00 0 1693 4 0 0
v_min_id_clo_res 1020 1020 0 0.00 0 6091 21 0 0
v_min_id_ord_sup 5382 5382 0 0.00 0 4916 5 0 0
v_min_non_paid_invoice 5931 5931 0 0.00 0 3493 7 0 0
v_min_non_paid_realizn 7010 7010 0 0.00 0 6544 12 0 0
v_our_firm 10024 10024 0 0.00 0 19 1 0 0
v_reserve_write_off 19680 19680 0 0.00 0 4560 5 0 0
x_lock_depdocs_on_canc_invoice 11388 11388 0 0.00 0 9879 107 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 7919 299 0 0
x_make_qstorno_1000_1200 5394 5394 0 0.00 2 37160 1286 0 0
x_make_qstorno_1200_2000 5385 5385 0 0.00 3 93917 3439 0 0
x_make_qstorno_2100_3300 444950 444950 0 0.00 0 78503 150 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-01-2021 10:27:59 to 06-01-2021 10:28:03.

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:56:59 12 8192000000 8798616336 8809865216 4515760 0.055 2 1 2 0 4615264 63648 35856
07:57:59 38823 8192000000 9729337264 10028285952 808450352 8.982 100 89 178 1 838133056 8177424 21505280
07:59:42 22060 8192000000 9766207920 10072645632 835421696 9.254 100 88 176 0 869983920 13360192 21202032
08:01:10 12565 8192000000 9808067360 10129186816 874232064 9.642 100 84 164 2 918593552 25689200 18672288
08:02:24 14571 8192000000 9889208976 10222534656 923878640 10.134 100 82 163 0 990744512 49622608 17243264
08:03:41 7873 8192000000 9930117152 10274316288 964804496 10.536 100 81 162 1 1044136672 62837136 16495040
08:04:51 5665 8192000000 9984298736 10339020800 1003393680 10.911 100 79 156 0 1093028592 73975520 15659392
08:05:58 2656 8192000000 10025053632 10388209664 1039403760 11.259 100 93 183 1 1143918848 86406160 18108928
08:07:03 1721 8192000000 10030728256 10394693632 1052670288 11.386 100 86 172 0 1137775936 67393232 17712416
08:08:07 44112 8192000000 10015427536 10391351296 1071454544 11.566 100 50 104 0 1126358160 44460080 10443536
08:09:51 3625 8192000000 9985288368 10369417216 1079389904 11.642 100 45 89 0 1103839152 15543424 8905824
08:10:56 7159 8192000000 9987336576 10387591168 1084602384 11.691 100 35 72 2 1111837664 19520976 7714304
08:12:04 7696 8192000000 10029750240 10419818496 1090027264 11.743 100 46 92 4 1145250608 44936896 10286448
08:13:15 51240 8192000000 10019972576 10405523456 1095478576 11.795 100 49 99 1 1123910496 16785664 11646256
08:15:09 6444 8192000000 10011693360 10421088256 1100302432 11.841 100 50 103 1 1136238592 25128224 10807936
08:16:17 7653 8192000000 10024329344 10432823296 1098845376 11.827 100 37 75 1 1135873824 29114240 7914208
08:17:26 7580 8192000000 9986363024 10388082688 1040234752 11.267 96 27 53 0 1069760080 24257840 5267488
08:18:37 70452 8192000000 9959240608 10365845504 953847936 10.429 89 25 50 3 975691360 16323776 5519648
08:20:49 5145 8192000000 9940167568 10313842688 1013799984 11.012 99 58 118 3 1058954416 32216816 12937616
08:21:57 7797 8192000000 9933976368 10321948672 1020715072 11.079 100 35 70 0 1054482608 25994848 7772688
08:23:09 5629 8192000000 9964683872 10348994560 1030113552 11.170 99 27 53 2 1081353104 45639920 5599632
08:24:17 31024 8192000000 9960440864 10348314624 1040178960 11.266 99 65 132 4 1067540720 13410496 13951264
08:25:51 8510 8192000000 9973144288 10380029952 1055979264 11.418 100 38 77 1 1088247056 23468464 8799328
08:27:01 74642 8192000000 10015160320 10412249088 1088872000 11.732 100 37 74 2 1109587232 12267264 8447968
08:29:21 22502 8192000000 10087496000 10485030912 1116647920 11.995 100 48 94 2 1192211120 65308416 10254784
08:30:46 27085 8192000000 10128589376 10538582016 1131262880 12.133 100 48 96 0 1235711408 93662320 10786208
08:32:37 13722 8192000000 10166074784 10573418496 1145198752 12.264 100 50 99 0 1267203552 111078896 10925904
08:33:55 12910 8192000000 10192464416 10604781568 1151802720 12.326 100 44 88 0 1301360832 139387920 10170192
08:35:18 3929 8192000000 10258945872 10663264256 1157849984 12.383 100 64 128 1 1365147008 193007856 14289168
08:36:28 1266 8192000000 10258451104 10662293504 1171471200 12.511 100 71 142 1 1368086800 180650240 15965360
08:37:37 3064 8192000000 10252724832 10673319936 1149792992 12.308 98 70 138 2 1356366016 190598480 15974544
08:38:48 5849 8192000000 10260016272 10668879872 1170015200 12.497 100 77 154 0 1357094320 170230448 16848672
08:39:59 6193 8192000000 10291984496 10699419648 1162446336 12.426 99 74 146 1 1395617392 216482816 16688240
08:41:10 3138 8192000000 10317237872 10720571392 1176358560 12.556 100 84 168 0 1417114384 222474976 18280848
08:42:18 2005 8192000000 10360103872 10753077248 1155164256 12.358 98 93 185 1 1443793984 267621696 21008032
08:43:24 6502 8192000000 10388834240 10802470912 1149710512 12.307 99 75 150 0 1484610672 316978080 17922080
08:44:35 2404 8192000000 10359644432 10768699392 1125306880 12.077 98 74 149 0 1450357056 305999328 19050848
08:45:42 7400 8192000000 10321203888 10731020288 1136471104 12.182 100 51 102 2 1419904832 270484688 12949040
08:46:55 12293 8192000000 10290095728 10708439040 1088968368 11.733 96 37 74 0 1362845920 264465696 9411856
08:48:12 10129 8192000000 10258732112 10691792896 1112920656 11.960 99 31 63 1 1354047104 233839792 7286656
08:49:26 16202 8192000000 10293210304 10721714176 1132269776 12.143 100 24 48 1 1383543600 245426432 5847392
08:50:50 24481 8192000000 10318573824 10748719104 1116514448 11.994 100 23 46 2 1412345472 290133168 5697856
08:52:17 32978 8192000000 10152425328 10613714944 1096006704 11.800 99 15 30 1 1347256784 246868288 4381792
08:53:51 8220 8192000000 10064713184 10471043072 1100828480 11.846 99 56 108 0 1147082032 34128992 12124560
08:55:01 87067 8192000000 10046997440 10490355712 1099285616 11.831 99 29 57 2 1158718976 53177760 6255600
08:58:43 57579 8192000000 10072972608 10493313024 1109008720 11.923 99 38 76 3 1157241712 40160336 8072656
09:00:43 30162 8192000000 10138412224 10556268544 1120610160 12.033 99 42 81 1 1210300544 80607856 9082528
09:03:13 27272 8192000000 10061709072 10503741440 1128774032 12.110 100 17 32 1 1169094144 36993808 3326304
09:04:41 26492 8192000000 10045304768 10499866624 1103754064 11.873 98 8 16 0 1127638288 21966608 1917616
09:06:13 42897 8192000000 10049067648 10502746112 1120140064 12.028 99 11 22 1 1165728960 43613376 1975520
09:08:02 56681 8192000000 10069389344 10530033664 1125094064 12.075 99 14 28 1 1162176928 34180880 2901984
09:10:00 62470 8192000000 10108193088 10590187520 1136816432 12.186 100 13 26 0 1203358000 63607472 2934096
09:12:12 65335 8192000000 10117013504 10567696384 1135618160 12.174 99 16 32 1 1202461360 63498976 3344224
09:14:22 50860 8192000000 10128808768 10565509120 1155482000 12.361 100 27 53 0 1239559728 78241728 5836000
09:16:16 24979 8192000000 10150375376 10570346496 1154578896 12.352 100 42 85 1 1224192688 61009264 8604528
09:17:45 47330 8192000000 10080106224 10506862592 1128156032 12.104 98 25 48 1 1154797616 21195872 5445712
09:19:35 22987 8192000000 10091442416 10531491840 1139061264 12.207 100 33 66 0 1186451648 40490000 6900384
09:21:02 89067 8192000000 10098758032 10530295808 1103680768 11.873 97 24 47 1 1149992528 41414304 4897456
09:23:32 105842 8192000000 10068014976 10533982208 1126663760 12.090 100 16 32 1 1170926544 40876400 3386384
09:26:20 50646 8192000000 10092773872 10563346432 1102047568 11.857 98 6 12 0 1154579536 51476096 1055872
09:28:28 75895 8192000000 10184078816 10702008320 1137781200 12.195 100 19 38 0 1312128544 169985680 4361664
09:30:45 72460 8192000000 10075251360 10546085888 1096635152 11.806 97 17 34 0 1200274000 99921168 3717680
09:34:14 27733 8192000000 10086199616 10507325440 1090064016 11.743 97 49 99 1 1154809632 54789872 9955744
09:35:41 51933 8192000000 10135676624 10629734400 1104308816 11.879 98 29 58 0 1223476864 113144624 6023424
09:37:33 21284 8192000000 10066456480 10535481344 1111956192 11.951 99 32 64 2 1143300080 24876000 6467888
09:38:59 64429 8192000000 10061425264 10526625792 1064019520 11.495 95 21 42 1 1143123616 75264752 3839344
09:41:09 114925 8192000000 10049012368 10505318400 1106888368 11.903 99 24 48 0 1157882048 45006656 5987024
09:44:04 51255 8192000000 10066830224 10513956864 1127854576 12.101 100 7 14 0 1176860016 47658112 1347328
09:46:00 60120 8192000000 10093645280 10589888512 1132756528 12.147 100 7 14 0 1164939776 30725584 1457664
09:48:15 87789 8192000000 10172065088 10634113024 1131926304 12.140 99 20 40 1 1245764928 109157728 4680896
09:50:43 77888 8192000000 10091503472 10536509440 1114659392 11.977 98 14 28 1 1219049472 100372096 4017984
09:53:01 55729 8192000000 10156133200 10625814528 1149973024 12.309 100 19 38 2 1242688064 88850064 3864976
09:56:16 44125 8192000000 10105306160 10611597312 1144417920 12.257 100 10 20 0 1224880368 78317488 2144960
09:58:00 98394 8192000000 10075006640 10544312320 1118289680 12.011 97 27 55 4 1164267088 41279200 4698208
10:00:42 46533 8192000000 10067279360 10538774528 1133027264 12.150 99 14 29 1 1168848000 33280240 2540496
10:02:37 52217 8192000000 10095643024 10583076864 1140091360 12.216 99 5 10 0 1173219952 31558400 1570192
10:04:30 59337 8192000000 10072216816 10536480768 1130437360 12.125 98 13 26 1 1170399168 37410896 2550912
10:06:30 49072 8192000000 10123498272 10559881216 1122097440 12.047 97 25 50 3 1192170384 64331632 5741312
10:08:20 62241 8192000000 10113111648 10625568768 1132559296 12.145 99 26 52 3 1218172096 79784368 5828432
10:10:59 61185 8192000000 10120805856 10612518912 1133361392 12.153 99 29 58 1 1231726112 92186512 6178208
10:13:14 57553 8192000000 10100165440 10530217984 1111703168 11.949 97 34 66 3 1166686304 48644640 6338496
10:15:12 72003 8192000000 10127219296 10608390144 1114837008 11.978 98 13 26 0 1188611184 70960496 2813680
10:18:49 66327 8192000000 10090768752 10590830592 1115200784 11.982 99 7 14 0 1209444608 92727616 1516208
10:20:55 44499 8192000000 10063847488 10498785280 1115427344 11.984 99 18 35 2 1133749968 14504464 3818160
10:22:45 54292 8192000000 10164026160 10631307264 1133604320 12.155 100 26 52 2 1220676112 81417952 5653840
10:24:49 65903 8192000000 10056466288 10485501952 1060009920 11.457 94 15 30 1 1110137712 47298784 2829008

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 06-01-2021 10:28:05 to 06-01-2021 10:28:20.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 122 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-01-2021 10:28:21 to 06-01-2021 10:28:23.

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          233788
MON$OLDEST_ACTIVE               235357
MON$OLDEST_SNAPSHOT             235357
MON$NEXT_TRANSACTION            235357
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                       2100928
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             802
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2317 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2317 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2317 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.2317 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 ---
:51: 
:52: mscs-test-161.budzdorov.ru    Wed Jan  6 10:27:06 2021
:53:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:54: 
--- end of diff output ---

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

06.01.21 10:28:24. 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-01-06 10:28:26 No severe PSQL-related problems occured
06.01.21 10:28:27. End of report.