Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.01.2021 04: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 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 16510284 kB
MemAvailable 31258604 kB
Buffers 20 kB
Cached 15133572 kB
SwapCached 4868 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-01-29 04:26:54
DTS_END                         2021-01-29 04:26:54.9270
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/54048
TRN_ID                          233651
ATT_ID                          615
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: 9003.18 22765 1080382
customer order: creation 167.34 395 20081
customer order: refuse 14.22 339 1706
order to supplier: creation 44.75 1363 5370
order to supplier: removal 3.31 118051 397
invoice (draft): creation 45.21 3466 5425
invoice (draft): removal 50.30 962 6036
invoice accept: apply 41.59 16236 4991
invoice accept: cancel 46.06 913 5527
customer reserve: creation 3819.43 157 458332
customer reserve: removal 4484.58 69 538149
realization accept: apply 82.73 184 9928
realization accept: cancel 79.73 109 9568
payment from customer: creation 58.23 114 6987
payment from customer: removal 5.41 74 649
payment to supplier: creation 46.50 126 5580
payment to supplier: removal 3.33 87 400
service: total inventory turnovers 3.66 3819 439
service: total monetary turnovers 5.88 624 706
service: aggregate perf. data 0.89 14011 107
service: refresh index statistics 0.03 294209 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-01-2021 04:28:56 to 29-01-2021 04:28:58.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2270
TEST_TIME 2 4006
TEST_TIME 3 4762
TEST_TIME 4 4826
TEST_TIME 5 5019
TEST_TIME 6 5124
TEST_TIME 7 5281
TEST_TIME 8 5661
TEST_TIME 9 5667
TEST_TIME 10 5592
TEST_TIME 11 5513
TEST_TIME 12 5690
TEST_TIME 13 5884
TEST_TIME 14 6102
TEST_TIME 15 6430
TEST_TIME 16 6367
TEST_TIME 17 6810
TEST_TIME 18 6985
TEST_TIME 19 7285
TEST_TIME 20 7578
TEST_TIME 21 8155
TEST_TIME 22 8093
TEST_TIME 23 8441
TEST_TIME 24 9139
TEST_TIME 25 9200
TEST_TIME 26 9263
TEST_TIME 27 10675
TEST_TIME 28 10823
TEST_TIME 29 10826
TEST_TIME 30 10916
TEST_TIME 31 10968
TEST_TIME 32 10765
TEST_TIME 33 10606
TEST_TIME 34 10429
TEST_TIME 35 10321
TEST_TIME 36 10187
TEST_TIME 37 9989
TEST_TIME 38 10056
TEST_TIME 39 9969
TEST_TIME 40 9888
TEST_TIME 41 10150
TEST_TIME 42 10062
TEST_TIME 43 9939
TEST_TIME 44 9811
TEST_TIME 45 9681
TEST_TIME 46 9628
TEST_TIME 47 9679
TEST_TIME 48 9822
TEST_TIME 49 9711
TEST_TIME 50 9743
TEST_TIME 51 9751
TEST_TIME 52 9749
TEST_TIME 53 9715
TEST_TIME 54 9721
TEST_TIME 55 9849
TEST_TIME 56 9819
TEST_TIME 57 10024
TEST_TIME 58 9951
TEST_TIME 59 9913
TEST_TIME 60 9786
TEST_TIME 61 9688
TEST_TIME 62 9588
TEST_TIME 63 9508
TEST_TIME 64 9412
TEST_TIME 65 9476
TEST_TIME 66 9423
TEST_TIME 67 9414
TEST_TIME 68 9374
TEST_TIME 69 9331
TEST_TIME 70 9280
TEST_TIME 71 9309
TEST_TIME 72 9353
TEST_TIME 73 9404
TEST_TIME 74 9495
TEST_TIME 75 9643
TEST_TIME 76 9639
TEST_TIME 77 9699
TEST_TIME 78 9654
TEST_TIME 79 9581
TEST_TIME 80 9486
TEST_TIME 81 9401
TEST_TIME 82 9376
TEST_TIME 83 9322
TEST_TIME 84 9341
TEST_TIME 85 9294
TEST_TIME 86 9274
TEST_TIME 87 9230
TEST_TIME 88 9206
TEST_TIME 89 9201
TEST_TIME 90 9259
TEST_TIME 91 9257
TEST_TIME 92 9240
TEST_TIME 93 9220
TEST_TIME 94 9284
TEST_TIME 95 9279
TEST_TIME 96 9353
TEST_TIME 97 9346
TEST_TIME 98 9306
TEST_TIME 99 9245
TEST_TIME 100 9242
TEST_TIME 101 9231
TEST_TIME 102 9211
TEST_TIME 103 9174
TEST_TIME 104 9161
TEST_TIME 105 9143
TEST_TIME 106 9148
TEST_TIME 107 9139
TEST_TIME 108 9168
TEST_TIME 109 9134
TEST_TIME 110 9143
TEST_TIME 111 9148
TEST_TIME 112 9178
TEST_TIME 113 9198
TEST_TIME 114 9217
TEST_TIME 115 9151
TEST_TIME 116 9102
TEST_TIME 117 9077
TEST_TIME 118 9052
TEST_TIME 119 9020
TEST_TIME 120 9004

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 29-01-2021 04:28:59 to 29-01-2021 04:29:03.

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 1503921 1503921 0 0.00 0 32822 6 0 0
doc_list_biud 1503921 1503921 0 0.00 0 35026 31 0 0
sp_add_invoice_to_stock 5090 4991 99 1.94 19 175993 16237 0 99
sp_cancel_adding_invoice 5527 5527 0 0.00 5 73237 913 0 0
sp_cancel_client_order 1706 1706 0 0.00 5 17487 339 0 0
sp_cancel_customer_reserve 538149 538149 0 0.00 1 27712 70 0 0
sp_cancel_pay_from_customer 649 649 0 0.00 3 5848 75 0 0
sp_cancel_pay_to_supplier 400 400 0 0.00 3 3570 87 0 0
sp_cancel_supplier_invoice 6048 6036 12 0.19 2 28203 962 0 12
sp_cancel_supplier_order 397 397 0 0.00 7 1364312 118051 0 0
sp_cancel_write_off 9568 9568 0 0.00 1 9421 110 0 0
sp_client_order 20081 20081 0 0.00 4 27567 395 0 0
sp_customer_reserve 458332 458332 0 0.00 0 120460 158 0 0
sp_fill_shopping_cart 30859 30859 0 0.00 1 18731 43 0 0
sp_fill_shopping_cart_clo_res 458331 458331 0 0.00 0 22669 17 0 0
sp_get_clo_for_invoice 4990 4990 0 0.00 0 46412 441 0 0
sp_kill_cost_storno 16144 16144 0 0.00 0 8536 50 0 0
sp_kill_qstorno_ret_qs2qd 1092299 1092299 0 0.00 0 28170 38 0 0
sp_kill_qty_storno 576301 576301 0 0.00 0 35026 81 0 0
sp_lock_selected_doc 20475 20475 0 0.00 0 8472 4 0 0
sp_make_cost_storno 27485 27485 0 0.00 0 14682 56 0 0
sp_multiply_rows_for_pdistr 27485 27485 0 0.00 0 8821 33 0 0
sp_multiply_rows_for_qdistr 20081 20081 0 0.00 0 18454 205 0 0
sp_pay_from_customer 6987 6987 0 0.00 4 13279 114 0 0
sp_pay_to_supplier 5580 5580 0 0.00 5 16516 126 0 0
sp_payment_common 12567 12567 0 0.00 1 11474 19 0 0
sp_qd_handle_on_cancel_clo 1706 1706 0 0.00 0 17480 302 0 0
sp_qd_handle_on_invoice_upd_sts 10517 10517 0 0.00 0 35026 395 0 0
sp_qd_handle_on_reserve_upd_sts 9928 9928 0 0.00 0 13208 61 0 0
sp_reserve_write_off 9928 9928 0 0.00 6 17719 185 0 0
sp_supplier_invoice 5425 5425 0 0.00 7 96021 3466 0 0
sp_supplier_order 5370 5370 0 0.00 5 30051 1364 0 0
srv_aggregate_perf_data 107 107 0 0.00 2726 161425 14011 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1246 140550 48705 0 0
srv_find_qd_qs_mism 1033311 1033311 0 0.00 0 32821 5 0 0
srv_make_invnt_saldo 439 439 0 0.00 51 119539 3819 0 0
srv_make_money_saldo 706 706 0 0.00 1 16303 625 0 0
srv_recalc_idx_stat 4 4 0 0.00 130272 520165 294210 0 0
t$perf-abend:sp_add_invoice_to_stock 99 99 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4991 4991 0 0.00 0 18976 195 0 0
t$perf-norm:sp_cancel_adding_invoice 413 413 0 0.00 1 2674 82 0 0
t$perf-norm:sp_cancel_client_order 1706 1706 0 0.00 0 4719 15 0 0
t$perf-norm:sp_cancel_customer_reserve 634 634 0 0.00 0 2642 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 649 649 0 0.00 0 1488 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 400 400 0 0.00 0 5611 21 0 0
t$perf-norm:sp_cancel_supplier_invoice 386 386 0 0.00 0 737 13 0 0
t$perf-norm:sp_cancel_supplier_order 397 397 0 0.00 0 23342 1611 0 0
t$perf-norm:sp_cancel_write_off 972 972 0 0.00 0 5950 10 0 0
t$perf-norm:sp_client_order 20081 20081 0 0.00 0 13667 20 0 0
t$perf-norm:sp_customer_reserve 959 959 0 0.00 0 8574 20 0 0
t$perf-norm:sp_pay_from_customer 6987 6987 0 0.00 0 7366 11 0 0
t$perf-norm:sp_pay_to_supplier 5580 5580 0 0.00 0 3912 11 0 0
t$perf-norm:sp_reserve_write_off 9928 9928 0 0.00 0 7347 10 0 0
t$perf-norm:sp_supplier_invoice 5425 5425 0 0.00 0 6984 20 0 0
t$perf-norm:sp_supplier_order 5370 5370 0 0.00 0 15967 21 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 627 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 262 7 0 0
t$perf-norm:srv_make_invnt_saldo 439 439 0 0.00 0 1171 12 0 0
t$perf-norm:srv_make_money_saldo 706 706 0 0.00 0 600 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 10 4 0 0
v_add_invoice_to_stock 10178 9980 198 1.94 0 5490 16 0 198
v_all_customers 36506 36506 0 0.00 0 118 1 0 0
v_all_suppliers 29686 29686 0 0.00 0 21 1 0 0
v_all_wares 40162 40162 0 0.00 0 27 1 0 0
v_cancel_adding_invoice 826 826 0 0.00 0 6438 21 0 0
v_cancel_client_order 3412 3412 0 0.00 0 5266 8 0 0
v_cancel_customer_prepayment 1298 1298 0 0.00 0 1826 6 0 0
v_cancel_customer_reserve 1268 1268 0 0.00 0 2837 9 0 0
v_cancel_payment_to_supplier 800 800 0 0.00 0 1260 7 0 0
v_cancel_supplier_invoice 796 772 24 3.01 0 1623 18 0 24
v_cancel_supplier_order 794 794 0 0.00 0 250 4 0 0
v_cancel_write_off 1944 1944 0 0.00 0 1907 12 0 0
v_max_id_clo_ord 5370 5370 0 0.00 0 493 1 0 0
v_max_id_clo_res 959 959 0 0.00 0 2885 12 0 0
v_max_id_ord_sup 5408 5408 0 0.00 0 9609 5 0 0
v_max_non_paid_invoice 1515 1515 0 0.00 0 1329 3 0 0
v_max_non_paid_realizn 3826 3826 0 0.00 0 6119 5 0 0
v_min_id_clo_ord 5370 5370 0 0.00 0 3880 5 0 0
v_min_id_clo_res 959 959 0 0.00 0 5033 9 0 0
v_min_id_ord_sup 5408 5408 0 0.00 0 5644 9 0 0
v_min_non_paid_invoice 5580 5580 0 0.00 0 11466 12 0 0
v_min_non_paid_realizn 6987 6987 0 0.00 0 6370 14 0 0
v_our_firm 9978 9978 0 0.00 0 19 1 0 0
v_reserve_write_off 19856 19856 0 0.00 0 5212 6 0 0
x_lock_depdocs_on_canc_invoice 11177 11177 0 0.00 0 14181 105 0 0
x_lock_depdocs_on_canc_sup_ord 397 397 0 0.00 0 22855 380 0 0
x_make_qstorno_1000_1200 5370 5370 0 0.00 2 30042 1285 0 0
x_make_qstorno_1200_2000 5425 5425 0 0.00 3 95978 3297 0 0
x_make_qstorno_2100_3300 458332 458332 0 0.00 0 120448 137 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-01-2021 04:29:04 to 29-01-2021 04:29:08.

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
01:56:58 1 8192000000 8796640128 8807108608 3168304 0.038 1 1 2 0 3265920 61840 35776
01:57:59 32139 8192000000 9715467296 10009370624 792414544 8.819 100 89 178 1 821843104 7827728 21600832
01:59:42 25178 8192000000 9757235168 10062233600 829576000 9.195 100 89 178 0 864430000 13385552 21468448
02:01:11 11734 8192000000 9798196800 10114203648 862648608 9.527 100 91 183 0 909919120 26408752 20861760
02:02:28 14708 8192000000 9864541024 10195623936 912800560 10.025 100 79 159 1 981288384 50152688 18335136
02:03:47 9205 8192000000 9899000592 10246500352 958318832 10.473 100 74 146 1 1013817792 39825200 15673760
02:05:04 4477 8192000000 9958279504 10313252864 995071040 10.831 100 79 157 0 1068182624 57142336 15969248
02:06:12 4884 8192000000 10010397280 10374094848 1023220048 11.103 100 78 156 1 1117923616 78749760 15953808
02:07:18 1406 8192000000 10022274208 10386894848 1052497264 11.385 100 85 168 0 1131276752 61944160 16835328
02:08:21 22814 8192000000 9998658032 10395971584 1064292032 11.498 100 54 109 1 1120173232 44310752 11570448
02:09:45 4970 8192000000 10001963488 10381017088 1074366736 11.594 100 43 87 1 1118421168 34606752 9447680
02:10:51 7163 8192000000 9988968000 10382536704 1086268944 11.707 100 36 68 3 1104040608 9668688 8102976
02:12:02 62106 8192000000 10006047920 10421604352 1092347008 11.765 100 38 74 0 1116317088 16484784 7485296
02:14:04 8268 8192000000 10013941936 10421190656 1099177856 11.830 100 29 58 2 1133582496 28066864 6337776
02:15:15 8180 8192000000 10019122352 10421219328 1092189952 11.763 99 19 38 0 1116464640 20358240 3916448
02:16:26 36215 8192000000 10027659408 10448699392 1096915200 11.808 100 35 70 0 1138352656 33239008 8198448
02:18:27 5218 8192000000 10012139440 10402557952 1062064592 11.476 99 48 96 0 1109143648 37499616 9579440
02:19:35 4948 8192000000 9950279808 10336808960 1024517184 11.116 98 36 71 1 1060030336 27117904 8395248
02:20:42 4743 8192000000 9941155328 10308493312 1011989680 10.995 99 42 81 1 1056728944 36162944 8576320
02:21:47 45302 8192000000 9931580400 10321842176 981610592 10.700 95 33 66 1 1012582224 23848544 7123088
02:23:35 4648 8192000000 9969960352 10350927872 1048790000 11.349 100 52 102 3 1073044528 11660272 12594256
02:24:41 7115 8192000000 9981340464 10373201920 1061282112 11.469 100 37 75 2 1084051200 14312032 8457056
02:25:50 4880 8192000000 9984542192 10373832704 1071377696 11.565 100 35 67 1 1105616592 27495712 6743184
02:26:56 37387 8192000000 9991452368 10392133632 1090837312 11.751 100 30 60 0 1108941552 10961696 7142544
02:28:43 6058 8192000000 10063067872 10456756224 1113637328 11.967 100 56 112 0 1168523568 42673552 12212688
02:29:56 10905 8192000000 10101970384 10498859008 1131211888 12.133 100 48 93 1 1209374944 68700080 9462976
02:31:15 15566 8192000000 10123327696 10530275328 1150622560 12.315 100 43 84 0 1229814752 69257360 9934832
02:32:34 2529 8192000000 10179064608 10579603456 1167688800 12.475 100 76 153 1 1290466192 106539824 16237568
02:33:46 7517 8192000000 10229099136 10637615104 1174735440 12.541 100 56 112 1 1334132432 147108848 12288144
02:34:59 27167 8192000000 10228911760 10649870336 1183373504 12.622 100 47 93 3 1339330976 145286608 10670864
02:36:33 13853 8192000000 10243698512 10667343872 1188704736 12.671 100 39 78 2 1353326192 156004336 8617120
02:37:50 10431 8192000000 10292934736 10709106688 1178507584 12.576 99 57 114 0 1401317744 209763968 13046192
02:39:05 13666 8192000000 10350861696 10778873856 1199748688 12.774 100 65 129 0 1456478480 241279872 15449920
02:40:25 4165 8192000000 10355070224 10769502208 1193748944 12.718 100 82 162 1 1458917792 246594192 18574656
02:41:35 3125 8192000000 10391213536 10795855872 1180581936 12.596 100 79 158 1 1495342080 296620400 18139744
02:42:43 2794 8192000000 10377901232 10776150016 1145054896 12.263 100 83 165 0 1483800464 318773792 19971776
02:43:52 1245 8192000000 10400081984 10799099904 1152156144 12.330 100 89 179 1 1499406736 326832432 20418160
02:44:58 2525 8192000000 10424302144 10819411968 1144329232 12.256 100 84 168 0 1529610624 366326624 18954768
02:46:09 5059 8192000000 10448442416 10848882688 1142391920 12.238 100 85 166 3 1543781200 382721040 18668240
02:47:19 11769 8192000000 10384376080 10804813824 1147843504 12.289 100 36 72 0 1481684640 325112752 8728384
02:48:47 23270 8192000000 10381247760 10804588544 1137826064 12.195 99 41 80 1 1516033808 367834192 10373552
02:50:17 22499 8192000000 10340728784 10826215424 1130344656 12.125 100 25 50 1 1475810448 338518320 6947472
02:51:50 34928 8192000000 10318613424 10749321216 1094751568 11.788 98 25 50 0 1422011232 320512544 6747120
02:53:27 11599 8192000000 10207033600 10768748544 1115659920 11.986 100 24 48 2 1368784848 247057232 6067696
02:54:48 65542 8192000000 10085369504 10563538944 1122290496 12.049 100 10 19 0 1174340752 50213664 1836592
02:56:55 30243 8192000000 10079642880 10506104832 1126221504 12.086 99 59 116 0 1155768848 16653504 12893840
02:58:35 27483 8192000000 10056244112 10472747008 1119827456 12.025 99 41 85 0 1146715776 17552144 9336176
03:00:09 56426 8192000000 10097617952 10523758592 1098249552 11.821 97 33 67 1 1160631936 55501408 6880976
03:03:17 123709 8192000000 10133030064 10594570240 1111965600 11.951 98 22 44 1 1193568800 77483728 4119472
03:06:21 81617 8192000000 10124614560 10653655040 1139714640 12.213 100 23 46 0 1276155360 130962560 5478160
03:08:45 53113 8192000000 10095132592 10538528768 1133161264 12.151 99 27 54 1 1178994848 39917408 5916176
03:10:43 23449 8192000000 10132231824 10580070400 1136105936 12.179 99 18 36 1 1226694608 86331824 4256848
03:12:06 34748 8192000000 10168345968 10614784000 1134239392 12.161 99 19 38 1 1258289280 119938224 4111664
03:13:46 47357 8192000000 10113694640 10582654976 1135070624 12.169 99 14 28 0 1221310096 83334272 2905200
03:15:42 37276 8192000000 10070510976 10506293248 1145970080 12.272 100 32 64 1 1181044576 28207616 6866880
03:17:20 51996 8192000000 10109796992 10549354496 1117850912 12.007 97 29 59 0 1174993808 50174896 6968000
03:19:13 56038 8192000000 10134212864 10585190400 1139092560 12.207 99 15 30 0 1221315184 78988288 3234336
03:21:29 120189 8192000000 10154011424 10664697856 1135185664 12.170 98 19 38 1 1204895472 65705904 4003904
03:25:13 61770 8192000000 10072401648 10518622208 1114494464 11.975 98 25 50 0 1154382544 35081632 4806448
03:27:53 26057 8192000000 10066844880 10531942400 1131862032 12.139 99 17 32 1 1158373296 23789168 2722096
03:29:24 57118 8192000000 10076484256 10541977600 1096773568 11.807 96 8 16 0 1157522416 58907872 1840976
03:31:22 70810 8192000000 10058942944 10532642816 1094299264 11.784 97 7 14 0 1148461760 52715232 1447264
03:33:37 66835 8192000000 10080193744 10512879616 1076260432 11.612 96 19 38 0 1126297984 46111392 3926160
03:35:44 46756 8192000000 10148710496 10580951040 1076807344 11.617 96 29 58 1 1175108000 91641008 6659648
03:37:36 67865 8192000000 10136329888 10620170240 1091788192 11.760 98 21 41 0 1218474352 122068672 4617488
03:39:44 116339 8192000000 10101490096 10612109312 1083198608 11.678 98 16 32 0 1179947360 93318144 3430608
03:43:19 140550 8192000000 10048875216 10521497600 1092147424 11.763 98 19 38 0 1162382592 66572000 3663168
03:47:16 51177 8192000000 10060504176 10505895936 1108139408 11.915 99 14 26 1 1152776080 41883152 2753520
03:49:07 86091 8192000000 10042326976 10567843840 1088203120 11.726 97 6 12 0 1133255632 43682768 1369744
03:51:35 48027 8192000000 10066454384 10512723968 1100476528 11.842 98 34 68 0 1135725888 28415856 6833504
03:53:33 35935 8192000000 10082457744 10517782528 1112090720 11.952 99 30 60 2 1161790000 43221376 6477904
03:55:10 61927 8192000000 10126088176 10590302208 1131060368 12.131 100 27 54 1 1218769008 81815760 5892880
03:57:14 107506 8192000000 10095168784 10542751744 1099741408 11.835 96 13 26 0 1131304048 29176112 2386528
04:00:03 110439 8192000000 10117005440 10561761280 1109888080 11.931 97 7 14 0 1155984832 44885328 1211424
04:02:54 87158 8192000000 10083893248 10530045952 1101553200 11.852 96 19 38 1 1126963984 21052064 4358720
04:05:33 98026 8192000000 10077274384 10583318528 1116459872 11.994 98 11 21 1 1171252352 52431824 2360656
04:08:45 53611 8192000000 10073095936 10508972032 1142055648 12.235 100 34 67 2 1187655792 38524432 7075712
04:10:45 58391 8192000000 10109793648 10558193664 1136552528 12.183 99 20 41 1 1175653968 34800096 4301344
04:12:49 66223 8192000000 10088341616 10525224960 1121766208 12.044 98 12 25 2 1159286256 35329184 2190864
04:15:01 35912 8192000000 10087691952 10508750848 1130312160 12.124 99 36 73 2 1161216704 23106048 7798496
04:16:37 57245 8192000000 10050782640 10507571200 1087368192 11.718 96 12 24 0 1124299600 34791904 2139504
04:18:36 107588 8192000000 10074316688 10525339648 1060719808 11.463 94 8 16 0 1085253056 23158848 1374400
04:21:25 119200 8192000000 10063164976 10509938688 1121970272 12.046 99 25 50 0 1166464928 39602560 4892096
04:24:25 47816 8192000000 10061653328 10504032256 1097707216 11.816 98 11 22 1 1141698144 41974272 2016656
04:26:13 56044 8192000000 9425396176 9791414272 552066992 6.313 49 10 20 0 607125808 52583280 2475536

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 29-01-2021 04:29:10 to 29-01-2021 04:29:24.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 29-01-2021 04:29:25 to 29-01-2021 04:29:27.

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          233884
MON$OLDEST_ACTIVE               233885
MON$OLDEST_SNAPSHOT             233885
MON$NEXT_TRANSACTION            233885
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                       2107712
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             801
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2345 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2345 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2345 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.2345 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 ---
:41: 
:42: mscs-test-161.budzdorov.ru    Fri Jan 29 04:27:20 2021
:43:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:44: 
:45: 
:46: mscs-test-161.budzdorov.ru    Fri Jan 29 04:27:27 2021
:47:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:48: 
--- end of diff output ---

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

29.01.21 04:29:28. 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-01-29 04:29:31 No severe PSQL-related problems occured
29.01.21 04:29:32. End of report.