Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.08.2021 10: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 16092708 kB
MemAvailable 31089320 kB
Buffers 0 kB
Cached 15619484 kB
SwapCached 2572 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-08-07 10:37:03
DTS_END                         2021-08-07 10:37:03.8420
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/48320
TRN_ID                          233080
ATT_ID                          693
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: 9020.24 21600 1082429
customer order: creation 168.30 413 20196
customer order: refuse 14.28 341 1714
order to supplier: creation 44.39 1513 5327
order to supplier: removal 3.51 113229 421
invoice (draft): creation 44.16 3375 5299
invoice (draft): removal 52.45 974 6294
invoice accept: apply 40.61 18673 4873
invoice accept: cancel 47.56 772 5707
customer reserve: creation 3686.71 187 442405
customer reserve: removal 4628.65 66 555438
realization accept: apply 81.40 209 9768
realization accept: cancel 82.93 122 9952
payment from customer: creation 57.72 133 6926
payment from customer: removal 5.62 118 674
payment to supplier: creation 48.20 132 5784
payment to supplier: removal 3.15 117 378
service: total inventory turnovers 3.73 3601 448
service: total monetary turnovers 5.99 550 719
service: aggregate perf. data 0.85 15530 102
service: refresh index statistics 0.03 271944 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-08-2021 10:38:46 to 07-08-2021 10:38:48.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5548
TEST_TIME 2 5833
TEST_TIME 3 5674
TEST_TIME 4 4669
TEST_TIME 5 4811
TEST_TIME 6 4744
TEST_TIME 7 4575
TEST_TIME 8 4637
TEST_TIME 9 4916
TEST_TIME 10 5154
TEST_TIME 11 5550
TEST_TIME 12 5603
TEST_TIME 13 5965
TEST_TIME 14 6042
TEST_TIME 15 6087
TEST_TIME 16 6292
TEST_TIME 17 7111
TEST_TIME 18 7512
TEST_TIME 19 8015
TEST_TIME 20 7938
TEST_TIME 21 8368
TEST_TIME 22 8584
TEST_TIME 23 8619
TEST_TIME 24 9002
TEST_TIME 25 9678
TEST_TIME 26 9988
TEST_TIME 27 10348
TEST_TIME 28 10253
TEST_TIME 29 10530
TEST_TIME 30 10467
TEST_TIME 31 10499
TEST_TIME 32 10466
TEST_TIME 33 10527
TEST_TIME 34 10433
TEST_TIME 35 10495
TEST_TIME 36 10363
TEST_TIME 37 10270
TEST_TIME 38 10295
TEST_TIME 39 10213
TEST_TIME 40 10244
TEST_TIME 41 10383
TEST_TIME 42 10348
TEST_TIME 43 10196
TEST_TIME 44 10024
TEST_TIME 45 9987
TEST_TIME 46 9869
TEST_TIME 47 9777
TEST_TIME 48 9748
TEST_TIME 49 9788
TEST_TIME 50 9802
TEST_TIME 51 9849
TEST_TIME 52 9794
TEST_TIME 53 9718
TEST_TIME 54 9609
TEST_TIME 55 9533
TEST_TIME 56 9647
TEST_TIME 57 9595
TEST_TIME 58 9548
TEST_TIME 59 9429
TEST_TIME 60 9423
TEST_TIME 61 9377
TEST_TIME 62 9372
TEST_TIME 63 9339
TEST_TIME 64 9360
TEST_TIME 65 9383
TEST_TIME 66 9393
TEST_TIME 67 9474
TEST_TIME 68 9514
TEST_TIME 69 9545
TEST_TIME 70 9574
TEST_TIME 71 9639
TEST_TIME 72 9656
TEST_TIME 73 9688
TEST_TIME 74 9721
TEST_TIME 75 9611
TEST_TIME 76 9512
TEST_TIME 77 9548
TEST_TIME 78 9530
TEST_TIME 79 9443
TEST_TIME 80 9424
TEST_TIME 81 9410
TEST_TIME 82 9451
TEST_TIME 83 9436
TEST_TIME 84 9411
TEST_TIME 85 9454
TEST_TIME 86 9430
TEST_TIME 87 9466
TEST_TIME 88 9466
TEST_TIME 89 9415
TEST_TIME 90 9528
TEST_TIME 91 9513
TEST_TIME 92 9625
TEST_TIME 93 9546
TEST_TIME 94 9479
TEST_TIME 95 9445
TEST_TIME 96 9416
TEST_TIME 97 9346
TEST_TIME 98 9306
TEST_TIME 99 9250
TEST_TIME 100 9183
TEST_TIME 101 9165
TEST_TIME 102 9180
TEST_TIME 103 9154
TEST_TIME 104 9189
TEST_TIME 105 9185
TEST_TIME 106 9241
TEST_TIME 107 9207
TEST_TIME 108 9199
TEST_TIME 109 9256
TEST_TIME 110 9305
TEST_TIME 111 9289
TEST_TIME 112 9278
TEST_TIME 113 9263
TEST_TIME 114 9199
TEST_TIME 115 9215
TEST_TIME 116 9160
TEST_TIME 117 9137
TEST_TIME 118 9131
TEST_TIME 119 9098
TEST_TIME 120 9026

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 07-08-2021 10:38:50 to 07-08-2021 10:38: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 1494227 1494227 0 0.00 0 23111 7 0 0
doc_list_biud 1494226 1494226 0 0.00 0 32581 31 0 0
sp_add_invoice_to_stock 5010 4873 137 2.73 23 205431 18673 0 137
sp_cancel_adding_invoice 5707 5707 0 0.00 5 51447 773 0 0
sp_cancel_client_order 1714 1714 0 0.00 4 6192 341 0 0
sp_cancel_customer_reserve 555438 555438 0 0.00 1 32203 67 0 0
sp_cancel_pay_from_customer 674 674 0 0.00 3 5400 119 0 0
sp_cancel_pay_to_supplier 378 378 0 0.00 3 9550 117 0 0
sp_cancel_supplier_invoice 6307 6294 13 0.20 2 21753 974 0 13
sp_cancel_supplier_order 421 421 0 0.00 8 1247060 113230 0 0
sp_cancel_write_off 9952 9952 0 0.00 1 16169 123 0 0
sp_client_order 20196 20196 0 0.00 4 23344 414 0 0
sp_customer_reserve 442405 442405 0 0.00 0 178141 188 0 0
sp_fill_shopping_cart 30810 30810 0 0.00 1 16291 42 0 0
sp_fill_shopping_cart_clo_res 442395 442395 0 0.00 0 15506 16 0 0
sp_get_clo_for_invoice 4861 4861 0 0.00 0 24314 358 0 0
sp_kill_cost_storno 16711 16711 0 0.00 0 6706 67 0 0
sp_kill_qstorno_ret_qs2qd 1127543 1127543 0 0.00 0 32083 36 0 0
sp_kill_qty_storno 594154 594154 0 0.00 0 32581 78 0 0
sp_lock_selected_doc 20193 20193 0 0.00 0 6796 5 0 0
sp_make_cost_storno 27339 27339 0 0.00 0 16036 62 0 0
sp_multiply_rows_for_pdistr 27339 27339 0 0.00 0 11063 37 0 0
sp_multiply_rows_for_qdistr 20196 20196 0 0.00 0 12144 213 0 0
sp_pay_from_customer 6926 6926 0 0.00 4 12196 133 0 0
sp_pay_to_supplier 5784 5784 0 0.00 5 12353 132 0 0
sp_payment_common 12710 12710 0 0.00 1 10018 27 0 0
sp_qd_handle_on_cancel_clo 1714 1714 0 0.00 0 4374 318 0 0
sp_qd_handle_on_invoice_upd_sts 10568 10568 0 0.00 0 32580 368 0 0
sp_qd_handle_on_reserve_upd_sts 9767 9767 0 0.00 0 21393 63 0 0
sp_reserve_write_off 9768 9768 0 0.00 7 21410 209 0 0
sp_supplier_invoice 5299 5299 0 0.00 6 70727 3375 0 0
sp_supplier_order 5327 5327 0 0.00 6 27139 1514 0 0
srv_aggregate_perf_data 102 102 0 0.00 2727 159119 15531 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1419 152096 44435 0 0
srv_find_qd_qs_mism 1037316 1037316 0 0.00 0 23111 6 0 0
srv_make_invnt_saldo 448 448 0 0.00 49 116573 3601 0 0
srv_make_money_saldo 719 719 0 0.00 1 23662 550 0 0
srv_recalc_idx_stat 4 4 0 0.00 171797 384452 271944 0 0
t$perf-abend:sp_add_invoice_to_stock 137 137 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4873 4873 0 0.00 0 17203 163 0 0
t$perf-norm:sp_cancel_adding_invoice 396 396 0 0.00 0 10322 105 0 0
t$perf-norm:sp_cancel_client_order 1714 1714 0 0.00 0 3454 14 0 0
t$perf-norm:sp_cancel_customer_reserve 689 689 0 0.00 0 1391 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 674 674 0 0.00 0 1966 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 378 378 0 0.00 0 6615 28 0 0
t$perf-norm:sp_cancel_supplier_invoice 369 369 0 0.00 0 8852 41 0 0
t$perf-norm:sp_cancel_supplier_order 421 421 0 0.00 0 34310 1871 0 0
t$perf-norm:sp_cancel_write_off 928 928 0 0.00 0 2735 8 0 0
t$perf-norm:sp_client_order 20196 20196 0 0.00 0 10768 18 0 0
t$perf-norm:sp_customer_reserve 998 998 0 0.00 0 7981 15 0 0
t$perf-norm:sp_pay_from_customer 6926 6926 0 0.00 0 8261 15 0 0
t$perf-norm:sp_pay_to_supplier 5784 5784 0 0.00 0 8321 13 0 0
t$perf-norm:sp_reserve_write_off 9768 9768 0 0.00 0 16659 15 0 0
t$perf-norm:sp_supplier_invoice 5299 5299 0 0.00 0 6696 18 0 0
t$perf-norm:sp_supplier_order 5327 5327 0 0.00 0 7790 23 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 175 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 436 8 0 0
t$perf-norm:srv_make_invnt_saldo 448 448 0 0.00 0 2443 25 0 0
t$perf-norm:srv_make_money_saldo 719 719 0 0.00 0 2366 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 2 0 0
v_add_invoice_to_stock 9988 9714 274 2.74 0 3653 16 0 274
v_all_customers 36456 36456 0 0.00 0 17 1 0 0
v_all_suppliers 29892 29892 0 0.00 0 26 1 0 0
v_all_wares 40390 40390 0 0.00 0 88 1 0 0
v_cancel_adding_invoice 792 792 0 0.00 0 11906 23 0 0
v_cancel_client_order 3428 3428 0 0.00 0 952 3 0 0
v_cancel_customer_prepayment 1348 1348 0 0.00 0 3308 10 0 0
v_cancel_customer_reserve 1378 1378 0 0.00 0 552 4 0 0
v_cancel_payment_to_supplier 756 756 0 0.00 0 580 5 0 0
v_cancel_supplier_invoice 764 738 26 3.40 0 234 11 0 26
v_cancel_supplier_order 842 842 0 0.00 0 238 3 0 0
v_cancel_write_off 1856 1856 0 0.00 0 3466 18 0 0
v_max_id_clo_ord 5323 5323 0 0.00 0 6510 3 0 0
v_max_id_clo_res 998 998 0 0.00 0 2711 21 0 0
v_max_id_ord_sup 5291 5291 0 0.00 0 6049 4 0 0
v_max_non_paid_invoice 1452 1452 0 0.00 0 1643 3 0 0
v_max_non_paid_realizn 3727 3727 0 0.00 0 5527 6 0 0
v_min_id_clo_ord 5323 5323 0 0.00 0 2987 6 0 0
v_min_id_clo_res 998 998 0 0.00 0 377 2 0 0
v_min_id_ord_sup 5291 5291 0 0.00 0 8961 8 0 0
v_min_non_paid_invoice 5784 5784 0 0.00 0 6716 14 0 0
v_min_non_paid_realizn 6926 6926 0 0.00 0 10015 24 0 0
v_our_firm 10328 10328 0 0.00 0 17 1 0 0
v_reserve_write_off 19533 19533 0 0.00 0 9959 6 0 0
x_lock_depdocs_on_canc_invoice 11632 11632 0 0.00 0 20820 107 0 0
x_lock_depdocs_on_canc_sup_ord 421 421 0 0.00 0 6889 324 0 0
x_make_qstorno_1000_1200 5327 5327 0 0.00 1 27124 1428 0 0
x_make_qstorno_1200_2000 5299 5299 0 0.00 3 67259 3219 0 0
x_make_qstorno_2100_3300 442405 442405 0 0.00 0 178137 169 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-08-2021 10:38:54 to 07-08-2021 10:38: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
08:07:07 2 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:08 29847 8192000000 9721580512 10018910208 796836240 8.864 100 85 169 0 825318848 7562496 20920112
08:09:45 35880 8192000000 9764881216 10071838720 835798624 9.258 100 92 184 0 870837408 13560560 21478224
08:11:30 20087 8192000000 9808368224 10129326080 880021328 9.700 100 75 150 0 918203808 21464224 16718256
08:12:53 9699 8192000000 9879091696 10211594240 920325504 10.099 100 84 168 0 992012016 53212832 18473680
08:14:05 8902 8192000000 9918586912 10261307392 951991824 10.411 100 84 168 1 1030704544 60975696 17737024
08:15:17 5038 8192000000 9958216448 10311131136 988698480 10.769 100 81 162 1 1068650832 63735856 16216496
08:16:24 3852 8192000000 9994871488 10353659904 1014417552 11.018 100 86 172 0 1107226592 75742320 17066720
08:17:30 1354 8192000000 10024571936 10392023040 1041171232 11.276 100 91 183 0 1138631712 78747296 18713184
08:18:32 1807 8192000000 10026485104 10398306304 1062644832 11.482 100 91 183 1 1139112816 58070656 18397328
08:19:35 3861 8192000000 10010387808 10394824704 1064331904 11.498 100 50 101 1 1121907648 47846000 9729744
08:20:40 4354 8192000000 9980825376 10371244032 1078194912 11.630 100 21 42 0 1108344512 26185824 3963776
08:21:46 62806 8192000000 10000082432 10392522752 1085554592 11.700 100 39 76 1 1112921168 19237040 8129536
08:23:51 3525 8192000000 10024211872 10423123968 1094898176 11.789 100 42 82 0 1144251520 40537168 8816176
08:24:55 8163 8192000000 10010830864 10428608512 1101822816 11.855 100 41 82 3 1138060080 27081088 9156176
08:26:04 59835 8192000000 10012624912 10421428224 1058854432 11.446 96 40 80 0 1087410480 20202736 8353312
08:28:05 7439 8192000000 10000301808 10411634688 1076032688 11.610 100 32 63 0 1125394352 42915952 6445712
08:29:14 5880 8192000000 9992250640 10380800000 1063220880 11.487 100 49 98 1 1100454064 26322320 10910864
08:30:21 16002 8192000000 9947698480 10342395904 981485072 10.699 95 30 60 3 1017905296 30049504 6370720
08:32:22 3652 8192000000 9942882400 10324008960 1008798224 10.964 97 61 119 1 1039263520 17232416 13232880
08:33:27 5918 8192000000 9960125616 10347802624 1053629296 11.395 100 32 64 1 1085032688 24116320 7287072
08:34:33 7393 8192000000 9971209536 10372976640 1061422848 11.470 100 29 58 0 1078192128 10262848 6506432
08:35:42 32614 8192000000 9979502208 10387931136 1065927216 11.513 100 31 61 3 1103795280 31185264 6682800
08:37:20 26373 8192000000 10017297328 10410835968 1092112880 11.763 100 47 93 0 1120178080 19043824 9021376
08:38:52 16849 8192000000 10095158592 10496086016 1111824864 11.950 100 58 116 0 1203260416 79040416 12395136
08:40:21 30690 8192000000 10088535376 10503118848 1131691936 12.137 100 31 62 0 1202858816 63564624 7602256
08:42:04 18772 8192000000 10136221072 10548756480 1136506064 12.183 100 51 101 1 1245175520 97188224 11481232
08:43:39 28119 8192000000 10156238048 10583330816 1150316240 12.312 100 35 70 1 1264120208 106099600 7704368
08:45:15 10844 8192000000 10218761696 10627796992 1157192416 12.377 100 48 95 0 1314055184 146760768 10102000
08:46:33 10037 8192000000 10263575328 10679451648 1172854944 12.524 100 53 105 1 1372662720 188283216 11524560
08:47:48 3782 8192000000 10285095952 10696732672 1167432928 12.473 99 65 130 0 1384425680 202375088 14617664
08:48:55 2509 8192000000 10333360448 10746093568 1189887536 12.682 100 82 162 4 1442989424 234870848 18231040
08:50:04 2912 8192000000 10394556304 10798206976 1183638176 12.624 100 87 174 2 1493747808 289660352 20449280
08:51:12 1418 8192000000 10404864912 10815836160 1185942688 12.646 100 83 164 0 1506550064 301666720 18940656
08:52:20 2643 8192000000 10449603008 10859900928 1187289312 12.658 100 84 164 3 1551146064 343895568 19961184
08:53:29 2557 8192000000 10476498592 10888708096 1186441088 12.650 100 79 158 0 1575770240 370702096 18627056
08:54:39 4185 8192000000 10503556400 10960236544 1182587248 12.614 100 82 163 0 1605244736 403196448 19461040
08:55:50 12115 8192000000 10403379504 10862522368 1160492128 12.408 100 41 79 1 1508724752 338287856 9944768
08:57:04 11752 8192000000 10366564240 10800832512 1120810112 12.035 97 46 92 0 1444611104 312682416 11118576
08:58:18 14020 8192000000 10384469184 10842030080 1145604544 12.268 100 31 62 0 1489098032 335202288 8291200
08:59:34 6881 8192000000 10360369104 10790285312 1142547232 12.239 99 44 86 1 1450644784 297502624 10594928
09:00:45 9280 8192000000 10346434960 10790785024 1120211760 12.029 99 34 68 0 1449861856 320560304 9089792
09:02:00 12305 8192000000 10256482624 10758778880 1121820608 12.044 100 33 66 0 1404289824 273991024 8478192
09:03:15 21802 8192000000 10169211520 10666590208 1125995712 12.084 100 13 26 2 1305732800 176045424 3691664
09:04:43 39518 8192000000 10128307040 10602852352 1097972144 11.818 97 9 18 1 1220514672 120443600 2098928
09:06:42 36890 8192000000 10103332720 10575548416 1131682496 12.137 100 21 42 1 1198426560 62150672 4593392
09:08:19 79552 8192000000 10087310688 10542002176 1072480992 11.576 95 21 43 0 1141423840 64450048 4492800
09:10:47 71452 8192000000 10078592128 10525835264 1110354992 11.936 98 11 22 0 1132404736 20028096 2021648
09:13:19 63755 8192000000 10066161760 10498670592 1125925664 12.083 99 19 36 1 1161983792 32142192 3915936
09:15:27 40125 8192000000 10075778960 10495393792 1126080656 12.084 99 38 75 0 1159161696 23807616 9273424
09:17:08 130406 8192000000 10090490720 10622472192 1130208320 12.123 99 15 30 1 1202532784 69296816 3027648
09:20:22 102699 8192000000 10118064688 10553085952 1149330144 12.303 100 23 46 1 1181353328 26842672 5180512
09:23:07 152096 8192000000 10151107440 10606108672 1127260496 12.096 97 27 54 3 1208805552 74936896 6608160
09:26:40 30125 8192000000 10160730576 10596495360 1146014592 12.272 99 48 96 0 1238386720 82213376 10158752
09:28:27 66547 8192000000 10087643248 10569719808 1140608896 12.221 99 8 16 0 1196879952 54360848 1910208
09:30:33 75414 8192000000 10154784480 10630463488 1160253184 12.406 100 12 24 0 1256620336 93712704 2654448
09:32:54 59983 8192000000 10074636384 10552590336 1143542848 12.249 99 17 34 0 1220281216 73172672 3565696
09:35:12 33904 8192000000 10051789680 10499473408 1127142864 12.094 98 21 42 1 1157387168 26254192 3990112
09:36:55 52905 8192000000 10055512160 10541375488 1099855040 11.836 96 6 12 0 1149168048 48372960 940048
09:38:48 21025 8192000000 10055429904 10506031104 1118076464 12.009 98 17 34 1 1143909152 23012128 2820560
09:40:11 27773 8192000000 10054629264 10493685760 1098217856 11.821 96 24 48 0 1127976368 24932640 4825872
09:41:45 41509 8192000000 10130580768 10588958720 1134386816 12.163 99 23 46 1 1221444272 81424304 5633152
09:43:26 37852 8192000000 10141681568 10592583680 1127249360 12.095 98 19 38 0 1255252384 123471792 4531232
09:45:04 19983 8192000000 10071384896 10514436096 1093219984 11.773 96 48 96 2 1131402320 27546896 10635440
09:46:25 22321 8192000000 10141270528 10589417472 1127131712 12.094 100 44 86 3 1234775920 98862464 8781744
09:47:48 87744 8192000000 10093467952 10593501184 1079448672 11.642 96 15 30 0 1214026768 131120224 3457872
09:50:17 42913 8192000000 10082581840 10547544064 1083199968 11.678 96 15 30 2 1142509264 56429104 2880192
09:52:01 120929 8192000000 10109263360 10538496000 1112833760 11.959 99 32 64 0 1195731488 76241344 6656384
09:55:02 67568 8192000000 10082445104 10606743552 1117201200 12.001 99 6 12 0 1192782000 74565840 1014960
09:57:16 29334 8192000000 10083422000 10551140352 1117909584 12.007 99 20 38 1 1169922112 47779536 4232992
09:58:46 60972 8192000000 10070011968 10578309120 1105165264 11.887 98 21 43 1 1177044768 67178752 4700752
10:00:48 14409 8192000000 10041744976 10480807936 1125749808 12.081 100 40 79 2 1156386992 21762816 8874368
10:02:07 38561 8192000000 10086571792 10534567936 1108610880 11.919 98 34 68 1 1172858416 56346992 7900544
10:04:10 75923 8192000000 10091368672 10547003392 1126227840 12.086 99 25 50 1 1197531984 66157472 5146672
10:06:29 32497 8192000000 10131769104 10568069120 1134906304 12.168 99 27 54 0 1205107712 64870112 5331296
10:08:02 78328 8192000000 10140736240 10604941312 1103554832 11.871 96 18 36 0 1164084784 57064032 3465920
10:10:28 93935 8192000000 10092809728 10549252096 1146812272 12.280 100 25 50 1 1195711808 43876592 5022944
10:13:05 41359 8192000000 10065466416 10536484864 1140791744 12.223 99 17 34 2 1167895232 23501056 3602432
10:14:54 59360 8192000000 10101852112 10551599104 1143470720 12.248 99 16 32 0 1185550112 38856464 3222928
10:16:55 65809 8192000000 10115522768 10583977984 1154382736 12.351 100 18 36 0 1216106112 57886576 3836800
10:19:04 72077 8192000000 10133275840 10613587968 1134141648 12.160 98 21 42 0 1206318112 67345360 4831104
10:21:18 90322 8192000000 10112949488 10590461952 1104662992 11.882 95 18 35 0 1176628288 67688512 4276784
10:23:48 36178 8192000000 10077048256 10517061632 1120235072 12.029 98 12 25 0 1156503760 33918336 2350352
10:25:28 86140 8192000000 10139837360 10662567936 1117678944 12.005 98 12 24 0 1251588464 131045104 2864416
10:27:55 52694 8192000000 10070959520 10503655424 1102137040 11.858 97 32 64 0 1132423952 23700112 6586800
10:29:48 115865 8192000000 10081817424 10537033728 1136775136 12.185 100 19 38 1 1197987840 57476896 3735808
10:33:02 52065 8192000000 10090280096 10560106496 1115536480 11.985 98 7 14 0 1181007360 64018304 1452576
10:35:10 46904 8192000000 10056377296 10566303744 1095969904 11.799 97 12 22 2 1149970240 51304624 2695712
10:36:57 15034 8192000000 9027579856 9262219264 194816656 2.322 18 1 2 0 200461040 5608976 35408

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 07-08-2021 10:39:00 to 07-08-2021 10:39:15.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 137 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-08-2021 10:39:16 to 07-08-2021 10:39: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          233367
MON$OLDEST_ACTIVE               233368
MON$OLDEST_SNAPSHOT             233368
MON$NEXT_TRANSACTION            233368
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                       2065568
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             795
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2563 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2563 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2563 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2563 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 ---
:63: 
:64: mscs-test-161.budzdorov.ru    Sat Aug  7 10:37:19 2021
:65:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:66: 
:67: 
:68: mscs-test-161.budzdorov.ru    Sat Aug  7 10:37:20 2021
:69:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:70: 
--- end of diff output ---

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

07.08.21 10:39: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-08-07 10:39:22 No severe PSQL-related problems occured
07.08.21 10:39:23. End of report.