Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.02.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 16355116 kB
MemAvailable 31098672 kB
Buffers 0 kB
Cached 15320372 kB
SwapCached 3272 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-27 10:26:57
DTS_END                         2021-02-27 10:26:57.1500
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/52564
TRN_ID                          230171
ATT_ID                          616
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: 8965.78 26248 1075893
customer order: creation 164.28 424 19713
customer order: refuse 13.85 386 1662
order to supplier: creation 44.08 1376 5289
order to supplier: removal 3.35 117514 402
invoice (draft): creation 43.04 3658 5165
invoice (draft): removal 50.63 1015 6076
invoice accept: apply 40.05 17358 4806
invoice accept: cancel 45.95 889 5514
customer reserve: creation 3731.90 168 447828
customer reserve: removal 4545.38 68 545446
realization accept: apply 81.59 190 9791
realization accept: cancel 78.01 122 9361
payment from customer: creation 57.07 120 6848
payment from customer: removal 5.33 114 639
payment to supplier: creation 47.69 148 5723
payment to supplier: removal 3.23 105 388
service: total inventory turnovers 3.85 3091 462
service: total monetary turnovers 5.68 498 681
service: aggregate perf. data 0.79 18107 95
service: refresh index statistics 0.03 359602 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-02-2021 10:28:19 to 27-02-2021 10:28:21.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1941
TEST_TIME 2 1986
TEST_TIME 3 1858
TEST_TIME 4 2141
TEST_TIME 5 2676
TEST_TIME 6 3036
TEST_TIME 7 3024
TEST_TIME 8 3426
TEST_TIME 9 4496
TEST_TIME 10 4515
TEST_TIME 11 4746
TEST_TIME 12 4898
TEST_TIME 13 4919
TEST_TIME 14 4973
TEST_TIME 15 5191
TEST_TIME 16 5472
TEST_TIME 17 5881
TEST_TIME 18 6492
TEST_TIME 19 7010
TEST_TIME 20 7125
TEST_TIME 21 7294
TEST_TIME 22 7452
TEST_TIME 23 8078
TEST_TIME 24 8776
TEST_TIME 25 9206
TEST_TIME 26 9460
TEST_TIME 27 10173
TEST_TIME 28 10273
TEST_TIME 29 10091
TEST_TIME 30 10267
TEST_TIME 31 10105
TEST_TIME 32 9985
TEST_TIME 33 10234
TEST_TIME 34 10143
TEST_TIME 35 10225
TEST_TIME 36 10048
TEST_TIME 37 9868
TEST_TIME 38 9667
TEST_TIME 39 9584
TEST_TIME 40 9612
TEST_TIME 41 9538
TEST_TIME 42 9540
TEST_TIME 43 9490
TEST_TIME 44 9534
TEST_TIME 45 9424
TEST_TIME 46 9383
TEST_TIME 47 9284
TEST_TIME 48 9325
TEST_TIME 49 9407
TEST_TIME 50 9368
TEST_TIME 51 9472
TEST_TIME 52 9416
TEST_TIME 53 9431
TEST_TIME 54 9475
TEST_TIME 55 9521
TEST_TIME 56 9523
TEST_TIME 57 9495
TEST_TIME 58 9586
TEST_TIME 59 9611
TEST_TIME 60 9769
TEST_TIME 61 9655
TEST_TIME 62 9516
TEST_TIME 63 9443
TEST_TIME 64 9373
TEST_TIME 65 9313
TEST_TIME 66 9244
TEST_TIME 67 9226
TEST_TIME 68 9234
TEST_TIME 69 9220
TEST_TIME 70 9177
TEST_TIME 71 9123
TEST_TIME 72 9207
TEST_TIME 73 9148
TEST_TIME 74 9330
TEST_TIME 75 9382
TEST_TIME 76 9330
TEST_TIME 77 9353
TEST_TIME 78 9366
TEST_TIME 79 9263
TEST_TIME 80 9201
TEST_TIME 81 9157
TEST_TIME 82 9160
TEST_TIME 83 9150
TEST_TIME 84 9104
TEST_TIME 85 9080
TEST_TIME 86 9052
TEST_TIME 87 9033
TEST_TIME 88 9085
TEST_TIME 89 9168
TEST_TIME 90 9127
TEST_TIME 91 9159
TEST_TIME 92 9208
TEST_TIME 93 9220
TEST_TIME 94 9315
TEST_TIME 95 9313
TEST_TIME 96 9306
TEST_TIME 97 9234
TEST_TIME 98 9214
TEST_TIME 99 9183
TEST_TIME 100 9185
TEST_TIME 101 9195
TEST_TIME 102 9179
TEST_TIME 103 9163
TEST_TIME 104 9133
TEST_TIME 105 9132
TEST_TIME 106 9168
TEST_TIME 107 9155
TEST_TIME 108 9159
TEST_TIME 109 9167
TEST_TIME 110 9234
TEST_TIME 111 9232
TEST_TIME 112 9192
TEST_TIME 113 9115
TEST_TIME 114 9116
TEST_TIME 115 9062
TEST_TIME 116 9031
TEST_TIME 117 9016
TEST_TIME 118 8966
TEST_TIME 119 8974
TEST_TIME 120 8969

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 27-02-2021 10:28:23 to 27-02-2021 10:28:26.

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 1490861 1490861 0 0.00 0 21648 6 0 0
doc_list_biud 1490861 1490861 0 0.00 0 52195 32 0 0
sp_add_invoice_to_stock 4931 4806 125 2.53 25 185369 17359 0 125
sp_cancel_adding_invoice 5514 5514 0 0.00 4 67068 890 0 0
sp_cancel_client_order 1662 1662 0 0.00 4 41582 387 0 0
sp_cancel_customer_reserve 545446 545446 0 0.00 1 52199 68 0 0
sp_cancel_pay_from_customer 639 639 0 0.00 3 6584 115 0 0
sp_cancel_pay_to_supplier 388 388 0 0.00 3 6751 106 0 0
sp_cancel_supplier_invoice 6083 6076 7 0.11 2 39653 1016 0 7
sp_cancel_supplier_order 402 402 0 0.00 11 1319245 117515 0 0
sp_cancel_write_off 9361 9361 0 0.00 1 20362 122 0 0
sp_client_order 19713 19713 0 0.00 4 95145 425 0 0
sp_customer_reserve 447828 447828 0 0.00 0 80865 168 0 0
sp_fill_shopping_cart 30155 30155 0 0.00 1 18138 47 0 0
sp_fill_shopping_cart_clo_res 447822 447822 0 0.00 0 25377 18 0 0
sp_get_clo_for_invoice 4800 4800 0 0.00 0 45409 416 0 0
sp_kill_cost_storno 15902 15902 0 0.00 0 18399 62 0 0
sp_kill_qstorno_ret_qs2qd 1106731 1106731 0 0.00 0 43143 37 0 0
sp_kill_qty_storno 583052 583052 0 0.00 0 52195 80 0 0
sp_lock_selected_doc 20106 20106 0 0.00 0 8608 7 0 0
sp_make_cost_storno 27162 27162 0 0.00 0 15105 55 0 0
sp_multiply_rows_for_pdistr 27162 27162 0 0.00 0 15221 35 0 0
sp_multiply_rows_for_qdistr 19713 19713 0 0.00 0 95123 223 0 0
sp_pay_from_customer 6848 6848 0 0.00 4 11859 120 0 0
sp_pay_to_supplier 5723 5723 0 0.00 5 20190 148 0 0
sp_payment_common 12571 12571 0 0.00 1 14339 36 0 0
sp_qd_handle_on_cancel_clo 1662 1662 0 0.00 0 41537 338 0 0
sp_qd_handle_on_invoice_upd_sts 10314 10314 0 0.00 0 48449 382 0 0
sp_qd_handle_on_reserve_upd_sts 9791 9791 0 0.00 0 43927 58 0 0
sp_reserve_write_off 9791 9791 0 0.00 7 43996 191 0 0
sp_supplier_invoice 5165 5165 0 0.00 6 98898 3659 0 0
sp_supplier_order 5289 5289 0 0.00 6 54605 1377 0 0
srv_aggregate_perf_data 95 95 0 0.00 2724 192588 18107 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1434 186111 50073 0 0
srv_find_qd_qs_mism 1030166 1030166 0 0.00 0 21648 5 0 0
srv_make_invnt_saldo 462 462 0 0.00 58 120188 3091 0 0
srv_make_money_saldo 681 681 0 0.00 0 13643 498 0 0
srv_recalc_idx_stat 4 4 0 0.00 305573 446217 359602 0 0
t$perf-abend:sp_add_invoice_to_stock 125 125 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 36447 149 0 0
t$perf-norm:sp_cancel_adding_invoice 395 395 0 0.00 0 5220 80 0 0
t$perf-norm:sp_cancel_client_order 1662 1662 0 0.00 0 5639 18 0 0
t$perf-norm:sp_cancel_customer_reserve 671 671 0 0.00 0 1515 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 639 639 0 0.00 0 3465 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 388 388 0 0.00 0 623 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 392 392 0 0.00 0 1375 21 0 0
t$perf-norm:sp_cancel_supplier_order 402 402 0 0.00 0 18476 1600 0 0
t$perf-norm:sp_cancel_write_off 966 966 0 0.00 0 605 4 0 0
t$perf-norm:sp_client_order 19713 19713 0 0.00 0 9818 15 0 0
t$perf-norm:sp_customer_reserve 984 984 0 0.00 0 1982 5 0 0
t$perf-norm:sp_pay_from_customer 6848 6848 0 0.00 0 6592 7 0 0
t$perf-norm:sp_pay_to_supplier 5723 5723 0 0.00 0 9828 14 0 0
t$perf-norm:sp_reserve_write_off 9791 9791 0 0.00 0 4798 9 0 0
t$perf-norm:sp_supplier_invoice 5165 5165 0 0.00 0 4069 15 0 0
t$perf-norm:sp_supplier_order 5289 5289 0 0.00 0 4600 14 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 27 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 11 1 0 0
t$perf-norm:srv_make_invnt_saldo 462 462 0 0.00 0 1407 10 0 0
t$perf-norm:srv_make_money_saldo 681 681 0 0.00 0 9009 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 9850 9600 250 2.53 0 12478 19 0 250
v_all_customers 35578 35578 0 0.00 0 125 1 0 0
v_all_suppliers 29382 29382 0 0.00 0 106 1 0 0
v_all_wares 39426 39426 0 0.00 0 113 1 0 0
v_cancel_adding_invoice 790 790 0 0.00 0 3853 11 0 0
v_cancel_client_order 3324 3324 0 0.00 0 9321 11 0 0
v_cancel_customer_prepayment 1278 1278 0 0.00 0 3250 10 0 0
v_cancel_customer_reserve 1342 1342 0 0.00 0 9983 12 0 0
v_cancel_payment_to_supplier 776 776 0 0.00 0 5997 13 0 0
v_cancel_supplier_invoice 798 784 14 1.75 0 756 12 0 14
v_cancel_supplier_order 804 804 0 0.00 0 9324 35 0 0
v_cancel_write_off 1932 1932 0 0.00 0 527 12 0 0
v_max_id_clo_ord 5285 5285 0 0.00 0 1982 3 0 0
v_max_id_clo_res 984 984 0 0.00 0 3723 20 0 0
v_max_id_ord_sup 5155 5155 0 0.00 0 7435 10 0 0
v_max_non_paid_invoice 1472 1472 0 0.00 0 584 2 0 0
v_max_non_paid_realizn 3882 3882 0 0.00 0 10495 6 0 0
v_min_id_clo_ord 5285 5285 0 0.00 0 6420 10 0 0
v_min_id_clo_res 984 984 0 0.00 0 8304 29 0 0
v_min_id_ord_sup 5155 5155 0 0.00 0 4961 8 0 0
v_min_non_paid_invoice 5723 5723 0 0.00 0 14328 32 0 0
v_min_non_paid_realizn 6848 6848 0 0.00 0 5726 22 0 0
v_our_firm 9780 9780 0 0.00 0 66 1 0 0
v_reserve_write_off 19580 19580 0 0.00 0 10097 9 0 0
x_lock_depdocs_on_canc_invoice 11198 11198 0 0.00 0 14330 121 0 0
x_lock_depdocs_on_canc_sup_ord 402 402 0 0.00 0 10538 385 0 0
x_make_qstorno_1000_1200 5289 5289 0 0.00 2 54596 1296 0 0
x_make_qstorno_1200_2000 5165 5165 0 0.00 3 95219 3477 0 0
x_make_qstorno_2100_3300 447828 447828 0 0.00 0 80865 148 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-02-2021 10:28:27 to 27-02-2021 10:28:31.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
07:57:01 9 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
07:58:02 34777 8192000000 9742966736 10045972480 820415168 9.103 100 86 172 0 849124128 7859408 20849552
07:59:43 26054 8192000000 9789104864 10099187712 856616256 9.466 100 91 182 0 892952032 14622224 21713552
08:01:12 15829 8192000000 9826910768 10153222144 888189936 9.781 100 79 158 1 932158368 25711632 18256800
08:02:34 11845 8192000000 9883629056 10222215168 932256992 10.217 100 80 159 1 995513232 45574352 17681888
08:04:00 8395 8192000000 9953529248 10298929152 970890496 10.595 100 89 178 0 1056405712 66907472 18607744
08:05:13 4260 8192000000 9972971536 10327789568 1010105584 10.976 100 77 153 0 1088039008 62653344 15280080
08:06:19 3093 8192000000 10014861376 10378924032 1034305168 11.210 100 90 179 0 1134854880 82621600 17928112
08:07:23 1690 8192000000 10032947680 10402783232 1062554896 11.481 100 89 178 1 1143872480 62557248 18760336
08:08:26 2506 8192000000 10043267152 10432442368 1076667040 11.616 100 72 144 0 1160583744 69189840 14726864
08:09:30 11563 8192000000 9983198032 10379272192 1078577072 11.634 100 36 72 1 1100887280 13917264 8392944
08:10:42 7396 8192000000 10017536320 10416263168 1086851744 11.713 100 31 62 1 1136505648 43480896 6173008
08:11:51 7020 8192000000 10001015776 10397921280 1091355456 11.756 100 31 57 5 1127467936 28821328 7291152
08:12:59 9534 8192000000 10013092352 10420535296 1096149600 11.801 100 26 52 0 1119276032 17584800 5541632
08:14:09 48135 8192000000 10018870288 10433843200 1101834560 11.855 100 25 50 1 1137057136 30096992 5125584
08:15:58 4456 8192000000 10027722288 10434633728 1104365440 11.879 100 40 80 1 1134444112 21136112 8942560
08:17:07 10509 8192000000 10001131568 10406686720 1083415056 11.680 99 37 73 3 1112814224 21022096 8377072
08:18:19 11466 8192000000 9967594976 10368061440 1029526640 11.164 96 35 67 2 1056103008 18821456 7754912
08:19:31 5030 8192000000 9965442496 10339102720 1040285024 11.267 99 66 132 4 1066425792 9770624 16370144
08:20:36 4744 8192000000 9940957136 10321100800 1013382544 11.008 99 49 96 4 1052461168 28169360 10909264
08:21:44 4919 8192000000 9927279712 10310787072 997731008 10.857 98 47 94 2 1023532432 14672304 11129120
08:22:52 5556 8192000000 9945948656 10331594752 1040305216 11.268 100 39 73 3 1077795808 29135168 8355424
08:24:03 9579 8192000000 9956610352 10362966016 1038081248 11.246 99 18 35 4 1082541472 40895024 3565200
08:25:15 3912 8192000000 9984706912 10367868928 1053173536 11.391 100 62 122 1 1083085520 15846064 14065920
08:26:25 9653 8192000000 9991266304 10387767296 1068341088 11.536 100 47 93 2 1099052944 20420560 10291296
08:27:37 11663 8192000000 10019396928 10409406464 1086804288 11.712 100 62 124 1 1122354592 22407312 13142992
08:28:54 117067 8192000000 10108077664 10513833984 1107490224 11.909 100 58 115 1 1183240320 63726224 12023872
08:31:54 65046 8192000000 10135356192 10565218304 1126091616 12.085 100 36 73 1 1238588752 104252400 8244736
08:34:02 12902 8192000000 10200416960 10609123328 1141192928 12.227 99 51 102 1 1293530656 140330224 12007504
08:35:17 7808 8192000000 10265280032 10692030464 1159268624 12.396 100 58 116 0 1367454992 195636512 12549856
08:36:38 26025 8192000000 10270746944 10685493248 1170621824 12.503 100 56 110 2 1362159072 178564576 12972672
08:38:10 5178 8192000000 10289998608 10699923456 1181589776 12.605 100 72 139 3 1389930256 190334512 18005968
08:39:19 2884 8192000000 10308791808 10717773824 1179327680 12.584 100 80 159 1 1415142272 217033152 18781440
08:40:27 8003 8192000000 10366756000 10782351360 1189646480 12.680 100 69 138 0 1468986352 262742048 16597824
08:41:42 3233 8192000000 10401162656 10811121664 1181308608 12.602 100 86 171 0 1501960656 300999040 19653008
08:42:49 4238 8192000000 10438906112 10863099904 1181851520 12.607 100 68 135 0 1537345392 339190896 16302976
08:44:02 4199 8192000000 10442505120 10879160320 1184071680 12.628 100 64 127 2 1541758208 342870848 14815680
08:45:11 1434 8192000000 10426448624 10844745728 1167988800 12.478 100 80 160 0 1518580960 332612992 17979168
08:46:15 11279 8192000000 10422015552 10849034240 1156389472 12.369 100 45 88 0 1525546912 358345744 10811696
08:47:29 12315 8192000000 10454411344 10897240064 1157239632 12.377 100 44 88 0 1560877152 392211904 11425616
08:48:52 10279 8192000000 10483997008 10916089856 1133712912 12.156 100 44 88 0 1590798272 445234400 11850960
08:50:03 37360 8192000000 10331105440 10787606528 1128117264 12.104 99 30 60 0 1496491248 360386480 7987504
08:51:58 23557 8192000000 10265074304 10735017984 1121786208 12.044 100 24 48 0 1359539744 231538944 6214592
08:53:39 34027 8192000000 10049199184 10532691968 1089949952 11.742 99 15 29 1 1136064768 43077792 3037024
08:56:53 70585 8192000000 10059580224 10490220544 1057519296 11.433 96 17 34 1 1082749776 22150496 3079984
08:59:04 66660 8192000000 10064831056 10534768640 1105548592 11.890 99 13 26 0 1178568688 70742864 2277232
09:01:12 93173 8192000000 10047536816 10471931904 1093714416 11.778 97 8 16 0 1124199232 28859328 1625488
09:03:45 107144 8192000000 10112394624 10565005312 1139292256 12.209 100 33 64 2 1203677744 57686608 6698880
09:06:58 24333 8192000000 10077659392 10513522688 1111231520 11.944 98 17 34 0 1153272080 38501376 3539184
09:08:22 21218 8192000000 10093364816 10536546304 1110052608 11.933 99 44 87 1 1172246704 52697136 9496960
09:10:11 68855 8192000000 10066926400 10515460096 1107899088 11.913 98 16 32 0 1171769152 59938944 3931120
09:12:20 36716 8192000000 10080032800 10552246272 1128620624 12.108 100 18 36 0 1191104256 58756576 3727056
09:13:59 55649 8192000000 10125877584 10585681920 1127720896 12.100 99 22 44 2 1219754176 87081360 4951920
09:16:31 21453 8192000000 10104898528 10534637568 1149166512 12.302 100 44 86 0 1195788560 37403280 9218768
09:17:56 38765 8192000000 10085216864 10535370752 1142269584 12.237 100 11 22 0 1167444160 22840624 2333952
09:19:38 62237 8192000000 10065311760 10516467712 1133391264 12.153 99 18 36 0 1192261344 54808416 4061664
09:21:41 32425 8192000000 10084380272 10529263616 1123702144 12.062 98 18 34 1 1182162128 54466016 3993968
09:23:25 68122 8192000000 10120530608 10589532160 1120992304 12.036 98 20 40 0 1202127408 76827968 4307136
09:25:42 59157 8192000000 10072292944 10564124672 1116986496 11.999 99 4 8 0 1186803376 69060848 756032
09:27:41 114762 8192000000 10000797840 10442166272 1050573136 11.366 94 16 32 0 1072309920 18863360 2873424
09:30:40 57910 8192000000 10038291968 10484211712 1100130944 11.839 98 5 10 0 1129256112 28308464 816704
09:32:40 75907 8192000000 10093930224 10577772544 1121862048 12.045 99 11 22 0 1197045360 73199552 1983760
09:35:12 53007 8192000000 10074667648 10501861376 1076753376 11.617 95 23 46 1 1127740352 46033824 4953152
09:37:09 42210 8192000000 10109713568 10532147200 1055080048 11.409 94 32 63 1 1147803824 86362784 6360992
09:38:54 53538 8192000000 10136231696 10559037440 1111439824 11.946 100 30 59 0 1243769632 126099952 6229856
09:40:50 73230 8192000000 10044643184 10499145728 1095047152 11.791 98 14 28 1 1144547712 46506864 2993696
09:43:07 29511 8192000000 10090476752 10530213888 1120948128 12.036 100 41 82 1 1192357072 62600624 8808320
09:44:47 136358 8192000000 10038166400 10489188352 1109661312 11.929 99 22 44 1 1155385360 41086944 4637104
09:48:16 23670 8192000000 10040163792 10523418624 1106030976 11.895 99 7 14 0 1149566928 42239872 1296080
09:50:10 48918 8192000000 10049450640 10485899264 1097365728 11.813 98 22 42 1 1131954704 30252096 4336880
09:52:00 47971 8192000000 10096668624 10532646912 1129095216 12.113 100 32 64 0 1182279408 46308736 6875456
09:53:49 68178 8192000000 10127268016 10627166208 1116096176 11.990 99 24 49 0 1213313520 91710448 5506896
09:55:57 70279 8192000000 10126915792 10575507456 1102025856 11.857 97 17 33 1 1174348816 68801744 3521216
09:58:08 53683 8192000000 10137714656 10591293440 1116417552 11.993 98 16 32 2 1208904400 88915216 3571632
10:00:05 50612 8192000000 10080349232 10538045440 1122106032 12.047 99 16 32 0 1221528800 96049792 3372976
10:01:56 124549 8192000000 10098353040 10591846400 1142786928 12.242 100 19 37 1 1219290336 72662432 3840976
10:05:31 85789 8192000000 10163659440 10663088128 1157108432 12.376 100 10 20 0 1204642128 45565888 1967808
10:09:29 48612 8192000000 10107720880 10549899264 1125989712 12.084 97 20 40 2 1171544720 41588400 3966608
10:11:22 24537 8192000000 10118565344 10552999936 1153322784 12.341 100 48 96 1 1203780416 40026512 10431120
10:12:50 70413 8192000000 10100541968 10551001088 1119805648 12.025 97 34 68 1 1172406256 46359120 6241488
10:15:02 50013 8192000000 10114619872 10580897792 1132526800 12.145 99 24 46 1 1188742608 50783248 5432560
10:16:56 186111 8192000000 10091648192 10544177152 1090168288 11.744 95 18 36 1 1153535968 59987344 3380336
10:21:07 65044 8192000000 10065699296 10540789760 1117610512 12.004 98 11 22 0 1165286928 45160560 2515856
10:23:12 57691 8192000000 10071813856 10574860288 1139156928 12.208 100 10 20 1 1191842192 50616928 2068336
10:25:17 66777 8192000000 10052897840 10532065280 1093622048 11.777 96 10 20 0 1133302192 37706496 1973648

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 27-02-2021 10:28:33 to 27-02-2021 10:28:48.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 125 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 27-02-2021 10:28:49 to 27-02-2021 10:28:51.

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          231941
MON$OLDEST_ACTIVE               231942
MON$OLDEST_SNAPSHOT             231942
MON$NEXT_TRANSACTION            231942
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                       2109312
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             793
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2377 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2377 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2377 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.2377 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 ---
--- end of diff output ---

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

27.02.21 10:28:52. 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-27 10:28:54 No severe PSQL-related problems occured
27.02.21 10:28:56. End of report.