Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.11.2020 10:28
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID e0e7b46250ba4f9795ceb55e0face73f
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2020-09-25 13:42
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.00
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 17024008 kB
MemAvailable 31480632 kB
Buffers 0 kB
Cached 14676684 kB
SwapCached 2832 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 2020-11-08 10:26:53
DTS_END                         2020-11-08 10:26:53.1910
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/40490
TRN_ID                          224677
ATT_ID                          652
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: 8904.79 20637 1068575
customer order: creation 161.21 431 19345
customer order: refuse 13.45 297 1614
order to supplier: creation 43.49 1413 5219
order to supplier: removal 2.99 124168 359
invoice (draft): creation 42.58 3661 5109
invoice (draft): removal 51.15 917 6138
invoice accept: apply 39.28 17939 4714
invoice accept: cancel 46.23 744 5547
customer reserve: creation 3674.79 173 440975
customer reserve: removal 4561.04 65 547325
realization accept: apply 75.75 169 9090
realization accept: cancel 71.43 112 8571
payment from customer: creation 56.24 100 6749
payment from customer: removal 5.16 74 619
payment to supplier: creation 45.88 110 5506
payment to supplier: removal 3.36 67 403
service: total inventory turnovers 3.92 3023 470
service: total monetary turnovers 5.85 475 702
service: aggregate perf. data 0.97 13370 116
service: refresh index statistics 0.03 245441 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-11-2020 10:29:08 to 08-11-2020 10:29:10.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4490
TEST_TIME 2 3545
TEST_TIME 3 4666
TEST_TIME 4 5116
TEST_TIME 5 5464
TEST_TIME 6 5205
TEST_TIME 7 4975
TEST_TIME 8 5176
TEST_TIME 9 5154
TEST_TIME 10 5694
TEST_TIME 11 5914
TEST_TIME 12 5876
TEST_TIME 13 5889
TEST_TIME 14 6308
TEST_TIME 15 6761
TEST_TIME 16 7052
TEST_TIME 17 7798
TEST_TIME 18 7932
TEST_TIME 19 8082
TEST_TIME 20 8128
TEST_TIME 21 7929
TEST_TIME 22 8418
TEST_TIME 23 8835
TEST_TIME 24 9277
TEST_TIME 25 11065
TEST_TIME 26 10757
TEST_TIME 27 10480
TEST_TIME 28 10231
TEST_TIME 29 10263
TEST_TIME 30 10082
TEST_TIME 31 10007
TEST_TIME 32 9819
TEST_TIME 33 9838
TEST_TIME 34 9565
TEST_TIME 35 9449
TEST_TIME 36 9386
TEST_TIME 37 9371
TEST_TIME 38 9362
TEST_TIME 39 9341
TEST_TIME 40 9311
TEST_TIME 41 9402
TEST_TIME 42 9443
TEST_TIME 43 9438
TEST_TIME 44 9529
TEST_TIME 45 9535
TEST_TIME 46 9523
TEST_TIME 47 9630
TEST_TIME 48 9507
TEST_TIME 49 9391
TEST_TIME 50 9305
TEST_TIME 51 9286
TEST_TIME 52 9176
TEST_TIME 53 9096
TEST_TIME 54 9016
TEST_TIME 55 9105
TEST_TIME 56 9093
TEST_TIME 57 9107
TEST_TIME 58 9119
TEST_TIME 59 9214
TEST_TIME 60 9127
TEST_TIME 61 9220
TEST_TIME 62 9302
TEST_TIME 63 9316
TEST_TIME 64 9361
TEST_TIME 65 9398
TEST_TIME 66 9438
TEST_TIME 67 9360
TEST_TIME 68 9229
TEST_TIME 69 9127
TEST_TIME 70 9124
TEST_TIME 71 9127
TEST_TIME 72 9070
TEST_TIME 73 9087
TEST_TIME 74 9059
TEST_TIME 75 9040
TEST_TIME 76 9006
TEST_TIME 77 9026
TEST_TIME 78 9101
TEST_TIME 79 9086
TEST_TIME 80 9065
TEST_TIME 81 9239
TEST_TIME 82 9173
TEST_TIME 83 9087
TEST_TIME 84 9043
TEST_TIME 85 9082
TEST_TIME 86 9012
TEST_TIME 87 8959
TEST_TIME 88 8985
TEST_TIME 89 8957
TEST_TIME 90 8908
TEST_TIME 91 8933
TEST_TIME 92 8993
TEST_TIME 93 8984
TEST_TIME 94 9005
TEST_TIME 95 8984
TEST_TIME 96 9088
TEST_TIME 97 9112
TEST_TIME 98 9110
TEST_TIME 99 9085
TEST_TIME 100 9114
TEST_TIME 101 9110
TEST_TIME 102 9051
TEST_TIME 103 8992
TEST_TIME 104 8984
TEST_TIME 105 8979
TEST_TIME 106 8942
TEST_TIME 107 8923
TEST_TIME 108 8886
TEST_TIME 109 8962
TEST_TIME 110 8928
TEST_TIME 111 8905
TEST_TIME 112 8995
TEST_TIME 113 9072
TEST_TIME 114 9128
TEST_TIME 115 9100
TEST_TIME 116 9105
TEST_TIME 117 9036
TEST_TIME 118 9004
TEST_TIME 119 8964
TEST_TIME 120 8906

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 08-11-2020 10:29:11 to 08-11-2020 10:29:15.

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 1475156 1475156 0 0.00 0 13372 6 0 0
doc_list_biud 1475156 1475156 0 0.00 0 32650 30 0 0
sp_add_invoice_to_stock 4824 4714 110 2.28 40 188707 17939 0 110
sp_cancel_adding_invoice 5547 5547 0 0.00 5 91042 745 0 0
sp_cancel_client_order 1614 1614 0 0.00 5 16000 297 0 0
sp_cancel_customer_reserve 547325 547325 0 0.00 1 32652 65 0 0
sp_cancel_pay_from_customer 619 619 0 0.00 3 8981 75 0 0
sp_cancel_pay_to_supplier 403 403 0 0.00 3 3801 67 0 0
sp_cancel_supplier_invoice 6149 6138 11 0.17 2 17293 918 0 11
sp_cancel_supplier_order 359 359 0 0.00 14 1126816 124169 0 0
sp_cancel_write_off 8571 8571 0 0.00 1 6515 112 0 0
sp_client_order 19345 19345 0 0.00 4 40007 432 0 0
sp_customer_reserve 440975 440975 0 0.00 0 117809 174 0 0
sp_fill_shopping_cart 29669 29669 0 0.00 1 29491 47 0 0
sp_fill_shopping_cart_clo_res 440972 440972 0 0.00 0 28935 23 0 0
sp_get_clo_for_invoice 4711 4711 0 0.00 0 52514 542 0 0
sp_kill_cost_storno 15140 15140 0 0.00 0 8974 51 0 0
sp_kill_qstorno_ret_qs2qd 1109718 1109718 0 0.00 0 32627 36 0 0
sp_kill_qty_storno 583355 583355 0 0.00 0 32650 76 0 0
sp_lock_selected_doc 19055 19055 0 0.00 0 7853 7 0 0
sp_make_cost_storno 26056 26056 0 0.00 0 13349 47 0 0
sp_multiply_rows_for_pdistr 26056 26056 0 0.00 0 8586 30 0 0
sp_multiply_rows_for_qdistr 19345 19345 0 0.00 0 39867 255 0 0
sp_pay_from_customer 6749 6749 0 0.00 4 12208 101 0 0
sp_pay_to_supplier 5506 5506 0 0.00 5 13539 110 0 0
sp_payment_common 12255 12255 0 0.00 1 7631 21 0 0
sp_qd_handle_on_cancel_clo 1614 1614 0 0.00 0 15992 274 0 0
sp_qd_handle_on_invoice_upd_sts 10258 10258 0 0.00 0 27627 325 0 0
sp_qd_handle_on_reserve_upd_sts 9090 9090 0 0.00 0 20566 52 0 0
sp_reserve_write_off 9090 9090 0 0.00 7 20586 170 0 0
sp_supplier_invoice 5109 5109 0 0.00 6 77705 3661 0 0
sp_supplier_order 5219 5219 0 0.00 6 33533 1413 0 0
srv_aggregate_perf_data 116 116 0 0.00 2277 111953 13370 0 0
srv_fill_mon_cache_memory 59 59 0 0.00 1825 125097 53361 0 0
srv_find_qd_qs_mism 1022619 1022619 0 0.00 0 10631 5 0 0
srv_make_invnt_saldo 470 470 0 0.00 53 92285 3024 0 0
srv_make_money_saldo 702 702 0 0.00 1 15903 476 0 0
srv_recalc_idx_stat 4 4 0 0.00 142051 418352 245441 0 0
t$perf-abend:sp_add_invoice_to_stock 110 110 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4714 4714 0 0.00 0 33366 174 0 0
t$perf-norm:sp_cancel_adding_invoice 352 352 0 0.00 1 3886 98 0 0
t$perf-norm:sp_cancel_client_order 1614 1614 0 0.00 0 5575 11 0 0
t$perf-norm:sp_cancel_customer_reserve 607 607 0 0.00 0 932 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 619 619 0 0.00 0 2086 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 403 403 0 0.00 0 947 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 390 390 0 0.00 0 3963 19 0 0
t$perf-norm:sp_cancel_supplier_order 359 359 0 0.00 0 15952 1393 0 0
t$perf-norm:sp_cancel_write_off 913 913 0 0.00 0 738 3 0 0
t$perf-norm:sp_client_order 19345 19345 0 0.00 0 9537 17 0 0
t$perf-norm:sp_customer_reserve 920 920 0 0.00 0 5260 15 0 0
t$perf-norm:sp_pay_from_customer 6749 6749 0 0.00 0 10058 15 0 0
t$perf-norm:sp_pay_to_supplier 5506 5506 0 0.00 0 10052 12 0 0
t$perf-norm:sp_reserve_write_off 9090 9090 0 0.00 0 12081 14 0 0
t$perf-norm:sp_supplier_invoice 5109 5109 0 0.00 0 6977 16 0 0
t$perf-norm:sp_supplier_order 5219 5219 0 0.00 0 4398 15 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 635 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 59 59 0 0.00 0 2989 53 0 0
t$perf-norm:srv_make_invnt_saldo 470 470 0 0.00 0 2289 10 0 0
t$perf-norm:srv_make_money_saldo 702 702 0 0.00 0 544 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 9636 9416 220 2.28 0 6437 18 0 220
v_all_customers 35666 35666 0 0.00 0 99 1 0 0
v_all_suppliers 28236 28236 0 0.00 0 34 1 0 0
v_all_wares 38690 38690 0 0.00 0 105 1 0 0
v_cancel_adding_invoice 704 704 0 0.00 0 536 7 0 0
v_cancel_client_order 3228 3228 0 0.00 0 1063 3 0 0
v_cancel_customer_prepayment 1238 1238 0 0.00 0 1606 7 0 0
v_cancel_customer_reserve 1214 1214 0 0.00 0 2813 7 0 0
v_cancel_payment_to_supplier 806 806 0 0.00 0 3790 8 0 0
v_cancel_supplier_invoice 802 780 22 2.74 0 3574 23 0 22
v_cancel_supplier_order 718 718 0 0.00 0 497 4 0 0
v_cancel_write_off 1826 1826 0 0.00 0 1102 11 0 0
v_max_id_clo_ord 5219 5219 0 0.00 0 2268 2 0 0
v_max_id_clo_res 920 920 0 0.00 0 3924 14 0 0
v_max_id_ord_sup 5105 5105 0 0.00 0 2812 3 0 0
v_max_non_paid_invoice 1712 1712 0 0.00 0 317 2 0 0
v_max_non_paid_realizn 3404 3404 0 0.00 0 1560 3 0 0
v_min_id_clo_ord 5219 5219 0 0.00 0 3088 6 0 0
v_min_id_clo_res 920 920 0 0.00 0 1081 4 0 0
v_min_id_ord_sup 5105 5105 0 0.00 0 4656 12 0 0
v_min_non_paid_invoice 5506 5506 0 0.00 0 7623 15 0 0
v_min_non_paid_realizn 6749 6749 0 0.00 0 6023 14 0 0
v_our_firm 9714 9714 0 0.00 0 99 1 0 0
v_reserve_write_off 18180 18180 0 0.00 0 4066 4 0 0
x_lock_depdocs_on_canc_invoice 11295 11295 0 0.00 0 38642 94 0 0
x_lock_depdocs_on_canc_sup_ord 359 359 0 0.00 0 4272 322 0 0
x_make_qstorno_1000_1200 5219 5219 0 0.00 2 30438 1345 0 0
x_make_qstorno_1200_2000 5109 5109 0 0.00 4 77598 3459 0 0
x_make_qstorno_2100_3300 440975 440975 0 0.00 0 117808 148 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-11-2020 10:29:16 to 08-11-2020 10:29:20.

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:56 2 8192000000 8796633152 8807174144 3163616 0.038 1 1 2 0 3261312 61840 35856
07:57:57 25313 8192000000 9729027152 10027237376 801999104 8.917 100 90 180 1 831529264 7765104 21765056
07:59:26 17277 8192000000 9761799184 10070024192 838002080 9.280 100 90 180 0 871830208 11975616 21852512
08:00:44 26421 8192000000 9811217888 10130759680 873868416 9.639 100 87 174 1 919146432 24910880 20367136
08:02:16 10924 8192000000 9862927088 10200477696 920488704 10.101 100 76 152 0 976206448 39184032 16533712
08:03:30 11412 8192000000 9914590096 10261970944 951872048 10.409 100 80 160 2 1026256048 57507632 16876368
08:04:45 5827 8192000000 9957196912 10309472256 988908496 10.771 100 76 152 2 1072351584 68315088 15128000
08:05:53 5154 8192000000 9983365440 10344058880 1014978112 11.024 100 70 140 0 1095738944 66769744 13991088
08:07:02 2578 8192000000 10023844592 10398658560 1047592064 11.338 100 83 164 3 1134844944 70585264 16667616
08:08:07 2271 8192000000 10045420448 10416668672 1064933120 11.504 100 92 184 0 1164198704 80245760 19019824
08:09:12 2052 8192000000 10029507824 10407370752 1068647520 11.539 100 83 164 2 1146079472 61033136 16398816
08:10:15 3729 8192000000 10005224352 10397339648 1073913008 11.589 100 69 138 0 1128008688 39927136 14168544
08:11:22 4327 8192000000 9991438064 10385870848 1077507760 11.624 100 44 85 2 1103717664 17646240 8563664
08:12:29 56614 8192000000 10004482608 10392879104 1084107680 11.687 100 42 85 1 1123122288 30155056 8859552
08:14:26 6378 8192000000 10004906112 10407448576 1087750352 11.721 100 29 57 3 1122221104 27757296 6713456
08:15:36 11229 8192000000 10003012928 10405556224 1075444064 11.604 98 27 54 3 1104025392 22801776 5779552
08:16:47 41543 8192000000 10008768752 10424320000 1077172432 11.621 99 24 48 0 1100970480 18471520 5326528
08:18:47 7159 8192000000 9979031328 10374418432 1016074944 11.034 96 40 80 0 1056066944 31896128 8095872
08:19:56 8326 8192000000 9943023568 10320769024 988360496 10.766 96 42 83 1 1020488432 22975520 9152416
08:21:06 14733 8192000000 9933097648 10313715712 1005719216 10.934 98 29 58 3 1031322400 19667200 5935984
08:22:21 14510 8192000000 9915102112 10302935040 966307472 10.551 95 21 43 1 982963216 12225472 4430272
08:23:36 6734 8192000000 9963031904 10340335616 1036719936 11.233 100 37 75 1 1063689584 18774624 8195024
08:24:43 7134 8192000000 9971249072 10358071296 1050470096 11.365 100 43 86 0 1077889648 17534992 9884560
08:25:53 40288 8192000000 9984889184 10396065792 1067240544 11.526 100 32 63 0 1096695680 22809984 6645152
08:27:37 47744 8192000000 10040887616 10435219456 1087958480 11.723 100 35 70 0 1135839520 40963280 6917760
08:29:27 15720 8192000000 10091159136 10485940224 1112443264 11.956 100 55 110 1 1204030160 79843152 11743744
08:30:49 16539 8192000000 10144269296 10548137984 1124805744 12.072 100 60 120 1 1267035536 129088048 13141744
08:32:18 23005 8192000000 10160001008 10566287360 1139357968 12.209 100 40 80 2 1254460192 105811472 9290752
08:33:43 8136 8192000000 10219596496 10622971904 1156728320 12.373 100 59 116 3 1326589168 157278752 12582096
08:34:54 7979 8192000000 10270281840 10679836672 1164722400 12.447 100 64 128 5 1375239792 196141440 14375952
08:36:09 4608 8192000000 10302071168 10723491840 1167465152 12.473 100 74 148 2 1422270032 237489936 17314944
08:37:21 5243 8192000000 10345469152 10749321216 1173574000 12.530 100 72 144 0 1445054288 254398304 17081984
08:38:34 2505 8192000000 10358690656 10759520256 1167259744 12.471 99 75 149 0 1457050400 273207616 16583040
08:39:41 3023 8192000000 10418962992 10825510912 1170048928 12.497 99 78 154 2 1515675328 327196800 18429600
08:40:49 1823 8192000000 10415969744 10838933504 1159615536 12.400 99 88 176 1 1514822256 333949824 21256896
08:41:56 4109 8192000000 10442045152 10844209152 1164566192 12.446 99 86 170 0 1530860544 346759856 19534496
08:43:06 7556 8192000000 10389399488 10804477952 1142397568 12.238 99 46 93 0 1500578688 345883504 12297616
08:44:17 14651 8192000000 10375250432 10792759296 1143292528 12.246 100 33 66 1 1468506704 316810064 8404112
08:45:40 12546 8192000000 10374986368 10816749568 1130648528 12.127 99 40 80 1 1477859408 337120992 10089888
08:46:57 22710 8192000000 10412990352 10841243648 1122520864 12.051 98 35 70 0 1490210672 358636800 9053008
08:48:20 14875 8192000000 10426900784 10892066816 1132854992 12.148 100 23 46 1 1535905456 395799120 7251344
08:49:38 15893 8192000000 10392578832 10887614464 1100015744 11.838 98 29 58 0 1481835904 373626768 8193392
08:50:55 28827 8192000000 10302860688 10792382464 1104271376 11.878 99 22 44 1 1429301088 318997488 6032224
08:52:31 53061 8192000000 10044118928 10468593664 1122113440 12.047 100 49 98 0 1143803568 11312080 10378048
08:54:25 15075 8192000000 10107604384 10526785536 1123228384 12.057 100 72 141 2 1201088448 63413600 14446464
08:56:13 125097 8192000000 10061873296 10533974016 1089022496 11.733 97 10 20 0 1133112096 42109744 1979856
08:59:18 124081 8192000000 10035372160 10472726528 1113026256 11.961 99 30 56 3 1154692736 35391536 6274944
09:02:30 75552 8192000000 10080344512 10524250112 1104892256 11.884 99 16 33 1 1161819216 53206720 3720240
09:04:46 59410 8192000000 10092085984 10512855040 1099013008 11.828 97 24 48 2 1139791728 35572864 5205856
09:06:50 72945 8192000000 10098317488 10525151232 1098581872 11.824 97 22 43 0 1184700640 81719792 4398976
09:09:06 60735 8192000000 10079538544 10560204800 1119576992 12.023 99 19 38 1 1221706112 97885568 4243552
09:11:07 74970 8192000000 10138697840 10610659328 1121274528 12.039 99 19 38 1 1220950000 95909344 3766128
09:13:24 56004 8192000000 10062338432 10481545216 1120055088 12.028 99 22 44 0 1138294592 13912720 4326784
09:15:22 96119 8192000000 10089426816 10543538176 1122353936 12.049 99 40 80 0 1178084768 48100592 7630240
09:18:04 37737 8192000000 10063176592 10523406336 1137107616 12.188 100 8 16 0 1172074880 33327472 1639792
09:20:52 50760 8192000000 10144046320 10618093568 1138375984 12.200 99 19 38 3 1210772768 68235600 4161184
09:22:43 66793 8192000000 10089202672 10547068928 1137281760 12.190 99 14 28 0 1178903920 38637056 2985104
09:24:50 118906 8192000000 10138950112 10571653120 1105218000 11.887 96 13 27 0 1146640704 38857264 2565440
09:27:54 95847 8192000000 10084903264 10548064256 1127397104 12.097 98 23 46 2 1211002224 78254016 5351104
09:30:40 46335 8192000000 10082335120 10547077120 1096249424 11.802 96 24 48 0 1138372960 37076672 5046864
09:32:29 109425 8192000000 10088849664 10570223616 1099084784 11.829 97 9 17 1 1147722000 46816256 1820960
09:37:54 63295 8192000000 10079090768 10506989568 1098329344 11.822 97 19 37 1 1147613696 45149728 4134624
09:40:02 50962 8192000000 10083247264 10517970944 1093631792 11.777 97 18 37 0 1134894640 37449824 3813024
09:41:54 48466 8192000000 10097974544 10523062272 1053167936 11.391 94 16 32 1 1128333072 71849264 3315872
09:43:49 56876 8192000000 10082110272 10532343808 1086337472 11.708 98 24 48 1 1176282016 85033728 4910816
09:45:46 32556 8192000000 10117944672 10539941888 1129456496 12.116 100 45 90 0 1205082112 66364592 9261024
09:47:22 68412 8192000000 10072906256 10491760640 1094098240 11.782 97 17 33 2 1132444256 34643344 3702672
09:49:31 87856 8192000000 10126873376 10572632064 1115858672 11.988 99 31 61 0 1193985520 71973664 6153184
09:52:14 51034 8192000000 10064714496 10564407296 1115599248 11.985 99 5 11 0 1151860352 34854912 1406192
09:54:13 104611 8192000000 10052094096 10503110656 1023115440 11.102 91 11 19 0 1081410384 56487936 1807008
09:57:01 68627 8192000000 10091200368 10580561920 1114075536 11.971 99 25 50 1 1202103968 83071680 4956752
09:59:30 51153 8192000000 10098234608 10516635648 1099635568 11.834 97 34 68 1 1149309808 42649680 7024560
10:01:24 91649 8192000000 10140559600 10620362752 1135371504 12.172 100 8 16 0 1227569152 90332128 1865520
10:03:58 80872 8192000000 10062284512 10491613184 1098289712 11.821 97 12 24 3 1131835792 31241984 2304096
10:06:20 107311 8192000000 10105536224 10619252736 1106257328 11.897 97 30 60 1 1194112880 81735344 6120208
10:09:34 77639 8192000000 10077775072 10546823168 1116205728 11.991 98 11 22 0 1167338720 48611488 2521504
10:11:53 52949 8192000000 10070596944 10522800128 1134451760 12.163 100 14 28 1 1167605472 30616528 2537184
10:13:47 49280 8192000000 10126657792 10564448256 1114343632 11.974 97 21 42 1 1164409504 45684272 4381600
10:15:40 100828 8192000000 10176475712 10617626624 1116751424 11.996 97 20 40 0 1178309392 57020528 4537440
10:18:35 64840 8192000000 10169131552 10668244992 1137201952 12.189 100 8 16 1 1260821920 121543376 2076592
10:20:40 77841 8192000000 10068574928 10503778304 1093865088 11.779 96 22 44 0 1132096336 33940704 4290544
10:22:58 124661 8192000000 10053411088 10505035776 1132243296 12.143 100 22 44 0 1177573856 41112832 4217728
10:26:21 40154 8192000000 9443262240 9778196480 579523008 6.606 52 1 2 0 586704864 7146368 35488

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 08-11-2020 10:29:22 to 08-11-2020 10:29:36.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 110 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-11-2020 10:29:37 to 08-11-2020 10:29:39.

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          224904
MON$OLDEST_ACTIVE               224905
MON$OLDEST_SNAPSHOT             224905
MON$NEXT_TRANSACTION            224905
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                       2082720
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                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             779
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2248 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2248 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2248 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.2248 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 ---
:83: 
:84: mscs-test-161.budzdorov.ru    Sun Nov  8 10:27:14 2020
:85:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:86: 
:87: 
:88: mscs-test-161.budzdorov.ru    Sun Nov  8 10:27:16 2020
:89:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:90: 
:91: 
:92: mscs-test-161.budzdorov.ru    Sun Nov  8 10:27:19 2020
:93:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:94: 
--- end of diff output ---

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

08.11.20 10:29:40. 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
2020-11-08 10:29:43 No severe PSQL-related problems occured
08.11.20 10:29:44. End of report.