Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.02.2021 10:27
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID e0e7b46250ba4f9795ceb55e0face73f
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2020-09-25 13:42
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.00
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16421056 kB
MemAvailable 31129696 kB
Buffers 0 kB
Cached 15227880 kB
SwapCached 4092 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.0
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-02-16 10:27:27
DTS_END                         2021-02-16 10:26:57.5160
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/50076
TRN_ID                          242789
ATT_ID                          259
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: 9048.13 22259 1085776
customer order: creation 177.61 414 21313
customer order: refuse 14.77 305 1772
order to supplier: creation 47.83 1410 5740
order to supplier: removal 3.19 121807 383
invoice (draft): creation 47.20 3800 5664
invoice (draft): removal 49.91 966 5989
invoice accept: apply 43.16 16630 5179
invoice accept: cancel 45.33 881 5439
customer reserve: creation 3892.09 166 467051
customer reserve: removal 4428.92 70 531470
realization accept: apply 85.68 174 10282
realization accept: cancel 79.45 119 9534
payment from customer: creation 62.36 114 7483
payment from customer: removal 6.16 104 739
payment to supplier: creation 50.17 139 6020
payment to supplier: removal 3.47 89 416
service: total inventory turnovers 3.70 3796 444
service: total monetary turnovers 6.33 612 759
service: aggregate perf. data 0.79 18825 95
service: refresh index statistics 0.03 274752 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 16-02-2021 10:28:06 to 16-02-2021 10:28:09.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4080
TEST_TIME 2 5612
TEST_TIME 3 6277
TEST_TIME 4 5978
TEST_TIME 5 6138
TEST_TIME 6 5788
TEST_TIME 7 6141
TEST_TIME 8 6380
TEST_TIME 9 6440
TEST_TIME 10 6352
TEST_TIME 11 6404
TEST_TIME 12 6422
TEST_TIME 13 6622
TEST_TIME 14 6887
TEST_TIME 15 6996
TEST_TIME 16 7161
TEST_TIME 17 7505
TEST_TIME 18 7761
TEST_TIME 19 8410
TEST_TIME 20 8431
TEST_TIME 21 8773
TEST_TIME 22 8820
TEST_TIME 23 8958
TEST_TIME 24 8914
TEST_TIME 25 9126
TEST_TIME 26 9667
TEST_TIME 27 10189
TEST_TIME 28 10571
TEST_TIME 29 11508
TEST_TIME 30 11368
TEST_TIME 31 11123
TEST_TIME 32 10952
TEST_TIME 33 10919
TEST_TIME 34 10845
TEST_TIME 35 10819
TEST_TIME 36 10560
TEST_TIME 37 10474
TEST_TIME 38 10360
TEST_TIME 39 10288
TEST_TIME 40 10267
TEST_TIME 41 10284
TEST_TIME 42 10135
TEST_TIME 43 10098
TEST_TIME 44 9981
TEST_TIME 45 9904
TEST_TIME 46 9998
TEST_TIME 47 9920
TEST_TIME 48 9878
TEST_TIME 49 9835
TEST_TIME 50 9899
TEST_TIME 51 9861
TEST_TIME 52 10139
TEST_TIME 53 10072
TEST_TIME 54 10028
TEST_TIME 55 10010
TEST_TIME 56 10185
TEST_TIME 57 10140
TEST_TIME 58 10024
TEST_TIME 59 9923
TEST_TIME 60 9825
TEST_TIME 61 9713
TEST_TIME 62 9606
TEST_TIME 63 9615
TEST_TIME 64 9644
TEST_TIME 65 9601
TEST_TIME 66 9542
TEST_TIME 67 9583
TEST_TIME 68 9540
TEST_TIME 69 9462
TEST_TIME 70 9475
TEST_TIME 71 9521
TEST_TIME 72 9672
TEST_TIME 73 9613
TEST_TIME 74 9674
TEST_TIME 75 9694
TEST_TIME 76 9689
TEST_TIME 77 9722
TEST_TIME 78 9614
TEST_TIME 79 9570
TEST_TIME 80 9511
TEST_TIME 81 9445
TEST_TIME 82 9390
TEST_TIME 83 9381
TEST_TIME 84 9362
TEST_TIME 85 9340
TEST_TIME 86 9328
TEST_TIME 87 9293
TEST_TIME 88 9331
TEST_TIME 89 9292
TEST_TIME 90 9316
TEST_TIME 91 9280
TEST_TIME 92 9330
TEST_TIME 93 9312
TEST_TIME 94 9419
TEST_TIME 95 9349
TEST_TIME 96 9263
TEST_TIME 97 9262
TEST_TIME 98 9242
TEST_TIME 99 9240
TEST_TIME 100 9240
TEST_TIME 101 9244
TEST_TIME 102 9228
TEST_TIME 103 9190
TEST_TIME 104 9207
TEST_TIME 105 9246
TEST_TIME 106 9218
TEST_TIME 107 9266
TEST_TIME 108 9302
TEST_TIME 109 9317
TEST_TIME 110 9323
TEST_TIME 111 9310
TEST_TIME 112 9269
TEST_TIME 113 9205
TEST_TIME 114 9145
TEST_TIME 115 9105
TEST_TIME 116 9103
TEST_TIME 117 9097
TEST_TIME 118 9085
TEST_TIME 119 9044
TEST_TIME 120 9053
TEST_TIME 121 8978

