Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.06.2021 04: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 16396044 kB
MemAvailable 31368488 kB
Buffers 20 kB
Cached 15170836 kB
SwapCached 4 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-12 04:26:59
DTS_END                         2021-06-12 04:26:59.7090
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/38900
TRN_ID                          234302
ATT_ID                          596
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: 9263.27 19984 1111592
customer order: creation 171.47 417 20576
customer order: refuse 14.41 338 1729
order to supplier: creation 45.85 1525 5502
order to supplier: removal 3.37 118510 404
invoice (draft): creation 45.23 3819 5428
invoice (draft): removal 51.19 995 6143
invoice accept: apply 41.95 16878 5034
invoice accept: cancel 46.58 878 5589
customer reserve: creation 3879.66 163 465559
customer reserve: removal 4671.23 67 560548
realization accept: apply 82.42 202 9890
realization accept: cancel 83.13 124 9976
payment from customer: creation 58.43 120 7012
payment from customer: removal 5.64 108 677
payment to supplier: creation 48.48 137 5818
payment to supplier: removal 3.45 68 414
service: total inventory turnovers 3.63 3828 435
service: total monetary turnovers 6.10 581 732
service: aggregate perf. data 1.02 13359 122
service: refresh index statistics 0.03 237561 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-06-2021 04:27:46 to 12-06-2021 04:27:48.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2918
TEST_TIME 2 4214
TEST_TIME 3 4502
TEST_TIME 4 4317
TEST_TIME 5 4361
TEST_TIME 6 4748
TEST_TIME 7 4934
TEST_TIME 8 5305
TEST_TIME 9 5375
TEST_TIME 10 5604
TEST_TIME 11 5660
TEST_TIME 12 6344
TEST_TIME 13 6571
TEST_TIME 14 6691
TEST_TIME 15 6788
TEST_TIME 16 6886
TEST_TIME 17 7394
TEST_TIME 18 7258
TEST_TIME 19 7890
TEST_TIME 20 8447
TEST_TIME 21 8442
TEST_TIME 22 8535
TEST_TIME 23 9164
TEST_TIME 24 9174
TEST_TIME 25 9119
TEST_TIME 26 9758
TEST_TIME 27 9975
TEST_TIME 28 10404
TEST_TIME 29 10821
TEST_TIME 30 10673
TEST_TIME 31 10548
TEST_TIME 32 10424
TEST_TIME 33 10355
TEST_TIME 34 10373
TEST_TIME 35 10280
TEST_TIME 36 10415
TEST_TIME 37 10532
TEST_TIME 38 10499
TEST_TIME 39 10580
TEST_TIME 40 10601
TEST_TIME 41 10456
TEST_TIME 42 10352
TEST_TIME 43 10278
TEST_TIME 44 10107
TEST_TIME 45 10087
TEST_TIME 46 9997
TEST_TIME 47 9952
TEST_TIME 48 9882
TEST_TIME 49 9789
TEST_TIME 50 9649
TEST_TIME 51 9509
TEST_TIME 52 9400
TEST_TIME 53 9331
TEST_TIME 54 9430
TEST_TIME 55 9541
TEST_TIME 56 9667
TEST_TIME 57 9742
TEST_TIME 58 9791
TEST_TIME 59 9714
TEST_TIME 60 9814
TEST_TIME 61 9765
TEST_TIME 62 9801
TEST_TIME 63 9772
TEST_TIME 64 9851
TEST_TIME 65 9882
TEST_TIME 66 9854
TEST_TIME 67 9985
TEST_TIME 68 9875
TEST_TIME 69 9751
TEST_TIME 70 9683
TEST_TIME 71 9594
TEST_TIME 72 9587
TEST_TIME 73 9517
TEST_TIME 74 9433
TEST_TIME 75 9403
TEST_TIME 76 9405
TEST_TIME 77 9412
TEST_TIME 78 9415
TEST_TIME 79 9423
TEST_TIME 80 9457
TEST_TIME 81 9409
TEST_TIME 82 9406
TEST_TIME 83 9442
TEST_TIME 84 9453
TEST_TIME 85 9552
TEST_TIME 86 9695
TEST_TIME 87 9713
TEST_TIME 88 9618
TEST_TIME 89 9539
TEST_TIME 90 9494
TEST_TIME 91 9466
TEST_TIME 92 9431
TEST_TIME 93 9382
TEST_TIME 94 9346
TEST_TIME 95 9385
TEST_TIME 96 9349
TEST_TIME 97 9357
TEST_TIME 98 9340
TEST_TIME 99 9313
TEST_TIME 100 9395
TEST_TIME 101 9481
TEST_TIME 102 9471
TEST_TIME 103 9499
TEST_TIME 104 9513
TEST_TIME 105 9520
TEST_TIME 106 9543
TEST_TIME 107 9534
TEST_TIME 108 9448
TEST_TIME 109 9410
TEST_TIME 110 9355
TEST_TIME 111 9317
TEST_TIME 112 9273
TEST_TIME 113 9280
TEST_TIME 114 9266
TEST_TIME 115 9264
TEST_TIME 116 9287
TEST_TIME 117 9262
TEST_TIME 118 9266
TEST_TIME 119 9273
TEST_TIME 120 9265
TEST_TIME 121 9189

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-06-2021 04:27:50 to 12-06-2021 04:27:53.

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 1542456 1542456 0 0.00 0 23092 6 0 0
doc_list_biud 1542455 1542455 0 0.00 0 76699 31 0 0
sp_add_invoice_to_stock 5161 5034 127 2.46 41 181385 16878 0 127
sp_cancel_adding_invoice 5589 5589 0 0.00 4 66229 879 0 0
sp_cancel_client_order 1729 1729 0 0.00 4 25855 338 0 0
sp_cancel_customer_reserve 560548 560548 0 0.00 1 22754 67 0 0
sp_cancel_pay_from_customer 677 677 0 0.00 2 7958 108 0 0
sp_cancel_pay_to_supplier 414 414 0 0.00 3 4281 68 0 0
sp_cancel_supplier_invoice 6153 6143 10 0.16 2 76707 995 0 10
sp_cancel_supplier_order 404 404 0 0.00 7 914041 118510 0 0
sp_cancel_write_off 9976 9976 0 0.00 1 20281 124 0 0
sp_client_order 20576 20576 0 0.00 4 30676 417 0 0
sp_customer_reserve 465559 465559 0 0.00 0 103638 163 0 0
sp_fill_shopping_cart 31484 31484 0 0.00 1 17071 44 0 0
sp_fill_shopping_cart_clo_res 465554 465554 0 0.00 0 17299 15 0 0
sp_get_clo_for_invoice 5029 5029 0 0.00 0 30576 388 0 0
sp_kill_cost_storno 16656 16656 0 0.00 0 8602 57 0 0
sp_kill_qstorno_ret_qs2qd 1137619 1137619 0 0.00 0 76695 37 0 0
sp_kill_qty_storno 599308 599308 0 0.00 0 76697 80 0 0
sp_lock_selected_doc 20565 20565 0 0.00 0 9292 7 0 0
sp_make_cost_storno 27749 27749 0 0.00 0 21513 60 0 0
sp_multiply_rows_for_pdistr 27749 27749 0 0.00 0 9253 36 0 0
sp_multiply_rows_for_qdistr 20576 20576 0 0.00 0 12123 228 0 0
sp_pay_from_customer 7012 7012 0 0.00 4 13711 120 0 0
sp_pay_to_supplier 5818 5818 0 0.00 5 22627 137 0 0
sp_payment_common 12830 12830 0 0.00 1 7568 22 0 0
sp_qd_handle_on_cancel_clo 1729 1729 0 0.00 0 25848 306 0 0
sp_qd_handle_on_invoice_upd_sts 10618 10618 0 0.00 0 23094 389 0 0
sp_qd_handle_on_reserve_upd_sts 9890 9890 0 0.00 0 25865 64 0 0
sp_reserve_write_off 9890 9890 0 0.00 6 25934 202 0 0
sp_supplier_invoice 5428 5428 0 0.00 6 118432 3819 0 0
sp_supplier_order 5502 5502 0 0.00 5 90767 1525 0 0
srv_aggregate_perf_data 122 122 0 0.00 2616 170722 13359 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1179 159874 45289 0 0
srv_find_qd_qs_mism 1063933 1063933 0 0.00 0 14633 5 0 0
srv_make_invnt_saldo 435 435 0 0.00 66 107363 3829 0 0
srv_make_money_saldo 732 732 0 0.00 1 22752 581 0 0
srv_recalc_idx_stat 4 4 0 0.00 140224 397980 237562 0 0
t$perf-abend:sp_add_invoice_to_stock 127 127 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5034 5034 0 0.00 0 10284 169 0 0
t$perf-norm:sp_cancel_adding_invoice 409 409 0 0.00 1 3827 80 0 0
t$perf-norm:sp_cancel_client_order 1729 1729 0 0.00 0 7548 15 0 0
t$perf-norm:sp_cancel_customer_reserve 653 653 0 0.00 0 4499 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 677 677 0 0.00 0 2261 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 414 414 0 0.00 0 584 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 381 381 0 0.00 0 1593 14 0 0
t$perf-norm:sp_cancel_supplier_order 404 404 0 0.00 0 21888 1574 0 0
t$perf-norm:sp_cancel_write_off 979 979 0 0.00 0 9039 18 0 0
t$perf-norm:sp_client_order 20576 20576 0 0.00 0 14558 21 0 0
t$perf-norm:sp_customer_reserve 976 976 0 0.00 0 1469 4 0 0
t$perf-norm:sp_pay_from_customer 7012 7012 0 0.00 0 12825 13 0 0
t$perf-norm:sp_pay_to_supplier 5818 5818 0 0.00 0 6789 10 0 0
t$perf-norm:sp_reserve_write_off 9890 9890 0 0.00 0 7785 12 0 0
t$perf-norm:sp_supplier_invoice 5428 5428 0 0.00 0 15767 21 0 0
t$perf-norm:sp_supplier_order 5502 5502 0 0.00 0 12536 20 0 0
t$perf-norm:srv_aggregate_perf_data 122 122 0 0.00 0 482 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 602 26 0 0
t$perf-norm:srv_make_invnt_saldo 435 435 0 0.00 0 1707 11 0 0
t$perf-norm:srv_make_money_saldo 732 732 0 0.00 0 2145 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 469 120 0 0
v_add_invoice_to_stock 10312 10058 254 2.46 0 10171 20 0 254
v_all_customers 37574 37574 0 0.00 0 22 1 0 0
v_all_suppliers 30250 30250 0 0.00 0 24 1 0 0
v_all_wares 41152 41152 0 0.00 0 156 1 0 0
v_cancel_adding_invoice 818 818 0 0.00 0 625 6 0 0
v_cancel_client_order 3458 3458 0 0.00 0 5043 6 0 0
v_cancel_customer_prepayment 1354 1354 0 0.00 0 1959 9 0 0
v_cancel_customer_reserve 1306 1306 0 0.00 0 401 3 0 0
v_cancel_payment_to_supplier 828 828 0 0.00 0 1473 7 0 0
v_cancel_supplier_invoice 782 762 20 2.55 0 1567 17 0 20
v_cancel_supplier_order 808 808 0 0.00 0 487 3 0 0
v_cancel_write_off 1958 1958 0 0.00 0 7266 19 0 0
v_max_id_clo_ord 5494 5494 0 0.00 0 1372 2 0 0
v_max_id_clo_res 976 976 0 0.00 0 6221 26 0 0
v_max_id_ord_sup 5414 5414 0 0.00 0 3961 6 0 0
v_max_non_paid_invoice 1601 1601 0 0.00 0 425 2 0 0
v_max_non_paid_realizn 3700 3700 0 0.00 0 2569 4 0 0
v_min_id_clo_ord 5494 5494 0 0.00 0 6366 8 0 0
v_min_id_clo_res 976 976 0 0.00 0 6318 13 0 0
v_min_id_ord_sup 5414 5414 0 0.00 0 4700 8 0 0
v_min_non_paid_invoice 5818 5818 0 0.00 0 7564 16 0 0
v_min_non_paid_realizn 7012 7012 0 0.00 0 5606 14 0 0
v_our_firm 10202 10202 0 0.00 0 13 1 0 0
v_reserve_write_off 19780 19780 0 0.00 0 10281 8 0 0
x_lock_depdocs_on_canc_invoice 11351 11351 0 0.00 0 15294 118 0 0
x_lock_depdocs_on_canc_sup_ord 404 404 0 0.00 0 12301 360 0 0
x_make_qstorno_1000_1200 5502 5502 0 0.00 2 90758 1445 0 0
x_make_qstorno_1200_2000 5428 5428 0 0.00 3 118369 3646 0 0
x_make_qstorno_2100_3300 465559 465559 0 0.00 0 103636 146 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-06-2021 04:27:54 to 12-06-2021 04:27:58.

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
01:57:03 8 8192000000 8796668768 8807174144 3189024 0.038 1 1 2 0 3286640 61840 35776
01:58:04 18563 8192000000 9699846432 9990770688 779394672 8.687 100 90 180 0 809743136 7564608 22783856
01:59:29 30572 8192000000 9746366960 10048978944 817362064 9.072 100 89 178 1 851228864 12079184 21787616
02:01:08 17105 8192000000 9781760592 10101112832 855339552 9.454 100 77 154 1 891970544 18616400 18014592
02:02:34 13195 8192000000 9861658352 10193940480 918656640 10.083 100 74 148 0 964517952 29198304 16663008
02:03:53 7572 8192000000 9915446480 10263613440 962944496 10.518 100 69 135 1 1025397872 48775760 13677616
02:05:02 6227 8192000000 9965216816 10323234816 993422768 10.815 100 75 150 0 1079801040 71152656 15225616
02:06:13 3391 8192000000 9999032176 10360901632 1026971904 11.139 100 82 164 2 1109673936 65746960 16955072
02:07:18 1908 8192000000 10022970352 10393559040 1053875984 11.398 100 90 181 1 1142921744 69994624 19051136
02:08:21 2490 8192000000 10022161152 10392743936 1071464688 11.566 100 86 172 1 1135875376 47070704 17339984
02:09:24 7883 8192000000 9986665920 10385326080 1072010304 11.571 100 37 72 0 1113555920 33612288 7933328
02:10:33 3973 8192000000 9997879568 10395680768 1077600320 11.625 100 41 82 1 1111513104 25105744 8807040
02:11:38 9081 8192000000 10001940912 10412290048 1085897376 11.704 100 23 46 2 1122741376 32197872 4646128
02:12:48 49847 8192000000 9992013616 10402017280 1086047744 11.705 100 35 68 0 1104014448 9964224 8002480
02:14:39 6265 8192000000 10021842608 10429108224 1096326240 11.803 100 45 91 2 1138959920 32231328 10402352
02:15:49 5651 8192000000 10002104112 10405625856 1092972128 11.771 100 51 101 2 1118607904 15014048 10621728
02:16:58 5673 8192000000 10005896384 10409902080 1100859120 11.846 100 27 53 1 1122503440 15833904 5810416
02:18:08 45287 8192000000 9993641824 10386718720 1036330976 11.229 95 46 93 0 1076512624 30155104 10026544
02:19:54 7335 8192000000 9974012288 10357272576 1009220400 10.968 95 53 101 5 1041099216 21516352 10362464
02:21:06 8615 8192000000 9932125472 10322898944 1007397824 10.950 98 49 98 2 1032038960 13664672 10976464
02:22:16 6742 8192000000 9937963136 10325340160 1007042224 10.947 98 33 67 0 1034152384 19558960 7551200
02:23:28 42698 8192000000 9952809552 10352836608 1045916624 11.321 100 31 63 0 1069934480 16934512 7083344
02:25:11 5103 8192000000 9980791136 10376663040 1059943200 11.456 100 37 74 4 1083692080 14858256 8890624
02:26:19 10855 8192000000 9975225456 10386329600 1069257184 11.545 100 35 69 0 1099882608 23017552 7607872
02:27:37 4689 8192000000 10012132304 10405801984 1091520528 11.757 100 45 90 1 1116500976 14757248 10223200
02:28:48 42346 8192000000 10049351680 10451111936 1115522928 11.985 100 37 75 1 1154269504 31141408 7605168
02:30:37 30404 8192000000 10095050656 10502524928 1126085968 12.084 100 56 111 3 1198342608 60788912 11467728
02:32:17 16436 8192000000 10131259040 10539978752 1150572016 12.315 100 51 100 0 1246557552 84696160 11289376
02:33:46 6893 8192000000 10190425232 10597715968 1161263520 12.415 100 57 111 2 1293425952 120076944 12085488
02:34:56 2798 8192000000 10207491472 10625441792 1166780960 12.467 100 55 110 1 1311405344 132493856 12130528
02:36:04 5138 8192000000 10236425808 10653986816 1177288800 12.565 100 58 115 1 1349912480 160086816 12536864
02:37:14 10003 8192000000 10253980032 10678292480 1171381664 12.510 100 55 110 1 1367205072 182269824 13553584
02:38:34 4201 8192000000 10249124064 10686971904 1171615312 12.512 100 66 132 1 1362893744 175832928 15445504
02:39:50 13693 8192000000 10279509808 10693070848 1189224720 12.676 100 64 127 0 1370819264 167730160 13864384
02:41:09 1811 8192000000 10299805872 10709823488 1171924176 12.515 100 79 158 0 1407640960 217840768 17876016
02:42:17 6698 8192000000 10344091136 10760945664 1185615728 12.643 100 65 131 0 1440996192 240505232 14875232
02:43:28 5889 8192000000 10359450944 10804457472 1180050192 12.591 100 65 128 0 1465220192 269173312 15996688
02:44:42 2180 8192000000 10347920112 10754650112 1168280160 12.481 99 89 174 1 1448547920 260229040 20038720
02:45:50 1178 8192000000 10353807952 10757754880 1168633472 12.484 100 94 184 2 1451502048 261020672 21847904
02:46:54 9954 8192000000 10306835392 10769829888 1152852096 12.336 100 34 68 0 1415229744 253948880 8428768
02:48:08 8952 8192000000 10327354320 10750820352 1131205456 12.133 100 45 89 0 1434815520 292235728 11374336
02:49:33 14904 8192000000 10275859488 10726068224 1120163072 12.029 99 31 62 1 1372759456 244920336 7676048
02:50:50 9930 8192000000 10297108768 10724802560 1123441552 12.059 100 35 65 2 1398258160 266275808 8540800
02:52:08 17118 8192000000 10321371392 10782171136 1115157056 11.981 99 35 70 0 1424512928 300815648 8540224
02:53:28 17958 8192000000 10274250960 10749886464 1117436704 12.003 100 38 76 0 1380384736 253794544 9153488
02:55:11 25110 8192000000 10066378080 10592235520 1091225152 11.754 99 9 18 1 1185912352 92394912 2292288
02:56:41 62248 8192000000 10064032032 10495787008 1104913104 11.884 98 33 64 1 1129675616 17862256 6900256
02:58:44 34007 8192000000 10082305904 10532913152 1122923824 12.055 99 36 72 2 1173532544 43322512 7286208
03:00:42 81149 8192000000 10137290832 10585264128 1112265552 11.954 98 16 32 1 1194685792 79206224 3214016
03:03:07 88879 8192000000 10074679040 10534608896 1114689808 11.977 98 4 8 1 1151541904 36333056 519040
03:05:50 77095 8192000000 10079024544 10546425856 1149273104 12.303 100 10 19 2 1191428896 39867104 2288688
03:08:09 67331 8192000000 10124146336 10573160448 1120837296 12.035 97 34 68 3 1187731792 59397232 7497264
03:10:49 50159 8192000000 10095120256 10517504000 1125887600 12.083 98 52 102 3 1165831728 30067136 9876992
03:12:41 13477 8192000000 10099440384 10537701376 1161887920 12.421 100 38 76 3 1198860624 27152128 9820576
03:14:02 76325 8192000000 10134568960 10596937728 1154371488 12.351 99 18 36 1 1232329440 74214656 3743296
03:16:21 135398 8192000000 10208193952 10661838848 1175058352 12.544 100 18 36 0 1278442704 99408224 3976128
03:19:45 43225 8192000000 10203961456 10693132288 1140763808 12.223 97 23 46 1 1325492048 179114032 5614208
03:21:36 59603 8192000000 10152494720 10648977408 1145252928 12.265 98 12 24 0 1260356528 111965376 3138224
03:23:46 36048 8192000000 10103120016 10579410944 1157597328 12.381 100 15 30 1 1204183712 43009968 3576416
03:25:27 28445 8192000000 10116653984 10552168448 1154625872 12.353 100 36 73 1 1203924320 41496688 7801760
03:26:56 47316 8192000000 10083910160 10526334976 1115682256 11.986 96 28 55 1 1141690336 20851248 5156832
03:28:50 60891 8192000000 10117430400 10563252224 1109358928 11.926 96 28 56 0 1162277680 47325440 5593312
03:30:57 27467 8192000000 10128600560 10580598784 1115627952 11.986 98 17 34 0 1151274080 32267920 3378208
03:32:41 56431 8192000000 10083495584 10598068224 1101738944 11.854 97 15 30 1 1208210944 103134864 3337136
03:34:42 88968 8192000000 10033339392 10471092224 1072633872 11.577 96 31 62 0 1094678288 15642672 6401744
03:37:34 54450 8192000000 10031194096 10501144576 1107433648 11.908 100 9 18 1 1156345552 46626496 2285408
03:39:30 64383 8192000000 10057395312 10504585216 1081944336 11.666 96 28 57 1 1133741264 45967424 5829504
03:41:55 47462 8192000000 10057964400 10537033728 1094084752 11.781 99 10 20 1 1142008992 45597600 2326640
03:43:43 26064 8192000000 10103074880 10538602496 1122560400 12.051 100 36 72 0 1193415552 63300368 7554784
03:45:09 32586 8192000000 10106638448 10557640704 1110380736 11.936 99 33 66 1 1207986912 90009488 7596688
03:46:47 51930 8192000000 10117019888 10555162624 1098893792 11.827 98 23 46 0 1187522224 83261168 5367264
03:48:41 72641 8192000000 10139776256 10622701568 1102568128 11.862 98 13 26 0 1215248256 109737056 2943072
03:50:54 41626 8192000000 10156814624 10636013568 1080830768 11.655 96 18 36 1 1216749776 131647856 4271152
03:52:51 72205 8192000000 10048102464 10515406848 1082823408 11.674 97 17 34 1 1151914096 65376208 3714480
03:55:04 86398 8192000000 10054100048 10523168768 1101162448 11.849 99 16 32 0 1158006720 53424288 3419984
03:57:31 31214 8192000000 10038446016 10516340736 1104887280 11.884 99 11 22 0 1136454800 29384224 2183296
03:59:06 63806 8192000000 10076663728 10525499392 1097557472 11.814 97 11 22 0 1145283376 45198768 2527136
04:01:17 66707 8192000000 10114211632 10560483328 1129848064 12.120 99 22 45 0 1184158512 49656304 4654144
04:03:28 95776 8192000000 10189336656 10652381184 1152555920 12.333 100 18 36 0 1285014912 128251792 4207200
04:06:07 82951 8192000000 10089594480 10592698368 1134246912 12.161 99 13 26 1 1253487408 116698608 2541888
04:08:42 47779 8192000000 10142747312 10573750272 1143820128 12.251 99 40 81 1 1211379936 59237280 8322528
04:10:31 99045 8192000000 10097815728 10555138048 1123719440 12.062 97 18 36 2 1185174224 57799328 3655456
04:13:13 159874 8192000000 10090905584 10560860160 1140787824 12.223 99 36 72 1 1182916816 34975392 7153600
04:17:06 94948 8192000000 10117795648 10593841152 1143062960 12.244 99 14 28 2 1190648592 44557488 3028144
04:19:41 107405 8192000000 10102397680 10562220032 1122045040 12.046 97 11 22 0 1185649264 61366576 2237648
04:22:44 30391 8192000000 10045234752 10494771200 1117978224 12.008 99 16 32 0 1152140944 30900032 3262688
04:24:32 36656 8192000000 10084617696 10545000448 1140849056 12.224 100 27 54 1 1194508208 47411696 6247456
04:26:15 39336 8192000000 10090426560 10538635264 1107865280 11.912 98 17 34 0 1149897936 38378720 3653936

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 12-06-2021 04:28:00 to 12-06-2021 04:28:15.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 127 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-06-2021 04:28:16 to 12-06-2021 04:28:18.

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          232887
MON$OLDEST_ACTIVE               234647
MON$OLDEST_SNAPSHOT             234647
MON$NEXT_TRANSACTION            234647
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                       2128960
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             794
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2514 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2514 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2514 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2514 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 ---
:141: 
:142: mscs-test-161.budzdorov.ru    Sat Jun 12 04:27:03 2021
:143:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:144: 
:145: 
:146: mscs-test-161.budzdorov.ru    Sat Jun 12 04:27:05 2021
:147:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:148: 
:149: 
:150: mscs-test-161.budzdorov.ru    Sat Jun 12 04:27:08 2021
:151:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:152: 
:153: 
:154: mscs-test-161.budzdorov.ru    Sat Jun 12 04:27:08 2021
:155:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:156: 
--- end of diff output ---

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

12.06.21 04:28:19. 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-12 04:28:21 No severe PSQL-related problems occured
12.06.21 04:28:22. End of report.