Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.02.2021 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 16531648 kB
MemAvailable 31133464 kB
Buffers 20 kB
Cached 15047072 kB
SwapCached 4876 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-07 04:26:56
DTS_END                         2021-02-07 04:26:56.7180
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/49382
TRN_ID                          236549
ATT_ID                          622
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: 9068.03 19668 1088163
customer order: creation 170.81 454 20497
customer order: refuse 13.73 311 1648
order to supplier: creation 44.58 1413 5350
order to supplier: removal 3.48 120118 418
invoice (draft): creation 46.18 3401 5542
invoice (draft): removal 51.42 957 6170
invoice accept: apply 41.26 18116 4951
invoice accept: cancel 45.96 855 5515
customer reserve: creation 3840.18 177 460821
customer reserve: removal 4520.13 74 542415
realization accept: apply 82.95 174 9954
realization accept: cancel 78.24 127 9389
payment from customer: creation 59.06 116 7087
payment from customer: removal 5.71 91 685
payment to supplier: creation 49.83 129 5979
payment to supplier: removal 3.43 71 412
service: total inventory turnovers 4.03 3040 484
service: total monetary turnovers 6.22 508 746
service: aggregate perf. data 0.79 16959 95
service: refresh index statistics 0.04 226275 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 07-02-2021 04:27:38 to 07-02-2021 04:27:41.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5860
TEST_TIME 2 4053
TEST_TIME 3 4506
TEST_TIME 4 4781
TEST_TIME 5 4910
TEST_TIME 6 5020
TEST_TIME 7 5208
TEST_TIME 8 5139
TEST_TIME 9 5297
TEST_TIME 10 5320
TEST_TIME 11 5481
TEST_TIME 12 5612
TEST_TIME 13 5861
TEST_TIME 14 6189
TEST_TIME 15 6513
TEST_TIME 16 6955
TEST_TIME 17 7532
TEST_TIME 18 8050
TEST_TIME 19 8243
TEST_TIME 20 8605
TEST_TIME 21 9278
TEST_TIME 22 9037
TEST_TIME 23 9594
TEST_TIME 24 10400
TEST_TIME 25 11347
TEST_TIME 26 11417
TEST_TIME 27 11341
TEST_TIME 28 11154
TEST_TIME 29 10979
TEST_TIME 30 10845
TEST_TIME 31 10673
TEST_TIME 32 10482
TEST_TIME 33 10473
TEST_TIME 34 10301
TEST_TIME 35 10329
TEST_TIME 36 10351
TEST_TIME 37 10204
TEST_TIME 38 10128
TEST_TIME 39 9967
TEST_TIME 40 9950
TEST_TIME 41 10145
TEST_TIME 42 10018
TEST_TIME 43 10034
TEST_TIME 44 10053
TEST_TIME 45 10023
TEST_TIME 46 10129
TEST_TIME 47 10112
TEST_TIME 48 10122
TEST_TIME 49 10182
TEST_TIME 50 10202
TEST_TIME 51 10246
TEST_TIME 52 10079
TEST_TIME 53 9950
TEST_TIME 54 9893
TEST_TIME 55 9769
TEST_TIME 56 9752
TEST_TIME 57 9737
TEST_TIME 58 9699
TEST_TIME 59 9682
TEST_TIME 60 9630
TEST_TIME 61 9636
TEST_TIME 62 9593
TEST_TIME 63 9582
TEST_TIME 64 9634
TEST_TIME 65 9561
TEST_TIME 66 9524
TEST_TIME 67 9497
TEST_TIME 68 9540
TEST_TIME 69 9537
TEST_TIME 70 9510
TEST_TIME 71 9479
TEST_TIME 72 9363
TEST_TIME 73 9284
TEST_TIME 74 9298
TEST_TIME 75 9311
TEST_TIME 76 9279
TEST_TIME 77 9240
TEST_TIME 78 9244
TEST_TIME 79 9296
TEST_TIME 80 9254
TEST_TIME 81 9251
TEST_TIME 82 9267
TEST_TIME 83 9308
TEST_TIME 84 9259
TEST_TIME 85 9310
TEST_TIME 86 9352
TEST_TIME 87 9270
TEST_TIME 88 9196
TEST_TIME 89 9139
TEST_TIME 90 9078
TEST_TIME 91 9068
TEST_TIME 92 9034
TEST_TIME 93 9023
TEST_TIME 94 9071
TEST_TIME 95 9092
TEST_TIME 96 9108
TEST_TIME 97 9103
TEST_TIME 98 9109
TEST_TIME 99 9088
TEST_TIME 100 9067
TEST_TIME 101 9067
TEST_TIME 102 9139
TEST_TIME 103 9122
TEST_TIME 104 9150
TEST_TIME 105 9103
TEST_TIME 106 9033
TEST_TIME 107 9007
TEST_TIME 108 8951
TEST_TIME 109 8900
TEST_TIME 110 8929
TEST_TIME 111 8914
TEST_TIME 112 8925
TEST_TIME 113 8942
TEST_TIME 114 8915
TEST_TIME 115 8929
TEST_TIME 116 8967
TEST_TIME 117 8992
TEST_TIME 118 9026
TEST_TIME 119 9079
TEST_TIME 120 9077

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 07-02-2021 04:27:42 to 07-02-2021 04:27:46.

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 1514099 1514099 0 0.00 0 21463 7 0 0
doc_list_biud 1514099 1514099 0 0.00 0 31803 33 0 0
sp_add_invoice_to_stock 5092 4951 141 2.76 35 214247 18117 0 141
sp_cancel_adding_invoice 5515 5515 0 0.00 4 61723 856 0 0
sp_cancel_client_order 1648 1648 0 0.00 4 9005 311 0 0
sp_cancel_customer_reserve 542415 542415 0 0.00 1 22614 74 0 0
sp_cancel_pay_from_customer 685 685 0 0.00 3 7483 92 0 0
sp_cancel_pay_to_supplier 412 412 0 0.00 3 2546 71 0 0
sp_cancel_supplier_invoice 6178 6170 8 0.12 1 17155 957 0 8
sp_cancel_supplier_order 418 418 0 0.00 7 1252499 120118 0 0
sp_cancel_write_off 9389 9389 0 0.00 1 11187 128 0 0
sp_client_order 20497 20497 0 0.00 4 52256 455 0 0
sp_customer_reserve 460821 460821 0 0.00 0 82554 178 0 0
sp_fill_shopping_cart 31388 31388 0 0.00 1 21414 43 0 0
sp_fill_shopping_cart_clo_res 460813 460813 0 0.00 0 31761 20 0 0
sp_get_clo_for_invoice 4936 4936 0 0.00 0 41617 409 0 0
sp_kill_cost_storno 16001 16001 0 0.00 0 11184 56 0 0
sp_kill_qstorno_ret_qs2qd 1100807 1100807 0 0.00 0 22608 40 0 0
sp_kill_qty_storno 580441 580441 0 0.00 0 31803 85 0 0
sp_lock_selected_doc 20588 20588 0 0.00 0 9469 6 0 0
sp_make_cost_storno 27952 27952 0 0.00 0 15888 57 0 0
sp_multiply_rows_for_pdistr 27952 27952 0 0.00 0 7669 32 0 0
sp_multiply_rows_for_qdistr 20497 20497 0 0.00 0 24996 256 0 0
sp_pay_from_customer 7087 7087 0 0.00 4 16912 117 0 0
sp_pay_to_supplier 5979 5979 0 0.00 5 14914 130 0 0
sp_payment_common 13066 13066 0 0.00 1 16884 24 0 0
sp_qd_handle_on_cancel_clo 1648 1648 0 0.00 0 8995 281 0 0
sp_qd_handle_on_invoice_upd_sts 10447 10447 0 0.00 0 31802 349 0 0
sp_qd_handle_on_reserve_upd_sts 9954 9954 0 0.00 0 18217 57 0 0
sp_reserve_write_off 9954 9954 0 0.00 7 18261 175 0 0
sp_supplier_invoice 5542 5542 0 0.00 8 94236 3402 0 0
sp_supplier_order 5350 5350 0 0.00 6 54048 1413 0 0
srv_aggregate_perf_data 95 95 0 0.00 2962 277205 16960 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2377 128225 46396 0 0
srv_find_qd_qs_mism 1040192 1040192 0 0.00 0 15071 6 0 0
srv_make_invnt_saldo 484 484 0 0.00 43 103310 3040 0 0
srv_make_money_saldo 746 746 0 0.00 1 16831 508 0 0
srv_recalc_idx_stat 5 5 0 0.00 160879 326139 226276 0 0
t$perf-abend:sp_add_invoice_to_stock 141 141 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 4951 4951 0 0.00 0 30657 146 0 0
t$perf-norm:sp_cancel_adding_invoice 412 412 0 0.00 0 5625 100 0 0
t$perf-norm:sp_cancel_client_order 1648 1648 0 0.00 0 3750 11 0 0
t$perf-norm:sp_cancel_customer_reserve 692 692 0 0.00 0 5163 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 685 685 0 0.00 0 9299 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 412 412 0 0.00 0 1127 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 374 374 0 0.00 0 300 9 0 0
t$perf-norm:sp_cancel_supplier_order 418 418 0 0.00 0 30102 1423 0 0
t$perf-norm:sp_cancel_write_off 1061 1061 0 0.00 0 4760 11 0 0
t$perf-norm:sp_client_order 20497 20497 0 0.00 0 5443 14 0 0
t$perf-norm:sp_customer_reserve 1019 1019 0 0.00 0 4355 7 0 0
t$perf-norm:sp_pay_from_customer 7087 7087 0 0.00 0 10560 9 0 0
t$perf-norm:sp_pay_to_supplier 5979 5979 0 0.00 0 7518 7 0 0
t$perf-norm:sp_reserve_write_off 9954 9954 0 0.00 0 11924 10 0 0
t$perf-norm:sp_supplier_invoice 5542 5542 0 0.00 0 11109 19 0 0
t$perf-norm:sp_supplier_order 5350 5350 0 0.00 0 11158 15 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 200 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 395 7 0 0
t$perf-norm:srv_make_invnt_saldo 484 484 0 0.00 0 682 8 0 0
t$perf-norm:srv_make_money_saldo 746 746 0 0.00 0 476 4 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 14 5 0 0
v_add_invoice_to_stock 10146 9864 282 2.77 0 8608 15 0 282
v_all_customers 37124 37124 0 0.00 0 20 1 0 0
v_all_suppliers 30754 30754 0 0.00 0 41 1 0 0
v_all_wares 40994 40994 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 824 824 0 0.00 0 1612 11 0 0
v_cancel_client_order 3296 3296 0 0.00 0 1924 6 0 0
v_cancel_customer_prepayment 1370 1370 0 0.00 0 7444 14 0 0
v_cancel_customer_reserve 1384 1384 0 0.00 0 2156 5 0 0
v_cancel_payment_to_supplier 824 824 0 0.00 0 1545 7 0 0
v_cancel_supplier_invoice 764 748 16 2.09 0 7700 34 0 16
v_cancel_supplier_order 836 836 0 0.00 0 6654 19 0 0
v_cancel_write_off 2122 2122 0 0.00 0 1847 15 0 0
v_max_id_clo_ord 5350 5350 0 0.00 0 477 1 0 0
v_max_id_clo_res 1019 1019 0 0.00 0 3701 14 0 0
v_max_id_ord_sup 5541 5541 0 0.00 0 4692 7 0 0
v_max_non_paid_invoice 1493 1493 0 0.00 0 1846 3 0 0
v_max_non_paid_realizn 3795 3795 0 0.00 0 4073 5 0 0
v_min_id_clo_ord 5350 5350 0 0.00 0 5435 8 0 0
v_min_id_clo_res 1019 1019 0 0.00 0 4901 11 0 0
v_min_id_ord_sup 5541 5541 0 0.00 0 7767 9 0 0
v_min_non_paid_invoice 5979 5979 0 0.00 0 6567 13 0 0
v_min_non_paid_realizn 7087 7087 0 0.00 0 7416 16 0 0
v_our_firm 10454 10454 0 0.00 0 14 1 0 0
v_reserve_write_off 19908 19908 0 0.00 0 2194 5 0 0
x_lock_depdocs_on_canc_invoice 11311 11311 0 0.00 0 30096 117 0 0
x_lock_depdocs_on_canc_sup_ord 418 418 0 0.00 0 20766 411 0 0
x_make_qstorno_1000_1200 5350 5350 0 0.00 2 54033 1341 0 0
x_make_qstorno_1200_2000 5542 5542 0 0.00 4 84889 3234 0 0
x_make_qstorno_2100_3300 460820 460820 0 0.00 0 82553 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-02-2021 04:27:47 to 07-02-2021 04:27:51.

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:57:00 22 8192000000 8808486976 8823631872 10396480 0.126 4 3 4 1 10606016 114064 95472
01:58:01 28003 8192000000 9730236048 10027798528 806058272 8.958 100 88 176 0 834631504 7576864 20996368
01:59:39 25687 8192000000 9768685488 10075627520 841560800 9.315 100 90 180 0 876001536 13303376 21137360
02:01:11 16928 8192000000 9810734672 10135343104 880676288 9.706 100 77 154 0 926937072 28636368 17624416
02:02:32 11898 8192000000 9898332464 10230239232 924085888 10.136 100 80 160 2 1001337888 59514224 17737776
02:03:51 8024 8192000000 9927103072 10271449088 963530624 10.524 100 79 157 0 1035735264 56380848 15823792
02:05:04 4164 8192000000 9965620032 10321272832 996590480 10.845 100 78 155 2 1078616800 65938752 16087568
02:06:11 7503 8192000000 10022652304 10388623360 1030998256 11.178 100 89 178 1 1134717200 86065520 17653424
02:07:22 1577 8192000000 10027787392 10397671424 1054981920 11.408 100 93 183 2 1140377952 65675600 19720432
02:08:24 9391 8192000000 10025512336 10410958848 1064628528 11.501 100 60 118 0 1148632112 71370272 12633312
02:09:34 11134 8192000000 9990273360 10380300288 1076163216 11.611 100 33 62 2 1108080640 25302432 6614992
02:10:46 5608 8192000000 9987580768 10392137728 1079925200 11.647 100 36 71 4 1114818832 26859760 8033872
02:11:57 34761 8192000000 9996540048 10397851648 1084629520 11.692 100 41 82 2 1101862912 8041872 9191520
02:13:32 2948 8192000000 10009551840 10410655744 1090065616 11.743 100 42 85 0 1130799776 32120896 8613264
02:14:38 5852 8192000000 10009251584 10419621888 1093798864 11.779 100 35 70 1 1133573920 32196144 7578912
02:15:44 8379 8192000000 9993219888 10395242496 1084473888 11.690 100 26 53 0 1110008720 19603344 5931488
02:16:59 30290 8192000000 9997132864 10409308160 1060123552 11.458 97 23 46 2 1098953200 34051744 4777904
02:18:32 12084 8192000000 9961627600 10352091136 994659328 10.827 94 24 49 0 1020350016 20636064 5054624
02:19:45 4572 8192000000 9931648688 10307686400 974155312 10.627 94 49 97 2 993340848 8691312 10494224
02:20:49 65490 8192000000 9935182784 10336751616 976154496 10.647 95 39 79 2 1016216032 31062912 8998624
02:22:59 6751 8192000000 9973307184 10364174336 1043580208 11.299 100 33 65 1 1079183376 27817024 7786144
02:24:06 10503 8192000000 9970828576 10367524864 1058049584 11.438 100 28 56 1 1077201984 12508384 6644016
02:25:19 22753 8192000000 9990221008 10395176960 1064388064 11.498 100 31 62 0 1093373232 22192416 6792752
02:26:44 20393 8192000000 9998772256 10399662080 1075111600 11.601 100 28 56 0 1108056272 27081104 5863568
02:28:14 55008 8192000000 10062008544 10469314560 1111001808 11.942 100 47 94 0 1173918880 53231200 9685872
02:30:12 11335 8192000000 10102945088 10506170368 1138576576 12.202 100 54 106 1 1206335632 56415536 11343520
02:31:37 12582 8192000000 10165345232 10573942784 1152558624 12.334 100 57 111 1 1271944848 107173680 12212544
02:33:07 33118 8192000000 10226282240 10646519808 1159990448 12.403 99 46 92 1 1324267696 153251440 11025808
02:34:45 7225 8192000000 10279764960 10692710400 1179114128 12.582 100 55 109 0 1381706160 190044320 12547712
02:35:56 4178 8192000000 10324113696 10737942528 1190147904 12.685 100 66 128 0 1428204144 223235328 14820912
02:37:05 10111 8192000000 10343867936 10758860800 1189399936 12.678 100 51 102 0 1451634688 249641520 12593232
02:38:24 3543 8192000000 10408869584 10822295552 1192583136 12.707 100 80 160 0 1510696768 298229440 19884192
02:39:33 4696 8192000000 10439928560 10859593728 1187695536 12.662 100 64 128 1 1551621008 349244832 14680640
02:40:51 4315 8192000000 10480152688 10897321984 1189323792 12.677 100 77 150 0 1596105216 389030016 17751408
02:42:00 2377 8192000000 10517634640 10931486720 1189066560 12.675 100 81 162 0 1616400272 406755728 20577984
02:43:08 11950 8192000000 10478625680 10920013824 1156108080 12.367 99 54 108 0 1599281040 429271648 13901312
02:44:24 10880 8192000000 10470610400 10897027072 1157917760 12.384 100 44 88 0 1555219744 385992656 11309328
02:45:39 3813 8192000000 10446760592 10867359744 1168040928 12.479 100 72 143 0 1549139264 362972816 18125520
02:46:47 10990 8192000000 10428918272 10898296832 1146940832 12.281 99 36 72 0 1531548016 375322160 9285024
02:48:04 14005 8192000000 10376768624 10828812288 1133595312 12.155 100 27 54 0 1473263232 332768016 6899904
02:49:43 14090 8192000000 10368254288 10857537536 1113343568 11.964 100 29 58 1 1510565328 389207200 8014560
02:50:59 54945 8192000000 10059929104 10496147456 1091934208 11.761 98 7 14 0 1165993808 72510560 1549040
02:53:04 65325 8192000000 10029110352 10462277632 1052077024 11.381 95 11 23 1 1082499856 27850384 2572448
02:55:15 74564 8192000000 10092668624 10527936512 1096796576 11.807 99 33 65 2 1146204896 42843616 6564704
02:57:31 104668 8192000000 10057277376 10485510144 1069279920 11.545 96 27 55 0 1095662912 20694464 5688528
03:00:17 19082 8192000000 10078429616 10511355904 1118832208 12.016 99 38 75 0 1159997088 32656768 8508112
03:02:04 67936 8192000000 10047826912 10496704512 1125867008 12.082 99 9 18 1 1184263744 56815088 1581648
03:04:12 38690 8192000000 10098860048 10548244480 1146633776 12.278 100 22 44 0 1187550720 36303776 4613168
03:05:55 21615 8192000000 10129534560 10592518144 1153820112 12.345 100 25 50 1 1224915920 65790064 5305744
03:07:18 43422 8192000000 10092596464 10599583744 1137269456 12.190 99 16 32 2 1239878768 98516656 4092656
03:09:12 34218 8192000000 10117985536 10573570048 1129466336 12.116 98 25 50 0 1191822672 57025040 5331296
03:10:58 31526 8192000000 10090462528 10548731904 1129039984 12.112 99 18 36 0 1196895168 63877312 3977872
03:12:30 35250 8192000000 10105007232 10594504704 1119284736 12.020 98 19 38 1 1214052272 90946320 3821216
03:14:07 64477 8192000000 10102418944 10568372224 1127629152 12.099 99 26 52 1 1213772304 80670944 5472208
03:16:14 28612 8192000000 10081775984 10508836864 1124466240 12.069 98 32 64 1 1152528208 21181664 6880304
03:17:46 101158 8192000000 10128539120 10608668672 1134250448 12.161 99 35 69 0 1195135280 54283456 6601376
03:21:08 93055 8192000000 10125686928 10587455488 1152600192 12.334 100 8 17 0 1217296784 62973680 1722912
03:23:48 53421 8192000000 10114922896 10564055040 1152188832 12.330 100 19 38 1 1204288192 47470752 4628608
03:25:47 19419 8192000000 10082888112 10526367744 1141375392 12.228 99 48 96 0 1172518432 20195168 10947872
03:27:07 49138 8192000000 10058881360 10520309760 1130899664 12.130 99 20 40 3 1181849536 47066544 3883328
03:28:57 56597 8192000000 10118228480 10578296832 1118090976 12.009 98 32 62 1 1190316896 65617440 6608480
03:30:57 32691 8192000000 10063205664 10496421888 1117144928 12.000 99 36 72 1 1150751696 26398560 7208208
03:32:35 10190 8192000000 10112369392 10535526400 1118568304 12.013 100 61 120 2 1190950064 59999728 12382032
03:33:51 103433 8192000000 10063022736 10542690304 1086397648 11.708 96 25 50 0 1157031824 65685840 4948336
03:36:46 43474 8192000000 10080001616 10532904960 1099087408 11.829 98 25 50 1 1146047136 41653600 5306128
03:38:38 87322 8192000000 10092209248 10537603072 1101654656 11.853 98 27 54 1 1180166400 72967824 5543920
03:41:12 30786 8192000000 10049799088 10538971136 1105695232 11.892 99 8 16 1 1170924912 63140816 2088864
03:42:43 91479 8192000000 10077881152 10536288256 1116850224 11.997 99 16 32 1 1176508464 56029056 3629184
03:45:22 40879 8192000000 10045260896 10501287936 1115138608 11.981 99 18 36 1 1163593568 43903520 4551440
03:47:04 46930 8192000000 10107562992 10536747008 1132272464 12.143 100 27 53 1 1190324736 52531360 5520912
03:48:54 55591 8192000000 10046343456 10479824896 1113199504 11.963 98 13 26 0 1173872608 57684160 2988944
03:50:54 53342 8192000000 10125842128 10580496384 1115225584 11.982 99 31 60 1 1191190912 69500736 6464592
03:52:56 128225 8192000000 10085313600 10524598272 1132569936 12.146 100 28 56 1 1195160624 56597856 5992832
03:56:14 73915 8192000000 10144021040 10599333888 1143103280 12.245 100 8 15 1 1224623744 79737328 1783136
03:58:28 112015 8192000000 10151289632 10668351488 1153906064 12.346 100 28 55 1 1287392464 127277104 6209296
04:01:30 60804 8192000000 10055360400 10492030976 1111901904 11.950 97 15 30 1 1146342288 31833824 2606560
04:03:35 60174 8192000000 10071887392 10520387584 1110552048 11.938 97 23 46 4 1162243120 47072000 4619072
04:05:36 41692 8192000000 10087284976 10526171136 1132431088 12.144 99 30 60 2 1163226960 25186384 5609488
04:07:28 118500 8192000000 10063317152 10531221504 1120656944 12.033 99 15 30 0 1185402896 61990448 2755504
04:10:27 34716 8192000000 10077018944 10502434816 1122602816 12.052 98 26 52 2 1156285024 28388544 5293664
04:12:09 102759 8192000000 10058840784 10534412288 1117260976 12.001 98 11 22 1 1164078384 44131984 2685424
04:16:01 71801 8192000000 10103934224 10582016000 1131420624 12.135 99 14 28 0 1218597312 84281680 2895008
04:18:21 54046 8192000000 10126284144 10609041408 1117928224 12.007 98 27 54 2 1188979904 65203952 5847728
04:20:16 45809 8192000000 10117397904 10550255616 1084133072 11.687 96 19 38 0 1165701760 77148752 4419936
04:22:02 55009 8192000000 10127534880 10594258944 1117604672 12.004 99 23 46 0 1230182784 107452048 5126064
04:24:05 66798 8192000000 10098429024 10594054144 1117388960 12.002 99 29 58 2 1209828240 86256544 6182736
04:26:12 55117 8192000000 9080238672 9333125120 220077536 2.616 20 6 12 0 233574944 12395792 1101616

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 07-02-2021 04:27:52 to 07-02-2021 04:28:07.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 141 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 07-02-2021 04:28:08 to 07-02-2021 04:28:10.

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          236818
MON$OLDEST_ACTIVE               236819
MON$OLDEST_SNAPSHOT             236819
MON$NEXT_TRANSACTION            236819
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                       2090880
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             801
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

Database statistics

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

Database validation

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

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

--- start of diff output ---
:37: 
:38: mscs-test-161.budzdorov.ru    Sun Feb  7 04:27:10 2021
:39:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:40: 
:41: 
:42: mscs-test-161.budzdorov.ru    Sun Feb  7 04:27:11 2021
:43:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:44: 
--- end of diff output ---

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

07.02.21 04:28:11. 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-07 04:28:14 No severe PSQL-related problems occured
07.02.21 04:28:15. End of report.