Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.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 16584320 kB
MemAvailable 31259804 kB
Buffers 0 kB
Cached 15113528 kB
SwapCached 4876 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-03 10:26:55
DTS_END                         2021-02-03 10:26:55.5010
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/46946
TRN_ID                          235309
ATT_ID                          640
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 8945.22 20571 1073426
customer order: creation 168.51 408 20221
customer order: refuse 14.43 326 1731
order to supplier: creation 43.66 1417 5239
order to supplier: removal 3.36 117725 403
invoice (draft): creation 45.01 3802 5401
invoice (draft): removal 51.25 967 6150
invoice accept: apply 40.86 18719 4903
invoice accept: cancel 45.43 851 5451
customer reserve: creation 3768.88 183 452265
customer reserve: removal 4469.58 69 536350
realization accept: apply 82.82 167 9938
realization accept: cancel 84.60 115 10152
payment from customer: creation 58.77 108 7052
payment from customer: removal 5.72 70 686
payment to supplier: creation 47.83 123 5739
payment to supplier: removal 3.42 76 410
service: total inventory turnovers 3.97 3170 476
service: total monetary turnovers 6.18 508 741
service: aggregate perf. data 0.94 13578 113
service: refresh index statistics 0.04 249041 5

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2848
TEST_TIME 2 3855
TEST_TIME 3 3978
TEST_TIME 4 4292
TEST_TIME 5 4452
TEST_TIME 6 5397
TEST_TIME 7 5185
TEST_TIME 8 5323
TEST_TIME 9 5458
TEST_TIME 10 5784
TEST_TIME 11 6055
TEST_TIME 12 6231
TEST_TIME 13 6401
TEST_TIME 14 6392
TEST_TIME 15 6496
TEST_TIME 16 6776
TEST_TIME 17 6826
TEST_TIME 18 7048
TEST_TIME 19 7486
TEST_TIME 20 7563
TEST_TIME 21 7648
TEST_TIME 22 8098
TEST_TIME 23 8357
TEST_TIME 24 8334
TEST_TIME 25 8740
TEST_TIME 26 9274
TEST_TIME 27 9525
TEST_TIME 28 10099
TEST_TIME 29 10924
TEST_TIME 30 11039
TEST_TIME 31 10853
TEST_TIME 32 10667
TEST_TIME 33 10472
TEST_TIME 34 10331
TEST_TIME 35 10208
TEST_TIME 36 10186
TEST_TIME 37 10090
TEST_TIME 38 10017
TEST_TIME 39 9978
TEST_TIME 40 9970
TEST_TIME 41 9963
TEST_TIME 42 9805
TEST_TIME 43 9636
TEST_TIME 44 9847
TEST_TIME 45 9949
TEST_TIME 46 9937
TEST_TIME 47 10010
TEST_TIME 48 10021
TEST_TIME 49 10040
TEST_TIME 50 9991
TEST_TIME 51 9937
TEST_TIME 52 9896
TEST_TIME 53 9906
TEST_TIME 54 10035
TEST_TIME 55 10035
TEST_TIME 56 9894
TEST_TIME 57 9748
TEST_TIME 58 9747
TEST_TIME 59 9663
TEST_TIME 60 9584
TEST_TIME 61 9513
TEST_TIME 62 9493
TEST_TIME 63 9436
TEST_TIME 64 9396
TEST_TIME 65 9493
TEST_TIME 66 9406
TEST_TIME 67 9455
TEST_TIME 68 9475
TEST_TIME 69 9470
TEST_TIME 70 9511
TEST_TIME 71 9502
TEST_TIME 72 9545
TEST_TIME 73 9547
TEST_TIME 74 9521
TEST_TIME 75 9599
TEST_TIME 76 9512
TEST_TIME 77 9390
TEST_TIME 78 9409
TEST_TIME 79 9353
TEST_TIME 80 9294
TEST_TIME 81 9212
TEST_TIME 82 9265
TEST_TIME 83 9275
TEST_TIME 84 9223
TEST_TIME 85 9287
TEST_TIME 86 9222
TEST_TIME 87 9299
TEST_TIME 88 9317
TEST_TIME 89 9275
TEST_TIME 90 9304
TEST_TIME 91 9393
TEST_TIME 92 9423
TEST_TIME 93 9412
TEST_TIME 94 9327
TEST_TIME 95 9292
TEST_TIME 96 9223
TEST_TIME 97 9231
TEST_TIME 98 9228
TEST_TIME 99 9185
TEST_TIME 100 9226
TEST_TIME 101 9205
TEST_TIME 102 9171
TEST_TIME 103 9128
TEST_TIME 104 9174
TEST_TIME 105 9189
TEST_TIME 106 9196
TEST_TIME 107 9238
TEST_TIME 108 9238
TEST_TIME 109 9210
TEST_TIME 110 9315
TEST_TIME 111 9254
TEST_TIME 112 9178
TEST_TIME 113 9152
TEST_TIME 114 9098
TEST_TIME 115 9059
TEST_TIME 116 9028
TEST_TIME 117 9019
TEST_TIME 118 8987
TEST_TIME 119 8961
TEST_TIME 120 8946

