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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID df9f488d08424df294c459d6c283e9c3
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-03-04 16:07
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.26
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16746544 kB
MemAvailable 31442012 kB
Buffers 20 kB
Cached 14801016 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-22 04:26:58
DTS_END                         2021-03-22 04:26:58.9270
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/48260
TRN_ID                          227506
ATT_ID                          640
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: 9026.48 26466 1083177
customer order: creation 161.16 420 19339
customer order: refuse 14.20 401 1704
order to supplier: creation 44.35 1492 5322
order to supplier: removal 3.19 147538 383
invoice (draft): creation 44.45 3856 5334
invoice (draft): removal 51.03 1217 6123
invoice accept: apply 39.91 20863 4789
invoice accept: cancel 46.43 1007 5571
customer reserve: creation 3748.39 199 449807
customer reserve: removal 4589.48 77 550737
realization accept: apply 78.92 198 9470
realization accept: cancel 84.05 143 10086
payment from customer: creation 55.59 129 6671
payment from customer: removal 5.54 98 665
payment to supplier: creation 45.95 140 5514
payment to supplier: removal 3.50 107 420
service: total inventory turnovers 3.17 4858 380
service: total monetary turnovers 6.28 634 754
service: aggregate perf. data 0.87 18446 104
service: refresh index statistics 0.03 327490 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-03-2021 04:27:35 to 22-03-2021 04:27:37.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6594
TEST_TIME 2 5147
TEST_TIME 3 4649
TEST_TIME 4 4411
TEST_TIME 5 4633
TEST_TIME 6 5052
TEST_TIME 7 4925
TEST_TIME 8 5193
TEST_TIME 9 5310
TEST_TIME 10 5462
TEST_TIME 11 6019
TEST_TIME 12 6337
TEST_TIME 13 6285
TEST_TIME 14 6725
TEST_TIME 15 6611
TEST_TIME 16 6613
TEST_TIME 17 6804
TEST_TIME 18 6829
TEST_TIME 19 6906
TEST_TIME 20 7023
TEST_TIME 21 7252
TEST_TIME 22 7140
TEST_TIME 23 7245
TEST_TIME 24 7334
TEST_TIME 25 7667
TEST_TIME 26 7979
TEST_TIME 27 8089
TEST_TIME 28 8225
TEST_TIME 29 8082
TEST_TIME 30 7992
TEST_TIME 31 8202
TEST_TIME 32 9702
TEST_TIME 33 10559
TEST_TIME 34 10831
TEST_TIME 35 10841
TEST_TIME 36 10964
TEST_TIME 37 10699
TEST_TIME 38 10547
TEST_TIME 39 10581
TEST_TIME 40 10406
TEST_TIME 41 10296
TEST_TIME 42 10149
TEST_TIME 43 10136
TEST_TIME 44 10082
TEST_TIME 45 10095
TEST_TIME 46 10105
TEST_TIME 47 10017
TEST_TIME 48 9937
TEST_TIME 49 9833
TEST_TIME 50 9778
TEST_TIME 51 9769
TEST_TIME 52 9663
TEST_TIME 53 9662
TEST_TIME 54 9712
TEST_TIME 55 9687
TEST_TIME 56 9745
TEST_TIME 57 9772
TEST_TIME 58 9788
TEST_TIME 59 9740
TEST_TIME 60 9750
TEST_TIME 61 9793
TEST_TIME 62 9800
TEST_TIME 63 9796
TEST_TIME 64 9801
TEST_TIME 65 9855
TEST_TIME 66 9803
TEST_TIME 67 9689
TEST_TIME 68 9629
TEST_TIME 69 9549
TEST_TIME 70 9487
TEST_TIME 71 9435
TEST_TIME 72 9365
TEST_TIME 73 9376
TEST_TIME 74 9326
TEST_TIME 75 9355
TEST_TIME 76 9357
TEST_TIME 77 9372
TEST_TIME 78 9366
TEST_TIME 79 9313
TEST_TIME 80 9305
TEST_TIME 81 9396
TEST_TIME 82 9503
TEST_TIME 83 9469
TEST_TIME 84 9472
TEST_TIME 85 9448
TEST_TIME 86 9517
TEST_TIME 87 9440
TEST_TIME 88 9334
TEST_TIME 89 9277
TEST_TIME 90 9235
TEST_TIME 91 9181
TEST_TIME 92 9144
TEST_TIME 93 9116
TEST_TIME 94 9112
TEST_TIME 95 9106
TEST_TIME 96 9101
TEST_TIME 97 9134
TEST_TIME 98 9173
TEST_TIME 99 9157
TEST_TIME 100 9191
TEST_TIME 101 9183
TEST_TIME 102 9253
TEST_TIME 103 9220
TEST_TIME 104 9194
TEST_TIME 105 9152
TEST_TIME 106 9088
TEST_TIME 107 9079
TEST_TIME 108 9019
TEST_TIME 109 8979
TEST_TIME 110 8991
TEST_TIME 111 8959
TEST_TIME 112 8938
TEST_TIME 113 8963
TEST_TIME 114 8948
TEST_TIME 115 8947
TEST_TIME 116 9003
TEST_TIME 117 9008
TEST_TIME 118 9001
TEST_TIME 119 9016
TEST_TIME 120 9034

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 22-03-2021 04:27:38 to 22-03-2021 04:27:42.

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 1497713 1497713 0 0.00 0 26672 8 0 0
doc_list_biud 1497713 1497713 0 0.00 0 58303 37 0 0
sp_add_invoice_to_stock 4890 4789 101 2.06 33 197095 20864 0 101
sp_cancel_adding_invoice 5571 5571 0 0.00 5 63813 1007 0 0
sp_cancel_client_order 1704 1704 0 0.00 4 16692 401 0 0
sp_cancel_customer_reserve 550737 550737 0 0.00 1 58314 78 0 0
sp_cancel_pay_from_customer 665 665 0 0.00 3 4252 98 0 0
sp_cancel_pay_to_supplier 420 420 0 0.00 3 6159 108 0 0
sp_cancel_supplier_invoice 6127 6123 4 0.06 3 27059 1218 0 4
sp_cancel_supplier_order 383 383 0 0.00 14 1320705 147538 0 0
sp_cancel_write_off 10086 10086 0 0.00 1 10725 143 0 0
sp_client_order 19339 19339 0 0.00 4 32058 420 0 0
sp_customer_reserve 449807 449807 0 0.00 0 157862 200 0 0
sp_fill_shopping_cart 29983 29983 0 0.00 1 14719 45 0 0
sp_fill_shopping_cart_clo_res 449801 449801 0 0.00 0 22631 17 0 0
sp_get_clo_for_invoice 4779 4779 0 0.00 0 44577 386 0 0
sp_kill_cost_storno 16742 16742 0 0.00 0 9144 73 0 0
sp_kill_qstorno_ret_qs2qd 1118066 1118066 0 0.00 0 58286 43 0 0
sp_kill_qty_storno 588853 588853 0 0.00 0 58303 92 0 0
sp_lock_selected_doc 19925 19925 0 0.00 0 8808 5 0 0
sp_make_cost_storno 26434 26434 0 0.00 0 13000 59 0 0
sp_multiply_rows_for_pdistr 26434 26434 0 0.00 0 13385 37 0 0
sp_multiply_rows_for_qdistr 19339 19339 0 0.00 0 22990 237 0 0
sp_pay_from_customer 6671 6671 0 0.00 4 13398 129 0 0
sp_pay_to_supplier 5514 5514 0 0.00 5 17920 141 0 0
sp_payment_common 12185 12185 0 0.00 1 8695 21 0 0
sp_qd_handle_on_cancel_clo 1704 1704 0 0.00 0 16529 368 0 0
sp_qd_handle_on_invoice_upd_sts 10350 10350 0 0.00 0 47338 465 0 0
sp_qd_handle_on_reserve_upd_sts 9470 9470 0 0.00 0 18164 67 0 0
sp_reserve_write_off 9470 9470 0 0.00 7 18218 199 0 0
sp_supplier_invoice 5334 5334 0 0.00 7 103479 3857 0 0
sp_supplier_order 5322 5322 0 0.00 5 27856 1492 0 0
srv_aggregate_perf_data 104 104 0 0.00 2831 251895 18447 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1533 234943 46671 0 0
srv_find_qd_qs_mism 1036654 1036654 0 0.00 0 26672 6 0 0
srv_make_invnt_saldo 380 380 0 0.00 55 110027 4859 0 0
srv_make_money_saldo 754 754 0 0.00 1 15898 635 0 0
srv_recalc_idx_stat 4 4 0 0.00 152153 609532 327490 0 0
t$perf-abend:sp_add_invoice_to_stock 101 101 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4790 4790 0 0.00 0 21741 252 0 0
t$perf-norm:sp_cancel_adding_invoice 394 394 0 0.00 0 4861 104 0 0
t$perf-norm:sp_cancel_client_order 1704 1704 0 0.00 0 1537 13 0 0
t$perf-norm:sp_cancel_customer_reserve 722 722 0 0.00 0 2365 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 665 665 0 0.00 0 11182 23 0 0
t$perf-norm:sp_cancel_pay_to_supplier 420 420 0 0.00 0 439 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 378 378 0 0.00 0 2141 32 0 0
t$perf-norm:sp_cancel_supplier_order 383 383 0 0.00 0 31364 2123 0 0
t$perf-norm:sp_cancel_write_off 1011 1011 0 0.00 0 3003 9 0 0
t$perf-norm:sp_client_order 19339 19339 0 0.00 0 7238 21 0 0
t$perf-norm:sp_customer_reserve 949 949 0 0.00 0 1382 7 0 0
t$perf-norm:sp_pay_from_customer 6671 6671 0 0.00 0 4816 12 0 0
t$perf-norm:sp_pay_to_supplier 5514 5514 0 0.00 0 9562 10 0 0
t$perf-norm:sp_reserve_write_off 9471 9471 0 0.00 0 14516 17 0 0
t$perf-norm:sp_supplier_invoice 5334 5334 0 0.00 0 9125 26 0 0
t$perf-norm:sp_supplier_order 5322 5322 0 0.00 0 3815 21 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 1605 20 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 275 6 0 0
t$perf-norm:srv_make_invnt_saldo 380 380 0 0.00 0 3625 22 0 0
t$perf-norm:srv_make_money_saldo 754 754 0 0.00 0 975 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 54 17 0 0
v_add_invoice_to_stock 9758 9556 202 2.07 0 7955 20 0 202
v_all_customers 35390 35390 0 0.00 0 35 1 0 0
v_all_suppliers 29546 29546 0 0.00 0 55 1 0 0
v_all_wares 38678 38678 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 788 788 0 0.00 0 967 7 0 0
v_cancel_client_order 3408 3408 0 0.00 0 1207 4 0 0
v_cancel_customer_prepayment 1330 1330 0 0.00 0 2060 9 0 0
v_cancel_customer_reserve 1444 1444 0 0.00 0 2100 6 0 0
v_cancel_payment_to_supplier 840 840 0 0.00 0 1232 6 0 0
v_cancel_supplier_invoice 764 756 8 1.04 0 183 12 0 8
v_cancel_supplier_order 766 766 0 0.00 0 3742 13 0 0
v_cancel_write_off 2022 2022 0 0.00 0 5394 18 0 0
v_max_id_clo_ord 5318 5318 0 0.00 0 300 1 0 0
v_max_id_clo_res 949 949 0 0.00 0 2975 17 0 0
v_max_id_ord_sup 5326 5326 0 0.00 0 4694 5 0 0
v_max_non_paid_invoice 1385 1385 0 0.00 0 1196 3 0 0
v_max_non_paid_realizn 3424 3424 0 0.00 0 1197 4 0 0
v_min_id_clo_ord 5318 5318 0 0.00 0 4853 10 0 0
v_min_id_clo_res 949 949 0 0.00 0 1607 5 0 0
v_min_id_ord_sup 5326 5326 0 0.00 0 6559 9 0 0
v_min_non_paid_invoice 5514 5514 0 0.00 0 5033 12 0 0
v_min_non_paid_realizn 6671 6671 0 0.00 0 4796 14 0 0
v_our_firm 9782 9782 0 0.00 0 25 1 0 0
v_reserve_write_off 18940 18940 0 0.00 0 6141 5 0 0
x_lock_depdocs_on_canc_invoice 11316 11316 0 0.00 0 33167 150 0 0
x_lock_depdocs_on_canc_sup_ord 383 383 0 0.00 0 3100 398 0 0
x_make_qstorno_1000_1200 5322 5322 0 0.00 1 27790 1394 0 0
x_make_qstorno_1200_2000 5334 5334 0 0.00 3 100652 3697 0 0
x_make_qstorno_2100_3300 449807 449807 0 0.00 0 157862 179 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-03-2021 04:27:43 to 22-03-2021 04:27:47.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
01:57:02 2 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
01:58:03 27742 8192000000 9734924992 10033733632 813328640 9.031 100 88 177 0 842530464 8034144 21167680
01:59:40 20004 8192000000 9772397664 10081435648 849530688 9.395 100 92 184 1 885001920 13806128 21665104
02:01:02 21975 8192000000 9814969808 10133905408 879769360 9.697 100 92 184 0 920731936 19907120 21055456
02:02:30 14572 8192000000 9877150656 10209669120 920670656 10.103 100 87 173 1 981523120 41579440 19273024
02:03:52 9878 8192000000 9909643392 10252464128 945694992 10.349 100 76 152 1 1023476400 61547024 16234384
02:05:07 5183 8192000000 9952848128 10301001728 975640832 10.642 100 74 148 0 1061726720 70385744 15700144
02:06:14 2213 8192000000 9975880848 10335318016 1015436000 11.028 100 83 159 0 1093320032 61667472 16216560
02:07:18 2535 8192000000 10015210848 10384179200 1038529072 11.251 100 86 172 0 1129942368 73452336 17960960
02:08:22 1851 8192000000 10038372112 10410237952 1056237744 11.420 100 93 182 0 1146013696 71228272 18547680
02:09:25 12142 8192000000 10016339520 10401558528 1070784624 11.560 100 70 141 2 1135530512 51056272 13689616
02:10:43 7401 8192000000 10000724960 10392014848 1072635184 11.577 100 35 69 1 1103524736 23580640 7308912
02:11:51 3611 8192000000 10001871872 10394361856 1078112496 11.629 100 43 84 0 1116716864 29944208 8660160
02:12:57 4629 8192000000 9986188784 10387468288 1083359760 11.679 100 43 86 2 1102362016 10124704 8877552
02:14:03 8121 8192000000 9995742496 10406285312 1086461152 11.709 100 29 58 1 1119767472 27315504 5990816
02:15:12 17276 8192000000 10010178096 10425614336 1086477280 11.709 100 29 57 3 1109247056 16428080 6341696
02:16:35 5265 8192000000 10020352320 10423939072 1093110336 11.772 100 54 108 0 1129577360 25441088 11025936
02:17:42 9595 8192000000 9980686896 10376302592 1044778784 11.311 97 37 75 0 1078310080 24376464 9154832
02:18:55 4834 8192000000 9982043136 10376257536 1071599888 11.567 100 41 82 1 1093066896 12102096 9364912
02:20:01 35460 8192000000 9948430480 10341330944 972063360 10.607 91 25 51 0 992573792 14999264 5511168
02:21:37 13461 8192000000 9945953536 10333691904 976297632 10.648 94 31 62 0 1007007648 24258048 6451968
02:22:53 4305 8192000000 9936112448 10306891776 1007879936 10.955 98 42 80 1 1029892272 13013072 8999264
02:23:58 9949 8192000000 9971358864 10364141568 1045991120 11.322 100 33 67 0 1077443456 23934896 7517440
02:25:15 16769 8192000000 9986477600 10363289600 1051596640 11.376 100 74 147 3 1084982832 17474784 15911408
02:26:32 5545 8192000000 9987915904 10374955008 1064949312 11.504 100 49 96 1 1091190784 14991552 11249920
02:27:41 8793 8192000000 10029809808 10421657600 1081841776 11.665 100 55 110 1 1132593952 38999760 11752416
02:28:59 50492 8192000000 10039393808 10451001344 1110286304 11.935 100 43 86 0 1159917120 39930032 9700784
02:30:52 16263 8192000000 10104730432 10509135872 1118910768 12.017 100 53 106 1 1207253792 77390880 10952144
02:32:12 16659 8192000000 10117474880 10523504640 1126133728 12.085 100 55 109 0 1220782896 83015456 11633712
02:33:33 10097 8192000000 10142256352 10550476800 1138196160 12.199 100 51 102 2 1244768880 95599712 10973008
02:34:51 7142 8192000000 10152462448 10568204288 1148948976 12.300 100 42 81 1 1269695088 112065824 8680288
02:36:00 5210 8192000000 10206396512 10615717888 1152561808 12.334 100 68 134 1 1312861984 145916080 14384096
02:37:09 7641 8192000000 10205714624 10639822848 1155426544 12.360 100 58 113 1 1311859952 144264736 12168672
02:38:20 4219 8192000000 10246443472 10655809536 1178389040 12.575 100 83 165 1 1346696624 150092992 18214592
02:39:30 1868 8192000000 10234161200 10639331328 1164158992 12.442 98 74 143 2 1341164176 161793552 15211632
02:40:37 1686 8192000000 10283773216 10686095360 1184822992 12.635 100 96 192 0 1385736992 180126320 20787680
02:41:44 5002 8192000000 10304898080 10705723392 1175093424 12.544 99 85 166 2 1398523936 205369040 18061472
02:42:54 1529 8192000000 10367235440 10769883136 1199553520 12.772 100 77 154 0 1464510160 247266912 17689728
02:44:01 6263 8192000000 10376328448 10788478976 1201286560 12.788 100 64 127 0 1479233184 262203968 15742656
02:45:14 3836 8192000000 10406986752 10808901632 1180083088 12.591 100 82 162 1 1495699936 297314448 18302400
02:46:21 2372 8192000000 10440270816 10844078080 1178443952 12.576 100 90 182 1 1538049280 339100656 20504672
02:47:30 2375 8192000000 10494199664 10899267584 1179247088 12.583 100 91 182 0 1593366528 392600704 21518736
02:48:36 3722 8192000000 10532221936 10935558144 1180819168 12.598 100 80 160 1 1626190128 425587648 19783312
02:49:45 4528 8192000000 10575854864 10983075840 1170093008 12.498 100 82 163 1 1672932528 482929696 19909824
02:50:59 3309 8192000000 10581486416 11019354112 1183148832 12.620 100 86 169 0 1675132656 471338464 20645360
02:52:09 2958 8192000000 10556444976 10987995136 1172169424 12.517 100 71 142 0 1651938576 462426416 17342736
02:53:30 9332 8192000000 10526847936 10972819456 1178351568 12.575 100 37 74 0 1632681664 445180928 9149168
02:54:41 19433 8192000000 10559498224 11036684288 1155970960 12.366 99 25 50 0 1639644368 476859408 6814000
02:56:12 10994 8192000000 10606961136 11059470336 1124303328 12.068 99 42 84 0 1697258688 561066768 11888592
02:57:32 14536 8192000000 10657060064 11106963456 1132721824 12.147 100 39 78 0 1755659568 611768608 11169136
02:58:51 37930 8192000000 10205874240 10820300800 1145544368 12.268 100 24 48 1 1416637504 265259936 5833200
03:00:53 85873 8192000000 10141635088 10689794048 1150434400 12.314 100 24 48 0 1196683648 41413088 4836160
03:03:28 41496 8192000000 10058807072 10481106944 1098013024 11.819 96 49 97 2 1133631664 25851312 9767328
03:05:10 123286 8192000000 10063489344 10531213312 1109478512 11.927 98 23 47 2 1188059056 73652352 4928192
03:08:18 23085 8192000000 10078571536 10517471232 1141185296 12.227 100 40 78 3 1170081472 21233792 7662384
03:09:43 60948 8192000000 10108113920 10537308160 1105258368 11.888 96 31 62 1 1163479664 51637136 6584160
03:11:54 43652 8192000000 10078965360 10547511296 1110245248 11.935 98 19 38 0 1167399376 53502464 3651664
03:13:51 46006 8192000000 10061218848 10523697152 1101421184 11.851 97 13 25 1 1142607904 38314864 2871856
03:15:38 234943 8192000000 10118411376 10624561152 1150040192 12.310 100 14 28 0 1232844016 79566352 3237472
03:21:24 50324 8192000000 10139762544 10624028672 1150769456 12.317 99 16 32 0 1207517680 53394288 3353936
03:24:11 66401 8192000000 10068377424 10535366656 1094398800 11.784 95 22 44 0 1132852960 33762544 4691616
03:26:28 65806 8192000000 10125960992 10588659712 1127732000 12.100 98 29 58 0 1188292048 55027264 5532784
03:28:47 35443 8192000000 10073309856 10519576576 1116892592 11.998 97 29 58 2 1152634336 30080224 5661520
03:30:23 62218 8192000000 10088653712 10575372288 1128614224 12.108 99 22 44 0 1194013920 61102080 4297616
03:32:35 114784 8192000000 10097118992 10556567552 1111440384 11.946 98 26 51 1 1151534112 33933488 6160240
03:35:31 42005 8192000000 10059054432 10552741888 1137869280 12.195 100 13 26 1 1183746992 42775296 3102416
03:37:41 54486 8192000000 10078820688 10565193728 1140713040 12.222 100 3 6 0 1172427408 31365248 349120
03:39:37 87394 8192000000 10101328016 10561183744 1103326144 11.869 96 15 30 0 1162551600 56007680 3217776
03:42:08 105585 8192000000 10070636672 10531033088 1063126704 11.486 93 27 54 0 1122213536 53256672 5830160
03:45:21 40881 8192000000 10134535184 10591281152 1135409776 12.172 100 29 58 0 1228366912 87094128 5863008
03:47:06 39349 8192000000 10123764144 10566123520 1139014368 12.206 100 20 40 0 1216002080 72953792 4033920
03:48:53 105673 8192000000 10101154304 10538479616 1117087696 11.999 98 19 36 0 1163199920 41882176 4230048
03:51:45 117504 8192000000 10080604080 10539216896 1110048704 11.933 97 14 28 0 1202890784 89964800 2877280
03:54:48 81859 8192000000 10067882288 10524336128 1137281728 12.190 100 18 36 0 1191201808 50001008 3919072
03:57:18 40354 8192000000 10057714000 10547937280 1097044528 11.810 97 7 14 1 1145364592 46968976 1351088
03:58:58 59742 8192000000 10062476784 10539319296 1084250608 11.688 97 11 21 1 1141480304 55243072 1986624
04:01:01 63990 8192000000 10107850192 10563002368 1116385872 11.993 99 15 30 0 1164507776 44999984 3121920
04:03:07 105299 8192000000 10079856224 10581803008 1123927248 12.064 99 12 24 0 1189697152 63143840 2626064
04:06:11 81039 8192000000 10121679344 10618712064 1139696944 12.213 100 17 34 0 1236167776 92792736 3678096
04:08:32 111037 8192000000 10081837008 10513809408 1046519408 11.327 91 17 34 0 1123849488 74152816 3177264
04:11:42 107625 8192000000 10082350064 10560995328 1126914832 12.092 99 19 38 0 1167550896 36614464 4021600
04:14:36 51022 8192000000 10097181968 10576515072 1142454416 12.239 100 6 12 0 1191241472 47726848 1060208
04:16:29 59893 8192000000 10067892592 10547326976 1109846320 11.931 98 12 24 1 1177176176 64409424 2920432
04:18:33 72392 8192000000 10091274560 10571694080 1106579536 11.900 97 15 30 0 1161915024 52159056 3176432
04:20:46 55697 8192000000 10152128000 10604666880 1122287888 12.049 98 39 78 1 1205137440 75106576 7742976
04:22:42 80344 8192000000 10089269312 10520465408 1100144304 11.839 96 13 26 0 1149360848 46755200 2461344
04:25:03 54488 8192000000 10075359744 10503426048 1095483600 11.795 97 24 48 0 1135755040 35770896 4500544
04:26:58 10075 8192000000 8963503936 9230893056 63505920 0.769 6 1 2 0 68235456 4694128 35408

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 22-03-2021 04:27:49 to 22-03-2021 04:28:04.

Exceptions occured during test was in run

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

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

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          227812
MON$OLDEST_ACTIVE               227813
MON$OLDEST_SNAPSHOT             227813
MON$NEXT_TRANSACTION            227813
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                       2102336
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             786
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2390 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2390 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2390 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.2390 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 ---
--- end of diff output ---

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

22.03.21 04:28:07. 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-22 04:28:10 No severe PSQL-related problems occured
22.03.21 04:28:11. End of report.