Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.02.2021 10: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 16580932 kB
MemAvailable 31152172 kB
Buffers 0 kB
Cached 15070668 kB
SwapCached 4772 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-11 10:26:57
DTS_END                         2021-02-11 10:26:57.0210
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/58924
TRN_ID                          235363
ATT_ID                          691
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: 8703.92 19060 1044470
customer order: creation 175.47 413 21056
customer order: refuse 15.53 397 1864
order to supplier: creation 45.48 1509 5458
order to supplier: removal 3.27 121991 392
invoice (draft): creation 46.88 4169 5626
invoice (draft): removal 48.20 1063 5784
invoice accept: apply 41.32 18946 4958
invoice accept: cancel 43.11 1044 5173
customer reserve: creation 3764.03 187 451684
customer reserve: removal 4234.08 73 508089
realization accept: apply 84.98 180 10198
realization accept: cancel 72.48 145 8698
payment from customer: creation 60.31 110 7237
payment from customer: removal 5.75 96 690
payment to supplier: creation 48.85 123 5862
payment to supplier: removal 3.14 86 377
service: total inventory turnovers 4.01 3653 481
service: total monetary turnovers 6.06 559 727
service: aggregate perf. data 0.93 13934 112
service: refresh index statistics 0.03 212521 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 11-02-2021 10:27:41 to 11-02-2021 10:27:44.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2913
TEST_TIME 2 3173
TEST_TIME 3 3736
TEST_TIME 4 3782
TEST_TIME 5 4139
TEST_TIME 6 4481
TEST_TIME 7 5062
TEST_TIME 8 5269
TEST_TIME 9 5630
TEST_TIME 10 5845
TEST_TIME 11 6118
TEST_TIME 12 6020
TEST_TIME 13 5928
TEST_TIME 14 5954
TEST_TIME 15 6129
TEST_TIME 16 6322
TEST_TIME 17 6303
TEST_TIME 18 6600
TEST_TIME 19 6636
TEST_TIME 20 7004
TEST_TIME 21 7246
TEST_TIME 22 7820
TEST_TIME 23 7878
TEST_TIME 24 7768
TEST_TIME 25 7933
TEST_TIME 26 8223
TEST_TIME 27 8966
TEST_TIME 28 9080
TEST_TIME 29 10111
TEST_TIME 30 10128
TEST_TIME 31 9993
TEST_TIME 32 10036
TEST_TIME 33 9903
TEST_TIME 34 9885
TEST_TIME 35 9826
TEST_TIME 36 9957
TEST_TIME 37 9839
TEST_TIME 38 9660
TEST_TIME 39 9486
TEST_TIME 40 9305
TEST_TIME 41 9319
TEST_TIME 42 9264
TEST_TIME 43 9184
TEST_TIME 44 9293
TEST_TIME 45 9349
TEST_TIME 46 9397
TEST_TIME 47 9289
TEST_TIME 48 9236
TEST_TIME 49 9186
TEST_TIME 50 9096
TEST_TIME 51 9072
TEST_TIME 52 9064
TEST_TIME 53 9002
TEST_TIME 54 9103
TEST_TIME 55 9093
TEST_TIME 56 9002
TEST_TIME 57 9072
TEST_TIME 58 9064
TEST_TIME 59 9158
TEST_TIME 60 9103
TEST_TIME 61 9218
TEST_TIME 62 9183
TEST_TIME 63 9214
TEST_TIME 64 9259
TEST_TIME 65 9184
TEST_TIME 66 9215
TEST_TIME 67 9087
TEST_TIME 68 9006
TEST_TIME 69 8958
TEST_TIME 70 8923
TEST_TIME 71 8833
TEST_TIME 72 8830
TEST_TIME 73 8810
TEST_TIME 74 8828
TEST_TIME 75 8819
TEST_TIME 76 8850
TEST_TIME 77 8818
TEST_TIME 78 8791
TEST_TIME 79 8839
TEST_TIME 80 8858
TEST_TIME 81 8823
TEST_TIME 82 8918
TEST_TIME 83 8955
TEST_TIME 84 8940
TEST_TIME 85 8868
TEST_TIME 86 8774
TEST_TIME 87 8767
TEST_TIME 88 8717
TEST_TIME 89 8686
TEST_TIME 90 8680
TEST_TIME 91 8640
TEST_TIME 92 8635
TEST_TIME 93 8608
TEST_TIME 94 8645
TEST_TIME 95 8682
TEST_TIME 96 8728
TEST_TIME 97 8722
TEST_TIME 98 8720
TEST_TIME 99 8714
TEST_TIME 100 8804
TEST_TIME 101 8773
TEST_TIME 102 8778
TEST_TIME 103 8847
TEST_TIME 104 8878
TEST_TIME 105 8816
TEST_TIME 106 8753
TEST_TIME 107 8724
TEST_TIME 108 8690
TEST_TIME 109 8684
TEST_TIME 110 8661
TEST_TIME 111 8631
TEST_TIME 112 8628
TEST_TIME 113 8637
TEST_TIME 114 8696
TEST_TIME 115 8677
TEST_TIME 116 8658
TEST_TIME 117 8657
TEST_TIME 118 8690
TEST_TIME 119 8703
TEST_TIME 120 8711

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-02-2021 10:27:45 to 11-02-2021 10: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 1464037 1464037 0 0.00 0 53289 7 0 0
doc_list_biud 1464037 1464037 0 0.00 0 57499 33 0 0
sp_add_invoice_to_stock 5049 4958 91 1.80 19 176017 18947 0 91
sp_cancel_adding_invoice 5173 5173 0 0.00 5 74786 1045 0 0
sp_cancel_client_order 1864 1864 0 0.00 4 19534 398 0 0
sp_cancel_customer_reserve 508089 508089 0 0.00 1 57500 74 0 0
sp_cancel_pay_from_customer 690 690 0 0.00 3 4475 97 0 0
sp_cancel_pay_to_supplier 377 377 0 0.00 3 2841 86 0 0
sp_cancel_supplier_invoice 5790 5784 6 0.10 2 24899 1063 0 6
sp_cancel_supplier_order 392 392 0 0.00 6 1369501 121992 0 0
sp_cancel_write_off 8698 8698 0 0.00 1 16157 145 0 0
sp_client_order 21056 21056 0 0.00 4 87900 413 0 0
sp_customer_reserve 451684 451684 0 0.00 0 123861 187 0 0
sp_fill_shopping_cart 32123 32123 0 0.00 1 20811 47 0 0
sp_fill_shopping_cart_clo_res 451673 451673 0 0.00 0 27291 17 0 0
sp_get_clo_for_invoice 4946 4946 0 0.00 0 45369 391 0 0
sp_kill_cost_storno 14938 14938 0 0.00 0 8432 72 0 0
sp_kill_qstorno_ret_qs2qd 1031052 1031052 0 0.00 0 57484 40 0 0
sp_kill_qty_storno 545144 545144 0 0.00 0 57499 87 0 0
sp_lock_selected_doc 20984 20984 0 0.00 0 9136 7 0 0
sp_make_cost_storno 28243 28243 0 0.00 0 15627 58 0 0
sp_multiply_rows_for_pdistr 28243 28243 0 0.00 0 8993 33 0 0
sp_multiply_rows_for_qdistr 21056 21056 0 0.00 0 87893 213 0 0
sp_pay_from_customer 7237 7237 0 0.00 4 11899 111 0 0
sp_pay_to_supplier 5862 5862 0 0.00 5 11884 124 0 0
sp_payment_common 13099 13099 0 0.00 1 11838 23 0 0
sp_qd_handle_on_cancel_clo 1864 1864 0 0.00 0 19527 369 0 0
sp_qd_handle_on_invoice_upd_sts 10119 10119 0 0.00 0 49384 452 0 0
sp_qd_handle_on_reserve_upd_sts 10198 10198 0 0.00 0 6464 47 0 0
sp_reserve_write_off 10198 10198 0 0.00 6 15965 181 0 0
sp_supplier_invoice 5626 5626 0 0.00 7 150533 4170 0 0
sp_supplier_order 5458 5458 0 0.00 6 25339 1510 0 0
srv_aggregate_perf_data 112 112 0 0.00 2984 247933 13935 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1041 160419 44433 0 0
srv_find_qd_qs_mism 997521 997521 0 0.00 0 53287 6 0 0
srv_make_invnt_saldo 481 481 0 0.00 40 91924 3653 0 0
srv_make_money_saldo 727 727 0 0.00 1 19326 559 0 0
srv_recalc_idx_stat 4 4 0 0.00 139364 275491 212522 0 0
t$perf-abend:sp_add_invoice_to_stock 91 91 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4958 4958 0 0.00 0 12358 160 0 0
t$perf-norm:sp_cancel_adding_invoice 414 414 0 0.00 0 3315 81 0 0
t$perf-norm:sp_cancel_client_order 1864 1864 0 0.00 0 6198 20 0 0
t$perf-norm:sp_cancel_customer_reserve 682 682 0 0.00 0 6124 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 690 690 0 0.00 0 641 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 377 377 0 0.00 0 3213 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 390 390 0 0.00 0 1567 18 0 0
t$perf-norm:sp_cancel_supplier_order 392 392 0 0.00 0 15953 1493 0 0
t$perf-norm:sp_cancel_write_off 1031 1031 0 0.00 0 1363 6 0 0
t$perf-norm:sp_client_order 21056 21056 0 0.00 0 9225 19 0 0
t$perf-norm:sp_customer_reserve 957 957 0 0.00 0 6106 9 0 0
t$perf-norm:sp_pay_from_customer 7237 7237 0 0.00 0 6811 9 0 0
t$perf-norm:sp_pay_to_supplier 5862 5862 0 0.00 0 8606 11 0 0
t$perf-norm:sp_reserve_write_off 10198 10198 0 0.00 0 7759 11 0 0
t$perf-norm:sp_supplier_invoice 5626 5626 0 0.00 0 8636 18 0 0
t$perf-norm:sp_supplier_order 5458 5458 0 0.00 0 8210 17 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 642 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 1294 52 0 0
t$perf-norm:srv_make_invnt_saldo 481 481 0 0.00 0 6029 23 0 0
t$perf-norm:srv_make_money_saldo 727 727 0 0.00 0 413 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 10074 9892 182 1.80 0 6030 17 0 182
v_all_customers 37970 37970 0 0.00 0 54 1 0 0
v_all_suppliers 30644 30644 0 0.00 0 41 1 0 0
v_all_wares 42110 42110 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 828 828 0 0.00 0 3156 14 0 0
v_cancel_client_order 3728 3728 0 0.00 0 3050 6 0 0
v_cancel_customer_prepayment 1380 1380 0 0.00 0 2809 10 0 0
v_cancel_customer_reserve 1364 1364 0 0.00 0 1544 5 0 0
v_cancel_payment_to_supplier 754 754 0 0.00 0 1380 9 0 0
v_cancel_supplier_invoice 792 780 12 1.51 0 2511 18 0 12
v_cancel_supplier_order 784 784 0 0.00 0 2442 8 0 0
v_cancel_write_off 2062 2062 0 0.00 0 1573 13 0 0
v_max_id_clo_ord 5457 5457 0 0.00 0 2217 2 0 0
v_max_id_clo_res 957 957 0 0.00 0 6731 22 0 0
v_max_id_ord_sup 5611 5611 0 0.00 0 6005 4 0 0
v_max_non_paid_invoice 1608 1608 0 0.00 0 562 2 0 0
v_max_non_paid_realizn 3932 3932 0 0.00 0 3370 4 0 0
v_min_id_clo_ord 5457 5457 0 0.00 0 7006 8 0 0
v_min_id_clo_res 957 957 0 0.00 0 12494 59 0 0
v_min_id_ord_sup 5611 5611 0 0.00 0 3637 6 0 0
v_min_non_paid_invoice 5862 5862 0 0.00 0 7679 13 0 0
v_min_non_paid_realizn 7237 7237 0 0.00 0 8966 17 0 0
v_our_firm 10750 10750 0 0.00 0 43 1 0 0
v_reserve_write_off 20396 20396 0 0.00 0 2926 4 0 0
x_lock_depdocs_on_canc_invoice 10567 10567 0 0.00 0 14312 127 0 0
x_lock_depdocs_on_canc_sup_ord 392 392 0 0.00 0 16109 381 0 0
x_make_qstorno_1000_1200 5458 5458 0 0.00 2 25219 1426 0 0
x_make_qstorno_1200_2000 5626 5626 0 0.00 4 140378 3977 0 0
x_make_qstorno_2100_3300 451684 451684 0 0.00 0 123860 167 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-02-2021 10:27:49 to 11-02-2021 10: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
07:57:00 7 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
07:58:01 38926 8192000000 9728730512 10022711296 801602464 8.913 100 96 192 0 832841920 7802672 23436784
07:59:44 20450 8192000000 9752240624 10054393856 824245888 9.141 100 90 180 0 859635392 13786464 21603040
08:01:09 20802 8192000000 9789919344 10106056704 853579472 9.436 100 83 166 0 900712048 27464656 19667920
08:02:33 12826 8192000000 9853904144 10186452992 904247616 9.940 100 76 152 0 966445664 45292816 16905232
08:03:48 7598 8192000000 9902091504 10248572928 953487072 10.425 100 79 158 1 1027817216 57482368 16847776
08:04:59 4459 8192000000 9950893552 10300047360 992230608 10.803 100 85 169 1 1058756672 49238480 17287584
08:06:08 2971 8192000000 9996861744 10356822016 1020234304 11.074 100 81 160 2 1107591056 70928720 16428032
08:07:14 4627 8192000000 10009574032 10377158656 1038581664 11.251 100 77 148 0 1131733584 78666656 14485264
08:08:19 1419 8192000000 10046813216 10414813184 1061840624 11.474 100 94 190 0 1160863520 79301168 19721728
08:09:21 3698 8192000000 10026601104 10413985792 1065962992 11.514 100 54 107 0 1147865344 71367376 10534976
08:10:30 5903 8192000000 10004528000 10389590016 1079879584 11.646 100 47 95 1 1115321120 25672048 9769488
08:11:37 66656 8192000000 10003168704 10414682112 1088085568 11.724 100 37 74 2 1110260224 14374144 7800512
08:13:45 5299 8192000000 10009012368 10413146112 1090453792 11.747 100 49 96 1 1130755552 29981760 10320000
08:14:53 10375 8192000000 10017606080 10424647680 1097069680 11.810 100 26 52 0 1126503840 24411856 5022304
08:16:06 5169 8192000000 10002604592 10406789120 1083761536 11.683 99 40 80 1 1110031424 16942432 9327456
08:17:12 43850 8192000000 9986420096 10388574208 1051836800 11.378 96 25 50 3 1076573744 19206160 5530784
08:18:58 7008 8192000000 9964600768 10360791040 1043406048 11.297 99 42 83 2 1070419712 18533200 8480464
08:20:09 9166 8192000000 9955589600 10344566784 1031751488 11.185 99 19 37 0 1065049728 29554272 3743968
08:21:20 4555 8192000000 9941954800 10313867264 1012339024 10.998 99 53 106 1 1048612320 25202960 11070336
08:22:26 16476 8192000000 9954781008 10331963392 1026814816 11.138 100 70 142 0 1058144176 15850816 15478544
08:23:43 8422 8192000000 9949846560 10349510656 1026656224 11.136 99 40 80 2 1052611600 16807792 9147584
08:24:52 12126 8192000000 9956248032 10352484352 1037020016 11.236 99 28 56 1 1076729808 33429120 6280672
08:26:09 7570 8192000000 9974700544 10373189632 1057667040 11.434 100 31 62 1 1085104144 21094944 6342160
08:27:18 75909 8192000000 10014805008 10411081728 1082767824 11.674 100 41 80 2 1113484048 21785792 8930432
08:29:37 28582 8192000000 10065411264 10470731776 1101525728 11.852 100 48 96 1 1186260528 75025696 9709104
08:31:17 23665 8192000000 10096206784 10504056832 1115331936 11.983 100 41 82 2 1202962416 78821696 8808784
08:32:46 17723 8192000000 10124175392 10533154816 1125288032 12.077 100 56 111 0 1236231392 98704032 12239328
08:34:08 9281 8192000000 10134440816 10541760512 1133088864 12.150 100 52 103 2 1240825424 96980624 10755936
08:35:21 2522 8192000000 10147378096 10553606144 1141046320 12.225 100 60 117 4 1243220144 89744528 12429296
08:36:28 25147 8192000000 10186282848 10614136832 1158061280 12.385 100 49 98 3 1298444576 129597344 10785952
08:37:59 8864 8192000000 10204420704 10617880576 1166190832 12.461 100 55 110 0 1307567232 129600176 11776224
08:39:16 5612 8192000000 10246137920 10656997376 1174808176 12.542 100 68 136 1 1350033744 159823104 15402464
08:40:26 2135 8192000000 10302240720 10712371200 1182994400 12.618 100 88 171 0 1405391568 203553600 18843568
08:41:35 5003 8192000000 10349376960 10759659520 1177590944 12.568 100 69 137 0 1454031392 260536416 15904032
08:42:45 5492 8192000000 10334738464 10765283328 1170017648 12.497 99 69 138 0 1435305152 248067360 17220144
08:44:00 1950 8192000000 10399024720 10796888064 1159634224 12.400 100 90 176 2 1496687888 317270112 19783552
08:45:06 1040 8192000000 10396287472 10797477888 1148323024 12.294 100 90 179 1 1492069168 323250768 20495376
08:46:13 2448 8192000000 10398873120 10821689344 1155573104 12.362 100 70 139 0 1500030656 328017856 16439696
08:47:19 2768 8192000000 10377407440 10789699584 1151277936 12.321 100 64 128 1 1478145632 311528000 15339696
08:48:29 4002 8192000000 10383417696 10816589824 1175284896 12.546 100 53 106 0 1494408480 306345888 12777696
08:49:38 10159 8192000000 10380583616 10807881728 1169185872 12.489 100 41 82 0 1475576528 295504544 10886112
08:50:53 6177 8192000000 10451290704 10875367424 1141146800 12.226 99 50 98 0 1541226560 387348832 12730928
08:52:06 8979 8192000000 10453434608 10883010560 1144430880 12.257 99 42 84 3 1550398928 395074448 10893600
08:53:32 18191 8192000000 10323244064 10761936896 1130698912 12.128 99 33 66 2 1418846912 278693600 9454400
08:55:42 60311 8192000000 10019715648 10465423360 1058873824 11.446 95 4 8 0 1119518576 59988512 656240
08:57:46 67543 8192000000 10057463968 10517680128 1096884864 11.808 98 18 36 0 1128630784 28507504 3238416
08:59:55 51439 8192000000 10056071328 10518769664 1101651936 11.853 98 26 52 1 1139527472 32643632 5231904
09:01:54 86819 8192000000 10043624240 10461126656 1090616464 11.749 97 15 27 0 1115743696 22861888 2265344
09:04:25 117432 8192000000 10136111968 10585821184 1130351184 12.125 100 32 63 1 1216977248 79745712 6880352
09:08:22 56843 8192000000 10164527216 10594291712 1124704816 12.071 99 45 90 0 1224734352 89447824 10581712
09:10:19 48481 8192000000 10140880144 10646925312 1128986336 12.112 100 26 52 2 1245498768 110258144 6254288
09:13:10 90702 8192000000 10125715648 10570006528 1151476096 12.323 100 23 45 2 1213451680 56928128 5047456
09:16:08 83113 8192000000 10109336336 10609111040 1154040736 12.347 100 15 30 1 1230750240 72995760 3713744
09:19:10 38617 8192000000 10112454848 10581794816 1152124736 12.329 100 9 18 0 1227206640 73423552 1658352
09:20:51 45076 8192000000 10097512688 10583040000 1147525504 12.286 99 18 36 0 1187821904 36636528 3659872
09:22:36 47053 8192000000 10141048560 10614153216 1140342464 12.219 98 40 80 2 1230145712 81152656 8650592
09:24:31 71690 8192000000 10100960704 10558267392 1110429664 11.936 96 22 44 1 1165216240 50244864 4541712
09:26:47 62769 8192000000 10070850640 10579030016 1069208720 11.545 93 20 40 0 1153541712 80565376 3767616
09:29:13 60493 8192000000 10088633904 10576441344 1121299056 12.039 98 24 48 2 1194922752 68854128 4769568
09:31:20 28927 8192000000 10118512704 10548895744 1130133200 12.123 99 33 66 0 1186111504 49219104 6759200
09:32:54 95260 8192000000 10084385952 10524315648 1139882624 12.214 100 35 71 1 1185748832 38465184 7401024
09:35:36 50403 8192000000 10077738352 10540298240 1131248544 12.133 99 12 24 1 1182346544 48717712 2380288
09:37:28 30751 8192000000 10084745328 10542399488 1136028192 12.178 100 15 30 0 1190817776 51294352 3495232
09:38:59 43583 8192000000 10096963632 10538180608 1098289200 11.821 97 17 34 1 1151703648 48995200 4419248
09:40:46 49767 8192000000 10038239056 10474385408 1080511904 11.652 96 16 32 0 1113574448 29789712 3272832
09:42:38 59222 8192000000 10096686688 10560913408 1109172848 11.925 98 26 51 3 1163594752 48816592 5605312
09:44:59 75145 8192000000 10065992144 10519855104 1089478848 11.738 96 25 50 1 1181491648 86363024 5649776
09:47:17 46514 8192000000 10113129792 10548150272 1095047488 11.791 98 41 82 0 1149270848 46193792 8029568
09:49:03 73841 8192000000 10050187648 10534785024 1086772496 11.712 97 11 22 0 1146903424 57662704 2468224
09:51:18 120103 8192000000 10068632192 10532229120 1093839360 11.779 98 15 30 0 1140821456 44063712 2918384
09:54:31 30212 8192000000 10050180960 10539057152 1116797472 11.997 100 4 8 0 1158058256 40632752 628032
09:56:01 61247 8192000000 10033341728 10489102336 1087902080 11.723 97 16 32 0 1122564864 31230288 3432496
09:58:09 66856 8192000000 10100625040 10545332224 1098498112 11.823 97 22 44 0 1137732432 34331664 4902656
10:00:17 65103 8192000000 10089794400 10551353344 1105251520 11.887 98 18 36 2 1192720320 83563184 3905616
10:02:22 32854 8192000000 10053506192 10494668800 1109656720 11.929 99 28 56 0 1195606736 80223072 5726944
10:03:57 35319 8192000000 10067564064 10508464128 1126025760 12.084 100 11 22 0 1167192592 39169488 1997344
10:05:34 66949 8192000000 10136904272 10601275392 1141221392 12.227 100 21 42 0 1215562400 69803968 4537040
10:07:43 34254 8192000000 10080954960 10524168192 1130641904 12.127 99 35 69 0 1169107984 31202496 7263584
10:09:26 28019 8192000000 10116817136 10607656960 1134232496 12.161 99 18 36 3 1243153744 105417984 3503264
10:11:30 160419 8192000000 10062951104 10521608192 1110992320 11.942 98 27 54 1 1148331296 31720352 5618624
10:15:18 61045 8192000000 10044824704 10523197440 1073967968 11.590 95 8 16 0 1135661728 59626752 2067008
10:17:20 56329 8192000000 10084191392 10526052352 1100257328 11.840 97 23 46 0 1149670192 43854368 5558496
10:19:17 62737 8192000000 10113729488 10559164416 1133024624 12.150 100 17 34 2 1225738656 89027136 3686896
10:21:25 25599 8192000000 10054386576 10494451712 1106028096 11.895 99 28 56 1 1147124528 35119264 5977168
10:22:51 69956 8192000000 10112710032 10568404992 1109132528 11.924 98 24 48 1 1182923584 68806896 4984160
10:25:01 34764 8192000000 10096309872 10530037760 1104840464 11.884 99 44 88 1 1164088768 49659664 9588640
10:26:48 24411 8192000000 8937024320 9131261952 93795280 1.132 9 1 2 0 103757664 9926976 35408

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 91 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-02-2021 10:28:11 to 11-02-2021 10: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          235653
MON$OLDEST_ACTIVE               235654
MON$OLDEST_SNAPSHOT             235654
MON$NEXT_TRANSACTION            235654
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                       2091520
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             798
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

11.02.21 10: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-11 10:28:17 No severe PSQL-related problems occured
11.02.21 10:28:18. End of report.