Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.06.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 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 16820080 kB
MemAvailable 31468068 kB
Buffers 0 kB
Cached 14869168 kB
SwapCached 24 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-09 13:27:00
DTS_END                         2021-06-09 13:27:00.2150
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/54860
TRN_ID                          231940
ATT_ID                          647
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: 8776.60 28305 1053192
customer order: creation 163.14 397 19577
customer order: refuse 14.12 291 1694
order to supplier: creation 44.34 1423 5321
order to supplier: removal 3.12 110040 374
invoice (draft): creation 45.39 3865 5447
invoice (draft): removal 48.52 967 5822
invoice accept: apply 41.23 16736 4947
invoice accept: cancel 44.51 909 5341
customer reserve: creation 3745.93 166 449511
customer reserve: removal 4346.28 62 521554
realization accept: apply 80.88 171 9706
realization accept: cancel 75.44 106 9053
payment from customer: creation 57.75 113 6930
payment from customer: removal 5.67 73 680
payment to supplier: creation 46.25 132 5550
payment to supplier: removal 3.30 60 396
service: total inventory turnovers 3.94 3151 473
service: total monetary turnovers 5.89 549 707
service: aggregate perf. data 0.88 15439 105
service: refresh index statistics 0.03 411441 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-06-2021 13:29:16 to 09-06-2021 13:29:18.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6833
TEST_TIME 2 5903
TEST_TIME 3 4616
TEST_TIME 4 4249
TEST_TIME 5 5117
TEST_TIME 6 5428
TEST_TIME 7 5564
TEST_TIME 8 5608
TEST_TIME 9 5834
TEST_TIME 10 5981
TEST_TIME 11 6147
TEST_TIME 12 6209
TEST_TIME 13 6435
TEST_TIME 14 6873
TEST_TIME 15 7033
TEST_TIME 16 7310
TEST_TIME 17 8701
TEST_TIME 18 8528
TEST_TIME 19 8715
TEST_TIME 20 8689
TEST_TIME 21 8691
TEST_TIME 22 8917
TEST_TIME 23 8916
TEST_TIME 24 9124
TEST_TIME 25 9295
TEST_TIME 26 9801
TEST_TIME 27 10644
TEST_TIME 28 11008
TEST_TIME 29 11061
TEST_TIME 30 10922
TEST_TIME 31 10640
TEST_TIME 32 10320
TEST_TIME 33 10179
TEST_TIME 34 9899
TEST_TIME 35 9994
TEST_TIME 36 9953
TEST_TIME 37 9843
TEST_TIME 38 9745
TEST_TIME 39 9743
TEST_TIME 40 9638
TEST_TIME 41 9701
TEST_TIME 42 9592
TEST_TIME 43 9493
TEST_TIME 44 9509
TEST_TIME 45 9447
TEST_TIME 46 9405
TEST_TIME 47 9451
TEST_TIME 48 9412
TEST_TIME 49 9473
TEST_TIME 50 9447
TEST_TIME 51 9521
TEST_TIME 52 9446
TEST_TIME 53 9367
TEST_TIME 54 9491
TEST_TIME 55 9459
TEST_TIME 56 9400
TEST_TIME 57 9486
TEST_TIME 58 9373
TEST_TIME 59 9254
TEST_TIME 60 9179
TEST_TIME 61 9167
TEST_TIME 62 9060
TEST_TIME 63 9059
TEST_TIME 64 9020
TEST_TIME 65 9030
TEST_TIME 66 8977
TEST_TIME 67 9075
TEST_TIME 68 9051
TEST_TIME 69 9076
TEST_TIME 70 9067
TEST_TIME 71 9045
TEST_TIME 72 9106
TEST_TIME 73 9087
TEST_TIME 74 9041
TEST_TIME 75 9195
TEST_TIME 76 9257
TEST_TIME 77 9231
TEST_TIME 78 9170
TEST_TIME 79 9104
TEST_TIME 80 9044
TEST_TIME 81 9046
TEST_TIME 82 9031
TEST_TIME 83 9006
TEST_TIME 84 9026
TEST_TIME 85 8980
TEST_TIME 86 8960
TEST_TIME 87 8957
TEST_TIME 88 8927
TEST_TIME 89 9014
TEST_TIME 90 8993
TEST_TIME 91 9043
TEST_TIME 92 9127
TEST_TIME 93 9116
TEST_TIME 94 9147
TEST_TIME 95 9108
TEST_TIME 96 9126
TEST_TIME 97 9080
TEST_TIME 98 9076
TEST_TIME 99 9072
TEST_TIME 100 9035
TEST_TIME 101 9040
TEST_TIME 102 8990
TEST_TIME 103 8979
TEST_TIME 104 9005
TEST_TIME 105 9068
TEST_TIME 106 9040
TEST_TIME 107 9139
TEST_TIME 108 9116
TEST_TIME 109 9108
TEST_TIME 110 9104
TEST_TIME 111 9145
TEST_TIME 112 9067
TEST_TIME 113 8994
TEST_TIME 114 8973
TEST_TIME 115 8943
TEST_TIME 116 8932
TEST_TIME 117 8903
TEST_TIME 118 8885
TEST_TIME 119 8855
TEST_TIME 120 8781

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 09-06-2021 13:29:19 to 09-06-2021 13:29:23.

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 1467850 1467850 0 0.00 0 33874 6 0 0
doc_list_biud 1467850 1467850 0 0.00 0 48271 28 0 0
sp_add_invoice_to_stock 5029 4947 82 1.63 40 164689 16736 0 82
sp_cancel_adding_invoice 5341 5341 0 0.00 5 80783 910 0 0
sp_cancel_client_order 1694 1694 0 0.00 4 7668 291 0 0
sp_cancel_customer_reserve 521554 521554 0 0.00 1 48274 62 0 0
sp_cancel_pay_from_customer 680 680 0 0.00 3 2587 74 0 0
sp_cancel_pay_to_supplier 396 396 0 0.00 3 813 61 0 0
sp_cancel_supplier_invoice 5832 5822 10 0.17 2 29138 968 0 10
sp_cancel_supplier_order 374 374 0 0.00 8 962684 110041 0 0
sp_cancel_write_off 9053 9053 0 0.00 1 8342 106 0 0
sp_client_order 19577 19577 0 0.00 4 66706 397 0 0
sp_customer_reserve 449511 449511 0 0.00 0 45431 167 0 0
sp_fill_shopping_cart 30339 30339 0 0.00 1 16177 51 0 0
sp_fill_shopping_cart_clo_res 449498 449498 0 0.00 0 25318 16 0 0
sp_get_clo_for_invoice 4934 4934 0 0.00 0 40036 378 0 0
sp_kill_cost_storno 15470 15470 0 0.00 0 33859 56 0 0
sp_kill_qstorno_ret_qs2qd 1058357 1058357 0 0.00 0 48269 34 0 0
sp_kill_qty_storno 558478 558478 0 0.00 0 48271 73 0 0
sp_lock_selected_doc 20127 20127 0 0.00 0 4452 3 0 0
sp_make_cost_storno 27120 27120 0 0.00 0 21721 61 0 0
sp_multiply_rows_for_pdistr 27120 27120 0 0.00 0 8859 28 0 0
sp_multiply_rows_for_qdistr 19577 19577 0 0.00 0 38519 214 0 0
sp_pay_from_customer 6930 6930 0 0.00 4 11284 113 0 0
sp_pay_to_supplier 5550 5550 0 0.00 5 16420 133 0 0
sp_payment_common 12480 12480 0 0.00 1 9190 20 0 0
sp_qd_handle_on_cancel_clo 1694 1694 0 0.00 0 7662 263 0 0
sp_qd_handle_on_invoice_upd_sts 10275 10275 0 0.00 0 25494 355 0 0
sp_qd_handle_on_reserve_upd_sts 9706 9706 0 0.00 0 8730 48 0 0
sp_reserve_write_off 9706 9706 0 0.00 7 22066 171 0 0
sp_supplier_invoice 5447 5447 0 0.00 7 97100 3865 0 0
sp_supplier_order 5321 5321 0 0.00 7 32352 1423 0 0
srv_aggregate_perf_data 105 105 0 0.00 2589 145597 15440 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1392 152155 48758 0 0
srv_find_qd_qs_mism 1006390 1006390 0 0.00 0 13114 5 0 0
srv_make_invnt_saldo 473 473 0 0.00 48 82219 3151 0 0
srv_make_money_saldo 707 707 0 0.00 1 16352 549 0 0
srv_recalc_idx_stat 4 4 0 0.00 325956 514876 411442 0 0
t$perf-abend:sp_add_invoice_to_stock 82 82 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4947 4947 0 0.00 0 15879 145 0 0
t$perf-norm:sp_cancel_adding_invoice 425 425 0 0.00 0 3896 77 0 0
t$perf-norm:sp_cancel_client_order 1694 1694 0 0.00 0 1742 9 0 0
t$perf-norm:sp_cancel_customer_reserve 656 656 0 0.00 0 15467 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 680 680 0 0.00 0 2562 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 396 396 0 0.00 0 842 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 324 324 0 0.00 0 1102 15 0 0
t$perf-norm:sp_cancel_supplier_order 374 374 0 0.00 0 25929 1444 0 0
t$perf-norm:sp_cancel_write_off 939 939 0 0.00 0 835 5 0 0
t$perf-norm:sp_client_order 19577 19577 0 0.00 0 6026 15 0 0
t$perf-norm:sp_customer_reserve 931 931 0 0.00 0 2298 7 0 0
t$perf-norm:sp_pay_from_customer 6930 6930 0 0.00 0 3111 6 0 0
t$perf-norm:sp_pay_to_supplier 5550 5550 0 0.00 0 5125 9 0 0
t$perf-norm:sp_reserve_write_off 9706 9706 0 0.00 0 5089 8 0 0
t$perf-norm:sp_supplier_invoice 5447 5447 0 0.00 0 6533 14 0 0
t$perf-norm:sp_supplier_order 5321 5321 0 0.00 0 2292 13 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 750 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 88 3 0 0
t$perf-norm:srv_make_invnt_saldo 473 473 0 0.00 0 472 6 0 0
t$perf-norm:srv_make_money_saldo 707 707 0 0.00 0 1224 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10030 9866 164 1.63 0 7623 16 0 164
v_all_customers 35752 35752 0 0.00 0 16 1 0 0
v_all_suppliers 29398 29398 0 0.00 0 15 1 0 0
v_all_wares 39154 39154 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 850 850 0 0.00 0 1845 9 0 0
v_cancel_client_order 3388 3388 0 0.00 0 2625 6 0 0
v_cancel_customer_prepayment 1360 1360 0 0.00 0 2571 11 0 0
v_cancel_customer_reserve 1312 1312 0 0.00 0 1923 6 0 0
v_cancel_payment_to_supplier 792 792 0 0.00 0 533 4 0 0
v_cancel_supplier_invoice 668 648 20 2.99 0 1343 14 0 20
v_cancel_supplier_order 748 748 0 0.00 0 2173 9 0 0
v_cancel_write_off 1878 1878 0 0.00 0 4724 17 0 0
v_max_id_clo_ord 5319 5319 0 0.00 0 636 1 0 0
v_max_id_clo_res 931 931 0 0.00 0 5967 25 0 0
v_max_id_ord_sup 5442 5442 0 0.00 0 4943 5 0 0
v_max_non_paid_invoice 1612 1612 0 0.00 0 554 2 0 0
v_max_non_paid_realizn 3766 3766 0 0.00 0 7511 5 0 0
v_min_id_clo_ord 5319 5319 0 0.00 0 8143 9 0 0
v_min_id_clo_res 931 931 0 0.00 0 6145 15 0 0
v_min_id_ord_sup 5442 5442 0 0.00 0 7882 13 0 0
v_min_non_paid_invoice 5550 5550 0 0.00 0 7970 12 0 0
v_min_non_paid_realizn 6930 6930 0 0.00 0 7674 13 0 0
v_our_firm 9730 9730 0 0.00 0 13 1 0 0
v_reserve_write_off 19412 19412 0 0.00 0 8173 8 0 0
x_lock_depdocs_on_canc_invoice 10839 10839 0 0.00 0 13762 114 0 0
x_lock_depdocs_on_canc_sup_ord 374 374 0 0.00 0 7193 339 0 0
x_make_qstorno_1000_1200 5321 5321 0 0.00 2 32073 1339 0 0
x_make_qstorno_1200_2000 5447 5447 0 0.00 4 94089 3672 0 0
x_make_qstorno_2100_3300 449511 449511 0 0.00 0 45431 149 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-06-2021 13:29:24 to 09-06-2021 13:29:28.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
10:57:03 8 8192000000 8796668768 8807174144 3189024 0.038 1 1 2 0 3286640 61840 35776
10:58:05 29701 8192000000 9727723840 10028486656 803519088 8.932 100 90 180 0 833479968 7763664 22197216
10:59:40 25055 8192000000 9772903936 10083241984 846511536 9.365 100 90 180 2 881859328 13832688 21515104
11:01:19 17607 8192000000 9825153072 10146652160 878660720 9.686 100 82 164 2 924595040 27210544 18723776
11:02:41 10785 8192000000 9879206400 10215034880 926112352 10.156 100 82 164 1 997873328 53612208 18148768
11:03:58 10718 8192000000 9935753584 10282602496 959434768 10.483 100 75 149 1 1041105808 66160080 15510960
11:05:12 3657 8192000000 9969578160 10325311488 1002041296 10.898 100 80 160 3 1081488240 63069648 16377296
11:06:18 2045 8192000000 10019167200 10381463552 1031580160 11.184 100 83 165 0 1123308016 75258016 16469840
11:07:22 2343 8192000000 10021709184 10394427392 1060240352 11.459 100 86 172 2 1139509952 62151856 17117744
11:08:28 2023 8192000000 10056539456 10430783488 1069382592 11.546 100 93 188 1 1180057472 91689392 18985488
11:09:31 1685 8192000000 10032569360 10407768064 1074043312 11.591 100 87 174 0 1139656128 47303872 18308944
11:10:35 6424 8192000000 9993393632 10383077376 1078966816 11.638 100 49 97 2 1108054016 18550368 10536832
11:11:43 3673 8192000000 10013735408 10398306304 1079399312 11.642 100 46 87 2 1117631536 28568656 9663568
11:12:53 11564 8192000000 9995149360 10404245504 1088597120 11.729 100 17 34 1 1114170864 22265488 3308256
11:14:07 63550 8192000000 10017001008 10416324608 1094453952 11.785 100 54 108 2 1120503088 14617248 11431888
11:16:11 7444 8192000000 10017628608 10420486144 1104012688 11.876 100 39 77 5 1135931120 23871408 8047024
11:17:21 4466 8192000000 10022255600 10409943040 1086047808 11.705 99 51 102 2 1117619552 20389552 11182192
11:18:28 4196 8192000000 9963901248 10355404800 1047951168 11.341 98 48 95 3 1072629392 14015072 10663152
11:19:34 11465 8192000000 9986630896 10394599424 1042944128 11.293 99 34 65 0 1092861776 43162944 6754704
11:20:49 4973 8192000000 9952348608 10339475456 1018765232 11.060 99 44 89 2 1058159408 29451072 9943104
11:21:54 3732 8192000000 9936817952 10318639104 1020668368 11.078 99 52 100 3 1047697360 16302736 10726256
11:22:59 69009 8192000000 9941876112 10342936576 1017469936 11.048 98 45 91 1 1039355216 12232224 9653056
11:25:10 5495 8192000000 9968547968 10357219328 1047922496 11.341 100 56 113 2 1082639760 22003424 12713840
11:26:17 5391 8192000000 9982783984 10371518464 1059941168 11.456 100 52 104 5 1087698640 15814688 11942784
11:27:29 33984 8192000000 9995108000 10392219648 1081203264 11.659 100 33 64 1 1107832784 19156304 7473216
11:29:22 86138 8192000000 10068353440 10474647552 1111268208 11.944 100 39 76 2 1178306336 58456624 8581504
11:32:04 13822 8192000000 10107153728 10535706624 1122352560 12.049 100 34 69 0 1214754048 85119088 7282400
11:33:21 5911 8192000000 10151414784 10562744320 1137282320 12.190 100 59 118 3 1261608864 111836608 12489936
11:34:33 4924 8192000000 10157631888 10563760128 1146980464 12.281 100 52 103 1 1267411232 109336496 11094272
11:35:41 6934 8192000000 10181888128 10594246656 1157188096 12.377 100 60 120 2 1291632912 121360640 13084176
11:36:53 9595 8192000000 10200032032 10615607296 1141087616 12.226 99 50 98 2 1312505904 160756064 10662224
11:38:06 4750 8192000000 10254843232 10663993344 1157658992 12.381 100 69 138 1 1361864496 188679808 15525696
11:39:19 11278 8192000000 10257431040 10671689728 1162968224 12.431 100 61 122 1 1361896480 185451040 13477216
11:40:33 3385 8192000000 10304616160 10711490560 1156046624 12.366 100 86 171 0 1398464112 223279520 19137968
11:41:44 1390 8192000000 10325890080 10733772800 1150999328 12.319 99 80 163 0 1423397200 254125344 18272528
11:42:49 3210 8192000000 10353489568 10777653248 1157824208 12.383 100 78 153 1 1452057712 275230176 19003328
11:43:56 8755 8192000000 10287558736 10760167424 1144809376 12.261 100 60 119 0 1395560672 236053760 14697536
11:45:09 6003 8192000000 10224334480 10658398208 1133544896 12.155 100 52 103 0 1324981024 178258160 13177968
11:46:26 2975 8192000000 10301816224 10706407424 1147833024 12.289 100 82 164 2 1398267232 232193552 18240656
11:47:32 8410 8192000000 10311218368 10727047168 1141063776 12.226 99 39 75 4 1401174416 251215824 8894816
11:48:45 14498 8192000000 10358480208 10788290560 1137155920 12.189 100 41 82 0 1457436912 309636832 10644160
11:50:01 13834 8192000000 10375525984 10798444544 1130985952 12.131 99 33 66 0 1462482000 323229040 8267008
11:51:17 15966 8192000000 10402285920 10880409600 1139825440 12.214 100 33 66 0 1538909584 390274672 8809472
11:52:47 29008 8192000000 10370064752 10841313280 1124235424 12.067 99 26 51 1 1493653824 362335664 7082736
11:54:27 11146 8192000000 10291720176 10746744832 1128073424 12.103 100 29 58 4 1406451360 271430368 6947568
11:55:45 82098 8192000000 10068885408 10544795648 1094403312 11.785 98 18 36 1 1166995424 68831728 3760384
11:58:11 113531 8192000000 10069732832 10506092544 1093296160 11.774 97 21 42 0 1153078480 54979856 4802464
12:01:05 27063 8192000000 10098980672 10522570752 1128944976 12.111 100 57 117 1 1201833104 60434976 12453152
12:02:32 33672 8192000000 10131728944 10605887488 1138411936 12.201 100 23 46 2 1247119312 103459904 5247472
12:04:51 46053 8192000000 10119718944 10563633152 1116082528 11.990 98 30 60 2 1214270368 91776512 6411328
12:06:55 82231 8192000000 10099452368 10579505152 1133680400 12.156 100 7 14 0 1234180432 98959744 1540288
12:09:19 56791 8192000000 10063466256 10524966912 1135002784 12.168 100 15 31 0 1165270864 26975024 3293056
12:11:33 54391 8192000000 10079499616 10552418304 1119331024 12.021 98 7 13 0 1150239792 29711776 1196992
12:13:35 39516 8192000000 10084391216 10555715584 1131412192 12.135 99 35 68 1 1199449072 60537520 7499360
12:15:15 83508 8192000000 10072939248 10571284480 1128414144 12.106 99 15 31 0 1211189616 79495344 3280128
12:17:39 48005 8192000000 10113689536 10566664192 1138232688 12.199 99 22 44 2 1196588848 53890480 4465680
12:19:27 36562 8192000000 10082051584 10516127744 1136965792 12.187 99 34 67 3 1174300416 30227088 7107536
12:21:07 55639 8192000000 10090647264 10578763776 1130792144 12.129 99 19 38 1 1201086096 66488144 3805808
12:23:04 49409 8192000000 10124703440 10560647168 1099806816 11.836 96 33 66 1 1175622480 69218480 6597184
12:25:00 112873 8192000000 10030217728 10472824832 1099303552 11.831 98 17 34 0 1125316256 22085360 3927344
12:27:54 59708 8192000000 10031737776 10512764928 1101120816 11.848 98 15 30 0 1153732336 49617744 2993776
12:30:04 41012 8192000000 10072148560 10519928832 1102449888 11.861 98 10 20 0 1155307712 51149712 1708112
12:31:46 67843 8192000000 10053471584 10512068608 1095511600 11.795 97 8 16 0 1127718304 30558800 1647904
12:33:54 62672 8192000000 10056538080 10535862272 1126905584 12.092 100 18 37 0 1197029936 66370736 3753616
12:35:59 51007 8192000000 10044273312 10502582272 1055203856 11.411 94 25 50 0 1116021488 55796480 5021152
12:37:56 45286 8192000000 10093260816 10516004864 1084385568 11.689 98 37 74 2 1165760688 74200192 7174928
12:39:41 36814 8192000000 10054306384 10526670848 1112711088 11.958 100 26 52 1 1159023008 41117056 5194864
12:41:25 50886 8192000000 10172149584 10595495936 1099023472 11.828 98 37 73 1 1219750960 113095280 7632208
12:43:21 66677 8192000000 10092480064 10574675968 1119932032 12.026 100 9 18 1 1175121888 53108656 2081200
12:45:30 110728 8192000000 10051508080 10513043456 1098581504 11.824 98 16 32 1 1154313152 52668384 3063264
12:48:21 54037 8192000000 10049636000 10530140160 1081462128 11.661 97 7 14 2 1112549824 29836176 1251520
12:50:16 69662 8192000000 10043489360 10492571648 1087013840 11.714 97 13 26 0 1128738320 38504944 3219536
12:52:31 49650 8192000000 10080755920 10515185664 1102634704 11.863 98 18 36 1 1153392384 47359600 3398080
12:54:22 46013 8192000000 10113151504 10553556992 1121104640 12.037 100 24 48 0 1184621328 58484080 5032608
12:56:19 64923 8192000000 10081356032 10519404544 1100791456 11.845 98 15 30 0 1218650160 114504928 3353776
12:58:25 152155 8192000000 10087282256 10530639872 1097608864 11.815 97 11 22 1 1175705808 76134160 1962784
13:01:57 86644 8192000000 10109283152 10621489152 1126634416 12.090 99 9 18 0 1199436064 71148848 1652800
13:04:54 63132 8192000000 10082362608 10579947520 1137239104 12.190 100 8 16 0 1171067504 32106704 1721696
13:06:58 53636 8192000000 10075865792 10520481792 1119873312 12.026 98 21 42 1 1149886144 25908368 4104464
13:08:55 82844 8192000000 10114316176 10543579136 1127346768 12.096 99 31 60 0 1180272096 46383584 6541744
13:11:18 87822 8192000000 10084071152 10567340032 1123859952 12.063 98 17 34 0 1181658720 54262544 3536224
13:13:47 135105 8192000000 10070274976 10517979136 1075873904 11.608 94 13 26 1 1134060336 55452544 2733888
13:17:04 50106 8192000000 10070849888 10496823296 1088808016 11.731 96 24 49 2 1106715200 13172352 4734832
13:18:58 115388 8192000000 10031648528 10487533568 1098552320 11.824 97 13 26 0 1137971472 36498576 2920576
13:22:00 53312 8192000000 10072165200 10531504128 1119527168 12.023 99 8 16 0 1167075808 45855568 1693072
13:24:03 46383 8192000000 10067015360 10552111104 1132981536 12.149 100 6 12 1 1165629568 31442992 1205040
13:26:00 50902 8192000000 10079736176 10554040320 1121119104 12.038 98 9 18 1 1154486144 31404480 1962560

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 09-06-2021 13:29:29 to 09-06-2021 13:29:44.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 82 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-06-2021 13:29:45 to 09-06-2021 13:29:47.

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          232957
MON$OLDEST_ACTIVE               232958
MON$OLDEST_SNAPSHOT             232958
MON$NEXT_TRANSACTION            232958
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                       2096768
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             794
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2514 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2514 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2514 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2514 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 ---
--- end of diff output ---

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

09.06.21 13:29:48. 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-09 13:29:50 No severe PSQL-related problems occured
09.06.21 13:29:51. End of report.