Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.03.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 17046552 kB
MemAvailable 31624196 kB
Buffers 0 kB
Cached 14645652 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-03-10 13:26:55
DTS_END                         2021-03-10 13:26:55.0610
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/36244
TRN_ID                          224692
ATT_ID                          640
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: 8569.95 24589 1028394
customer order: creation 159.71 442 19165
customer order: refuse 13.93 380 1672
order to supplier: creation 42.42 1467 5090
order to supplier: removal 3.18 140940 382
invoice (draft): creation 42.70 4010 5124
invoice (draft): removal 48.36 1171 5803
invoice accept: apply 39.40 17990 4728
invoice accept: cancel 43.36 997 5203
customer reserve: creation 3652.24 173 438269
customer reserve: removal 4248.99 82 509879
realization accept: apply 79.64 216 9557
realization accept: cancel 76.68 138 9202
payment from customer: creation 55.28 149 6633
payment from customer: removal 5.03 83 604
payment to supplier: creation 45.77 148 5492
payment to supplier: removal 3.13 104 375
service: total inventory turnovers 3.36 4023 403
service: total monetary turnovers 5.89 541 707
service: aggregate perf. data 0.86 15149 103
service: refresh index statistics 0.03 303575 3

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 10-03-2021 13:28:29 to 10-03-2021 13:28:32.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2463
TEST_TIME 2 3070
TEST_TIME 3 3324
TEST_TIME 4 3728
TEST_TIME 5 3509
TEST_TIME 6 3767
TEST_TIME 7 4222
TEST_TIME 8 4430
TEST_TIME 9 4584
TEST_TIME 10 4728
TEST_TIME 11 4956
TEST_TIME 12 5070
TEST_TIME 13 5203
TEST_TIME 14 5336
TEST_TIME 15 5565
TEST_TIME 16 5692
TEST_TIME 17 5698
TEST_TIME 18 5918
TEST_TIME 19 6225
TEST_TIME 20 6595
TEST_TIME 21 7058
TEST_TIME 22 7130
TEST_TIME 23 7020
TEST_TIME 24 7436
TEST_TIME 25 7640
TEST_TIME 26 7654
TEST_TIME 27 8035
TEST_TIME 28 8517
TEST_TIME 29 8957
TEST_TIME 30 10190
TEST_TIME 31 10319
TEST_TIME 32 10274
TEST_TIME 33 10065
TEST_TIME 34 9936
TEST_TIME 35 9930
TEST_TIME 36 9773
TEST_TIME 37 9695
TEST_TIME 38 9559
TEST_TIME 39 9461
TEST_TIME 40 9470
TEST_TIME 41 9358
TEST_TIME 42 9412
TEST_TIME 43 9368
TEST_TIME 44 9356
TEST_TIME 45 9359
TEST_TIME 46 9255
TEST_TIME 47 9361
TEST_TIME 48 9452
TEST_TIME 49 9378
TEST_TIME 50 9437
TEST_TIME 51 9263
TEST_TIME 52 9115
TEST_TIME 53 9046
TEST_TIME 54 8978
TEST_TIME 55 8947
TEST_TIME 56 8888
TEST_TIME 57 8812
TEST_TIME 58 8814
TEST_TIME 59 8767
TEST_TIME 60 8683
TEST_TIME 61 8655
TEST_TIME 62 8802
TEST_TIME 63 8866
TEST_TIME 64 8869
TEST_TIME 65 8878
TEST_TIME 66 8875
TEST_TIME 67 8886
TEST_TIME 68 8849
TEST_TIME 69 8973
TEST_TIME 70 9006
TEST_TIME 71 8938
TEST_TIME 72 9081
TEST_TIME 73 9044
TEST_TIME 74 9079
TEST_TIME 75 9031
TEST_TIME 76 8946
TEST_TIME 77 8879
TEST_TIME 78 8846
TEST_TIME 79 8800
TEST_TIME 80 8738
TEST_TIME 81 8689
TEST_TIME 82 8628
TEST_TIME 83 8669
TEST_TIME 84 8699
TEST_TIME 85 8678
TEST_TIME 86 8677
TEST_TIME 87 8680
TEST_TIME 88 8676
TEST_TIME 89 8683
TEST_TIME 90 8775
TEST_TIME 91 8843
TEST_TIME 92 8808
TEST_TIME 93 8843
TEST_TIME 94 8854
TEST_TIME 95 8882
TEST_TIME 96 8829
TEST_TIME 97 8770
TEST_TIME 98 8725
TEST_TIME 99 8687
TEST_TIME 100 8626
TEST_TIME 101 8606
TEST_TIME 102 8599
TEST_TIME 103 8598
TEST_TIME 104 8625
TEST_TIME 105 8594
TEST_TIME 106 8583
TEST_TIME 107 8598
TEST_TIME 108 8614
TEST_TIME 109 8679
TEST_TIME 110 8709
TEST_TIME 111 8751
TEST_TIME 112 8752
TEST_TIME 113 8779
TEST_TIME 114 8754
TEST_TIME 115 8682
TEST_TIME 116 8635
TEST_TIME 117 8615
TEST_TIME 118 8570
TEST_TIME 119 8570
TEST_TIME 120 8576

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-03-2021 13:28:33 to 10-03-2021 13:28:36.

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 1431733 1431733 0 0.00 0 22265 7 0 0
doc_list_biud 1431728 1431728 0 0.00 0 33933 37 0 0
sp_add_invoice_to_stock 4819 4728 91 1.88 46 177877 17990 0 91
sp_cancel_adding_invoice 5203 5203 0 0.00 5 67461 998 0 0
sp_cancel_client_order 1672 1672 0 0.00 4 11777 380 0 0
sp_cancel_customer_reserve 509879 509879 0 0.00 1 26437 82 0 0
sp_cancel_pay_from_customer 604 604 0 0.00 3 2033 83 0 0
sp_cancel_pay_to_supplier 375 375 0 0.00 3 4970 104 0 0
sp_cancel_supplier_invoice 5810 5803 7 0.12 2 37891 1171 0 7
sp_cancel_supplier_order 382 382 0 0.00 12 1360000 140941 0 0
sp_cancel_write_off 9202 9202 0 0.00 1 12552 138 0 0
sp_client_order 19165 19165 0 0.00 4 24569 442 0 0
sp_customer_reserve 438269 438269 0 0.00 0 107997 173 0 0
sp_fill_shopping_cart 29373 29373 0 0.00 1 18277 47 0 0
sp_fill_shopping_cart_clo_res 438258 438258 0 0.00 0 22864 20 0 0
sp_get_clo_for_invoice 4717 4717 0 0.00 0 42570 490 0 0
sp_kill_cost_storno 15384 15384 0 0.00 0 4953 74 0 0
sp_kill_qstorno_ret_qs2qd 1035145 1035145 0 0.00 0 33931 45 0 0
sp_kill_qty_storno 546414 546414 0 0.00 0 33932 96 0 0
sp_lock_selected_doc 19669 19669 0 0.00 0 8585 6 0 0
sp_make_cost_storno 26399 26399 0 0.00 0 21673 70 0 0
sp_multiply_rows_for_pdistr 26398 26398 0 0.00 0 20334 40 0 0
sp_multiply_rows_for_qdistr 19165 19165 0 0.00 0 24562 225 0 0
sp_pay_from_customer 6633 6633 0 0.00 4 18945 149 0 0
sp_pay_to_supplier 5492 5492 0 0.00 5 21659 149 0 0
sp_payment_common 12125 12125 0 0.00 1 17101 35 0 0
sp_qd_handle_on_cancel_clo 1672 1672 0 0.00 0 11770 352 0 0
sp_qd_handle_on_invoice_upd_sts 9920 9920 0 0.00 0 15655 435 0 0
sp_qd_handle_on_reserve_upd_sts 9556 9556 0 0.00 0 10529 57 0 0
sp_reserve_write_off 9557 9557 0 0.00 7 22276 216 0 0
sp_supplier_invoice 5124 5124 0 0.00 5 108371 4010 0 0
sp_supplier_order 5090 5090 0 0.00 5 31665 1467 0 0
srv_aggregate_perf_data 103 103 0 0.00 2572 136346 15150 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1227 164040 47636 0 0
srv_find_qd_qs_mism 982526 982526 0 0.00 0 14695 6 0 0
srv_make_invnt_saldo 403 403 0 0.00 54 137119 4023 0 0
srv_make_money_saldo 707 707 0 0.00 1 13437 541 0 0
srv_recalc_idx_stat 3 3 0 0.00 228728 343900 303575 0 0
t$perf-abend:sp_add_invoice_to_stock 91 91 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 4729 4729 0 0.00 0 15705 166 0 0
t$perf-norm:sp_cancel_adding_invoice 383 383 0 0.00 0 1975 90 0 0
t$perf-norm:sp_cancel_client_order 1672 1672 0 0.00 0 6705 17 0 0
t$perf-norm:sp_cancel_customer_reserve 625 625 0 0.00 0 2385 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 604 604 0 0.00 0 1660 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 375 375 0 0.00 0 777 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 389 389 0 0.00 0 911 14 0 0
t$perf-norm:sp_cancel_supplier_order 382 382 0 0.00 0 19037 1845 0 0
t$perf-norm:sp_cancel_write_off 966 966 0 0.00 0 1030 6 0 0
t$perf-norm:sp_client_order 19165 19165 0 0.00 0 8035 19 0 0
t$perf-norm:sp_customer_reserve 939 939 0 0.00 0 1683 9 0 0
t$perf-norm:sp_pay_from_customer 6633 6633 0 0.00 0 8699 12 0 0
t$perf-norm:sp_pay_to_supplier 5492 5492 0 0.00 0 3705 8 0 0
t$perf-norm:sp_reserve_write_off 9557 9557 0 0.00 0 12255 12 0 0
t$perf-norm:sp_supplier_invoice 5124 5124 0 0.00 0 6142 20 0 0
t$perf-norm:sp_supplier_order 5090 5090 0 0.00 0 3654 14 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 3683 65 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 28 2 0 0
t$perf-norm:srv_make_invnt_saldo 403 403 0 0.00 0 582 11 0 0
t$perf-norm:srv_make_money_saldo 707 707 0 0.00 0 1252 5 0 0
t$perf-norm:srv_recalc_idx_stat 3 3 0 0.00 2 6 3 0 0
v_add_invoice_to_stock 9616 9434 182 1.89 0 8080 21 0 182
v_all_customers 35066 35066 0 0.00 0 16 1 0 0
v_all_suppliers 28526 28526 0 0.00 0 20 1 0 0
v_all_wares 38330 38330 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 766 766 0 0.00 0 1983 10 0 0
v_cancel_client_order 3344 3344 0 0.00 0 2529 7 0 0
v_cancel_customer_prepayment 1208 1208 0 0.00 0 593 6 0 0
v_cancel_customer_reserve 1250 1250 0 0.00 0 3439 8 0 0
v_cancel_payment_to_supplier 750 750 0 0.00 0 2368 11 0 0
v_cancel_supplier_invoice 792 778 14 1.76 0 2980 21 0 14
v_cancel_supplier_order 764 764 0 0.00 0 7362 21 0 0
v_cancel_write_off 1932 1932 0 0.00 0 2387 19 0 0
v_max_id_clo_ord 5090 5090 0 0.00 0 718 2 0 0
v_max_id_clo_res 939 939 0 0.00 0 5299 33 0 0
v_max_id_ord_sup 5118 5118 0 0.00 0 9724 8 0 0
v_max_non_paid_invoice 1437 1437 0 0.00 0 410 2 0 0
v_max_non_paid_realizn 3581 3581 0 0.00 0 6089 8 0 0
v_min_id_clo_ord 5090 5090 0 0.00 0 4436 10 0 0
v_min_id_clo_res 939 939 0 0.00 0 1159 4 0 0
v_min_id_ord_sup 5118 5118 0 0.00 0 2414 4 0 0
v_min_non_paid_invoice 5492 5492 0 0.00 0 17096 21 0 0
v_min_non_paid_realizn 6633 6633 0 0.00 0 14327 27 0 0
v_our_firm 9368 9368 0 0.00 0 13 1 0 0
v_reserve_write_off 19112 19112 0 0.00 0 3428 7 0 0
x_lock_depdocs_on_canc_invoice 10617 10617 0 0.00 0 21349 133 0 0
x_lock_depdocs_on_canc_sup_ord 382 382 0 0.00 0 9854 437 0 0
x_make_qstorno_1000_1200 5090 5090 0 0.00 2 31660 1380 0 0
x_make_qstorno_1200_2000 5124 5124 0 0.00 3 108363 3828 0 0
x_make_qstorno_2100_3300 438269 438269 0 0.00 0 107996 151 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-03-2021 13:28:37 to 10-03-2021 13:28:41.

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:56:58 2 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
10:57:58 20401 8192000000 9711654064 10005970944 793280096 8.828 100 86 172 0 822222720 7295792 21646832
10:59:24 21627 8192000000 9756289664 10058379264 827386976 9.173 100 89 178 0 860882784 12417376 21078432
11:00:50 16416 8192000000 9789253728 10101035008 854997792 9.450 100 83 166 0 891957328 17829488 19130048
11:02:09 18282 8192000000 9854016976 10181017600 902249520 9.921 100 82 164 0 960757280 40428928 18078832
11:03:37 11826 8192000000 9913434640 10252177408 949164976 10.383 100 83 166 0 1023381536 56503824 17712736
11:04:52 5601 8192000000 9949190624 10300862464 986211776 10.745 100 81 163 2 1060920880 57634016 17075088
11:06:00 4215 8192000000 9998786496 10355986432 1017179984 11.045 100 89 174 2 1108393648 73453696 17759968
11:07:08 1624 8192000000 10028157776 10390720512 1034986800 11.216 100 91 181 2 1139269056 86055360 18226896
11:08:11 1445 8192000000 10038701920 10406858752 1061369088 11.470 100 90 180 1 1155331568 75822560 18139920
11:09:13 6477 8192000000 10011276864 10404085760 1064724416 11.502 100 55 110 1 1121385776 45492672 11168688
11:10:25 6311 8192000000 10001697616 10383626240 1076907152 11.618 100 40 80 1 1113566672 28134368 8525152
11:11:32 5965 8192000000 9991023056 10385186816 1085090240 11.696 100 31 63 2 1108433936 16296384 7047312
11:12:39 7561 8192000000 10013246016 10411429888 1088238688 11.726 100 31 62 2 1125429488 31077952 6112848
11:13:47 35784 8192000000 10016883184 10409267200 1095760688 11.797 100 55 111 1 1116483280 9007744 11714848
11:15:23 3664 8192000000 10038411280 10436837376 1098103248 11.820 100 41 81 2 1158364480 51051568 9209664
11:16:27 3684 8192000000 10022938272 10421972992 1099214416 11.830 100 47 96 1 1128090976 18400832 10475728
11:17:31 8508 8192000000 10006510496 10409721856 1084224448 11.688 99 31 62 1 1109845120 18047840 7572832
11:18:40 20994 8192000000 9994976592 10401538048 1027180736 11.141 94 14 27 0 1066015008 36227024 2607248
11:20:03 4881 8192000000 9948385456 10332622848 1000652608 10.885 96 59 116 5 1028778560 14931728 13194224
11:21:11 6012 8192000000 9923112272 10293555200 974735840 10.633 96 41 75 3 1004444240 22051072 7657328
11:22:19 58700 8192000000 9938635520 10337742848 998633200 10.865 97 42 82 2 1030949408 22962048 9354160
11:24:27 3667 8192000000 9964674128 10343690240 1040222048 11.267 100 59 117 5 1070063184 16733888 13107248
11:25:32 7698 8192000000 9985666464 10384011264 1058009680 11.437 100 31 62 1 1083362416 18756880 6595856
11:26:42 9707 8192000000 10007557328 10412015616 1079899232 11.647 100 36 72 0 1114469488 26059856 8510400
11:28:12 73511 8192000000 10045515520 10464985088 1093608400 11.777 100 45 91 1 1151556832 47267472 10680960
11:30:46 56721 8192000000 10089961664 10498867200 1119171488 12.019 100 38 75 3 1187022480 59571920 8279072
11:32:50 49915 8192000000 10133633360 10559528960 1131521120 12.136 100 38 76 0 1240973616 101295024 8157472
11:34:48 9959 8192000000 10163149728 10570768384 1149027488 12.300 100 51 102 2 1265392928 105525408 10840032
11:36:02 4910 8192000000 10194610704 10604855296 1162377008 12.426 100 51 102 0 1307912992 134061616 11474368
11:37:21 20777 8192000000 10242621008 10667671552 1168674304 12.484 100 66 132 2 1353766608 169850016 15242288
11:38:45 6955 8192000000 10268055600 10683408384 1184300992 12.630 100 58 116 0 1373481072 176384208 12795872
11:39:58 2752 8192000000 10334299568 10739761152 1186383296 12.650 100 77 150 0 1429394080 226152688 16858096
11:41:05 2764 8192000000 10357028352 10776666112 1191813968 12.700 100 73 145 1 1457692608 249151552 16727088
11:42:19 6507 8192000000 10373914192 10798944256 1183698736 12.625 99 64 126 1 1468128720 269355088 15074896
11:43:31 2448 8192000000 10442353984 10855796736 1194140304 12.722 100 79 158 0 1542924992 330450000 18334688
11:44:47 1649 8192000000 10460168864 10866409472 1182542096 12.614 100 86 168 0 1558743328 356518416 19682816
11:45:53 3212 8192000000 10483845984 10893512704 1184899280 12.636 100 82 165 2 1578215104 374161472 19154352
11:47:01 1226 8192000000 10485561152 10893086720 1180747424 12.597 100 87 170 2 1580260144 379412048 20100672
11:48:05 5470 8192000000 10450541056 10872741888 1167740656 12.476 99 54 108 2 1545446000 364299824 13405520
11:49:15 3499 8192000000 10497189568 10913406976 1171011840 12.506 100 70 139 2 1588856784 400941648 16903296
11:50:35 2831 8192000000 10557845712 10996486144 1171148832 12.508 100 76 149 2 1651082976 460721344 19212800
11:51:41 18603 8192000000 10515696448 10942541824 1141382624 12.229 99 42 84 0 1601465472 448379920 11702928
11:53:03 12272 8192000000 10544348704 11004915712 1151437664 12.323 100 46 90 0 1644252400 480310544 12504192
11:54:20 14272 8192000000 10434359488 10923442176 1127117728 12.094 100 29 58 0 1546359744 411106288 8135728
11:55:44 26653 8192000000 10328977296 10793373696 1093872032 11.779 98 23 46 0 1466537984 366326720 6339232
11:57:13 27498 8192000000 10064563056 10497019904 1123193616 12.057 100 22 42 2 1164621504 37478240 3949648
11:59:05 164040 8192000000 10046699040 10509770752 1079927280 11.647 96 25 51 0 1120190128 35365600 4897248
12:02:53 84321 8192000000 10047762128 10491269120 1104693360 11.882 98 35 70 1 1145360112 32986896 7679856
12:05:31 82984 8192000000 10086547040 10551885824 1130277376 12.124 100 22 44 0 1186333088 51438736 4616976
12:07:59 75462 8192000000 10062405248 10508660736 1097757600 11.816 97 14 28 1 1166569888 65774064 3038224
12:10:22 81661 8192000000 10057178560 10526593024 1118037296 12.008 99 9 18 0 1147772944 27479344 2256304
12:12:44 33054 8192000000 10128092080 10584518656 1143900288 12.252 100 25 50 1 1212793664 63631104 5262272
12:14:19 116981 8192000000 10111228080 10590183424 1136945088 12.187 99 14 28 0 1189240832 49582480 2713264
12:18:46 28609 8192000000 10079682256 10525102080 1145441392 12.267 100 50 102 0 1182252080 25095424 11715264
12:20:17 16326 8192000000 10094587584 10541916160 1131319312 12.134 99 22 44 0 1176870000 40673936 4876752
12:21:43 74146 8192000000 10125844448 10603806720 1151725280 12.326 100 14 28 0 1222550784 67785440 3040064
12:24:00 74768 8192000000 10146431600 10603937792 1140614768 12.221 98 14 28 1 1230596064 86711088 3270208
12:26:28 56726 8192000000 10160971616 10608893952 1155738544 12.363 100 13 26 1 1259996352 101459552 2798256
12:28:25 80044 8192000000 10093276208 10603036672 1152650496 12.334 100 12 23 1 1210681968 55820016 2211456
12:30:46 76021 8192000000 10111485904 10588790784 1148679280 12.297 99 17 34 0 1196232096 43608880 3943936
12:33:12 94170 8192000000 10155576480 10605920256 1090655456 11.749 94 18 36 2 1186492160 92117840 3718864
12:35:53 67823 8192000000 10052850048 10513616896 1081023856 11.657 95 22 44 0 1130435184 45089792 4321536
12:38:04 93867 8192000000 10104240544 10567016448 1130555872 12.127 99 10 20 0 1201912560 69183648 2173040
12:40:38 49918 8192000000 10058693856 10484310016 1092563104 11.767 96 41 78 2 1128422688 27693264 8166320
12:42:42 94221 8192000000 10047837024 10511777792 1113559072 11.966 99 24 48 0 1178305872 59621856 5124944
12:45:30 41776 8192000000 10026047568 10483433472 1097395952 11.813 98 6 12 0 1124481936 25918784 1167200
12:47:35 85392 8192000000 10095774672 10588377088 1106126064 11.896 98 9 18 0 1185560080 77195264 2238752
12:50:52 37619 8192000000 10051361536 10499584000 1100111712 11.839 99 20 40 1 1133258416 28610576 4536128
12:52:34 24372 8192000000 10045502592 10455130112 1061032864 11.466 97 45 88 2 1099073504 27856624 10184016
12:54:02 43577 8192000000 10095053152 10544791552 1092986896 11.771 99 23 46 0 1177561584 79466032 5108656
12:55:49 82264 8192000000 10067363488 10532282368 1047372704 11.335 94 26 52 1 1124536256 71996576 5166976
12:58:15 19509 8192000000 10077698800 10502459392 1110907424 11.941 100 41 82 0 1156240832 36524928 8808480
12:59:38 18259 8192000000 10095439920 10512596992 1109242672 11.925 99 37 73 0 1150945136 34631824 7070640
13:01:06 71157 8192000000 10063354912 10537500672 1120398144 12.031 100 25 50 0 1172730864 47347536 4985184
13:03:55 34080 8192000000 10037568144 10490634240 1114267312 11.973 99 7 14 0 1138382848 22457424 1658112
13:05:41 55306 8192000000 10047831552 10499543040 1114142352 11.972 99 4 8 0 1153098912 38173712 782848
13:07:38 78041 8192000000 10049922512 10512310272 1091479120 11.757 97 12 24 0 1133864096 39918208 2466768
13:10:06 50154 8192000000 10074395760 10519830528 1131424320 12.135 100 19 38 0 1230343008 94673216 4245472
13:12:02 51723 8192000000 10133207728 10572963840 1125050048 12.075 99 30 60 0 1213566912 81288432 7228432
13:13:54 68390 8192000000 10131567568 10633068544 1143735152 12.251 100 19 38 1 1241645728 93881344 4029232
13:16:03 46852 8192000000 10111356144 10566074368 1130145248 12.123 99 23 46 1 1203831472 68701824 4984400
13:17:58 69969 8192000000 10102614240 10538840064 1115470208 11.984 97 20 39 1 1165399504 45607680 4321616
13:20:15 87401 8192000000 10053810464 10515951616 1119766128 12.025 99 17 34 0 1159641024 35843168 4031728
13:23:09 67713 8192000000 10054750896 10517839872 1097132624 11.810 97 7 14 0 1125615888 27212656 1270608
13:25:17 72646 8192000000 10112038048 10600378368 1129335536 12.115 100 16 32 1 1214091440 81152480 3603424

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 10-03-2021 13:28:43 to 10-03-2021 13:28:58.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 91 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 10-03-2021 13:28:59 to 10-03-2021 13:29:01.

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          222099
MON$OLDEST_ACTIVE               224829
MON$OLDEST_SNAPSHOT             224829
MON$NEXT_TRANSACTION            224829
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                       2092096
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             777
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2384 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2384 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2384 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.2384 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 ---
:32: 
:33: mscs-test-161.budzdorov.ru    Wed Mar 10 13:27:11 2021
:34:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:35: 
:36: 
:37: mscs-test-161.budzdorov.ru    Wed Mar 10 13:27:11 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'

10.03.21 13:29:02. 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-03-10 13:29:05 No severe PSQL-related problems occured
10.03.21 13:29:06. End of report.