Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.04.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 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 16901688 kB
MemAvailable 31434648 kB
Buffers 0 kB
Cached 14723244 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-04-01 10:26:55
DTS_END                         2021-04-01 10:26:55.4910
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/36608
TRN_ID                          232021
ATT_ID                          682
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.73 24774 1068927
customer order: creation 168.68 436 20242
customer order: refuse 14.57 331 1748
order to supplier: creation 43.65 1368 5238
order to supplier: removal 3.16 126645 379
invoice (draft): creation 44.63 3684 5356
invoice (draft): removal 50.83 937 6099
invoice accept: apply 40.78 17995 4893
invoice accept: cancel 45.85 908 5502
customer reserve: creation 3741.14 176 448937
customer reserve: removal 4471.46 71 536575
realization accept: apply 81.35 177 9762
realization accept: cancel 76.72 135 9206
payment from customer: creation 57.72 102 6926
payment from customer: removal 5.36 103 643
payment to supplier: creation 47.52 107 5702
payment to supplier: removal 3.37 79 404
service: total inventory turnovers 3.92 3456 470
service: total monetary turnovers 6.13 538 735
service: aggregate perf. data 0.88 17754 106
service: refresh index statistics 0.03 320472 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 01-04-2021 10:28:09 to 01-04-2021 10:28:11.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2622
TEST_TIME 2 3472
TEST_TIME 3 3930
TEST_TIME 4 4613
TEST_TIME 5 5008
TEST_TIME 6 5398
TEST_TIME 7 5492
TEST_TIME 8 5673
TEST_TIME 9 5956
TEST_TIME 10 6130
TEST_TIME 11 6715
TEST_TIME 12 7147
TEST_TIME 13 7044
TEST_TIME 14 7023
TEST_TIME 15 7022
TEST_TIME 16 7381
TEST_TIME 17 7575
TEST_TIME 18 7764
TEST_TIME 19 8080
TEST_TIME 20 8338
TEST_TIME 21 8379
TEST_TIME 22 8580
TEST_TIME 23 8668
TEST_TIME 24 9162
TEST_TIME 25 9068
TEST_TIME 26 9241
TEST_TIME 27 10130
TEST_TIME 28 10691
TEST_TIME 29 11552
TEST_TIME 30 11385
TEST_TIME 31 11042
TEST_TIME 32 10809
TEST_TIME 33 10662
TEST_TIME 34 10568
TEST_TIME 35 10526
TEST_TIME 36 10334
TEST_TIME 37 10282
TEST_TIME 38 10344
TEST_TIME 39 10224
TEST_TIME 40 10092
TEST_TIME 41 9971
TEST_TIME 42 9988
TEST_TIME 43 9942
TEST_TIME 44 9827
TEST_TIME 45 9844
TEST_TIME 46 9791
TEST_TIME 47 9777
TEST_TIME 48 9781
TEST_TIME 49 9795
TEST_TIME 50 9750
TEST_TIME 51 9780
TEST_TIME 52 9735
TEST_TIME 53 9653
TEST_TIME 54 9706
TEST_TIME 55 9816
TEST_TIME 56 9767
TEST_TIME 57 9798
TEST_TIME 58 9794
TEST_TIME 59 9792
TEST_TIME 60 9655
TEST_TIME 61 9570
TEST_TIME 62 9474
TEST_TIME 63 9437
TEST_TIME 64 9364
TEST_TIME 65 9348
TEST_TIME 66 9350
TEST_TIME 67 9348
TEST_TIME 68 9380
TEST_TIME 69 9392
TEST_TIME 70 9436
TEST_TIME 71 9390
TEST_TIME 72 9338
TEST_TIME 73 9322
TEST_TIME 74 9379
TEST_TIME 75 9445
TEST_TIME 76 9496
TEST_TIME 77 9518
TEST_TIME 78 9524
TEST_TIME 79 9421
TEST_TIME 80 9382
TEST_TIME 81 9357
TEST_TIME 82 9265
TEST_TIME 83 9215
TEST_TIME 84 9182
TEST_TIME 85 9136
TEST_TIME 86 9210
TEST_TIME 87 9203
TEST_TIME 88 9177
TEST_TIME 89 9251
TEST_TIME 90 9203
TEST_TIME 91 9243
TEST_TIME 92 9311
TEST_TIME 93 9309
TEST_TIME 94 9337
TEST_TIME 95 9321
TEST_TIME 96 9302
TEST_TIME 97 9216
TEST_TIME 98 9195
TEST_TIME 99 9156
TEST_TIME 100 9121
TEST_TIME 101 9053
TEST_TIME 102 9011
TEST_TIME 103 9005
TEST_TIME 104 8977
TEST_TIME 105 9034
TEST_TIME 106 9076
TEST_TIME 107 9109
TEST_TIME 108 9080
TEST_TIME 109 9087
TEST_TIME 110 9060
TEST_TIME 111 9110
TEST_TIME 112 9088
TEST_TIME 113 9032
TEST_TIME 114 8988
TEST_TIME 115 8947
TEST_TIME 116 8972
TEST_TIME 117 8936
TEST_TIME 118 8913
TEST_TIME 119 8888
TEST_TIME 120 8909

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 01-04-2021 10:28:12 to 01-04-2021 10:28:16.

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 1484447 1484447 0 0.00 0 21805 7 0 0
doc_list_biud 1484447 1484447 0 0.00 0 67832 32 0 0
sp_add_invoice_to_stock 5010 4893 117 2.33 40 210311 17996 0 117
sp_cancel_adding_invoice 5502 5502 0 0.00 5 55492 909 0 0
sp_cancel_client_order 1748 1748 0 0.00 4 11586 332 0 0
sp_cancel_customer_reserve 536575 536575 0 0.00 1 29326 72 0 0
sp_cancel_pay_from_customer 643 643 0 0.00 3 5520 104 0 0
sp_cancel_pay_to_supplier 404 404 0 0.00 3 4232 80 0 0
sp_cancel_supplier_invoice 6103 6099 4 0.06 3 73633 938 0 4
sp_cancel_supplier_order 379 379 0 0.00 8 1272190 126645 0 0
sp_cancel_write_off 9206 9206 0 0.00 1 14694 136 0 0
sp_client_order 20242 20242 0 0.00 4 26647 437 0 0
sp_customer_reserve 448937 448937 0 0.00 0 109080 177 0 0
sp_fill_shopping_cart 30834 30834 0 0.00 1 25542 47 0 0
sp_fill_shopping_cart_clo_res 448933 448933 0 0.00 0 21059 17 0 0
sp_get_clo_for_invoice 4887 4887 0 0.00 0 44505 457 0 0
sp_kill_cost_storno 15755 15755 0 0.00 0 8503 59 0 0
sp_kill_qstorno_ret_qs2qd 1088834 1088834 0 0.00 0 67831 38 0 0
sp_kill_qty_storno 574158 574158 0 0.00 0 67831 82 0 0
sp_lock_selected_doc 20303 20303 0 0.00 0 10201 5 0 0
sp_make_cost_storno 27277 27277 0 0.00 0 8753 46 0 0
sp_multiply_rows_for_pdistr 27277 27277 0 0.00 0 8784 31 0 0
sp_multiply_rows_for_qdistr 20242 20242 0 0.00 0 26603 275 0 0
sp_pay_from_customer 6926 6926 0 0.00 4 8622 102 0 0
sp_pay_to_supplier 5702 5702 0 0.00 5 9078 108 0 0
sp_payment_common 12628 12628 0 0.00 1 5630 16 0 0
sp_qd_handle_on_cancel_clo 1748 1748 0 0.00 0 11576 297 0 0
sp_qd_handle_on_invoice_upd_sts 10389 10389 0 0.00 0 18752 386 0 0
sp_qd_handle_on_reserve_upd_sts 9762 9762 0 0.00 0 14427 52 0 0
sp_reserve_write_off 9762 9762 0 0.00 6 14530 178 0 0
sp_supplier_invoice 5356 5356 0 0.00 6 89013 3684 0 0
sp_supplier_order 5238 5238 0 0.00 5 109140 1368 0 0
srv_aggregate_perf_data 106 106 0 0.00 2408 419151 17754 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1065 151056 48150 0 0
srv_find_qd_qs_mism 1022468 1022468 0 0.00 0 21804 6 0 0
srv_make_invnt_saldo 470 470 0 0.00 40 97437 3456 0 0
srv_make_money_saldo 735 735 0 0.00 1 17903 539 0 0
srv_recalc_idx_stat 4 4 0 0.00 241406 427459 320472 0 0
t$perf-abend:sp_add_invoice_to_stock 117 117 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4893 4893 0 0.00 0 16473 176 0 0
t$perf-norm:sp_cancel_adding_invoice 416 416 0 0.00 0 2184 81 0 0
t$perf-norm:sp_cancel_client_order 1748 1748 0 0.00 0 1928 11 0 0
t$perf-norm:sp_cancel_customer_reserve 693 693 0 0.00 0 1090 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 643 643 0 0.00 0 1648 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 404 404 0 0.00 0 641 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 393 393 0 0.00 0 2142 20 0 0
t$perf-norm:sp_cancel_supplier_order 379 379 0 0.00 0 27674 1654 0 0
t$perf-norm:sp_cancel_write_off 978 978 0 0.00 0 4610 9 0 0
t$perf-norm:sp_client_order 20242 20242 0 0.00 0 5076 18 0 0
t$perf-norm:sp_customer_reserve 993 993 0 0.00 0 1078 6 0 0
t$perf-norm:sp_pay_from_customer 6926 6926 0 0.00 0 4781 6 0 0
t$perf-norm:sp_pay_to_supplier 5702 5702 0 0.00 0 3741 10 0 0
t$perf-norm:sp_reserve_write_off 9762 9762 0 0.00 0 5569 9 0 0
t$perf-norm:sp_supplier_invoice 5356 5356 0 0.00 0 2820 13 0 0
t$perf-norm:sp_supplier_order 5238 5238 0 0.00 0 5626 19 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 194 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 2963 72 0 0
t$perf-norm:srv_make_invnt_saldo 470 470 0 0.00 0 3979 21 0 0
t$perf-norm:srv_make_money_saldo 735 735 0 0.00 0 581 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 36 11 0 0
v_add_invoice_to_stock 10008 9774 234 2.33 0 4775 16 0 234
v_all_customers 36600 36600 0 0.00 0 79 1 0 0
v_all_suppliers 29650 29650 0 0.00 0 139 1 0 0
v_all_wares 40482 40482 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 832 832 0 0.00 0 5905 19 0 0
v_cancel_client_order 3496 3496 0 0.00 0 7596 8 0 0
v_cancel_customer_prepayment 1286 1286 0 0.00 0 2742 8 0 0
v_cancel_customer_reserve 1386 1386 0 0.00 0 5579 11 0 0
v_cancel_payment_to_supplier 808 808 0 0.00 0 4215 10 0 0
v_cancel_supplier_invoice 794 786 8 1.00 0 5847 24 0 8
v_cancel_supplier_order 758 758 0 0.00 0 446 5 0 0
v_cancel_write_off 1956 1956 0 0.00 0 8559 24 0 0
v_max_id_clo_ord 5238 5238 0 0.00 0 2535 2 0 0
v_max_id_clo_res 993 993 0 0.00 0 1170 9 0 0
v_max_id_ord_sup 5355 5355 0 0.00 0 4277 5 0 0
v_max_non_paid_invoice 1470 1470 0 0.00 0 43 2 0 0
v_max_non_paid_realizn 3815 3815 0 0.00 0 5593 4 0 0
v_min_id_clo_ord 5238 5238 0 0.00 0 1800 5 0 0
v_min_id_clo_res 993 993 0 0.00 0 9161 26 0 0
v_min_id_ord_sup 5355 5355 0 0.00 0 3244 5 0 0
v_min_non_paid_invoice 5702 5702 0 0.00 0 5625 10 0 0
v_min_non_paid_realizn 6926 6926 0 0.00 0 3957 9 0 0
v_our_firm 10104 10104 0 0.00 0 140 1 0 0
v_reserve_write_off 19524 19524 0 0.00 0 7249 9 0 0
x_lock_depdocs_on_canc_invoice 11208 11208 0 0.00 0 17444 122 0 0
x_lock_depdocs_on_canc_sup_ord 379 379 0 0.00 0 3101 295 0 0
x_make_qstorno_1000_1200 5238 5238 0 0.00 2 109071 1292 0 0
x_make_qstorno_1200_2000 5356 5356 0 0.00 3 89005 3496 0 0
x_make_qstorno_2100_3300 448937 448937 0 0.00 0 109077 157 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-04-2021 10:28:17 to 01-04-2021 10:28:21.

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 2 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
07:58:00 24747 8192000000 9713036816 10007965696 795364576 8.849 100 91 182 0 826259888 8372448 22522864
07:59:29 18117 8192000000 9758461856 10063605760 834955072 9.249 100 91 182 0 869118496 12411168 21752256
08:01:00 16681 8192000000 9801638128 10117275648 862684064 9.527 100 87 174 0 906329456 22858784 20786608
08:02:21 9928 8192000000 9863363648 10195238912 912825968 10.025 100 75 149 0 973616688 44414048 16376672
08:03:33 8472 8192000000 9924846416 10266435584 963467552 10.523 100 83 165 0 1031317792 50229520 17620720
08:04:43 5337 8192000000 9977929728 10328723456 1004360960 10.921 100 88 174 0 1082949024 61533696 17054368
08:05:50 3744 8192000000 10007371456 10368008192 1021702496 11.088 100 86 166 1 1117865584 79610976 16552112
08:06:56 1472 8192000000 10015177712 10382962688 1049950288 11.360 100 89 178 0 1126126208 57637280 18538640
08:07:59 5995 8192000000 10045799424 10422554624 1068590864 11.539 100 75 151 0 1165982176 82063632 15327680
08:09:07 12715 8192000000 10015652784 10396938240 1081044768 11.657 100 71 141 1 1132602128 36071696 15485664
08:10:21 7280 8192000000 9988857024 10388201472 1081783696 11.664 100 52 105 1 1113502208 20124512 11594000
08:11:35 5751 8192000000 10003167472 10398408704 1087847056 11.722 100 60 120 2 1122809376 21364880 13597440
08:12:52 9234 8192000000 9997376240 10408271872 1087071040 11.715 100 31 62 2 1114985712 21167344 6747328
08:14:02 9824 8192000000 10007783536 10414477312 1093226384 11.773 100 34 69 1 1120229440 19325200 7677856
08:15:12 8710 8192000000 10012212400 10421084160 1095196592 11.792 100 24 48 0 1127743696 27569424 4977680
08:16:21 60418 8192000000 10037187840 10425556992 1087322560 11.717 99 69 138 2 1119359296 17065456 14971280
08:18:21 6339 8192000000 9999059184 10406772736 1070514224 11.557 99 35 70 0 1103532416 25102464 7915728
08:19:32 5699 8192000000 9946987040 10331299840 1015859056 11.032 98 33 66 0 1049655712 25782944 8013712
08:20:40 8072 8192000000 9934406576 10315685888 994182096 10.822 98 31 63 3 1025584000 24861008 6540896
08:21:48 22968 8192000000 9940274016 10346164224 1026783488 11.137 100 36 72 0 1057056480 21821488 8451504
08:23:12 7347 8192000000 9962888240 10350780416 1043878560 11.302 100 51 104 1 1073905856 18082016 11945280
08:24:24 11868 8192000000 9965834912 10365657088 1043622960 11.299 99 31 62 2 1064744144 13783344 7337840
08:25:37 15209 8192000000 9976113792 10379337728 1068715808 11.540 100 35 72 2 1092192864 15361824 8115232
08:26:55 54174 8192000000 10009086704 10411143168 1087146912 11.716 100 29 58 0 1120948336 28098000 5703424
08:28:57 39808 8192000000 10056350704 10474393600 1107969984 11.913 100 36 72 0 1168033184 52595408 7467792
08:30:44 21666 8192000000 10112460288 10518962176 1126583248 12.089 100 52 104 3 1203423664 65822352 11018064
08:32:11 8754 8192000000 10143142848 10557849600 1139362960 12.210 100 68 136 1 1265679744 111792416 14524368
08:33:26 15876 8192000000 10163460768 10577297408 1149439840 12.304 100 56 112 1 1275073504 113686064 11947600
08:34:55 5456 8192000000 10227625136 10632794112 1158315792 12.387 100 63 126 1 1331795216 160004512 13474912
08:36:05 2276 8192000000 10235325152 10641104896 1157786704 12.383 99 79 155 0 1327566064 151389856 18389504
08:37:10 5533 8192000000 10255790640 10680299520 1165262576 12.453 100 71 138 0 1353518560 173282240 14973744
08:38:20 3930 8192000000 10215516048 10649870336 1158558608 12.390 99 56 112 1 1313406720 142317360 12530752
08:39:34 8873 8192000000 10264695984 10688696320 1179169744 12.582 100 61 119 1 1365496240 173075936 13250560
08:40:48 3746 8192000000 10279772896 10686369792 1173332640 12.528 100 80 159 0 1378415904 186705232 18378032
08:41:58 1696 8192000000 10326493216 10736062464 1184062784 12.628 100 82 161 1 1428548064 226171440 18313840
08:43:02 3534 8192000000 10353559632 10759233536 1171401024 12.510 100 68 137 0 1444329536 257699488 15229024
08:44:11 2318 8192000000 10378992224 10774401024 1155161760 12.358 99 85 171 0 1463440688 288877312 19401616
08:45:17 6215 8192000000 10352570752 10762362880 1135128304 12.170 100 56 110 0 1448407872 300332544 12947024
08:46:27 4374 8192000000 10350171504 10752729088 1125408400 12.078 98 64 128 1 1431505760 291043280 15054080
08:47:35 3015 8192000000 10349162736 10762452992 1112841808 11.959 98 48 94 1 1432849808 307807424 12200576
08:48:43 1057 8192000000 10402674176 10816262144 1148530864 12.296 99 92 182 1 1496031840 325887888 21613088
08:49:46 7813 8192000000 10452661008 10884460544 1157472656 12.380 100 47 94 1 1544875152 374835568 12566928
08:50:57 16856 8192000000 10465330656 10908729344 1169154528 12.489 100 33 66 0 1555464384 377608336 8701520
08:52:14 13488 8192000000 10524016240 10957115392 1144065152 12.254 100 37 73 0 1618581904 464381232 10135520
08:54:37 29790 8192000000 10180978416 10750967808 1115967168 11.989 100 12 24 1 1380414704 261201568 3245968
08:56:14 104142 8192000000 10063347536 10484994048 1107211072 11.906 98 34 68 0 1138451872 23924672 7316128
08:59:29 151056 8192000000 10098301328 10536828928 1115509216 11.985 98 23 46 1 1165488192 45190016 4788960
09:03:06 19663 8192000000 10158542896 10596782080 1142777920 12.242 100 51 100 1 1247536720 93046288 11712512
09:04:46 59765 8192000000 10111911136 10628796416 1132514448 12.145 99 7 14 1 1219420640 85314896 1591296
09:06:48 34881 8192000000 10146761792 10584203264 1153405360 12.341 100 43 86 2 1236562320 73647232 9509728
09:08:28 59169 8192000000 10146591936 10650337280 1148120496 12.292 99 12 24 0 1245690464 94570960 2999008
09:10:30 86313 8192000000 10111993504 10620276736 1140394928 12.219 99 9 18 1 1199782144 57829536 1557680
09:13:00 46957 8192000000 10071631136 10529722368 1127138288 12.094 98 15 30 2 1163082288 33086432 2857568
09:14:50 41702 8192000000 10078754128 10533507072 1139501872 12.211 99 12 23 0 1173163136 31081120 2580144
09:16:49 78915 8192000000 10096477168 10562002944 1132983648 12.149 98 19 37 0 1173103776 36407456 3712672
09:19:14 30849 8192000000 10153152544 10603872256 1154781552 12.354 99 25 50 0 1242165616 82307136 5076928
09:20:48 55932 8192000000 10072997104 10558054400 1141264000 12.227 99 19 38 1 1205484976 60285040 3935936
09:22:59 35628 8192000000 10121018208 10574405632 1133249488 12.152 99 22 44 0 1189807024 51988704 4568832
09:24:49 129313 8192000000 10054895072 10534715392 1127807792 12.101 99 19 38 1 1185237760 53369840 4060128
09:28:08 88591 8192000000 10086110800 10546053120 1132791008 12.148 99 7 14 0 1176866288 42161344 1913936
09:30:37 91866 8192000000 10118665664 10605363200 1125835792 12.082 98 16 32 0 1214136720 84898304 3402624
09:33:10 86921 8192000000 10102156592 10547146752 1127960176 12.102 98 14 27 0 1185608464 54771280 2877008
09:35:41 70831 8192000000 10097081184 10573385728 1112482592 11.956 97 22 42 3 1180982736 63762048 4738096
09:37:52 22451 8192000000 10071822048 10499518464 1084917344 11.694 96 25 50 1 1118368928 28397552 5054032
09:39:21 86890 8192000000 10109535808 10605182976 1099426176 11.832 98 17 34 2 1209828512 106640768 3761568
09:41:49 44532 8192000000 10087496656 10531110912 1081961152 11.666 96 27 54 0 1112334016 24951216 5421648
09:43:35 71185 8192000000 10099324016 10526507008 1085293824 11.698 96 33 66 0 1149193184 57069712 6829648
09:45:53 119407 8192000000 10026385296 10515582976 1061591088 11.472 95 27 54 0 1137401232 70032464 5777680
09:48:55 31178 8192000000 10053801904 10535677952 1095222064 11.792 99 7 14 1 1158468912 61322880 1923968
09:50:28 71032 8192000000 10078224256 10535727104 1080352496 11.651 97 11 22 0 1143708880 60629168 2727216
09:52:39 73281 8192000000 10028849824 10481197056 1053177184 11.391 95 18 36 1 1104959056 47165776 4616096
09:54:56 72621 8192000000 10058817968 10560598016 1104196336 11.877 99 24 48 0 1180349776 71395904 4757536
09:57:14 127426 8192000000 10101753616 10561163264 1106290016 11.897 98 21 42 0 1155404000 45236336 3877648
10:00:25 50415 8192000000 10081328576 10525601792 1143026800 12.244 100 24 48 3 1170014176 22721296 4266080
10:02:16 27763 8192000000 10113350128 10560561152 1140740240 12.222 100 24 48 1 1199901056 54697744 4463072
10:03:44 75143 8192000000 10057813104 10524549120 1129203472 12.114 99 15 30 0 1172799024 40367824 3227728
10:07:37 75460 8192000000 10139230560 10588094464 1101441920 11.851 96 16 33 0 1179216896 74525504 3249472
10:10:00 38177 8192000000 10165855360 10614063104 1142565632 12.240 100 22 43 1 1241388080 94037648 4784800
10:11:39 84794 8192000000 10144909248 10649800704 1150452096 12.314 100 21 42 2 1295441376 140075728 4913552
10:14:04 50588 8192000000 10111126080 10554957824 1140297280 12.218 99 18 36 2 1196917984 53122736 3497968
10:16:09 27452 8192000000 10116232480 10547175424 1120135856 12.028 97 53 106 0 1181867648 51516560 10215232
10:17:36 71402 8192000000 10102648480 10542886912 1129747664 12.119 99 18 36 0 1179196224 45814896 3633664
10:20:43 89989 8192000000 10106529440 10572115968 1125624144 12.080 98 10 20 2 1171774192 43491760 2658288
10:23:37 75206 8192000000 10089831040 10543312896 1110278544 11.935 97 21 42 0 1172844784 57875744 4690496
10:25:59 66107 8192000000 9262212928 9615421440 382395904 4.459 33 8 16 0 436667616 52489808 1781904

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 117 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 01-04-2021 10:28:38 to 01-04-2021 10:28:40.

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          232288
MON$OLDEST_ACTIVE               232289
MON$OLDEST_SNAPSHOT             232289
MON$NEXT_TRANSACTION            232289
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                       2082336
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             791
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2396 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2396 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2396 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.2396 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 ---
:15: 
:16: mscs-test-161.budzdorov.ru    Thu Apr  1 10:27:08 2021
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:18: 
:19: 
:20: mscs-test-161.budzdorov.ru    Thu Apr  1 10:27:17 2021
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:22: 
:23: 
:24: mscs-test-161.budzdorov.ru    Thu Apr  1 10:27:21 2021
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:26: 
--- end of diff output ---

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

01.04.21 10:28:41. 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-04-01 10:28:44 No severe PSQL-related problems occured
01.04.21 10:28:45. End of report.