Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.01.2021 10:29
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 16846392 kB
MemAvailable 31243076 kB
Buffers 0 kB
Cached 14778900 kB
SwapCached 4868 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-01-22 10:26:53
DTS_END                         2021-01-22 10:26:53.3650
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/34168
TRN_ID                          225986
ATT_ID                          669
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: 8460.70 21756 1015284
customer order: creation 163.40 439 19608
customer order: refuse 13.68 370 1642
order to supplier: creation 42.58 1533 5109
order to supplier: removal 3.19 136048 383
invoice (draft): creation 43.71 3980 5245
invoice (draft): removal 47.08 1118 5650
invoice accept: apply 39.91 18992 4789
invoice accept: cancel 41.72 1067 5006
customer reserve: creation 3672.90 184 440748
customer reserve: removal 4125.73 83 495088
realization accept: apply 77.29 203 9275
realization accept: cancel 68.00 147 8160
payment from customer: creation 55.78 118 6694
payment from customer: removal 5.79 75 695
payment to supplier: creation 46.58 126 5590
payment to supplier: removal 3.39 61 407
service: total inventory turnovers 3.48 4081 418
service: total monetary turnovers 5.66 656 679
service: aggregate perf. data 0.77 22621 92
service: refresh index statistics 0.05 243216 6

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-01-2021 10:29:30 to 22-01-2021 10:29:32.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3776
TEST_TIME 2 2745
TEST_TIME 3 3935
TEST_TIME 4 4353
TEST_TIME 5 4573
TEST_TIME 6 4837
TEST_TIME 7 4712
TEST_TIME 8 4870
TEST_TIME 9 5063
TEST_TIME 10 5158
TEST_TIME 11 5373
TEST_TIME 12 5608
TEST_TIME 13 5771
TEST_TIME 14 5767
TEST_TIME 15 5913
TEST_TIME 16 5995
TEST_TIME 17 6248
TEST_TIME 18 6790
TEST_TIME 19 6987
TEST_TIME 20 7096
TEST_TIME 21 7349
TEST_TIME 22 7488
TEST_TIME 23 7612
TEST_TIME 24 7804
TEST_TIME 25 7696
TEST_TIME 26 8153
TEST_TIME 27 8087
TEST_TIME 28 8622
TEST_TIME 29 9194
TEST_TIME 30 10425
TEST_TIME 31 10166
TEST_TIME 32 9926
TEST_TIME 33 9754
TEST_TIME 34 9635
TEST_TIME 35 9538
TEST_TIME 36 9468
TEST_TIME 37 9400
TEST_TIME 38 9412
TEST_TIME 39 9300
TEST_TIME 40 9188
TEST_TIME 41 9144
TEST_TIME 42 9054
TEST_TIME 43 8964
TEST_TIME 44 9034
TEST_TIME 45 9044
TEST_TIME 46 8953
TEST_TIME 47 8974
TEST_TIME 48 8915
TEST_TIME 49 8941
TEST_TIME 50 8890
TEST_TIME 51 8882
TEST_TIME 52 8847
TEST_TIME 53 8839
TEST_TIME 54 8921
TEST_TIME 55 8901
TEST_TIME 56 8917
TEST_TIME 57 8791
TEST_TIME 58 8694
TEST_TIME 59 8705
TEST_TIME 60 8607
TEST_TIME 61 8522
TEST_TIME 62 8532
TEST_TIME 63 8544
TEST_TIME 64 8524
TEST_TIME 65 8551
TEST_TIME 66 8609
TEST_TIME 67 8632
TEST_TIME 68 8658
TEST_TIME 69 8719
TEST_TIME 70 8755
TEST_TIME 71 8773
TEST_TIME 72 8792
TEST_TIME 73 8913
TEST_TIME 74 8865
TEST_TIME 75 8882
TEST_TIME 76 8968
TEST_TIME 77 8931
TEST_TIME 78 8970
TEST_TIME 79 8869
TEST_TIME 80 8782
TEST_TIME 81 8757
TEST_TIME 82 8712
TEST_TIME 83 8657
TEST_TIME 84 8624
TEST_TIME 85 8628
TEST_TIME 86 8619
TEST_TIME 87 8595
TEST_TIME 88 8570
TEST_TIME 89 8532
TEST_TIME 90 8514
TEST_TIME 91 8521
TEST_TIME 92 8573
TEST_TIME 93 8659
TEST_TIME 94 8631
TEST_TIME 95 8620
TEST_TIME 96 8627
TEST_TIME 97 8655
TEST_TIME 98 8669
TEST_TIME 99 8752
TEST_TIME 100 8735
TEST_TIME 101 8726
TEST_TIME 102 8662
TEST_TIME 103 8629
TEST_TIME 104 8606
TEST_TIME 105 8562
TEST_TIME 106 8527
TEST_TIME 107 8500
TEST_TIME 108 8485
TEST_TIME 109 8541
TEST_TIME 110 8521
TEST_TIME 111 8526
TEST_TIME 112 8522
TEST_TIME 113 8493
TEST_TIME 114 8529
TEST_TIME 115 8588
TEST_TIME 116 8583
TEST_TIME 117 8588
TEST_TIME 118 8531
TEST_TIME 119 8510
TEST_TIME 120 8461

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 22-01-2021 10:29:33 to 22-01-2021 10:29: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 1422515 1422515 0 0.00 0 34320 7 0 0
doc_list_biud 1422515 1422515 0 0.00 0 32818 37 0 0
sp_add_invoice_to_stock 4822 4789 33 0.68 26 200098 18993 0 33
sp_cancel_adding_invoice 5006 5006 0 0.00 4 69599 1067 0 0
sp_cancel_client_order 1642 1642 0 0.00 4 12833 370 0 0
sp_cancel_customer_reserve 495088 495088 0 0.00 1 32825 84 0 0
sp_cancel_pay_from_customer 695 695 0 0.00 3 3184 75 0 0
sp_cancel_pay_to_supplier 407 407 0 0.00 3 907 61 0 0
sp_cancel_supplier_invoice 5654 5650 4 0.07 2 23994 1119 0 4
sp_cancel_supplier_order 383 383 0 0.00 6 1212623 136049 0 0
sp_cancel_write_off 8160 8160 0 0.00 1 15341 147 0 0
sp_client_order 19608 19608 0 0.00 4 66991 439 0 0
sp_customer_reserve 440748 440748 0 0.00 0 77401 184 0 0
sp_fill_shopping_cart 29955 29955 0 0.00 1 17446 50 0 0
sp_fill_shopping_cart_clo_res 440744 440744 0 0.00 0 30267 18 0 0
sp_get_clo_for_invoice 4785 4785 0 0.00 0 56258 535 0 0
sp_kill_cost_storno 14268 14268 0 0.00 0 8353 75 0 0
sp_kill_qstorno_ret_qs2qd 1004369 1004369 0 0.00 0 32799 45 0 0
sp_kill_qty_storno 529987 529987 0 0.00 0 32818 97 0 0
sp_lock_selected_doc 19561 19561 0 0.00 0 8103 6 0 0
sp_make_cost_storno 26342 26342 0 0.00 0 32774 61 0 0
sp_multiply_rows_for_pdistr 26342 26342 0 0.00 0 10616 35 0 0
sp_multiply_rows_for_qdistr 19608 19608 0 0.00 0 60429 223 0 0
sp_pay_from_customer 6694 6694 0 0.00 4 14610 118 0 0
sp_pay_to_supplier 5590 5590 0 0.00 4 19571 127 0 0
sp_payment_common 12284 12284 0 0.00 1 9222 22 0 0
sp_qd_handle_on_cancel_clo 1642 1642 0 0.00 0 12823 340 0 0
sp_qd_handle_on_invoice_upd_sts 9789 9789 0 0.00 0 19992 456 0 0
sp_qd_handle_on_reserve_upd_sts 9275 9275 0 0.00 0 10274 64 0 0
sp_reserve_write_off 9275 9275 0 0.00 7 32894 204 0 0
sp_supplier_invoice 5245 5245 0 0.00 7 100001 3980 0 0
sp_supplier_order 5109 5109 0 0.00 6 60619 1534 0 0
srv_aggregate_perf_data 92 92 0 0.00 2668 435175 22621 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 880 156496 48378 0 0
srv_find_qd_qs_mism 969561 969561 0 0.00 0 34318 6 0 0
srv_make_invnt_saldo 418 418 0 0.00 34 137596 4081 0 0
srv_make_money_saldo 679 679 0 0.00 1 13062 656 0 0
srv_recalc_idx_stat 6 6 0 0.00 119179 398311 243217 0 0
t$perf-abend:sp_add_invoice_to_stock 33 33 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 4789 4789 0 0.00 0 20318 172 0 0
t$perf-norm:sp_cancel_adding_invoice 401 401 0 0.00 0 6671 86 0 0
t$perf-norm:sp_cancel_client_order 1642 1642 0 0.00 0 5149 15 0 0
t$perf-norm:sp_cancel_customer_reserve 664 664 0 0.00 0 1498 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 695 695 0 0.00 0 1787 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 407 407 0 0.00 0 3784 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 390 390 0 0.00 0 839 21 0 0
t$perf-norm:sp_cancel_supplier_order 383 383 0 0.00 0 26706 1647 0 0
t$perf-norm:sp_cancel_write_off 922 922 0 0.00 0 1360 8 0 0
t$perf-norm:sp_client_order 19608 19608 0 0.00 0 14754 22 0 0
t$perf-norm:sp_customer_reserve 895 895 0 0.00 0 526 4 0 0
t$perf-norm:sp_pay_from_customer 6694 6694 0 0.00 0 4459 9 0 0
t$perf-norm:sp_pay_to_supplier 5590 5590 0 0.00 0 11780 9 0 0
t$perf-norm:sp_reserve_write_off 9275 9275 0 0.00 0 13575 14 0 0
t$perf-norm:sp_supplier_invoice 5245 5245 0 0.00 0 5978 21 0 0
t$perf-norm:sp_supplier_order 5109 5109 0 0.00 0 32597 29 0 0
t$perf-norm:srv_aggregate_perf_data 92 92 0 0.00 0 4224 48 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 804 14 0 0
t$perf-norm:srv_make_invnt_saldo 418 418 0 0.00 0 1042 14 0 0
t$perf-norm:srv_make_money_saldo 679 679 0 0.00 0 1630 7 0 0
t$perf-norm:srv_recalc_idx_stat 6 6 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 9630 9564 66 0.68 0 6108 15 0 66
v_all_customers 35410 35410 0 0.00 0 19 1 0 0
v_all_suppliers 28692 28692 0 0.00 0 16 1 0 0
v_all_wares 39216 39216 0 0.00 0 35 1 0 0
v_cancel_adding_invoice 802 802 0 0.00 0 593 7 0 0
v_cancel_client_order 3284 3284 0 0.00 0 3245 6 0 0
v_cancel_customer_prepayment 1390 1390 0 0.00 0 3162 6 0 0
v_cancel_customer_reserve 1328 1328 0 0.00 0 2867 7 0 0
v_cancel_payment_to_supplier 814 814 0 0.00 0 853 5 0 0
v_cancel_supplier_invoice 788 780 8 1.01 0 2051 14 0 8
v_cancel_supplier_order 766 766 0 0.00 0 604 4 0 0
v_cancel_write_off 1844 1844 0 0.00 0 5985 25 0 0
v_max_id_clo_ord 5109 5109 0 0.00 0 940 2 0 0
v_max_id_clo_res 895 895 0 0.00 0 2564 14 0 0
v_max_id_ord_sup 5238 5238 0 0.00 0 4692 5 0 0
v_max_non_paid_invoice 1591 1591 0 0.00 0 293 2 0 0
v_max_non_paid_realizn 3720 3720 0 0.00 0 4891 4 0 0
v_min_id_clo_ord 5109 5109 0 0.00 0 3761 8 0 0
v_min_id_clo_res 895 895 0 0.00 0 5886 20 0 0
v_min_id_ord_sup 5238 5238 0 0.00 0 3921 6 0 0
v_min_non_paid_invoice 5590 5590 0 0.00 0 9217 19 0 0
v_min_non_paid_realizn 6694 6694 0 0.00 0 6909 13 0 0
v_our_firm 9754 9754 0 0.00 0 19 1 0 0
v_reserve_write_off 18550 18550 0 0.00 0 3817 6 0 0
x_lock_depdocs_on_canc_invoice 10266 10266 0 0.00 0 23143 121 0 0
x_lock_depdocs_on_canc_sup_ord 383 383 0 0.00 0 7892 396 0 0
x_make_qstorno_1000_1200 5109 5109 0 0.00 2 60610 1454 0 0
x_make_qstorno_1200_2000 5245 5245 0 0.00 3 99607 3769 0 0
x_make_qstorno_2100_3300 440748 440748 0 0.00 0 77399 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-01-2021 10:29:38 to 22-01-2021 10:29: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
07:56:56 5 8192000000 8796638048 8807108608 3168016 0.038 1 1 2 0 3265632 61840 35776
07:57:56 32647 8192000000 9712910976 10007023616 788439264 8.779 100 87 174 0 817648272 8082464 21126544
07:59:41 27499 8192000000 9771738224 10079531008 846906784 9.369 100 89 178 0 880583552 12482320 21194448
08:01:13 14760 8192000000 9816760352 10135375872 879825872 9.698 100 88 176 0 927682080 27353520 20502688
08:02:32 9606 8192000000 9863861040 10197073920 922595040 10.122 100 71 142 1 969955888 32335376 15025472
08:03:44 7173 8192000000 9926264928 10269859840 960666112 10.496 100 82 164 0 1043050672 65241856 17142704
08:04:56 6209 8192000000 9954921232 10308550656 993783120 10.818 100 85 170 1 1070805008 59013104 18008784
08:06:04 2856 8192000000 10013948960 10372390912 1018163776 11.054 100 92 182 1 1130204352 93862256 18178320
08:07:08 5388 8192000000 10014286336 10379190272 1036467968 11.231 100 82 164 1 1119529008 66265200 16795840
08:08:15 2155 8192000000 10049548880 10415296512 1057816768 11.436 100 93 187 1 1159989408 83166432 19006208
08:09:19 1621 8192000000 10049135360 10423148544 1065733472 11.511 100 92 182 1 1160698112 76854368 18110272
08:10:22 9719 8192000000 9988216800 10380546048 1074063520 11.591 100 28 54 1 1108224992 27640448 6521024
08:11:33 8717 8192000000 10001716256 10413461504 1082126288 11.668 100 30 60 1 1118340720 29434000 6780432
08:12:42 26085 8192000000 10017919312 10403807232 1086855856 11.713 100 55 111 0 1111883392 13219680 11807856
08:14:09 8539 8192000000 10002366912 10413424640 1087801280 11.722 100 38 76 1 1116126288 20293792 8031216
08:15:21 8835 8192000000 10007667376 10411835392 1083300272 11.679 99 26 50 2 1109956240 21135312 5520656
08:16:31 5434 8192000000 10003818272 10405011456 1091823600 11.760 100 33 66 1 1116870400 18433024 6613776
08:17:39 24604 8192000000 10006426320 10420629504 1072756784 11.578 98 25 50 0 1102994304 24654992 5582528
08:19:07 7436 8192000000 9986310464 10379554816 1047247776 11.334 98 44 87 1 1079796128 22592864 9955488
08:20:18 5710 8192000000 9937012464 10315571200 997649600 10.856 97 32 63 0 1015664448 11337200 6677648
08:21:26 69057 8192000000 9928899824 10324160512 970360512 10.590 94 29 58 2 994022192 17142640 6519040
08:23:36 11663 8192000000 9953753744 10348433408 1019113184 11.063 98 39 78 1 1048043824 20418288 8512352
08:24:51 5679 8192000000 9980016800 10374549504 1043796096 11.301 99 45 90 2 1067682656 13617120 10269440
08:26:02 17456 8192000000 9990262144 10389975040 1075694496 11.606 100 25 50 1 1097863872 16926272 5243104
08:27:24 34015 8192000000 10001721120 10399256576 1095727488 11.797 100 34 68 2 1113727504 10826592 7173424
08:29:04 19826 8192000000 10078409552 10480996352 1108512656 11.918 100 59 111 3 1167687696 48430704 10744336
08:30:28 4818 8192000000 10082029440 10478489600 1122457312 12.050 100 64 125 3 1183973072 48258016 13257744
08:31:40 20223 8192000000 10123470256 10531254272 1139855984 12.214 100 46 92 2 1236431424 87183440 9392000
08:33:04 16026 8192000000 10150423728 10553524224 1151393344 12.323 100 46 92 0 1252804992 91262704 10148944
08:34:24 28481 8192000000 10194248384 10623344640 1154218416 12.349 100 39 78 1 1301422912 138709744 8494752
08:36:12 6337 8192000000 10198585824 10615984128 1164432768 12.445 100 73 146 1 1309722384 128650688 16638928
08:37:25 6099 8192000000 10257501328 10665517056 1174426816 12.538 100 74 144 1 1359254944 169354640 15473488
08:38:36 2445 8192000000 10290678816 10696294400 1176347792 12.556 100 73 146 1 1390347536 197579952 16419792
08:39:42 4460 8192000000 10304417840 10716835840 1189330016 12.677 100 75 148 0 1408745408 202482976 16932416
08:40:50 3197 8192000000 10357745328 10761740288 1185138432 12.638 100 70 137 0 1450830160 250341168 15350560
08:41:59 4457 8192000000 10402161520 10818011136 1183115296 12.619 100 70 140 0 1503244048 303936672 16192080
08:43:11 878 8192000000 10447507392 10855284736 1184042464 12.628 100 83 166 0 1546282912 342816384 19424064
08:44:20 2514 8192000000 10412726960 10825334784 1182591232 12.614 100 84 169 0 1504906288 303073088 19241968
08:45:30 4395 8192000000 10424352752 10847666176 1166859216 12.467 99 82 160 0 1513304720 327565376 18880128
08:46:43 1254 8192000000 10431604928 10835832832 1167516608 12.474 100 86 172 0 1532811952 345005408 20289936
08:47:48 4052 8192000000 10428119632 10826788864 1158457984 12.389 100 82 165 0 1519365392 340882208 20025200
08:48:59 3965 8192000000 10453836992 10881089536 1168029024 12.478 100 74 148 0 1561651808 375912128 17710656
08:50:18 8297 8192000000 10490129664 10904657920 1158309872 12.387 100 58 114 2 1575525328 403163936 14051520
08:51:41 15424 8192000000 10504921792 10937262080 1156131424 12.367 100 39 78 1 1605911536 438693856 11086256
08:53:10 17531 8192000000 10442089184 10885791744 1120634912 12.033 98 37 74 0 1549758976 419377760 9746304
08:54:54 7030 8192000000 10379959088 10849648640 1126446640 12.088 100 37 74 1 1476463280 340171776 9844864
08:56:03 36365 8192000000 10103744832 10646396928 1101136400 11.848 98 11 22 2 1305807296 201634768 3036128
08:57:43 68192 8192000000 10073095776 10499764224 1123301712 12.058 99 33 65 0 1154145440 24252576 6591152
08:59:55 7358 8192000000 10101761920 10524028928 1131083696 12.132 99 57 111 2 1202094176 59503904 11506576
09:01:02 156496 8192000000 10052491840 10523508736 1073227008 11.583 94 17 34 0 1151180864 73931808 4022048
09:04:41 57572 8192000000 10081397984 10524606464 1104462848 11.880 98 20 40 1 1152278608 43082064 4733696
09:06:47 104413 8192000000 10157058128 10634252288 1139536416 12.211 100 13 26 0 1250722944 107866480 3320048
09:09:46 79409 8192000000 10101637968 10601779200 1142583344 12.240 100 32 64 1 1233152912 83716304 6853264
09:12:09 77823 8192000000 10061900544 10529304576 1131728512 12.138 99 13 26 1 1186051728 51212720 3110496
09:14:27 48590 8192000000 10084809040 10539053056 1126088736 12.084 98 39 79 0 1174893936 40700272 8104928
09:16:30 92888 8192000000 10076251088 10529931264 1122005248 12.046 98 16 32 0 1169948592 44590944 3352400
09:19:04 63449 8192000000 10108968304 10550124544 1144686544 12.260 100 25 50 0 1186419296 36714400 5018352
09:21:07 34445 8192000000 10093647280 10529239040 1125231136 12.076 98 29 57 1 1168616992 36359504 7026352
09:22:48 136770 8192000000 10109091472 10558926848 1130377744 12.125 99 28 56 1 1186596000 50034416 6183840
09:26:14 27168 8192000000 10047031712 10530574336 1105773776 11.892 97 7 14 0 1131602512 24172096 1656640
09:27:53 62430 8192000000 10111707824 10590408704 1141168976 12.227 100 16 32 1 1189818624 45561168 3088480
09:29:56 111436 8192000000 10110255440 10591203328 1075576032 11.605 93 18 34 1 1139747168 59800672 4370464
09:32:53 30669 8192000000 10102389728 10527072256 1132975776 12.149 100 37 74 2 1185885536 45363504 7546256
09:34:25 44702 8192000000 10062957456 10538274816 1075772800 11.607 95 11 22 0 1137750160 59353216 2624144
09:36:10 52244 8192000000 10088630160 10529017856 1114872496 11.979 99 22 44 0 1143981680 24559328 4549856
09:38:06 72248 8192000000 10091398688 10550042624 1110568768 11.938 98 19 38 0 1182385376 67699264 4117344
09:40:26 41417 8192000000 10047534784 10464739328 1077069520 11.620 96 38 76 2 1105229520 20340384 7819616
09:42:22 86744 8192000000 10089727008 10567598080 1079125856 11.639 97 12 24 0 1132028544 50742048 2160640
09:44:50 113936 8192000000 10028150032 10461548544 1101258864 11.850 100 18 36 0 1131892816 26868672 3765280
09:47:49 44770 8192000000 10030413568 10487730176 1073304064 11.584 96 15 30 1 1110792304 34361968 3126272
09:49:35 51845 8192000000 10060841888 10522062848 1100780176 11.845 99 10 20 1 1170916480 67793232 2343072
09:51:43 61184 8192000000 10026443984 10496847872 1077896544 11.627 97 7 14 0 1116328592 36955248 1476800
09:53:53 66036 8192000000 10040191728 10485915648 1103697328 11.873 100 7 14 0 1141084400 35670032 1717040
09:56:13 36378 8192000000 10110570048 10549624832 1119802208 12.025 100 26 51 1 1209493424 84246864 5444352
09:58:00 75864 8192000000 10098998080 10581176320 1114172016 11.972 99 19 38 0 1202308112 83842096 4294000
10:00:16 32561 8192000000 10068462176 10496176128 1129763152 12.119 100 28 55 0 1158299312 22391136 6145024
10:01:55 23553 8192000000 10101409984 10531078144 1118066752 12.009 99 43 86 1 1166301152 39427120 8807280
10:03:21 53003 8192000000 10091806176 10544078848 1119328224 12.021 98 29 57 0 1156672720 32048560 5295936
10:05:16 59763 8192000000 10071377968 10517024768 1109226064 11.925 97 17 34 0 1159358016 47095296 3036656
10:07:23 148468 8192000000 10091421584 10564517888 1153244688 12.340 100 21 42 1 1205325888 48011408 4069792
10:10:55 66480 8192000000 10070461616 10535968768 1136749984 12.185 99 5 10 0 1169073360 30872192 1451184
10:13:01 76973 8192000000 10116619712 10560827392 1125337184 12.077 98 18 36 2 1167891312 38429456 4124672
10:15:51 61929 8192000000 10090479328 10529222656 1133435056 12.154 98 13 26 1 1153921632 17858976 2627600
10:17:59 108974 8192000000 10133213216 10579283968 1118108640 12.009 97 16 32 1 1193190304 71306176 3775488
10:20:49 115886 8192000000 10085037840 10553253888 1084073840 11.686 95 15 31 0 1151792016 64842352 2875824
10:23:47 93235 8192000000 10064530896 10504265728 1099313072 11.831 98 30 59 1 1153588144 48453648 5821424

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 22-01-2021 10:29:44 to 22-01-2021 10:29:58.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 33 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-01-2021 10:29:59 to 22-01-2021 10:30:01.

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          225447
MON$OLDEST_ACTIVE               226110
MON$OLDEST_SNAPSHOT             226110
MON$NEXT_TRANSACTION            226110
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                       2075872
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             784
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2336 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2336 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2336 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.2336 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.01.21 10:30:02. 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-01-22 10:30:05 No severe PSQL-related problems occured
22.01.21 10:30:06. End of report.