Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.06.2021 07: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 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 16534716 kB
MemAvailable 31342436 kB
Buffers 0 kB
Cached 15087360 kB
SwapCached 2416 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.1
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-06-22 07:27:01
DTS_END                         2021-06-22 07:27:01.0330
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_FROM_CUSTOMER(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/57052
TRN_ID                          232643
ATT_ID                          651
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: 9105.54 24753 1092665
customer order: creation 170.56 386 20467
customer order: refuse 14.24 389 1709
order to supplier: creation 45.38 1359 5445
order to supplier: removal 3.48 123216 418
invoice (draft): creation 46.48 3675 5578
invoice (draft): removal 52.28 949 6273
invoice accept: apply 40.73 17237 4888
invoice accept: cancel 47.41 1011 5689
customer reserve: creation 3791.35 165 454962
customer reserve: removal 4602.50 74 552300
realization accept: apply 84.35 169 10122
realization accept: cancel 77.58 128 9310
payment from customer: creation 59.16 93 7099
payment from customer: removal 5.63 85 676
payment to supplier: creation 50.18 116 6021
payment to supplier: removal 3.50 70 420
service: total inventory turnovers 3.78 3776 453
service: total monetary turnovers 6.06 608 727
service: aggregate perf. data 0.86 18360 103
service: refresh index statistics 0.04 323191 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-06-2021 07:28:06 to 22-06-2021 07:28:08.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4206
TEST_TIME 2 4183
TEST_TIME 3 4670
TEST_TIME 4 4617
TEST_TIME 5 5177
TEST_TIME 6 5319
TEST_TIME 7 5396
TEST_TIME 8 5676
TEST_TIME 9 6077
TEST_TIME 10 6065
TEST_TIME 11 6128
TEST_TIME 12 6266
TEST_TIME 13 6761
TEST_TIME 14 7123
TEST_TIME 15 7299
TEST_TIME 16 7673
TEST_TIME 17 7701
TEST_TIME 18 8061
TEST_TIME 19 8589
TEST_TIME 20 8678
TEST_TIME 21 8810
TEST_TIME 22 8938
TEST_TIME 23 9093
TEST_TIME 24 8931
TEST_TIME 25 9117
TEST_TIME 26 9621
TEST_TIME 27 9602
TEST_TIME 28 10797
TEST_TIME 29 11679
TEST_TIME 30 11814
TEST_TIME 31 11629
TEST_TIME 32 11569
TEST_TIME 33 11406
TEST_TIME 34 11306
TEST_TIME 35 11209
TEST_TIME 36 10950
TEST_TIME 37 10980
TEST_TIME 38 10883
TEST_TIME 39 10779
TEST_TIME 40 10708
TEST_TIME 41 10574
TEST_TIME 42 10523
TEST_TIME 43 10410
TEST_TIME 44 10348
TEST_TIME 45 10317
TEST_TIME 46 10479
TEST_TIME 47 10309
TEST_TIME 48 10189
TEST_TIME 49 10139
TEST_TIME 50 10077
TEST_TIME 51 9941
TEST_TIME 52 9897
TEST_TIME 53 9894
TEST_TIME 54 9891
TEST_TIME 55 9820
TEST_TIME 56 9899
TEST_TIME 57 9873
TEST_TIME 58 9837
TEST_TIME 59 9915
TEST_TIME 60 9894
TEST_TIME 61 9891
TEST_TIME 62 9988
TEST_TIME 63 10092
TEST_TIME 64 10059
TEST_TIME 65 10034
TEST_TIME 66 10090
TEST_TIME 67 9985
TEST_TIME 68 9857
TEST_TIME 69 9842
TEST_TIME 70 9794
TEST_TIME 71 9709
TEST_TIME 72 9648
TEST_TIME 73 9632
TEST_TIME 74 9526
TEST_TIME 75 9475
TEST_TIME 76 9425
TEST_TIME 77 9431
TEST_TIME 78 9417
TEST_TIME 79 9446
TEST_TIME 80 9464
TEST_TIME 81 9409
TEST_TIME 82 9466
TEST_TIME 83 9452
TEST_TIME 84 9499
TEST_TIME 85 9457
TEST_TIME 86 9567
TEST_TIME 87 9531
TEST_TIME 88 9434
TEST_TIME 89 9430
TEST_TIME 90 9393
TEST_TIME 91 9372
TEST_TIME 92 9392
TEST_TIME 93 9367
TEST_TIME 94 9374
TEST_TIME 95 9345
TEST_TIME 96 9326
TEST_TIME 97 9296
TEST_TIME 98 9267
TEST_TIME 99 9393
TEST_TIME 100 9378
TEST_TIME 101 9371
TEST_TIME 102 9398
TEST_TIME 103 9383
TEST_TIME 104 9394
TEST_TIME 105 9474
TEST_TIME 106 9461
TEST_TIME 107 9485
TEST_TIME 108 9476
TEST_TIME 109 9406
TEST_TIME 110 9354
TEST_TIME 111 9325
TEST_TIME 112 9279
TEST_TIME 113 9229
TEST_TIME 114 9206
TEST_TIME 115 9214
TEST_TIME 116 9186
TEST_TIME 117 9174
TEST_TIME 118 9175
TEST_TIME 119 9170
TEST_TIME 120 9113

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 22-06-2021 07:28:10 to 22-06-2021 07:28:13.

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 1513077 1513077 0 0.00 0 19610 6 0 0
doc_list_biud 1513077 1513077 0 0.00 0 47550 34 0 0
sp_add_invoice_to_stock 4975 4888 87 1.74 26 151336 17238 0 87
sp_cancel_adding_invoice 5689 5689 0 0.00 5 62902 1011 0 0
sp_cancel_client_order 1709 1709 0 0.00 4 12312 389 0 0
sp_cancel_customer_reserve 552300 552300 0 0.00 1 23235 75 0 0
sp_cancel_pay_from_customer 676 676 0 0.00 3 3565 85 0 0
sp_cancel_pay_to_supplier 420 420 0 0.00 3 2419 71 0 0
sp_cancel_supplier_invoice 6275 6273 2 0.03 2 21452 949 0 2
sp_cancel_supplier_order 418 418 0 0.00 9 1432360 123216 0 0
sp_cancel_write_off 9310 9310 0 0.00 1 22357 128 0 0
sp_client_order 20467 20467 0 0.00 4 22986 387 0 0
sp_customer_reserve 454962 454962 0 0.00 0 50785 166 0 0
sp_fill_shopping_cart 31474 31474 0 0.00 1 15256 42 0 0
sp_fill_shopping_cart_clo_res 454952 454952 0 0.00 0 23403 16 0 0
sp_get_clo_for_invoice 4878 4878 0 0.00 0 35998 364 0 0
sp_kill_cost_storno 16095 16095 0 0.00 0 6285 65 0 0
sp_kill_qstorno_ret_qs2qd 1120601 1120601 0 0.00 0 22312 40 0 0
sp_kill_qty_storno 590699 590699 0 0.00 0 47549 86 0 0
sp_lock_selected_doc 20731 20731 0 0.00 0 9521 5 0 0
sp_make_cost_storno 28120 28120 0 0.00 0 10172 50 0 0
sp_multiply_rows_for_pdistr 28120 28120 0 0.00 0 7474 29 0 0
sp_multiply_rows_for_qdistr 20467 20467 0 0.00 0 10435 212 0 0
sp_pay_from_customer 7099 7099 0 0.00 4 11442 94 0 0
sp_pay_to_supplier 6021 6021 0 0.00 5 12875 116 0 0
sp_payment_common 13120 13120 0 0.00 1 9291 17 0 0
sp_qd_handle_on_cancel_clo 1709 1709 0 0.00 0 12304 366 0 0
sp_qd_handle_on_invoice_upd_sts 10567 10567 0 0.00 0 47549 434 0 0
sp_qd_handle_on_reserve_upd_sts 10122 10122 0 0.00 0 8850 53 0 0
sp_reserve_write_off 10122 10122 0 0.00 6 15892 169 0 0
sp_supplier_invoice 5578 5578 0 0.00 8 134514 3676 0 0
sp_supplier_order 5445 5445 0 0.00 6 31765 1359 0 0
srv_aggregate_perf_data 103 103 0 0.00 1931 168256 18361 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 835 108278 43689 0 0
srv_find_qd_qs_mism 1044785 1044785 0 0.00 0 19610 5 0 0
srv_make_invnt_saldo 453 453 0 0.00 32 121530 3777 0 0
srv_make_money_saldo 727 727 0 0.00 1 18270 609 0 0
srv_recalc_idx_stat 5 5 0 0.00 237762 541806 323192 0 0
t$perf-abend:sp_add_invoice_to_stock 87 87 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 2 2 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4888 4888 0 0.00 0 13754 184 0 0
t$perf-norm:sp_cancel_adding_invoice 469 469 0 0.00 1 1907 70 0 0
t$perf-norm:sp_cancel_client_order 1709 1709 0 0.00 0 2625 13 0 0
t$perf-norm:sp_cancel_customer_reserve 650 650 0 0.00 0 920 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 676 676 0 0.00 0 2988 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 420 420 0 0.00 0 3223 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 384 384 0 0.00 0 1089 17 0 0
t$perf-norm:sp_cancel_supplier_order 418 418 0 0.00 0 18360 1695 0 0
t$perf-norm:sp_cancel_write_off 1008 1008 0 0.00 0 2851 8 0 0
t$perf-norm:sp_client_order 20468 20468 0 0.00 0 10357 20 0 0
t$perf-norm:sp_customer_reserve 1002 1002 0 0.00 0 1837 5 0 0
t$perf-norm:sp_pay_from_customer 7099 7099 0 0.00 0 4616 9 0 0
t$perf-norm:sp_pay_to_supplier 6021 6021 0 0.00 0 5513 12 0 0
t$perf-norm:sp_reserve_write_off 10122 10122 0 0.00 0 6292 12 0 0
t$perf-norm:sp_supplier_invoice 5578 5578 0 0.00 0 11780 15 0 0
t$perf-norm:sp_supplier_order 5445 5445 0 0.00 0 11798 16 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 156 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 158 3 0 0
t$perf-norm:srv_make_invnt_saldo 453 453 0 0.00 0 3527 31 0 0
t$perf-norm:srv_make_money_saldo 727 727 0 0.00 0 1890 9 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 15 5 0 0
v_add_invoice_to_stock 9924 9750 174 1.75 0 6878 14 0 174
v_all_customers 37408 37408 0 0.00 0 26 1 0 0
v_all_suppliers 31022 31022 0 0.00 0 17 1 0 0
v_all_wares 40934 40934 0 0.00 0 107 1 0 0
v_cancel_adding_invoice 938 938 0 0.00 0 401 6 0 0
v_cancel_client_order 3418 3418 0 0.00 0 4045 4 0 0
v_cancel_customer_prepayment 1352 1352 0 0.00 0 1604 5 0 0
v_cancel_customer_reserve 1300 1300 0 0.00 0 3528 6 0 0
v_cancel_payment_to_supplier 840 840 0 0.00 0 2413 7 0 0
v_cancel_supplier_invoice 772 768 4 0.51 0 3618 15 0 4
v_cancel_supplier_order 836 836 0 0.00 0 1265 5 0 0
v_cancel_write_off 2016 2016 0 0.00 0 5294 17 0 0
v_max_id_clo_ord 5445 5445 0 0.00 0 553 1 0 0
v_max_id_clo_res 1002 1002 0 0.00 0 6160 21 0 0
v_max_id_ord_sup 5562 5562 0 0.00 0 3170 5 0 0
v_max_non_paid_invoice 1517 1517 0 0.00 0 96 2 0 0
v_max_non_paid_realizn 3715 3715 0 0.00 0 3862 4 0 0
v_min_id_clo_ord 5445 5445 0 0.00 0 5364 4 0 0
v_min_id_clo_res 1002 1002 0 0.00 0 676 3 0 0
v_min_id_ord_sup 5562 5562 0 0.00 0 8974 7 0 0
v_min_non_paid_invoice 6021 6021 0 0.00 0 3539 8 0 0
v_min_non_paid_realizn 7099 7099 0 0.00 0 3672 10 0 0
v_our_firm 10294 10294 0 0.00 0 26 1 0 0
v_reserve_write_off 20244 20244 0 0.00 0 7541 5 0 0
x_lock_depdocs_on_canc_invoice 11578 11578 0 0.00 0 22596 117 0 0
x_lock_depdocs_on_canc_sup_ord 418 418 0 0.00 0 4913 378 0 0
x_make_qstorno_1000_1200 5445 5445 0 0.00 2 31742 1277 0 0
x_make_qstorno_1200_2000 5578 5578 0 0.00 4 125497 3517 0 0
x_make_qstorno_2100_3300 454962 454962 0 0.00 0 46924 147 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-06-2021 07:28:14 to 22-06-2021 07:28:18.

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
04:57:04 4 8192000000 8798722336 8810123264 4726720 0.057 2 1 2 0 4826144 63648 35776
04:58:05 26047 8192000000 9734714464 10032160768 808182816 8.979 100 91 181 0 837975616 7650160 22142640
04:59:36 31856 8192000000 9773860176 10085199872 849944112 9.400 100 87 174 0 882383408 12182048 20257248
05:01:10 25920 8192000000 9818796736 10140995584 882397232 9.724 100 82 164 0 928123296 26982592 18743472
05:02:48 13461 8192000000 9878366096 10213269504 924578512 10.141 100 77 154 0 984774960 43599568 16596880
05:04:05 8463 8192000000 9929930816 10277785600 966626848 10.554 100 76 151 0 1040932992 58610768 15695376
05:05:15 5052 8192000000 9982292832 10336043008 1001481152 10.893 100 77 155 2 1089857184 73031024 15345008
05:06:22 3567 8192000000 10019470112 10384510976 1030847536 11.177 100 82 158 2 1130755024 84723536 15183952
05:07:28 2110 8192000000 10025571968 10397683712 1058210688 11.439 100 82 164 0 1140282848 65746496 16325664
05:08:32 1146 8192000000 10051356224 10421272576 1065873888 11.513 100 93 186 1 1154884368 69800192 19210288
05:09:34 2077 8192000000 10017198992 10390097920 1073978480 11.590 100 88 176 0 1120239776 27123984 19137312
05:10:38 8205 8192000000 9989054656 10389946368 1079184080 11.640 100 42 85 0 1116446704 28082640 9179984
05:11:49 60518 8192000000 10027365984 10435325952 1091272560 11.755 100 35 68 1 1131805136 33632544 6900032
05:13:51 6359 8192000000 10018258736 10421612544 1097968608 11.818 100 50 101 3 1124121792 14822096 11331088
05:14:58 4374 8192000000 10026639568 10426159104 1103112864 11.867 100 43 86 1 1138638848 25111072 10414912
05:16:03 7304 8192000000 10014078672 10407858176 1081692880 11.664 98 23 46 1 1104467568 17989168 4785520
05:17:13 22571 8192000000 10035873104 10421780480 1095815952 11.798 99 80 160 3 1126385584 13040352 17529280
05:18:36 5867 8192000000 10019600416 10424610816 1093411440 11.775 100 39 74 3 1144362640 42018240 8932960
05:19:42 5867 8192000000 9971840400 10364944384 1033566064 11.203 97 40 80 1 1061415104 18612224 9236816
05:20:52 8159 8192000000 9921143360 10306228224 967570912 10.563 94 32 65 0 1004491056 29660576 7259568
05:22:02 34575 8192000000 9943845344 10346569728 1006423376 10.941 98 20 40 0 1047788160 37406896 3957888
05:23:40 7228 8192000000 9949675040 10334367744 1014213760 11.016 98 31 62 2 1036689904 15919792 6556352
05:24:52 4522 8192000000 9974693728 10365472768 1052119472 11.381 100 54 107 4 1084477104 19822080 12535552
05:25:57 5000 8192000000 9970870352 10363715584 1067259520 11.526 100 31 62 1 1087308192 12281760 7766912
05:27:05 83464 8192000000 10010385168 10409971712 1081876976 11.665 100 38 76 0 1112276848 22551456 7848416
05:29:54 18509 8192000000 10113283760 10515046400 1114146304 11.972 100 56 111 0 1217721328 92044752 11530272
05:31:17 6374 8192000000 10126246336 10524508160 1128016176 12.103 100 62 124 1 1236156032 94341328 13798528
05:32:30 14899 8192000000 10153810000 10561712128 1142471936 12.239 100 54 107 3 1261097824 106328176 12297712
05:33:56 20792 8192000000 10195089120 10604449792 1152585504 12.334 100 58 116 1 1296491072 130973072 12932496
05:35:25 8440 8192000000 10252343984 10666283008 1156434336 12.370 100 57 113 1 1357284640 188037632 12812672
05:36:40 7627 8192000000 10255883008 10679103488 1168727248 12.485 100 61 120 1 1363941168 181878752 13335168
05:37:53 3571 8192000000 10299421056 10715394048 1178669168 12.578 100 65 129 0 1402367344 209537472 14160704
05:39:00 2041 8192000000 10363650112 10773307392 1178409984 12.575 100 91 181 2 1466158320 266717728 21030608
05:40:05 3159 8192000000 10379847168 10807693312 1189592992 12.680 100 88 172 1 1482446800 273510480 19343328
05:41:14 6912 8192000000 10352126576 10766708736 1172459680 12.520 99 72 141 1 1447216512 259105168 15651664
05:42:27 3766 8192000000 10413572160 10831925248 1197873344 12.757 100 83 163 2 1516067264 300304656 17889264
05:43:34 10101 8192000000 10410876640 10840481792 1171054864 12.507 100 49 96 1 1509731936 327402992 11274080
05:44:52 3113 8192000000 10437112240 10844102656 1158591104 12.390 99 85 169 0 1534548560 356672624 19284832
05:46:01 833 8192000000 10424131856 10831417344 1157237472 12.377 100 85 168 2 1521132032 344765296 19129264
05:47:04 8895 8192000000 10361719312 10803441664 1158982320 12.394 100 48 95 2 1472578640 302141344 11454976
05:48:21 7677 8192000000 10402407920 10831503360 1136396912 12.182 100 65 130 2 1498570352 346981760 15191680
05:49:37 6648 8192000000 10390867840 10815627264 1137110512 12.188 99 39 76 1 1499929472 352698672 10120288
05:51:01 6735 8192000000 10424828048 10847899648 1143977008 12.253 100 47 93 2 1536336880 380458784 11901088
05:52:16 10143 8192000000 10471912048 10919477248 1133435232 12.154 100 35 70 1 1584980656 442219568 9325856
05:53:42 22514 8192000000 10439010672 10924613632 1113589072 11.966 100 20 40 3 1537245344 417690688 5965584
05:55:27 17521 8192000000 10295767024 10844831744 1102100928 11.858 99 27 54 0 1444561568 335251424 7209216
05:56:58 77378 8192000000 10110043024 10590134272 1110189232 11.934 98 7 13 0 1161360016 50125392 1045392
05:59:55 40473 8192000000 10125374656 10556944384 1141386384 12.229 100 35 66 2 1209053632 59598016 8069232
06:01:37 106185 8192000000 10101623024 10579251200 1137486928 12.192 100 26 51 0 1212784512 69402752 5894832
06:04:25 29103 8192000000 10065102304 10504933376 1108733568 11.920 97 30 60 1 1158411168 43966624 5710976
06:05:56 26141 8192000000 10115818784 10546696192 1130291008 12.124 99 29 58 0 1191103392 54992160 5820224
06:07:28 31730 8192000000 10111047440 10588372992 1150444864 12.314 100 17 35 1 1216333648 61817376 4071408
06:09:01 64956 8192000000 10160074768 10628898816 1143499744 12.248 99 16 33 0 1240221936 92691328 4030864
06:11:11 45092 8192000000 10174776496 10638671872 1148980448 12.300 100 22 44 0 1270570928 116624896 4965584
06:13:16 67813 8192000000 10081641728 10574925824 1146958816 12.281 100 13 26 0 1210269184 60178496 3131872
06:16:41 41831 8192000000 10096397264 10586710016 1157087632 12.376 100 30 59 0 1219810048 56580112 6142304
06:18:28 98362 8192000000 10133523824 10633220096 1136860944 12.186 98 18 36 0 1199814576 59372016 3581616
06:21:07 34331 8192000000 10086051184 10537205760 1148088736 12.292 99 34 70 0 1178739776 23063616 7587424
06:22:46 60028 8192000000 10091340064 10591268864 1115714880 11.986 97 35 70 0 1176609344 53560224 7334240
06:24:48 60869 8192000000 10119510624 10588274688 1123366048 12.059 97 22 42 1 1197047840 68995456 4686336
06:27:31 96204 8192000000 10153770704 10642792448 1131568448 12.136 98 19 38 2 1223735968 87713968 4453552
06:30:27 63525 8192000000 10067029456 10521939968 1083420512 11.680 94 11 22 0 1109937888 23993728 2523648
06:32:38 72231 8192000000 10104130272 10585608192 1092292448 11.764 95 21 42 1 1164841232 68067760 4481024
06:34:51 108278 8192000000 10077556416 10533359616 1108965952 11.923 98 25 49 0 1184758208 70626608 5165648
06:37:40 71453 8192000000 10047206032 10502180864 1086597088 11.710 96 13 24 0 1118459328 29759808 2102432
06:39:51 72328 8192000000 10020581760 10511224832 1029481280 11.163 93 11 22 0 1077075616 44990656 2603680
06:42:12 40433 8192000000 10008070160 10464702464 1064760912 11.502 97 13 26 3 1093021184 24709360 3550912
06:43:53 52611 8192000000 10055960224 10514870272 1091158800 11.754 98 16 32 0 1145245088 50832448 3253840
06:45:46 76780 8192000000 10091017904 10560192512 1093384720 11.775 98 22 44 0 1168031616 69284560 5362336
06:48:06 62017 8192000000 10132459712 10580127744 1129188880 12.114 100 21 42 0 1205068672 71170016 4709776
06:50:11 54405 8192000000 10104902512 10563145728 1102928784 11.865 98 34 68 3 1189614672 79326176 7359712
06:52:06 41841 8192000000 10128587936 10569388032 1128111376 12.104 100 30 60 1 1215874272 81753472 6009424
06:53:48 36947 8192000000 10095027296 10559533056 1104216720 11.878 97 36 72 2 1167240656 55783472 7240464
06:56:43 88196 8192000000 10104443648 10580647936 1126302352 12.086 99 12 24 0 1193116736 64291536 2522848
06:59:13 83293 8192000000 10069052336 10547130368 1103169840 11.868 97 7 14 0 1155771168 51470752 1130576
07:01:41 77780 8192000000 10094562016 10549321728 1148780080 12.298 100 16 31 0 1171215168 19000832 3434256
07:04:08 59395 8192000000 10142532384 10582355968 1153745872 12.345 99 21 42 0 1222450112 64410544 4293696
07:06:21 71481 8192000000 10101621392 10551627776 1111925136 11.951 96 16 32 1 1165158192 50073504 3159552
07:08:33 22578 8192000000 10139819424 10597629952 1153991616 12.347 100 33 66 0 1213422384 52561136 6869632
07:09:56 23904 8192000000 10106106128 10551914496 1150450592 12.314 100 35 71 3 1203361504 45692944 7217968
07:11:22 29599 8192000000 10116578240 10554425344 1164714960 12.447 100 14 28 0 1230680976 62205424 3760592
07:12:57 88075 8192000000 10129819680 10604109824 1145838256 12.270 99 23 46 3 1179200176 28141072 5220848
07:15:26 42057 8192000000 10082490016 10532745216 1121772672 12.044 97 20 40 0 1163024400 37094176 4157552
07:17:08 68315 8192000000 10075886960 10536845312 1123622096 12.061 98 23 46 0 1157668192 29624080 4422016
07:19:38 79756 8192000000 10100026320 10548424704 1141501760 12.230 100 10 20 3 1181358480 37858720 1998000
07:22:04 50235 8192000000 10081813248 10566815744 1119245648 12.020 98 13 26 1 1188913056 66608512 3058896
07:23:54 36371 8192000000 10063237328 10507333632 1104461120 11.880 98 24 48 0 1154317072 44313056 5542896
07:25:38 67892 8192000000 10104183008 10549317632 1093944496 11.780 96 20 40 0 1150485856 52060704 4480656

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 22-06-2021 07:28:20 to 22-06-2021 07:28:35.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 87 v_add_invoice_to_stock 335544517
except 2 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-06-2021 07:28:36 to 22-06-2021 07:28:38.

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          227678
MON$OLDEST_ACTIVE               232782
MON$OLDEST_SNAPSHOT             232782
MON$NEXT_TRANSACTION            232782
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                       2111872
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             792
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2519 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2519 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2519 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2519 Firebird 4.0/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 ---
:170: 
:171: mscs-test-161.budzdorov.ru    Tue Jun 22 07:27:10 2021
:172:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:173: 
:174: 
:175: mscs-test-161.budzdorov.ru    Tue Jun 22 07:27:10 2021
:176:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:177: 
--- end of diff output ---

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

22.06.21 07:28:39. 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-06-22 07:28:41 No severe PSQL-related problems occured
22.06.21 07:28:43. End of report.