Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.02.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 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 16631692 kB
MemAvailable 31174120 kB
Buffers 20 kB
Cached 14964216 kB
SwapCached 4876 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-02-02 04:26:54
DTS_END                         2021-02-02 04:26:54.8940
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/55832
TRN_ID                          228952
ATT_ID                          566
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: 8829.04 19983 1059485
customer order: creation 168.73 377 20248
customer order: refuse 14.41 376 1729
order to supplier: creation 44.96 1452 5395
order to supplier: removal 3.12 122362 374
invoice (draft): creation 44.41 3921 5329
invoice (draft): removal 48.73 1088 5848
invoice accept: apply 40.92 18797 4910
invoice accept: cancel 43.88 1038 5266
customer reserve: creation 3809.93 182 457191
customer reserve: removal 4322.89 68 518747
realization accept: apply 83.01 177 9961
realization accept: cancel 78.14 128 9377
payment from customer: creation 58.18 122 6982
payment from customer: removal 5.63 82 676
payment to supplier: creation 47.98 143 5757
payment to supplier: removal 3.41 164 409
service: total inventory turnovers 3.66 3941 439
service: total monetary turnovers 6.25 506 750
service: aggregate perf. data 0.78 16339 93
service: refresh index statistics 0.03 228398 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-02-2021 04:27:41 to 02-02-2021 04:27:43.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1787
TEST_TIME 2 3303
TEST_TIME 3 3252
TEST_TIME 4 3989
TEST_TIME 5 3629
TEST_TIME 6 3998
TEST_TIME 7 4097
TEST_TIME 8 5020
TEST_TIME 9 5111
TEST_TIME 10 4828
TEST_TIME 11 5024
TEST_TIME 12 5143
TEST_TIME 13 5286
TEST_TIME 14 5378
TEST_TIME 15 5760
TEST_TIME 16 5887
TEST_TIME 17 6109
TEST_TIME 18 6366
TEST_TIME 19 6799
TEST_TIME 20 7260
TEST_TIME 21 7304
TEST_TIME 22 7334
TEST_TIME 23 7527
TEST_TIME 24 7584
TEST_TIME 25 7959
TEST_TIME 26 8228
TEST_TIME 27 8807
TEST_TIME 28 9560
TEST_TIME 29 9712
TEST_TIME 30 9852
TEST_TIME 31 9994
TEST_TIME 32 10032
TEST_TIME 33 10152
TEST_TIME 34 10215
TEST_TIME 35 10074
TEST_TIME 36 9876
TEST_TIME 37 9692
TEST_TIME 38 9477
TEST_TIME 39 9384
TEST_TIME 40 9469
TEST_TIME 41 9510
TEST_TIME 42 9412
TEST_TIME 43 9339
TEST_TIME 44 9357
TEST_TIME 45 9333
TEST_TIME 46 9215
TEST_TIME 47 9129
TEST_TIME 48 8967
TEST_TIME 49 9146
TEST_TIME 50 9084
TEST_TIME 51 9218
TEST_TIME 52 9248
TEST_TIME 53 9157
TEST_TIME 54 9148
TEST_TIME 55 9134
TEST_TIME 56 9362
TEST_TIME 57 9367
TEST_TIME 58 9379
TEST_TIME 59 9387
TEST_TIME 60 9319
TEST_TIME 61 9354
TEST_TIME 62 9353
TEST_TIME 63 9247
TEST_TIME 64 9129
TEST_TIME 65 9080
TEST_TIME 66 8986
TEST_TIME 67 8960
TEST_TIME 68 8945
TEST_TIME 69 8885
TEST_TIME 70 8891
TEST_TIME 71 8871
TEST_TIME 72 8825
TEST_TIME 73 8845
TEST_TIME 74 8816
TEST_TIME 75 8768
TEST_TIME 76 8917
TEST_TIME 77 8882
TEST_TIME 78 8860
TEST_TIME 79 8881
TEST_TIME 80 8862
TEST_TIME 81 8939
TEST_TIME 82 8943
TEST_TIME 83 8871
TEST_TIME 84 8827
TEST_TIME 85 8766
TEST_TIME 86 8731
TEST_TIME 87 8695
TEST_TIME 88 8639
TEST_TIME 89 8653
TEST_TIME 90 8684
TEST_TIME 91 8669
TEST_TIME 92 8680
TEST_TIME 93 8766
TEST_TIME 94 8733
TEST_TIME 95 8773
TEST_TIME 96 8769
TEST_TIME 97 8816
TEST_TIME 98 8843
TEST_TIME 99 8831
TEST_TIME 100 8807
TEST_TIME 101 8870
TEST_TIME 102 8922
TEST_TIME 103 8865
TEST_TIME 104 8800
TEST_TIME 105 8799
TEST_TIME 106 8741
TEST_TIME 107 8704
TEST_TIME 108 8712
TEST_TIME 109 8689
TEST_TIME 110 8694
TEST_TIME 111 8711
TEST_TIME 112 8694
TEST_TIME 113 8726
TEST_TIME 114 8716
TEST_TIME 115 8732
TEST_TIME 116 8708
TEST_TIME 117 8763
TEST_TIME 118 8765
TEST_TIME 119 8748
TEST_TIME 120 8833

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 02-02-2021 04:27:44 to 02-02-2021 04:27:48.

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 1480668 1480668 0 0.00 0 27769 7 0 0
doc_list_biud 1480661 1480661 0 0.00 0 89827 31 0 0
sp_add_invoice_to_stock 5027 4910 117 2.32 43 161175 18798 0 117
sp_cancel_adding_invoice 5266 5266 0 0.00 5 73442 1038 0 0
sp_cancel_client_order 1729 1729 0 0.00 5 33161 377 0 0
sp_cancel_customer_reserve 518747 518747 0 0.00 1 47162 68 0 0
sp_cancel_pay_from_customer 676 676 0 0.00 3 4658 82 0 0
sp_cancel_pay_to_supplier 409 409 0 0.00 3 15361 165 0 0
sp_cancel_supplier_invoice 5861 5848 13 0.22 2 89829 1089 0 13
sp_cancel_supplier_order 374 374 0 0.00 7 1298907 122362 0 0
sp_cancel_write_off 9377 9377 0 0.00 1 20373 129 0 0
sp_client_order 20248 20248 0 0.00 4 19245 378 0 0
sp_customer_reserve 457191 457191 0 0.00 0 105041 182 0 0
sp_fill_shopping_cart 30944 30944 0 0.00 1 34971 48 0 0
sp_fill_shopping_cart_clo_res 457182 457182 0 0.00 0 32075 18 0 0
sp_get_clo_for_invoice 4901 4901 0 0.00 0 54092 484 0 0
sp_kill_cost_storno 15728 15728 0 0.00 0 11392 65 0 0
sp_kill_qstorno_ret_qs2qd 1053093 1053093 0 0.00 0 89825 38 0 0
sp_kill_qty_storno 556198 556198 0 0.00 0 89826 82 0 0
sp_lock_selected_doc 20473 20473 0 0.00 0 15351 4 0 0
sp_make_cost_storno 27600 27600 0 0.00 0 16013 58 0 0
sp_multiply_rows_for_pdistr 27599 27599 0 0.00 0 15613 35 0 0
sp_multiply_rows_for_qdistr 20248 20248 0 0.00 0 16233 207 0 0
sp_pay_from_customer 6982 6982 0 0.00 4 16235 122 0 0
sp_pay_to_supplier 5757 5757 0 0.00 5 18207 143 0 0
sp_payment_common 12736 12736 0 0.00 1 15559 29 0 0
sp_qd_handle_on_cancel_clo 1729 1729 0 0.00 0 33148 346 0 0
sp_qd_handle_on_invoice_upd_sts 10165 10165 0 0.00 0 34621 440 0 0
sp_qd_handle_on_reserve_upd_sts 9958 9958 0 0.00 0 13869 44 0 0
sp_reserve_write_off 9961 9961 0 0.00 7 16148 177 0 0
sp_supplier_invoice 5329 5329 0 0.00 7 125588 3921 0 0
sp_supplier_order 5395 5395 0 0.00 6 28454 1453 0 0
srv_aggregate_perf_data 93 93 0 0.00 2654 229986 16339 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1314 125024 43495 0 0
srv_find_qd_qs_mism 1011537 1011537 0 0.00 0 27767 6 0 0
srv_make_invnt_saldo 439 439 0 0.00 74 167863 3941 0 0
srv_make_money_saldo 750 750 0 0.00 1 14450 506 0 0
srv_recalc_idx_stat 4 4 0 0.00 86514 371709 228398 0 0
t$perf-abend:sp_add_invoice_to_stock 117 117 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4910 4910 0 0.00 0 9656 165 0 0
t$perf-norm:sp_cancel_adding_invoice 412 412 0 0.00 0 3011 107 0 0
t$perf-norm:sp_cancel_client_order 1729 1729 0 0.00 0 10643 18 0 0
t$perf-norm:sp_cancel_customer_reserve 643 643 0 0.00 0 1484 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 676 676 0 0.00 0 1871 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 409 409 0 0.00 0 1417 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 393 393 0 0.00 0 3138 25 0 0
t$perf-norm:sp_cancel_supplier_order 374 374 0 0.00 0 20102 1775 0 0
t$perf-norm:sp_cancel_write_off 981 981 0 0.00 0 2414 10 0 0
t$perf-norm:sp_client_order 20248 20248 0 0.00 0 7048 19 0 0
t$perf-norm:sp_customer_reserve 1011 1011 0 0.00 0 465 3 0 0
t$perf-norm:sp_pay_from_customer 6982 6982 0 0.00 0 5808 9 0 0
t$perf-norm:sp_pay_to_supplier 5757 5757 0 0.00 0 11901 9 0 0
t$perf-norm:sp_reserve_write_off 9961 9961 0 0.00 0 7523 9 0 0
t$perf-norm:sp_supplier_invoice 5329 5329 0 0.00 0 6104 20 0 0
t$perf-norm:sp_supplier_order 5395 5395 0 0.00 0 7088 21 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 295 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 118 4 0 0
t$perf-norm:srv_make_invnt_saldo 439 439 0 0.00 0 853 11 0 0
t$perf-norm:srv_make_money_saldo 750 750 0 0.00 0 935 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 10 5 0 0
v_add_invoice_to_stock 10030 9796 234 2.33 0 8876 15 0 234
v_all_customers 36988 36988 0 0.00 0 35 1 0 0
v_all_suppliers 29570 29570 0 0.00 0 18 1 0 0
v_all_wares 40484 40484 0 0.00 0 81 1 0 0
v_cancel_adding_invoice 824 824 0 0.00 0 7460 18 0 0
v_cancel_client_order 3458 3458 0 0.00 0 10029 6 0 0
v_cancel_customer_prepayment 1352 1352 0 0.00 0 1147 8 0 0
v_cancel_customer_reserve 1286 1286 0 0.00 0 9717 16 0 0
v_cancel_payment_to_supplier 818 818 0 0.00 0 10028 19 0 0
v_cancel_supplier_invoice 812 786 26 3.20 0 1290 15 0 26
v_cancel_supplier_order 748 748 0 0.00 0 9401 17 0 0
v_cancel_write_off 1962 1962 0 0.00 0 10484 18 0 0
v_max_id_clo_ord 5384 5384 0 0.00 0 2093 2 0 0
v_max_id_clo_res 1011 1011 0 0.00 0 5440 19 0 0
v_max_id_ord_sup 5318 5318 0 0.00 0 1329 2 0 0
v_max_non_paid_invoice 1671 1671 0 0.00 0 792 3 0 0
v_max_non_paid_realizn 3677 3677 0 0.00 0 5531 6 0 0
v_min_id_clo_ord 5384 5384 0 0.00 0 3698 9 0 0
v_min_id_clo_res 1011 1011 0 0.00 0 10445 25 0 0
v_min_id_ord_sup 5317 5317 0 0.00 0 15673 13 0 0
v_min_non_paid_invoice 5755 5755 0 0.00 0 14761 22 0 0
v_min_non_paid_realizn 6981 6981 0 0.00 0 14672 20 0 0
v_our_firm 10104 10104 0 0.00 0 14 1 0 0
v_reserve_write_off 19916 19916 0 0.00 0 10029 7 0 0
x_lock_depdocs_on_canc_invoice 10721 10721 0 0.00 0 17644 112 0 0
x_lock_depdocs_on_canc_sup_ord 374 374 0 0.00 0 8990 394 0 0
x_make_qstorno_1000_1200 5395 5395 0 0.00 2 28433 1359 0 0
x_make_qstorno_1200_2000 5329 5329 0 0.00 2 125577 3739 0 0
x_make_qstorno_2100_3300 457191 457191 0 0.00 0 105039 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-02-2021 04:27:49 to 02-02-2021 04:27:53.

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:56:58 2 8192000000 8796640128 8807108608 3168304 0.038 1 1 2 0 3265920 61840 35776
01:57:59 26596 8192000000 9719841872 10014134272 793327040 8.829 100 92 184 0 823385632 7810096 22248496
01:59:33 25153 8192000000 9759928464 10061901824 829126528 9.190 100 86 172 0 861899232 12348144 20424560
02:01:00 17728 8192000000 9790581280 10104803328 862566256 9.526 100 84 168 3 899726672 17976960 19183456
02:02:22 17897 8192000000 9851522336 10184302592 910693408 10.004 100 80 160 1 962949488 35286928 16969152
02:03:47 9690 8192000000 9920917472 10262257664 948075808 10.372 100 80 160 1 1036921968 71914160 16932000
02:05:04 6574 8192000000 9936553408 10288963584 987877584 10.761 100 82 164 0 1059289280 54595328 16816368
02:06:13 5260 8192000000 9983761200 10344124416 1005175856 10.929 100 75 149 0 1097843424 77778960 14888608
02:07:22 2903 8192000000 10003443648 10362339328 1033884160 11.206 100 82 163 0 1110912128 60063296 16964672
02:08:27 1916 8192000000 10029480272 10392801280 1051771488 11.378 100 93 188 0 1145431968 74736752 18923728
02:09:31 11722 8192000000 10023926544 10397560832 1059666656 11.453 100 75 151 0 1139711296 64453920 15590720
02:10:44 21150 8192000000 9975615872 10371899392 1066607088 11.520 100 26 52 0 1104597248 32720096 5270064
02:12:07 7464 8192000000 9979942288 10372415488 1077767648 11.626 100 27 54 0 1110913536 27604368 5541520
02:13:15 9127 8192000000 10004708464 10415415296 1083118816 11.677 100 19 38 0 1120400896 33589728 3692352
02:14:25 9831 8192000000 10022727552 10432774144 1089223984 11.735 100 56 109 2 1126249696 25564784 11460928
02:15:38 32392 8192000000 10023395936 10409467904 1095614816 11.796 100 66 132 3 1126340512 17132624 13593072
02:17:11 5088 8192000000 9997417552 10389794816 1077305792 11.622 98 51 100 2 1099390592 9930864 12153936
02:18:21 7391 8192000000 9982102912 10379063296 1061441856 11.470 99 41 80 1 1084505584 14162000 8901728
02:19:29 25985 8192000000 9955081744 10346094592 981621632 10.700 92 34 68 1 1005688992 17431376 6635984
02:20:58 3490 8192000000 9926252448 10304692224 992449824 10.805 96 45 90 4 1015861984 13089520 10322640
02:22:03 4647 8192000000 9942003312 10321563648 1009772432 10.973 99 39 77 0 1033708416 15563504 8372480
02:23:09 9533 8192000000 9934923440 10309890048 993860992 10.819 97 48 96 1 1024368512 19044752 11462768
02:24:18 43092 8192000000 9942521088 10334752768 994200368 10.822 96 19 38 1 1025179616 26947984 4031264
02:26:02 7476 8192000000 9974964032 10372042752 1045672048 11.319 99 36 69 2 1068117376 14538112 7907216
02:27:18 9982 8192000000 9999732016 10391085056 1078935456 11.637 100 45 90 2 1104610208 16097808 9576944
02:28:51 9739 8192000000 10050076352 10436943872 1097589680 11.815 100 53 103 2 1155713088 47196544 10926864
02:30:05 74038 8192000000 10057853872 10472034304 1109553056 11.928 100 25 50 2 1172539696 57211520 5775120
02:32:24 15242 8192000000 10097720928 10504704000 1125435824 12.078 100 47 91 3 1215026240 79504912 10085504
02:33:41 11600 8192000000 10170000144 10566619136 1135691968 12.175 100 73 144 2 1272728928 121853136 15183824
02:34:59 27471 8192000000 10152493792 10570825728 1147828672 12.289 100 37 74 2 1278645888 122534992 8282224
02:36:31 20988 8192000000 10199440896 10605400064 1155958192 12.365 100 53 106 0 1294877136 127091856 11827088
02:38:11 6364 8192000000 10227829152 10641182720 1167643616 12.475 100 54 107 5 1337326496 157355712 12327168
02:39:21 12358 8192000000 10259561568 10676482048 1176109280 12.554 100 51 102 2 1364093120 175598608 12385232
02:40:42 1312 8192000000 10304736240 10701479936 1174666320 12.540 100 83 161 1 1403584656 210976064 17942272
02:41:46 2715 8192000000 10335026176 10740969472 1168742336 12.485 99 77 147 2 1429225008 244277824 16204848
02:42:53 8476 8192000000 10337649456 10746429440 1150619488 12.315 100 67 135 2 1435097104 269123120 15354496
02:44:09 7125 8192000000 10355276992 10768678912 1171677616 12.513 100 64 128 0 1449693856 262848656 15167584
02:45:28 2887 8192000000 10360795376 10756124672 1160910384 12.412 100 89 176 0 1452689168 271476256 20302528
02:46:36 6009 8192000000 10379268496 10787471360 1152297888 12.331 99 72 144 2 1475213856 306359936 16556032
02:47:46 6839 8192000000 10356908256 10768019456 1143161440 12.245 99 45 90 1 1446385840 291641568 11582832
02:48:56 14889 8192000000 10417755504 10838429696 1136554656 12.183 99 46 92 0 1521658960 373473648 11630656
02:50:15 7719 8192000000 10404887968 10816450560 1113758288 11.968 99 53 106 1 1498241856 371095104 13388464
02:51:27 4006 8192000000 10429873568 10874150912 1136384976 12.182 100 73 147 0 1556738720 402442832 17910912
02:52:37 10368 8192000000 10364643376 10819821568 1116393616 11.993 100 37 69 2 1479278080 352751024 10133440
02:53:52 11070 8192000000 10251935056 10686955520 1101623360 11.853 98 52 102 1 1364941648 251175536 12142752
02:55:07 17803 8192000000 10156857056 10635558912 1117947760 12.008 100 12 24 0 1272195744 151409072 2838912
02:56:28 33431 8192000000 10114947888 10589241344 1108924080 11.922 100 13 26 0 1201142656 89558816 2659760
02:58:03 8248 8192000000 10093138368 10501918720 1096117200 11.801 98 59 109 0 1163298864 53885968 13295696
02:59:27 45695 8192000000 10062144400 10552983552 1087016064 11.714 97 11 22 1 1165495152 75944496 2534592
03:01:17 93935 8192000000 10125939424 10573676544 1130397952 12.125 100 28 56 0 1200790192 64410032 5982208
03:03:56 112041 8192000000 10103565584 10556866560 1135247056 12.171 100 42 84 1 1213476192 69247104 8982032
03:06:50 44883 8192000000 10137052384 10604580864 1149759776 12.307 100 25 50 1 1239620000 84576816 5283408
03:08:50 57280 8192000000 10094570832 10536763392 1115070800 11.980 98 27 54 1 1169028144 48019824 5937520
03:11:21 41787 8192000000 10056746992 10523615232 1108646640 11.920 98 18 36 2 1146143392 34093904 3402848
03:13:03 66120 8192000000 10123170416 10576535552 1129893712 12.120 100 14 28 1 1202028640 69082272 3052656
03:15:09 85079 8192000000 10121540592 10618265600 1111814832 11.950 98 12 23 1 1217984656 103028176 3141648
03:17:37 50151 8192000000 10085241504 10530963456 1123670432 12.062 99 19 39 0 1164097536 36493760 3933344
03:19:41 54996 8192000000 10138076992 10608607232 1100365488 11.841 96 19 38 1 1202569424 97621168 4582768
03:21:45 48084 8192000000 10091318560 10565038080 1127094368 12.094 100 14 28 1 1233278864 102937504 3246992
03:23:38 59381 8192000000 10081348784 10534940672 1103939104 11.875 98 28 56 2 1152239664 42688416 5612144
03:25:38 67582 8192000000 10081082192 10529832960 1122296480 12.049 99 20 40 0 1192740544 66453472 3990592
03:27:53 55850 8192000000 10106462768 10545328128 1135163504 12.170 100 15 30 1 1184505856 46420400 2921952
03:29:49 85667 8192000000 10061191712 10502029312 1118912688 12.017 99 21 42 0 1152017344 29349056 3755600
03:32:16 75143 8192000000 10077984080 10540449792 1109683056 11.929 98 10 20 0 1161918032 50171904 2063072
03:36:31 60152 8192000000 10039418672 10507104256 1070297232 11.555 95 13 26 0 1105135760 32263008 2575520
03:38:35 21666 8192000000 10072107600 10497347584 1115404080 11.984 99 34 68 2 1164703536 42273312 7026144
03:40:13 47369 8192000000 10087098992 10524151808 1104203392 11.878 97 18 36 0 1155920832 47983696 3733744
03:42:04 61297 8192000000 10041789168 10502111232 1085519280 11.700 96 23 46 0 1160620672 70241664 4859728
03:44:06 12009 8192000000 10061745888 10484158464 1104718784 11.882 98 54 107 1 1150314112 34114688 11480640
03:45:19 46471 8192000000 10071479600 10494865408 1066046992 11.514 95 27 54 1 1123991552 52776640 5167920
03:47:27 55903 8192000000 10040984720 10538749952 1084241024 11.688 98 7 14 1 1151161328 65439200 1481104
03:49:29 49782 8192000000 10015599696 10462351360 1096660480 11.806 100 9 18 0 1119321552 21008672 1652400
03:51:19 43105 8192000000 10069628432 10504572928 1109770000 11.930 100 22 44 1 1157507632 43200032 4537600
03:53:16 38415 8192000000 10050828240 10522054656 1096656960 11.806 98 11 22 0 1151305872 52273264 2375648
03:54:57 68895 8192000000 10052482752 10523176960 1088483184 11.728 98 5 10 0 1144615072 55152912 978976
03:57:06 41741 8192000000 10072491632 10506629120 1112559744 11.957 99 24 48 1 1152067824 34613024 4895056
03:58:49 76552 8192000000 10055623152 10545262592 1126871008 12.092 100 14 28 0 1202025520 72103488 3051024
04:01:05 72071 8192000000 10076668576 10529284096 1097614512 11.815 97 25 50 2 1144617104 41910384 5092208
04:03:31 117752 8192000000 10065054720 10533892096 1097475184 11.814 96 10 20 0 1136826224 37700128 1650912
04:06:39 116069 8192000000 10102883568 10589196288 1085477536 11.700 95 10 17 3 1149369216 61682288 2209392
04:09:36 125024 8192000000 10094752160 10589974528 1127263456 12.096 99 26 52 1 1167383376 34948208 5171712
04:12:53 41278 8192000000 10109138048 10578620416 1135675120 12.175 99 12 24 3 1228149712 89812816 2661776
04:14:40 52539 8192000000 10109772096 10566352896 1119311712 12.020 98 21 42 2 1207440480 83544368 4584400
04:16:41 73624 8192000000 10107574464 10563997696 1123570448 12.061 98 17 34 0 1179229792 52299152 3360192
04:18:55 62742 8192000000 10089024880 10538119168 1120116416 12.028 98 33 66 0 1169089680 40862896 8110368
04:21:00 59174 8192000000 10057867488 10513584128 1106746048 11.902 97 21 42 0 1144380496 32866672 4767776
04:23:15 36360 8192000000 10111451984 10544472064 1132639696 12.146 100 29 58 0 1236512128 97877888 5994544
04:24:59 28886 8192000000 10098024304 10524573696 1101580480 11.853 97 35 70 1 1147363504 39026928 6756096
04:26:29 43925 8192000000 8938812144 9151041536 90668288 1.094 9 2 4 0 103467472 12571408 227776

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 02-02-2021 04:27:55 to 02-02-2021 04:28:10.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 117 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

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

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          229257
MON$OLDEST_ACTIVE               229258
MON$OLDEST_SNAPSHOT             229258
MON$NEXT_TRANSACTION            229258
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                       2086624
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             789
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2353 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2353 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2353 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.2353 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'

02.02.21 04:28:14. 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-02-02 04:28:16 No severe PSQL-related problems occured
02.02.21 04:28:17. End of report.