Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.08.2021 04:38
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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 16205712 kB
MemAvailable 30940124 kB
Buffers 20 kB
Cached 15456124 kB
SwapCached 1320 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.1
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-08-27 04:37:08
DTS_END                         2021-08-27 04:37:08.2720
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_WRITE_OFF(41:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/48936
TRN_ID                          236184
ATT_ID                          605
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: 9050.67 18357 1086081
customer order: creation 170.18 466 20421
customer order: refuse 14.58 286 1750
order to supplier: creation 43.59 1436 5231
order to supplier: removal 3.45 110239 414
invoice (draft): creation 43.90 3396 5268
invoice (draft): removal 49.94 916 5993
invoice accept: apply 40.77 17985 4892
invoice accept: cancel 45.91 856 5509
customer reserve: creation 3798.05 176 455766
customer reserve: removal 4550.99 67 546119
realization accept: apply 80.85 164 9702
realization accept: cancel 81.80 133 9816
payment from customer: creation 58.31 116 6997
payment from customer: removal 6.09 66 731
payment to supplier: creation 47.64 142 5717
payment to supplier: removal 3.42 134 410
service: total inventory turnovers 3.93 3091 471
service: total monetary turnovers 6.23 403 747
service: aggregate perf. data 1.03 12325 123
service: refresh index statistics 0.03 214734 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 27-08-2021 04:38:39 to 27-08-2021 04:38:42.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7505
TEST_TIME 2 6663
TEST_TIME 3 6301
TEST_TIME 4 6153
TEST_TIME 5 6000
TEST_TIME 6 5690
TEST_TIME 7 5813
TEST_TIME 8 5585
TEST_TIME 9 5760
TEST_TIME 10 6044
TEST_TIME 11 6444
TEST_TIME 12 6762
TEST_TIME 13 7039
TEST_TIME 14 7244
TEST_TIME 15 7543
TEST_TIME 16 8150
TEST_TIME 17 8269
TEST_TIME 18 8279
TEST_TIME 19 8553
TEST_TIME 20 8924
TEST_TIME 21 9231
TEST_TIME 22 9731
TEST_TIME 23 10410
TEST_TIME 24 11005
TEST_TIME 25 10876
TEST_TIME 26 10657
TEST_TIME 27 10516
TEST_TIME 28 10254
TEST_TIME 29 10102
TEST_TIME 30 10241
TEST_TIME 31 10296
TEST_TIME 32 10270
TEST_TIME 33 10091
TEST_TIME 34 9950
TEST_TIME 35 9848
TEST_TIME 36 9725
TEST_TIME 37 9629
TEST_TIME 38 9519
TEST_TIME 39 9627
TEST_TIME 40 9584
TEST_TIME 41 9639
TEST_TIME 42 9666
TEST_TIME 43 9808
TEST_TIME 44 9889
TEST_TIME 45 10020
TEST_TIME 46 10120
TEST_TIME 47 10018
TEST_TIME 48 9912
TEST_TIME 49 10129
TEST_TIME 50 10026
TEST_TIME 51 9913
TEST_TIME 52 9873
TEST_TIME 53 9814
TEST_TIME 54 9743
TEST_TIME 55 9704
TEST_TIME 56 9650
TEST_TIME 57 9598
TEST_TIME 58 9529
TEST_TIME 59 9529
TEST_TIME 60 9636
TEST_TIME 61 9601
TEST_TIME 62 9555
TEST_TIME 63 9636
TEST_TIME 64 9637
TEST_TIME 65 9707
TEST_TIME 66 9781
TEST_TIME 67 9760
TEST_TIME 68 9799
TEST_TIME 69 9736
TEST_TIME 70 9651
TEST_TIME 71 9617
TEST_TIME 72 9584
TEST_TIME 73 9504
TEST_TIME 74 9432
TEST_TIME 75 9406
TEST_TIME 76 9390
TEST_TIME 77 9354
TEST_TIME 78 9350
TEST_TIME 79 9319
TEST_TIME 80 9375
TEST_TIME 81 9358
TEST_TIME 82 9463
TEST_TIME 83 9464
TEST_TIME 84 9527
TEST_TIME 85 9525
TEST_TIME 86 9529
TEST_TIME 87 9525
TEST_TIME 88 9433
TEST_TIME 89 9388
TEST_TIME 90 9361
TEST_TIME 91 9333
TEST_TIME 92 9279
TEST_TIME 93 9285
TEST_TIME 94 9260
TEST_TIME 95 9228
TEST_TIME 96 9248
TEST_TIME 97 9267
TEST_TIME 98 9262
TEST_TIME 99 9259
TEST_TIME 100 9244
TEST_TIME 101 9324
TEST_TIME 102 9298
TEST_TIME 103 9378
TEST_TIME 104 9366
TEST_TIME 105 9358
TEST_TIME 106 9289
TEST_TIME 107 9248
TEST_TIME 108 9252
TEST_TIME 109 9211
TEST_TIME 110 9153
TEST_TIME 111 9117
TEST_TIME 112 9139
TEST_TIME 113 9141
TEST_TIME 114 9117
TEST_TIME 115 9139
TEST_TIME 116 9111
TEST_TIME 117 9097
TEST_TIME 118 9107
TEST_TIME 119 9128
TEST_TIME 120 9056

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 27-08-2021 04:38:43 to 27-08-2021 04:38:47.

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 1507210 1507210 0 0.00 0 31818 7 0 0
doc_list_biud 1507208 1507208 0 0.00 0 39680 30 0 0
sp_add_invoice_to_stock 5021 4892 129 2.56 45 232865 17985 0 129
sp_cancel_adding_invoice 5509 5509 0 0.00 5 72163 856 0 0
sp_cancel_client_order 1750 1750 0 0.00 5 5826 287 0 0
sp_cancel_customer_reserve 546119 546119 0 0.00 1 39682 68 0 0
sp_cancel_pay_from_customer 731 731 0 0.00 3 4292 66 0 0
sp_cancel_pay_to_supplier 410 410 0 0.00 3 11008 135 0 0
sp_cancel_supplier_invoice 6003 5993 10 0.16 3 36395 917 0 10
sp_cancel_supplier_order 414 414 0 0.00 6 1050363 110240 0 0
sp_cancel_write_off 9816 9816 0 0.00 1 31923 134 0 0
sp_client_order 20421 20421 0 0.00 4 37108 467 0 0
sp_customer_reserve 455766 455766 0 0.00 0 70025 177 0 0
sp_fill_shopping_cart 30914 30914 0 0.00 1 15534 40 0 0
sp_fill_shopping_cart_clo_res 455752 455752 0 0.00 0 18395 19 0 0
sp_get_clo_for_invoice 4878 4878 0 0.00 0 38027 420 0 0
sp_kill_cost_storno 16466 16466 0 0.00 0 30166 58 0 0
sp_kill_qstorno_ret_qs2qd 1108461 1108461 0 0.00 0 39672 37 0 0
sp_kill_qty_storno 584181 584181 0 0.00 0 39679 77 0 0
sp_lock_selected_doc 20397 20397 0 0.00 0 9421 6 0 0
sp_make_cost_storno 27294 27294 0 0.00 0 20499 55 0 0
sp_multiply_rows_for_pdistr 27294 27294 0 0.00 0 9194 31 0 0
sp_multiply_rows_for_qdistr 20421 20421 0 0.00 0 37097 300 0 0
sp_pay_from_customer 6997 6997 0 0.00 4 23025 116 0 0
sp_pay_to_supplier 5717 5717 0 0.00 4 28458 143 0 0
sp_payment_common 12714 12714 0 0.00 1 8200 21 0 0
sp_qd_handle_on_cancel_clo 1750 1750 0 0.00 0 5818 257 0 0
sp_qd_handle_on_invoice_upd_sts 10387 10387 0 0.00 0 22781 306 0 0
sp_qd_handle_on_reserve_upd_sts 9702 9702 0 0.00 0 17083 51 0 0
sp_reserve_write_off 9702 9702 0 0.00 6 21276 165 0 0
sp_supplier_invoice 5268 5268 0 0.00 7 89256 3396 0 0
sp_supplier_order 5231 5231 0 0.00 6 33037 1437 0 0
srv_aggregate_perf_data 123 123 0 0.00 2784 134441 12325 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1255 141135 49308 0 0
srv_find_qd_qs_mism 1038774 1038774 0 0.00 0 31817 6 0 0
srv_make_invnt_saldo 471 471 0 0.00 45 105081 3092 0 0
srv_make_money_saldo 747 747 0 0.00 1 12631 403 0 0
srv_recalc_idx_stat 4 4 0 0.00 121149 277515 214734 0 0
t$perf-abend:sp_add_invoice_to_stock 129 129 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4893 4893 0 0.00 0 12686 142 0 0
t$perf-norm:sp_cancel_adding_invoice 437 437 0 0.00 1 3501 85 0 0
t$perf-norm:sp_cancel_client_order 1750 1750 0 0.00 0 6668 14 0 0
t$perf-norm:sp_cancel_customer_reserve 678 678 0 0.00 0 2224 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 731 731 0 0.00 0 671 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 410 410 0 0.00 0 626 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 399 399 0 0.00 0 5963 28 0 0
t$perf-norm:sp_cancel_supplier_order 414 414 0 0.00 0 37787 1511 0 0
t$perf-norm:sp_cancel_write_off 1001 1001 0 0.00 0 3468 10 0 0
t$perf-norm:sp_client_order 20421 20421 0 0.00 0 12232 17 0 0
t$perf-norm:sp_customer_reserve 964 964 0 0.00 0 2478 6 0 0
t$perf-norm:sp_pay_from_customer 6997 6997 0 0.00 0 7101 11 0 0
t$perf-norm:sp_pay_to_supplier 5717 5717 0 0.00 0 11686 14 0 0
t$perf-norm:sp_reserve_write_off 9702 9702 0 0.00 0 5276 10 0 0
t$perf-norm:sp_supplier_invoice 5268 5268 0 0.00 0 5061 14 0 0
t$perf-norm:sp_supplier_order 5231 5231 0 0.00 0 6210 16 0 0
t$perf-norm:srv_aggregate_perf_data 123 123 0 0.00 0 476 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 977 31 0 0
t$perf-norm:srv_make_invnt_saldo 471 471 0 0.00 0 455 6 0 0
t$perf-norm:srv_make_money_saldo 747 747 0 0.00 0 647 3 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 16 6 0 0
v_add_invoice_to_stock 10008 9750 258 2.57 0 5094 16 0 258
v_all_customers 37496 37496 0 0.00 0 21 1 0 0
v_all_suppliers 29568 29568 0 0.00 0 18 1 0 0
v_all_wares 40842 40842 0 0.00 0 92 1 0 0
v_cancel_adding_invoice 874 874 0 0.00 0 2671 8 0 0
v_cancel_client_order 3500 3500 0 0.00 0 4174 8 0 0
v_cancel_customer_prepayment 1462 1462 0 0.00 0 2299 5 0 0
v_cancel_customer_reserve 1356 1356 0 0.00 0 2365 4 0 0
v_cancel_payment_to_supplier 820 820 0 0.00 0 5503 15 0 0
v_cancel_supplier_invoice 818 798 20 2.44 0 4664 22 0 20
v_cancel_supplier_order 828 828 0 0.00 0 3816 12 0 0
v_cancel_write_off 2002 2002 0 0.00 0 5671 20 0 0
v_max_id_clo_ord 5230 5230 0 0.00 0 2477 2 0 0
v_max_id_clo_res 964 964 0 0.00 0 9731 22 0 0
v_max_id_ord_sup 5263 5263 0 0.00 0 5878 5 0 0
v_max_non_paid_invoice 1426 1426 0 0.00 0 1104 3 0 0
v_max_non_paid_realizn 3553 3553 0 0.00 0 2916 5 0 0
v_min_id_clo_ord 5230 5230 0 0.00 0 11526 14 0 0
v_min_id_clo_res 964 964 0 0.00 0 3118 10 0 0
v_min_id_ord_sup 5263 5263 0 0.00 0 5591 6 0 0
v_min_non_paid_invoice 5717 5717 0 0.00 0 6238 13 0 0
v_min_non_paid_realizn 6997 6997 0 0.00 0 8196 15 0 0
v_our_firm 10234 10234 0 0.00 0 14 1 0 0
v_reserve_write_off 19404 19404 0 0.00 0 5857 5 0 0
x_lock_depdocs_on_canc_invoice 11103 11103 0 0.00 0 12890 117 0 0
x_lock_depdocs_on_canc_sup_ord 414 414 0 0.00 0 9530 313 0 0
x_make_qstorno_1000_1200 5231 5231 0 0.00 2 33025 1361 0 0
x_make_qstorno_1200_2000 5268 5268 0 0.00 3 89243 3248 0 0
x_make_qstorno_2100_3300 455765 455765 0 0.00 0 70006 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-08-2021 04:38:48 to 27-08-2021 04:38:52.

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
02:07:12 1 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:13 20103 8192000000 9711601456 10007199744 785489376 8.749 100 84 167 1 813979568 7860704 20629488
02:09:38 21358 8192000000 9751739104 10058948608 827393568 9.173 100 86 172 0 860257072 12310288 20553216
02:11:09 21320 8192000000 9795441680 10114310144 867094896 9.571 100 82 164 0 909930720 24011504 18824320
02:12:38 12182 8192000000 9863434400 10198511616 918383296 10.080 100 76 152 1 974934464 40644016 15907152
02:13:57 10264 8192000000 9931676864 10277703680 959820048 10.487 100 82 164 0 1033718608 57250144 16648416
02:15:09 5289 8192000000 9969270384 10324361216 993051712 10.811 100 87 172 0 1081642496 71502208 17088576
02:16:16 3263 8192000000 10001913344 10363092992 1021295680 11.085 100 87 171 1 1109111712 70024368 17791664
02:17:20 1505 8192000000 10028043376 10395209728 1045071472 11.313 100 91 180 1 1136702848 73443136 18188240
02:18:23 1794 8192000000 10015376464 10393571328 1062533824 11.481 100 71 140 1 1131444768 55133168 13777776
02:19:25 32474 8192000000 9988273056 10368946176 1067134656 11.525 100 61 124 0 1091874976 10842336 13897984
02:20:58 5660 8192000000 9994879856 10389209088 1068402384 11.537 100 37 74 2 1100505616 24683456 7419776
02:22:06 5378 8192000000 9985182048 10380742656 1079781968 11.645 100 41 80 0 1105238864 16399008 9057888
02:23:12 6192 8192000000 10003773520 10407235584 1087660736 11.720 100 37 69 1 1124335968 29075072 7600160
02:24:19 56878 8192000000 9993484640 10392989696 1077563392 11.624 99 30 60 0 1108347264 24011264 6772608
02:26:23 5398 8192000000 10011015792 10414780416 1091459200 11.757 100 51 101 1 1138052432 36200176 10393056
02:27:29 5702 8192000000 9996841024 10394447872 1081266224 11.660 100 39 74 4 1112512256 23290192 7955840
02:28:36 31768 8192000000 9975849184 10381332480 1056189296 11.420 99 34 68 1 1098821824 34781216 7851312
02:30:09 5251 8192000000 9959519104 10332696576 1020388752 11.076 98 46 89 0 1063464000 33637600 9437648
02:31:15 6244 8192000000 9921877072 10297741312 999871632 10.877 98 33 66 1 1028137856 20046816 8219408
02:32:23 4927 8192000000 9956406624 10341335040 1029570768 11.164 100 44 86 3 1055607616 17046528 8990320
02:33:32 17292 8192000000 9953282128 10361126912 1033153312 11.199 99 14 28 1 1070117760 33933168 3031280
02:34:50 5585 8192000000 9973187952 10374217728 1054788944 11.407 100 46 90 1 1087374208 23199696 9385568
02:35:56 5335 8192000000 9979988480 10377592832 1060447904 11.461 100 49 95 4 1089011776 19127760 9436112
02:37:03 12071 8192000000 9998819456 10401071104 1075079440 11.601 100 21 42 1 1097278048 17538784 4659824
02:38:20 110077 8192000000 10042464720 10449272832 1088386672 11.727 99 42 84 1 1145573344 47934224 9252448
02:41:14 5346 8192000000 10084150976 10502258688 1119216224 12.020 100 54 109 0 1195533232 65022288 11294720
02:42:26 21668 8192000000 10118567152 10524844032 1124260784 12.067 100 58 115 0 1218619664 81848032 12510848
02:44:01 17293 8192000000 10164060480 10590441472 1149628176 12.306 100 50 98 0 1274138576 113603904 10906496
02:45:23 6569 8192000000 10219203728 10626592768 1139163024 12.208 99 58 114 1 1317425952 166060496 12202432
02:46:32 6858 8192000000 10257449920 10675339264 1138686976 12.203 99 56 112 0 1368545344 215831536 14026832
02:47:43 9671 8192000000 10270605920 10684243968 1158064864 12.385 100 61 122 0 1374132816 202024304 14043648
02:49:01 4224 8192000000 10304062256 10711232512 1156772304 12.373 99 69 137 1 1397953456 225526816 15654336
02:50:09 3174 8192000000 10319591568 10723647488 1151323504 12.322 99 84 164 1 1418625856 248506128 18796224
02:51:16 1252 8192000000 10364561760 10773229568 1169368768 12.491 100 88 175 2 1461563552 271942816 20251968
02:52:22 1836 8192000000 10349326512 10757255168 1160287920 12.406 100 82 163 0 1445518880 266966928 18264032
02:53:28 5864 8192000000 10329607312 10748649472 1142798768 12.242 99 57 114 2 1428383280 272006192 13578320
02:54:37 17062 8192000000 10336419120 10765176832 1143682016 12.250 99 25 50 0 1443440768 293444352 6314400
02:55:58 9822 8192000000 10367519760 10788859904 1122932960 12.055 99 37 74 0 1458853136 326632160 9288016
02:57:10 16362 8192000000 10335241936 10756710400 1118675376 12.014 99 32 64 0 1423342048 296626208 8040464
02:58:29 20709 8192000000 10324413104 10812051456 1129495328 12.117 99 35 70 2 1457228528 318865712 8867488
02:59:53 42295 8192000000 10191391440 10672541696 1087220032 11.716 97 21 42 1 1341874336 249073456 5580848
03:01:58 14154 8192000000 10041867920 10472415232 1104375744 11.879 100 32 63 0 1136515648 25603056 6536848
03:03:16 91579 8192000000 10105888352 10535821312 1082331808 11.670 97 23 45 2 1165162336 78093248 4737280
03:06:11 98148 8192000000 10064972528 10502508544 1096907008 11.808 98 22 44 0 1142574576 40707104 4960464
03:08:58 17680 8192000000 10053961392 10491838464 1103782672 11.874 100 19 38 2 1163594224 55840480 3971072
03:10:16 20034 8192000000 10065808096 10516901888 1116378848 11.993 100 16 31 2 1160334720 40575552 3380320
03:11:37 67453 8192000000 10071003216 10534051840 1108999680 11.923 99 23 46 0 1185750112 71338800 5411632
03:14:05 79814 8192000000 10127171056 10618155008 1126350496 12.087 100 10 20 1 1228158048 99348640 2458912
03:16:57 31269 8192000000 10107538368 10573205504 1130683744 12.128 100 34 67 1 1210819056 72489776 7645536
03:18:31 41114 8192000000 10119498848 10552012800 1119800784 12.025 98 21 42 2 1240189520 115258128 5130608
03:20:12 76421 8192000000 10111417504 10612035584 1124520560 12.070 99 21 42 1 1240710352 111469712 4720080
03:24:10 38043 8192000000 10079808016 10509541376 1129568832 12.117 99 26 50 1 1164693664 30528672 4596160
03:25:49 47697 8192000000 10175573792 10659958784 1153124576 12.339 100 26 52 0 1313387072 154306816 5955680
03:27:43 113697 8192000000 10093916688 10563112960 1154357456 12.350 100 25 50 0 1189612704 29707408 5547840
03:30:51 44951 8192000000 10098683120 10577362944 1155646432 12.362 100 6 11 0 1212425136 55716720 1061984
03:32:36 65855 8192000000 10079142656 10542911488 1156872240 12.374 100 4 8 1 1206693648 48795280 1026128
03:34:43 57807 8192000000 10114430480 10562965504 1156216576 12.368 99 13 26 1 1199127072 39951184 2959312
03:36:41 72749 8192000000 10146676128 10611326976 1156444400 12.370 99 23 46 1 1233217424 72006352 4766672
03:38:54 43670 8192000000 10115619712 10555863040 1149994592 12.309 99 24 48 0 1188375696 33816416 4564688
03:40:38 53152 8192000000 10123629136 10561667072 1141643216 12.231 98 22 44 0 1201148944 55202384 4303344
03:42:32 116933 8192000000 10094454752 10547458048 1028622064 11.155 89 19 38 1 1079342528 47078688 3641776
03:45:30 141135 8192000000 10030791232 10487881728 1065124864 11.506 95 16 31 2 1124989920 56191360 3673696
03:48:51 50217 8192000000 10008865552 10485596160 1072868256 11.579 97 9 16 1 1120135072 45339536 1927280
03:50:41 57845 8192000000 10064380832 10506452992 1068545856 11.538 96 21 42 0 1109194208 36261216 4387136
03:52:39 42627 8192000000 10060610016 10535915520 1088111376 11.725 98 9 18 0 1133354752 43656896 1586480
03:54:22 48191 8192000000 10095465424 10527432704 1063355328 11.489 95 28 56 1 1137493680 67741040 6397312
03:56:24 36935 8192000000 10134773696 10562048000 1106117216 11.896 98 24 48 1 1219043568 107900768 5025584
03:58:04 94408 8192000000 10127029456 10626424832 1105039600 11.885 98 30 60 1 1243148400 131708688 6400112
04:00:47 82684 8192000000 10054301472 10512580608 1092831008 11.770 97 10 20 0 1160627296 65578128 2218160
04:03:11 21126 8192000000 10081827056 10528681984 1111016544 11.942 100 44 88 0 1170071248 50513168 8541536
04:04:32 129515 8192000000 10058624544 10493874176 1106432464 11.899 99 36 70 1 1160882848 47079904 7370480
04:07:46 46468 8192000000 10036191952 10480037888 1105936736 11.894 98 9 16 1 1126593680 19227888 1429056
04:09:32 51718 8192000000 10074817952 10509770752 1133033520 12.150 100 10 20 0 1176672576 41403696 2235360
04:11:31 34926 8192000000 10091814816 10539958272 1141142512 12.226 99 25 50 0 1193344992 47157360 5045120
04:13:09 42322 8192000000 10147314656 10604150784 1146075344 12.273 99 24 47 1 1220927072 69781728 5070000
04:14:58 76715 8192000000 10128694224 10576928768 1141123280 12.226 99 30 59 1 1186500528 39083488 6293760
04:17:15 64826 8192000000 10125140128 10596503552 1142718960 12.241 99 23 46 1 1245252016 97535664 4997392
04:19:20 32250 8192000000 10112469648 10554650624 1157722992 12.382 100 30 60 0 1202851088 38866560 6261536
04:20:57 103391 8192000000 10087750064 10552283136 1121421440 12.040 97 9 18 2 1162155312 39195232 1538640
04:23:40 105253 8192000000 10082936496 10561089536 1145412592 12.266 99 35 69 0 1190583168 37790208 7380368
04:26:29 45063 8192000000 10084902928 10543419392 1148278416 12.293 99 12 24 0 1190409104 39405264 2725424
04:28:15 76020 8192000000 10119299488 10614837248 1160858048 12.411 100 14 28 1 1235982656 71999376 3125232
04:30:33 62473 8192000000 10111655040 10560221184 1105493472 11.890 96 23 44 2 1171227648 60659424 5074752
04:32:36 38965 8192000000 10109401888 10575220736 1102054224 11.857 96 26 52 0 1181037904 72691040 6292640
04:34:19 32391 8192000000 10181228448 10619170816 1120914944 12.036 97 34 68 0 1240372400 111939856 7517600
04:35:54 84489 8192000000 9113998480 9448198144 244079392 2.893 21 11 20 1 342123328 95409968 2633968

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 27-08-2021 04:38:54 to 27-08-2021 04:39:09.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 129 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-08-2021 04:39:10 to 27-08-2021 04:39:12.

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          236488
MON$OLDEST_ACTIVE               236489
MON$OLDEST_SNAPSHOT             236489
MON$NEXT_TRANSACTION            236489
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                       2033120
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             804
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2573 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2573 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2573 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2573 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17: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 ---
--- end of diff output ---

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

27.08.21 04:39:13. 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-08-27 04:39:15 No severe PSQL-related problems occured
27.08.21 04:39:16. End of report.