Performance per minute, chart


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

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 1517118 1517118 0 0.00 0 15212 6 0 0
doc_list_biud 1517114 1517114 0 0.00 0 42616 31 0 0
sp_add_invoice_to_stock 5311 5179 132 2.48 26 148752 16631 0 132
sp_cancel_adding_invoice 5439 5439 0 0.00 6 97809 882 0 0
sp_cancel_client_order 1772 1772 0 0.00 4 12352 305 0 0
sp_cancel_customer_reserve 531470 531470 0 0.00 1 16691 71 0 0
sp_cancel_pay_from_customer 739 739 0 0.00 3 7993 104 0 0
sp_cancel_pay_to_supplier 416 416 0 0.00 3 7443 89 0 0
sp_cancel_supplier_invoice 6001 5989 12 0.19 2 42623 967 0 12
sp_cancel_supplier_order 383 383 0 0.00 7 1155684 121807 0 0
sp_cancel_write_off 9534 9534 0 0.00 1 9229 120 0 0
sp_client_order 21313 21313 0 0.00 4 29082 414 0 0
sp_customer_reserve 467051 467051 0 0.00 0 74302 166 0 0
sp_fill_shopping_cart 32714 32714 0 0.00 1 18500 47 0 0
sp_fill_shopping_cart_clo_res 467041 467041 0 0.00 0 23355 17 0 0
sp_get_clo_for_invoice 5168 5168 0 0.00 0 32426 396 0 0
sp_kill_cost_storno 16128 16128 0 0.00 0 9658 60 0 0
sp_kill_qstorno_ret_qs2qd 1078846 1078846 0 0.00 0 42615 38 0 0
sp_kill_qty_storno 570036 570036 0 0.00 0 42616 82 0 0
sp_lock_selected_doc 21380 21380 0 0.00 0 11730 6 0 0
sp_make_cost_storno 28953 28953 0 0.00 0 15153 53 0 0
sp_multiply_rows_for_pdistr 28952 28952 0 0.00 0 9510 31 0 0
sp_multiply_rows_for_qdistr 21313 21313 0 0.00 0 29075 232 0 0
sp_pay_from_customer 7483 7483 0 0.00 4 17061 114 0 0
sp_pay_to_supplier 6020 6020 0 0.00 5 24577 139 0 0
sp_payment_common 13502 13502 0 0.00 1 10065 25 0 0
sp_qd_handle_on_cancel_clo 1772 1772 0 0.00 0 7545 273 0 0
sp_qd_handle_on_invoice_upd_sts 10606 10606 0 0.00 0 20147 375 0 0
sp_qd_handle_on_reserve_upd_sts 10282 10282 0 0.00 0 8891 51 0 0
sp_reserve_write_off 10282 10282 0 0.00 7 20379 175 0 0
sp_supplier_invoice 5664 5664 0 0.00 7 128986 3800 0 0
sp_supplier_order 5740 5740 0 0.00 6 23535 1410 0 0
srv_aggregate_perf_data 95 95 0 0.00 2679 203656 18825 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1218 139834 40413 0 0
srv_find_qd_qs_mism 1036252 1036252 0 0.00 0 10311 5 0 0
srv_make_invnt_saldo 444 444 0 0.00 51 97765 3797 0 0
srv_make_money_saldo 759 759 0 0.00 1 9986 612 0 0
srv_recalc_idx_stat 4 4 0 0.00 91205 391179 274752 0 0
t$perf-abend:sp_add_invoice_to_stock 132 132 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5180 5180 0 0.00 0 8933 150 0 0
t$perf-norm:sp_cancel_adding_invoice 404 404 0 0.00 0 5848 96 0 0
t$perf-norm:sp_cancel_client_order 1772 1772 0 0.00 0 2816 16 0 0
t$perf-norm:sp_cancel_customer_reserve 740 740 0 0.00 0 3520 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 739 739 0 0.00 0 392 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 416 416 0 0.00 0 717 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 414 414 0 0.00 0 2318 23 0 0
t$perf-norm:sp_cancel_supplier_order 383 383 0 0.00 0 22463 1561 0 0
t$perf-norm:sp_cancel_write_off 1063 1063 0 0.00 0 2633 12 0 0
t$perf-norm:sp_client_order 21313 21313 0 0.00 0 6040 18 0 0
t$perf-norm:sp_customer_reserve 1062 1062 0 0.00 0 4691 14 0 0
t$perf-norm:sp_pay_from_customer 7483 7483 0 0.00 0 9571 12 0 0
t$perf-norm:sp_pay_to_supplier 6020 6020 0 0.00 0 7549 13 0 0
t$perf-norm:sp_reserve_write_off 10282 10282 0 0.00 0 5576 8 0 0
t$perf-norm:sp_supplier_invoice 5664 5664 0 0.00 0 6145 17 0 0
t$perf-norm:sp_supplier_order 5740 5740 0 0.00 0 4727 15 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 284 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 3769 63 0 0
t$perf-norm:srv_make_invnt_saldo 444 444 0 0.00 0 382 5 0 0
t$perf-norm:srv_make_money_saldo 759 759 0 0.00 0 263 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 13 6 0 0
v_add_invoice_to_stock 10598 10334 264 2.49 0 8181 20 0 264
v_all_customers 39180 39180 0 0.00 0 19 1 0 0
v_all_suppliers 31330 31330 0 0.00 0 22 1 0 0
v_all_wares 42624 42624 0 0.00 0 58 1 0 0
v_cancel_adding_invoice 808 808 0 0.00 0 2925 11 0 0
v_cancel_client_order 3544 3544 0 0.00 0 2746 4 0 0
v_cancel_customer_prepayment 1478 1478 0 0.00 0 4044 8 0 0
v_cancel_customer_reserve 1480 1480 0 0.00 0 4617 6 0 0
v_cancel_payment_to_supplier 832 832 0 0.00 0 7362 15 0 0
v_cancel_supplier_invoice 852 828 24 2.81 0 176 11 0 24
v_cancel_supplier_order 766 766 0 0.00 0 4616 19 0 0
v_cancel_write_off 2126 2126 0 0.00 0 3196 17 0 0
v_max_id_clo_ord 5740 5740 0 0.00 0 351 1 0 0
v_max_id_clo_res 1062 1062 0 0.00 0 2548 17 0 0
v_max_id_ord_sup 5661 5661 0 0.00 0 5598 3 0 0
v_max_non_paid_invoice 1755 1755 0 0.00 0 1002 3 0 0
v_max_non_paid_realizn 3871 3871 0 0.00 0 6195 5 0 0
v_min_id_clo_ord 5740 5740 0 0.00 0 2059 5 0 0
v_min_id_clo_res 1062 1062 0 0.00 0 4282 9 0 0
v_min_id_ord_sup 5661 5661 0 0.00 0 9426 19 0 0
v_min_non_paid_invoice 6019 6019 0 0.00 0 9366 19 0 0
v_min_non_paid_realizn 7483 7483 0 0.00 0 9155 15 0 0
v_our_firm 10668 10668 0 0.00 0 19 1 0 0
v_reserve_write_off 20564 20564 0 0.00 0 6343 6 0 0
x_lock_depdocs_on_canc_invoice 11014 11014 0 0.00 0 9792 105 0 0
x_lock_depdocs_on_canc_sup_ord 383 383 0 0.00 0 10546 361 0 0
x_make_qstorno_1000_1200 5740 5740 0 0.00 2 23520 1327 0 0
x_make_qstorno_1200_2000 5664 5664 0 0.00 3 127791 3620 0 0
x_make_qstorno_2100_3300 467051 467051 0 0.00 0 74300 147 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 16-02-2021 10:28:14 to 16-02-2021 10:28:19.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
07:57:01 12 8192000000 8799252704 8810582016 5260880 0.064 2 1 2 0 5360304 63648 35776
07:58:03 35828 8192000000 9731260720 10027270144 800575184 8.902 100 85 170 0 828595456 7449664 20570608
07:59:41 13947 8192000000 9764891424 10067791872 839764592 9.297 100 91 182 0 873875664 12155264 21955808
08:01:03 13223 8192000000 9790011264 10105585664 855682576 9.457 100 77 154 0 896069968 22124544 18262848
08:02:20 12399 8192000000 9866092496 10195111936 917219776 10.069 100 81 158 1 977368208 42544944 17603488
08:03:36 10798 8192000000 9919929712 10260414464 961400624 10.503 100 76 151 1 1025767120 48727392 15639104
08:04:49 4710 8192000000 9958956816 10313895936 996985552 10.849 100 81 162 0 1076944112 63588400 16370160
08:05:57 5398 8192000000 10008120176 10369208320 1023028048 11.101 100 82 165 1 1120945632 81624944 16292640
08:07:04 1920 8192000000 10034225200 10401452032 1052548288 11.385 100 94 186 0 1144277968 72858960 18870720
08:08:07 17420 8192000000 9997072432 10376097792 1067857520 11.532 100 67 135 0 1116532560 34140016 14535024
08:09:26 5243 8192000000 10008589504 10396737536 1078504800 11.633 100 55 110 2 1131417712 40820336 12092576
08:10:32 2921 8192000000 10008549952 10394718208 1084102144 11.687 100 51 101 3 1126521536 31456512 10962880
08:11:38 9619 8192000000 10023172816 10406031360 1089584560 11.739 100 76 155 0 1131358928 25246192 16528176
08:12:48 7313 8192000000 10021722480 10431164416 1092698864 11.768 100 35 71 1 1118186992 18202992 7285136
08:14:01 7008 8192000000 10019191408 10425192448 1096641952 11.806 100 29 56 1 1133374656 30866704 5866000
08:15:10 5602 8192000000 10011549360 10408693760 1100790400 11.845 100 44 90 1 1123327952 12436720 10100832
08:16:17 27652 8192000000 9998591744 10408931328 1087009920 11.714 99 22 42 0 1118958496 27542176 4406400
08:17:48 5571 8192000000 10001884992 10390462464 1063724624 11.492 98 43 85 1 1096351152 22467104 10159424
08:18:55 3763 8192000000 9926849328 10301440000 993303952 10.814 96 44 89 3 1013610608 10303152 10003504
08:20:05 63863 8192000000 9917386192 10294579200 929180224 10.187 91 46 93 2 957017200 17770272 10066704
08:22:09 6700 8192000000 9933679024 10326917120 1019174288 11.064 100 27 54 0 1045127968 19990560 5963120
08:23:20 7449 8192000000 9963141104 10361147392 1044817136 11.311 100 31 60 2 1080271856 28378944 7075776
08:24:30 4287 8192000000 9987217984 10375147520 1062196512 11.477 100 40 75 2 1083499456 13033632 8269312
08:25:35 26007 8192000000 9969525632 10373210112 1065731472 11.511 100 48 95 0 1103228688 25393456 12103760
08:27:04 17841 8192000000 10005824000 10397597696 1086055808 11.705 100 66 132 4 1115575696 14264240 15255648
08:28:32 1957 8192000000 10089862880 10484523008 1108795792 11.921 100 82 161 1 1190658848 65362336 16500720
08:29:49 2075 8192000000 10054728944 10460139520 1112407360 11.955 100 66 131 2 1169711152 43346400 13957392
08:30:54 13139 8192000000 10126256656 10528092160 1126337504 12.087 100 56 112 2 1232295520 94428304 11529712
08:32:13 29348 8192000000 10130120576 10545664000 1141475008 12.229 100 44 88 0 1234464560 83284064 9705488
08:33:46 6977 8192000000 10157691328 10570121216 1155118752 12.358 100 50 99 1 1274247792 107954048 11174992
08:34:57 21376 8192000000 10188478720 10612183040 1158389248 12.388 99 45 90 1 1297569408 128374976 10805184
08:36:21 2582 8192000000 10247538080 10653958144 1181988624 12.609 100 73 142 3 1349875936 152382688 15504624
08:37:27 3857 8192000000 10282542032 10697469952 1180390112 12.594 100 68 133 0 1394302880 199046608 14866160
08:38:41 1330 8192000000 10318412080 10727313408 1186870208 12.654 100 77 148 1 1415553888 211948272 16735408
08:39:47 6123 8192000000 10324460464 10734379008 1165254656 12.452 99 68 136 2 1423735216 243648976 14831584
08:40:58 7869 8192000000 10338559808 10750898176 1169074144 12.488 100 58 114 0 1439081904 256536400 13471360
08:42:10 1216 8192000000 10375653472 10775613440 1162768608 12.429 100 93 187 1 1475097760 290604800 21724352
08:43:16 1754 8192000000 10361086528 10781597696 1141318672 12.228 100 85 168 3 1461571600 300761680 19491248
08:44:23 5816 8192000000 10423427296 10837774336 1157171552 12.377 100 66 131 0 1525270768 352293568 15805648
08:45:34 5403 8192000000 10391329088 10816290816 1142548400 12.239 100 47 93 2 1498729120 344973552 11207168
08:46:43 1302 8192000000 10404220656 10805723136 1157761408 12.382 100 93 189 1 1498796336 319645104 21389824
08:47:47 9904 8192000000 10389758592 10830376960 1145853920 12.271 100 39 78 0 1492582448 336591232 10137296
08:49:12 10604 8192000000 10450346688 10877288448 1143685168 12.250 99 44 86 2 1548272784 393054576 11533040
08:50:33 5851 8192000000 10450507392 10873266176 1138958848 12.206 100 46 92 0 1548986416 398064528 11963040
08:51:49 7196 8192000000 10458963456 10881413120 1144749616 12.260 100 50 100 1 1561120288 403327040 13043632
08:52:59 12124 8192000000 10374528704 10855718912 1160578880 12.409 100 33 66 0 1478744608 309625488 8540240
08:54:20 70124 8192000000 10083036112 10675978240 1122534144 12.051 99 12 22 1 1372835360 247123536 3177680
08:56:36 54542 8192000000 10069938864 10501529600 1120832928 12.035 99 28 52 0 1144275264 18302560 5139776
08:58:31 8744 8192000000 10139261840 10557009920 1147044528 12.282 100 54 108 3 1226480864 67562224 11874112
08:59:52 84157 8192000000 10111664848 10644865024 1151567280 12.324 100 26 52 1 1251993056 95205488 5220288
09:03:14 48083 8192000000 10089775664 10534178816 1133691280 12.156 100 33 66 0 1177651040 37097152 6862608
09:05:11 44915 8192000000 10110344000 10571558912 1143235376 12.246 100 28 56 0 1207657184 58459168 5962640
09:07:04 62362 8192000000 10092951152 10543362048 1126192096 12.085 98 17 34 0 1179100224 49116016 3792112
09:09:08 47379 8192000000 10113412448 10578964480 1128114512 12.104 98 15 30 0 1205598448 74339376 3144560
09:10:55 20865 8192000000 10096184592 10574135296 1138186112 12.198 99 6 12 0 1199378608 59819136 1373360
09:12:18 60939 8192000000 10072950848 10586042368 1121853024 12.044 98 5 10 0 1163171328 40109136 1209168
09:14:19 29637 8192000000 10075461264 10524209152 1139030112 12.206 99 16 32 0 1160770016 18516784 3223120
09:15:50 45668 8192000000 10175805104 10618560512 1139764816 12.213 99 34 68 2 1221436672 74526304 7145552
09:17:38 119707 8192000000 10093407664 10585198592 1100144176 11.839 96 12 23 2 1154297488 51630016 2523296
09:21:03 61337 8192000000 10139299168 10604896256 1106284832 11.897 97 12 24 1 1191674352 83016416 2373104
09:23:12 72899 8192000000 10050629568 10482335744 1077044368 11.619 95 27 51 2 1110383472 26502960 6836144
09:25:39 56918 8192000000 10058421168 10526752768 1124146832 12.066 100 19 38 0 1159610128 31198032 4265264
09:27:43 44700 8192000000 10079753584 10527150080 1123954624 12.064 99 11 21 0 1166736576 40783584 1998368
09:29:28 81841 8192000000 10077636400 10600382464 1090745216 11.750 96 12 24 0 1166348560 72896640 2706704
09:31:50 50795 8192000000 10070252256 10506620928 1082402672 11.670 96 19 36 1 1113909808 27677872 3829264
09:33:52 34791 8192000000 10078271440 10512441344 1118677776 12.014 100 25 50 0 1181213056 57114480 5420800
09:35:30 22650 8192000000 10126229744 10544553984 1072100800 11.572 95 40 80 2 1168044784 87317696 8626288
09:36:56 35295 8192000000 10169031056 10614034432 1130699904 12.128 100 40 80 1 1249871968 110280800 8891264
09:38:34 76858 8192000000 10079169776 10506461184 1100527536 11.843 98 16 31 1 1139441664 35964624 2949504
09:40:51 46331 8192000000 10093125088 10574757888 1105530256 11.890 99 15 30 0 1184924640 76117488 3276896
09:42:43 38532 8192000000 10064427072 10537635840 1114619392 11.976 100 42 83 0 1204222064 79392848 10209824
09:44:26 85982 8192000000 10033442432 10491047936 1117139568 12.000 100 23 46 2 1149533808 28022960 4371280
09:46:55 45316 8192000000 10031437984 10486001664 1081811536 11.665 97 7 14 1 1101222016 17985248 1425232
09:48:41 52289 8192000000 10058257712 10540335104 1118211792 12.010 100 9 18 2 1162564048 42340336 2011920
09:50:33 77530 8192000000 10042910096 10501378048 1065775760 11.512 95 19 38 1 1102931664 32927600 4228304
09:52:55 24490 8192000000 10075007440 10518405120 1129363072 12.115 100 27 53 2 1164039376 28318880 6357424
09:54:24 37223 8192000000 10061513312 10520555520 1117501296 12.003 99 26 53 1 1166552592 43584576 5466720
09:56:03 49552 8192000000 10136606032 10594951168 1133724896 12.156 100 27 52 3 1217530960 78405280 5400784
09:57:53 92370 8192000000 10113279600 10594021376 1071468992 11.566 95 11 22 1 1138957312 65703056 1785264
10:00:36 139833 8192000000 10122183120 10597318656 1098054464 11.819 97 10 20 0 1173184736 73196240 1934032
10:03:57 42821 8192000000 10065810672 10592784384 1117633552 12.005 99 4 8 0 1170083712 51567952 882208
10:05:40 52954 8192000000 10076870048 10584555520 1107199872 11.906 98 12 22 1 1162702640 53329696 2173072
10:07:59 41193 8192000000 10118903184 10570227712 1136117168 12.179 100 38 74 0 1173624784 29015824 8491792
10:10:02 33259 8192000000 10111361776 10545897472 1128749200 12.110 100 29 57 0 1210651488 75581024 6321264
10:11:41 49032 8192000000 10116856432 10557853696 1101989968 11.857 96 27 53 1 1138804848 30733920 6080960
10:13:37 77619 8192000000 10087170288 10564632576 1069633312 11.549 95 9 18 0 1097455520 26235120 1587088
10:15:59 82299 8192000000 10094385632 10581155840 1092983040 11.771 97 15 30 0 1146450576 50512080 2955456
10:18:33 88574 8192000000 10058196784 10529357824 1109562928 11.928 99 17 34 0 1157465040 44129808 3772304
10:21:25 55811 8192000000 10088922592 10566324224 1129745376 12.119 100 8 16 0 1181874288 50266912 1862000
10:23:43 36244 8192000000 10066297520 10509598720 1100754864 11.845 97 15 30 1 1131445168 27210544 3479760
10:25:20 47466 8192000000 10096017392 10556440576 1098675216 11.825 97 30 60 1 1165319344 59302112 7342016

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 16-02-2021 10:28:20 to 16-02-2021 10:28:36.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 132 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-02-2021 10:28:37 to 16-02-2021 10:28:39.

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          267302
MON$OLDEST_ACTIVE               267303
MON$OLDEST_SNAPSHOT             267303
MON$NEXT_TRANSACTION            267303
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                       2107520
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             817
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2366 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2366 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2366 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.2366 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
on disk structure version 13.0

Database statistics

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

Database validation

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

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

--- start of diff output ---
--- end of diff output ---

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

16.02.21 10:28:40. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-02-16 10:28:42 No severe PSQL-related problems occured
16.02.21 10:28:43. End of report.