Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 31.08.2021 07:49
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 15805880 kB
MemAvailable 30904096 kB
Buffers 0 kB
Cached 15857168 kB
SwapCached 324 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-31 07:48:50
DTS_END                         2021-08-31 07:48:50.2400
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/57786
TRN_ID                          235079
ATT_ID                          642
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: 9223.49 19281 1106819
customer order: creation 175.81 351 21097
customer order: refuse 14.89 390 1787
order to supplier: creation 45.44 1402 5453
order to supplier: removal 3.23 115280 388
invoice (draft): creation 46.70 3911 5604
invoice (draft): removal 48.93 1021 5871
invoice accept: apply 42.91 18221 5149
invoice accept: cancel 44.95 966 5394
customer reserve: creation 4044.78 174 485373
customer reserve: removal 4466.89 64 536027
realization accept: apply 83.94 200 10073
realization accept: cancel 77.00 132 9240
payment from customer: creation 58.84 133 7061
payment from customer: removal 5.63 89 675
payment to supplier: creation 49.55 132 5946
payment to supplier: removal 3.34 73 401
service: total inventory turnovers 3.78 3713 454
service: total monetary turnovers 6.09 639 731
service: aggregate perf. data 0.75 20413 90
service: refresh index statistics 0.04 218307 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 31-08-2021 07:49:30 to 31-08-2021 07:49:32.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 753
TEST_TIME 2 594
TEST_TIME 3 2982
TEST_TIME 4 3271
TEST_TIME 5 3838
TEST_TIME 6 3869
TEST_TIME 7 4110
TEST_TIME 8 4371
TEST_TIME 9 4754
TEST_TIME 10 4937
TEST_TIME 11 5462
TEST_TIME 12 5943
TEST_TIME 13 6251
TEST_TIME 14 6682
TEST_TIME 15 6820
TEST_TIME 16 7412
TEST_TIME 17 7732
TEST_TIME 18 8078
TEST_TIME 19 8072
TEST_TIME 20 8092
TEST_TIME 21 8336
TEST_TIME 22 8572
TEST_TIME 23 8847
TEST_TIME 24 8780
TEST_TIME 25 8726
TEST_TIME 26 8850
TEST_TIME 27 9119
TEST_TIME 28 9720
TEST_TIME 29 10171
TEST_TIME 30 10720
TEST_TIME 31 10465
TEST_TIME 32 10626
TEST_TIME 33 10501
TEST_TIME 34 10356
TEST_TIME 35 10221
TEST_TIME 36 10165
TEST_TIME 37 10163
TEST_TIME 38 10180
TEST_TIME 39 10077
TEST_TIME 40 10208
TEST_TIME 41 10136
TEST_TIME 42 10190
TEST_TIME 43 10251
TEST_TIME 44 10197
TEST_TIME 45 10132
TEST_TIME 46 10170
TEST_TIME 47 10222
TEST_TIME 48 10024
TEST_TIME 49 9873
TEST_TIME 50 9950
TEST_TIME 51 9841
TEST_TIME 52 9763
TEST_TIME 53 9716
TEST_TIME 54 9627
TEST_TIME 55 9753
TEST_TIME 56 9755
TEST_TIME 57 9674
TEST_TIME 58 9598
TEST_TIME 59 9499
TEST_TIME 60 9483
TEST_TIME 61 9602
TEST_TIME 62 9657
TEST_TIME 63 9647
TEST_TIME 64 9744
TEST_TIME 65 9742
TEST_TIME 66 9681
TEST_TIME 67 9753
TEST_TIME 68 9742
TEST_TIME 69 9751
TEST_TIME 70 9720
TEST_TIME 71 9770
TEST_TIME 72 9785
TEST_TIME 73 9861
TEST_TIME 74 9825
TEST_TIME 75 9837
TEST_TIME 76 9724
TEST_TIME 77 9663
TEST_TIME 78 9606
TEST_TIME 79 9555
TEST_TIME 80 9559
TEST_TIME 81 9507
TEST_TIME 82 9490
TEST_TIME 83 9465
TEST_TIME 84 9409
TEST_TIME 85 9375
TEST_TIME 86 9335
TEST_TIME 87 9298
TEST_TIME 88 9337
TEST_TIME 89 9387
TEST_TIME 90 9380
TEST_TIME 91 9340
TEST_TIME 92 9316
TEST_TIME 93 9392
TEST_TIME 94 9435
TEST_TIME 95 9449
TEST_TIME 96 9419
TEST_TIME 97 9456
TEST_TIME 98 9471
TEST_TIME 99 9493
TEST_TIME 100 9508
TEST_TIME 101 9427
TEST_TIME 102 9376
TEST_TIME 103 9372
TEST_TIME 104 9360
TEST_TIME 105 9308
TEST_TIME 106 9271
TEST_TIME 107 9254
TEST_TIME 108 9231
TEST_TIME 109 9179
TEST_TIME 110 9150
TEST_TIME 111 9166
TEST_TIME 112 9157
TEST_TIME 113 9132
TEST_TIME 114 9163
TEST_TIME 115 9200
TEST_TIME 116 9209
TEST_TIME 117 9184
TEST_TIME 118 9144
TEST_TIME 119 9240
TEST_TIME 120 9224

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 31-08-2021 07:49:34 to 31-08-2021 07:49:37.

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 1553506 1553506 0 0.00 0 13614 7 0 0
doc_list_biud 1553506 1553506 0 0.00 0 76878 29 0 0
sp_add_invoice_to_stock 5307 5149 158 2.97 60 158732 18221 0 158
sp_cancel_adding_invoice 5394 5394 0 0.00 5 59052 966 0 0
sp_cancel_client_order 1787 1787 0 0.00 5 9727 391 0 0
sp_cancel_customer_reserve 536027 536027 0 0.00 1 20776 65 0 0
sp_cancel_pay_from_customer 675 675 0 0.00 3 4428 90 0 0
sp_cancel_pay_to_supplier 401 401 0 0.00 3 1667 73 0 0
sp_cancel_supplier_invoice 5880 5871 9 0.15 1 76934 1022 0 9
sp_cancel_supplier_order 388 388 0 0.00 16 1240995 115281 0 0
sp_cancel_write_off 9240 9240 0 0.00 1 17914 132 0 0
sp_client_order 21097 21097 0 0.00 4 26761 351 0 0
sp_customer_reserve 485373 485373 0 0.00 0 105663 175 0 0
sp_fill_shopping_cart 32144 32144 0 0.00 1 22957 49 0 0
sp_fill_shopping_cart_clo_res 485372 485372 0 0.00 0 11952 15 0 0
sp_get_clo_for_invoice 5148 5148 0 0.00 0 36369 379 0 0
sp_kill_cost_storno 15710 15710 0 0.00 0 7333 67 0 0
sp_kill_qstorno_ret_qs2qd 1087553 1087553 0 0.00 0 76877 35 0 0
sp_kill_qty_storno 573928 573928 0 0.00 0 76878 78 0 0
sp_lock_selected_doc 20996 20996 0 0.00 0 6907 3 0 0
sp_make_cost_storno 28228 28228 0 0.00 0 13595 65 0 0
sp_multiply_rows_for_pdistr 28228 28228 0 0.00 0 7720 37 0 0
sp_multiply_rows_for_qdistr 21097 21097 0 0.00 0 13476 162 0 0
sp_pay_from_customer 7061 7061 0 0.00 4 19887 133 0 0
sp_pay_to_supplier 5946 5946 0 0.00 4 10844 132 0 0
sp_payment_common 13007 13007 0 0.00 2 9171 27 0 0
sp_qd_handle_on_cancel_clo 1787 1787 0 0.00 0 9699 365 0 0
sp_qd_handle_on_invoice_upd_sts 10542 10542 0 0.00 0 21137 414 0 0
sp_qd_handle_on_reserve_upd_sts 10073 10073 0 0.00 0 14032 58 0 0
sp_reserve_write_off 10073 10073 0 0.00 6 19340 201 0 0
sp_supplier_invoice 5604 5604 0 0.00 7 89100 3912 0 0
sp_supplier_order 5453 5453 0 0.00 6 54966 1402 0 0
srv_aggregate_perf_data 90 90 0 0.00 2157 253107 20413 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1487 176011 44056 0 0
srv_find_qd_qs_mism 1056679 1056679 0 0.00 0 10870 5 0 0
srv_make_invnt_saldo 454 454 0 0.00 73 94472 3714 0 0
srv_make_money_saldo 731 731 0 0.00 1 16090 640 0 0
srv_recalc_idx_stat 5 5 0 0.00 151309 301868 218307 0 0
t$perf-abend:sp_add_invoice_to_stock 158 158 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5149 5149 0 0.00 0 8957 168 0 0
t$perf-norm:sp_cancel_adding_invoice 424 424 0 0.00 0 3824 100 0 0
t$perf-norm:sp_cancel_client_order 1787 1787 0 0.00 0 3490 13 0 0
t$perf-norm:sp_cancel_customer_reserve 707 707 0 0.00 0 7635 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 675 675 0 0.00 0 505 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 401 401 0 0.00 0 707 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 386 386 0 0.00 0 943 11 0 0
t$perf-norm:sp_cancel_supplier_order 388 388 0 0.00 0 16851 1767 0 0
t$perf-norm:sp_cancel_write_off 1007 1007 0 0.00 0 3474 12 0 0
t$perf-norm:sp_client_order 21097 21097 0 0.00 0 12960 19 0 0
t$perf-norm:sp_customer_reserve 1044 1044 0 0.00 0 12703 17 0 0
t$perf-norm:sp_pay_from_customer 7061 7061 0 0.00 0 5059 9 0 0
t$perf-norm:sp_pay_to_supplier 5946 5946 0 0.00 0 4143 11 0 0
t$perf-norm:sp_reserve_write_off 10073 10073 0 0.00 0 12988 13 0 0
t$perf-norm:sp_supplier_invoice 5604 5604 0 0.00 0 4168 18 0 0
t$perf-norm:sp_supplier_order 5453 5453 0 0.00 0 4737 19 0 0
t$perf-norm:srv_aggregate_perf_data 90 90 0 0.00 0 268 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 3300 51 0 0
t$perf-norm:srv_make_invnt_saldo 454 454 0 0.00 0 2251 28 0 0
t$perf-norm:srv_make_money_saldo 731 731 0 0.00 0 2973 11 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 8 4 0 0
v_add_invoice_to_stock 10612 10296 316 2.97 0 5564 16 0 316
v_all_customers 38328 38328 0 0.00 0 18 1 0 0
v_all_suppliers 30886 30886 0 0.00 0 29 1 0 0
v_all_wares 42194 42194 0 0.00 0 88 1 0 0
v_cancel_adding_invoice 848 848 0 0.00 0 419 7 0 0
v_cancel_client_order 3574 3574 0 0.00 0 1502 4 0 0
v_cancel_customer_prepayment 1350 1350 0 0.00 0 1788 5 0 0
v_cancel_customer_reserve 1414 1414 0 0.00 0 2326 10 0 0
v_cancel_payment_to_supplier 802 802 0 0.00 0 1657 6 0 0
v_cancel_supplier_invoice 790 772 18 2.27 0 2270 18 0 18
v_cancel_supplier_order 776 776 0 0.00 0 2367 6 0 0
v_cancel_write_off 2014 2014 0 0.00 0 1594 17 0 0
v_max_id_clo_ord 5453 5453 0 0.00 0 12243 3 0 0
v_max_id_clo_res 1044 1044 0 0.00 0 7958 21 0 0
v_max_id_ord_sup 5589 5589 0 0.00 0 6867 6 0 0
v_max_non_paid_invoice 1544 1544 0 0.00 0 295 2 0 0
v_max_non_paid_realizn 3798 3798 0 0.00 0 608 3 0 0
v_min_id_clo_ord 5453 5453 0 0.00 0 2280 5 0 0
v_min_id_clo_res 1044 1044 0 0.00 0 9409 31 0 0
v_min_id_ord_sup 5589 5589 0 0.00 0 6811 7 0 0
v_min_non_paid_invoice 5946 5946 0 0.00 0 7190 17 0 0
v_min_non_paid_realizn 7061 7061 0 0.00 0 8843 23 0 0
v_our_firm 10392 10392 0 0.00 0 17 1 0 0
v_reserve_write_off 20146 20146 0 0.00 0 5816 6 0 0
x_lock_depdocs_on_canc_invoice 10879 10879 0 0.00 0 18568 127 0 0
x_lock_depdocs_on_canc_sup_ord 388 388 0 0.00 0 39376 486 0 0
x_make_qstorno_1000_1200 5453 5453 0 0.00 2 54957 1313 0 0
x_make_qstorno_1200_2000 5604 5604 0 0.00 3 87939 3722 0 0
x_make_qstorno_2100_3300 485373 485373 0 0.00 0 105662 157 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 31-08-2021 07:49:38 to 31-08-2021 07:49:42.

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
05:18:54 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:19:55 24063 8192000000 9706135968 10005778432 790805360 8.803 100 84 167 0 819235264 7173040 21256864
05:21:21 14264 8192000000 9758505520 10064289792 834898864 9.249 100 83 166 1 865713536 11530096 19284576
05:22:54 19246 8192000000 9811904704 10125529088 866083440 9.561 100 90 178 2 916195760 28933344 21178976
05:24:16 13817 8192000000 9850363824 10182651904 907742960 9.975 100 74 148 1 959764048 35736320 16284768
05:25:33 10810 8192000000 9919122112 10265919488 962215584 10.511 100 74 148 1 1034592480 56518768 15858128
05:27:00 5641 8192000000 9972689872 10324885504 998995216 10.869 100 81 163 0 1082044064 66440640 16608208
05:28:07 4391 8192000000 9991913040 10352381952 1028363408 11.153 100 87 175 1 1106608368 60913568 17331392
05:29:15 6093 8192000000 10024265136 10392297472 1047945360 11.341 100 85 170 3 1143918032 78687120 17285552
05:30:23 1711 8192000000 10034842144 10406428672 1074014256 11.590 100 89 178 0 1144688832 52427376 18247200
05:31:26 8795 8192000000 10033249760 10408787968 1084551696 11.691 100 89 176 0 1150290784 46828688 18910400
05:32:35 5770 8192000000 9994030160 10394701824 1087337136 11.717 100 23 46 0 1115807568 23848160 4622272
05:33:42 7924 8192000000 10005834592 10403917824 1095900368 11.799 100 31 63 1 1119814608 17469968 6444272
05:34:55 66776 8192000000 9996121344 10400432128 1101229088 11.849 100 38 77 0 1124510944 14440688 8841168
05:37:02 4442 8192000000 10042323360 10447114240 1102443536 11.861 100 42 85 2 1150698944 39477680 8777728
05:38:09 5586 8192000000 10016279632 10418331648 1102322032 11.860 100 40 77 4 1132160768 20011440 9827296
05:39:17 4258 8192000000 9998127856 10399924224 1088336464 11.727 99 35 68 1 1110046944 14206400 7504080
05:40:21 8341 8192000000 10005696560 10388664320 1076848032 11.617 99 64 127 1 1104300048 12814560 14637456
05:41:30 5900 8192000000 9974108144 10363633664 1037456032 11.240 99 44 90 1 1067994576 21320016 9218528
05:42:36 5658 8192000000 9939380752 10328633344 1009926000 10.975 99 49 101 1 1042359200 20963008 11470192
05:43:42 8463 8192000000 9921301424 10304655360 983653984 10.720 97 36 73 1 1008041392 16330448 8056960
05:44:52 21893 8192000000 9939124544 10324852736 1008392816 10.960 98 18 34 1 1026870336 14879584 3597936
05:46:18 8586 8192000000 9961193184 10353811456 1043468096 11.298 100 39 76 3 1072240000 20373504 8398400
05:47:29 8845 8192000000 9971350720 10376974336 1054779472 11.406 100 27 53 0 1088754432 27769792 6205168
05:48:41 100366 8192000000 10010342448 10409480192 1083388720 11.680 100 39 78 3 1100919312 8783248 8747344
05:51:46 12521 8192000000 10059291696 10461925376 1114052864 11.971 100 42 82 2 1165388752 41920688 9415200
05:53:01 12007 8192000000 10103911360 10501926912 1126528240 12.089 100 53 105 0 1213858096 75886400 11443456
05:54:21 11786 8192000000 10146901440 10552643584 1138625200 12.203 100 39 78 0 1249902336 101910608 9366528
05:55:41 32917 8192000000 10184254064 10597404672 1145317840 12.266 100 52 104 2 1304448512 147222528 11908144
05:57:28 17348 8192000000 10215822976 10637619200 1161668080 12.419 100 55 108 2 1319317664 145313200 12336384
05:58:49 2331 8192000000 10239945312 10655023104 1158527024 12.389 100 64 128 0 1342723968 170144032 14052912
05:59:56 2519 8192000000 10238250592 10674360320 1171276800 12.509 100 65 129 1 1342666080 155501344 15887936
06:01:03 4651 8192000000 10249064960 10667524096 1167372208 12.472 100 54 105 0 1351521648 172127408 12022032
06:02:11 1485 8192000000 10249105120 10654158848 1148268384 12.293 98 80 156 2 1340556400 175178880 17109136
06:03:18 1936 8192000000 10277023520 10686308352 1169195728 12.489 100 76 151 1 1374311904 188125776 16990400
06:04:25 1930 8192000000 10294567680 10702376960 1168494496 12.483 100 80 160 0 1387078752 200745664 17838592
06:05:34 2253 8192000000 10292048496 10711969792 1155292960 12.359 99 88 176 1 1373929264 198917840 19718464
06:06:39 6695 8192000000 10271437248 10690551808 1145002336 12.263 100 48 96 0 1377215584 220981584 11231664
06:07:52 3105 8192000000 10302701584 10704093184 1140555424 12.221 100 78 156 0 1391662880 233766064 17341392
06:09:02 5325 8192000000 10361639424 10752438272 1137155264 12.189 99 84 168 2 1441304192 284794144 19354784
06:10:13 22194 8192000000 10332756032 10765918208 1139591280 12.212 99 42 84 2 1427196704 276586752 11018672
06:11:37 21549 8192000000 10331388272 10778763264 1147743088 12.288 100 28 56 2 1423636992 268969216 6924688
06:13:04 19432 8192000000 10383053312 10818007040 1149102304 12.301 100 30 60 0 1483505264 326403024 7999936
06:14:26 14971 8192000000 10410269776 10853646336 1138902128 12.205 100 34 68 1 1515360784 367519664 8938992
06:15:54 14330 8192000000 10379188320 10817794048 1124307808 12.068 100 27 54 0 1481456800 349963216 7185776
06:17:18 9891 8192000000 10209577968 10704912384 1106285744 11.897 99 34 66 1 1293443824 179307968 7850112
06:18:32 57031 8192000000 10077230800 10496663552 1098482992 11.823 99 29 56 1 1161189248 56992336 5713920
06:20:31 21835 8192000000 10069456496 10556334080 1105874592 11.893 99 19 38 0 1169292624 59423584 3994448
06:22:13 26572 8192000000 10090552256 10525151232 1112462000 11.956 99 15 28 1 1152557216 37235840 2859376
06:23:45 55034 8192000000 10117445360 10580037632 1106777392 11.902 98 16 32 0 1199348352 89164208 3406752
06:25:41 90623 8192000000 10119102848 10558246912 1117896400 12.007 99 19 38 0 1181725488 60395376 3433712
06:28:14 37151 8192000000 10072161472 10558234624 1117185168 12.000 99 24 47 0 1205220224 82354288 5680768
06:30:01 68822 8192000000 10133986368 10626846720 1142371696 12.238 100 14 29 0 1231877584 86768096 2737792
06:32:23 44379 8192000000 10076256624 10520829952 1117607616 12.004 98 14 28 0 1149155600 28895312 2652672
06:34:07 38693 8192000000 10127254944 10594492416 1136724288 12.185 99 29 58 1 1225798992 83180944 5893760
06:36:25 122900 8192000000 10124909888 10552086528 1120522160 12.032 98 34 68 1 1195908192 68286928 7099104
06:39:33 69954 8192000000 10097279456 10540253184 1140172240 12.217 100 29 56 2 1177243520 30565376 6505904
06:41:45 48806 8192000000 10141100368 10588102656 1136325776 12.181 99 14 28 1 1234370032 94845712 3198544
06:44:09 34087 8192000000 10100057984 10565906432 1142389680 12.238 99 25 49 2 1210086032 62536176 5160176
06:45:50 55725 8192000000 10142001840 10615070720 1158848448 12.392 100 14 28 0 1244066656 82193568 3024640
06:47:47 78333 8192000000 10124046032 10641129472 1085509664 11.700 94 18 36 0 1231617392 142204112 3903616
06:50:07 47677 8192000000 10111704288 10620133376 1102156560 11.858 97 15 30 0 1189526240 83965312 3404368
06:52:05 79437 8192000000 10063009936 10521956352 1038304864 11.248 91 12 24 0 1086528320 46023600 2199856
06:54:26 24762 8192000000 10104076464 10541883392 1113698624 11.967 99 36 72 2 1178377200 57056976 7621600
06:55:51 71530 8192000000 10074695520 10512633856 1108969664 11.923 98 20 40 1 1167906624 54587088 4349872
06:58:05 28831 8192000000 10069605104 10505195520 1090375712 11.746 97 37 75 2 1149655232 51622208 7657312
06:59:41 101924 8192000000 10108971984 10615693312 1092227184 11.764 97 8 16 0 1155483920 61748896 1507840
07:02:54 64783 8192000000 10064833296 10552905728 1093523216 11.776 97 23 46 0 1166968672 68445744 4999712
07:05:05 54376 8192000000 10072299008 10522341376 1113554480 11.966 100 21 42 0 1150452560 32530656 4367424
07:07:05 25826 8192000000 10060722864 10518863872 1112423232 11.955 100 30 60 0 1160946064 42648288 5874544
07:08:33 52471 8192000000 10057480272 10503602176 1092022224 11.762 98 18 36 0 1136677888 41488960 3166704
07:10:26 48800 8192000000 10040265952 10492485632 1074354336 11.594 97 11 22 0 1137012624 59998896 2659392
07:12:15 59718 8192000000 10050037520 10508070912 1071762800 11.569 97 17 34 0 1118545104 43075920 3706384
07:15:53 59975 8192000000 10084067792 10559447040 1113864640 11.969 100 16 33 0 1203244576 85779824 3600112
07:17:55 23250 8192000000 10050236880 10492211200 1111376992 11.945 99 20 40 0 1130256608 14742128 4137488
07:19:22 43855 8192000000 10112931840 10552631296 1117004464 11.999 99 27 54 0 1190851312 68364624 5482224
07:21:06 32768 8192000000 10085733296 10540675072 1121487152 12.041 100 23 46 1 1192533776 66359472 4687152
07:22:39 102456 8192000000 10100455136 10561871872 1121772768 12.044 99 17 33 1 1170322448 44963232 3586448
07:25:26 99021 8192000000 10098148064 10606161920 1119494592 12.022 98 24 48 0 1182572912 58837456 4240864
07:28:10 176011 8192000000 10080781504 10555781120 1116991056 11.999 97 24 48 2 1175146784 52915968 5239760
07:32:07 42330 8192000000 10068546768 10564190208 1129462128 12.116 99 16 32 0 1193486768 60352752 3671888
07:33:56 73009 8192000000 10084576368 10546454528 1118867456 12.016 98 12 24 0 1160232176 38729024 2635696
07:36:10 38028 8192000000 10074060176 10538463232 1138507792 12.201 100 28 56 0 1187492944 42867344 6117808
07:37:48 77710 8192000000 10097417536 10570739712 1105278720 11.888 97 10 20 0 1166638048 59118976 2240352
07:40:08 37656 8192000000 10039428784 10480529408 1075322944 11.603 95 17 34 0 1092997776 14158496 3516336
07:41:47 51384 8192000000 10137865088 10605813760 1117065968 11.999 98 26 52 0 1206184208 83534288 5583952
07:43:48 48388 8192000000 10072158656 10506174464 1111087392 11.943 99 18 36 0 1151845760 37169072 3589296
07:45:47 68037 8192000000 10180190464 10627903488 1125402976 12.078 99 26 52 1 1223974640 93498912 5072752
07:47:55 58053 8192000000 9234781248 9528430592 342321664 4.011 31 7 12 1 368275424 24596016 1357744

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 31-08-2021 07:49:44 to 31-08-2021 07:49:59.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 158 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 31-08-2021 07:50:00 to 31-08-2021 07:50:02.

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          235383
MON$OLDEST_ACTIVE               235384
MON$OLDEST_SNAPSHOT             235384
MON$NEXT_TRANSACTION            235384
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                       2079072
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             800
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

31.08.21 07:50:03. 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-31 07:50:05 No severe PSQL-related problems occured
31.08.21 07:50:07. End of report.