Performance per minute, chart


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

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 1491533 1491533 0 0.00 0 14578 7 0 0
doc_list_biud 1491533 1491533 0 0.00 0 99363 32 0 0
sp_add_invoice_to_stock 5013 4903 110 2.19 23 207569 18719 0 110
sp_cancel_adding_invoice 5451 5451 0 0.00 4 63162 852 0 0
sp_cancel_client_order 1731 1731 0 0.00 4 11443 326 0 0
sp_cancel_customer_reserve 536350 536350 0 0.00 1 43055 70 0 0
sp_cancel_pay_from_customer 686 686 0 0.00 3 3170 71 0 0
sp_cancel_pay_to_supplier 410 410 0 0.00 3 4484 77 0 0
sp_cancel_supplier_invoice 6155 6150 5 0.08 1 99367 968 0 5
sp_cancel_supplier_order 403 403 0 0.00 7 992633 117725 0 0
sp_cancel_write_off 10152 10152 0 0.00 1 8603 115 0 0
sp_client_order 20221 20221 0 0.00 4 23411 408 0 0
sp_customer_reserve 452265 452265 0 0.00 0 175726 184 0 0
sp_fill_shopping_cart 30853 30853 0 0.00 1 14706 45 0 0
sp_fill_shopping_cart_clo_res 452255 452255 0 0.00 0 21586 17 0 0
sp_get_clo_for_invoice 4893 4893 0 0.00 0 34848 399 0 0
sp_kill_cost_storno 16699 16699 0 0.00 0 8420 58 0 0
sp_kill_qstorno_ret_qs2qd 1089405 1089405 0 0.00 0 99360 38 0 0
sp_kill_qty_storno 575068 575068 0 0.00 0 99362 81 0 0
sp_lock_selected_doc 20367 20367 0 0.00 0 11039 5 0 0
sp_make_cost_storno 27622 27622 0 0.00 0 12334 53 0 0
sp_multiply_rows_for_pdistr 27622 27622 0 0.00 0 8817 30 0 0
sp_multiply_rows_for_qdistr 20221 20221 0 0.00 0 23296 220 0 0
sp_pay_from_customer 7052 7052 0 0.00 4 10511 108 0 0
sp_pay_to_supplier 5739 5739 0 0.00 5 11634 123 0 0
sp_payment_common 12791 12791 0 0.00 1 8138 18 0 0
sp_qd_handle_on_cancel_clo 1731 1731 0 0.00 0 9218 296 0 0
sp_qd_handle_on_invoice_upd_sts 10344 10344 0 0.00 0 25565 375 0 0
sp_qd_handle_on_reserve_upd_sts 9938 9938 0 0.00 0 15837 48 0 0
sp_reserve_write_off 9938 9938 0 0.00 6 15864 168 0 0
sp_supplier_invoice 5401 5401 0 0.00 7 96931 3803 0 0
sp_supplier_order 5239 5239 0 0.00 6 72811 1417 0 0
srv_aggregate_perf_data 113 113 0 0.00 2430 164703 13578 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1170 203811 45630 0 0
srv_find_qd_qs_mism 1026361 1026361 0 0.00 0 14577 6 0 0
srv_make_invnt_saldo 476 476 0 0.00 28 94256 3171 0 0
srv_make_money_saldo 741 741 0 0.00 1 16195 508 0 0
srv_recalc_idx_stat 5 5 0 0.00 147946 488009 249041 0 0
t$perf-abend:sp_add_invoice_to_stock 110 110 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4903 4903 0 0.00 0 14856 184 0 0
t$perf-norm:sp_cancel_adding_invoice 369 369 0 0.00 0 5533 138 0 0
t$perf-norm:sp_cancel_client_order 1731 1731 0 0.00 0 3006 15 0 0
t$perf-norm:sp_cancel_customer_reserve 595 595 0 0.00 0 5517 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 686 686 0 0.00 0 913 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 410 410 0 0.00 0 461 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 403 403 0 0.00 0 2762 30 0 0
t$perf-norm:sp_cancel_supplier_order 403 403 0 0.00 0 17043 1391 0 0
t$perf-norm:sp_cancel_write_off 940 940 0 0.00 0 1710 9 0 0
t$perf-norm:sp_client_order 20221 20221 0 0.00 0 6793 17 0 0
t$perf-norm:sp_customer_reserve 1018 1018 0 0.00 0 883 4 0 0
t$perf-norm:sp_pay_from_customer 7053 7053 0 0.00 0 10691 9 0 0
t$perf-norm:sp_pay_to_supplier 5739 5739 0 0.00 0 8269 11 0 0
t$perf-norm:sp_reserve_write_off 9938 9938 0 0.00 0 3695 8 0 0
t$perf-norm:sp_supplier_invoice 5401 5401 0 0.00 0 3644 17 0 0
t$perf-norm:sp_supplier_order 5239 5239 0 0.00 0 5189 16 0 0
t$perf-norm:srv_aggregate_perf_data 113 113 0 0.00 0 420 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 6 1 0 0
t$perf-norm:srv_make_invnt_saldo 476 476 0 0.00 0 6238 25 0 0
t$perf-norm:srv_make_money_saldo 741 741 0 0.00 0 2739 14 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 9 4 0 0
v_add_invoice_to_stock 10004 9784 220 2.19 0 5630 17 0 220
v_all_customers 37462 37462 0 0.00 0 62 1 0 0
v_all_suppliers 29746 29746 0 0.00 0 201 1 0 0
v_all_wares 40440 40440 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 738 738 0 0.00 0 1639 11 0 0
v_cancel_client_order 3462 3462 0 0.00 0 3412 5 0 0
v_cancel_customer_prepayment 1372 1372 0 0.00 0 2544 7 0 0
v_cancel_customer_reserve 1190 1190 0 0.00 0 606 3 0 0
v_cancel_payment_to_supplier 820 820 0 0.00 0 730 4 0 0
v_cancel_supplier_invoice 816 806 10 1.22 0 466 12 0 10
v_cancel_supplier_order 806 806 0 0.00 0 727 5 0 0
v_cancel_write_off 1880 1880 0 0.00 0 1776 13 0 0
v_max_id_clo_ord 5239 5239 0 0.00 0 2433 2 0 0
v_max_id_clo_res 1018 1018 0 0.00 0 1963 16 0 0
v_max_id_ord_sup 5394 5394 0 0.00 0 5108 5 0 0
v_max_non_paid_invoice 1499 1499 0 0.00 0 36 2 0 0
v_max_non_paid_realizn 3629 3629 0 0.00 0 2410 3 0 0
v_min_id_clo_ord 5239 5239 0 0.00 0 2649 5 0 0
v_min_id_clo_res 1018 1018 0 0.00 0 6700 9 0 0
v_min_id_ord_sup 5394 5394 0 0.00 0 7179 10 0 0
v_min_non_paid_invoice 5739 5739 0 0.00 0 8133 11 0 0
v_min_non_paid_realizn 7052 7052 0 0.00 0 8133 13 0 0
v_our_firm 9824 9824 0 0.00 0 14 1 0 0
v_reserve_write_off 19876 19876 0 0.00 0 3363 5 0 0
x_lock_depdocs_on_canc_invoice 11198 11198 0 0.00 0 23254 113 0 0
x_lock_depdocs_on_canc_sup_ord 403 403 0 0.00 0 24559 402 0 0
x_make_qstorno_1000_1200 5239 5239 0 0.00 2 71878 1346 0 0
x_make_qstorno_1200_2000 5401 5401 0 0.00 3 93503 3622 0 0
x_make_qstorno_2100_3300 452265 452265 0 0.00 0 175701 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-02-2021 10:28:36 to 03-02-2021 10:28:40.

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:56:59 1 8192000000 8796640128 8807108608 3168304 0.038 1 1 2 0 3265920 61840 35776
07:57:59 25535 8192000000 9718828736 10011926528 799650720 8.893 100 86 172 0 827659952 7334512 20674720
07:59:31 29715 8192000000 9764870112 10068967424 836924352 9.269 100 86 172 0 870530288 12904384 20701552
08:01:06 16582 8192000000 9806919792 10127699968 865958128 9.560 100 78 156 0 909440448 25420240 18062080
08:02:28 12556 8192000000 9869215328 10201382912 919148800 10.088 100 82 162 0 976230976 39260720 17821456
08:03:43 6918 8192000000 9915607632 10259206144 963270560 10.521 100 78 155 0 1029780272 50638112 15871600
08:04:52 5493 8192000000 9950451296 10305552384 996316304 10.843 100 78 156 1 1070478576 58464256 15698016
08:06:00 3528 8192000000 10011438816 10374955008 1019802240 11.070 100 83 166 1 1124131632 87172960 17156432
08:07:05 2378 8192000000 10007738368 10376609792 1043749232 11.301 100 84 170 0 1124251296 63731072 16770992
08:08:08 1424 8192000000 10029467376 10397450240 1055935312 11.418 100 89 179 0 1142218720 68395616 17887792
08:09:10 1188 8192000000 9996004256 10373099520 1061795232 11.474 100 78 156 0 1111967440 32762112 17410096
08:10:12 7971 8192000000 10003716688 10396770304 1072021152 11.571 100 47 93 1 1110045856 28421920 9602784
08:11:24 5772 8192000000 10004957536 10397360128 1080563168 11.653 100 49 98 3 1116912816 25750752 10598896
08:12:30 28490 8192000000 10010934032 10414161920 1082572672 11.672 100 34 68 2 1122643424 32140864 7929888
08:14:00 5344 8192000000 10025331376 10426314752 1087277040 11.717 100 33 66 2 1128807872 34372112 7158720
08:15:06 6250 8192000000 10006133904 10403946496 1083844672 11.684 100 45 90 0 1109293536 14914656 10534208
08:16:13 9894 8192000000 10004083888 10408734720 1052171680 11.382 97 38 74 2 1088412784 27997264 8243840
08:17:27 10153 8192000000 9972982080 10374926336 1046739376 11.329 98 27 52 3 1075280992 23001600 5540016
08:18:43 6724 8192000000 9946331824 10326601728 1011392528 10.989 96 49 94 2 1043590112 21444992 10752592
08:19:50 7683 8192000000 9933655792 10311266304 974187024 10.628 95 28 56 0 1005411680 25125568 6099088
08:21:00 15971 8192000000 9924508064 10315907072 979288096 10.677 95 40 81 2 1015279216 26903552 9087568
08:22:18 4460 8192000000 9941282096 10309681152 1005103616 10.928 97 70 140 0 1033230144 13920672 14205856
08:23:23 5737 8192000000 9977701504 10378919936 1051573536 11.376 100 45 90 2 1086678720 25454432 9650752
08:24:32 6945 8192000000 9978155536 10370023424 1058984160 11.447 100 32 64 0 1083759584 17656304 7119120
08:25:41 7916 8192000000 9991585040 10388631552 1072455344 11.576 100 39 79 1 1102308976 22038224 7815408
08:26:52 36300 8192000000 10019073936 10415120384 1092267632 11.764 100 44 88 0 1125649872 24321936 9060304
08:28:34 18732 8192000000 10046006928 10457911296 1105712624 11.892 100 35 70 1 1171335056 58053664 7568768
08:30:00 18356 8192000000 10078485216 10486648832 1123947904 12.064 100 43 87 0 1181726112 48758768 9019440
08:31:23 3374 8192000000 10109419456 10512637952 1129449888 12.116 100 52 104 1 1214912768 73853008 11609872
08:32:29 3305 8192000000 10159982400 10559254528 1143036768 12.244 100 66 132 1 1250186784 93880352 13269664
08:33:36 10723 8192000000 10176445280 10593193984 1148280096 12.293 100 58 116 0 1276190944 115954800 11956048
08:34:53 5721 8192000000 10170627888 10579542016 1135543408 12.174 98 58 117 1 1254961952 105829952 13588592
08:36:07 16226 8192000000 10202925616 10616639488 1162505872 12.427 100 63 126 2 1313528304 136882304 14140128
08:37:49 8597 8192000000 10188702768 10604478464 1173021120 12.525 100 50 99 1 1291858320 107495824 11341376
08:39:11 7734 8192000000 10269420528 10676895744 1179303616 12.584 100 69 138 1 1365879392 171529696 15046080
08:40:24 2639 8192000000 10262605264 10671316992 1177972528 12.571 100 66 131 2 1371728992 178164480 15591984
08:41:33 5275 8192000000 10290359008 10699698176 1177535152 12.567 100 70 140 0 1391327760 198562000 15230608
08:42:43 4049 8192000000 10325730704 10733158400 1171116080 12.507 100 72 141 2 1421042112 233450464 16475568
08:43:55 1569 8192000000 10389572128 10783592448 1178265392 12.574 100 97 194 0 1481555344 281600256 21689696
08:45:02 1974 8192000000 10380893120 10778742784 1163361888 12.435 99 91 180 1 1470859440 286724048 20773504
08:46:08 8839 8192000000 10385791792 10798432256 1150456688 12.314 98 50 100 2 1472820352 309936160 12427504
08:47:22 1167 8192000000 10422208272 10812280832 1148800688 12.298 100 88 174 2 1521455600 352606704 20048208
08:48:26 3749 8192000000 10484606240 10913161216 1160830816 12.411 100 50 97 0 1592195536 418869056 12495664
08:49:33 11537 8192000000 10428497856 10860085248 1153116128 12.339 100 46 92 0 1546402576 381077824 12208624
08:50:54 21605 8192000000 10441978112 10901368832 1132999136 12.150 99 37 74 0 1539258432 396218832 10040464
08:52:19 15997 8192000000 10354274688 10804850688 1140115296 12.217 100 28 56 0 1459487920 311854816 7517808
08:53:37 18248 8192000000 10326190128 10804088832 1112452768 11.956 99 26 52 0 1419749904 300351824 6945312
08:55:51 49892 8192000000 10044607168 10523987968 1082408896 11.670 97 10 20 2 1115593984 30797904 2387184
08:57:43 95555 8192000000 10041694192 10477780992 1082147600 11.668 98 39 78 1 1122485776 31892096 8446080
09:00:24 60720 8192000000 10083181152 10510569472 1107533472 11.909 99 23 46 1 1173744960 61862016 4349472
09:02:26 68184 8192000000 10111713104 10542444544 1122697824 12.052 99 41 82 3 1185661808 53892368 9071616
09:04:47 203797 8192000000 10133736592 10607226880 1146029840 12.272 100 29 58 1 1257877184 105319840 6527504
09:09:45 75897 8192000000 10156668320 10678546432 1153661056 12.344 100 15 30 0 1300810176 143461264 3687856
09:12:06 47363 8192000000 10141558160 10613370880 1141130688 12.226 99 10 20 2 1210685248 67453152 2101408
09:13:54 90381 8192000000 10101190512 10591707136 1118239536 12.010 97 12 24 0 1194605152 74086192 2279424
09:16:25 52759 8192000000 10100502912 10533384192 1127335760 12.096 98 34 68 0 1170974480 37093344 6545376
09:18:21 24809 8192000000 10134770608 10568941568 1131609600 12.137 99 30 60 2 1204480400 66597936 6272864
09:20:39 87608 8192000000 10093876480 10541989888 1109090720 11.924 97 13 26 1 1140180272 28385104 2704448
09:23:12 70859 8192000000 10118586048 10568974336 1137902368 12.196 100 27 54 0 1204458048 60409216 6146464
09:25:40 52873 8192000000 10057230944 10539388928 1136070880 12.179 100 9 18 1 1175416416 37470816 1874720
09:27:33 70493 8192000000 10058329392 10545053696 1087365808 11.718 96 11 22 0 1135472208 45507232 2599168
09:29:44 67638 8192000000 10121089232 10566938624 1131334144 12.134 99 27 52 0 1202860288 65543696 5982448
09:31:53 60700 8192000000 10096315424 10521120768 1085309184 11.698 95 24 48 0 1139381632 48966896 5105552
09:33:54 52216 8192000000 10048860976 10480701440 1079270864 11.641 96 14 28 0 1134347936 51593136 3483936
09:35:48 37672 8192000000 10127231760 10557472768 1130987952 12.131 99 33 66 1 1209347216 71672432 6686832
09:37:31 37869 8192000000 10094268560 10538795008 1107558240 11.909 98 31 62 0 1176139328 62330624 6250464
09:39:11 22852 8192000000 10052362256 10469830656 1071113840 11.563 98 35 70 0 1124537696 46118144 7305712
09:40:36 123665 8192000000 10077368752 10534916096 1094316160 11.784 98 13 26 0 1176780496 79415136 3049200
09:43:56 62639 8192000000 10068902704 10556637184 1101459648 11.851 99 19 38 1 1175151968 69835232 3857088
09:46:06 81227 8192000000 10098457344 10573144064 1084241088 11.688 97 8 16 0 1141625104 55531504 1852512
09:49:15 70766 8192000000 10081367968 10525118464 1097911584 11.818 98 28 57 1 1154828384 50322832 6593968
09:51:30 53889 8192000000 10081317328 10515066880 1071916368 11.570 96 23 45 1 1144103216 67282112 4904736
09:53:38 51673 8192000000 10090579104 10517217280 1106356656 11.898 99 19 36 2 1170476816 60277424 3842736
09:55:30 33739 8192000000 10090608640 10514067456 1109064400 11.924 99 46 93 2 1178227808 59663008 9500400
09:57:26 92337 8192000000 10101463456 10605277184 1125153392 12.076 100 9 18 0 1203303392 76816320 1333680
10:00:05 117412 8192000000 10093453712 10569392128 1141661904 12.231 100 16 32 0 1196765216 51759648 3343664
10:03:03 47505 8192000000 10083932288 10594029568 1145012192 12.263 100 4 8 0 1194158384 48601456 544736
10:04:51 49177 8192000000 10074275136 10513915904 1125330528 12.077 98 17 33 0 1153725360 25213824 3181008
10:06:40 95845 8192000000 10083683280 10546966528 1124743984 12.072 98 16 33 0 1179958256 51537664 3676608
10:09:17 20650 8192000000 10125546800 10548445184 1143883120 12.252 100 62 124 0 1214873168 58158752 12831296
10:10:43 37417 8192000000 10130264592 10562867200 1103561680 11.871 96 29 59 1 1155769248 45938496 6269072
10:12:38 46320 8192000000 10087432624 10556252160 1129281344 12.115 99 22 44 1 1165309856 31472624 4555888
10:14:31 44902 8192000000 10087921936 10506457088 1111606448 11.948 97 27 54 0 1141194304 23946496 5641360
10:16:29 81060 8192000000 10085801968 10604351488 1139197344 12.208 100 11 22 1 1238517344 97096448 2223552
10:19:38 52021 8192000000 10063400416 10516537344 1127544800 12.098 98 4 8 0 1147815728 19830032 440896
10:21:32 59527 8192000000 10112215088 10577416192 1146225312 12.274 99 12 24 1 1191043184 42224480 2593392
10:23:39 63342 8192000000 10056919104 10544488448 1094866752 11.789 96 10 20 0 1131172976 34313760 1992464
10:25:42 33664 8192000000 10115435984 10559750144 1133420832 12.154 99 27 53 0 1188989312 49681344 5887136

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-02-2021 10:28:42 to 03-02-2021 10:28:57.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 110 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 03-02-2021 10:28:58 to 03-02-2021 10:29:00.

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          230832
MON$OLDEST_ACTIVE               235503
MON$OLDEST_SNAPSHOT             235503
MON$NEXT_TRANSACTION            235503
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                       2099648
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             799
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2357 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2357 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2357 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.2357 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 ---
:39: 
:40: mscs-test-161.budzdorov.ru    Wed Feb  3 10:26:56 2021
:41:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:42: 
:43: 
:44: mscs-test-161.budzdorov.ru    Wed Feb  3 10:26:58 2021
:45:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:46: 
:47: 
:48: mscs-test-161.budzdorov.ru    Wed Feb  3 10:27:01 2021
:49:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:50: 
--- end of diff output ---

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

03.02.21 10:29:01. 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-03 10:29:03 No severe PSQL-related problems occured
03.02.21 10:29:05. End of report.