Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.10.2021 16:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15928612 kB
MemAvailable 30610788 kB
Buffers 0 kB
Cached 15736852 kB
SwapCached 3704 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 3.0.8
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-10-17 16:37:54
DTS_END                         2021-10-17 16:37:54.8910
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/58590
TRN_ID                          240177
ATT_ID                          719
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: 9206.36 22847 1104763
customer order: creation 170.38 377 20445
customer order: refuse 14.62 346 1754
order to supplier: creation 45.01 1295 5401
order to supplier: removal 3.39 110421 407
invoice (draft): creation 46.95 3308 5634
invoice (draft): removal 52.04 838 6245
invoice accept: apply 41.40 19011 4968
invoice accept: cancel 47.00 879 5640
customer reserve: creation 3877.93 185 465351
customer reserve: removal 4607.08 64 552850
realization accept: apply 83.51 176 10021
realization accept: cancel 86.47 115 10376
payment from customer: creation 58.53 124 7023
payment from customer: removal 5.65 102 678
payment to supplier: creation 50.48 144 6057
payment to supplier: removal 3.57 59 428
service: total inventory turnovers 4.56 1085 547
service: total monetary turnovers 6.98 32 838
service: aggregate perf. data 0.80 24823 96
service: refresh index statistics 0.03 293561 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-10-2021 16:38:57 to 17-10-2021 16:38:59.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2812
TEST_TIME 2 4025
TEST_TIME 3 4543
TEST_TIME 4 4743
TEST_TIME 5 5420
TEST_TIME 6 4985
TEST_TIME 7 5325
TEST_TIME 8 5730
TEST_TIME 9 5938
TEST_TIME 10 5695
TEST_TIME 11 5827
TEST_TIME 12 5832
TEST_TIME 13 5939
TEST_TIME 14 6209
TEST_TIME 15 6663
TEST_TIME 16 6783
TEST_TIME 17 7039
TEST_TIME 18 7121
TEST_TIME 19 7324
TEST_TIME 20 7878
TEST_TIME 21 7805
TEST_TIME 22 7717
TEST_TIME 23 8165
TEST_TIME 24 8158
TEST_TIME 25 8243
TEST_TIME 26 8976
TEST_TIME 27 9260
TEST_TIME 28 9989
TEST_TIME 29 10615
TEST_TIME 30 10537
TEST_TIME 31 10470
TEST_TIME 32 10323
TEST_TIME 33 10043
TEST_TIME 34 10138
TEST_TIME 35 10170
TEST_TIME 36 10056
TEST_TIME 37 9896
TEST_TIME 38 9815
TEST_TIME 39 9680
TEST_TIME 40 9776
TEST_TIME 41 9800
TEST_TIME 42 9680
TEST_TIME 43 9562
TEST_TIME 44 9569
TEST_TIME 45 9659
TEST_TIME 46 9598
TEST_TIME 47 9466
TEST_TIME 48 9455
TEST_TIME 49 9371
TEST_TIME 50 9256
TEST_TIME 51 9262
TEST_TIME 52 9280
TEST_TIME 53 9232
TEST_TIME 54 9307
TEST_TIME 55 9331
TEST_TIME 56 9304
TEST_TIME 57 9488
TEST_TIME 58 9399
TEST_TIME 59 9487
TEST_TIME 60 9475
TEST_TIME 61 9475
TEST_TIME 62 9635
TEST_TIME 63 9671
TEST_TIME 64 9601
TEST_TIME 65 9467
TEST_TIME 66 9463
TEST_TIME 67 9407
TEST_TIME 68 9336
TEST_TIME 69 9332
TEST_TIME 70 9298
TEST_TIME 71 9319
TEST_TIME 72 9296
TEST_TIME 73 9287
TEST_TIME 74 9328
TEST_TIME 75 9336
TEST_TIME 76 9336
TEST_TIME 77 9389
TEST_TIME 78 9377
TEST_TIME 79 9360
TEST_TIME 80 9423
TEST_TIME 81 9442
TEST_TIME 82 9436
TEST_TIME 83 9359
TEST_TIME 84 9357
TEST_TIME 85 9331
TEST_TIME 86 9296
TEST_TIME 87 9295
TEST_TIME 88 9284
TEST_TIME 89 9231
TEST_TIME 90 9251
TEST_TIME 91 9219
TEST_TIME 92 9236
TEST_TIME 93 9199
TEST_TIME 94 9200
TEST_TIME 95 9242
TEST_TIME 96 9264
TEST_TIME 97 9276
TEST_TIME 98 9346
TEST_TIME 99 9353
TEST_TIME 100 9337
TEST_TIME 101 9360
TEST_TIME 102 9427
TEST_TIME 103 9389
TEST_TIME 104 9382
TEST_TIME 105 9373
TEST_TIME 106 9411
TEST_TIME 107 9332
TEST_TIME 108 9348
TEST_TIME 109 9312
TEST_TIME 110 9302
TEST_TIME 111 9252
TEST_TIME 112 9227
TEST_TIME 113 9179
TEST_TIME 114 9174
TEST_TIME 115 9154
TEST_TIME 116 9141
TEST_TIME 117 9138
TEST_TIME 118 9157
TEST_TIME 119 9196
TEST_TIME 120 9214

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-10-2021 16:39:01 to 17-10-2021 16:39:04.

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 1534710 1534710 0 0.00 0 32361 6 0 0
doc_list_biud 1534708 1534708 0 0.00 0 30717 29 0 0
sp_add_invoice_to_stock 5074 4968 106 2.08 37 192114 19012 0 106
sp_cancel_adding_invoice 5640 5640 0 0.00 4 78036 879 0 0
sp_cancel_client_order 1754 1754 0 0.00 4 12391 347 0 0
sp_cancel_customer_reserve 552850 552850 0 0.00 1 30720 64 0 0
sp_cancel_pay_from_customer 678 678 0 0.00 2 7202 102 0 0
sp_cancel_pay_to_supplier 428 428 0 0.00 3 2458 60 0 0
sp_cancel_supplier_invoice 6251 6245 6 0.09 2 30344 839 0 6
sp_cancel_supplier_order 407 407 0 0.00 5 903619 110422 0 0
sp_cancel_write_off 10376 10376 0 0.00 1 27974 116 0 0
sp_client_order 20445 20445 0 0.00 3 27106 378 0 0
sp_customer_reserve 465351 465351 0 0.00 0 60448 186 0 0
sp_fill_shopping_cart 31479 31479 0 0.00 1 15313 38 0 0
sp_fill_shopping_cart_clo_res 465331 465331 0 0.00 0 11162 14 0 0
sp_get_clo_for_invoice 4946 4946 0 0.00 0 19899 356 0 0
sp_kill_cost_storno 17122 17122 0 0.00 0 32349 60 0 0
sp_kill_qstorno_ret_qs2qd 1122728 1122728 0 0.00 0 30701 35 0 0
sp_kill_qty_storno 592238 592238 0 0.00 0 30717 74 0 0
sp_lock_selected_doc 20785 20785 0 0.00 0 11046 8 0 0
sp_make_cost_storno 28046 28046 0 0.00 0 10948 52 0 0
sp_multiply_rows_for_pdistr 28046 28046 0 0.00 0 8971 28 0 0
sp_multiply_rows_for_qdistr 20445 20445 0 0.00 0 12426 167 0 0
sp_pay_from_customer 7023 7023 0 0.00 4 22768 125 0 0
sp_pay_to_supplier 6057 6057 0 0.00 4 12577 145 0 0
sp_payment_common 13080 13080 0 0.00 1 10753 30 0 0
sp_qd_handle_on_cancel_clo 1754 1754 0 0.00 0 8318 304 0 0
sp_qd_handle_on_invoice_upd_sts 10585 10585 0 0.00 0 24360 325 0 0
sp_qd_handle_on_reserve_upd_sts 10021 10021 0 0.00 0 13006 54 0 0
sp_reserve_write_off 10021 10021 0 0.00 6 14957 177 0 0
sp_supplier_invoice 5634 5634 0 0.00 7 83725 3309 0 0
sp_supplier_order 5401 5401 0 0.00 5 42464 1296 0 0
srv_aggregate_perf_data 96 96 0 0.00 2437 451521 24823 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1536 127017 44440 0 0
srv_find_qd_qs_mism 1056388 1056388 0 0.00 0 23385 5 0 0
srv_make_invnt_saldo 547 547 0 0.00 49 44860 1086 0 0
srv_make_money_saldo 838 838 0 0.00 1 9192 33 0 0
srv_recalc_idx_stat 4 4 0 0.00 117217 424285 293562 0 0
t$perf-abend:sp_add_invoice_to_stock 106 106 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 4968 4968 0 0.00 0 10190 145 0 0
t$perf-norm:sp_cancel_adding_invoice 411 411 0 0.00 0 2161 100 0 0
t$perf-norm:sp_cancel_client_order 1754 1754 0 0.00 0 1220 8 0 0
t$perf-norm:sp_cancel_customer_reserve 701 701 0 0.00 0 1454 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 678 678 0 0.00 0 263 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 428 428 0 0.00 0 905 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 441 441 0 0.00 0 2584 20 0 0
t$perf-norm:sp_cancel_supplier_order 407 407 0 0.00 0 21151 1515 0 0
t$perf-norm:sp_cancel_write_off 1000 1000 0 0.00 0 405 3 0 0
t$perf-norm:sp_client_order 20445 20445 0 0.00 0 9568 15 0 0
t$perf-norm:sp_customer_reserve 1001 1001 0 0.00 0 468 3 0 0
t$perf-norm:sp_pay_from_customer 7023 7023 0 0.00 0 11030 12 0 0
t$perf-norm:sp_pay_to_supplier 6057 6057 0 0.00 0 3642 7 0 0
t$perf-norm:sp_reserve_write_off 10021 10021 0 0.00 0 5224 9 0 0
t$perf-norm:sp_supplier_invoice 5634 5634 0 0.00 0 7471 18 0 0
t$perf-norm:sp_supplier_order 5401 5401 0 0.00 0 6413 17 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 110 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 172 5 0 0
t$perf-norm:srv_make_invnt_saldo 547 547 0 0.00 0 1896 27 0 0
t$perf-norm:srv_make_money_saldo 838 838 0 0.00 0 871 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10100 9888 212 2.09 0 22532 27 0 212
v_all_customers 37084 37084 0 0.00 0 18 1 0 0
v_all_suppliers 31130 31130 0 0.00 0 27 1 0 0
v_all_wares 40890 40890 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 822 822 0 0.00 0 22609 47 0 0
v_cancel_client_order 3508 3508 0 0.00 0 12244 12 0 0
v_cancel_customer_prepayment 1356 1356 0 0.00 0 2328 6 0 0
v_cancel_customer_reserve 1402 1402 0 0.00 0 1685 5 0 0
v_cancel_payment_to_supplier 856 856 0 0.00 0 2208 10 0 0
v_cancel_supplier_invoice 894 882 12 1.34 0 7500 26 0 12
v_cancel_supplier_order 814 814 0 0.00 0 2578 8 0 0
v_cancel_write_off 2000 2000 0 0.00 0 4519 20 0 0
v_max_id_clo_ord 5401 5401 0 0.00 0 705 1 0 0
v_max_id_clo_res 1001 1001 0 0.00 0 4285 13 0 0
v_max_id_ord_sup 5633 5633 0 0.00 0 6965 6 0 0
v_max_non_paid_invoice 1526 1526 0 0.00 0 1173 3 0 0
v_max_non_paid_realizn 3763 3763 0 0.00 0 697 4 0 0
v_min_id_clo_ord 5401 5401 0 0.00 0 12353 10 0 0
v_min_id_clo_res 1001 1001 0 0.00 0 1805 6 0 0
v_min_id_ord_sup 5633 5633 0 0.00 0 5394 9 0 0
v_min_non_paid_invoice 6057 6057 0 0.00 0 6701 25 0 0
v_min_non_paid_realizn 7023 7023 0 0.00 0 7157 19 0 0
v_our_firm 10326 10326 0 0.00 0 17 1 0 0
v_reserve_write_off 20042 20042 0 0.00 0 14811 8 0 0
x_lock_depdocs_on_canc_invoice 11444 11444 0 0.00 0 33279 137 0 0
x_lock_depdocs_on_canc_sup_ord 407 407 0 0.00 0 18570 434 0 0
x_make_qstorno_1000_1200 5401 5401 0 0.00 2 42418 1215 0 0
x_make_qstorno_1200_2000 5634 5634 0 0.00 2 72451 3177 0 0
x_make_qstorno_2100_3300 465351 465351 0 0.00 0 60447 169 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-10-2021 16:39:05 to 17-10-2021 16:39:09.

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
14:07:58 12 8192000000 8798900048 8810233856 5314912 0.064 2 1 2 0 5414512 56816 42784
14:08:59 24284 8192000000 9900798000 10211442688 987652528 10.759 100 86 172 0 1016413264 5697296 23063440
14:10:33 22162 8192000000 9951798480 10273439744 1027379424 11.143 100 85 170 0 1057645312 7584960 22680928
14:11:58 16649 8192000000 9979463072 10312769536 1063770880 11.493 100 79 158 0 1092526288 8732976 20022432
14:13:20 14707 8192000000 10038695712 10386276352 1124012512 12.065 100 86 172 0 1154928752 10612640 20303600
14:14:37 10340 8192000000 10091418368 10451697664 1175986464 12.553 100 73 147 0 1204456400 12000016 16469920
14:15:49 6053 8192000000 10129361520 10499100672 1220957504 12.971 100 84 168 1 1252652192 13105360 18589328
14:16:57 2165 8192000000 10165972656 10541596672 1252688832 13.263 100 86 171 1 1285839568 13509024 19641712
14:18:01 1463 8192000000 10193975136 10576244736 1282888768 13.539 100 90 180 1 1316505328 13357808 20258752
14:19:04 1369 8192000000 10220737840 10605805568 1305153136 13.742 100 95 191 0 1340391824 13728432 21510256
14:20:06 2951 8192000000 10211381200 10614747136 1310821552 13.794 100 64 128 0 1336284688 11312112 14151024
14:21:11 7152 8192000000 10212673568 10624512000 1319893712 13.876 100 26 52 1 1332449760 7211872 5344176
14:22:19 15921 8192000000 10208760160 10630127616 1324730416 13.920 100 25 49 1 1340429504 10493104 5205984
14:23:39 15879 8192000000 10214126192 10636787712 1326106624 13.932 100 25 50 1 1337797456 6387264 5303568
14:24:57 12614 8192000000 10223696256 10646581248 1332506896 13.990 100 21 41 1 1343149136 6209376 4432864
14:26:11 7636 8192000000 10225351616 10643587072 1334363936 14.007 100 23 46 2 1344847392 4983728 5499728
14:27:22 58521 8192000000 10187791504 10604048384 1271366000 13.434 96 25 48 1 1282984960 6016944 5602016
14:29:25 6784 8192000000 10163419536 10565922816 1239016848 13.137 96 24 47 2 1250014496 5615088 5382560
14:30:33 7783 8192000000 10140582912 10545266688 1195323520 12.733 94 28 56 1 1207316368 5396496 6596352
14:31:43 64134 8192000000 10147639008 10553483264 1227584720 13.032 97 27 54 0 1239786640 5803120 6398800
14:33:47 6901 8192000000 10164272000 10572595200 1275312432 13.470 100 19 38 1 1285387712 5192560 4882720
14:34:55 3549 8192000000 10187494272 10596134912 1294418144 13.644 100 23 46 0 1305955936 6458928 5078864
14:36:00 14806 8192000000 10194179488 10606886912 1304019584 13.732 100 19 38 1 1313424224 5259488 4145152
14:37:17 11981 8192000000 10217345536 10628444160 1318630176 13.864 100 45 90 2 1334185488 5352688 10202624
14:38:39 12685 8192000000 10258575488 10664947712 1341602896 14.072 100 63 126 1 1372876224 17293760 13979568
14:39:57 13834 8192000000 10265100848 10678272000 1354384800 14.187 100 52 103 4 1375339104 9624448 11329856
14:41:15 9861 8192000000 10280675792 10692075520 1367599328 14.306 100 49 98 1 1388005200 9073712 11332160
14:42:31 11017 8192000000 10281700336 10703093760 1376068640 14.381 100 50 97 2 1396231504 9051056 11111808
14:43:45 25591 8192000000 10303087552 10728013824 1386919120 14.478 100 41 82 1 1412697392 15872320 9905952
14:45:21 3044 8192000000 10313458224 10742124544 1399345360 14.589 100 57 110 1 1433236352 19580000 14310992
14:46:27 9428 8192000000 10309024272 10726457344 1383003088 14.443 100 64 122 0 1420171008 22584224 14583696
14:47:43 12083 8192000000 10319240336 10740215808 1387981024 14.488 99 57 114 1 1425147232 23548928 13617280
14:48:59 7436 8192000000 10328809952 10748981248 1400917696 14.603 100 51 103 0 1437061744 23351792 12792256
14:50:08 6265 8192000000 10332112880 10752159744 1399958944 14.595 100 56 111 0 1442221344 28779568 13482832
14:51:18 7012 8192000000 10342136144 10762293248 1399259728 14.588 100 70 139 1 1452497968 36316672 16921568
14:52:28 3710 8192000000 10361884160 10770145280 1396320816 14.562 99 72 143 0 1451869456 38638672 16909968
14:53:36 1535 8192000000 10359055392 10764345344 1399781120 14.593 100 82 163 0 1461434880 41949952 19703808
14:54:40 3567 8192000000 10359570272 10769534976 1396547840 14.564 100 71 140 1 1456725552 42985504 17192208
14:55:51 2383 8192000000 10372985840 10783895552 1409223232 14.677 100 72 140 3 1472840704 44935328 18682144
14:56:58 4546 8192000000 10384436560 10798088192 1415401904 14.732 100 71 142 1 1484128560 50368256 18358400
14:58:05 3483 8192000000 10372413888 10793377792 1414048608 14.720 99 52 104 1 1465533424 37554496 13930320
14:59:22 5736 8192000000 10349045824 10767876096 1389101200 14.498 99 61 122 1 1449188816 43164000 16923616
15:00:36 11984 8192000000 10354043632 10782138368 1407566928 14.662 100 35 70 1 1458803312 41194544 10041840
15:01:52 9528 8192000000 10362343632 10791903232 1406961520 14.657 100 40 80 1 1468450960 49762128 11727312
15:03:14 35007 8192000000 10332260720 10775539712 1382909216 14.443 99 29 58 1 1445191888 53223344 9059328
15:05:18 30786 8192000000 10302765488 10755010560 1372150896 14.346 99 15 29 1 1402768784 26597920 4019968
15:06:51 94009 8192000000 10293487984 10743525376 1349695856 14.145 97 10 18 1 1367798064 16166368 1935840
15:09:33 107207 8192000000 10275455760 10728951808 1352358496 14.169 98 25 50 1 1370752944 12337840 6056608
15:12:28 108238 8192000000 10273439264 10728452096 1312300832 13.807 95 18 34 0 1326998560 11023424 3674304
15:15:26 27094 8192000000 10288553184 10729619456 1373779920 14.361 100 33 66 0 1396693920 15041808 7872192
15:16:59 19287 8192000000 10302242352 10732093440 1356868224 14.209 98 45 90 1 1389185088 21735920 10580944
15:18:23 69354 8192000000 10289094512 10735079424 1365366384 14.286 99 11 22 0 1385724704 17756832 2601488
15:20:32 53333 8192000000 10296367632 10748616704 1385446048 14.465 100 15 30 0 1408663312 19718144 3499120
15:24:16 80407 8192000000 10284973184 10750312448 1393578752 14.538 100 16 30 2 1408616912 11545840 3492320
15:26:37 37127 8192000000 10279537904 10743455744 1380537696 14.421 99 16 30 4 1390477152 6828288 3111168
15:28:44 63681 8192000000 10290273232 10755538944 1381471296 14.430 99 11 22 0 1394580944 10603456 2506192
15:30:49 50954 8192000000 10306058416 10761072640 1403883392 14.630 100 12 24 0 1418464016 11836496 2744128
15:33:01 49185 8192000000 10308992896 10759163904 1388418752 14.492 99 19 38 0 1405371248 12488640 4463856
15:34:51 73126 8192000000 10290175920 10741358592 1364335136 14.276 98 18 36 0 1381433520 13265904 3832480
15:37:08 85574 8192000000 10289542688 10736676864 1325154160 13.923 95 16 32 2 1345446032 16570720 3721152
15:39:36 126389 8192000000 10264092720 10700554240 1294665104 13.647 93 14 28 2 1309516672 11768592 3082976
15:42:43 66200 8192000000 10282030800 10732654592 1349710048 14.145 98 35 70 0 1369301008 11348784 8242176
15:44:50 48816 8192000000 10262000016 10723962880 1332051040 13.986 97 5 10 0 1344968368 11895216 1022112
15:46:55 67014 8192000000 10287702992 10739814400 1380518480 14.421 100 16 32 0 1395912576 11452400 3941696
15:49:05 43000 8192000000 10305491568 10759196672 1397903056 14.576 100 20 40 1 1414743584 12395024 4445504
15:51:02 76240 8192000000 10294418144 10750394368 1375079856 14.373 98 22 44 1 1391891328 12058464 4753008
15:53:20 59349 8192000000 10280777072 10735017984 1344228352 14.096 97 23 46 0 1360051904 10809136 5014416
15:55:19 82560 8192000000 10280676864 10727063552 1297530368 13.673 94 16 32 0 1311633216 10593616 3509232
15:57:45 82604 8192000000 10284978720 10719264768 1293529680 13.636 94 18 35 0 1307331072 10361488 3439904
16:00:09 109656 8192000000 10269507728 10710007808 1342346128 14.079 98 24 48 1 1355482400 7619472 5516800
16:03:00 10982 8192000000 10282664032 10730131456 1371789920 14.343 100 32 64 0 1388861184 9848944 7222320
16:04:11 48813 8192000000 10258781792 10713792512 1354266128 14.186 99 23 46 0 1369918288 10764608 4887552
16:06:11 63925 8192000000 10269573120 10715705344 1343782688 14.092 98 10 20 0 1355344272 9357200 2204384
16:08:25 59964 8192000000 10271645888 10723188736 1363488000 14.269 99 15 30 0 1379284688 11676400 4120288
16:10:26 55021 8192000000 10279730576 10726825984 1356087232 14.202 98 19 38 0 1368112560 7779904 4245424
16:12:34 59196 8192000000 10296469168 10742472704 1357397440 14.214 98 24 48 2 1376608896 13894752 5316704
16:14:33 31757 8192000000 10278046400 10728677376 1369722816 14.325 99 31 62 0 1390860896 13896480 7241600
16:16:15 68104 8192000000 10279222816 10728644608 1362513280 14.260 99 28 56 0 1378621168 10063328 6044560
16:18:28 99243 8192000000 10268544048 10724745216 1293644384 13.637 94 23 46 1 1313087552 14258384 5184784
16:21:29 19608 8192000000 10290014944 10724540416 1363861168 14.272 100 36 72 0 1382733568 11125184 7747216
16:22:52 35122 8192000000 10290694016 10735091712 1356643408 14.207 99 36 72 0 1376947088 12654400 7649280
16:24:30 127017 8192000000 10278557040 10740166656 1319009376 13.868 96 25 50 1 1340478720 15448928 6020416
16:27:38 66871 8192000000 10235289216 10691043328 1314474928 13.827 97 12 24 1 1325019760 7762544 2782288
16:30:25 47882 8192000000 10239785952 10690584576 1307938448 13.767 96 22 44 0 1321008352 7906288 5163616
16:32:40 20703 8192000000 10257202272 10705752064 1344426128 14.097 99 10 20 1 1354692768 7812512 2454128
16:34:01 35556 8192000000 10264524800 10719387648 1349287680 14.141 98 23 46 0 1365920912 11037520 5595712
16:35:38 53911 8192000000 10287100816 10741755904 1362182704 14.257 99 21 42 1 1379479536 11560368 5736464
16:37:32 29487 8192000000 9063792448 9290039296 227020048 2.696 17 2 3 0 231733040 4303568 409424

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 17-10-2021 16:39:11 to 17-10-2021 16:39:26.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 106 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 17-10-2021 16:39:27 to 17-10-2021 16:39:29.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          240462
MON$OLDEST_ACTIVE               240463
MON$OLDEST_SNAPSHOT             240463
MON$NEXT_TRANSACTION            240463
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-03 16:41:55.3470
MON$PAGES                       2024640
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33520 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33520 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33520 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33520 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:490: 
:491: mscs-test-161.budzdorov.ru    Sun Oct 17 16:38:04 2021
:492:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:493: 
--- end of diff output ---

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

17.10.21 16:39:30. There are 3 logs in /var/tmp/logs-oltp30 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-10-17 16:39:32 No severe PSQL-related problems occured
17.10.21 16:39:33. End of report.