Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.09.2021 13:39
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 16105456 kB
MemAvailable 30950268 kB
Buffers 0 kB
Cached 15627656 kB
SwapCached 336 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-09-03 13:37:07
DTS_END                         2021-09-03 13:37:07.7890
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/52824
TRN_ID                          230820
ATT_ID                          631
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: 9078.71 18929 1089445
customer order: creation 165.10 416 19812
customer order: refuse 14.33 333 1720
order to supplier: creation 44.87 1514 5384
order to supplier: removal 3.53 112088 423
invoice (draft): creation 45.06 3586 5407
invoice (draft): removal 51.94 996 6233
invoice accept: apply 40.04 22246 4805
invoice accept: cancel 47.04 858 5645
customer reserve: creation 3752.90 217 450348
customer reserve: removal 4634.81 66 556177
realization accept: apply 81.71 206 9805
realization accept: cancel 73.32 144 8798
payment from customer: creation 56.98 127 6838
payment from customer: removal 5.75 84 690
payment to supplier: creation 47.30 129 5676
payment to supplier: removal 3.45 112 414
service: total inventory turnovers 3.61 3984 433
service: total monetary turnovers 6.07 653 728
service: aggregate perf. data 0.88 16356 105
service: refresh index statistics 0.03 214456 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-09-2021 13:39:20 to 03-09-2021 13:39:22.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 11401
TEST_TIME 2 9724
TEST_TIME 3 8432
TEST_TIME 4 7772
TEST_TIME 5 7225
TEST_TIME 6 7189
TEST_TIME 7 7029
TEST_TIME 8 7043
TEST_TIME 9 7023
TEST_TIME 10 7248
TEST_TIME 11 7470
TEST_TIME 12 7878
TEST_TIME 13 7825
TEST_TIME 14 7825
TEST_TIME 15 8093
TEST_TIME 16 8297
TEST_TIME 17 8490
TEST_TIME 18 8493
TEST_TIME 19 8469
TEST_TIME 20 8490
TEST_TIME 21 8501
TEST_TIME 22 8787
TEST_TIME 23 9104
TEST_TIME 24 9392
TEST_TIME 25 9499
TEST_TIME 26 9973
TEST_TIME 27 10830
TEST_TIME 28 11228
TEST_TIME 29 10943
TEST_TIME 30 10820
TEST_TIME 31 10719
TEST_TIME 32 10619
TEST_TIME 33 10461
TEST_TIME 34 10488
TEST_TIME 35 10296
TEST_TIME 36 10364
TEST_TIME 37 10296
TEST_TIME 38 10273
TEST_TIME 39 10112
TEST_TIME 40 10124
TEST_TIME 41 10020
TEST_TIME 42 10135
TEST_TIME 43 10017
TEST_TIME 44 9923
TEST_TIME 45 9924
TEST_TIME 46 9932
TEST_TIME 47 9897
TEST_TIME 48 9998
TEST_TIME 49 9904
TEST_TIME 50 9954
TEST_TIME 51 9896
TEST_TIME 52 9921
TEST_TIME 53 10194
TEST_TIME 54 10115
TEST_TIME 55 10104
TEST_TIME 56 9965
TEST_TIME 57 9867
TEST_TIME 58 9760
TEST_TIME 59 9730
TEST_TIME 60 9742
TEST_TIME 61 9647
TEST_TIME 62 9565
TEST_TIME 63 9550
TEST_TIME 64 9482
TEST_TIME 65 9436
TEST_TIME 66 9541
TEST_TIME 67 9556
TEST_TIME 68 9611
TEST_TIME 69 9652
TEST_TIME 70 9615
TEST_TIME 71 9575
TEST_TIME 72 9612
TEST_TIME 73 9547
TEST_TIME 74 9606
TEST_TIME 75 9761
TEST_TIME 76 9688
TEST_TIME 77 9613
TEST_TIME 78 9524
TEST_TIME 79 9509
TEST_TIME 80 9458
TEST_TIME 81 9383
TEST_TIME 82 9317
TEST_TIME 83 9279
TEST_TIME 84 9227
TEST_TIME 85 9332
TEST_TIME 86 9292
TEST_TIME 87 9301
TEST_TIME 88 9286
TEST_TIME 89 9314
TEST_TIME 90 9382
TEST_TIME 91 9365
TEST_TIME 92 9422
TEST_TIME 93 9454
TEST_TIME 94 9437
TEST_TIME 95 9410
TEST_TIME 96 9495
TEST_TIME 97 9429
TEST_TIME 98 9359
TEST_TIME 99 9328
TEST_TIME 100 9295
TEST_TIME 101 9249
TEST_TIME 102 9214
TEST_TIME 103 9198
TEST_TIME 104 9188
TEST_TIME 105 9209
TEST_TIME 106 9190
TEST_TIME 107 9184
TEST_TIME 108 9185
TEST_TIME 109 9166
TEST_TIME 110 9220
TEST_TIME 111 9180
TEST_TIME 112 9177
TEST_TIME 113 9261
TEST_TIME 114 9304
TEST_TIME 115 9259
TEST_TIME 116 9263
TEST_TIME 117 9196
TEST_TIME 118 9163
TEST_TIME 119 9157
TEST_TIME 120 9084

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 03-09-2021 13:39:23 to 03-09-2021 13:39:27.

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 1504943 1504943 0 0.00 0 19711 7 0 0
doc_list_biud 1504943 1504943 0 0.00 0 33651 32 0 0
sp_add_invoice_to_stock 4875 4805 70 1.43 37 236848 22247 0 70
sp_cancel_adding_invoice 5645 5645 0 0.00 6 54979 858 0 0
sp_cancel_client_order 1720 1720 0 0.00 5 26997 333 0 0
sp_cancel_customer_reserve 556177 556177 0 0.00 1 33585 67 0 0
sp_cancel_pay_from_customer 690 690 0 0.00 3 3017 85 0 0
sp_cancel_pay_to_supplier 414 414 0 0.00 3 10440 113 0 0
sp_cancel_supplier_invoice 6239 6233 6 0.09 2 33660 996 0 6
sp_cancel_supplier_order 423 423 0 0.00 11 995648 112089 0 0
sp_cancel_write_off 8798 8798 0 0.00 1 11976 145 0 0
sp_client_order 19812 19812 0 0.00 4 31055 416 0 0
sp_customer_reserve 450348 450348 0 0.00 0 51264 217 0 0
sp_fill_shopping_cart 30586 30586 0 0.00 1 16091 42 0 0
sp_fill_shopping_cart_clo_res 450327 450327 0 0.00 0 14731 16 0 0
sp_get_clo_for_invoice 4784 4784 0 0.00 0 20128 343 0 0
sp_kill_cost_storno 15547 15547 0 0.00 0 6897 61 0 0
sp_kill_qstorno_ret_qs2qd 1127808 1127808 0 0.00 0 33650 37 0 0
sp_kill_qty_storno 593585 593585 0 0.00 0 33651 79 0 0
sp_lock_selected_doc 20306 20306 0 0.00 0 8922 4 0 0
sp_make_cost_storno 27103 27103 0 0.00 0 12993 53 0 0
sp_multiply_rows_for_pdistr 27103 27103 0 0.00 0 12363 39 0 0
sp_multiply_rows_for_qdistr 19812 19812 0 0.00 0 22981 207 0 0
sp_pay_from_customer 6838 6838 0 0.00 4 11939 127 0 0
sp_pay_to_supplier 5676 5676 0 0.00 4 11697 130 0 0
sp_payment_common 12514 12514 0 0.00 1 6929 26 0 0
sp_qd_handle_on_cancel_clo 1720 1720 0 0.00 0 26990 307 0 0
sp_qd_handle_on_invoice_upd_sts 10429 10429 0 0.00 0 29115 375 0 0
sp_qd_handle_on_reserve_upd_sts 9805 9805 0 0.00 0 23275 65 0 0
sp_reserve_write_off 9805 9805 0 0.00 6 23299 206 0 0
sp_supplier_invoice 5407 5407 0 0.00 7 93038 3586 0 0
sp_supplier_order 5384 5384 0 0.00 6 43421 1515 0 0
srv_aggregate_perf_data 105 105 0 0.00 2790 150080 16357 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1563 124362 44187 0 0
srv_find_qd_qs_mism 1042474 1042474 0 0.00 0 19710 7 0 0
srv_make_invnt_saldo 433 433 0 0.00 48 105665 3984 0 0
srv_make_money_saldo 728 728 0 0.00 1 34364 654 0 0
srv_recalc_idx_stat 4 4 0 0.00 129455 310012 214456 0 0
t$perf-abend:sp_add_invoice_to_stock 70 70 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4805 4805 0 0.00 0 31721 197 0 0
t$perf-norm:sp_cancel_adding_invoice 369 369 0 0.00 1 8442 116 0 0
t$perf-norm:sp_cancel_client_order 1720 1720 0 0.00 0 4436 19 0 0
t$perf-norm:sp_cancel_customer_reserve 685 685 0 0.00 0 1101 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 690 690 0 0.00 0 3105 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 414 414 0 0.00 0 12039 40 0 0
t$perf-norm:sp_cancel_supplier_invoice 386 386 0 0.00 0 4763 29 0 0
t$perf-norm:sp_cancel_supplier_order 423 423 0 0.00 0 31762 1760 0 0
t$perf-norm:sp_cancel_write_off 1030 1030 0 0.00 0 2009 8 0 0
t$perf-norm:sp_client_order 19812 19812 0 0.00 0 15007 23 0 0
t$perf-norm:sp_customer_reserve 920 920 0 0.00 0 8591 18 0 0
t$perf-norm:sp_pay_from_customer 6838 6838 0 0.00 0 6836 13 0 0
t$perf-norm:sp_pay_to_supplier 5676 5676 0 0.00 0 26805 18 0 0
t$perf-norm:sp_reserve_write_off 9805 9805 0 0.00 0 23852 16 0 0
t$perf-norm:sp_supplier_invoice 5407 5407 0 0.00 0 7140 18 0 0
t$perf-norm:sp_supplier_order 5384 5384 0 0.00 0 7596 17 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 176 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 22 1 0 0
t$perf-norm:srv_make_invnt_saldo 433 433 0 0.00 0 856 10 0 0
t$perf-norm:srv_make_money_saldo 728 728 0 0.00 0 791 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 4 0 0
v_add_invoice_to_stock 9708 9568 140 1.44 0 4245 16 0 140
v_all_customers 35800 35800 0 0.00 0 19 1 0 0
v_all_suppliers 29728 29728 0 0.00 0 32 1 0 0
v_all_wares 39624 39624 0 0.00 0 42 1 0 0
v_cancel_adding_invoice 738 738 0 0.00 0 1251 9 0 0
v_cancel_client_order 3440 3440 0 0.00 0 4094 8 0 0
v_cancel_customer_prepayment 1380 1380 0 0.00 0 3003 13 0 0
v_cancel_customer_reserve 1370 1370 0 0.00 0 5206 12 0 0
v_cancel_payment_to_supplier 828 828 0 0.00 0 383 4 0 0
v_cancel_supplier_invoice 784 772 12 1.53 0 4167 20 0 12
v_cancel_supplier_order 846 846 0 0.00 0 4448 15 0 0
v_cancel_write_off 2060 2060 0 0.00 0 3497 17 0 0
v_max_id_clo_ord 5384 5384 0 0.00 0 351 1 0 0
v_max_id_clo_res 920 920 0 0.00 0 2077 12 0 0
v_max_id_ord_sup 5390 5390 0 0.00 0 7089 8 0 0
v_max_non_paid_invoice 1586 1586 0 0.00 0 126 2 0 0
v_max_non_paid_realizn 3763 3763 0 0.00 0 868 3 0 0
v_min_id_clo_ord 5384 5384 0 0.00 0 3813 6 0 0
v_min_id_clo_res 920 920 0 0.00 0 6563 23 0 0
v_min_id_ord_sup 5390 5390 0 0.00 0 5050 6 0 0
v_min_non_paid_invoice 5676 5676 0 0.00 0 6566 16 0 0
v_min_non_paid_realizn 6838 6838 0 0.00 0 5975 21 0 0
v_our_firm 9974 9974 0 0.00 0 15 1 0 0
v_reserve_write_off 19610 19610 0 0.00 0 7484 7 0 0
x_lock_depdocs_on_canc_invoice 11492 11492 0 0.00 0 20843 114 0 0
x_lock_depdocs_on_canc_sup_ord 423 423 0 0.00 0 18803 313 0 0
x_make_qstorno_1000_1200 5384 5384 0 0.00 2 43385 1431 0 0
x_make_qstorno_1200_2000 5407 5407 0 0.00 3 93030 3434 0 0
x_make_qstorno_2100_3300 450348 450348 0 0.00 0 42932 198 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-09-2021 13:39:28 to 03-09-2021 13:39:32.

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
11:07:11 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:13 20068 8192000000 9713781008 10007097344 795535600 8.851 100 88 176 0 824691744 7719360 21436784
11:09:44 26687 8192000000 9764915840 10072838144 838475488 9.284 100 86 172 0 870755776 11901296 20378992
11:11:13 19006 8192000000 9810456624 10131537920 876337232 9.663 100 81 162 0 913656416 18677104 18642080
11:12:41 14658 8192000000 9880029840 10218295296 939534080 10.288 100 83 166 1 997327360 39344256 18449024
11:13:58 8994 8192000000 9928721168 10275573760 968930224 10.576 100 77 152 0 1045430704 60812048 15688432
11:15:11 5216 8192000000 9970459152 10326265856 998245104 10.862 100 79 157 0 1091691552 77968080 15478368
11:16:21 1640 8192000000 9996360832 10361356288 1030517824 11.173 100 79 158 0 1111020144 64637264 15865056
11:17:25 2067 8192000000 10018762704 10386341888 1049054880 11.352 100 84 167 0 1130728752 64491712 17182160
11:18:28 4067 8192000000 10023950080 10417008640 1059711888 11.454 100 58 116 0 1136052880 64888592 11452400
11:19:36 5832 8192000000 9996056544 10383355904 1071292576 11.564 100 45 89 2 1106877120 26448208 9136336
11:20:42 7065 8192000000 9993027168 10389278720 1080987456 11.657 100 43 83 3 1116082160 25757392 9337312
11:21:51 50941 8192000000 10007054928 10399657984 1085244688 11.697 100 49 98 2 1120204400 23291136 11668576
11:23:42 9563 8192000000 9999369696 10404724736 1093862720 11.779 100 35 69 2 1115883184 14780112 7240352
11:24:54 8434 8192000000 10019909728 10425638912 1089080912 11.734 99 42 81 3 1126291328 27778784 9431632
11:26:06 8711 8192000000 10009176192 10408067072 1101378832 11.851 100 33 63 2 1128454336 20659232 6416272
11:27:16 43968 8192000000 9982074400 10390188032 1019683696 11.069 93 26 50 1 1041804272 16674416 5446160
11:29:00 6362 8192000000 9985486064 10369597440 1030332336 11.172 98 43 87 2 1074475984 35389856 8753792
11:30:07 6788 8192000000 9921799728 10307293184 961724112 10.506 94 45 90 1 984921792 13072224 10125456
11:31:16 7061 8192000000 9958043680 10352918528 1039134752 11.256 100 37 74 0 1071931872 25025280 7771840
11:32:24 21389 8192000000 9956119184 10360344576 1020467728 11.077 98 18 36 0 1069627712 45680368 3479616
11:33:52 4565 8192000000 9981375280 10369662976 1053402000 11.393 100 48 96 1 1110011456 46827696 9781760
11:34:57 5939 8192000000 9974344000 10373570560 1064693600 11.501 100 46 92 1 1085269968 9831104 10745264
11:36:06 62542 8192000000 9987457648 10388078592 1074022832 11.590 100 45 90 0 1107432608 22833696 10576080
11:38:13 22162 8192000000 10079504256 10478886912 1108465184 11.918 100 54 108 1 1179781440 60842592 10473664
11:39:38 29699 8192000000 10081560272 10491019264 1129444976 12.116 100 53 106 1 1182611232 42645040 10521216
11:41:15 9039 8192000000 10111230576 10515374080 1146344080 12.275 100 52 104 0 1217290304 60006928 10939296
11:42:29 35267 8192000000 10172861840 10600091648 1153229600 12.340 100 54 106 0 1284546000 119809664 11506736
11:44:18 10177 8192000000 10179454672 10592149504 1161659920 12.419 100 58 115 0 1282614448 108036304 12918224
11:45:33 10149 8192000000 10201443568 10636197888 1165548288 12.455 100 54 107 1 1314435264 137109088 11777888
11:46:57 9599 8192000000 10269694096 10680963072 1174394848 12.538 100 71 141 0 1369684448 180380048 14909552
11:48:12 7515 8192000000 10238456752 10656579584 1184316128 12.630 100 55 110 0 1342686112 145987568 12382416
11:49:23 3093 8192000000 10258408576 10669621248 1175919536 12.552 100 84 167 1 1354447616 160698944 17829136
11:50:32 6800 8192000000 10269036400 10685181952 1170704864 12.503 100 57 114 1 1380967936 197584368 12678704
11:51:43 2981 8192000000 10321459616 10727424000 1175304448 12.546 100 86 172 0 1419239472 224390992 19544032
11:52:49 3980 8192000000 10319089008 10725445632 1152144944 12.330 100 75 150 1 1418934592 249421680 17367968
11:53:59 2287 8192000000 10339116640 10740137984 1146105184 12.273 100 75 148 1 1439633056 276504096 17023776
11:55:05 4731 8192000000 10348014704 10752049152 1135837824 12.176 98 73 146 2 1445253696 291886176 17529696
11:56:28 1562 8192000000 10406561952 10812526592 1164416496 12.445 100 74 148 0 1503484672 321810944 17257232
11:57:45 9499 8192000000 10401810656 10812481536 1150228736 12.312 100 44 87 0 1498305728 337237808 10839184
11:59:00 17942 8192000000 10417054832 10844782592 1147281680 12.284 100 41 82 0 1524778928 367256864 10240384
12:00:25 9768 8192000000 10448214992 10873602048 1130423568 12.125 99 39 78 2 1527031248 385882976 10724704
12:01:42 16453 8192000000 10388313104 10869452800 1127569952 12.098 99 37 74 0 1507555152 370448656 9536544
12:03:06 26156 8192000000 10365802848 10824806400 1121065104 12.037 100 14 28 1 1459372368 333596112 4711152
12:04:41 42114 8192000000 10045474688 10497773568 1082744352 11.674 96 14 28 0 1196605216 110341424 3519440
12:06:25 85718 8192000000 10078558384 10501689344 1106737328 11.902 98 54 108 1 1163911488 45601312 11572848
12:08:55 38876 8192000000 10092897312 10519506944 1132950432 12.149 100 49 97 3 1187746960 44572256 10224272
12:10:36 21678 8192000000 10109732336 10551648256 1144350272 12.256 100 24 48 0 1209973936 60814256 4809408
12:11:59 23559 8192000000 10125923200 10555002880 1130553360 12.127 98 26 52 2 1200047872 63968640 5525872
12:13:29 44360 8192000000 10129101920 10577178624 1149722496 12.307 100 13 26 0 1223498336 70754048 3021792
12:15:15 41447 8192000000 10107618912 10556432384 1154720720 12.354 100 16 32 0 1213658784 55315536 3622528
12:16:57 49300 8192000000 10128085248 10579275776 1158907088 12.393 100 14 28 0 1247960752 85930720 3122944
12:19:21 62123 8192000000 10084420000 10600804352 1142635600 12.240 99 8 16 0 1190103808 45841056 1627152
12:21:24 41635 8192000000 10148873424 10599649280 1166889376 12.468 100 37 73 1 1235940672 61357072 7694224
12:23:21 47425 8192000000 10128010816 10614566912 1163424416 12.435 100 7 14 0 1225386400 60457504 1504480
12:25:08 31968 8192000000 10152331056 10611752960 1163974896 12.440 100 38 76 0 1250393040 78399552 8018592
12:26:55 37637 8192000000 10127271584 10570870784 1169694288 12.494 100 26 52 5 1245380192 70112224 5573680
12:28:39 54127 8192000000 10185383216 10676236288 1161634944 12.419 99 17 34 0 1267533888 102222688 3676256
12:30:38 84222 8192000000 10085946240 10529366016 1134440528 12.163 97 13 26 0 1184191808 47047888 2703392
12:33:03 98866 8192000000 10092022112 10527449088 1118938448 12.017 98 44 88 1 1188245168 60048432 9258288
12:36:03 43845 8192000000 10092485904 10567720960 1133566192 12.155 99 19 38 0 1196147248 58660048 3921008
12:37:57 37886 8192000000 10070156000 10529361920 1123334944 12.058 99 6 12 0 1167177904 42869792 973168
12:39:41 87947 8192000000 10137620096 10626048000 1116146688 11.991 97 13 26 1 1191006880 71930160 2930032
12:42:53 71680 8192000000 10102435632 10582507520 1108113472 11.915 99 16 32 0 1239503424 127610656 3779296
12:45:07 43181 8192000000 10084148912 10573320192 1098736688 11.826 98 24 48 1 1196113296 92016736 5359872
12:46:52 36509 8192000000 10054740112 10492325888 1114144112 11.972 100 27 54 0 1155213280 36100048 4969120
12:48:29 19074 8192000000 10107240720 10532261888 1112115504 11.952 99 51 102 0 1177939488 55728992 10094992
12:49:53 52721 8192000000 10106327088 10529796096 1097305024 11.812 97 38 76 1 1173171280 68191680 7674576
12:51:46 44812 8192000000 10128023936 10615324672 1117285024 12.001 99 12 24 2 1281998160 161676896 3036240
12:53:42 89162 8192000000 10044732400 10498506752 1123839408 12.063 100 27 54 1 1168237120 37785696 6612016
12:56:13 64591 8192000000 10075129984 10551287808 1118656608 12.014 99 12 24 0 1164009072 42415616 2936848
12:58:19 69830 8192000000 10055026608 10513805312 1118712640 12.015 99 11 22 0 1159354896 38515728 2126528
13:00:37 51022 8192000000 10092449680 10528448512 1087345040 11.717 96 18 36 1 1156731664 65583008 3803616
13:02:28 48883 8192000000 10059912224 10492485632 1097636576 11.815 97 22 44 0 1142336480 39578768 5121136
13:04:22 55427 8192000000 10110750576 10556018688 1125721584 12.081 99 33 66 0 1196257184 63550288 6985312
13:06:23 102885 8192000000 10095656256 10578554880 1125623616 12.080 99 7 14 0 1160798448 33838480 1336352
13:09:14 80665 8192000000 10098253712 10600665088 1141912592 12.234 99 17 34 2 1238600400 93490976 3196832
13:11:40 36875 8192000000 10094250656 10544009216 1121072400 12.037 97 27 54 0 1160100160 33387168 5640592
13:13:31 124362 8192000000 10077800416 10527719424 1128729600 12.109 98 14 28 0 1166846432 34617344 3499488
13:16:47 59627 8192000000 10081194656 10564571136 1158216880 12.387 100 13 26 0 1202244128 41215552 2811696
13:18:47 79427 8192000000 10133766448 10607468544 1161544400 12.418 100 16 32 0 1224472784 59540480 3387904
13:21:17 83384 8192000000 10140717680 10636255232 1163508336 12.436 100 10 20 0 1240034672 74519008 2007328
13:23:42 66700 8192000000 10108238080 10581458944 1122846448 12.054 96 24 48 1 1181696816 53432208 5418160
13:25:57 48373 8192000000 10122780160 10576359424 1123216272 12.057 97 15 30 1 1197413712 71131936 3065504
13:27:48 77317 8192000000 10130125616 10575396864 1099095664 11.829 95 15 30 0 1166532416 64121616 3315136
13:30:08 79915 8192000000 10090550272 10578968576 1120469392 12.031 98 17 34 0 1202522864 78704928 3348544
13:32:29 21023 8192000000 10080514448 10510393344 1127436592 12.097 99 44 87 0 1161751952 24789680 9525680
13:33:52 112337 8192000000 10090053488 10530963456 1116132224 11.990 99 38 76 1 1169917248 45926128 7858896
13:36:52 20402 8192000000 9348404112 9668448256 481941280 5.556 43 6 12 0 499472656 16398624 1132752

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 03-09-2021 13:39:34 to 03-09-2021 13:39:49.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 70 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 03-09-2021 13:39:50 to 03-09-2021 13:39:52.

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          230550
MON$OLDEST_ACTIVE               231082
MON$OLDEST_SNAPSHOT             231082
MON$NEXT_TRANSACTION            231082
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                       2048544
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.2580 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2580 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2580 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2580 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17: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 ---
:20: 
:21: mscs-test-161.budzdorov.ru    Fri Sep  3 13:37:30 2021
:22:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:23: 
--- end of diff output ---

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

03.09.21 13:39:53. 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-09-03 13:39:55 No severe PSQL-related problems occured
03.09.21 13:39:57. End of report.