Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.03.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 16495236 kB
MemAvailable 31076660 kB
Buffers 0 kB
Cached 15188060 kB
SwapCached 3300 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.0
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-03-01 10:26:55
DTS_END                         2021-03-01 10:26:55.6200
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/55410
TRN_ID                          226034
ATT_ID                          599
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: 8574.13 23968 1028896
customer order: creation 160.48 429 19257
customer order: refuse 13.58 396 1629
order to supplier: creation 43.82 1337 5258
order to supplier: removal 3.18 129852 382
invoice (draft): creation 43.19 4008 5183
invoice (draft): removal 48.26 900 5791
invoice accept: apply 39.28 20026 4714
invoice accept: cancel 43.96 928 5275
customer reserve: creation 3637.56 194 436507
customer reserve: removal 4264.50 78 511740
realization accept: apply 78.55 192 9426
realization accept: cancel 77.77 129 9332
payment from customer: creation 56.50 122 6780
payment from customer: removal 5.46 112 655
payment to supplier: creation 44.83 118 5380
payment to supplier: removal 3.37 98 404
service: total inventory turnovers 3.59 3859 431
service: total monetary turnovers 5.39 672 647
service: aggregate perf. data 0.83 13737 100
service: refresh index statistics 0.04 302167 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 01-03-2021 10:28:14 to 01-03-2021 10:28:17.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5262
TEST_TIME 2 4691
TEST_TIME 3 4597
TEST_TIME 4 4658
TEST_TIME 5 5172
TEST_TIME 6 5326
TEST_TIME 7 5395
TEST_TIME 8 6074
TEST_TIME 9 5796
TEST_TIME 10 5489
TEST_TIME 11 5482
TEST_TIME 12 5395
TEST_TIME 13 5484
TEST_TIME 14 5726
TEST_TIME 15 5987
TEST_TIME 16 6213
TEST_TIME 17 6483
TEST_TIME 18 6721
TEST_TIME 19 7296
TEST_TIME 20 7337
TEST_TIME 21 7624
TEST_TIME 22 7995
TEST_TIME 23 8057
TEST_TIME 24 8471
TEST_TIME 25 8417
TEST_TIME 26 8510
TEST_TIME 27 8557
TEST_TIME 28 8951
TEST_TIME 29 9732
TEST_TIME 30 10389
TEST_TIME 31 10173
TEST_TIME 32 9890
TEST_TIME 33 9801
TEST_TIME 34 9673
TEST_TIME 35 9907
TEST_TIME 36 9753
TEST_TIME 37 9595
TEST_TIME 38 9480
TEST_TIME 39 9432
TEST_TIME 40 9362
TEST_TIME 41 9237
TEST_TIME 42 9294
TEST_TIME 43 9178
TEST_TIME 44 9160
TEST_TIME 45 9044
TEST_TIME 46 8959
TEST_TIME 47 8945
TEST_TIME 48 8872
TEST_TIME 49 8780
TEST_TIME 50 8985
TEST_TIME 51 8918
TEST_TIME 52 8924
TEST_TIME 53 8815
TEST_TIME 54 8930
TEST_TIME 55 8863
TEST_TIME 56 8810
TEST_TIME 57 8815
TEST_TIME 58 8772
TEST_TIME 59 8964
TEST_TIME 60 9045
TEST_TIME 61 9008
TEST_TIME 62 9083
TEST_TIME 63 9086
TEST_TIME 64 9114
TEST_TIME 65 9128
TEST_TIME 66 9175
TEST_TIME 67 9269
TEST_TIME 68 9245
TEST_TIME 69 9318
TEST_TIME 70 9201
TEST_TIME 71 9090
TEST_TIME 72 9019
TEST_TIME 73 8994
TEST_TIME 74 8959
TEST_TIME 75 8899
TEST_TIME 76 8881
TEST_TIME 77 8809
TEST_TIME 78 8751
TEST_TIME 79 8738
TEST_TIME 80 8693
TEST_TIME 81 8813
TEST_TIME 82 8776
TEST_TIME 83 8782
TEST_TIME 84 8870
TEST_TIME 85 8854
TEST_TIME 86 8859
TEST_TIME 87 8890
TEST_TIME 88 8855
TEST_TIME 89 8914
TEST_TIME 90 8905
TEST_TIME 91 8879
TEST_TIME 92 8895
TEST_TIME 93 8800
TEST_TIME 94 8745
TEST_TIME 95 8700
TEST_TIME 96 8661
TEST_TIME 97 8655
TEST_TIME 98 8623
TEST_TIME 99 8601
TEST_TIME 100 8597
TEST_TIME 101 8600
TEST_TIME 102 8591
TEST_TIME 103 8564
TEST_TIME 104 8612
TEST_TIME 105 8712
TEST_TIME 106 8682
TEST_TIME 107 8745
TEST_TIME 108 8767
TEST_TIME 109 8733
TEST_TIME 110 8781
TEST_TIME 111 8850
TEST_TIME 112 8805
TEST_TIME 113 8758
TEST_TIME 114 8727
TEST_TIME 115 8690
TEST_TIME 116 8670
TEST_TIME 117 8647
TEST_TIME 118 8631
TEST_TIME 119 8601
TEST_TIME 120 8578

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 01-03-2021 10:28:18 to 01-03-2021 10:28:22.

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 1431205 1431205 0 0.00 0 10179 7 0 0
doc_list_biud 1431203 1431203 0 0.00 0 35393 34 0 0
sp_add_invoice_to_stock 4840 4714 126 2.60 23 169348 20027 0 126
sp_cancel_adding_invoice 5275 5275 0 0.00 5 56576 928 0 0
sp_cancel_client_order 1629 1629 0 0.00 4 10065 396 0 0
sp_cancel_customer_reserve 511740 511740 0 0.00 1 32656 78 0 0
sp_cancel_pay_from_customer 655 655 0 0.00 3 7749 112 0 0
sp_cancel_pay_to_supplier 404 404 0 0.00 3 2693 98 0 0
sp_cancel_supplier_invoice 5803 5791 12 0.20 2 14949 900 0 12
sp_cancel_supplier_order 382 382 0 0.00 9 1240660 129853 0 0
sp_cancel_write_off 9332 9332 0 0.00 1 9898 129 0 0
sp_client_order 19257 19257 0 0.00 4 69866 430 0 0
sp_customer_reserve 436507 436507 0 0.00 0 96783 195 0 0
sp_fill_shopping_cart 29685 29685 0 0.00 1 16380 46 0 0
sp_fill_shopping_cart_clo_res 436498 436498 0 0.00 0 28440 19 0 0
sp_get_clo_for_invoice 4705 4705 0 0.00 0 68562 445 0 0
sp_kill_cost_storno 15666 15666 0 0.00 0 6857 65 0 0
sp_kill_qstorno_ret_qs2qd 1038985 1038985 0 0.00 0 31182 41 0 0
sp_kill_qty_storno 548279 548279 0 0.00 0 35393 89 0 0
sp_lock_selected_doc 19596 19596 0 0.00 0 11267 5 0 0
sp_make_cost_storno 26291 26291 0 0.00 0 10178 56 0 0
sp_multiply_rows_for_pdistr 26290 26290 0 0.00 0 6150 34 0 0
sp_multiply_rows_for_qdistr 19257 19257 0 0.00 0 69806 240 0 0
sp_pay_from_customer 6780 6780 0 0.00 4 10184 122 0 0
sp_pay_to_supplier 5380 5380 0 0.00 5 10580 118 0 0
sp_payment_common 12159 12159 0 0.00 1 10565 28 0 0
sp_qd_handle_on_cancel_clo 1629 1629 0 0.00 0 10055 370 0 0
sp_qd_handle_on_invoice_upd_sts 9979 9979 0 0.00 0 35392 422 0 0
sp_qd_handle_on_reserve_upd_sts 9426 9426 0 0.00 0 9336 60 0 0
sp_reserve_write_off 9426 9426 0 0.00 6 9418 192 0 0
sp_supplier_invoice 5183 5183 0 0.00 7 104855 4009 0 0
sp_supplier_order 5258 5258 0 0.00 5 90444 1338 0 0
srv_aggregate_perf_data 100 100 0 0.00 2865 109379 13737 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1250 134864 47146 0 0
srv_find_qd_qs_mism 983137 983137 0 0.00 0 9523 6 0 0
srv_make_invnt_saldo 431 431 0 0.00 19 108032 3859 0 0
srv_make_money_saldo 647 647 0 0.00 1 12289 673 0 0
srv_recalc_idx_stat 5 5 0 0.00 77186 435029 302167 0 0
t$perf-abend:sp_add_invoice_to_stock 126 126 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4715 4715 0 0.00 0 12619 191 0 0
t$perf-norm:sp_cancel_adding_invoice 361 361 0 0.00 0 22392 149 0 0
t$perf-norm:sp_cancel_client_order 1629 1629 0 0.00 0 2566 16 0 0
t$perf-norm:sp_cancel_customer_reserve 678 678 0 0.00 0 3494 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 655 655 0 0.00 0 5896 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 404 404 0 0.00 0 322 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 398 398 0 0.00 0 908 17 0 0
t$perf-norm:sp_cancel_supplier_order 382 382 0 0.00 0 23436 1635 0 0
t$perf-norm:sp_cancel_write_off 959 959 0 0.00 0 815 4 0 0
t$perf-norm:sp_client_order 19257 19257 0 0.00 0 4169 21 0 0
t$perf-norm:sp_customer_reserve 941 941 0 0.00 0 3559 11 0 0
t$perf-norm:sp_pay_from_customer 6780 6780 0 0.00 0 13737 13 0 0
t$perf-norm:sp_pay_to_supplier 5380 5380 0 0.00 0 13680 10 0 0
t$perf-norm:sp_reserve_write_off 9426 9426 0 0.00 0 7591 9 0 0
t$perf-norm:sp_supplier_invoice 5183 5183 0 0.00 0 4666 20 0 0
t$perf-norm:sp_supplier_order 5258 5258 0 0.00 0 4027 20 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 513 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 94 4 0 0
t$perf-norm:srv_make_invnt_saldo 431 431 0 0.00 0 2151 14 0 0
t$perf-norm:srv_make_money_saldo 647 647 0 0.00 0 1067 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 8 4 0 0
v_add_invoice_to_stock 9660 9408 252 2.60 0 5138 18 0 252
v_all_customers 35282 35282 0 0.00 0 35 1 0 0
v_all_suppliers 28236 28236 0 0.00 0 20 1 0 0
v_all_wares 38514 38514 0 0.00 0 127 1 0 0
v_cancel_adding_invoice 722 722 0 0.00 0 257 6 0 0
v_cancel_client_order 3258 3258 0 0.00 0 1824 5 0 0
v_cancel_customer_prepayment 1310 1310 0 0.00 0 2582 14 0 0
v_cancel_customer_reserve 1356 1356 0 0.00 0 1971 5 0 0
v_cancel_payment_to_supplier 808 808 0 0.00 0 2290 10 0 0
v_cancel_supplier_invoice 820 796 24 2.92 0 5787 19 0 24
v_cancel_supplier_order 764 764 0 0.00 0 1614 8 0 0
v_cancel_write_off 1918 1918 0 0.00 0 1783 16 0 0
v_max_id_clo_ord 5252 5252 0 0.00 0 541 2 0 0
v_max_id_clo_res 941 941 0 0.00 0 3213 14 0 0
v_max_id_ord_sup 5176 5176 0 0.00 0 4629 3 0 0
v_max_non_paid_invoice 1690 1690 0 0.00 0 426 3 0 0
v_max_non_paid_realizn 3524 3524 0 0.00 0 4319 7 0 0
v_min_id_clo_ord 5252 5252 0 0.00 0 2817 7 0 0
v_min_id_clo_res 941 941 0 0.00 0 4581 14 0 0
v_min_id_ord_sup 5176 5176 0 0.00 0 6305 8 0 0
v_min_non_paid_invoice 5380 5380 0 0.00 0 5079 16 0 0
v_min_non_paid_realizn 6779 6779 0 0.00 0 6750 21 0 0
v_our_firm 9742 9742 0 0.00 0 16 1 0 0
v_reserve_write_off 18852 18852 0 0.00 0 4144 5 0 0
x_lock_depdocs_on_canc_invoice 10668 10668 0 0.00 0 23242 130 0 0
x_lock_depdocs_on_canc_sup_ord 382 382 0 0.00 0 7788 350 0 0
x_make_qstorno_1000_1200 5258 5258 0 0.00 2 89267 1242 0 0
x_make_qstorno_1200_2000 5183 5183 0 0.00 3 104837 3843 0 0
x_make_qstorno_2100_3300 436507 436507 0 0.00 0 96782 173 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-03-2021 10:28:23 to 01-03-2021 10:28:27.

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:59 6 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
07:58:00 18776 8192000000 9709820688 10002427904 782099712 8.715 100 83 165 0 809925072 7631376 20193984
07:59:26 19837 8192000000 9763273312 10067763200 839170096 9.291 100 92 184 0 872986000 11387328 22428576
08:00:51 17674 8192000000 9800710368 10117320704 867302080 9.573 100 80 158 3 905183152 19679904 18201168
08:02:13 15891 8192000000 9854594432 10184876032 913103696 10.028 100 80 160 0 960140096 29926288 17110112
08:03:32 8865 8192000000 9910457072 10248208384 947975600 10.371 100 89 178 0 1019090416 52343120 18771696
08:04:44 6231 8192000000 9945871488 10296635392 985045280 10.733 100 81 162 0 1062465376 60752032 16668064
08:05:52 2165 8192000000 9987718752 10344656896 1012137408 10.996 100 90 180 0 1110518992 80365600 18015984
08:06:55 2279 8192000000 10009581184 10373287936 1034598432 11.213 100 88 174 2 1123543296 71164448 17780416
08:07:59 1843 8192000000 10034870880 10403602432 1057438416 11.432 100 91 183 0 1146046448 69340784 19267248
08:09:03 25138 8192000000 9993813184 10387001344 1065573504 11.510 100 58 116 0 1126321984 48402288 12346192
08:10:29 9266 8192000000 9969471856 10367033344 1072875824 11.580 100 34 68 1 1113778768 34001424 6901520
08:11:40 8328 8192000000 10013251472 10408144896 1080058144 11.648 100 35 69 1 1126728896 39207456 7463296
08:12:48 7465 8192000000 10012442704 10414243840 1087174096 11.716 100 36 72 1 1125524192 30554688 7795408
08:13:57 54960 8192000000 10004891616 10416177152 1090200512 11.745 100 35 69 1 1114886592 16468224 8217856
08:15:54 5407 8192000000 10012989392 10428260352 1097288864 11.812 100 35 70 2 1135821504 31023424 7509216
08:17:00 6587 8192000000 10009651216 10408251392 1088555920 11.729 100 42 85 2 1112408928 14867824 8985184
08:18:10 4651 8192000000 9947549696 10342313984 1029727328 11.166 97 23 45 2 1058871488 24196320 4947840
08:19:17 52937 8192000000 9938604416 10317692928 944038304 10.333 90 20 40 0 965618992 16824160 4756528
08:21:10 8941 8192000000 9971236752 10367057920 1034940704 11.216 100 37 74 1 1074281328 30996864 8343760
08:22:23 10544 8192000000 9948772448 10351579136 1027402672 11.143 99 33 67 2 1074916384 40232336 7281376
08:23:35 6712 8192000000 9957291600 10348265472 1037675056 11.242 100 31 61 1 1074372128 29736736 6960336
08:24:46 50260 8192000000 9968067888 10380607488 1059246592 11.449 100 32 64 2 1090104160 24355216 6502352
08:26:36 9201 8192000000 10004700304 10400935936 1081018976 11.657 100 41 79 2 1125831536 36036992 8775568
08:27:52 3758 8192000000 10051410368 10442739712 1104009856 11.876 100 69 137 1 1151178592 32297616 14871120
08:28:59 47820 8192000000 10075299376 10485972992 1110283024 11.935 100 49 97 0 1182597376 61686848 10627504
08:30:55 10357 8192000000 10070320944 10474229760 1128444032 12.107 100 35 70 0 1189073952 52691376 7938544
08:32:08 17966 8192000000 10163101200 10566049792 1142648816 12.240 100 68 135 0 1277556848 120364864 14543168
08:33:30 7467 8192000000 10170835568 10578677760 1150649824 12.316 100 62 124 2 1283449104 119527792 13271488
08:34:40 17820 8192000000 10148879664 10588794880 1155242912 12.359 100 29 58 1 1271754992 110038880 6473200
08:36:06 40895 8192000000 10222097120 10639605760 1163353344 12.435 100 48 96 0 1294737792 121380816 10003632
08:38:35 17754 8192000000 10286738304 10706558976 1181659808 12.606 100 71 142 1 1382177312 184613488 15904016
08:40:01 2832 8192000000 10315785744 10730962944 1174983632 12.543 99 68 134 0 1414939296 224773248 15182416
08:41:08 4090 8192000000 10343989904 10749968384 1175784688 12.551 100 78 151 1 1443083376 250161216 17137472
08:42:16 7216 8192000000 10366949792 10784755712 1177017376 12.562 100 68 136 0 1470855760 277959264 15879120
08:43:29 1250 8192000000 10380904576 10788610048 1169290800 12.490 99 92 178 0 1476023728 286259872 20473056
08:44:34 4711 8192000000 10416621312 10837647360 1187055104 12.656 100 69 138 2 1521850544 317922720 16872720
08:45:43 4801 8192000000 10387716192 10787639296 1170299968 12.500 100 84 164 0 1479566624 291422208 17844448
08:46:51 2081 8192000000 10389299568 10794176512 1151231904 12.321 100 81 162 2 1491366240 320545584 19588752
08:48:00 3107 8192000000 10415903424 10820935680 1158914144 12.393 100 86 169 3 1509767648 331017024 19836480
08:49:09 12397 8192000000 10361371968 10804174848 1145541616 12.268 100 32 64 0 1472759696 318743120 8474960
08:50:24 14658 8192000000 10371503952 10794332160 1142325088 12.237 98 45 88 1 1462937600 309672112 10940400
08:51:44 14949 8192000000 10381295440 10827747328 1147332080 12.284 100 28 54 2 1495082384 340285488 7464816
08:53:06 12991 8192000000 10368475456 10828079104 1145828272 12.270 100 35 70 0 1466033392 310909744 9295376
08:54:27 19652 8192000000 10411550640 10852536320 1131215152 12.133 99 27 54 1 1474040752 335573872 7251728
08:56:27 46204 8192000000 10040449424 10481176576 1107856544 11.912 99 11 22 0 1181502672 71183456 2462672
08:58:27 45703 8192000000 10089822992 10512060416 1123560240 12.061 100 60 120 0 1195462928 57994640 13908048
09:00:15 68015 8192000000 10093241104 10568110080 1076131088 11.611 96 19 40 0 1188878576 108641536 4105952
09:02:27 98474 8192000000 10081150480 10512224256 1092438896 11.766 97 11 22 0 1137437792 42617712 2381184
09:05:11 42500 8192000000 10082688624 10512887808 1133030400 12.150 100 35 70 1 1185072368 43998208 8043760
09:07:00 134864 8192000000 10080506288 10571309056 1135862304 12.177 100 28 53 2 1225916192 83471248 6582640
09:10:15 64090 8192000000 10083947968 10552709120 1138837488 12.205 100 17 34 1 1200084176 57767840 3478848
09:12:20 34758 8192000000 10107807264 10563747840 1125051360 12.075 98 24 48 1 1196155072 65756832 5346880
09:14:03 79347 8192000000 10161868992 10597339136 1105792064 11.893 97 25 51 1 1215734464 104559616 5382784
09:16:34 53007 8192000000 10106606224 10592456704 1137983376 12.197 100 10 20 1 1237254432 96970208 2300848
09:18:27 69668 8192000000 10108434224 10606460928 1123576800 12.061 99 14 28 2 1214492240 87476608 3438832
09:20:41 80731 8192000000 10050348016 10486050816 1097244272 11.811 97 10 20 0 1138000336 38827264 1928800
09:23:05 66521 8192000000 10124205344 10581127168 1136292960 12.181 100 23 46 0 1219085376 77970848 4821568
09:25:18 100216 8192000000 10089203440 10559655936 1104936064 11.884 97 9 16 1 1181123568 73824544 2362960
09:27:59 66134 8192000000 10066636304 10526699520 1129931792 12.121 99 18 35 0 1182932400 50055696 2944912
09:30:05 95909 8192000000 10077613056 10539966464 1086892384 11.713 94 25 49 2 1139589136 47779040 4917712
09:32:49 79477 8192000000 10088086784 10593419264 1139279440 12.209 99 13 26 0 1192988800 51643600 2065760
09:35:15 86640 8192000000 10069594784 10552877056 1131443536 12.135 98 8 16 0 1172566016 39373168 1749312
09:37:47 43297 8192000000 10066080976 10512769024 1102890080 11.865 96 23 46 0 1136358656 29242768 4225808
09:39:40 39477 8192000000 10087851216 10527784960 1129861200 12.120 99 32 65 0 1165640208 29512992 6266016
09:41:34 37030 8192000000 10081476256 10527199232 1129341872 12.115 98 14 28 2 1187084160 54968112 2774176
09:43:15 63417 8192000000 10126879744 10572398592 1120463440 12.031 99 17 34 0 1206612240 82284336 3864464
09:45:25 56009 8192000000 10149984112 10644959232 1111416864 11.946 98 18 36 0 1233113280 116979216 4717200
09:47:25 43117 8192000000 10097518080 10607448064 1112054784 11.952 99 20 40 1 1218461952 101717200 4689968
09:49:08 75339 8192000000 10072130048 10598752256 1098930064 11.827 97 21 42 1 1206529616 102500576 5098976
09:51:27 63422 8192000000 10076013552 10516348928 1095511888 11.795 97 30 60 1 1159021456 56620272 6889296
09:53:42 32674 8192000000 10058701344 10478440448 1082361120 11.670 97 41 80 2 1118792208 28289248 8141840
09:55:19 43722 8192000000 10075462128 10548822016 1096336320 11.803 98 26 52 1 1159718256 58063872 5318064
09:57:15 80767 8192000000 10063298400 10537680896 1116627600 11.995 99 25 50 0 1177491872 55643616 5220656
10:01:35 52554 8192000000 10037078656 10483527680 1101811248 11.855 98 8 15 0 1138040160 35234064 994848
10:03:28 62638 8192000000 10044679056 10536996864 1108787584 11.921 99 8 16 0 1142820432 32588848 1444000
10:05:31 76667 8192000000 10088730272 10545360896 1122386944 12.050 99 19 38 1 1180853616 54222592 4244080
10:07:49 54658 8192000000 10164446944 10617032704 1139113696 12.207 99 30 59 0 1221856384 76012768 6729920
10:09:45 75340 8192000000 10121654752 10660225024 1133948240 12.159 100 18 36 1 1252784208 114748688 4087280
10:12:05 28287 8192000000 10126891296 10551140352 1133443616 12.154 99 48 95 1 1199042816 55615520 9983680
10:13:50 91200 8192000000 10080243680 10522484736 1052872976 11.388 93 12 24 0 1096495168 41069680 2552512
10:16:25 85676 8192000000 10057173952 10479267840 1103033936 11.866 98 32 63 1 1161340240 51638160 6668144
10:18:51 99185 8192000000 10046715616 10508808192 1124502208 12.070 100 15 30 2 1185162912 57003024 3657680
10:21:34 37157 8192000000 10047924032 10495520768 1120870464 12.035 99 12 24 1 1161037840 37767344 2400032
10:23:14 56017 8192000000 10059359104 10515742720 1132426864 12.144 100 8 16 0 1158923376 25456080 1040432
10:25:11 44464 8192000000 10083020976 10530598912 1121359936 12.040 98 15 30 0 1150171920 25953696 2858288

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 01-03-2021 10:28:28 to 01-03-2021 10:28:43.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 126 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 01-03-2021 10:28:44 to 01-03-2021 10:28:46.

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          223114
MON$OLDEST_ACTIVE               226164
MON$OLDEST_SNAPSHOT             226164
MON$NEXT_TRANSACTION            226164
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                       2093888
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             779
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2377 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2377 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2377 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.2377 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 ---
:143: 
:144: mscs-test-161.budzdorov.ru    Mon Mar  1 10:26:59 2021
:145:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:146: 
:147: 
:148: mscs-test-161.budzdorov.ru    Mon Mar  1 10:27:00 2021
:149:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:150: 
:151: 
:152: mscs-test-161.budzdorov.ru    Mon Mar  1 10:27:00 2021
:153:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:154: 
--- end of diff output ---

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

01.03.21 10:28:47. There are 6 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-03-01 10:28:50 No severe PSQL-related problems occured
01.03.21 10:28:51. End of report.