Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.06.2021 07:38
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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 16662696 kB
MemAvailable 31385972 kB
Buffers 0 kB
Cached 14965204 kB
SwapCached 72 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.1
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-06-15 07:37:25
DTS_END                         2021-06-15 07:37:25.0880
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/60140
TRN_ID                          232972
ATT_ID                          665
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: 8798.83 25078 1055860
customer order: creation 165.27 434 19832
customer order: refuse 13.92 336 1670
order to supplier: creation 44.35 1457 5322
order to supplier: removal 3.23 116993 388
invoice (draft): creation 44.28 3858 5313
invoice (draft): removal 49.36 846 5923
invoice accept: apply 40.06 18443 4807
invoice accept: cancel 44.98 920 5398
customer reserve: creation 3657.77 182 438932
customer reserve: removal 4451.63 68 534196
realization accept: apply 80.61 171 9673
realization accept: cancel 79.86 129 9583
payment from customer: creation 57.33 103 6880
payment from customer: removal 5.28 59 634
payment to supplier: creation 46.48 113 5577
payment to supplier: removal 3.44 77 413
service: total inventory turnovers 3.93 3297 472
service: total monetary turnovers 6.23 512 747
service: aggregate perf. data 0.79 20922 95
service: refresh index statistics 0.04 332645 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-06-2021 07:38:43 to 15-06-2021 07:38:45.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7763
TEST_TIME 2 5412
TEST_TIME 3 5629
TEST_TIME 4 4837
TEST_TIME 5 5152
TEST_TIME 6 5063
TEST_TIME 7 5457
TEST_TIME 8 6033
TEST_TIME 9 5911
TEST_TIME 10 6267
TEST_TIME 11 6443
TEST_TIME 12 6673
TEST_TIME 13 6809
TEST_TIME 14 7143
TEST_TIME 15 7620
TEST_TIME 16 7700
TEST_TIME 17 7943
TEST_TIME 18 8833
TEST_TIME 19 8959
TEST_TIME 20 8845
TEST_TIME 21 8731
TEST_TIME 22 9004
TEST_TIME 23 9138
TEST_TIME 24 9437
TEST_TIME 25 9426
TEST_TIME 26 9812
TEST_TIME 27 10646
TEST_TIME 28 11412
TEST_TIME 29 11256
TEST_TIME 30 11151
TEST_TIME 31 10805
TEST_TIME 32 10615
TEST_TIME 33 10423
TEST_TIME 34 10420
TEST_TIME 35 10280
TEST_TIME 36 10215
TEST_TIME 37 10159
TEST_TIME 38 10084
TEST_TIME 39 9978
TEST_TIME 40 9914
TEST_TIME 41 9823
TEST_TIME 42 9819
TEST_TIME 43 9841
TEST_TIME 44 9751
TEST_TIME 45 9704
TEST_TIME 46 9967
TEST_TIME 47 9902
TEST_TIME 48 9794
TEST_TIME 49 9693
TEST_TIME 50 9645
TEST_TIME 51 9876
TEST_TIME 52 9797
TEST_TIME 53 9818
TEST_TIME 54 9950
TEST_TIME 55 9838
TEST_TIME 56 9668
TEST_TIME 57 9628
TEST_TIME 58 9515
TEST_TIME 59 9446
TEST_TIME 60 9358
TEST_TIME 61 9362
TEST_TIME 62 9338
TEST_TIME 63 9298
TEST_TIME 64 9294
TEST_TIME 65 9272
TEST_TIME 66 9299
TEST_TIME 67 9394
TEST_TIME 68 9363
TEST_TIME 69 9336
TEST_TIME 70 9356
TEST_TIME 71 9297
TEST_TIME 72 9362
TEST_TIME 73 9336
TEST_TIME 74 9427
TEST_TIME 75 9489
TEST_TIME 76 9372
TEST_TIME 77 9335
TEST_TIME 78 9248
TEST_TIME 79 9227
TEST_TIME 80 9181
TEST_TIME 81 9127
TEST_TIME 82 9089
TEST_TIME 83 9115
TEST_TIME 84 9069
TEST_TIME 85 9087
TEST_TIME 86 9105
TEST_TIME 87 9168
TEST_TIME 88 9119
TEST_TIME 89 9089
TEST_TIME 90 9181
TEST_TIME 91 9151
TEST_TIME 92 9235
TEST_TIME 93 9266
TEST_TIME 94 9175
TEST_TIME 95 9124
TEST_TIME 96 9058
TEST_TIME 97 8990
TEST_TIME 98 8988
TEST_TIME 99 8973
TEST_TIME 100 8983
TEST_TIME 101 8955
TEST_TIME 102 8934
TEST_TIME 103 8953
TEST_TIME 104 9060
TEST_TIME 105 9082
TEST_TIME 106 9072
TEST_TIME 107 9135
TEST_TIME 108 9089
TEST_TIME 109 9119
TEST_TIME 110 9098
TEST_TIME 111 9099
TEST_TIME 112 9031
TEST_TIME 113 9023
TEST_TIME 114 8964
TEST_TIME 115 8944
TEST_TIME 116 8892
TEST_TIME 117 8898
TEST_TIME 118 8868
TEST_TIME 119 8859
TEST_TIME 120 8805

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 15-06-2021 07:38:46 to 15-06-2021 07:38:50.

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 1463067 1463067 0 0.00 0 22165 7 0 0
doc_list_biud 1463063 1463063 0 0.00 0 52253 31 0 0
sp_add_invoice_to_stock 4951 4807 144 2.90 46 192089 18444 0 144
sp_cancel_adding_invoice 5398 5398 0 0.00 5 58600 920 0 0
sp_cancel_client_order 1670 1670 0 0.00 5 13329 337 0 0
sp_cancel_customer_reserve 534196 534196 0 0.00 1 52255 69 0 0
sp_cancel_pay_from_customer 634 634 0 0.00 3 1002 59 0 0
sp_cancel_pay_to_supplier 413 413 0 0.00 3 5241 77 0 0
sp_cancel_supplier_invoice 5942 5923 19 0.31 2 21676 847 0 19
sp_cancel_supplier_order 388 388 0 0.00 10 1008875 116994 0 0
sp_cancel_write_off 9583 9583 0 0.00 1 21997 130 0 0
sp_client_order 19832 19832 0 0.00 4 35647 435 0 0
sp_customer_reserve 438932 438932 0 0.00 0 89798 182 0 0
sp_fill_shopping_cart 30453 30453 0 0.00 1 26614 50 0 0
sp_fill_shopping_cart_clo_res 438908 438908 0 0.00 0 26712 19 0 0
sp_get_clo_for_invoice 4780 4780 0 0.00 0 30397 499 0 0
sp_kill_cost_storno 16028 16028 0 0.00 0 8293 58 0 0
sp_kill_qstorno_ret_qs2qd 1084286 1084286 0 0.00 0 52245 37 0 0
sp_kill_qty_storno 571610 571610 0 0.00 0 52252 79 0 0
sp_lock_selected_doc 19942 19942 0 0.00 0 7423 4 0 0
sp_make_cost_storno 26910 26910 0 0.00 0 10393 49 0 0
sp_multiply_rows_for_pdistr 26909 26909 0 0.00 0 9746 29 0 0
sp_multiply_rows_for_qdistr 19832 19832 0 0.00 0 35638 254 0 0
sp_pay_from_customer 6880 6880 0 0.00 4 10795 103 0 0
sp_pay_to_supplier 5577 5577 0 0.00 5 14671 113 0 0
sp_payment_common 12457 12457 0 0.00 1 10181 22 0 0
sp_qd_handle_on_cancel_clo 1670 1670 0 0.00 0 13323 304 0 0
sp_qd_handle_on_invoice_upd_sts 10177 10177 0 0.00 0 18999 384 0 0
sp_qd_handle_on_reserve_upd_sts 9673 9673 0 0.00 0 13415 51 0 0
sp_reserve_write_off 9673 9673 0 0.00 7 16746 172 0 0
sp_supplier_invoice 5313 5313 0 0.00 7 102404 3858 0 0
sp_supplier_order 5322 5322 0 0.00 6 53896 1458 0 0
srv_aggregate_perf_data 95 95 0 0.00 3031 350748 20922 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1230 164745 47325 0 0
srv_find_qd_qs_mism 1010598 1010598 0 0.00 0 22161 6 0 0
srv_make_invnt_saldo 472 472 0 0.00 60 121523 3297 0 0
srv_make_money_saldo 747 747 0 0.00 1 14122 513 0 0
srv_recalc_idx_stat 5 5 0 0.00 125389 587381 332645 0 0
t$perf-abend:sp_add_invoice_to_stock 144 144 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 19 19 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4807 4807 0 0.00 0 19604 172 0 0
t$perf-norm:sp_cancel_adding_invoice 399 399 0 0.00 0 1797 93 0 0
t$perf-norm:sp_cancel_client_order 1670 1670 0 0.00 0 4312 15 0 0
t$perf-norm:sp_cancel_customer_reserve 666 666 0 0.00 0 10023 28 0 0
t$perf-norm:sp_cancel_pay_from_customer 634 634 0 0.00 0 1134 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 413 413 0 0.00 0 344 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 378 378 0 0.00 0 3821 26 0 0
t$perf-norm:sp_cancel_supplier_order 388 388 0 0.00 0 17107 1443 0 0
t$perf-norm:sp_cancel_write_off 943 943 0 0.00 0 541 3 0 0
t$perf-norm:sp_client_order 19832 19832 0 0.00 0 8589 17 0 0
t$perf-norm:sp_customer_reserve 952 952 0 0.00 0 8810 15 0 0
t$perf-norm:sp_pay_from_customer 6880 6880 0 0.00 0 2928 7 0 0
t$perf-norm:sp_pay_to_supplier 5577 5577 0 0.00 0 6976 10 0 0
t$perf-norm:sp_reserve_write_off 9673 9673 0 0.00 0 5717 9 0 0
t$perf-norm:sp_supplier_invoice 5313 5313 0 0.00 0 5274 19 0 0
t$perf-norm:sp_supplier_order 5322 5322 0 0.00 0 5876 15 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 130 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 558 10 0 0
t$perf-norm:srv_make_invnt_saldo 472 472 0 0.00 0 284 6 0 0
t$perf-norm:srv_make_money_saldo 747 747 0 0.00 0 452 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 16 6 0 0
v_add_invoice_to_stock 9844 9556 288 2.92 0 9210 20 0 288
v_all_customers 36208 36208 0 0.00 0 96 1 0 0
v_all_suppliers 28990 28990 0 0.00 0 82 1 0 0
v_all_wares 39662 39662 0 0.00 0 56 1 0 0
v_cancel_adding_invoice 798 798 0 0.00 0 2133 13 0 0
v_cancel_client_order 3340 3340 0 0.00 0 4936 6 0 0
v_cancel_customer_prepayment 1268 1268 0 0.00 0 614 4 0 0
v_cancel_customer_reserve 1332 1332 0 0.00 0 5960 9 0 0
v_cancel_payment_to_supplier 826 826 0 0.00 0 632 5 0 0
v_cancel_supplier_invoice 794 756 38 4.78 0 862 14 0 38
v_cancel_supplier_order 776 776 0 0.00 0 1711 7 0 0
v_cancel_write_off 1886 1886 0 0.00 0 5036 17 0 0
v_max_id_clo_ord 5322 5322 0 0.00 0 4925 2 0 0
v_max_id_clo_res 952 952 0 0.00 0 4960 25 0 0
v_max_id_ord_sup 5300 5300 0 0.00 0 6518 5 0 0
v_max_non_paid_invoice 1704 1704 0 0.00 0 2305 3 0 0
v_max_non_paid_realizn 3646 3646 0 0.00 0 594 3 0 0
v_min_id_clo_ord 5322 5322 0 0.00 0 1555 4 0 0
v_min_id_clo_res 952 952 0 0.00 0 5141 17 0 0
v_min_id_ord_sup 5300 5300 0 0.00 0 3806 5 0 0
v_min_non_paid_invoice 5577 5577 0 0.00 0 9362 14 0 0
v_min_non_paid_realizn 6880 6880 0 0.00 0 10173 15 0 0
v_our_firm 9922 9922 0 0.00 0 30 1 0 0
v_reserve_write_off 19346 19346 0 0.00 0 8742 8 0 0
x_lock_depdocs_on_canc_invoice 10943 10943 0 0.00 0 28457 108 0 0
x_lock_depdocs_on_canc_sup_ord 388 388 0 0.00 0 20904 361 0 0
x_make_qstorno_1000_1200 5322 5322 0 0.00 2 53888 1376 0 0
x_make_qstorno_1200_2000 5313 5313 0 0.00 4 102377 3659 0 0
x_make_qstorno_2100_3300 438932 438932 0 0.00 0 89797 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-06-2021 07:38:51 to 15-06-2021 07:38:55.

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
05:07:28 6 8192000000 8798581616 8809734144 4478816 0.054 2 1 2 0 4578208 63616 35776
05:08:29 32194 8192000000 9728732400 10027941888 803783984 8.935 100 83 166 0 832375808 8516496 20075328
05:10:12 24432 8192000000 9768446272 10077573120 840620336 9.306 100 85 170 1 874261488 13685120 19956032
05:11:41 15069 8192000000 9810173136 10132119552 874836288 9.648 100 80 160 3 921507664 28548464 18122912
05:13:05 10768 8192000000 9893955184 10228854784 924862336 10.144 100 81 162 0 995975808 53253408 17860064
05:14:20 8119 8192000000 9943951600 10291355648 974882704 10.634 100 83 158 0 1048945456 57969248 16093504
05:15:30 4078 8192000000 9991925296 10348105728 1007147200 10.948 100 87 174 1 1102545376 78134240 17263936
05:16:37 2519 8192000000 10015577008 10377428992 1036857184 11.234 100 97 188 0 1131724432 75650960 19216288
05:17:42 1396 8192000000 10046930768 10417664000 1059189136 11.449 100 88 176 1 1165612304 88386288 18036880
05:18:46 31661 8192000000 10020653760 10406338560 1074711200 11.597 100 57 112 2 1140828928 53521728 12596000
05:20:18 5938 8192000000 10003976400 10399330304 1087276336 11.717 100 34 68 0 1113676864 19057888 7342640
05:21:25 8712 8192000000 9999824880 10404818944 1091976960 11.761 100 30 60 0 1129203472 30527056 6699456
05:22:35 6185 8192000000 10008790208 10415894528 1100083856 11.838 100 29 58 0 1127448320 20646416 6718048
05:23:47 57142 8192000000 10006150000 10416717824 1101428256 11.851 100 33 66 0 1130231232 21588880 7214096
05:25:45 9520 8192000000 10023797280 10424172544 1100582912 11.843 100 37 71 0 1134381584 26338240 7460432
05:26:55 10714 8192000000 10017647776 10422882304 1098789696 11.826 100 35 70 2 1136256400 29610016 7856688
05:28:11 4494 8192000000 9995246944 10391511040 1074113040 11.591 99 40 80 2 1098493776 15663312 8717424
05:29:17 38851 8192000000 9937588960 10338004992 935880544 10.252 87 18 36 0 959077680 19571952 3625184
05:30:57 6083 8192000000 9928301952 10306785280 998275968 10.862 98 37 75 2 1023970224 17865168 7829088
05:32:05 6862 8192000000 9930402096 10314805248 1011400928 10.989 99 40 78 3 1043788208 23223072 9164208
05:33:12 9302 8192000000 9953504432 10350690304 1030117024 11.170 99 40 80 2 1067638768 29220528 8301216
05:34:26 42432 8192000000 9951587328 10361286656 1044486768 11.308 100 28 55 2 1062527520 12626128 5414624
05:36:09 6969 8192000000 9971750000 10381819904 1065227728 11.506 100 55 109 2 1104369424 26858128 12283568
05:37:18 6702 8192000000 10000303264 10396086272 1080519504 11.652 100 41 80 2 1104652256 15706064 8426688
05:38:33 19747 8192000000 10033331744 10430791680 1100126752 11.839 100 49 98 0 1141871456 31432112 10312592
05:39:57 69990 8192000000 10054338928 10465034240 1107093344 11.905 100 45 90 0 1177381280 59736240 10551696
05:42:11 15848 8192000000 10094122352 10500993024 1125345712 12.077 100 50 100 0 1195753632 60432704 9975216
05:43:30 4196 8192000000 10108173920 10516115456 1132801872 12.148 100 60 119 0 1218968128 72511760 13654496
05:44:39 9407 8192000000 10173687840 10577068032 1145687216 12.269 100 67 134 0 1278778560 118521520 14569824
05:45:56 8949 8192000000 10194407552 10604728320 1159015504 12.394 100 64 124 1 1292632704 120247264 13369936
05:47:10 7406 8192000000 10214615120 10643947520 1165370384 12.454 100 59 116 1 1325062256 146177744 13514128
05:48:24 1228 8192000000 10242337040 10656419840 1168381872 12.482 100 73 145 0 1349244384 163745488 17117024
05:49:29 7347 8192000000 10275926800 10696458240 1177208336 12.564 100 66 129 0 1383734368 192283568 14242464
05:50:42 1939 8192000000 10303095936 10705305600 1157719952 12.382 98 86 170 2 1388472128 211789088 18963088
05:51:47 4273 8192000000 10325603552 10735718400 1151156000 12.320 99 62 122 1 1423182528 257855968 14170560
05:52:56 6633 8192000000 10319622848 10728710144 1158655696 12.391 100 78 156 0 1428559152 251455152 18448304
05:54:09 4896 8192000000 10322604880 10731495424 1150211872 12.311 100 83 165 1 1421679216 251457008 20010336
05:55:20 7237 8192000000 10281171280 10698911744 1133602320 12.155 100 40 80 2 1384744528 241148720 9993488
05:56:34 3792 8192000000 10287431760 10692796416 1122859024 12.054 99 61 122 0 1372210432 235254800 14096608
05:57:46 8018 8192000000 10300649760 10709843968 1126037536 12.084 100 50 99 1 1388156464 249946784 12172144
05:58:58 3681 8192000000 10406339312 10839576576 1154750688 12.354 100 74 148 0 1509630608 336893104 17986816
06:00:09 2689 8192000000 10401932528 10816622592 1156419744 12.370 100 85 167 2 1490360112 313598160 20342208
06:01:14 19488 8192000000 10355615952 10808496128 1141253552 12.227 99 41 82 0 1443304976 291458848 10592576
06:02:40 12962 8192000000 10401902656 10816724992 1147323536 12.284 100 47 94 0 1495457536 335950128 12183872
06:03:54 17759 8192000000 10324482304 10810511360 1125679856 12.081 100 22 44 0 1452383504 320275184 6428464
06:05:37 64426 8192000000 10081203472 10516074496 1092360544 11.765 97 8 16 1 1129075440 34825856 1889040
06:07:55 84299 8192000000 10060645744 10485182464 1090294096 11.745 97 49 97 1 1143755920 42801072 10660752
06:10:21 22311 8192000000 10097359264 10529468416 1122650208 12.052 99 57 114 0 1183954752 48837504 12467040
06:11:52 106417 8192000000 10074697264 10556387328 1113067536 11.961 98 10 20 1 1181369168 66308912 1992720
06:15:39 55490 8192000000 10076588912 10531905536 1143313328 12.247 100 18 36 1 1177776192 31025008 3437856
06:17:39 66313 8192000000 10096969840 10548776960 1137930000 12.196 99 23 45 2 1207670000 64742624 4997376
06:19:53 45892 8192000000 10073070768 10510733312 1136957408 12.187 99 24 44 0 1167089200 25637056 4494736
06:21:45 48211 8192000000 10165229568 10613919744 1162511424 12.427 100 30 60 2 1244120144 75096816 6511904
06:23:35 89011 8192000000 10065473456 10511740928 1117587056 12.004 97 8 16 1 1160031344 40871936 1572352
06:26:06 56096 8192000000 10182855328 10631487488 1167874416 12.477 100 36 72 0 1255352096 79984464 7493216
06:29:26 41394 8192000000 10120520640 10572992512 1119975408 12.027 96 26 52 0 1164552528 38946640 5630480
06:31:08 113940 8192000000 10100237584 10599047168 1119407552 12.021 97 4 8 0 1163905088 44002368 495168
06:34:50 54689 8192000000 10065493504 10529599488 1113735408 11.968 97 14 27 1 1152961328 36407760 2818160
06:38:22 64228 8192000000 10056651504 10510700544 1122626416 12.052 98 10 20 1 1149050336 24506880 1917040
06:40:27 45255 8192000000 10068447440 10500956160 1102263936 11.859 97 38 74 1 1134827920 24863440 7700544
06:42:16 58434 8192000000 10126437968 10577465344 1111078256 11.943 97 31 62 0 1160810592 43081952 6650384
06:44:15 69573 8192000000 10044237232 10491711488 1029095280 11.160 91 14 28 0 1068497376 36902448 2499648
06:46:28 35028 8192000000 10066606432 10491318272 1099242528 11.830 99 40 80 1 1147605776 40396848 7966400
06:48:07 35169 8192000000 10097852240 10533933056 1122635312 12.052 100 35 70 0 1189979424 60205200 7138912
06:49:47 75787 8192000000 10117760880 10547097600 1036442192 11.230 93 19 38 0 1113537392 73042272 4052928
06:52:05 119790 8192000000 10041924768 10475991040 1088571456 11.729 99 17 34 1 1177270784 85111648 3587680
06:55:28 74240 8192000000 10048887648 10553466880 1106426992 11.899 100 6 12 0 1181675936 73954240 1294704
06:57:43 59858 8192000000 10041457568 10511732736 1088802528 11.731 99 10 20 0 1128842624 38342384 1697712
06:59:43 68560 8192000000 10065152000 10551185408 1104236368 11.878 99 25 50 0 1181139072 71396128 5506576
07:02:16 73586 8192000000 10142487584 10605592576 1117692032 12.005 99 21 42 0 1211125680 88363232 5070416
07:04:30 53672 8192000000 10065561264 10491199488 1097845040 11.817 98 23 46 1 1128202864 25609520 4748304
07:06:25 110825 8192000000 10058132448 10534699008 1090468464 11.747 97 12 24 0 1136360848 43416256 2476128
07:09:21 118166 8192000000 10075336096 10589294592 1135699248 12.175 100 8 16 1 1220205632 83198064 1308320
07:12:19 86783 8192000000 10070192624 10540425216 1115586928 11.985 98 17 34 0 1162910992 44197936 3126128
07:14:46 28413 8192000000 10068418672 10577326080 1134433088 12.163 100 6 12 0 1171074544 35649216 992240
07:16:15 59734 8192000000 10080242048 10587332608 1128372240 12.106 99 23 43 2 1163428304 30307600 4748464
07:18:43 57137 8192000000 10151061728 10598735872 1134377808 12.163 98 22 44 1 1209983872 70826688 4779376
07:20:43 164745 8192000000 10092040128 10627248128 1132730272 12.147 99 7 14 0 1214858160 80578304 1549584
07:24:28 54868 8192000000 10080010528 10566557696 1106213520 11.897 97 17 32 1 1163995520 54514192 3267808
07:26:24 38247 8192000000 10085927248 10541928448 1132246080 12.143 100 26 51 1 1181678352 44388672 5043600
07:28:02 36189 8192000000 10084931760 10522726400 1119506272 12.022 98 41 81 2 1160591216 33061200 8023744
07:29:41 85365 8192000000 10086938560 10564276224 1137285776 12.190 100 18 37 2 1212521984 71759888 3476320
07:32:18 59284 8192000000 10074436128 10572718080 1131121808 12.132 100 4 8 0 1169418704 37491104 805792
07:34:18 44663 8192000000 10068111648 10547777536 1134897456 12.168 100 24 48 1 1171074896 30616992 5560448
07:36:03 63842 8192000000 10081581072 10529587200 1080159088 11.649 95 17 34 1 1135773488 51913168 3701232

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 15-06-2021 07:38:57 to 15-06-2021 07:39:11.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 144 v_add_invoice_to_stock 335544517
except 19 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-06-2021 07:39:12 to 15-06-2021 07:39:14.

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          229828
MON$OLDEST_ACTIVE               233116
MON$OLDEST_SNAPSHOT             233116
MON$NEXT_TRANSACTION            233116
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                       2107456
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             791
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2517 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2517 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2517 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2517 Firebird 4.0/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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Tue Jun 15 07:37:27 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Tue Jun 15 07:37:28 2021
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Tue Jun 15 07:37:31 2021
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
--- end of diff output ---

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

15.06.21 07:39:15. 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-06-15 07:39:18 No severe PSQL-related problems occured
15.06.21 07:39:19. End of report.