Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.03.2021 13: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 17090608 kB
MemAvailable 31528272 kB
Buffers 0 kB
Cached 14606508 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 13:26:57
DTS_END                         2021-03-28 13:26:57.3340
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/60844
TRN_ID                          215277
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: 8493.17 23264 1019181
customer order: creation 151.73 432 18207
customer order: refuse 13.04 381 1565
order to supplier: creation 40.53 1530 4864
order to supplier: removal 3.32 136703 398
invoice (draft): creation 40.18 3857 4822
invoice (draft): removal 49.62 1073 5954
invoice accept: apply 37.37 21332 4484
invoice accept: cancel 44.66 1035 5359
customer reserve: creation 3442.95 211 413154
customer reserve: removal 4397.43 82 527691
realization accept: apply 74.30 183 8916
realization accept: cancel 83.92 132 10070
payment from customer: creation 52.95 113 6354
payment from customer: removal 5.14 83 617
payment to supplier: creation 42.69 122 5123
payment to supplier: removal 3.18 61 382
service: total inventory turnovers 3.67 3669 440
service: total monetary turnovers 5.51 636 661
service: aggregate perf. data 0.97 12881 116
service: refresh index statistics 0.03 280764 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2902
TEST_TIME 2 5083
TEST_TIME 3 4370
TEST_TIME 4 4989
TEST_TIME 5 4858
TEST_TIME 6 4997
TEST_TIME 7 5026
TEST_TIME 8 5029
TEST_TIME 9 5143
TEST_TIME 10 5279
TEST_TIME 11 5352
TEST_TIME 12 5437
TEST_TIME 13 5451
TEST_TIME 14 5500
TEST_TIME 15 5525
TEST_TIME 16 5541
TEST_TIME 17 5935
TEST_TIME 18 6203
TEST_TIME 19 6340
TEST_TIME 20 6505
TEST_TIME 21 6739
TEST_TIME 22 6985
TEST_TIME 23 7246
TEST_TIME 24 7656
TEST_TIME 25 8168
TEST_TIME 26 8056
TEST_TIME 27 8532
TEST_TIME 28 9249
TEST_TIME 29 9684
TEST_TIME 30 9791
TEST_TIME 31 10154
TEST_TIME 32 10227
TEST_TIME 33 9992
TEST_TIME 34 9799
TEST_TIME 35 9635
TEST_TIME 36 9523
TEST_TIME 37 9483
TEST_TIME 38 9463
TEST_TIME 39 9314
TEST_TIME 40 9196
TEST_TIME 41 9236
TEST_TIME 42 9189
TEST_TIME 43 9121
TEST_TIME 44 9091
TEST_TIME 45 8954
TEST_TIME 46 8897
TEST_TIME 47 8810
TEST_TIME 48 8768
TEST_TIME 49 8715
TEST_TIME 50 8626
TEST_TIME 51 8593
TEST_TIME 52 8741
TEST_TIME 53 8831
TEST_TIME 54 8764
TEST_TIME 55 8884
TEST_TIME 56 8983
TEST_TIME 57 8939
TEST_TIME 58 8964
TEST_TIME 59 8988
TEST_TIME 60 9030
TEST_TIME 61 9019
TEST_TIME 62 9021
TEST_TIME 63 8892
TEST_TIME 64 8877
TEST_TIME 65 8838
TEST_TIME 66 8745
TEST_TIME 67 8687
TEST_TIME 68 8720
TEST_TIME 69 8670
TEST_TIME 70 8628
TEST_TIME 71 8617
TEST_TIME 72 8643
TEST_TIME 73 8634
TEST_TIME 74 8736
TEST_TIME 75 8820
TEST_TIME 76 8773
TEST_TIME 77 8861
TEST_TIME 78 8844
TEST_TIME 79 8834
TEST_TIME 80 8778
TEST_TIME 81 8682
TEST_TIME 82 8658
TEST_TIME 83 8631
TEST_TIME 84 8601
TEST_TIME 85 8541
TEST_TIME 86 8469
TEST_TIME 87 8515
TEST_TIME 88 8504
TEST_TIME 89 8478
TEST_TIME 90 8535
TEST_TIME 91 8527
TEST_TIME 92 8534
TEST_TIME 93 8526
TEST_TIME 94 8526
TEST_TIME 95 8539
TEST_TIME 96 8581
TEST_TIME 97 8719
TEST_TIME 98 8698
TEST_TIME 99 8668
TEST_TIME 100 8621
TEST_TIME 101 8624
TEST_TIME 102 8555
TEST_TIME 103 8564
TEST_TIME 104 8524
TEST_TIME 105 8493
TEST_TIME 106 8505
TEST_TIME 107 8544
TEST_TIME 108 8542
TEST_TIME 109 8534
TEST_TIME 110 8518
TEST_TIME 111 8569
TEST_TIME 112 8614
TEST_TIME 113 8593
TEST_TIME 114 8618
TEST_TIME 115 8641
TEST_TIME 116 8646
TEST_TIME 117 8620
TEST_TIME 118 8550
TEST_TIME 119 8543
TEST_TIME 120 8497

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 28-03-2021 13:29:06 to 28-03-2021 13:29:09.

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 1403038 1403038 0 0.00 0 18810 7 0 0
doc_list_biud 1403038 1403038 0 0.00 0 28291 38 0 0
sp_add_invoice_to_stock 4649 4484 165 3.54 35 214386 21332 0 165
sp_cancel_adding_invoice 5359 5359 0 0.00 5 106272 1036 0 0
sp_cancel_client_order 1565 1565 0 0.00 5 16216 381 0 0
sp_cancel_customer_reserve 527691 527691 0 0.00 1 25241 82 0 0
sp_cancel_pay_from_customer 617 617 0 0.00 3 2881 84 0 0
sp_cancel_pay_to_supplier 382 382 0 0.00 3 725 61 0 0
sp_cancel_supplier_invoice 5972 5954 18 0.30 2 25337 1073 0 18
sp_cancel_supplier_order 398 398 0 0.00 9 1152246 136703 0 0
sp_cancel_write_off 10070 10070 0 0.00 1 26797 133 0 0
sp_client_order 18207 18207 0 0.00 4 20720 432 0 0
sp_customer_reserve 413154 413154 0 0.00 0 71075 212 0 0
sp_fill_shopping_cart 27867 27867 0 0.00 1 10655 44 0 0
sp_fill_shopping_cart_clo_res 413144 413144 0 0.00 0 35641 23 0 0
sp_get_clo_for_invoice 4473 4473 0 0.00 0 42932 425 0 0
sp_kill_cost_storno 16428 16428 0 0.00 0 16341 69 0 0
sp_kill_qstorno_ret_qs2qd 1071804 1071804 0 0.00 0 26529 44 0 0
sp_kill_qty_storno 564425 564425 0 0.00 0 28290 94 0 0
sp_lock_selected_doc 18616 18616 0 0.00 0 15685 4 0 0
sp_make_cost_storno 24865 24865 0 0.00 0 15302 58 0 0
sp_multiply_rows_for_pdistr 24865 24865 0 0.00 0 9213 34 0 0
sp_multiply_rows_for_qdistr 18207 18207 0 0.00 0 20710 249 0 0
sp_pay_from_customer 6354 6354 0 0.00 4 10125 113 0 0
sp_pay_to_supplier 5123 5123 0 0.00 5 15331 122 0 0
sp_payment_common 11477 11477 0 0.00 1 7276 17 0 0
sp_qd_handle_on_cancel_clo 1565 1565 0 0.00 0 9897 347 0 0
sp_qd_handle_on_invoice_upd_sts 9831 9831 0 0.00 0 28290 434 0 0
sp_qd_handle_on_reserve_upd_sts 8916 8916 0 0.00 0 12917 55 0 0
sp_reserve_write_off 8916 8916 0 0.00 7 16343 183 0 0
sp_supplier_invoice 4822 4822 0 0.00 6 97026 3857 0 0
sp_supplier_order 4864 4864 0 0.00 6 65836 1531 0 0
srv_aggregate_perf_data 116 116 0 0.00 2640 102079 12882 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 947 125126 44126 0 0
srv_find_qd_qs_mism 977510 977510 0 0.00 0 15781 6 0 0
srv_make_invnt_saldo 440 440 0 0.00 42 121085 3669 0 0
srv_make_money_saldo 661 661 0 0.00 1 11608 637 0 0
srv_recalc_idx_stat 4 4 0 0.00 124032 478346 280764 0 0
t$perf-abend:sp_add_invoice_to_stock 165 165 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 18 18 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4484 4484 0 0.00 0 13774 190 0 0
t$perf-norm:sp_cancel_adding_invoice 345 345 0 0.00 0 3484 128 0 0
t$perf-norm:sp_cancel_client_order 1565 1565 0 0.00 0 4367 14 0 0
t$perf-norm:sp_cancel_customer_reserve 656 656 0 0.00 0 2791 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 617 617 0 0.00 0 5647 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 382 382 0 0.00 0 734 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 384 384 0 0.00 0 1085 17 0 0
t$perf-norm:sp_cancel_supplier_order 398 398 0 0.00 0 21309 1731 0 0
t$perf-norm:sp_cancel_write_off 887 887 0 0.00 0 3389 11 0 0
t$perf-norm:sp_client_order 18207 18207 0 0.00 0 10119 21 0 0
t$perf-norm:sp_customer_reserve 905 905 0 0.00 0 1587 7 0 0
t$perf-norm:sp_pay_from_customer 6354 6354 0 0.00 0 3028 8 0 0
t$perf-norm:sp_pay_to_supplier 5123 5123 0 0.00 0 6322 9 0 0
t$perf-norm:sp_reserve_write_off 8916 8916 0 0.00 0 10120 14 0 0
t$perf-norm:sp_supplier_invoice 4822 4822 0 0.00 0 6922 24 0 0
t$perf-norm:sp_supplier_order 4864 4864 0 0.00 0 4551 18 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 754 21 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 532 11 0 0
t$perf-norm:srv_make_invnt_saldo 440 440 0 0.00 0 2192 16 0 0
t$perf-norm:srv_make_money_saldo 661 661 0 0.00 0 1472 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 24 9 0 0
v_add_invoice_to_stock 9264 8934 330 3.56 0 6320 18 0 330
v_all_customers 33370 33370 0 0.00 0 19 1 0 0
v_all_suppliers 26726 26726 0 0.00 0 13 1 0 0
v_all_wares 36402 36402 0 0.00 0 25 1 0 0
v_cancel_adding_invoice 690 690 0 0.00 0 103 6 0 0
v_cancel_client_order 3130 3130 0 0.00 0 3452 5 0 0
v_cancel_customer_prepayment 1234 1234 0 0.00 0 2033 6 0 0
v_cancel_customer_reserve 1312 1312 0 0.00 0 2085 5 0 0
v_cancel_payment_to_supplier 764 764 0 0.00 0 404 4 0 0
v_cancel_supplier_invoice 804 768 36 4.47 0 2399 19 0 36
v_cancel_supplier_order 796 796 0 0.00 0 3102 12 0 0
v_cancel_write_off 1774 1774 0 0.00 0 2444 16 0 0
v_max_id_clo_ord 4859 4859 0 0.00 0 1978 2 0 0
v_max_id_clo_res 905 905 0 0.00 0 2394 10 0 0
v_max_id_ord_sup 4806 4806 0 0.00 0 10349 8 0 0
v_max_non_paid_invoice 1425 1425 0 0.00 0 459 2 0 0
v_max_non_paid_realizn 3240 3240 0 0.00 0 2281 4 0 0
v_min_id_clo_ord 4859 4859 0 0.00 0 3483 6 0 0
v_min_id_clo_res 905 905 0 0.00 0 2121 6 0 0
v_min_id_ord_sup 4806 4806 0 0.00 0 6849 10 0 0
v_min_non_paid_invoice 5123 5123 0 0.00 0 1665 7 0 0
v_min_non_paid_realizn 6354 6354 0 0.00 0 7270 15 0 0
v_our_firm 9260 9260 0 0.00 0 13 1 0 0
v_reserve_write_off 17830 17830 0 0.00 0 5254 6 0 0
x_lock_depdocs_on_canc_invoice 10929 10929 0 0.00 0 20917 125 0 0
x_lock_depdocs_on_canc_sup_ord 398 398 0 0.00 0 4004 355 0 0
x_make_qstorno_1000_1200 4864 4864 0 0.00 2 65807 1449 0 0
x_make_qstorno_1200_2000 4822 4822 0 0.00 3 94971 3686 0 0
x_make_qstorno_2100_3300 413154 413154 0 0.00 0 71075 186 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-03-2021 13:29:10 to 28-03-2021 13:29:14.

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
10:57:00 2 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
10:58:02 19164 8192000000 9712413744 10005676032 791993952 8.815 100 87 173 0 820471952 7302560 21175440
10:59:26 22268 8192000000 9756205744 10061512704 830268624 9.202 100 87 172 1 862741888 11823712 20649552
11:01:01 16425 8192000000 9798742080 10115313664 866803456 9.568 100 84 166 1 906076352 20532576 18740320
11:02:28 9607 8192000000 9870453824 10202877952 924591008 10.141 100 78 156 2 981844800 40338880 16914912
11:03:41 9403 8192000000 9904838496 10246320128 956859840 10.458 100 81 158 1 1017625136 43800720 16964576
11:04:53 7998 8192000000 9957028544 10310123520 987630192 10.758 100 83 166 0 1077558416 73189968 16738256
11:06:04 2118 8192000000 9992408832 10349907968 1011947568 10.994 100 83 165 0 1105783312 76956288 16879456
11:07:08 2418 8192000000 10011683088 10373472256 1036190064 11.228 100 92 184 0 1126428528 71157120 19081344
11:08:12 4171 8192000000 10024459824 10395992064 1050816176 11.369 100 85 170 3 1131003072 63110896 17076000
11:09:19 11071 8192000000 9993069904 10375200768 1067086960 11.524 100 64 127 2 1108767888 28482704 13198224
11:10:31 7611 8192000000 9981264384 10371522560 1073144112 11.582 100 43 86 2 1097549744 15414304 8991328
11:11:40 5805 8192000000 9984071120 10377351168 1080424000 11.652 100 34 69 0 1106551952 18098128 8029824
11:12:46 9971 8192000000 10004899200 10413277184 1088340912 11.727 100 28 57 0 1112855136 18924608 5589616
11:13:59 68301 8192000000 10008634496 10413199360 1096492512 11.804 100 29 57 2 1116909472 13602672 6814288
11:16:08 3173 8192000000 10036645376 10433687552 1102702656 11.863 100 45 92 1 1141236992 28510688 10023648
11:17:13 11712 8192000000 10013214368 10424717312 1076014400 11.609 98 25 48 1 1105573408 24738672 4820336
11:18:27 5577 8192000000 9997536048 10390487040 1074426368 11.594 99 48 95 1 1100466048 14518064 11521616
11:19:36 48506 8192000000 9934781824 10315067392 989743344 10.779 94 45 88 1 1011132384 11863376 9525664
11:21:36 8582 8192000000 9931812528 10320330752 985246080 10.735 96 44 86 1 1018884800 23074720 10564000
11:22:45 9926 8192000000 9949528688 10333347840 1018450880 11.057 98 33 63 1 1052151248 27470448 6229920
11:23:57 7178 8192000000 9941084528 10331656192 1034222304 11.209 99 39 77 0 1056748928 14611904 7914720
11:25:05 45829 8192000000 9973954352 10384879616 1053644336 11.396 100 39 78 1 1073216048 10618224 8953488
11:26:52 7245 8192000000 9978821744 10376265728 1064911232 11.503 100 46 90 0 1091470704 16489728 10069744
11:28:14 6724 8192000000 10029254448 10429759488 1095471680 11.795 100 54 107 1 1145414800 38130368 11812752
11:29:31 12146 8192000000 10070177760 10468188160 1115536896 11.985 100 63 125 2 1167527760 39900400 12090464
11:30:50 62507 8192000000 10095523120 10515017728 1127088000 12.094 100 44 88 3 1213833008 76831632 9913376
11:32:59 11824 8192000000 10130954816 10540462080 1142623104 12.240 100 52 102 2 1240273152 86506640 11143408
11:34:18 10451 8192000000 10172915504 10584260608 1147571328 12.287 100 48 95 1 1279487536 121532080 10384128
11:35:34 19115 8192000000 10202262624 10614984704 1165959504 12.459 100 57 115 0 1305263072 125986192 13317376
11:36:59 10854 8192000000 10226058416 10635190272 1174974608 12.543 100 68 134 1 1321748240 131395312 15378320
11:38:17 11903 8192000000 10235895248 10667298816 1183147088 12.620 100 47 94 1 1337770832 143503680 11120064
11:39:37 7565 8192000000 10304514672 10722217984 1195310704 12.733 100 64 127 0 1407119440 197380272 14428464
11:40:49 5874 8192000000 10319982896 10745507840 1205332672 12.826 100 48 96 0 1427472032 210343344 11796016
11:41:58 1548 8192000000 10365413904 10781966336 1205942640 12.831 100 70 140 0 1468465984 246511456 16011888
11:43:03 3716 8192000000 10406876576 10828214272 1203599296 12.810 100 67 135 0 1510494880 290253216 16642368
11:44:13 942 8192000000 10425813792 10834690048 1206710640 12.839 100 94 190 0 1522005712 292616096 22678976
11:45:21 6197 8192000000 10453472704 10870022144 1202997376 12.804 100 88 177 1 1548996336 325703776 20295184
11:46:32 1896 8192000000 10498266800 10916335616 1195900672 12.738 99 85 166 1 1596147328 380078976 20167680
11:47:44 5623 8192000000 10516290640 10936487936 1192222432 12.704 100 73 144 1 1611064880 401997888 16844560
11:48:55 5718 8192000000 10482171184 10903867392 1162979168 12.431 99 62 123 1 1574582384 396304768 15298448
11:50:04 23386 8192000000 10438118768 10907914240 1174186752 12.536 100 33 66 1 1555416848 371648544 9581552
11:51:44 8393 8192000000 10369164528 10811383808 1139699488 12.213 100 46 89 0 1465466400 314857584 10909328
11:53:05 16734 8192000000 10416344048 10909851648 1143271072 12.246 99 39 78 0 1499577440 346243456 10062912
11:54:39 23909 8192000000 10265507008 10797096960 1148839120 12.299 100 23 46 0 1393384896 238534592 6011184
11:56:04 37023 8192000000 10160073024 10654060544 1114184656 11.972 98 20 41 0 1307586896 188420400 4981840
11:57:46 24253 8192000000 10092646176 10530000896 1125765904 12.081 98 43 78 5 1173259968 39562576 7931488
11:59:26 99874 8192000000 10064818544 10512166912 1133998656 12.159 99 31 62 2 1170369936 30353328 6017952
12:02:10 125125 8192000000 10098664224 10580201472 1128259904 12.105 99 23 47 1 1216159056 83061968 4837184
12:05:56 55512 8192000000 10117989120 10576285696 1132930208 12.149 100 22 44 0 1221270992 83306816 5033968
12:07:52 33322 8192000000 10098732208 10569363456 1133687488 12.156 100 20 40 1 1208722864 70672368 4363008
12:09:27 121226 8192000000 10075162160 10572050432 1143463152 12.248 100 15 30 2 1205717680 59445168 2809360
12:12:29 52861 8192000000 10048044432 10508779520 1101823552 11.855 97 14 28 0 1145748896 40939200 2986144
12:15:45 52120 8192000000 10095527456 10557812736 1118623008 12.014 98 19 38 0 1184867712 61833792 4410912
12:17:48 85327 8192000000 10131397904 10655981568 1131355552 12.134 99 22 45 2 1266621056 129849088 5416416
12:20:29 71146 8192000000 10142001264 10621747200 1101034704 11.847 96 23 44 1 1213194896 107452272 4707920
12:22:46 47390 8192000000 10060579968 10503593984 1111639648 11.948 98 23 46 1 1149700624 32740304 5320672
12:24:36 52520 8192000000 10101877248 10548654080 1132674704 12.147 99 40 80 2 1179583152 38595456 8312992
12:26:37 45176 8192000000 10093408304 10571882496 1119787664 12.025 98 24 48 1 1190822256 66261024 4773568
12:28:23 110446 8192000000 10093264240 10598719488 1127082224 12.094 99 18 36 0 1215696608 84950544 3663840
12:31:30 48278 8192000000 10065016864 10541461504 1140010848 12.216 100 6 12 1 1162983936 22042960 930128
12:34:38 78957 8192000000 10110370800 10562347008 1153245008 12.340 100 19 38 0 1208558608 51207120 4106480
12:36:58 31806 8192000000 10142709776 10600099840 1146064256 12.273 99 29 59 1 1229604496 77010080 6530160
12:38:38 41854 8192000000 10163788128 10602811392 1131577616 12.136 97 25 49 3 1244939184 108044400 5317168
12:40:20 47799 8192000000 10108645920 10600349696 1104287344 11.878 96 20 40 2 1182293040 73605088 4400608
12:42:13 27376 8192000000 10100147840 10539503616 1113631728 11.967 97 36 71 1 1165245264 44254576 7358960
12:43:43 65105 8192000000 10077130080 10549440512 1124414528 12.069 99 23 44 1 1182589808 53464288 4710992
12:45:53 41147 8192000000 10123855456 10557214720 1117748896 12.006 98 42 83 0 1186492800 59662768 9081136
12:47:35 72580 8192000000 10069214832 10529128448 1109654160 11.929 98 18 36 2 1177245888 63714112 3877616
12:49:52 50131 8192000000 10045066688 10508832768 1117759504 12.006 99 8 16 0 1163072928 43729552 1583872
12:51:42 76862 8192000000 10094338784 10539048960 1093527696 11.776 96 9 18 1 1145575648 50247632 1800320
12:53:59 41908 8192000000 10037355328 10476507136 1082673536 11.673 96 15 29 1 1113650352 27486880 3489936
12:55:48 42798 8192000000 10078024592 10512527360 1070518496 11.557 95 30 60 0 1136871824 60393648 5959680
12:57:32 68819 8192000000 10082466624 10543607808 1098761888 11.826 97 23 47 1 1167966640 64820144 4384608
12:59:42 62417 8192000000 10143339760 10596728832 1134301056 12.162 99 36 72 0 1214390352 72730304 7358992
13:01:52 76594 8192000000 10099970512 10658484224 1125109904 12.075 99 17 34 0 1267523024 138265296 4147824
13:04:20 18522 8192000000 10058454736 10480799744 1086426256 11.709 96 41 81 2 1123127136 28488048 8212832
13:05:41 10007 8192000000 10086969328 10493579264 1112448624 11.956 99 80 159 0 1156389088 28978768 14961696
13:06:51 94113 8192000000 10110964368 10598703104 1105259056 11.888 98 28 56 0 1232274720 121106576 5909088
13:11:40 75171 8192000000 10101911872 10579124224 1102115968 11.858 97 24 46 2 1193032720 85886288 5030464
13:14:00 54618 8192000000 10132417200 10588332032 1140041936 12.216 100 16 32 0 1206574688 63063200 3469552
13:15:57 50274 8192000000 10117019984 10552016896 1122849264 12.054 98 22 44 1 1205825152 78117168 4858720
13:17:56 59475 8192000000 10094647616 10583998464 1131297664 12.134 99 18 36 1 1185195232 49745488 4152080
13:20:09 70198 8192000000 10139285616 10631966720 1130885840 12.130 99 31 62 0 1215399248 77813968 6699440
13:22:21 111451 8192000000 10084397696 10545504256 1105798608 11.893 97 13 26 0 1157008928 48421856 2788464
13:25:19 84004 8192000000 10049347664 10529345536 1097865712 11.817 97 8 16 0 1142926336 43593840 1466784

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 28-03-2021 13:29:16 to 28-03-2021 13:29:31.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 165 v_add_invoice_to_stock 335544517
except 18 v_cancel_supplier_invoice 335544517

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

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          215178
MON$OLDEST_ACTIVE               215389
MON$OLDEST_SNAPSHOT             215389
MON$NEXT_TRANSACTION            215389
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                       2074784
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             766
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2393 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2393 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2393 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.2393 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 ---
:24: 
:25: mscs-test-161.budzdorov.ru    Sun Mar 28 13:27:01 2021
:26:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:27: 
:28: 
:29: mscs-test-161.budzdorov.ru    Sun Mar 28 13:27:10 2021
:30:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:31: 
--- end of diff output ---

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

28.03.21 13:29:35. 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 13:29:37 No severe PSQL-related problems occured
28.03.21 13:29:38. End of report.