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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID e0e7b46250ba4f9795ceb55e0face73f
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2020-09-25 13:42
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.00
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16549988 kB
MemAvailable 31281832 kB
Buffers 0 kB
Cached 14989988 kB
SwapCached 4864 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-01-02 13:26:54
DTS_END                         2021-01-02 13:26:54.5440
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/39146
TRN_ID                          236430
ATT_ID                          674
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: 9158.73 27410 1099047
customer order: creation 168.02 416 20162
customer order: refuse 14.91 337 1789
order to supplier: creation 46.56 1363 5587
order to supplier: removal 3.45 124164 414
invoice (draft): creation 45.88 3421 5505
invoice (draft): removal 51.05 1041 6126
invoice accept: apply 41.76 17046 5011
invoice accept: cancel 46.18 995 5541
customer reserve: creation 3898.98 164 467877
customer reserve: removal 4541.82 74 545018
realization accept: apply 82.45 181 9894
realization accept: cancel 88.64 127 10637
payment from customer: creation 60.31 109 7237
payment from customer: removal 5.82 108 698
payment to supplier: creation 48.76 131 5851
payment to supplier: removal 3.35 96 402
service: total inventory turnovers 3.79 3908 455
service: total monetary turnovers 6.14 594 737
service: aggregate perf. data 0.85 17416 102
service: refresh index statistics 0.03 376509 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-01-2021 13:27:58 to 02-01-2021 13:28:00.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5605
TEST_TIME 2 5232
TEST_TIME 3 5758
TEST_TIME 4 5082
TEST_TIME 5 5530
TEST_TIME 6 5349
TEST_TIME 7 5197
TEST_TIME 8 4967
TEST_TIME 9 5444
TEST_TIME 10 5872
TEST_TIME 11 5848
TEST_TIME 12 6162
TEST_TIME 13 6095
TEST_TIME 14 6028
TEST_TIME 15 6287
TEST_TIME 16 6422
TEST_TIME 17 6323
TEST_TIME 18 6388
TEST_TIME 19 6895
TEST_TIME 20 7374
TEST_TIME 21 7386
TEST_TIME 22 7358
TEST_TIME 23 7576
TEST_TIME 24 7702
TEST_TIME 25 8411
TEST_TIME 26 8535
TEST_TIME 27 8698
TEST_TIME 28 8864
TEST_TIME 29 8916
TEST_TIME 30 10428
TEST_TIME 31 10544
TEST_TIME 32 10480
TEST_TIME 33 10630
TEST_TIME 34 10399
TEST_TIME 35 10441
TEST_TIME 36 10327
TEST_TIME 37 10306
TEST_TIME 38 10258
TEST_TIME 39 10297
TEST_TIME 40 10500
TEST_TIME 41 10278
TEST_TIME 42 10094
TEST_TIME 43 9940
TEST_TIME 44 9882
TEST_TIME 45 9828
TEST_TIME 46 9689
TEST_TIME 47 9828
TEST_TIME 48 9779
TEST_TIME 49 9672
TEST_TIME 50 9676
TEST_TIME 51 9665
TEST_TIME 52 9566
TEST_TIME 53 9481
TEST_TIME 54 9414
TEST_TIME 55 9267
TEST_TIME 56 9244
TEST_TIME 57 9407
TEST_TIME 58 9404
TEST_TIME 59 9391
TEST_TIME 60 9360
TEST_TIME 61 9351
TEST_TIME 62 9342
TEST_TIME 63 9422
TEST_TIME 64 9496
TEST_TIME 65 9500
TEST_TIME 66 9613
TEST_TIME 67 9533
TEST_TIME 68 9670
TEST_TIME 69 9581
TEST_TIME 70 9475
TEST_TIME 71 9413
TEST_TIME 72 9342
TEST_TIME 73 9283
TEST_TIME 74 9261
TEST_TIME 75 9233
TEST_TIME 76 9243
TEST_TIME 77 9245
TEST_TIME 78 9234
TEST_TIME 79 9233
TEST_TIME 80 9241
TEST_TIME 81 9321
TEST_TIME 82 9412
TEST_TIME 83 9451
TEST_TIME 84 9408
TEST_TIME 85 9407
TEST_TIME 86 9506
TEST_TIME 87 9461
TEST_TIME 88 9395
TEST_TIME 89 9346
TEST_TIME 90 9309
TEST_TIME 91 9240
TEST_TIME 92 9189
TEST_TIME 93 9180
TEST_TIME 94 9152
TEST_TIME 95 9152
TEST_TIME 96 9226
TEST_TIME 97 9190
TEST_TIME 98 9240
TEST_TIME 99 9215
TEST_TIME 100 9213
TEST_TIME 101 9238
TEST_TIME 102 9265
TEST_TIME 103 9268
TEST_TIME 104 9258
TEST_TIME 105 9191
TEST_TIME 106 9176
TEST_TIME 107 9126
TEST_TIME 108 9078
TEST_TIME 109 9090
TEST_TIME 110 9083
TEST_TIME 111 9052
TEST_TIME 112 9021
TEST_TIME 113 9003
TEST_TIME 114 9015
TEST_TIME 115 9074
TEST_TIME 116 9074
TEST_TIME 117 9105
TEST_TIME 118 9108
TEST_TIME 119 9117
TEST_TIME 120 9167

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 02-01-2021 13:28:01 to 02-01-2021 13:28:05.

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 1532154 1532154 0 0.00 0 14745 7 0 0
doc_list_biud 1532154 1532154 0 0.00 0 33001 33 0 0
sp_add_invoice_to_stock 5121 5011 110 2.14 16 159239 17046 0 110
sp_cancel_adding_invoice 5541 5541 0 0.00 5 87042 995 0 0
sp_cancel_client_order 1789 1789 0 0.00 5 13099 338 0 0
sp_cancel_customer_reserve 545018 545018 0 0.00 1 24187 74 0 0
sp_cancel_pay_from_customer 698 698 0 0.00 3 8367 108 0 0
sp_cancel_pay_to_supplier 402 402 0 0.00 3 6421 96 0 0
sp_cancel_supplier_invoice 6130 6126 4 0.06 2 33053 1042 0 4
sp_cancel_supplier_order 414 414 0 0.00 7 1471422 124164 0 0
sp_cancel_write_off 10637 10637 0 0.00 1 25164 127 0 0
sp_client_order 20162 20162 0 0.00 4 15729 416 0 0
sp_customer_reserve 467877 467877 0 0.00 0 60026 164 0 0
sp_fill_shopping_cart 31251 31251 0 0.00 1 25560 45 0 0
sp_fill_shopping_cart_clo_res 467873 467873 0 0.00 0 22506 17 0 0
sp_get_clo_for_invoice 4993 4993 0 0.00 0 54091 383 0 0
sp_kill_cost_storno 17278 17278 0 0.00 0 10472 70 0 0
sp_kill_qstorno_ret_qs2qd 1107213 1107213 0 0.00 0 32998 40 0 0
sp_kill_qty_storno 584411 584411 0 0.00 0 33000 86 0 0
sp_lock_selected_doc 20763 20763 0 0.00 0 7701 7 0 0
sp_make_cost_storno 27974 27974 0 0.00 0 14727 51 0 0
sp_multiply_rows_for_pdistr 27974 27974 0 0.00 0 9540 35 0 0
sp_multiply_rows_for_qdistr 20162 20162 0 0.00 0 12892 229 0 0
sp_pay_from_customer 7237 7237 0 0.00 4 10942 110 0 0
sp_pay_to_supplier 5851 5851 0 0.00 5 14751 131 0 0
sp_payment_common 13088 13088 0 0.00 1 6460 19 0 0
sp_qd_handle_on_cancel_clo 1789 1789 0 0.00 0 13092 305 0 0
sp_qd_handle_on_invoice_upd_sts 10533 10533 0 0.00 0 16861 381 0 0
sp_qd_handle_on_reserve_upd_sts 9894 9894 0 0.00 0 13589 56 0 0
sp_reserve_write_off 9894 9894 0 0.00 7 13633 182 0 0
sp_supplier_invoice 5505 5505 0 0.00 6 87855 3422 0 0
sp_supplier_order 5587 5587 0 0.00 5 36904 1363 0 0
srv_aggregate_perf_data 102 102 0 0.00 2824 143886 17417 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2107 121441 47208 0 0
srv_find_qd_qs_mism 1051192 1051192 0 0.00 0 11394 6 0 0
srv_make_invnt_saldo 455 455 0 0.00 40 96995 3909 0 0
srv_make_money_saldo 737 737 0 0.00 1 14189 595 0 0
srv_recalc_idx_stat 4 4 0 0.00 167832 578649 376509 0 0
t$perf-abend:sp_add_invoice_to_stock 110 110 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5011 5011 0 0.00 0 30888 188 0 0
t$perf-norm:sp_cancel_adding_invoice 404 404 0 0.00 0 3309 121 0 0
t$perf-norm:sp_cancel_client_order 1789 1789 0 0.00 0 1439 12 0 0
t$perf-norm:sp_cancel_customer_reserve 730 730 0 0.00 0 2761 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 698 698 0 0.00 0 2442 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 402 402 0 0.00 0 1915 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 403 403 0 0.00 0 2619 24 0 0
t$perf-norm:sp_cancel_supplier_order 414 414 0 0.00 0 26039 1730 0 0
t$perf-norm:sp_cancel_write_off 1037 1037 0 0.00 0 1399 8 0 0
t$perf-norm:sp_client_order 20162 20162 0 0.00 0 8456 19 0 0
t$perf-norm:sp_customer_reserve 1015 1015 0 0.00 0 4875 14 0 0
t$perf-norm:sp_pay_from_customer 7237 7237 0 0.00 0 10414 9 0 0
t$perf-norm:sp_pay_to_supplier 5851 5851 0 0.00 0 5385 9 0 0
t$perf-norm:sp_reserve_write_off 9894 9894 0 0.00 0 4255 10 0 0
t$perf-norm:sp_supplier_invoice 5505 5505 0 0.00 0 10395 23 0 0
t$perf-norm:sp_supplier_order 5587 5587 0 0.00 0 3342 15 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 385 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 556 13 0 0
t$perf-norm:srv_make_invnt_saldo 455 455 0 0.00 0 7495 31 0 0
t$perf-norm:srv_make_money_saldo 737 737 0 0.00 0 3473 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 10 4 0 0
v_add_invoice_to_stock 10204 9984 220 2.15 0 4608 19 0 220
v_all_customers 37328 37328 0 0.00 0 142 1 0 0
v_all_suppliers 30720 30720 0 0.00 0 25 1 0 0
v_all_wares 40324 40324 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 808 808 0 0.00 0 2505 8 0 0
v_cancel_client_order 3578 3578 0 0.00 0 2204 6 0 0
v_cancel_customer_prepayment 1396 1396 0 0.00 0 2901 9 0 0
v_cancel_customer_reserve 1460 1460 0 0.00 0 2901 8 0 0
v_cancel_payment_to_supplier 804 804 0 0.00 0 2597 11 0 0
v_cancel_supplier_invoice 814 806 8 0.98 0 2713 19 0 8
v_cancel_supplier_order 828 828 0 0.00 0 1241 9 0 0
v_cancel_write_off 2074 2074 0 0.00 0 5799 19 0 0
v_max_id_clo_ord 5587 5587 0 0.00 0 1223 2 0 0
v_max_id_clo_res 1015 1015 0 0.00 0 9507 28 0 0
v_max_id_ord_sup 5502 5502 0 0.00 0 9563 6 0 0
v_max_non_paid_invoice 1579 1579 0 0.00 0 762 3 0 0
v_max_non_paid_realizn 3705 3705 0 0.00 0 1736 3 0 0
v_min_id_clo_ord 5587 5587 0 0.00 0 1067 4 0 0
v_min_id_clo_res 1015 1015 0 0.00 0 10696 21 0 0
v_min_id_ord_sup 5502 5502 0 0.00 0 25525 17 0 0
v_min_non_paid_invoice 5851 5851 0 0.00 0 5386 11 0 0
v_min_non_paid_realizn 7237 7237 0 0.00 0 6441 14 0 0
v_our_firm 10060 10060 0 0.00 0 17 1 0 0
v_reserve_write_off 19788 19788 0 0.00 0 6438 6 0 0
x_lock_depdocs_on_canc_invoice 11264 11264 0 0.00 0 13780 127 0 0
x_lock_depdocs_on_canc_sup_ord 414 414 0 0.00 0 7830 368 0 0
x_make_qstorno_1000_1200 5587 5587 0 0.00 2 36892 1283 0 0
x_make_qstorno_1200_2000 5505 5505 0 0.00 3 87847 3249 0 0
x_make_qstorno_2100_3300 467877 467877 0 0.00 0 60024 145 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-01-2021 13:28:06 to 02-01-2021 13:28:10.

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
10:56:58 7 8192000000 8796637808 8807108608 3167696 0.038 1 1 2 0 3265392 61840 35856
10:57:59 15582 8192000000 9721874656 10016714752 798657008 8.883 100 86 172 0 827966144 7966336 21342800
10:59:22 24343 8192000000 9770856384 10081882112 845991840 9.360 100 88 175 2 877921360 11402816 20526704
11:00:54 16885 8192000000 9817202432 10135818240 881594656 9.716 100 83 166 1 922496768 21540720 19361392
11:02:16 11057 8192000000 9858081536 10192003072 916087632 10.057 100 75 148 2 965170272 32948880 16133760
11:03:28 10334 8192000000 9928247920 10274504704 963353408 10.522 100 89 176 1 1046749120 65369872 18025840
11:04:43 7631 8192000000 9957115056 10315591680 996199568 10.842 100 83 164 1 1074960096 61627632 17132896
11:05:54 2373 8192000000 10000268592 10363658240 1030578720 11.174 100 90 180 4 1114896768 66036016 18282032
11:06:58 1705 8192000000 10028383408 10395115520 1058805344 11.445 100 87 174 2 1141543104 64920752 17817008
11:08:02 1469 8192000000 10046934880 10419417088 1078768400 11.636 100 92 184 0 1161756448 64258896 18729152
11:09:05 4944 8192000000 10022944304 10417188864 1081203232 11.659 100 46 92 1 1141657264 51262160 9191872
11:10:13 6559 8192000000 10010422592 10407325696 1090450912 11.747 100 36 72 1 1131538464 33277584 7809968
11:11:20 30769 8192000000 10011560608 10418262016 1093094736 11.772 100 24 48 3 1136507888 38980368 4432784
11:12:52 4441 8192000000 10019713840 10433667072 1095549264 11.795 100 28 56 0 1140979488 40127744 5302480
11:13:57 3962 8192000000 10016406560 10414182400 1100262944 11.840 100 44 81 4 1126279280 16155728 9860608
11:15:03 74045 8192000000 10006931056 10420858880 1103796032 11.874 100 33 66 4 1146547280 35091712 7659536
11:17:18 5444 8192000000 10020209264 10416443392 1088573840 11.729 99 32 64 1 1119992544 24564400 6854304
11:18:26 5773 8192000000 9979286256 10369593344 1037195968 11.238 98 49 99 1 1073004240 24952832 10855440
11:19:40 5376 8192000000 9943254256 10331058176 1017944976 11.052 98 34 68 3 1046456304 20303280 8208048
11:20:49 52943 8192000000 9947132048 10349375488 1001057808 10.889 97 46 92 6 1033051120 22400832 9592480
11:22:46 8027 8192000000 9967438016 10358226944 1040280064 11.267 100 39 77 4 1066989344 17960608 8748672
11:23:57 8540 8192000000 9967388064 10348576768 1048606112 11.347 100 39 76 0 1079599808 22270480 8723216
11:25:07 4831 8192000000 9971514832 10363613184 1045165440 11.314 99 47 96 4 1072066592 17050048 9851104
11:26:12 65063 8192000000 9974463088 10372272128 1062030560 11.476 100 39 75 2 1087588336 16941936 8615840
11:28:24 30394 8192000000 10036465536 10433044480 1088070752 11.724 100 56 112 3 1156466208 56818224 11577232
11:30:03 9698 8192000000 10052716096 10447831040 1104380208 11.879 100 58 112 2 1161399776 45231456 11788112
11:31:14 21511 8192000000 10094396672 10502393856 1115990352 11.989 100 45 90 1 1206222432 80553776 9678304
11:32:47 13519 8192000000 10115456176 10520576000 1134894912 12.167 100 45 90 2 1220449552 76017968 9536672
11:34:05 24525 8192000000 10147540736 10559377408 1140080816 12.216 100 41 82 1 1258196400 109112640 9002944
11:35:32 6848 8192000000 10203506432 10629332992 1148284016 12.293 100 60 120 4 1310966944 149176464 13506464
11:36:48 8536 8192000000 10216955440 10635640832 1162204672 12.424 100 58 116 1 1317880176 142960608 12714896
11:38:04 16128 8192000000 10226899568 10659545088 1170963344 12.506 100 33 65 1 1332913136 154074848 7874944
11:39:24 2709 8192000000 10229780544 10638536704 1173186336 12.527 100 67 132 2 1336849760 148513600 15149824
11:40:32 15448 8192000000 10282176688 10695540736 1177357744 12.566 100 61 118 3 1387178352 196500640 13319968
11:41:57 2147 8192000000 10281294192 10679767040 1161316416 12.416 99 76 153 0 1378045248 199383024 17345808
11:43:06 6606 8192000000 10311904944 10723577856 1186241840 12.648 100 64 125 0 1410010736 209224976 14543920
11:44:15 3588 8192000000 10361464880 10764713984 1176702576 12.559 100 81 156 2 1467048128 272089040 18256512
11:45:22 2991 8192000000 10383182464 10781454336 1155891760 12.365 99 87 174 1 1473517168 297217648 20407760
11:46:31 4493 8192000000 10374141920 10791399424 1159066144 12.395 100 60 120 0 1483737072 309952656 14718272
11:47:57 3750 8192000000 10388936784 10796556288 1153866960 12.346 99 60 117 0 1483731792 315120800 14744032
11:49:09 2102 8192000000 10443071056 10850766848 1153460768 12.342 100 83 166 0 1538815184 365017264 20337152
11:50:16 3567 8192000000 10486574304 10898333696 1164113248 12.442 100 75 151 1 1579795520 397186144 18496128
11:51:22 9643 8192000000 10386630064 10857697280 1138299648 12.200 98 40 80 2 1510441136 361386784 10754704
11:52:32 14550 8192000000 10437468304 10867236864 1158980512 12.394 100 33 64 0 1532927024 365199312 8747200
11:53:48 25426 8192000000 10423381200 10867052544 1156163728 12.367 100 38 76 3 1508450832 342975424 9311680
11:55:19 30554 8192000000 10387270992 10853474304 1111269312 11.944 98 24 48 0 1499915584 381592960 7053312
11:56:58 121439 8192000000 10106392448 10590277632 1056218624 11.420 94 7 14 1 1109335536 52152432 964480
12:00:31 84821 8192000000 10081520832 10547138560 1108169216 11.915 98 26 52 3 1166400528 53973920 4257392
12:02:58 101331 8192000000 10085465152 10508378112 1096948304 11.809 97 17 34 3 1194792832 93990416 3854112
12:05:40 79569 8192000000 10067073008 10565410816 1113204320 11.963 99 8 16 0 1201461136 86842144 1414672
12:08:00 94946 8192000000 10057225248 10504667136 1133170736 12.151 100 12 24 0 1166626368 31092048 2363584
12:11:24 64259 8192000000 10122842816 10561220608 1147960048 12.290 100 36 72 0 1215454224 59128528 8365648
12:13:29 50489 8192000000 10114081872 10595074048 1142084128 12.235 99 14 29 1 1210001568 64764240 3153200
12:15:21 59204 8192000000 10105503248 10579365888 1116391936 11.993 97 14 27 1 1162435664 43121024 2922704
12:17:25 54869 8192000000 10086230704 10551496704 1149235600 12.302 100 20 40 1 1191831536 38180416 4415520
12:19:27 52542 8192000000 10071667824 10548805632 1147576576 12.287 100 25 51 0 1202030960 48916880 5537504
12:21:20 82458 8192000000 10130246752 10610933760 1132984464 12.149 98 22 44 1 1218118816 80135280 4999072
12:23:56 52361 8192000000 10101465888 10619248640 1144774432 12.260 100 12 24 1 1214514480 67157920 2582128
12:25:49 17581 8192000000 10091690432 10524958720 1130604272 12.127 99 57 114 1 1163188192 20876304 11707616
12:27:09 35111 8192000000 10125655088 10580983808 1129605888 12.118 100 36 72 2 1214708576 77660320 7442368
12:28:44 46165 8192000000 10111168544 10568667136 1111480912 11.946 98 25 50 1 1206666608 89225648 5960048
12:30:32 38688 8192000000 10102306720 10537521152 1098927616 11.827 98 31 62 1 1170852656 65509984 6415056
12:32:18 50875 8192000000 10078368480 10547265536 1066801136 11.522 95 12 24 1 1114508400 45056112 2651152
12:34:11 81830 8192000000 10043569040 10507653120 1068873376 11.541 95 19 38 0 1143169648 70357280 3938992
12:37:18 56752 8192000000 10037959760 10497236992 1121949760 12.045 100 10 20 0 1143395664 19872912 1572992
12:39:23 38598 8192000000 10048450192 10500915200 1102405696 11.860 98 7 12 1 1143929648 40279216 1244736
12:41:05 62388 8192000000 10096361392 10538385408 1068258416 11.535 95 20 40 0 1129351424 57299824 3793184
12:43:08 23143 8192000000 10069273440 10496409600 1094952256 11.790 98 36 72 0 1158062320 55754672 7355392
12:44:31 41867 8192000000 10115062592 10572595200 1125393744 12.078 100 31 62 3 1221059888 89036496 6629648
12:46:17 48117 8192000000 10144915424 10580504576 1107840112 11.912 98 21 42 1 1208451824 96072416 4539296
12:48:11 70408 8192000000 10102038608 10544001024 1075887200 11.608 95 14 27 1 1117197120 38191984 3117936
12:50:32 36062 8192000000 10116139152 10566905856 1126469488 12.088 99 32 64 0 1209982160 76715328 6797344
12:52:13 70123 8192000000 10074387872 10543321088 1099095440 11.829 97 10 20 1 1125001728 24001552 1904736
12:54:24 96850 8192000000 10085222832 10554187776 1140550896 12.221 100 19 38 0 1204454256 59898736 4004624
12:57:11 40192 8192000000 10106831072 10563371008 1147275056 12.284 100 9 17 1 1207279920 58100416 1904448
12:59:11 78276 8192000000 10121189360 10639839232 1121513584 12.041 98 6 12 1 1174823792 51697504 1612704
13:01:30 100894 8192000000 10079447440 10582679552 1110931376 11.941 97 18 36 0 1189921664 75180336 3809952
13:04:12 60297 8192000000 10073487584 10523648000 1137927152 12.196 100 18 36 0 1170656576 29054048 3675376
13:06:32 81093 8192000000 10083337344 10562166784 1124721792 12.072 98 35 69 0 1204840992 72839888 7279312
13:09:09 77753 8192000000 10107653904 10570080256 1124986832 12.074 97 12 24 0 1163835952 36820128 2028992
13:11:28 115867 8192000000 10053277424 10534973440 1134872128 12.167 99 19 38 1 1181992992 43358080 3762784
13:14:31 38686 8192000000 10093951056 10580017152 1139109824 12.207 99 8 16 1 1176412080 35259216 2043040
13:16:10 73311 8192000000 10050354752 10489389056 1069496976 11.547 94 21 42 2 1104406384 30638512 4270896
13:18:24 42485 8192000000 10104766672 10541809664 1111951040 11.951 98 28 56 3 1179855536 61508560 6395936
13:20:07 64550 8192000000 10137480592 10614738944 1087896480 11.723 96 21 42 0 1207931888 115351888 4683520
13:22:26 121062 8192000000 10069299456 10499588096 1097459744 11.814 97 22 44 1 1175913168 73955936 4497488
13:25:28 90146 8192000000 9486932112 9859637248 539750864 6.181 48 8 16 2 594548992 53031808 1766320

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 02-01-2021 13:28:12 to 02-01-2021 13:28:26.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 110 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 02-01-2021 13:28:27 to 02-01-2021 13:28:29.

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          236429
MON$OLDEST_ACTIVE               237014
MON$OLDEST_SNAPSHOT             237014
MON$NEXT_TRANSACTION            237014
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                       2109120
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                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             806
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2315 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2315 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2315 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2315 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:68: 
:69: mscs-test-161.budzdorov.ru    Sat Jan  2 13:27:01 2021
:70:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:71: 
:72: 
:73: mscs-test-161.budzdorov.ru    Sat Jan  2 13:27:06 2021
:74:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:75: 
:76: 
:77: mscs-test-161.budzdorov.ru    Sat Jan  2 13:27:06 2021
:78:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:79: 
--- end of diff output ---

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

02.01.21 13:28:30. 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-01-02 13:28:33 No severe PSQL-related problems occured
02.01.21 13:28:34. End of report.