Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.04.2021 13:28
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 df9f488d08424df294c459d6c283e9c3
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 2021-03-04 16:07
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.26
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 16824392 kB
MemAvailable 31499976 kB
Buffers 0 kB
Cached 14862420 kB
SwapCached 0 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-04-02 13:27:00
DTS_END                         2021-04-02 13:27:00.6260
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/54170
TRN_ID                          230951
ATT_ID                          652
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: 9074.93 21076 1088992
customer order: creation 167.83 430 20139
customer order: refuse 14.13 304 1695
order to supplier: creation 44.70 1456 5364
order to supplier: removal 3.46 122139 415
invoice (draft): creation 44.02 3406 5282
invoice (draft): removal 51.14 1027 6137
invoice accept: apply 40.38 18050 4845
invoice accept: cancel 46.17 902 5540
customer reserve: creation 3788.16 174 454579
customer reserve: removal 4591.62 73 550994
realization accept: apply 82.05 191 9846
realization accept: cancel 76.38 121 9165
payment from customer: creation 58.01 117 6961
payment from customer: removal 5.89 93 707
payment to supplier: creation 46.86 116 5623
payment to supplier: removal 3.33 85 400
service: total inventory turnovers 3.97 3091 476
service: total monetary turnovers 6.00 540 720
service: aggregate perf. data 0.83 13734 100
service: refresh index statistics 0.03 255476 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-04-2021 13:28:50 to 02-04-2021 13:28:52.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5392
TEST_TIME 2 5502
TEST_TIME 3 6006
TEST_TIME 4 5985
TEST_TIME 5 5645
TEST_TIME 6 5342
TEST_TIME 7 5441
TEST_TIME 8 5888
TEST_TIME 9 5872
TEST_TIME 10 6223
TEST_TIME 11 6220
TEST_TIME 12 6509
TEST_TIME 13 6811
TEST_TIME 14 6822
TEST_TIME 15 7428
TEST_TIME 16 7490
TEST_TIME 17 7673
TEST_TIME 18 7948
TEST_TIME 19 8368
TEST_TIME 20 8848
TEST_TIME 21 8835
TEST_TIME 22 9114
TEST_TIME 23 9409
TEST_TIME 24 9965
TEST_TIME 25 11479
TEST_TIME 26 11729
TEST_TIME 27 11715
TEST_TIME 28 11427
TEST_TIME 29 11491
TEST_TIME 30 11381
TEST_TIME 31 11284
TEST_TIME 32 11084
TEST_TIME 33 10814
TEST_TIME 34 10673
TEST_TIME 35 10515
TEST_TIME 36 10394
TEST_TIME 37 10211
TEST_TIME 38 10192
TEST_TIME 39 10459
TEST_TIME 40 10383
TEST_TIME 41 10229
TEST_TIME 42 10339
TEST_TIME 43 10233
TEST_TIME 44 10085
TEST_TIME 45 10133
TEST_TIME 46 10051
TEST_TIME 47 10023
TEST_TIME 48 10063
TEST_TIME 49 10073
TEST_TIME 50 10041
TEST_TIME 51 9952
TEST_TIME 52 9905
TEST_TIME 53 10117
TEST_TIME 54 10077
TEST_TIME 55 10107
TEST_TIME 56 10126
TEST_TIME 57 10111
TEST_TIME 58 10072
TEST_TIME 59 9934
TEST_TIME 60 9801
TEST_TIME 61 9791
TEST_TIME 62 9799
TEST_TIME 63 9735
TEST_TIME 64 9692
TEST_TIME 65 9663
TEST_TIME 66 9644
TEST_TIME 67 9631
TEST_TIME 68 9583
TEST_TIME 69 9606
TEST_TIME 70 9650
TEST_TIME 71 9762
TEST_TIME 72 9761
TEST_TIME 73 9757
TEST_TIME 74 9849
TEST_TIME 75 9972
TEST_TIME 76 9987
TEST_TIME 77 9963
TEST_TIME 78 9891
TEST_TIME 79 9792
TEST_TIME 80 9740
TEST_TIME 81 9672
TEST_TIME 82 9650
TEST_TIME 83 9592
TEST_TIME 84 9564
TEST_TIME 85 9504
TEST_TIME 86 9469
TEST_TIME 87 9466
TEST_TIME 88 9557
TEST_TIME 89 9527
TEST_TIME 90 9534
TEST_TIME 91 9568
TEST_TIME 92 9669
TEST_TIME 93 9668
TEST_TIME 94 9711
TEST_TIME 95 9715
TEST_TIME 96 9645
TEST_TIME 97 9580
TEST_TIME 98 9538
TEST_TIME 99 9475
TEST_TIME 100 9453
TEST_TIME 101 9420
TEST_TIME 102 9408
TEST_TIME 103 9419
TEST_TIME 104 9430
TEST_TIME 105 9427
TEST_TIME 106 9407
TEST_TIME 107 9374
TEST_TIME 108 9407
TEST_TIME 109 9372
TEST_TIME 110 9364
TEST_TIME 111 9376
TEST_TIME 112 9417
TEST_TIME 113 9422
TEST_TIME 114 9368
TEST_TIME 115 9321
TEST_TIME 116 9269
TEST_TIME 117 9224
TEST_TIME 118 9196
TEST_TIME 119 9154
TEST_TIME 120 9078

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 02-04-2021 13:28:53 to 02-04-2021 13:28:57.

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 1509575 1509575 0 0.00 0 14590 6 0 0
doc_list_biud 1509573 1509573 0 0.00 0 42367 33 0 0
sp_add_invoice_to_stock 4972 4845 127 2.55 63 225679 18050 0 127
sp_cancel_adding_invoice 5540 5540 0 0.00 5 112412 903 0 0
sp_cancel_client_order 1695 1695 0 0.00 4 9519 304 0 0
sp_cancel_customer_reserve 550994 550994 0 0.00 1 42369 74 0 0
sp_cancel_pay_from_customer 707 707 0 0.00 3 8562 94 0 0
sp_cancel_pay_to_supplier 400 400 0 0.00 3 6245 86 0 0
sp_cancel_supplier_invoice 6144 6137 7 0.11 2 27801 1027 0 7
sp_cancel_supplier_order 415 415 0 0.00 16 1503264 122140 0 0
sp_cancel_write_off 9165 9165 0 0.00 1 8332 121 0 0
sp_client_order 20139 20139 0 0.00 4 40676 431 0 0
sp_customer_reserve 454579 454579 0 0.00 0 134233 174 0 0
sp_fill_shopping_cart 30764 30764 0 0.00 1 21321 40 0 0
sp_fill_shopping_cart_clo_res 454573 454573 0 0.00 0 25264 18 0 0
sp_get_clo_for_invoice 4839 4839 0 0.00 0 34959 364 0 0
sp_kill_cost_storno 15812 15812 0 0.00 0 6108 60 0 0
sp_kill_qstorno_ret_qs2qd 1117705 1117705 0 0.00 0 38278 40 0 0
sp_kill_qty_storno 588631 588631 0 0.00 0 42367 85 0 0
sp_lock_selected_doc 20359 20359 0 0.00 0 8246 7 0 0
sp_make_cost_storno 27269 27269 0 0.00 0 14576 59 0 0
sp_multiply_rows_for_pdistr 27267 27267 0 0.00 0 6885 33 0 0
sp_multiply_rows_for_qdistr 20139 20139 0 0.00 0 40521 252 0 0
sp_pay_from_customer 6961 6961 0 0.00 4 13990 117 0 0
sp_pay_to_supplier 5623 5623 0 0.00 5 10330 117 0 0
sp_payment_common 12582 12582 0 0.00 1 13944 22 0 0
sp_qd_handle_on_cancel_clo 1695 1695 0 0.00 0 9443 281 0 0
sp_qd_handle_on_invoice_upd_sts 10379 10379 0 0.00 0 13350 375 0 0
sp_qd_handle_on_reserve_upd_sts 9846 9846 0 0.00 0 14956 51 0 0
sp_reserve_write_off 9846 9846 0 0.00 7 19704 192 0 0
sp_supplier_invoice 5282 5282 0 0.00 7 96392 3406 0 0
sp_supplier_order 5364 5364 0 0.00 5 67275 1456 0 0
srv_aggregate_perf_data 100 100 0 0.00 2245 174083 13735 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1573 148593 47925 0 0
srv_find_qd_qs_mism 1042269 1042269 0 0.00 0 12805 5 0 0
srv_make_invnt_saldo 476 476 0 0.00 46 140133 3092 0 0
srv_make_money_saldo 720 720 0 0.00 1 17513 541 0 0
srv_recalc_idx_stat 4 4 0 0.00 124987 435088 255476 0 0
t$perf-abend:sp_add_invoice_to_stock 127 127 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 4845 4845 0 0.00 0 16044 177 0 0
t$perf-norm:sp_cancel_adding_invoice 413 413 0 0.00 0 6102 111 0 0
t$perf-norm:sp_cancel_client_order 1695 1695 0 0.00 0 5828 16 0 0
t$perf-norm:sp_cancel_customer_reserve 679 679 0 0.00 0 576 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 707 707 0 0.00 0 1039 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 400 400 0 0.00 0 521 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 409 409 0 0.00 0 6767 33 0 0
t$perf-norm:sp_cancel_supplier_order 415 415 0 0.00 0 16566 1579 0 0
t$perf-norm:sp_cancel_write_off 958 958 0 0.00 0 793 5 0 0
t$perf-norm:sp_client_order 20139 20139 0 0.00 0 8589 18 0 0
t$perf-norm:sp_customer_reserve 988 988 0 0.00 0 2471 10 0 0
t$perf-norm:sp_pay_from_customer 6961 6961 0 0.00 0 4518 8 0 0
t$perf-norm:sp_pay_to_supplier 5623 5623 0 0.00 0 6137 10 0 0
t$perf-norm:sp_reserve_write_off 9846 9846 0 0.00 0 6386 10 0 0
t$perf-norm:sp_supplier_invoice 5282 5282 0 0.00 0 6683 26 0 0
t$perf-norm:sp_supplier_order 5364 5364 0 0.00 0 13047 20 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 144 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 38 1 0 0
t$perf-norm:srv_make_invnt_saldo 476 476 0 0.00 0 2934 13 0 0
t$perf-norm:srv_make_money_saldo 720 720 0 0.00 0 5681 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 9930 9676 254 2.55 0 9179 20 0 254
v_all_customers 36560 36560 0 0.00 0 222 1 0 0
v_all_suppliers 29776 29776 0 0.00 0 19 1 0 0
v_all_wares 40274 40274 0 0.00 0 69 1 0 0
v_cancel_adding_invoice 826 826 0 0.00 0 848 7 0 0
v_cancel_client_order 3390 3390 0 0.00 0 609 3 0 0
v_cancel_customer_prepayment 1414 1414 0 0.00 0 5221 12 0 0
v_cancel_customer_reserve 1358 1358 0 0.00 0 459 3 0 0
v_cancel_payment_to_supplier 800 800 0 0.00 0 656 4 0 0
v_cancel_supplier_invoice 832 818 14 1.68 0 1622 15 0 14
v_cancel_supplier_order 830 830 0 0.00 0 4099 9 0 0
v_cancel_write_off 1916 1916 0 0.00 0 5984 18 0 0
v_max_id_clo_ord 5360 5360 0 0.00 0 1579 2 0 0
v_max_id_clo_res 988 988 0 0.00 0 1984 14 0 0
v_max_id_ord_sup 5267 5267 0 0.00 0 3561 4 0 0
v_max_non_paid_invoice 1360 1360 0 0.00 0 3800 5 0 0
v_max_non_paid_realizn 3814 3814 0 0.00 0 850 3 0 0
v_min_id_clo_ord 5360 5360 0 0.00 0 1760 6 0 0
v_min_id_clo_res 988 988 0 0.00 0 1273 4 0 0
v_min_id_ord_sup 5267 5267 0 0.00 0 7365 14 0 0
v_min_non_paid_invoice 5621 5621 0 0.00 0 4293 11 0 0
v_min_non_paid_realizn 6961 6961 0 0.00 0 5783 15 0 0
v_our_firm 10008 10008 0 0.00 0 15 1 0 0
v_reserve_write_off 19688 19688 0 0.00 0 8331 7 0 0
x_lock_depdocs_on_canc_invoice 11268 11268 0 0.00 0 11870 108 0 0
x_lock_depdocs_on_canc_sup_ord 415 415 0 0.00 0 8420 356 0 0
x_make_qstorno_1000_1200 5364 5364 0 0.00 2 67262 1382 0 0
x_make_qstorno_1200_2000 5282 5282 0 0.00 3 91568 3255 0 0
x_make_qstorno_2100_3300 454579 454579 0 0.00 0 134231 153 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-04-2021 13:28:58 to 02-04-2021 13:29:02.

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
10:57:04 2 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
10:58:04 20329 8192000000 9719747040 10015571968 798834288 8.884 100 86 172 0 826619968 7453776 20331904
10:59:28 39070 8192000000 9767369824 10072338432 834681376 9.246 100 92 184 1 869629872 13180208 21768288
11:01:19 20224 8192000000 9813461472 10131062784 867991696 9.580 100 83 166 0 916341712 28885936 19464080
11:02:40 10801 8192000000 9880509904 10211524608 914215856 10.039 100 75 150 1 989870720 59369872 16284992
11:03:53 6777 8192000000 9920376592 10260320256 953721824 10.428 100 82 164 3 1035625648 63873200 18030624
11:05:02 3494 8192000000 9970804864 10319495168 1003246576 10.910 100 87 172 3 1086432768 65545888 17640304
11:06:08 5961 8192000000 10016385072 10379165696 1027911744 11.148 100 74 148 3 1123162144 80980480 14269920
11:07:15 1793 8192000000 10019433056 10385559552 1055541888 11.414 100 94 188 0 1134815280 59269984 20003408
11:08:18 27974 8192000000 9993752240 10371395584 1063435024 11.489 100 67 133 1 1125292096 47700032 14157040
11:09:47 6210 8192000000 10001759136 10387087360 1078248816 11.631 100 41 78 4 1113065984 26767584 8049584
11:10:53 9459 8192000000 9999727888 10407493632 1081220192 11.659 100 14 28 1 1111473456 27403344 2849920
11:12:09 25178 8192000000 10014714496 10413355008 1084193456 11.687 100 40 79 1 1121576672 29471568 7911648
11:13:34 5704 8192000000 10018779488 10409459712 1093051280 11.772 100 40 80 0 1111692992 9564032 9077680
11:14:42 6397 8192000000 10002845712 10398367744 1093485968 11.776 100 36 70 2 1122435648 21866496 7083184
11:15:51 72679 8192000000 9997465312 10398449664 1036800272 11.234 94 25 47 1 1069284080 27731392 4752416
11:18:04 3692 8192000000 9986146304 10376753152 1060952192 11.466 98 58 116 0 1081540320 9231200 11356928
11:19:10 5526 8192000000 9943051024 10319679488 1024271712 11.113 99 31 62 2 1050446976 18854416 7320848
11:20:17 2845 8192000000 9941324176 10319073280 1010200448 10.977 99 27 53 2 1049118176 32977856 5939872
11:21:20 36802 8192000000 9955362128 10351902720 1019691248 11.069 99 39 78 1 1050054992 22151520 8212224
11:23:00 5169 8192000000 9954673168 10333724672 1031344432 11.181 99 36 72 4 1056721136 16761792 8614912
11:24:09 9817 8192000000 9973393280 10366951424 1048338688 11.345 99 39 77 2 1071464656 14783008 8342960
11:25:19 8422 8192000000 9975136880 10382958592 1070299648 11.555 100 22 44 1 1105564304 30189040 5075616
11:26:29 28301 8192000000 9984072976 10395975680 1075142704 11.601 100 21 42 1 1097128384 17153152 4832528
11:28:04 27289 8192000000 10027625488 10426728448 1106024656 11.895 100 32 64 4 1136044848 23287120 6733072
11:29:36 5390 8192000000 10106563280 10505674752 1125036032 12.075 100 56 112 0 1215679408 79131392 11511984
11:30:46 27685 8192000000 10126388880 10538070016 1143236432 12.246 100 45 88 0 1227531472 74198832 10096208
11:32:27 4114 8192000000 10160707104 10569289728 1157816992 12.383 100 54 108 2 1271231072 102216560 11197520
11:33:37 3791 8192000000 10226814960 10630569984 1168146464 12.480 100 73 146 2 1331257088 147152560 15958064
11:34:48 14623 8192000000 10233353488 10656129024 1173567712 12.530 100 41 82 0 1349094080 165567872 9958496
11:36:14 16084 8192000000 10267859664 10694049792 1186437216 12.650 100 61 122 0 1374942912 174613392 13892304
11:37:40 7805 8192000000 10302706272 10733117440 1191324688 12.696 100 57 114 1 1407551120 203370656 12855776
11:38:53 4953 8192000000 10327532288 10731806720 1190757312 12.690 100 75 150 0 1431266176 222744784 17764080
11:40:03 5838 8192000000 10391093104 10814050304 1210590176 12.875 100 73 144 0 1497946464 270603584 16752704
11:41:17 1843 8192000000 10392153152 10801909760 1207973088 12.850 100 75 147 2 1490450416 265066192 17411136
11:42:22 7981 8192000000 10417329168 10855804928 1184291840 12.630 100 83 166 0 1516909424 312369120 20248464
11:43:36 2770 8192000000 10421313696 10825592832 1179230336 12.583 100 90 181 0 1518781056 318031008 21519712
11:44:42 1569 8192000000 10449750336 10852343808 1173248224 12.527 100 85 167 1 1544535136 351198384 20088528
11:45:47 8845 8192000000 10456483136 10875584512 1167088192 12.470 99 48 96 1 1549753760 370016320 12649248
11:47:04 2378 8192000000 10447163808 10870702080 1173315200 12.528 100 78 156 0 1541923872 350072528 18536144
11:48:08 17009 8192000000 10448025680 10882129920 1166937616 12.468 100 34 68 0 1533455856 356927600 9590640
11:49:30 7149 8192000000 10427975152 10849910784 1133175312 12.151 98 52 102 0 1513216752 367193952 12847488
11:50:39 41357 8192000000 10465446880 10932789248 1138028592 12.197 99 26 52 3 1551110272 405555664 7526016
11:53:09 66708 8192000000 10043503232 10500210688 1037354128 11.239 92 6 12 0 1083739536 45214464 1170944
11:55:41 37035 8192000000 10033822832 10473758720 1092556320 11.767 98 29 59 2 1148240288 49450016 6233952
11:57:20 22464 8192000000 10070457760 10499706880 1116270064 11.992 99 36 69 0 1161233472 37448896 7514512
11:58:43 104865 8192000000 10092500224 10532691968 1091092896 11.753 96 32 64 2 1123805760 26623024 6089840
12:01:44 148593 8192000000 10163427296 10605133824 1098204752 11.821 96 30 60 0 1213441280 108808448 6428080
12:05:14 51323 8192000000 10203775136 10665213952 1132574848 12.146 99 24 49 0 1286764864 148298016 5892000
12:07:10 62532 8192000000 10108175920 10557562880 1113947808 11.970 97 18 36 0 1174035312 55872112 4215392
12:09:13 31520 8192000000 10081079824 10609520640 1144066320 12.254 100 13 26 1 1235125952 87858992 3200640
12:10:46 48787 8192000000 10091679600 10552061952 1138276000 12.199 99 15 30 3 1189336016 47702256 3357760
12:12:39 56001 8192000000 10080733872 10599362560 1147654784 12.287 100 5 10 0 1185318992 36702624 961584
12:14:35 92695 8192000000 10083912752 10566533120 1125902912 12.083 98 18 36 0 1162740704 33004048 3833744
12:17:08 60638 8192000000 10102317456 10530525184 1142505472 12.239 99 29 58 0 1183152352 35121008 5525872
12:19:10 58882 8192000000 10168110416 10649751552 1150251888 12.312 100 23 46 0 1258032640 103099312 4681440
12:21:14 74399 8192000000 10083011104 10537644032 1098273168 11.821 95 23 46 0 1146190496 43533744 4383584
12:23:29 69896 8192000000 10115825344 10556784640 1099513568 11.833 96 15 30 0 1159489504 56989392 2986544
12:25:41 85515 8192000000 10091982288 10530508800 1099713632 11.835 97 41 82 1 1173056528 64418544 8924352
12:28:53 50314 8192000000 10063809088 10579345408 1116344848 11.992 99 7 14 1 1167155296 49584304 1226144
12:30:44 70496 8192000000 10062462704 10533007360 1127196800 12.095 99 10 21 0 1169859888 40680912 1982176
12:32:55 52930 8192000000 10067083552 10530992128 1060260640 11.459 93 22 45 0 1122410416 56932224 5217552
12:34:48 49870 8192000000 10106531920 10552201216 1067574208 11.529 94 31 61 0 1142015488 67590576 6850704
12:36:48 69585 8192000000 10160319472 10648969216 1097349696 11.812 97 20 40 0 1236765360 135044432 4371232
12:39:14 92866 8192000000 10106803360 10534301696 1086667376 11.711 96 25 48 1 1181919632 89945472 5306784
12:41:48 67188 8192000000 10053527584 10564251648 1065028688 11.505 95 40 80 0 1129215664 56297792 7889184
12:43:57 66419 8192000000 10045049136 10482847744 1062723952 11.483 95 14 28 0 1099672640 34376896 2571792
12:46:09 64322 8192000000 10017408384 10451640320 1082366064 11.670 97 32 60 2 1101865856 12535696 6964096
12:48:18 46328 8192000000 10040618416 10485358592 1084659104 11.692 98 10 20 0 1132571952 45822576 2090272
12:50:06 43948 8192000000 10044190720 10531741696 1095171568 11.792 99 10 21 0 1146507440 48834832 2501040
12:52:32 24895 8192000000 10105349936 10542608384 1109402528 11.927 99 34 68 1 1187600880 70878352 7320000
12:53:57 60215 8192000000 10131542208 10584039424 1107669488 11.910 98 30 60 0 1223471936 108926560 6875888
12:55:57 44127 8192000000 10109670992 10556735488 1117812240 12.006 99 19 38 0 1186850256 64970928 4067088
12:57:42 74703 8192000000 10127349168 10571911168 1127206480 12.095 99 14 28 0 1232426576 101974528 3245568
12:59:57 38376 8192000000 10110017728 10593460224 1147380400 12.285 100 30 60 1 1206053568 51725552 6947616
13:01:39 48915 8192000000 10122574576 10574827520 1150299200 12.312 100 16 32 1 1206226576 53002912 2924464
13:03:50 82923 8192000000 10082967872 10548219904 1139896384 12.215 100 26 51 0 1201621392 56627072 5097936
13:06:29 75584 8192000000 10102928496 10566967296 1088306768 11.727 95 6 12 1 1155863664 66286768 1270128
13:10:30 68518 8192000000 10077467200 10584891392 1125576288 12.080 98 19 38 0 1201702912 72093648 4032976
13:12:41 71806 8192000000 10076398336 10518380544 1114580208 11.976 97 23 44 2 1154441056 35203520 4657328
13:14:57 73308 8192000000 10062848384 10521686016 1087875536 11.722 96 13 26 0 1163135472 72378704 2881232
13:17:14 62154 8192000000 10124401136 10580459520 1122003360 12.046 99 21 42 0 1192814368 66234144 4576864
13:19:59 89548 8192000000 10074393472 10508197888 1084771472 11.693 96 27 54 1 1128258080 37962016 5524592
13:23:13 64886 8192000000 10053661168 10509701120 1116273216 11.992 99 9 18 0 1154194928 35856720 2064992
13:25:42 88814 8192000000 9460059488 9856401408 574569824 6.554 50 12 24 0 633287616 56106320 2611472

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 02-04-2021 13:29:04 to 02-04-2021 13:29:18.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 127 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 02-04-2021 13:29:19 to 02-04-2021 13:29:21.

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          231218
MON$OLDEST_ACTIVE               231219
MON$OLDEST_SNAPSHOT             231219
MON$NEXT_TRANSACTION            231219
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                       2099328
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             789
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2399 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2399 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2399 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.2399 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 ---
:28: 
:29: mscs-test-161.budzdorov.ru    Fri Apr  2 13:27:22 2021
:30:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:31: 
:32: 
:33: mscs-test-161.budzdorov.ru    Fri Apr  2 13:27:23 2021
:34:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:35: 
:36: 
:37: mscs-test-161.budzdorov.ru    Fri Apr  2 13:27:27 2021
:38:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:39: 
--- end of diff output ---

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

02.04.21 13:29:22. 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-04-02 13:29:25 No severe PSQL-related problems occured
02.04.21 13:29:26. End of report.