Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.03.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 df9f488d08424df294c459d6c283e9c3
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-03-04 16:07
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.26
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 16790468 kB
MemAvailable 31476852 kB
Buffers 20 kB
Cached 14826296 kB
SwapCached 0 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-03-28 04:26:58
DTS_END                         2021-03-28 04:26:58.2170
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/55234
TRN_ID                          224034
ATT_ID                          657
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: 8907.24 22865 1068869
customer order: creation 161.83 395 19420
customer order: refuse 13.95 412 1674
order to supplier: creation 43.19 1316 5183
order to supplier: removal 3.38 134667 406
invoice (draft): creation 43.63 3538 5236
invoice (draft): removal 50.41 1143 6049
invoice accept: apply 39.60 18526 4752
invoice accept: cancel 45.16 1005 5419
customer reserve: creation 3761.23 176 451347
customer reserve: removal 4468.36 79 536203
realization accept: apply 78.04 156 9365
realization accept: cancel 76.80 124 9216
payment from customer: creation 55.00 113 6600
payment from customer: removal 5.43 106 651
payment to supplier: creation 47.49 124 5699
payment to supplier: removal 3.38 108 406
service: total inventory turnovers 3.71 3531 445
service: total monetary turnovers 5.84 614 701
service: aggregate perf. data 0.78 17774 93
service: refresh index statistics 0.03 273389 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-03-2021 04:28:27 to 28-03-2021 04:28:29.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1885
TEST_TIME 2 2467
TEST_TIME 3 2768
TEST_TIME 4 3374
TEST_TIME 5 3528
TEST_TIME 6 3750
TEST_TIME 7 4109
TEST_TIME 8 4359
TEST_TIME 9 4358
TEST_TIME 10 4290
TEST_TIME 11 4905
TEST_TIME 12 5055
TEST_TIME 13 5638
TEST_TIME 14 5490
TEST_TIME 15 5631
TEST_TIME 16 5701
TEST_TIME 17 5959
TEST_TIME 18 6097
TEST_TIME 19 6207
TEST_TIME 20 6374
TEST_TIME 21 7160
TEST_TIME 22 7435
TEST_TIME 23 7548
TEST_TIME 24 7880
TEST_TIME 25 8156
TEST_TIME 26 8671
TEST_TIME 27 9382
TEST_TIME 28 10396
TEST_TIME 29 10629
TEST_TIME 30 10688
TEST_TIME 31 10560
TEST_TIME 32 10481
TEST_TIME 33 10365
TEST_TIME 34 10365
TEST_TIME 35 10353
TEST_TIME 36 10510
TEST_TIME 37 10527
TEST_TIME 38 10350
TEST_TIME 39 10096
TEST_TIME 40 9939
TEST_TIME 41 9835
TEST_TIME 42 9754
TEST_TIME 43 9778
TEST_TIME 44 9693
TEST_TIME 45 9750
TEST_TIME 46 9688
TEST_TIME 47 9584
TEST_TIME 48 9475
TEST_TIME 49 9473
TEST_TIME 50 9467
TEST_TIME 51 9445
TEST_TIME 52 9444
TEST_TIME 53 9413
TEST_TIME 54 9368
TEST_TIME 55 9484
TEST_TIME 56 9467
TEST_TIME 57 9543
TEST_TIME 58 9533
TEST_TIME 59 9474
TEST_TIME 60 9531
TEST_TIME 61 9535
TEST_TIME 62 9464
TEST_TIME 63 9381
TEST_TIME 64 9373
TEST_TIME 65 9342
TEST_TIME 66 9267
TEST_TIME 67 9229
TEST_TIME 68 9222
TEST_TIME 69 9214
TEST_TIME 70 9231
TEST_TIME 71 9209
TEST_TIME 72 9248
TEST_TIME 73 9242
TEST_TIME 74 9361
TEST_TIME 75 9393
TEST_TIME 76 9352
TEST_TIME 77 9532
TEST_TIME 78 9497
TEST_TIME 79 9417
TEST_TIME 80 9341
TEST_TIME 81 9313
TEST_TIME 82 9245
TEST_TIME 83 9188
TEST_TIME 84 9179
TEST_TIME 85 9188
TEST_TIME 86 9163
TEST_TIME 87 9172
TEST_TIME 88 9126
TEST_TIME 89 9202
TEST_TIME 90 9249
TEST_TIME 91 9213
TEST_TIME 92 9220
TEST_TIME 93 9224
TEST_TIME 94 9227
TEST_TIME 95 9195
TEST_TIME 96 9110
TEST_TIME 97 9080
TEST_TIME 98 9049
TEST_TIME 99 9025
TEST_TIME 100 8974
TEST_TIME 101 9037
TEST_TIME 102 9012
TEST_TIME 103 9004
TEST_TIME 104 9017
TEST_TIME 105 9040
TEST_TIME 106 9023
TEST_TIME 107 9014
TEST_TIME 108 9017
TEST_TIME 109 9042
TEST_TIME 110 9102
TEST_TIME 111 9112
TEST_TIME 112 9110
TEST_TIME 113 9032
TEST_TIME 114 9047
TEST_TIME 115 9010
TEST_TIME 116 8997
TEST_TIME 117 8971
TEST_TIME 118 8939
TEST_TIME 119 8915
TEST_TIME 120 8909
TEST_TIME 121 8836

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 28-03-2021 04:28:30 to 28-03-2021 04:28:34.

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 1485559 1485559 0 0.00 0 19154 7 0 0
doc_list_biud 1485559 1485559 0 0.00 0 81334 36 0 0
sp_add_invoice_to_stock 4864 4752 112 2.30 46 206403 18526 0 112
sp_cancel_adding_invoice 5419 5419 0 0.00 5 80873 1005 0 0
sp_cancel_client_order 1674 1674 0 0.00 4 28498 413 0 0
sp_cancel_customer_reserve 536203 536203 0 0.00 1 17508 80 0 0
sp_cancel_pay_from_customer 651 651 0 0.00 3 8389 107 0 0
sp_cancel_pay_to_supplier 406 406 0 0.00 3 5894 109 0 0
sp_cancel_supplier_invoice 6052 6049 3 0.04 2 81337 1144 0 3
sp_cancel_supplier_order 406 406 0 0.00 7 1471110 134668 0 0
sp_cancel_write_off 9216 9216 0 0.00 1 7452 124 0 0
sp_client_order 19420 19420 0 0.00 4 22836 395 0 0
sp_customer_reserve 451347 451347 0 0.00 0 154504 176 0 0
sp_fill_shopping_cart 29815 29815 0 0.00 1 24433 42 0 0
sp_fill_shopping_cart_clo_res 451341 451341 0 0.00 0 35115 19 0 0
sp_get_clo_for_invoice 4745 4745 0 0.00 0 58921 478 0 0
sp_kill_cost_storno 15692 15692 0 0.00 0 9728 70 0 0
sp_kill_qstorno_ret_qs2qd 1088077 1088077 0 0.00 0 81332 43 0 0
sp_kill_qty_storno 573077 573077 0 0.00 0 81334 92 0 0
sp_lock_selected_doc 19508 19508 0 0.00 0 10020 5 0 0
sp_make_cost_storno 26409 26409 0 0.00 0 19136 54 0 0
sp_multiply_rows_for_pdistr 26409 26409 0 0.00 0 9304 32 0 0
sp_multiply_rows_for_qdistr 19420 19420 0 0.00 0 22827 211 0 0
sp_pay_from_customer 6600 6600 0 0.00 4 14345 114 0 0
sp_pay_to_supplier 5699 5699 0 0.00 4 9562 124 0 0
sp_payment_common 12299 12299 0 0.00 1 7218 15 0 0
sp_qd_handle_on_cancel_clo 1674 1674 0 0.00 0 28490 369 0 0
sp_qd_handle_on_invoice_upd_sts 10164 10164 0 0.00 0 26126 413 0 0
sp_qd_handle_on_reserve_upd_sts 9365 9365 0 0.00 0 12302 47 0 0
sp_reserve_write_off 9365 9365 0 0.00 7 20596 157 0 0
sp_supplier_invoice 5236 5236 0 0.00 7 113727 3538 0 0
sp_supplier_order 5183 5183 0 0.00 6 64224 1316 0 0
srv_aggregate_perf_data 93 93 0 0.00 2496 182865 17775 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1559 132373 45918 0 0
srv_find_qd_qs_mism 1022650 1022650 0 0.00 0 16411 6 0 0
srv_make_invnt_saldo 445 445 0 0.00 44 109746 3532 0 0
srv_make_money_saldo 701 701 0 0.00 0 21528 614 0 0
srv_recalc_idx_stat 4 4 0 0.00 184511 429709 273389 0 0
t$perf-abend:sp_add_invoice_to_stock 112 112 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4752 4752 0 0.00 0 12726 182 0 0
t$perf-norm:sp_cancel_adding_invoice 374 374 0 0.00 1 8360 119 0 0
t$perf-norm:sp_cancel_client_order 1674 1674 0 0.00 0 7515 13 0 0
t$perf-norm:sp_cancel_customer_reserve 590 590 0 0.00 0 736 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 651 651 0 0.00 0 7433 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 406 406 0 0.00 0 5281 35 0 0
t$perf-norm:sp_cancel_supplier_invoice 386 386 0 0.00 0 4796 21 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 18420 1786 0 0
t$perf-norm:sp_cancel_write_off 911 911 0 0.00 0 3644 10 0 0
t$perf-norm:sp_client_order 19420 19420 0 0.00 0 15065 17 0 0
t$perf-norm:sp_customer_reserve 963 963 0 0.00 0 2339 6 0 0
t$perf-norm:sp_pay_from_customer 6600 6600 0 0.00 0 5424 11 0 0
t$perf-norm:sp_pay_to_supplier 5699 5699 0 0.00 0 6880 8 0 0
t$perf-norm:sp_reserve_write_off 9365 9365 0 0.00 0 5944 9 0 0
t$perf-norm:sp_supplier_invoice 5236 5236 0 0.00 0 7157 19 0 0
t$perf-norm:sp_supplier_order 5183 5183 0 0.00 0 7971 16 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 1043 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 439 16 0 0
t$perf-norm:srv_make_invnt_saldo 445 445 0 0.00 0 481 7 0 0
t$perf-norm:srv_make_money_saldo 701 701 0 0.00 0 1009 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 12 5 0 0
v_add_invoice_to_stock 9714 9490 224 2.30 0 5829 19 0 224
v_all_customers 35226 35226 0 0.00 0 17 1 0 0
v_all_suppliers 29436 29436 0 0.00 0 15 1 0 0
v_all_wares 38840 38840 0 0.00 0 157 1 0 0
v_cancel_adding_invoice 748 748 0 0.00 0 3199 15 0 0
v_cancel_client_order 3348 3348 0 0.00 0 3214 11 0 0
v_cancel_customer_prepayment 1302 1302 0 0.00 0 3812 10 0 0
v_cancel_customer_reserve 1180 1180 0 0.00 0 3845 9 0 0
v_cancel_payment_to_supplier 812 812 0 0.00 0 4431 12 0 0
v_cancel_supplier_invoice 778 772 6 0.77 0 3506 19 0 6
v_cancel_supplier_order 812 812 0 0.00 0 4632 13 0 0
v_cancel_write_off 1822 1822 0 0.00 0 4800 18 0 0
v_max_id_clo_ord 5183 5183 0 0.00 0 394 1 0 0
v_max_id_clo_res 963 963 0 0.00 0 1265 10 0 0
v_max_id_ord_sup 5212 5212 0 0.00 0 24421 9 0 0
v_max_non_paid_invoice 1376 1376 0 0.00 0 76 2 0 0
v_max_non_paid_realizn 3525 3525 0 0.00 0 1332 3 0 0
v_min_id_clo_ord 5183 5183 0 0.00 0 1099 4 0 0
v_min_id_clo_res 963 963 0 0.00 0 8306 17 0 0
v_min_id_ord_sup 5212 5212 0 0.00 0 4531 6 0 0
v_min_non_paid_invoice 5699 5699 0 0.00 0 1834 7 0 0
v_min_non_paid_realizn 6600 6600 0 0.00 0 4308 10 0 0
v_our_firm 9764 9764 0 0.00 0 34 1 0 0
v_reserve_write_off 18730 18730 0 0.00 0 4777 6 0 0
x_lock_depdocs_on_canc_invoice 11082 11082 0 0.00 0 16690 120 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 3013 320 0 0
x_make_qstorno_1000_1200 5183 5183 0 0.00 2 64197 1251 0 0
x_make_qstorno_1200_2000 5236 5236 0 0.00 3 113721 3357 0 0
x_make_qstorno_2100_3300 451347 451347 0 0.00 0 154503 154 0 0

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

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:57:01 3 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
01:58:02 39161 8192000000 9719012224 10013835264 793263264 8.828 100 89 178 0 823235952 8246832 21725856
01:59:47 13074 8192000000 9754581936 10058813440 833293712 9.232 100 84 168 0 865397216 12424384 19679120
02:01:12 20265 8192000000 9805127488 10124836864 872629648 9.626 100 83 166 1 913866496 21693840 19543008
02:02:38 17655 8192000000 9885615872 10217349120 919376864 10.090 100 83 166 1 987914752 50593168 17944720
02:04:01 8766 8192000000 9941282080 10286718976 963457328 10.523 100 79 155 0 1053706496 73970976 16278192
02:05:12 5330 8192000000 9967814944 10323148800 996206400 10.842 100 87 174 0 1084584496 70455008 17923088
02:06:18 2417 8192000000 9999919280 10361516032 1025421248 11.124 100 85 171 0 1113801584 71341760 17038576
02:07:23 2049 8192000000 10020350528 10384535552 1046778752 11.330 100 91 182 1 1124787232 59361344 18647136
02:08:26 1455 8192000000 10034860032 10406264832 1067309056 11.526 100 93 186 1 1151008752 64614208 19085488
02:09:29 4984 8192000000 10008705584 10388275200 1075396016 11.604 100 64 129 1 1119877440 30207552 14273872
02:10:35 37414 8192000000 9989193760 10396340224 1081761856 11.664 100 49 97 1 1098909120 6430560 10716704
02:12:18 8445 8192000000 10009632400 10416975872 1084508576 11.690 100 34 69 2 1114828880 23273728 7046576
02:13:28 7826 8192000000 10012390704 10421280768 1089200160 11.735 100 31 62 1 1123262032 27435488 6626384
02:14:38 12713 8192000000 10004618144 10416205824 1094088272 11.782 100 22 46 2 1121457936 22702064 4667600
02:15:52 53949 8192000000 10019725712 10422140928 1079364448 11.641 99 44 88 1 1112269600 23316576 9588576
02:17:53 5074 8192000000 9980388928 10371801088 1049859440 11.359 97 35 71 3 1087239696 29742080 7638176
02:18:59 11427 8192000000 9978427472 10382385152 1028938608 11.158 96 29 58 0 1060707424 25213840 6554976
02:20:11 7378 8192000000 9952648160 10347085824 1031059856 11.179 99 29 58 3 1056941616 20297808 5583952
02:21:23 53755 8192000000 9948903120 10336481280 977594400 10.661 95 40 78 0 1016747504 31480448 7672656
02:23:19 9544 8192000000 9952863024 10347134976 1027935504 11.149 99 50 101 1 1057801632 17703104 12163024
02:24:34 6585 8192000000 9969687296 10362179584 1045945904 11.322 100 51 98 2 1067088224 9571152 11571168
02:25:44 9000 8192000000 9964051616 10359173120 1056074528 11.419 100 27 53 1 1082555376 20654528 5826320
02:26:57 56146 8192000000 10005112064 10394955776 1074858640 11.598 100 55 110 0 1107132192 21078096 11195456
02:29:03 10735 8192000000 10036841280 10439053312 1095031056 11.790 100 46 86 1 1144062784 40702688 8329040
02:30:20 9030 8192000000 10050885168 10457247744 1107808896 11.912 100 40 80 1 1161859536 45635920 8414720
02:31:56 6442 8192000000 10122572624 10523803648 1130546608 12.127 100 54 108 0 1222555824 80408704 11600512
02:33:14 4430 8192000000 10158236224 10565668864 1143854464 12.252 100 64 126 3 1269940320 112876560 13209296
02:34:23 11633 8192000000 10215307232 10619260928 1152428368 12.332 100 61 121 0 1306926544 141023472 13474704
02:35:43 11481 8192000000 10243695328 10656223232 1164914992 12.449 100 52 102 1 1343347648 167218240 11214416
02:36:58 5553 8192000000 10307111952 10718056448 1176770240 12.560 100 71 140 1 1422409072 229459536 16179296
02:38:06 2144 8192000000 10301871456 10712018944 1175948560 12.552 100 77 151 2 1411007632 217857360 17201712
02:39:12 4937 8192000000 10325984032 10766987264 1186550944 12.651 100 58 113 1 1430886480 231316096 13019440
02:40:38 7883 8192000000 10367214672 10779062272 1192419728 12.706 100 64 127 0 1469836144 262513840 14902576
02:41:54 2807 8192000000 10445517088 10851000320 1203770160 12.811 100 87 168 0 1536391296 313034848 19586288
02:43:03 5577 8192000000 10465012064 10898055168 1203642992 12.810 100 78 152 2 1567552112 345391344 18517776
02:44:14 1775 8192000000 10498202416 10920165376 1198291376 12.760 100 81 161 1 1597301456 379275312 19734768
02:45:22 3083 8192000000 10568080448 10972028928 1185256272 12.639 99 94 188 3 1659251136 451572256 22422608
02:46:31 1557 8192000000 10602396016 11015348224 1198568288 12.763 100 85 166 1 1699403760 480917792 19917680
02:47:39 9942 8192000000 10525335760 10979373056 1177756176 12.569 99 51 100 0 1627557088 436498144 13302768
02:48:52 4342 8192000000 10510069952 10943508480 1172998816 12.525 100 50 100 0 1609880480 423525200 13356464
02:50:02 3036 8192000000 10531170944 10955100160 1158002112 12.385 99 56 112 0 1629496144 456890736 14603296
02:51:10 5496 8192000000 10498130016 10969276416 1143151472 12.245 99 41 78 1 1602719600 448657136 10910992
02:52:19 14043 8192000000 10438917744 10913787904 1134087744 12.160 100 39 76 1 1558258352 412991824 11178784
02:53:40 20996 8192000000 10294300112 10793734144 1111579952 11.947 100 20 40 1 1430525968 313432224 5513792
02:55:04 73517 8192000000 10069751264 10550194176 1084270832 11.688 97 11 22 2 1148694336 62190080 2233424
02:57:18 93897 8192000000 10069977568 10510323712 1050937328 11.370 93 15 28 0 1100015376 46537328 2540720
02:59:53 94184 8192000000 10073265152 10539110400 1075513680 11.605 96 17 34 0 1132880304 54042592 3324032
03:02:36 73835 8192000000 10075297072 10553405440 1113813648 11.969 99 7 14 0 1182818096 67474832 1529616
03:04:51 124358 8192000000 10044130560 10492387328 1099620416 11.834 98 26 49 0 1128286464 23351920 5314128
03:08:07 21206 8192000000 10088226368 10516344832 1114317344 11.973 99 42 84 1 1172425488 48617040 9491104
03:09:29 19543 8192000000 10105915552 10560544768 1130051648 12.122 100 58 117 1 1210273504 68342016 11879840
03:11:47 109153 8192000000 10119620608 10578698240 1138559776 12.202 99 23 47 0 1201037280 57689744 4787760
03:14:36 52507 8192000000 10111080272 10588737536 1137363200 12.191 99 18 36 0 1239458672 97828672 4266800
03:16:29 58602 8192000000 10107306208 10599956480 1132366944 12.144 99 6 12 2 1186975488 53395984 1212560
03:18:30 47264 8192000000 10105566304 10575077376 1131175264 12.132 98 18 36 2 1161357216 26487040 3694912
03:20:19 92548 8192000000 10071103920 10513801216 1145070896 12.263 100 15 30 1 1216540144 68089056 3380192
03:22:56 34883 8192000000 10122220736 10562670592 1139814240 12.214 99 33 65 0 1209077328 62828432 6434656
03:24:37 33087 8192000000 10084205856 10536153088 1149083440 12.301 100 24 46 1 1183596272 29471760 5041072
03:26:18 37424 8192000000 10091255488 10571988992 1140184720 12.217 99 16 32 1 1185760816 42076512 3499584
03:27:56 49302 8192000000 10143106176 10580901888 1123108688 12.056 97 24 48 4 1198377920 69895456 5373776
03:31:20 33149 8192000000 10068829760 10581037056 1139106400 12.207 99 9 18 0 1176021248 35368592 1546256
03:32:53 69774 8192000000 10118514720 10602070016 1143521008 12.249 99 13 26 0 1196552160 50575472 2455680
03:35:11 89269 8192000000 10123365200 10599211008 1094202320 11.783 94 20 40 0 1154961136 56020048 4738768
03:37:46 33622 8192000000 10080104752 10518097920 1062643360 11.482 94 25 50 0 1141371792 73290480 5437952
03:39:25 31296 8192000000 10088917552 10527776768 1041528144 11.279 94 51 102 4 1124868560 73022896 10317520
03:41:16 79387 8192000000 10078813584 10539642880 1090950128 11.752 99 11 22 0 1189026880 95849024 2227728
03:43:37 56871 8192000000 10073598416 10549608448 1105894256 11.894 99 9 18 1 1158188976 50357824 1936896
03:45:41 113167 8192000000 10049300896 10533736448 1117812752 12.006 100 15 30 0 1153553936 32591920 3149264
03:48:42 77962 8192000000 10053360544 10522587136 1110914480 11.941 100 10 22 0 1160855120 47859680 2080960
03:51:01 97337 8192000000 10064866352 10514628608 1084409792 11.689 97 21 42 0 1152006928 62852992 4744144
03:53:50 54128 8192000000 10110743216 10543693824 1098621744 11.825 97 29 57 0 1165331248 60951616 5757888
03:56:03 72897 8192000000 10048894448 10501951488 1069564256 11.548 96 14 28 0 1106984864 34753872 2666736
03:58:16 58054 8192000000 10084809824 10531405824 1117988816 12.008 99 31 61 2 1183666416 59719792 5957808
04:00:18 25484 8192000000 10089347840 10519605248 1141816896 12.233 100 49 98 1 1177314176 24922368 10574912
04:01:52 132373 8192000000 10062715328 10515615744 1129538816 12.117 98 17 34 1 1173391712 40610368 3242528
04:05:04 56703 8192000000 10072694048 10549911552 1130984368 12.131 99 15 30 1 1160107408 26043840 3079200
04:07:03 78168 8192000000 10092151840 10599366656 1151158720 12.320 100 15 30 1 1224949488 70775040 3015728
04:09:22 63904 8192000000 10098973056 10566627328 1131565488 12.136 98 26 52 0 1163635840 26781472 5288880
04:11:27 76791 8192000000 10085025152 10531049472 1129105248 12.113 98 12 24 1 1165464688 34095920 2263520
04:13:45 41231 8192000000 10094629616 10533228544 1110477968 11.937 97 28 56 2 1163194672 47489344 5227360
04:15:29 77114 8192000000 10128931712 10647801856 1133081392 12.150 99 13 26 1 1211089088 75099552 2908144
04:17:50 92020 8192000000 10122466400 10621214720 1138564000 12.202 99 11 22 1 1223792304 82998368 2229936
04:20:22 67825 8192000000 10076712128 10591055872 1147382768 12.285 100 11 21 1 1186688992 37156752 2149472
04:22:40 62826 8192000000 10069921904 10551099392 1126662080 12.090 98 8 15 2 1153483520 25107584 1713856
04:24:46 53212 8192000000 10127194896 10576003072 1139123104 12.207 98 35 70 1 1202321440 55630496 7567840
04:26:40 25710 8192000000 9180719216 9481297920 320643776 3.766 27 16 29 1 383196688 58614896 3938016

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 28-03-2021 04:28:41 to 28-03-2021 04:28:55.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 112 v_add_invoice_to_stock 335544517
except 3 v_cancel_supplier_invoice 335544517

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

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          219135
MON$OLDEST_ACTIVE               224297
MON$OLDEST_SNAPSHOT             224297
MON$NEXT_TRANSACTION            224297
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                       2101888
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             773
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2392 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2392 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2392 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.2392 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    Sun Mar 28 04:27:07 2021
:43:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:44: 
:45: 
:46: mscs-test-161.budzdorov.ru    Sun Mar 28 04:27:11 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'

28.03.21 04:28:59. 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-03-28 04:29:02 No severe PSQL-related problems occured
28.03.21 04:29:03. End of report.