builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-022 starttime: 1503623037.14 results: success (0) revision: 3199bacd6b3827d711155a9e81c081434cf7cd8f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-24 18:03:57.136761) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-24 18:03:57.137214) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-24 18:03:57.137511) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-24 18:03:57.153908) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-24 18:03:57.154240) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=930c87414c89fc706ee2024c000001c6-1503623036.536778-49172866 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-24 18:03:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 40.9M=0s 2017-08-24 18:03:57 (40.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.125851 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-24 18:03:57.295903) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-24 18:03:57.296633) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=930c87414c89fc706ee2024c000001c6-1503623036.536778-49172866 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016364 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-24 18:03:57.347687) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-24 18:03:57.348019) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3199bacd6b3827d711155a9e81c081434cf7cd8f --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 3199bacd6b3827d711155a9e81c081434cf7cd8f --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=930c87414c89fc706ee2024c000001c6-1503623036.536778-49172866 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-24 18:03:57,563 truncating revision to first 12 chars 2017-08-24 18:03:57,563 Setting DEBUG logging. 2017-08-24 18:03:57,563 attempt 1/10 2017-08-24 18:03:57,563 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3199bacd6b38?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-24 18:03:57,957 unpacking tar archive at: mozilla-central-3199bacd6b38/testing/mozharness/ program finished with exit code 0 elapsedTime=0.724258 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-24 18:03:58.087626) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-24 18:03:58.088029) ========= script_repo_revision: 3199bacd6b3827d711155a9e81c081434cf7cd8f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-24 18:03:58.088488) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-24 18:03:58.088994) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-24 18:03:58.101324) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2017-08-24 18:03:58.101641) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=930c87414c89fc706ee2024c000001c6-1503623036.536778-49172866 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:03:58 INFO - MultiFileLogger online at 20170824 18:03:58 in /builds/slave/test 18:03:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 18:03:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:03:58 INFO - {'append_to_log': False, 18:03:58 INFO - 'base_work_dir': '/builds/slave/test', 18:03:58 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 18:03:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:03:58 INFO - 'branch': 'Firefox-Non-PGO', 18:03:58 INFO - 'buildbot_json_path': 'buildprops.json', 18:03:58 INFO - 'code_coverage': False, 18:03:58 INFO - 'config_files': ('talos/linux_config.py',), 18:03:58 INFO - 'default_actions': ('clobber', 18:03:58 INFO - 'read-buildbot-config', 18:03:58 INFO - 'download-and-extract', 18:03:58 INFO - 'populate-webroot', 18:03:58 INFO - 'create-virtualenv', 18:03:58 INFO - 'install', 18:03:58 INFO - 'setup-mitmproxy', 18:03:58 INFO - 'run-tests'), 18:03:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:03:58 INFO - 'disable_ccov_upload': False, 18:03:58 INFO - 'download_minidump_stackwalk': True, 18:03:58 INFO - 'download_symbols': 'ondemand', 18:03:58 INFO - 'enable_stylo': False, 18:03:58 INFO - 'enable_webrender': False, 18:03:58 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 18:03:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:03:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:03:58 INFO - 'gecko_profile': False, 18:03:58 INFO - 'gecko_profile_interval': 0, 18:03:58 INFO - 'installer_path': 'installer.exe', 18:03:58 INFO - 'log_level': 'info', 18:03:58 INFO - 'log_name': 'talos', 18:03:58 INFO - 'log_to_console': True, 18:03:58 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:03:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:03:58 INFO - 'opt_config_files': (), 18:03:58 INFO - 'pip_index': False, 18:03:58 INFO - 'suite': 'dromaeojs-e10s', 18:03:58 INFO - 'system_bits': '32', 18:03:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:03:58 INFO - 'title': 'talos-linux64-ix-022', 18:03:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:03:58 INFO - 'use_talos_json': True, 18:03:58 INFO - 'verify': 'False', 18:03:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:03:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:03:58 INFO - 'work_dir': 'build'} 18:03:58 INFO - [mozharness: 2017-08-25 01:03:58.455905Z] Running clobber step. 18:03:58 INFO - Running pre-action listener: _resource_record_pre_action 18:03:58 INFO - Running main action method: clobber 18:03:58 INFO - rmtree: /builds/slave/test/build 18:03:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:04:00 INFO - Running post-action listener: _resource_record_post_action 18:04:00 INFO - [mozharness: 2017-08-25 01:04:00.148199Z] Finished clobber step (success) 18:04:00 INFO - [mozharness: 2017-08-25 01:04:00.148297Z] Running read-buildbot-config step. 18:04:00 INFO - Running pre-action listener: _resource_record_pre_action 18:04:00 INFO - Running main action method: read_buildbot_config 18:04:00 INFO - Using buildbot properties: 18:04:00 INFO - { 18:04:00 INFO - "project": "", 18:04:00 INFO - "product": "firefox", 18:04:00 INFO - "who": "ryanvm@gmail.com", 18:04:00 INFO - "installer_path": "public/build/target.tar.bz2", 18:04:00 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 18:04:00 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 18:04:00 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 18:04:00 INFO - "stage_platform": "linux64", 18:04:00 INFO - "basedir": "/builds/slave/test", 18:04:00 INFO - "buildnumber": 1100, 18:04:00 INFO - "platform": "ubuntu64_hw", 18:04:00 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 18:04:00 INFO - "slavebuilddir": "test", 18:04:00 INFO - "taskId": "OsjFxLwxREKlWj-pQPPbjA", 18:04:00 INFO - "branch": "mozilla-central", 18:04:00 INFO - "script_repo_revision": "production", 18:04:00 INFO - "revision": "3199bacd6b3827d711155a9e81c081434cf7cd8f", 18:04:00 INFO - "slavename": "talos-linux64-ix-022", 18:04:00 INFO - "repo_path": "mozilla-central" 18:04:00 INFO - } 18:04:00 INFO - Finding installer, test and symbols from parent task. 18:04:00 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OsjFxLwxREKlWj-pQPPbjA'}, attempt #1 18:04:00 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OsjFxLwxREKlWj-pQPPbjA'}, attempt #1 18:04:01 INFO - Task dependencies: Amqe4HB8QW2hqpHBtZHK2w 18:04:01 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Amqe4HB8QW2hqpHBtZHK2w'}, attempt #1 18:04:01 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Amqe4HB8QW2hqpHBtZHK2w/artifacts/public/build/target.tar.bz2 18:04:01 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/Amqe4HB8QW2hqpHBtZHK2w/artifacts/public/build/target.test_packages.json 18:04:01 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Amqe4HB8QW2hqpHBtZHK2w/artifacts/public/build/target.crashreporter-symbols.zip 18:04:01 INFO - Running post-action listener: _resource_record_post_action 18:04:01 INFO - [mozharness: 2017-08-25 01:04:01.541889Z] Finished read-buildbot-config step (success) 18:04:01 INFO - [mozharness: 2017-08-25 01:04:01.542107Z] Running download-and-extract step. 18:04:01 INFO - Running pre-action listener: _resource_record_pre_action 18:04:01 INFO - Running main action method: download_and_extract 18:04:01 INFO - mkdir: /builds/slave/test/build/tests 18:04:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:01 INFO - trying https://queue.taskcluster.net/v1/task/Amqe4HB8QW2hqpHBtZHK2w/artifacts/public/build/target.test_packages.json 18:04:01 INFO - Downloading https://queue.taskcluster.net/v1/task/Amqe4HB8QW2hqpHBtZHK2w/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 18:04:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Amqe4HB8QW2hqpHBtZHK2w/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 18:04:02 INFO - Downloaded 989 bytes. 18:04:02 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 18:04:02 INFO - Using the following test package requirements: 18:04:02 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 18:04:02 INFO - u'common': [u'target.common.tests.zip'], 18:04:02 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 18:04:02 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 18:04:02 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 18:04:02 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 18:04:02 INFO - u'mozbase': [u'target.common.tests.zip'], 18:04:02 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 18:04:02 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 18:04:02 INFO - u'web-platform': [u'target.common.tests.zip', 18:04:02 INFO - u'target.web-platform.tests.tar.gz'], 18:04:02 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 18:04:02 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 18:04:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Amqe4HB8QW2hqpHBtZHK2w/artifacts/public/build/target.common.tests.zip 18:04:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Amqe4HB8QW2hqpHBtZHK2w/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:04:02 INFO - Fetch https://queue.taskcluster.net/v1/task/Amqe4HB8QW2hqpHBtZHK2w/artifacts/public/build/target.common.tests.zip into memory 18:04:03 INFO - Content-Length response header: 54980109 18:04:03 INFO - Bytes received: 54980109 18:04:14 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 18:04:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Amqe4HB8QW2hqpHBtZHK2w/artifacts/public/build/target.common.tests.zip 18:04:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Amqe4HB8QW2hqpHBtZHK2w/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:04:14 INFO - Fetch https://queue.taskcluster.net/v1/task/Amqe4HB8QW2hqpHBtZHK2w/artifacts/public/build/target.common.tests.zip into memory 18:04:15 INFO - Content-Length response header: 54980109 18:04:15 INFO - Bytes received: 54980109 18:04:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Amqe4HB8QW2hqpHBtZHK2w/artifacts/public/build/target.talos.tests.zip 18:04:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Amqe4HB8QW2hqpHBtZHK2w/artifacts/public/build/target.talos.tests.zip'}, attempt #1 18:04:28 INFO - Fetch https://queue.taskcluster.net/v1/task/Amqe4HB8QW2hqpHBtZHK2w/artifacts/public/build/target.talos.tests.zip into memory 18:04:29 INFO - Content-Length response header: 14496555 18:04:29 INFO - Bytes received: 14496555 18:04:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:29 INFO - trying https://queue.taskcluster.net/v1/task/Amqe4HB8QW2hqpHBtZHK2w/artifacts/public/build/target.tar.bz2 18:04:29 INFO - Downloading https://queue.taskcluster.net/v1/task/Amqe4HB8QW2hqpHBtZHK2w/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 18:04:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Amqe4HB8QW2hqpHBtZHK2w/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 18:04:30 INFO - Downloaded 61070188 bytes. 18:04:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Amqe4HB8QW2hqpHBtZHK2w/artifacts/public/build/target.tar.bz2 18:04:31 INFO - mkdir: /builds/slave/test/properties 18:04:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:04:31 INFO - Writing to file /builds/slave/test/properties/build_url 18:04:31 INFO - Contents: 18:04:31 INFO - build_url:https://queue.taskcluster.net/v1/task/Amqe4HB8QW2hqpHBtZHK2w/artifacts/public/build/target.tar.bz2 18:04:31 INFO - Running post-action listener: _resource_record_post_action 18:04:31 INFO - Running post-action listener: find_tests_for_verification 18:04:31 INFO - Running post-action listener: set_extra_try_arguments 18:04:31 INFO - [mozharness: 2017-08-25 01:04:31.077808Z] Finished download-and-extract step (success) 18:04:31 INFO - [mozharness: 2017-08-25 01:04:31.078037Z] Running populate-webroot step. 18:04:31 INFO - Running pre-action listener: _resource_record_pre_action 18:04:31 INFO - Running main action method: populate_webroot 18:04:31 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:04:31 INFO - 'chromez-stylo-e10s': {'talos_options': ['--stylo'], 18:04:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:04:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:04:31 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--stylo'], 18:04:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:04:31 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:04:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:04:31 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:04:31 INFO - 'talos_options': ['--stylo'], 18:04:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:04:31 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:04:31 INFO - 'tests': ['damp', 'tps']}, 18:04:31 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:04:31 INFO - 'talos_options': ['--stylo'], 18:04:31 INFO - 'tests': ['damp', 'tps']}, 18:04:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:04:31 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:04:31 INFO - 'g4-stylo-e10s': {'talos_options': ['--stylo'], 18:04:31 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 18:04:31 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:04:31 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:04:31 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:04:31 INFO - 'talos_options': ['--stylo'], 18:04:31 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:04:31 INFO - 'other-e10s': {'tests': ['a11yr', 18:04:31 INFO - 'ts_paint', 18:04:31 INFO - 'tpaint', 18:04:31 INFO - 'sessionrestore', 18:04:31 INFO - 'sessionrestore_many_windows', 18:04:31 INFO - 'sessionrestore_no_auto_restore', 18:04:31 INFO - 'tabpaint']}, 18:04:31 INFO - 'other-stylo-e10s': {'talos_options': ['--stylo'], 18:04:31 INFO - 'tests': ['a11yr', 18:04:31 INFO - 'ts_paint', 18:04:31 INFO - 'tpaint', 18:04:31 INFO - 'sessionrestore', 18:04:31 INFO - 'sessionrestore_many_windows', 18:04:31 INFO - 'sessionrestore_no_auto_restore', 18:04:31 INFO - 'tabpaint']}, 18:04:31 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 18:04:31 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 18:04:31 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--stylo'], 18:04:31 INFO - 'tests': ['bloom_basic_singleton']}, 18:04:31 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--stylo'], 18:04:31 INFO - 'tests': ['bloom_basic']}, 18:04:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:04:31 INFO - 'tsvgr_opacity', 18:04:31 INFO - 'tart', 18:04:31 INFO - 'tscrollx', 18:04:31 INFO - 'tsvg_static']}, 18:04:31 INFO - 'svgr-stylo-e10s': {'talos_options': ['--stylo'], 18:04:31 INFO - 'tests': ['tsvgx', 18:04:31 INFO - 'tsvgr_opacity', 18:04:31 INFO - 'tart', 18:04:31 INFO - 'tscrollx', 18:04:31 INFO - 'tsvg_static']}, 18:04:31 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:04:31 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:04:31 INFO - 'talos_options': ['--stylo'], 18:04:31 INFO - 'tests': ['tp5o']}, 18:04:31 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:04:31 INFO - 'talos_options': ['--mitmproxy', 18:04:31 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:04:31 INFO - '--firstNonBlankPaint'], 18:04:31 INFO - 'tests': ['tp6_google', 18:04:31 INFO - 'tp6_youtube', 18:04:31 INFO - 'tp6_amazon', 18:04:31 INFO - 'tp6_facebook']}, 18:04:31 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:04:31 INFO - 'talos_options': ['--stylo', 18:04:31 INFO - '--mitmproxy', 18:04:31 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:04:31 INFO - '--firstNonBlankPaint'], 18:04:31 INFO - 'tests': ['tp6_google', 18:04:31 INFO - 'tp6_youtube', 18:04:31 INFO - 'tp6_amazon', 18:04:31 INFO - 'tp6_facebook']}, 18:04:31 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:04:31 INFO - 'talos_options': ['--stylo', 18:04:31 INFO - '--stylo-threads=1', 18:04:31 INFO - '--mitmproxy', 18:04:31 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:04:31 INFO - '--firstNonBlankPaint'], 18:04:31 INFO - 'tests': ['tp6_google', 18:04:31 INFO - 'tp6_youtube', 18:04:31 INFO - 'tp6_amazon', 18:04:31 INFO - 'tp6_facebook']}, 18:04:31 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:04:31 INFO - 'talos_options': ['--xperf_path', 18:04:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:04:31 INFO - 'tests': ['tp5n']}, 18:04:31 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:04:31 INFO - 'talos_options': ['--stylo', 18:04:31 INFO - '--xperf_path', 18:04:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:04:31 INFO - 'tests': ['tp5n']}}} 18:04:31 INFO - Running post-action listener: _resource_record_post_action 18:04:31 INFO - [mozharness: 2017-08-25 01:04:31.094943Z] Finished populate-webroot step (success) 18:04:31 INFO - [mozharness: 2017-08-25 01:04:31.095033Z] Running create-virtualenv step. 18:04:31 INFO - Running pre-action listener: _resource_record_pre_action 18:04:31 INFO - Running main action method: create_virtualenv 18:04:31 INFO - Creating virtualenv /builds/slave/test/build/venv 18:04:31 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:04:31 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 18:04:31 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 18:04:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:04:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:04:31 INFO - Installing distribute.............................................................................................................................................................................................done. 18:04:31 INFO - Installing pip...............done. 18:04:31 INFO - Return code: 0 18:04:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:04:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:04:32 INFO - Reading from file tmpfile_stdout 18:04:32 INFO - Output received: 18:04:32 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 18:04:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:04:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:04:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:04:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x249a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2665a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2808f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2806130>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2807030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28074c0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1503623036.536778-49172866', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:04:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:04:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:04:32 INFO - Using env: {'DISPLAY': ':0', 18:04:32 INFO - 'HOME': '/home/cltbld', 18:04:32 INFO - 'LANG': 'en_US.UTF-8', 18:04:32 INFO - 'LANGUAGE': 'en_US:en', 18:04:32 INFO - 'LOGNAME': 'cltbld', 18:04:32 INFO - 'MAIL': '/var/mail/cltbld', 18:04:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:32 INFO - 'MOZ_NO_REMOTE': '1', 18:04:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:04:32 INFO - 'NO_EM_RESTART': '1', 18:04:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:04:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:04:32 INFO - 'PWD': '/builds/slave/test', 18:04:32 INFO - 'SHELL': '/bin/bash', 18:04:32 INFO - 'SHLVL': '1', 18:04:32 INFO - 'TERM': 'linux', 18:04:32 INFO - 'TMOUT': '86400', 18:04:32 INFO - 'USER': 'cltbld', 18:04:32 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1503623036.536778-49172866', 18:04:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:32 INFO - '_': '/tools/buildbot/bin/python'} 18:04:33 INFO - Ignoring indexes: http://pypi.python.org/simple/ 18:04:33 INFO - Downloading/unpacking pip>=1.5 18:04:33 INFO - Running setup.py egg_info for package pip 18:04:33 INFO - warning: no files found matching 'pip/cacert.pem' 18:04:33 INFO - warning: no files found matching '*.html' under directory 'docs' 18:04:33 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 18:04:33 INFO - no previously-included directories found matching 'docs/_build/_sources' 18:04:33 INFO - Installing collected packages: pip 18:04:33 INFO - Found existing installation: pip 1.1 18:04:33 INFO - Uninstalling pip: 18:04:33 INFO - Successfully uninstalled pip 18:04:33 INFO - Running setup.py install for pip 18:04:33 INFO - warning: no files found matching 'pip/cacert.pem' 18:04:33 INFO - warning: no files found matching '*.html' under directory 'docs' 18:04:33 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 18:04:33 INFO - no previously-included directories found matching 'docs/_build/_sources' 18:04:33 INFO - Installing pip script to /builds/slave/test/build/venv/bin 18:04:33 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 18:04:33 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 18:04:33 INFO - Successfully installed pip 18:04:33 INFO - Cleaning up... 18:04:33 INFO - Return code: 0 18:04:33 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:04:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:04:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:04:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x249a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2665a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2808f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2806130>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2807030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28074c0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1503623036.536778-49172866', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:04:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:04:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:04:33 INFO - Using env: {'DISPLAY': ':0', 18:04:33 INFO - 'HOME': '/home/cltbld', 18:04:33 INFO - 'LANG': 'en_US.UTF-8', 18:04:33 INFO - 'LANGUAGE': 'en_US:en', 18:04:33 INFO - 'LOGNAME': 'cltbld', 18:04:33 INFO - 'MAIL': '/var/mail/cltbld', 18:04:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:33 INFO - 'MOZ_NO_REMOTE': '1', 18:04:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:04:33 INFO - 'NO_EM_RESTART': '1', 18:04:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:04:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:04:33 INFO - 'PWD': '/builds/slave/test', 18:04:33 INFO - 'SHELL': '/bin/bash', 18:04:33 INFO - 'SHLVL': '1', 18:04:33 INFO - 'TERM': 'linux', 18:04:33 INFO - 'TMOUT': '86400', 18:04:33 INFO - 'USER': 'cltbld', 18:04:33 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1503623036.536778-49172866', 18:04:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:33 INFO - '_': '/tools/buildbot/bin/python'} 18:04:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:04:33 INFO - Downloading/unpacking psutil>=3.1.1 18:04:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:04:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:04:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:04:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:04:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:04:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:04:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:04:34 INFO - Installing collected packages: psutil 18:04:34 INFO - Running setup.py install for psutil 18:04:34 INFO - building 'psutil._psutil_linux' extension 18:04:34 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 18:04:34 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 18:04:34 INFO - building 'psutil._psutil_posix' extension 18:04:34 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 18:04:35 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 18:04:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:04:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:04:35 INFO - Successfully installed psutil 18:04:35 INFO - Cleaning up... 18:04:35 INFO - Return code: 0 18:04:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:04:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:04:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:04:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x249a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2665a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2808f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2806130>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2807030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28074c0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1503623036.536778-49172866', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:04:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:04:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:04:35 INFO - Using env: {'DISPLAY': ':0', 18:04:35 INFO - 'HOME': '/home/cltbld', 18:04:35 INFO - 'LANG': 'en_US.UTF-8', 18:04:35 INFO - 'LANGUAGE': 'en_US:en', 18:04:35 INFO - 'LOGNAME': 'cltbld', 18:04:35 INFO - 'MAIL': '/var/mail/cltbld', 18:04:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:35 INFO - 'MOZ_NO_REMOTE': '1', 18:04:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:04:35 INFO - 'NO_EM_RESTART': '1', 18:04:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:04:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:04:35 INFO - 'PWD': '/builds/slave/test', 18:04:35 INFO - 'SHELL': '/bin/bash', 18:04:35 INFO - 'SHLVL': '1', 18:04:35 INFO - 'TERM': 'linux', 18:04:35 INFO - 'TMOUT': '86400', 18:04:35 INFO - 'USER': 'cltbld', 18:04:35 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1503623036.536778-49172866', 18:04:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:35 INFO - '_': '/tools/buildbot/bin/python'} 18:04:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:04:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:04:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:04:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:04:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:04:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:04:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:04:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:04:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:04:36 INFO - Installing collected packages: mozsystemmonitor 18:04:36 INFO - Running setup.py install for mozsystemmonitor 18:04:36 INFO - Successfully installed mozsystemmonitor 18:04:36 INFO - Cleaning up... 18:04:36 INFO - Return code: 0 18:04:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:04:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:04:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:04:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x249a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2665a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2808f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2806130>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2807030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28074c0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1503623036.536778-49172866', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:04:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:04:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:04:36 INFO - Using env: {'DISPLAY': ':0', 18:04:36 INFO - 'HOME': '/home/cltbld', 18:04:36 INFO - 'LANG': 'en_US.UTF-8', 18:04:36 INFO - 'LANGUAGE': 'en_US:en', 18:04:36 INFO - 'LOGNAME': 'cltbld', 18:04:36 INFO - 'MAIL': '/var/mail/cltbld', 18:04:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:36 INFO - 'MOZ_NO_REMOTE': '1', 18:04:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:04:36 INFO - 'NO_EM_RESTART': '1', 18:04:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:04:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:04:36 INFO - 'PWD': '/builds/slave/test', 18:04:36 INFO - 'SHELL': '/bin/bash', 18:04:36 INFO - 'SHLVL': '1', 18:04:36 INFO - 'TERM': 'linux', 18:04:36 INFO - 'TMOUT': '86400', 18:04:36 INFO - 'USER': 'cltbld', 18:04:36 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1503623036.536778-49172866', 18:04:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:36 INFO - '_': '/tools/buildbot/bin/python'} 18:04:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:04:36 INFO - Downloading/unpacking jsonschema==2.5.1 18:04:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:04:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:04:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:04:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:04:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:04:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:04:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:04:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:04:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:04:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:04:38 INFO - Downloading functools32-3.2.3-2.tar.gz 18:04:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:04:38 INFO - warning: no files found matching '*.txt' 18:04:38 INFO - no previously-included directories found matching 'build' 18:04:38 INFO - no previously-included directories found matching 'dist' 18:04:38 INFO - no previously-included directories found matching '.git*' 18:04:38 INFO - Installing collected packages: jsonschema, functools32 18:04:38 INFO - Running setup.py install for functools32 18:04:38 INFO - warning: no files found matching '*.txt' 18:04:38 INFO - no previously-included directories found matching 'build' 18:04:38 INFO - no previously-included directories found matching 'dist' 18:04:38 INFO - no previously-included directories found matching '.git*' 18:04:38 INFO - Successfully installed jsonschema functools32 18:04:38 INFO - Cleaning up... 18:04:38 INFO - Return code: 0 18:04:38 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:04:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:04:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:04:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x249a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2665a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2808f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2806130>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2807030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28074c0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1503623036.536778-49172866', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:04:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:04:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:04:38 INFO - Using env: {'DISPLAY': ':0', 18:04:38 INFO - 'HOME': '/home/cltbld', 18:04:38 INFO - 'LANG': 'en_US.UTF-8', 18:04:38 INFO - 'LANGUAGE': 'en_US:en', 18:04:38 INFO - 'LOGNAME': 'cltbld', 18:04:38 INFO - 'MAIL': '/var/mail/cltbld', 18:04:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:38 INFO - 'MOZ_NO_REMOTE': '1', 18:04:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:04:38 INFO - 'NO_EM_RESTART': '1', 18:04:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:04:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:04:38 INFO - 'PWD': '/builds/slave/test', 18:04:38 INFO - 'SHELL': '/bin/bash', 18:04:38 INFO - 'SHLVL': '1', 18:04:38 INFO - 'TERM': 'linux', 18:04:38 INFO - 'TMOUT': '86400', 18:04:38 INFO - 'USER': 'cltbld', 18:04:38 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1503623036.536778-49172866', 18:04:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:38 INFO - '_': '/tools/buildbot/bin/python'} 18:04:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:04:38 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:04:38 INFO - Cleaning up... 18:04:38 INFO - Return code: 0 18:04:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:04:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:04:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:04:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x249a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2665a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2808f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2806130>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2807030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28074c0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1503623036.536778-49172866', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:04:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:04:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:04:38 INFO - Using env: {'DISPLAY': ':0', 18:04:38 INFO - 'HOME': '/home/cltbld', 18:04:38 INFO - 'LANG': 'en_US.UTF-8', 18:04:38 INFO - 'LANGUAGE': 'en_US:en', 18:04:38 INFO - 'LOGNAME': 'cltbld', 18:04:38 INFO - 'MAIL': '/var/mail/cltbld', 18:04:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:38 INFO - 'MOZ_NO_REMOTE': '1', 18:04:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:04:38 INFO - 'NO_EM_RESTART': '1', 18:04:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:04:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:04:38 INFO - 'PWD': '/builds/slave/test', 18:04:38 INFO - 'SHELL': '/bin/bash', 18:04:38 INFO - 'SHLVL': '1', 18:04:38 INFO - 'TERM': 'linux', 18:04:38 INFO - 'TMOUT': '86400', 18:04:38 INFO - 'USER': 'cltbld', 18:04:38 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1503623036.536778-49172866', 18:04:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:38 INFO - '_': '/tools/buildbot/bin/python'} 18:04:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:04:38 INFO - Downloading/unpacking blobuploader==1.2.4 18:04:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:04:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:04:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:04:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:04:39 INFO - Downloading blobuploader-1.2.4.tar.gz 18:04:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:04:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:04:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:04:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:04:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:04:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:04:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:04:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:04:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:04:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:04:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:04:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:04:40 INFO - Downloading docopt-0.6.1.tar.gz 18:04:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:04:40 INFO - Installing collected packages: blobuploader, requests, docopt 18:04:40 INFO - Running setup.py install for blobuploader 18:04:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:04:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:04:40 INFO - Running setup.py install for requests 18:04:40 INFO - Running setup.py install for docopt 18:04:41 INFO - Successfully installed blobuploader requests docopt 18:04:41 INFO - Cleaning up... 18:04:41 INFO - Return code: 0 18:04:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:04:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:04:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:04:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x249a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2665a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2808f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2806130>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2807030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28074c0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1503623036.536778-49172866', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:04:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:04:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:04:41 INFO - Using env: {'DISPLAY': ':0', 18:04:41 INFO - 'HOME': '/home/cltbld', 18:04:41 INFO - 'LANG': 'en_US.UTF-8', 18:04:41 INFO - 'LANGUAGE': 'en_US:en', 18:04:41 INFO - 'LOGNAME': 'cltbld', 18:04:41 INFO - 'MAIL': '/var/mail/cltbld', 18:04:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:41 INFO - 'MOZ_NO_REMOTE': '1', 18:04:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:04:41 INFO - 'NO_EM_RESTART': '1', 18:04:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:04:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:04:41 INFO - 'PWD': '/builds/slave/test', 18:04:41 INFO - 'SHELL': '/bin/bash', 18:04:41 INFO - 'SHLVL': '1', 18:04:41 INFO - 'TERM': 'linux', 18:04:41 INFO - 'TMOUT': '86400', 18:04:41 INFO - 'USER': 'cltbld', 18:04:41 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1503623036.536778-49172866', 18:04:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:41 INFO - '_': '/tools/buildbot/bin/python'} 18:04:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:04:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:04:41 INFO - Running setup.py (path:/tmp/pip-R1JdH1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:04:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:04:41 INFO - Running setup.py (path:/tmp/pip-HA1tXB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:04:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:04:41 INFO - Running setup.py (path:/tmp/pip-A6zLJY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:04:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:04:41 INFO - Running setup.py (path:/tmp/pip-S7YAzf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:04:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:04:41 INFO - Running setup.py (path:/tmp/pip-Ysb9M8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:04:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:04:41 INFO - Running setup.py (path:/tmp/pip-ucZHd9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:04:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:04:41 INFO - Running setup.py (path:/tmp/pip-ShnNZk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:04:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:04:41 INFO - Running setup.py (path:/tmp/pip-NUDpS_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:04:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:04:41 INFO - Running setup.py (path:/tmp/pip-7OcWsr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:04:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:04:41 INFO - Running setup.py (path:/tmp/pip-iiLv06-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:04:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:04:42 INFO - Running setup.py (path:/tmp/pip-QRwnh_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:04:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:04:42 INFO - Running setup.py (path:/tmp/pip-z4ZtCJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:04:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:04:42 INFO - Running setup.py (path:/tmp/pip-SM5qZl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:04:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:04:42 INFO - Running setup.py (path:/tmp/pip-Lj8OTL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:04:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:04:42 INFO - Running setup.py (path:/tmp/pip-xkTj5z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:04:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:04:42 INFO - Running setup.py (path:/tmp/pip-uywgXV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:04:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:04:42 INFO - Running setup.py (path:/tmp/pip-9BUaqP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:04:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:04:42 INFO - Running setup.py install for manifestparser 18:04:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:04:42 INFO - Running setup.py install for mozcrash 18:04:42 INFO - Running setup.py install for mozdebug 18:04:42 INFO - Running setup.py install for mozdevice 18:04:42 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:04:42 INFO - Running setup.py install for mozfile 18:04:42 INFO - Running setup.py install for mozhttpd 18:04:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:04:43 INFO - Running setup.py install for mozinfo 18:04:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:04:43 INFO - Running setup.py install for mozInstall 18:04:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:04:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:04:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:04:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:04:43 INFO - Running setup.py install for mozleak 18:04:43 INFO - Running setup.py install for mozlog 18:04:43 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:04:43 INFO - Running setup.py install for moznetwork 18:04:43 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:04:43 INFO - Running setup.py install for mozprocess 18:04:43 INFO - Running setup.py install for mozprofile 18:04:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:04:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:04:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:04:43 INFO - Running setup.py install for mozrunner 18:04:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:04:43 INFO - Running setup.py install for mozscreenshot 18:04:44 INFO - Running setup.py install for moztest 18:04:44 INFO - Running setup.py install for mozversion 18:04:44 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:04:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:04:44 INFO - Cleaning up... 18:04:44 INFO - Return code: 0 18:04:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:04:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:04:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:04:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x249a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2665a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2808f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2806130>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2807030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28074c0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1503623036.536778-49172866', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:04:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:04:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:04:44 INFO - Using env: {'DISPLAY': ':0', 18:04:44 INFO - 'HOME': '/home/cltbld', 18:04:44 INFO - 'LANG': 'en_US.UTF-8', 18:04:44 INFO - 'LANGUAGE': 'en_US:en', 18:04:44 INFO - 'LOGNAME': 'cltbld', 18:04:44 INFO - 'MAIL': '/var/mail/cltbld', 18:04:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:44 INFO - 'MOZ_NO_REMOTE': '1', 18:04:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:04:44 INFO - 'NO_EM_RESTART': '1', 18:04:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:04:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:04:44 INFO - 'PWD': '/builds/slave/test', 18:04:44 INFO - 'SHELL': '/bin/bash', 18:04:44 INFO - 'SHLVL': '1', 18:04:44 INFO - 'TERM': 'linux', 18:04:44 INFO - 'TMOUT': '86400', 18:04:44 INFO - 'USER': 'cltbld', 18:04:44 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1503623036.536778-49172866', 18:04:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:44 INFO - '_': '/tools/buildbot/bin/python'} 18:04:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:04:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:04:44 INFO - Running setup.py (path:/tmp/pip-GdCqkr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:04:44 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 18:04:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:04:44 INFO - Running setup.py (path:/tmp/pip-j7j42I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:04:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:04:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:04:44 INFO - Running setup.py (path:/tmp/pip-PtjjIL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:04:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:04:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:04:44 INFO - Running setup.py (path:/tmp/pip-Z97UxM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:04:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:04:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:04:44 INFO - Running setup.py (path:/tmp/pip-mfyd_s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:04:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 18:04:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:04:44 INFO - Running setup.py (path:/tmp/pip-BdbDsl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:04:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 18:04:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:04:44 INFO - Running setup.py (path:/tmp/pip-cwDD7j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:04:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 18:04:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:04:44 INFO - Running setup.py (path:/tmp/pip-G828lX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:04:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:04:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:04:45 INFO - Running setup.py (path:/tmp/pip-FK28ix-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:04:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 18:04:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:04:45 INFO - Running setup.py (path:/tmp/pip-Xg7mkf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:04:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:04:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:04:45 INFO - Running setup.py (path:/tmp/pip-ML4Ezn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:04:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 18:04:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:04:45 INFO - Running setup.py (path:/tmp/pip-Ea0VtD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:04:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 18:04:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:04:45 INFO - Running setup.py (path:/tmp/pip-y0H06O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:04:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 18:04:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:04:45 INFO - Running setup.py (path:/tmp/pip-O7oMBl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:04:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 18:04:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:04:45 INFO - Running setup.py (path:/tmp/pip-GX7No1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:04:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 18:04:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:04:45 INFO - Running setup.py (path:/tmp/pip-AyuWAq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:04:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 18:04:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:04:45 INFO - Running setup.py (path:/tmp/pip-VfuBZe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:04:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 18:04:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:04:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:04:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:04:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:04:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:04:45 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:04:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:04:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:04:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:04:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:04:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:04:46 INFO - Downloading blessings-1.6.tar.gz 18:04:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:04:46 INFO - Installing collected packages: blessings 18:04:46 INFO - Running setup.py install for blessings 18:04:46 INFO - Successfully installed blessings 18:04:46 INFO - Cleaning up... 18:04:47 INFO - Return code: 0 18:04:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:04:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:04:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:04:47 INFO - Reading from file tmpfile_stdout 18:04:47 INFO - Current package versions: 18:04:47 INFO - argparse == 1.2.1 18:04:47 INFO - blessings == 1.6 18:04:47 INFO - blobuploader == 1.2.4 18:04:47 INFO - docopt == 0.6.1 18:04:47 INFO - functools32 == 3.2.3-2 18:04:47 INFO - jsonschema == 2.5.1 18:04:47 INFO - manifestparser == 1.1 18:04:47 INFO - mozInstall == 1.13 18:04:47 INFO - mozcrash == 1.0 18:04:47 INFO - mozdebug == 0.1 18:04:47 INFO - mozdevice == 0.51 18:04:47 INFO - mozfile == 1.2 18:04:47 INFO - mozhttpd == 0.7 18:04:47 INFO - mozinfo == 0.10 18:04:47 INFO - mozleak == 0.1 18:04:47 INFO - mozlog == 3.5 18:04:47 INFO - moznetwork == 0.27 18:04:47 INFO - mozprocess == 0.25 18:04:47 INFO - mozprofile == 0.28 18:04:47 INFO - mozrunner == 6.13 18:04:47 INFO - mozscreenshot == 0.1 18:04:47 INFO - mozsystemmonitor == 0.3 18:04:47 INFO - moztest == 0.8 18:04:47 INFO - mozversion == 1.4 18:04:47 INFO - psutil == 3.1.1 18:04:47 INFO - requests == 1.2.3 18:04:47 INFO - wsgiref == 0.1.2 18:04:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:04:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:04:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:04:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x249a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2665a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2808f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2806130>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2807030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28074c0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1503623036.536778-49172866', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:04:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 18:04:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:04:47 INFO - Using env: {'DISPLAY': ':0', 18:04:47 INFO - 'HOME': '/home/cltbld', 18:04:47 INFO - 'LANG': 'en_US.UTF-8', 18:04:47 INFO - 'LANGUAGE': 'en_US:en', 18:04:47 INFO - 'LOGNAME': 'cltbld', 18:04:47 INFO - 'MAIL': '/var/mail/cltbld', 18:04:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:47 INFO - 'MOZ_NO_REMOTE': '1', 18:04:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:04:47 INFO - 'NO_EM_RESTART': '1', 18:04:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:04:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:04:47 INFO - 'PWD': '/builds/slave/test', 18:04:47 INFO - 'SHELL': '/bin/bash', 18:04:47 INFO - 'SHLVL': '1', 18:04:47 INFO - 'TERM': 'linux', 18:04:47 INFO - 'TMOUT': '86400', 18:04:47 INFO - 'USER': 'cltbld', 18:04:47 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1503623036.536778-49172866', 18:04:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:47 INFO - '_': '/tools/buildbot/bin/python'} 18:04:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:04:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:04:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 18:04:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 18:04:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:04:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 18:04:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 18:04:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 18:04:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 18:04:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 18:04:47 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:04:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:04:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:04:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:04:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:04:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 18:04:48 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:04:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:04:48 INFO - Installing collected packages: simplejson 18:04:48 INFO - Running setup.py install for simplejson 18:04:48 INFO - building 'simplejson._speedups' extension 18:04:48 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 18:04:49 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 18:04:49 INFO - Successfully installed simplejson 18:04:49 INFO - Cleaning up... 18:04:49 INFO - Return code: 0 18:04:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:04:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:04:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:04:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x249a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2665a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2808f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2806130>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2807030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28074c0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1503623036.536778-49172866', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:04:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:04:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:04:49 INFO - Using env: {'DISPLAY': ':0', 18:04:49 INFO - 'HOME': '/home/cltbld', 18:04:49 INFO - 'LANG': 'en_US.UTF-8', 18:04:49 INFO - 'LANGUAGE': 'en_US:en', 18:04:49 INFO - 'LOGNAME': 'cltbld', 18:04:49 INFO - 'MAIL': '/var/mail/cltbld', 18:04:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:49 INFO - 'MOZ_NO_REMOTE': '1', 18:04:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:04:49 INFO - 'NO_EM_RESTART': '1', 18:04:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:04:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:04:49 INFO - 'PWD': '/builds/slave/test', 18:04:49 INFO - 'SHELL': '/bin/bash', 18:04:49 INFO - 'SHLVL': '1', 18:04:49 INFO - 'TERM': 'linux', 18:04:49 INFO - 'TMOUT': '86400', 18:04:49 INFO - 'USER': 'cltbld', 18:04:49 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1503623036.536778-49172866', 18:04:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:49 INFO - '_': '/tools/buildbot/bin/python'} 18:04:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:04:49 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:04:49 INFO - Cleaning up... 18:04:49 INFO - Return code: 0 18:04:49 INFO - Running post-action listener: _resource_record_post_action 18:04:49 INFO - Running post-action listener: _start_resource_monitoring 18:04:49 INFO - Starting resource monitoring. 18:04:49 INFO - [mozharness: 2017-08-25 01:04:49.962709Z] Finished create-virtualenv step (success) 18:04:49 INFO - [mozharness: 2017-08-25 01:04:49.963257Z] Running install step. 18:04:49 INFO - Running pre-action listener: _resource_record_pre_action 18:04:49 INFO - Running main action method: install 18:04:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:04:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:04:50 INFO - Reading from file tmpfile_stdout 18:04:50 INFO - Detecting whether we're running mozinstall >=1.0... 18:04:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:04:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:04:50 INFO - Reading from file tmpfile_stdout 18:04:50 INFO - Output received: 18:04:50 INFO - Usage: mozinstall [options] installer 18:04:50 INFO - Options: 18:04:50 INFO - -h, --help show this help message and exit 18:04:50 INFO - -d DEST, --destination=DEST 18:04:50 INFO - Directory to install application into. [default: 18:04:50 INFO - "/builds/slave/test"] 18:04:50 INFO - --app=APP Application being installed. [default: firefox] 18:04:50 INFO - mkdir: /builds/slave/test/build/application 18:04:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 18:04:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 18:05:07 INFO - Reading from file tmpfile_stdout 18:05:07 INFO - Output received: 18:05:07 INFO - /builds/slave/test/build/application/firefox/firefox 18:05:07 INFO - Running post-action listener: _resource_record_post_action 18:05:07 INFO - [mozharness: 2017-08-25 01:05:07.959263Z] Finished install step (success) 18:05:07 INFO - [mozharness: 2017-08-25 01:05:07.959608Z] Running setup-mitmproxy step. 18:05:07 INFO - Running pre-action listener: _resource_record_pre_action 18:05:07 INFO - Running main action method: setup_mitmproxy 18:05:07 INFO - Skipping: mitmproxy is not required 18:05:07 INFO - Running post-action listener: _resource_record_post_action 18:05:07 INFO - [mozharness: 2017-08-25 01:05:07.961223Z] Finished setup-mitmproxy step (success) 18:05:07 INFO - [mozharness: 2017-08-25 01:05:07.961644Z] Running run-tests step. 18:05:07 INFO - Running pre-action listener: _resource_record_pre_action 18:05:07 INFO - Running pre-action listener: _set_gcov_prefix 18:05:07 INFO - Running main action method: run_tests 18:05:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:05:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:05:07 INFO - Python 2.7.3 18:05:07 INFO - Return code: 0 18:05:07 INFO - grabbing minidump binary from tooltool 18:05:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:07 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2806130>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2807030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28074c0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:05:07 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 18:05:07 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 18:05:07 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 18:05:08 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:05:08 INFO - Return code: 0 18:05:08 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:05:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:05:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:05:08 INFO - ENV: RUST_BACKTRACE is now full 18:05:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:05:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:05:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Amqe4HB8QW2hqpHBtZHK2w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-022', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 18:05:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Amqe4HB8QW2hqpHBtZHK2w/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-022 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 18:05:08 INFO - Using env: {'DISPLAY': ':0', 18:05:08 INFO - 'HOME': '/home/cltbld', 18:05:08 INFO - 'LANG': 'en_US.UTF-8', 18:05:08 INFO - 'LANGUAGE': 'en_US:en', 18:05:08 INFO - 'LOGNAME': 'cltbld', 18:05:08 INFO - 'MAIL': '/var/mail/cltbld', 18:05:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:05:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:05:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:08 INFO - 'MOZ_NO_REMOTE': '1', 18:05:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:05:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:05:08 INFO - 'NO_EM_RESTART': '1', 18:05:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:05:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:08 INFO - 'PWD': '/builds/slave/test', 18:05:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:05:08 INFO - 'RUST_BACKTRACE': 'full', 18:05:08 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:05:08 INFO - 'SHELL': '/bin/bash', 18:05:08 INFO - 'SHLVL': '1', 18:05:08 INFO - 'TERM': 'linux', 18:05:08 INFO - 'TMOUT': '86400', 18:05:08 INFO - 'USER': 'cltbld', 18:05:08 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1503623036.536778-49172866', 18:05:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:08 INFO - '_': '/tools/buildbot/bin/python'} 18:05:08 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Amqe4HB8QW2hqpHBtZHK2w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-022', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 18:05:08 INFO - mozversion application_buildid: 20170825001342 18:05:08 INFO - mozversion application_changeset: 3199bacd6b3827d711155a9e81c081434cf7cd8f 18:05:08 INFO - mozversion application_display_name: Nightly 18:05:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:05:08 INFO - mozversion application_name: Firefox 18:05:08 INFO - mozversion application_remotingname: firefox 18:05:08 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:05:08 INFO - mozversion application_vendor: Mozilla 18:05:08 INFO - mozversion application_version: 57.0a1 18:05:08 INFO - mozversion platform_buildid: 20170825001342 18:05:08 INFO - mozversion platform_changeset: 3199bacd6b3827d711155a9e81c081434cf7cd8f 18:05:08 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:05:08 INFO - mozversion platform_version: 57.0a1 18:05:08 INFO - using testdate: 1503623108 18:05:08 INFO - actual date: 1503623108 18:05:08 INFO - starting webserver on 'localhost:54626' 18:05:08 INFO - SUITE-START | Running 2 tests 18:05:08 INFO - TEST-START | dromaeo_css 18:05:08 INFO - Initialising browser for dromaeo_css test... 18:05:08 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:54626/getInfo.html -profile /tmp/tmpiW5trY/profile 18:05:08 INFO - TEST-INFO | started process 17012 (/builds/slave/test/build/application/firefox/firefox http://localhost:54626/getInfo.html) 18:05:14 INFO - TEST-INFO | 17012: exit 0 18:05:14 INFO - Browser initialized. 18:05:14 INFO - Running cycle 1/1 for dromaeo_css test... 18:05:14 INFO - TEST-INFO | started process 17230 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiW5trY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:05:15 INFO - PID 17230 | 18:05:15 INFO - PID 17230 | (/builds/slave/test/build/application/firefox/firefox:17280): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:05:15 INFO - PID 17230 | 18:05:15 INFO - PID 17230 | 18:05:15 INFO - PID 17230 | (/builds/slave/test/build/application/firefox/firefox:17333): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:05:15 INFO - PID 17230 | 18:05:15 INFO - PID 17230 | __metrics Screen width/height:1600/1200 18:05:15 INFO - PID 17230 | colorDepth:24 18:05:15 INFO - PID 17230 | Browser inner width/height: 1024/768 18:05:15 INFO - PID 17230 | __metrics 18:05:25 INFO - PID 17230 | RSS: Main: 175730688 18:05:25 INFO - PID 17230 | 18:08:23 INFO - PID 17230 | Cycle 1(1): loaded http://localhost:54626/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54626/tests/dromaeo/cssquery-ext.html) 18:08:25 INFO - PID 17230 | RSS: Main: 176672768 18:08:25 INFO - PID 17230 | 18:11:26 INFO - PID 17230 | Cycle 1(1): loaded http://localhost:54626/tests/dromaeo/cssquery-ext.html (next: http://localhost:54626/tests/dromaeo/cssquery-jquery.html) 18:11:27 INFO - PID 17230 | RSS: Main: 171597824 18:11:27 INFO - PID 17230 | 18:14:29 INFO - PID 17230 | Cycle 1(1): loaded http://localhost:54626/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54626/tests/dromaeo/cssquery-mootools.html) 18:14:29 INFO - PID 17230 | RSS: Main: 174755840 18:14:29 INFO - PID 17230 | 18:17:31 INFO - PID 17230 | Cycle 1(1): loaded http://localhost:54626/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54626/tests/dromaeo/cssquery-prototype.html) 18:17:32 INFO - PID 17230 | RSS: Main: 173305856 18:17:32 INFO - PID 17230 | 18:20:35 INFO - PID 17230 | Cycle 1(1): loaded http://localhost:54626/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54626/tests/dromaeo/cssquery-yui.html) 18:20:36 INFO - PID 17230 | RSS: Main: 174903296 18:20:36 INFO - PID 17230 | 18:23:38 INFO - PID 17230 | Cycle 1(1): loaded http://localhost:54626/tests/dromaeo/cssquery-yui.html (next: http://localhost:54626/tests/dromaeo/cssquery-dojo.html) 18:23:38 INFO - PID 17230 | RSS: Main: 177352704 18:23:38 INFO - PID 17230 | 18:23:38 INFO - PID 17230 | __start_tp_report 18:23:38 INFO - PID 17230 | _x_x_mozilla_page_load 18:23:38 INFO - PID 17230 | _x_x_mozilla_page_load_details 18:23:38 INFO - PID 17230 | |i|pagename|runs| 18:23:38 INFO - PID 17230 | |0;dojo.html;3259.20149563357;3276.7706260561763;3290.226796702774;3317.4194515959703;3341;6220.377962203777;6288.119663247149;6299.622022678643;6305.621662700236;6331.905021424676;6115.571909966304;6238.376162383759;6246.656433896134;6267.341929097447;6367.044943258502;5774.335951365584;5800.854978625547;5810.6513609183585;5850.385709500505;5867.031939729939;5583.832485025436;5707.486326230638;5720.998825205593;5781.421857814206;5797.971010144944;5685.118806584986;5767.163761254616;5776.39347868474;5807;5818.679972601505;5654.15187721845;5698.316202055738;5702.4582664647205;5747.885042299173;5764.798232061899;5746.281714785652;5767.0772676372;5769.163471296681;5772.682502462363;5790.247267855152;6160.507159427235;6212.161358216673;6258.812235632939;6260.40526150015;6273;6623.801285961381;6676.566023208476;6734.797956061326;6771.525993180479;6785.932140678579;6560.442362399202;6703.631300278483;6709.966450167718;6795.762148324785;6808;6280.120783090371;6300.747970081191;6307.211598550135;6310.87378252437;6322.083297921776;6892.551984121016;6895.862082758316;6930.514863959575;6972.686229119702;6986.777313970035;6586.27550969393;6602.570832895846;6642.501812364092;6704.128463299739;6724.66376681164;6540.149780528501;6626.867462650816;6653.63405012729;6741.831454213586;6749.3250674932115;6653.767118150841;6719.563228390139;6725.831854203684;6739.59562426256;6744.460443164535;6566.212054553484;6681.599104053772;6684.532082754258;6692.899606505906;6703.564268322543;4820.517948205152;4827.686200397034;4837.443693975168;4882.804687812519;4883.316335713003;4320.135972805451;4324.070324880166;4326.913461730748;4354.390385346054;4355.259605866947;5805.82582522525;5870.442307980735;5888.087346461305;5891.263592050994;5920.111983202554;6713;6763.323667633197;6847.554908930903;6849.315068493211;6868.896966545506;5679.716014199356;5795.1886735857015;5799.072148456226;5872.471477567039;5874.32445268791;5699.572532060112;5727.570432217517;5747.453991870766;5760;5780.16187652788;6407.679616019181;6460.289368169498;6490.188726409199;6545.934540654628;6548.607083574905;6607.372299631527;6674.132362792903;6744.730210791709;6839.62382068991;6844.828879278058;6418.19772528434;6448.903266450913;6497.09040734307;6502.967485162544;6514.804555863332;6623.867522649616;6655.168103987002;6662.900056499253;6726.663666816542;6733.461323094239;6666.899996500153;6767.695453704497;6769.424598909148;6854.725810967506;6868.862622747417;6450.838729031624;6534.869302614016;6541.149650545399;6618.6690665467495;6628.635425051669;6413.871722565429;6449.7420103195345;6477.740890364333;6512.47900167976;6522.478201743944;6301.6219026858535;6375.968120159369;6428.453581445442;6434.131392262172;6528.543001989815;6592.406683398517;6606.075149479173;6682.665866706548;6693.464522838259;6700.66496675174;6337.6197428154455;6350.587211831216;6398.584092034003;6404.9039264412;6440.2915679276175 18:23:38 INFO - PID 17230 | |1;ext.html;762.1578156137468;804.5574933786417;815.6492708135502;845.0915266088956;847.1359213602124;39934.20131597366;40832;40844.38733418994;40855.79572102143;40943.59056409435;36818;37158.814205929;37176;37195;37389.25221495568;6893.7931862044215;7010.544314619533;7017.71929122837;7020.262872398402;7031.402330801888;6871.656417179146;6973.372396484316;6979.720811167547;7007.509474336773;7009.8598028039405;6980.546264492792;7004.4396448284015;7566.621668916559;7728.304452599265;7732.45872788905;7666.3866890648615;7722.7297044603165;7729.227077292282;7730.652120654556;7732.381409487256;7538.3215510604305;7612.276833700789;7626;7632.465727399084;7642.159362470124;35910.46134307988;35916.10209744751;35963.28073438517;35978.460323094914;36008.27983440342;3380.7126394256516;3412.505186747908;3427.520147179397;3429.3998550253855;3439.329330780499;30468.54297185544;30542.236444088852;30542.930997414984;30567.847160764053;30570.235744106354;5118.155504341817;5133.512316329943;5134.2812006319145;5147;5158;176144.11927940277;179621;180233;182409;189646;47204.52795472001;47251.76374118107;47343.52656473391;47360.39517826484;47410.52589474061;48984.755076225105;49148;49172.508274917505;49196;49214.261786073235;15645.295961681752;15716.214189290718;15723.292451839694;15729.606759830867;15800;13077.411516481781;13085.607431777062;13123.737525249633;13128.474861005643;13135.671608209681;13503.459861605623;13541.729165416637;13568.525101621395;13644.590662280147;13682.31588420575;5675.063614503643;5693.755380504267;5746.310442746897;5755.309362876482;5758.3090029196765;987.7975015121883;994.721477986165;997.9900200998043;1006.4062203300089;1009.2228983682523;22455.88772056129;22517.887410562842;22719.545609087727;22777.430564236027;22885.199018034287;1090.4166271045112;1091.6779550032759;1091.9672409827558;1100.2408338246585;1110.245033377295;11765.88234117665;12063.698407539881;12101.636950891312;15164.620884477976;15604.273858299957;234077;234408;234595;234597;236104;54376;54855.725721371935;54861.451385486434;54876.72561637246;54920.72539637276;1374.2441657088561;1427.7144571085616;1437.590286768266;1444.0108525660166;1457.1912588513264;26080.608790868533;26136.73863261419;26530.734692652826;26548;26902.865485672446;36753.448698270084;37018.25963480769;37058.8147059263;37120.6287937128;37398;205965;206481;206940;207630;207882;32899.54554771714;36341.45487817632;36981.260374792895;37013.44479832751;37150.44274335941;44726.10547788961;44823.77588112169;44957.775211123735;44989.550104498536;45442.77278613718;33175.50236746499;33390.49914251337;33531.66468335286;33919.491207631414;34049.48925766165;32771.18072048218;33034.83482582572;33432.33135337328;33463.49804752882;33705.325893482484;36410.45384319184;36430.27139457143;36821.26357472889;36841.447378289886;37019.44470832886;32209.677903221604;32784;32994.67005329916;33136.33727325488;33320.50019249665;23819.519411458903;24266.272011839672;24722;24830.751692482845;25067.623985639864 18:23:38 INFO - PID 17230 | |2;jquery.html;541.7979739738148;542.2516926641233;553.6456667732651;561.3768716724433;562.721452880824;21517.892410537945;22287.888560557196;22292.66561001586;22311.665325020138;22410;23031.078756849747;24012.159574414898;25481;25489.61765573518;25618.615720764203;6584.374484423972;6742.52802303839;6749.156355455568;6750.1562304711915;6751.493637977147;5975.910361344583;6503.252126005499;6597.2083349998065;6608.933910660904;6637.502187335969;6463.030545418178;6465.321141280168;6469.7412103516035;6473.482121430275;6476.417122458978;6464.806055818333;6470.191226096738;6479.384458476461;6483.0275458681335;6493.805185844432;6005.219321488178;6010.609310394811;6023;6030.035194368924;6045.697715114226;28528.714712852812;28637.713622863714;29162.833486660507;29198.978035768647;29883.70116298831;2024.2611446821952;2057.753069631654;2061.29915828619;2063.5769667218183;2066.028966385796;19431.902840485847;19631.803681963283;19639.509012274666;19667.606647867105;19721.704174437396;2367.263127016727;2408.3256688127176;2459.9508009839883;2460.4463995601027;2475.851448913053;125682;130855.34572327172;132102;132299.33850330784;132490;13664.590062298146;14370.425182992563;14754.63113422173;14765.630859228606;15167.241637918281;23051.19320823763;23058.308250752503;23136.65295020576;23139.537209256057;23140.305790826304;9943.701688949342;10005.349652272578;10037.79924401508;10138.290319677551;10185.847212291821;9388.2020028297;9422.670206542874;9447.433154010781;9449.716508504756;9456.763580910532;7504.324610784946;8921.420107692978;10911.727206819893;11296.378699171624;11581;3503.5445392098864;3699.7780133191554;3872.980635096863;4002;4321.69748117635;915.354674954169;937.4141161773891;955.3551352836807;957.8132264208413;973.4110862927968;19562.217511299674;19567.90216048939;19630.21479140847;19738.60522789536;19802;991.4943378876825;1023.4933707814679;1028.4600584693096;1042.525650828871;1047.701405099543;10325.586976520855;10743.946280268548;10924.344539327665;13387.598372048855;14003.649908752159;543291;560492;565355;566422;573899;114443;118179;118659.40670296767;120109;122439.38780306041;1215.3558613934629;1235.3884827010688;1251.3305381620607;1252.2799390350585;1252.511520507003;20949.581008380053;21112.26107086306;21922.780772192713;22010.779892200873;22314.10743570239;58016;58697.70651146717;60198;60696;61357.69321153365;67442;67718.66140669266;67889.66055169693;68058.31941680718;68606.65696671484;14615.561533154023;14787.778183327473;14868.405263789327;14899.702005959603;14981.250937453302;23255.883720581965;23568.0572777087;23726.288211353687;23808;23815.761842382046;18483.537911552317;18593.72109418333;18777;18831.6233675323;19006.23975040983;16919.830801691827;17029.91485042567;17193;17393.304267829146;17810.910945445707;17820;19190.80809191946;19331.903340483208;19438.319658811874;19512.414627561197;14206.715865682836;14686.926565367105;14706.411743530567;14737;14757;16864.831351686327;17040.744388834424;18712.719309210646;18864.52838679044;18897 18:23:38 INFO - PID 17230 | |3;mootools.html;1281.5130250504806;1326.4959315460123;1332.6202032420763;1333.0868355176706;1338.0098726942065;14267.857321426782;14939.103653780767;15006.924965375172;15044.323005464754;15343;15461.92269038656;15672.764908526382;15682.921585392058;15692;15746;2718.6329845470905;2771.2933202033455;2774.0706863200753;2775.5697866830674;2777.750002499775;2647.9205623831317;2741.0817376179;2818.8308701477877;2819.3797364579864;2819.8872045118273;2682.168527756392;2703.8512881791494;2741.7806575473915;2744.5059889219933;2745.6156138140677;2653.01838319821;2654.8539830309323;2693.892244310225;2695.3800625856065;2729.890804367823;2661.7072122066556;2670.3457652874977;2683.7987150963754;2688.502627014009;2691.6407214356623;14419.711605767827;14584.781228281585;14587.854121458755;14591.124532528082;14615.561533154023;7615.31462168402;7880.408969327324;7884.17216192303;7907.090684571291;7919.683212671486;5204.594759414937;5291.656042357235;5342.492463216027;5401.108817045183;5416.972915135439;1833.230043381783;1862.5436767991794;1906.1517624657033;1927.1905799564076;1957.667196576571;24321.635175472384;25133.874330628412;25140.497190056463;25159.24522264335;25191.118310859216;7047.718091276394;7089.82275443118;7171.92828071723;7342.926570734331;7347.88978165328;16294.185290735415;16519.587010324598;16520.586985325463;16548.089855057846;16574.83425165733;7303.087114110736;7404.407647388196;7439.03292571973;7445.181030086686;7459.440541959375;6865.210500792373;6934.271901450376;6949.791506254821;7000.579965202104;7012.894806577905;5146.382434107931;5262.868428289246;5281.181416880389;5300.787968481218;5314.1497360422145;1514.4245186829155;1524.5350168198584;1547.2186545794302;1547.2186545794302;1550.4650895441055;896.1755185229605;897.5736525150501;902.8194361127801;909.8498747706554;916.0793402630314;7541.886871696929;7625.847483050419;7648.885266720892;7662.61686915652;7692.230776922263;690.2993461636443;695.4818660098158;701.1340993872564;713.1905287498727;719.9460040496983;4540.682152249311;4541.2733962565835;4668.743219122981;4691.812327506861;4741.217699079613;19595.412137635893;19857.801421985594;20069;20475.38573842787;20670.17319307241;20481.180752770022;20524.95975840085;20873.47816304574;20929.58140837175;20974.790252097588;10251.38491690486;10416.479176041168;10544.683659490227;10618.628348007936;10803.081738052353;7928.920710793049;8132.87800683002;8169.101398846241;8218.383621228551;8274.627641756015;17324.39364622232;17792.822071779116;17914;18057.18742656557;18211.726824097914;18285.81714182841;18401.263949441873;18697.971611561494;18728.53178670544;18898.338558150397;11655.53377864876;11993.700157496132;12079.698007549881;12119.45462454174;12120.939395302967;13431.664208394868;13639;13686;13693.589192324247;13713.588592342247;10774.838377424501;10796.514156862802;10823.134149267882;10861.728456788644;11003.724906877072;10866.836997445203;10886.40124793144;10895.673129806119;11157.66527004191;11232.719182020188;9613.615455381987;9823.36148150368;9828.852567211354;9829.606815727291;9833.557489913113;7088.787336379917;7150.356467917913;7152.177499587406;7165.104361954755;7177.102862142232;7465.06686664167;7481.289277518627;7508.587027713528;7515.511491753018;7557.130929942907 18:23:38 INFO - PID 17230 | |4;prototype.html;501.86591172307317;503.0456675814462;515.3146315400518;517.813587108641;524.512203650605;6288.962321216999;6352.841178970528;6400.359964003597;6428.678566071701;6440.645764482952;6041.728122234499;6214.130021796941;6227.28386235583;6234.84412889678;6242.875142497159;5009.2486127080865;5015.423226328964;5015.473375295596;5050.217216331474;5056.620753443488;4872.415310162785;4997.600671811899;5019.389917243031;5027.949720502803;5068.974655126719;4932.804901818692;4973.900521989558;5006.123928312549;5014.8244811431605;5019.322391477159;5003.849884503435;5006.123928312567;5006.523075692679;5032.370953630796;5032.798688052492;4466.12910482456;4491.910161796745;4495.820167193308;4515.706479078849;4529.705569137996;6138.263408391022;6144.477719393857;6145.539084568675;6176.8764624707255;6195.969020154916;5715.971420142872;5731.598788084836;5742.655440673531;5750;5755;6497.447716944065;6516.609003459807;6571.638559879205;6604.438622717074;6665.366790154976;4769.708900858396;4770.260609605517;4819.445763737145;4829.5653391194965;4832.371791667061;6834.496410789718;6895.34494223048;6932.376086152271;6939.88961766115;6944.4444444444325;4914;4957.677750946177;5014.774335154927;5043.218301163325;5047.722375269322;5021.8242361517905;5023.623228257895;5027.271045698353;5065.594752419797;5095.464976177522;5681.0910254359105;5682.602217844794;5705.343885453143;5720.542356611544;5746.281714785652;6553.394418367435;6585.769498067641;6624.979381288656;6663;6692.498062645321;1620.6434584391416;1663.4344322930262;1728.9827101728822;1778.3953455824824;1910.2740958435984;1409.7180563887264;1416.6529200345776;1418.4609848257596;1443.9928499385653;1452.1359790924382;5668.291463567054;5693.914591281134;5717.828465146053;5744.684042377709;5756.480408994627;6615.536912416181;6653.800385988428;6670.199576050905;6679.960617918835;6824.829379265459;5694.430556944272;5789.942100579025;5807.680577568274;5839.124131380242;5865.384134665884;3548.27260411615;3568.3398571264397;3594.25678545905;3622.7645203061716;3649.5802982656805;6488;6523.64119973406;6588.40704336612;6602.3397660233595;6604.900926486107;6933.757318493828;7025.789226323219;7038.3665470107935;7055.470839687044;7074.469414793911;6477.481801455872;6487.026945958144;6545.149130613085;6608.1827497437625;6637.900431493532;6748.392644661808;6773.93226067733;6779.661016949034;6811.467419830498;6874.3909447540445;6715.496337774589;6742;6779.186497620317;6804.523683342119;6832.692528836114;6511.3163117871945;6546.901796472961;6576.868462630625;6617.503687223574;6651.368120028589;4975.651704380659;4993.15116430215;5025.74871256443;5060;5073.746312684277;6876.140482439695;6880.724771009103;6884.277150899284;6885.586864788129;6895.379415852597;6345.8106953101105;6399.6800159992745;6473.827824365932;6548.639824809681;6586.1438013058;6484.383983521558;6542.663241746648;6587.176602924635;6600.504962127764;6634.734610615521;6121.32665406796;6356.506221038145;6374.131640761769;6479.352064793483;6485.221773387224;6504.837379065561;6523.7716679915975;6637.103990961158;6676.699548520425;6705.161854768154;6364.968175159094;6400.679966001774;6436.388543088399;6449.226092868698;6476.158099447231 18:23:38 INFO - PID 17230 | |5;yui.html;841.2722994609661;841.7685136587442;851.7146755836794;878.3719640457074;900.6082354175936;19007;19675.901620491895;19714.309999150013;19714.605707885832;22255.44361390962;20720.792792072076;20819.479513012182;21257.468563285925;21278.89360553199;21328.786712132875;940.2384068904175;941.0542404883083;943.32552225159;943.5423819447562;947.1806887042693;870.9782255443602;930.5626355612851;951.7525443384701;952.5475399185401;953.0850383631687;992.846108853129;996.2229461020415;999.9300048996573;1002.5989604158322;1003.1924300937778;983.4787562591802;991.3258983890951;992.0823238504355;992.6228033347318;993.7316924430436;727.5598263050831;728.8287252495659;729.9233580474026;730.2004305285703;730.4448619049507;19189.232430702756;19194.712079318822;19217.327393541298;19633.214671413127;19863.503412414662;20635;20655.89672051645;21063.789362106338;21137.47156321089;21167.364979050653;16439.506814795575;16923.746143807854;17101.743473847902;17107.82892171075;17318.134093295284;3686.8525258989607;3700.000999730085;3712.1276500022473;3712.9628703713156;3715.7398982071268;117246.41376793147;120377;121087.78912210853;123444;124608;15744.134072626115;16163.595910102342;16224.188790560425;16241.756373654405;16356.345746170258;29930.25174370658;30064.54903176454;30818.53772193419;30838.38323233523;30845.228869278;7248.6738096785775;7554;8122.350211983027;8266.586670666564;8405;4211.936820947688;4290.635295999875;4338.501072376654;4347.304431290978;4362.476502819619;4839.830605928775;4889.853304400874;4896.069746748107;4951.603871690256;4959.156943319645;948.7533241357158;958.5590628311055;970.0978090376018;970.7815741458115;971.6064993677572;638;687.7695971849387;702.1784512120729;710.2364957670525;710.9786706398817;11842.763144737057;11897.405129743478;13221.338933053308;13653.658658533615;13780.140005185482;693.4209934704477;699.1330749870125;699.576756062585;701.5229643842237;705.4603228530178;6259.311475737665;6269.310375858652;6451.000094985191;6490.350964903566;6558.213014438203;103818;108982;109949.45025274823;111523.88476115135;114003.42998284956;97042;99226;100799;100971;101285.49357253166;84472.99999999878;85962;86967.56516217504;87786;89232.5538372304;12643.87356126427;13098.738025239632;13195.736085278433;13253.07228493996;13285.202887826761;42821.57178428176;42913.57086429096;43168.56831431646;43848;44452;74102.25897740954;75451.62274188593;76040.23959760331;76685.61657191678;77698.6115069421;18570.864350599313;19157.233710651417;19341.903290483457;19408;19454;17629.382971595933;19908.60182796365;21520.677189842478;21957.231496897533;22579.43551411162;18408.447746567625;18697.90651046736;18737.812621873607;18758.437246882615;19774.70337944903;18123.81876181274;18279.1774370151;18519.53701157482;20019;20099.995000249637;18350;18496.81503184951;18824.435266942015;18943.90528047351;19004.619907601493;13297.26865022433;13619.863801362257;13947.302634868012;14366.928165359106;14523.273836307932;13287.80409763126;13997.741332613807;14435.78346324827;14552.708945821236;14650.34073466718 18:23:38 INFO - PID 17230 | __end_tp_report 18:23:38 INFO - PID 17230 | __start_cc_report 18:23:38 INFO - PID 17230 | _x_x_mozilla_cycle_collect,373 18:23:38 INFO - PID 17230 | __end_cc_report 18:23:38 INFO - PID 17230 | __startTimestamp1503624218320__endTimestamp 18:23:38 INFO - PID 17230 | 18:23:38 INFO - PID 17230 | ------- Summary: start ------- 18:23:38 INFO - PID 17230 | Number of tests: 6 18:23:38 INFO - PID 17230 | 18:23:38 INFO - PID 17230 | [#0] dojo.html Cycles:165 Average:6160.81 Median:6413.87 stddev:763.24 (11.9%) stddev-sans-first:730.85 18:23:38 INFO - PID 17230 | Values: 3259.2 3276.8 3290.2 3317.4 3341.0 6220.4 6288.1 6299.6 6305.6 6331.9 6115.6 6238.4 6246.7 6267.3 6367.0 5774.3 5800.9 5810.7 5850.4 5867.0 5583.8 5707.5 5721.0 5781.4 5798.0 5685.1 5767.2 5776.4 5807.0 5818.7 5654.2 5698.3 5702.5 5747.9 5764.8 5746.3 5767.1 5769.2 5772.7 5790.2 6160.5 6212.2 6258.8 6260.4 6273.0 6623.8 6676.6 6734.8 6771.5 6785.9 6560.4 6703.6 6710.0 6795.8 6808.0 6280.1 6300.7 6307.2 6310.9 6322.1 6892.6 6895.9 6930.5 6972.7 6986.8 6586.3 6602.6 6642.5 6704.1 6724.7 6540.1 6626.9 6653.6 6741.8 6749.3 6653.8 6719.6 6725.8 6739.6 6744.5 6566.2 6681.6 6684.5 6692.9 6703.6 4820.5 4827.7 4837.4 4882.8 4883.3 4320.1 4324.1 4326.9 4354.4 4355.3 5805.8 5870.4 5888.1 5891.3 5920.1 6713.0 6763.3 6847.6 6849.3 6868.9 5679.7 5795.2 5799.1 5872.5 5874.3 5699.6 5727.6 5747.5 5760.0 5780.2 6407.7 6460.3 6490.2 6545.9 6548.6 6607.4 6674.1 6744.7 6839.6 6844.8 6418.2 6448.9 6497.1 6503.0 6514.8 6623.9 6655.2 6662.9 6726.7 6733.5 6666.9 6767.7 6769.4 6854.7 6868.9 6450.8 6534.9 6541.1 6618.7 6628.6 6413.9 6449.7 6477.7 6512.5 6522.5 6301.6 6376.0 6428.5 6434.1 6528.5 6592.4 6606.1 6682.7 6693.5 6700.7 6337.6 6350.6 6398.6 6404.9 6440.3 18:23:38 INFO - PID 17230 | 18:23:38 INFO - PID 17230 | [#1] ext.html Cycles:180 Average:37729.30 Median:35970.87 stddev:54140.95 (150.5%) stddev-sans-first:54221.67 18:23:38 INFO - PID 17230 | Values: 762.2 804.6 815.6 845.1 847.1 39934.2 40832.0 40844.4 40855.8 40943.6 36818.0 37158.8 37176.0 37195.0 37389.3 6893.8 7010.5 7017.7 7020.3 7031.4 6871.7 6973.4 6979.7 7007.5 7009.9 6980.5 7004.4 7566.6 7728.3 7732.5 7666.4 7722.7 7729.2 7730.7 7732.4 7538.3 7612.3 7626.0 7632.5 7642.2 35910.5 35916.1 35963.3 35978.5 36008.3 3380.7 3412.5 3427.5 3429.4 3439.3 30468.5 30542.2 30542.9 30567.8 30570.2 5118.2 5133.5 5134.3 5147.0 5158.0 176144.1 179621.0 180233.0 182409.0 189646.0 47204.5 47251.8 47343.5 47360.4 47410.5 48984.8 49148.0 49172.5 49196.0 49214.3 15645.3 15716.2 15723.3 15729.6 15800.0 13077.4 13085.6 13123.7 13128.5 13135.7 13503.5 13541.7 13568.5 13644.6 13682.3 5675.1 5693.8 5746.3 5755.3 5758.3 987.8 994.7 998.0 1006.4 1009.2 22455.9 22517.9 22719.5 22777.4 22885.2 1090.4 1091.7 1092.0 1100.2 1110.2 11765.9 12063.7 12101.6 15164.6 15604.3 234077.0 234408.0 234595.0 234597.0 236104.0 54376.0 54855.7 54861.5 54876.7 54920.7 1374.2 1427.7 1437.6 1444.0 1457.2 26080.6 26136.7 26530.7 26548.0 26902.9 36753.4 37018.3 37058.8 37120.6 37398.0 205965.0 206481.0 206940.0 207630.0 207882.0 32899.5 36341.5 36981.3 37013.4 37150.4 44726.1 44823.8 44957.8 44989.6 45442.8 33175.5 33390.5 33531.7 33919.5 34049.5 32771.2 33034.8 33432.3 33463.5 33705.3 36410.5 36430.3 36821.3 36841.4 37019.4 32209.7 32784.0 32994.7 33136.3 33320.5 23819.5 24266.3 24722.0 24830.8 25067.6 18:23:38 INFO - PID 17230 | 18:23:38 INFO - PID 17230 | [#2] jquery.html Cycles:180 Average:36821.47 Median:22720.54 stddev:93556.53 (411.8%) stddev-sans-first:93779.32 18:23:38 INFO - PID 17230 | Values: 541.8 542.3 553.6 561.4 562.7 21517.9 22287.9 22292.7 22311.7 22410.0 23031.1 24012.2 25481.0 25489.6 25618.6 6584.4 6742.5 6749.2 6750.2 6751.5 5975.9 6503.3 6597.2 6608.9 6637.5 6463.0 6465.3 6469.7 6473.5 6476.4 6464.8 6470.2 6479.4 6483.0 6493.8 6005.2 6010.6 6023.0 6030.0 6045.7 28528.7 28637.7 29162.8 29199.0 29883.7 2024.3 2057.8 2061.3 2063.6 2066.0 19431.9 19631.8 19639.5 19667.6 19721.7 2367.3 2408.3 2460.0 2460.4 2475.9 125682.0 130855.3 132102.0 132299.3 132490.0 13664.6 14370.4 14754.6 14765.6 15167.2 23051.2 23058.3 23136.7 23139.5 23140.3 9943.7 10005.3 10037.8 10138.3 10185.8 9388.2 9422.7 9447.4 9449.7 9456.8 7504.3 8921.4 10911.7 11296.4 11581.0 3503.5 3699.8 3873.0 4002.0 4321.7 915.4 937.4 955.4 957.8 973.4 19562.2 19567.9 19630.2 19738.6 19802.0 991.5 1023.5 1028.5 1042.5 1047.7 10325.6 10743.9 10924.3 13387.6 14003.6 543291.0 560492.0 565355.0 566422.0 573899.0 114443.0 118179.0 118659.4 120109.0 122439.4 1215.4 1235.4 1251.3 1252.3 1252.5 20949.6 21112.3 21922.8 22010.8 22314.1 58016.0 58697.7 60198.0 60696.0 61357.7 67442.0 67718.7 67889.7 68058.3 68606.7 14615.6 14787.8 14868.4 14899.7 14981.3 23255.9 23568.1 23726.3 23808.0 23815.8 18483.5 18593.7 18777.0 18831.6 19006.2 16919.8 17029.9 17193.0 17393.3 17810.9 17820.0 19190.8 19331.9 19438.3 19512.4 14206.7 14686.9 14706.4 14737.0 14757.0 16864.8 17040.7 18712.7 18864.5 18897.0 18:23:38 INFO - PID 17230 | 18:23:38 INFO - PID 17230 | [#3] mootools.html Cycles:180 Average:9078.18 Median:2682.98 stddev:6383.26 (237.9%) stddev-sans-first:6374.28 18:23:38 INFO - PID 17230 | Values: 1281.5 1326.5 1332.6 1333.1 1338.0 14267.9 14939.1 15006.9 15044.3 15343.0 15461.9 15672.8 15682.9 15692.0 15746.0 2718.6 2771.3 2774.1 2775.6 2777.8 2647.9 2741.1 2818.8 2819.4 2819.9 2682.2 2703.9 2741.8 2744.5 2745.6 2653.0 2654.9 2693.9 2695.4 2729.9 2661.7 2670.3 2683.8 2688.5 2691.6 14419.7 14584.8 14587.9 14591.1 14615.6 7615.3 7880.4 7884.2 7907.1 7919.7 5204.6 5291.7 5342.5 5401.1 5417.0 1833.2 1862.5 1906.2 1927.2 1957.7 24321.6 25133.9 25140.5 25159.2 25191.1 7047.7 7089.8 7171.9 7342.9 7347.9 16294.2 16519.6 16520.6 16548.1 16574.8 7303.1 7404.4 7439.0 7445.2 7459.4 6865.2 6934.3 6949.8 7000.6 7012.9 5146.4 5262.9 5281.2 5300.8 5314.1 1514.4 1524.5 1547.2 1547.2 1550.5 896.2 897.6 902.8 909.8 916.1 7541.9 7625.8 7648.9 7662.6 7692.2 690.3 695.5 701.1 713.2 719.9 4540.7 4541.3 4668.7 4691.8 4741.2 19595.4 19857.8 20069.0 20475.4 20670.2 20481.2 20525.0 20873.5 20929.6 20974.8 10251.4 10416.5 10544.7 10618.6 10803.1 7928.9 8132.9 8169.1 8218.4 8274.6 17324.4 17792.8 17914.0 18057.2 18211.7 18285.8 18401.3 18698.0 18728.5 18898.3 11655.5 11993.7 12079.7 12119.5 12120.9 13431.7 13639.0 13686.0 13693.6 13713.6 10774.8 10796.5 10823.1 10861.7 11003.7 10866.8 10886.4 10895.7 11157.7 11232.7 9613.6 9823.4 9828.9 9829.6 9833.6 7088.8 7150.4 7152.2 7165.1 7177.1 7465.1 7481.3 7508.6 7515.5 7557.1 18:23:38 INFO - PID 17230 | 18:23:38 INFO - PID 17230 | [#4] prototype.html Cycles:180 Average:5549.63 Median:6186.42 stddev:1549.71 (25.1%) stddev-sans-first:1507.03 18:23:38 INFO - PID 17230 | Values: 501.9 503.0 515.3 517.8 524.5 6289.0 6352.8 6400.4 6428.7 6440.6 6041.7 6214.1 6227.3 6234.8 6242.9 5009.2 5015.4 5015.5 5050.2 5056.6 4872.4 4997.6 5019.4 5027.9 5069.0 4932.8 4973.9 5006.1 5014.8 5019.3 5003.8 5006.1 5006.5 5032.4 5032.8 4466.1 4491.9 4495.8 4515.7 4529.7 6138.3 6144.5 6145.5 6176.9 6196.0 5716.0 5731.6 5742.7 5750.0 5755.0 6497.4 6516.6 6571.6 6604.4 6665.4 4769.7 4770.3 4819.4 4829.6 4832.4 6834.5 6895.3 6932.4 6939.9 6944.4 4914.0 4957.7 5014.8 5043.2 5047.7 5021.8 5023.6 5027.3 5065.6 5095.5 5681.1 5682.6 5705.3 5720.5 5746.3 6553.4 6585.8 6625.0 6663.0 6692.5 1620.6 1663.4 1729.0 1778.4 1910.3 1409.7 1416.7 1418.5 1444.0 1452.1 5668.3 5693.9 5717.8 5744.7 5756.5 6615.5 6653.8 6670.2 6680.0 6824.8 5694.4 5789.9 5807.7 5839.1 5865.4 3548.3 3568.3 3594.3 3622.8 3649.6 6488.0 6523.6 6588.4 6602.3 6604.9 6933.8 7025.8 7038.4 7055.5 7074.5 6477.5 6487.0 6545.1 6608.2 6637.9 6748.4 6773.9 6779.7 6811.5 6874.4 6715.5 6742.0 6779.2 6804.5 6832.7 6511.3 6546.9 6576.9 6617.5 6651.4 4975.7 4993.2 5025.7 5060.0 5073.7 6876.1 6880.7 6884.3 6885.6 6895.4 6345.8 6399.7 6473.8 6548.6 6586.1 6484.4 6542.7 6587.2 6600.5 6634.7 6121.3 6356.5 6374.1 6479.4 6485.2 6504.8 6523.8 6637.1 6676.7 6705.2 6365.0 6400.7 6436.4 6449.2 6476.2 18:23:38 INFO - PID 17230 | 18:23:38 INFO - PID 17230 | [#5] yui.html Cycles:180 Average:24111.24 Median:29997.40 stddev:32125.11 (107.1%) stddev-sans-first:32167.70 18:23:38 INFO - PID 17230 | Values: 841.3 841.8 851.7 878.4 900.6 19007.0 19675.9 19714.3 19714.6 22255.4 20720.8 20819.5 21257.5 21278.9 21328.8 940.2 941.1 943.3 943.5 947.2 871.0 930.6 951.8 952.5 953.1 992.8 996.2 999.9 1002.6 1003.2 983.5 991.3 992.1 992.6 993.7 727.6 728.8 729.9 730.2 730.4 19189.2 19194.7 19217.3 19633.2 19863.5 20635.0 20655.9 21063.8 21137.5 21167.4 16439.5 16923.7 17101.7 17107.8 17318.1 3686.9 3700.0 3712.1 3713.0 3715.7 117246.4 120377.0 121087.8 123444.0 124608.0 15744.1 16163.6 16224.2 16241.8 16356.3 29930.3 30064.5 30818.5 30838.4 30845.2 7248.7 7554.0 8122.4 8266.6 8405.0 4211.9 4290.6 4338.5 4347.3 4362.5 4839.8 4889.9 4896.1 4951.6 4959.2 948.8 958.6 970.1 970.8 971.6 638.0 687.8 702.2 710.2 711.0 11842.8 11897.4 13221.3 13653.7 13780.1 693.4 699.1 699.6 701.5 705.5 6259.3 6269.3 6451.0 6490.4 6558.2 103818.0 108982.0 109949.5 111523.9 114003.4 97042.0 99226.0 100799.0 100971.0 101285.5 84473.0 85962.0 86967.6 87786.0 89232.6 12643.9 13098.7 13195.7 13253.1 13285.2 42821.6 42913.6 43168.6 43848.0 44452.0 74102.3 75451.6 76040.2 76685.6 77698.6 18570.9 19157.2 19341.9 19408.0 19454.0 17629.4 19908.6 21520.7 21957.2 22579.4 18408.4 18697.9 18737.8 18758.4 19774.7 18123.8 18279.2 18519.5 20019.0 20100.0 18350.0 18496.8 18824.4 18943.9 19004.6 13297.3 13619.9 13947.3 14366.9 14523.3 13287.8 13997.7 14435.8 14552.7 14650.3 18:23:38 INFO - PID 17230 | -------- Summary: end -------- 18:23:38 INFO - PID 17230 | 18:23:39 INFO - TEST-INFO | 17230: exit 0 18:23:39 INFO - TEST-OK | dromaeo_css | took 1110822ms 18:23:39 INFO - TEST-START | kraken 18:23:39 INFO - Initialising browser for kraken test... 18:23:39 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:54626/getInfo.html -profile /tmp/tmpHPRWY_/profile 18:23:39 INFO - TEST-INFO | started process 17644 (/builds/slave/test/build/application/firefox/firefox http://localhost:54626/getInfo.html) 18:23:44 INFO - TEST-INFO | 17644: exit 0 18:23:45 INFO - Browser initialized. 18:23:45 INFO - Running cycle 1/1 for kraken test... 18:23:45 INFO - TEST-INFO | started process 17820 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHPRWY_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:23:45 INFO - PID 17820 | 18:23:45 INFO - PID 17820 | (/builds/slave/test/build/application/firefox/firefox:17869): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:23:45 INFO - PID 17820 | 18:23:46 INFO - PID 17820 | 18:23:46 INFO - PID 17820 | (/builds/slave/test/build/application/firefox/firefox:17925): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:23:46 INFO - PID 17820 | 18:23:46 INFO - PID 17820 | __metrics Screen width/height:1600/1200 18:23:46 INFO - PID 17820 | colorDepth:24 18:23:46 INFO - PID 17820 | Browser inner width/height: 1024/768 18:23:46 INFO - PID 17820 | __metrics 18:23:56 INFO - PID 17820 | RSS: Main: 172830720 18:23:56 INFO - PID 17820 | 18:23:58 INFO - PID 17820 | Cycle 1(1): loaded http://localhost:54626/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54626/tests/kraken/driver.html?testName=audio-beat-detection) 18:23:59 INFO - PID 17820 | RSS: Main: 176734208 18:23:59 INFO - PID 17820 | 18:24:02 INFO - PID 17820 | Cycle 1(1): loaded http://localhost:54626/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54626/tests/kraken/driver.html?testName=audio-dft) 18:24:03 INFO - PID 17820 | RSS: Main: 177000448 18:24:03 INFO - PID 17820 | 18:24:06 INFO - PID 17820 | Cycle 1(1): loaded http://localhost:54626/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54626/tests/kraken/driver.html?testName=audio-fft) 18:24:06 INFO - PID 17820 | RSS: Main: 171159552 18:24:06 INFO - PID 17820 | 18:24:08 INFO - PID 17820 | Cycle 1(1): loaded http://localhost:54626/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54626/tests/kraken/driver.html?testName=audio-oscillator) 18:24:09 INFO - PID 17820 | RSS: Main: 172105728 18:24:09 INFO - PID 17820 | 18:24:11 INFO - PID 17820 | Cycle 1(1): loaded http://localhost:54626/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54626/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:24:12 INFO - PID 17820 | RSS: Main: 171884544 18:24:12 INFO - PID 17820 | 18:24:15 INFO - PID 17820 | Cycle 1(1): loaded http://localhost:54626/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54626/tests/kraken/driver.html?testName=imaging-darkroom) 18:24:15 INFO - PID 17820 | RSS: Main: 172670976 18:24:15 INFO - PID 17820 | 18:24:19 INFO - PID 17820 | Cycle 1(1): loaded http://localhost:54626/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54626/tests/kraken/driver.html?testName=imaging-desaturate) 18:24:20 INFO - PID 17820 | RSS: Main: 172638208 18:24:20 INFO - PID 17820 | 18:24:22 INFO - PID 17820 | Cycle 1(1): loaded http://localhost:54626/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54626/tests/kraken/driver.html?testName=json-parse-financial) 18:24:23 INFO - PID 17820 | RSS: Main: 173604864 18:24:23 INFO - PID 17820 | 18:24:25 INFO - PID 17820 | Cycle 1(1): loaded http://localhost:54626/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54626/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:24:25 INFO - PID 17820 | RSS: Main: 173506560 18:24:25 INFO - PID 17820 | 18:24:27 INFO - PID 17820 | Cycle 1(1): loaded http://localhost:54626/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54626/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:24:28 INFO - PID 17820 | RSS: Main: 173375488 18:24:28 INFO - PID 17820 | 18:24:30 INFO - PID 17820 | Cycle 1(1): loaded http://localhost:54626/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54626/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:24:30 INFO - PID 17820 | RSS: Main: 173432832 18:24:30 INFO - PID 17820 | 18:24:33 INFO - PID 17820 | Cycle 1(1): loaded http://localhost:54626/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54626/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:24:34 INFO - PID 17820 | RSS: Main: 174510080 18:24:34 INFO - PID 17820 | 18:24:37 INFO - PID 17820 | Cycle 1(1): loaded http://localhost:54626/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54626/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:24:37 INFO - PID 17820 | RSS: Main: 173912064 18:24:37 INFO - PID 17820 | 18:24:39 INFO - PID 17820 | Cycle 1(1): loaded http://localhost:54626/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54626/tests/kraken/driver.html?testName=ai-astar) 18:24:39 INFO - PID 17820 | RSS: Main: 175689728 18:24:39 INFO - PID 17820 | 18:24:39 INFO - PID 17820 | __start_tp_report 18:24:39 INFO - PID 17820 | _x_x_mozilla_page_load 18:24:39 INFO - PID 17820 | _x_x_mozilla_page_load_details 18:24:39 INFO - PID 17820 | |i|pagename|runs| 18:24:39 INFO - PID 17820 | |0;ai-astar;101;107;100;107;107;109;108;99;111;108 18:24:39 INFO - PID 17820 | |1;audio-beat-detection;155;118;150;182;122;147;143;120;172;117 18:24:39 INFO - PID 17820 | |2;audio-dft;163;162;162;161;164;164;163;166;164;165 18:24:39 INFO - PID 17820 | |3;audio-fft;74;73;74;168;76;75;76;73;166;75 18:24:39 INFO - PID 17820 | |4;audio-oscillator;79;78;126;80;78;81;131;79;79;128 18:24:39 INFO - PID 17820 | |5;imaging-gaussian-blur;114;113;114;115;114;117;116;117;117;114 18:24:39 INFO - PID 17820 | |6;imaging-darkroom;149;149;151;149;152;148;149;148;148;147 18:24:39 INFO - PID 17820 | |7;imaging-desaturate;94;94;94;94;94;94;99;99;99;95 18:24:39 INFO - PID 17820 | |8;json-parse-financial;52;55;59;51;53;54;55;54;52;50 18:24:39 INFO - PID 17820 | |9;json-stringify-tinderbox;55;54;59;56;54;55;58;56;55;55 18:24:39 INFO - PID 17820 | |10;stanford-crypto-aes;75;74;76;76;74;74;82;74;75;76 18:24:39 INFO - PID 17820 | |11;stanford-crypto-ccm;126;119;118;116;118;116;125;125;119;155 18:24:39 INFO - PID 17820 | |12;stanford-crypto-pbkdf2;172;167;170;167;176;173;176;173;169;176 18:24:39 INFO - PID 17820 | |13;stanford-crypto-sha256-iterative;66;68;63;62;63;63;61;63;65;68 18:24:39 INFO - PID 17820 | __end_tp_report 18:24:39 INFO - PID 17820 | __start_cc_report 18:24:39 INFO - PID 17820 | _x_x_mozilla_cycle_collect,950 18:24:39 INFO - PID 17820 | __end_cc_report 18:24:39 INFO - PID 17820 | __startTimestamp1503624279853__endTimestamp 18:24:39 INFO - PID 17820 | 18:24:39 INFO - PID 17820 | ------- Summary: start ------- 18:24:39 INFO - PID 17820 | Number of tests: 14 18:24:39 INFO - PID 17820 | 18:24:39 INFO - PID 17820 | [#0] ai-astar Cycles:10 Average:105.70 Median:108.00 stddev:4.14 (3.8%) stddev-sans-first:4.02 18:24:39 INFO - PID 17820 | Values: 101.0 107.0 100.0 107.0 107.0 109.0 108.0 99.0 111.0 108.0 18:24:39 INFO - PID 17820 | 18:24:39 INFO - PID 17820 | [#1] audio-beat-detection Cycles:10 Average:142.60 Median:148.50 stddev:23.19 (15.6%) stddev-sans-first:24.16 18:24:39 INFO - PID 17820 | Values: 155.0 118.0 150.0 182.0 122.0 147.0 143.0 120.0 172.0 117.0 18:24:39 INFO - PID 17820 | 18:24:39 INFO - PID 17820 | [#2] audio-dft Cycles:10 Average:163.40 Median:164.00 stddev:1.51 (0.9%) stddev-sans-first:1.59 18:24:39 INFO - PID 17820 | Values: 163.0 162.0 162.0 161.0 164.0 164.0 163.0 166.0 164.0 165.0 18:24:39 INFO - PID 17820 | 18:24:39 INFO - PID 17820 | [#3] audio-fft Cycles:10 Average:93.00 Median:74.50 stddev:39.02 (52.4%) stddev-sans-first:40.78 18:24:39 INFO - PID 17820 | Values: 74.0 73.0 74.0 168.0 76.0 75.0 76.0 73.0 166.0 75.0 18:24:39 INFO - PID 17820 | 18:24:39 INFO - PID 17820 | [#4] audio-oscillator Cycles:10 Average:93.90 Median:79.00 stddev:23.81 (30.1%) stddev-sans-first:24.63 18:24:39 INFO - PID 17820 | Values: 79.0 78.0 126.0 80.0 78.0 81.0 131.0 79.0 79.0 128.0 18:24:39 INFO - PID 17820 | 18:24:39 INFO - PID 17820 | [#5] imaging-gaussian-blur Cycles:10 Average:115.10 Median:115.50 stddev:1.52 (1.3%) stddev-sans-first:1.56 18:24:39 INFO - PID 17820 | Values: 114.0 113.0 114.0 115.0 114.0 117.0 116.0 117.0 117.0 114.0 18:24:39 INFO - PID 17820 | 18:24:39 INFO - PID 17820 | [#6] imaging-darkroom Cycles:10 Average:149.00 Median:149.00 stddev:1.49 (1.0%) stddev-sans-first:1.58 18:24:39 INFO - PID 17820 | Values: 149.0 149.0 151.0 149.0 152.0 148.0 149.0 148.0 148.0 147.0 18:24:39 INFO - PID 17820 | 18:24:39 INFO - PID 17820 | [#7] imaging-desaturate Cycles:10 Average:95.60 Median:94.50 stddev:2.37 (2.5%) stddev-sans-first:2.44 18:24:39 INFO - PID 17820 | Values: 94.0 94.0 94.0 94.0 94.0 94.0 99.0 99.0 99.0 95.0 18:24:39 INFO - PID 17820 | 18:24:39 INFO - PID 17820 | [#8] json-parse-financial Cycles:10 Average:53.50 Median:54.00 stddev:2.55 (4.7%) stddev-sans-first:2.65 18:24:39 INFO - PID 17820 | Values: 52.0 55.0 59.0 51.0 53.0 54.0 55.0 54.0 52.0 50.0 18:24:39 INFO - PID 17820 | 18:24:39 INFO - PID 17820 | [#9] json-stringify-tinderbox Cycles:10 Average:55.70 Median:55.50 stddev:1.64 (2.9%) stddev-sans-first:1.72 18:24:39 INFO - PID 17820 | Values: 55.0 54.0 59.0 56.0 54.0 55.0 58.0 56.0 55.0 55.0 18:24:39 INFO - PID 17820 | 18:24:39 INFO - PID 17820 | [#10] stanford-crypto-aes Cycles:10 Average:75.60 Median:75.50 stddev:2.41 (3.2%) stddev-sans-first:2.55 18:24:39 INFO - PID 17820 | Values: 75.0 74.0 76.0 76.0 74.0 74.0 82.0 74.0 75.0 76.0 18:24:39 INFO - PID 17820 | 18:24:39 INFO - PID 17820 | [#11] stanford-crypto-ccm Cycles:10 Average:123.70 Median:122.00 stddev:11.62 (9.5%) stddev-sans-first:12.30 18:24:39 INFO - PID 17820 | Values: 126.0 119.0 118.0 116.0 118.0 116.0 125.0 125.0 119.0 155.0 18:24:39 INFO - PID 17820 | 18:24:39 INFO - PID 17820 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.90 Median:173.00 stddev:3.54 (2.0%) stddev-sans-first:3.76 18:24:39 INFO - PID 17820 | Values: 172.0 167.0 170.0 167.0 176.0 173.0 176.0 173.0 169.0 176.0 18:24:39 INFO - PID 17820 | 18:24:39 INFO - PID 17820 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.20 Median:64.00 stddev:2.44 (3.8%) stddev-sans-first:2.50 18:24:39 INFO - PID 17820 | Values: 66.0 68.0 63.0 62.0 63.0 63.0 61.0 63.0 65.0 68.0 18:24:39 INFO - PID 17820 | -------- Summary: end -------- 18:24:39 INFO - PID 17820 | 18:24:40 INFO - PID 17820 | 18:24:40 INFO - PID 17820 | ###!!! [Parent][MessageChannel] Error: (msgtype=0x150081,name=PBrowser::Msg_Destroy) Closed channel: cannot send/recv 18:24:40 INFO - PID 17820 | 18:24:40 INFO - PID 17820 | 18:24:40 INFO - PID 17820 | ###!!! [Child][MessageChannel] Error: (msgtype=0x150001,name=PBrowser::Msg_AsyncMessage) Closed channel: cannot send/recv 18:24:40 INFO - PID 17820 | 18:24:40 INFO - PID 17820 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 18:24:40 INFO - TEST-INFO | 17820: exit 0 18:24:40 INFO - TEST-OK | kraken | took 61620ms 18:24:40 INFO - SUITE-END | took 1172s 18:24:41 INFO - Completed test suite (00:19:32) 18:24:41 INFO - javascript benchmark 18:24:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8544.380152548098, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3259.20149563357, 3276.7706260561763, 3290.226796702774, 3317.4194515959703, 3341.0, 6220.377962203777, 6288.119663247149, 6299.622022678643, 6305.621662700236, 6331.905021424676, 6115.571909966304, 6238.376162383759, 6246.656433896134, 6267.341929097447, 6367.044943258502, 5774.335951365584, 5800.854978625547, 5810.6513609183585, 5850.385709500505, 5867.031939729939, 5583.832485025436, 5707.486326230638, 5720.998825205593, 5781.421857814206, 5797.971010144944, 5685.118806584986, 5767.163761254616, 5776.39347868474, 5807.0, 5818.679972601505, 5654.15187721845, 5698.316202055738, 5702.4582664647205, 5747.885042299173, 5764.798232061899, 5746.281714785652, 5767.0772676372, 5769.163471296681, 5772.682502462363, 5790.247267855152, 6160.507159427235, 6212.161358216673, 6258.812235632939, 6260.40526150015, 6273.0, 6623.801285961381, 6676.566023208476, 6734.797956061326, 6771.525993180479, 6785.932140678579, 6560.442362399202, 6703.631300278483, 6709.966450167718, 6795.762148324785, 6808.0, 6280.120783090371, 6300.747970081191, 6307.211598550135, 6310.87378252437, 6322.083297921776, 6892.551984121016, 6895.862082758316, 6930.514863959575, 6972.686229119702, 6986.777313970035, 6586.27550969393, 6602.570832895846, 6642.501812364092, 6704.128463299739, 6724.66376681164, 6540.149780528501, 6626.867462650816, 6653.63405012729, 6741.831454213586, 6749.3250674932115, 6653.767118150841, 6719.563228390139, 6725.831854203684, 6739.59562426256, 6744.460443164535, 6566.212054553484, 6681.599104053772, 6684.532082754258, 6692.899606505906, 6703.564268322543, 4820.517948205152, 4827.686200397034, 4837.443693975168, 4882.804687812519, 4883.316335713003, 4320.135972805451, 4324.070324880166, 4326.913461730748, 4354.390385346054, 4355.259605866947, 5805.82582522525, 5870.442307980735, 5888.087346461305, 5891.263592050994, 5920.111983202554, 6713.0, 6763.323667633197, 6847.554908930903, 6849.315068493211, 6868.896966545506, 5679.716014199356, 5795.1886735857015, 5799.072148456226, 5872.471477567039, 5874.32445268791, 5699.572532060112, 5727.570432217517, 5747.453991870766, 5760.0, 5780.16187652788, 6407.679616019181, 6460.289368169498, 6490.188726409199, 6545.934540654628, 6548.607083574905, 6607.372299631527, 6674.132362792903, 6744.730210791709, 6839.62382068991, 6844.828879278058, 6418.19772528434, 6448.903266450913, 6497.09040734307, 6502.967485162544, 6514.804555863332, 6623.867522649616, 6655.168103987002, 6662.900056499253, 6726.663666816542, 6733.461323094239, 6666.899996500153, 6767.695453704497, 6769.424598909148, 6854.725810967506, 6868.862622747417, 6450.838729031624, 6534.869302614016, 6541.149650545399, 6618.6690665467495, 6628.635425051669, 6413.871722565429, 6449.7420103195345, 6477.740890364333, 6512.47900167976, 6522.478201743944, 6301.6219026858535, 6375.968120159369, 6428.453581445442, 6434.131392262172, 6528.543001989815, 6592.406683398517, 6606.075149479173, 6682.665866706548, 6693.464522838259, 6700.66496675174, 6337.6197428154455, 6350.587211831216, 6398.584092034003, 6404.9039264412, 6440.2915679276175], "value": 6102.264218567526, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [762.1578156137468, 804.5574933786417, 815.6492708135502, 845.0915266088956, 847.1359213602124, 39934.20131597366, 40832.0, 40844.38733418994, 40855.79572102143, 40943.59056409435, 36818.0, 37158.814205929, 37176.0, 37195.0, 37389.25221495568, 6893.7931862044215, 7010.544314619533, 7017.71929122837, 7020.262872398402, 7031.402330801888, 6871.656417179146, 6973.372396484316, 6979.720811167547, 7007.509474336773, 7009.8598028039405, 6980.546264492792, 7004.4396448284015, 7566.621668916559, 7728.304452599265, 7732.45872788905, 7666.3866890648615, 7722.7297044603165, 7729.227077292282, 7730.652120654556, 7732.381409487256, 7538.3215510604305, 7612.276833700789, 7626.0, 7632.465727399084, 7642.159362470124, 35910.46134307988, 35916.10209744751, 35963.28073438517, 35978.460323094914, 36008.27983440342, 3380.7126394256516, 3412.505186747908, 3427.520147179397, 3429.3998550253855, 3439.329330780499, 30468.54297185544, 30542.236444088852, 30542.930997414984, 30567.847160764053, 30570.235744106354, 5118.155504341817, 5133.512316329943, 5134.2812006319145, 5147.0, 5158.0, 176144.11927940277, 179621.0, 180233.0, 182409.0, 189646.0, 47204.52795472001, 47251.76374118107, 47343.52656473391, 47360.39517826484, 47410.52589474061, 48984.755076225105, 49148.0, 49172.508274917505, 49196.0, 49214.261786073235, 15645.295961681752, 15716.214189290718, 15723.292451839694, 15729.606759830867, 15800.0, 13077.411516481781, 13085.607431777062, 13123.737525249633, 13128.474861005643, 13135.671608209681, 13503.459861605623, 13541.729165416637, 13568.525101621395, 13644.590662280147, 13682.31588420575, 5675.063614503643, 5693.755380504267, 5746.310442746897, 5755.309362876482, 5758.3090029196765, 987.7975015121883, 994.721477986165, 997.9900200998043, 1006.4062203300089, 1009.2228983682523, 22455.88772056129, 22517.887410562842, 22719.545609087727, 22777.430564236027, 22885.199018034287, 1090.4166271045112, 1091.6779550032759, 1091.9672409827558, 1100.2408338246585, 1110.245033377295, 11765.88234117665, 12063.698407539881, 12101.636950891312, 15164.620884477976, 15604.273858299957, 234077.0, 234408.0, 234595.0, 234597.0, 236104.0, 54376.0, 54855.725721371935, 54861.451385486434, 54876.72561637246, 54920.72539637276, 1374.2441657088561, 1427.7144571085616, 1437.590286768266, 1444.0108525660166, 1457.1912588513264, 26080.608790868533, 26136.73863261419, 26530.734692652826, 26548.0, 26902.865485672446, 36753.448698270084, 37018.25963480769, 37058.8147059263, 37120.6287937128, 37398.0, 205965.0, 206481.0, 206940.0, 207630.0, 207882.0, 32899.54554771714, 36341.45487817632, 36981.260374792895, 37013.44479832751, 37150.44274335941, 44726.10547788961, 44823.77588112169, 44957.775211123735, 44989.550104498536, 45442.77278613718, 33175.50236746499, 33390.49914251337, 33531.66468335286, 33919.491207631414, 34049.48925766165, 32771.18072048218, 33034.83482582572, 33432.33135337328, 33463.49804752882, 33705.325893482484, 36410.45384319184, 36430.27139457143, 36821.26357472889, 36841.447378289886, 37019.44470832886, 32209.677903221604, 32784.0, 32994.67005329916, 33136.33727325488, 33320.50019249665, 23819.519411458903, 24266.272011839672, 24722.0, 24830.751692482845, 25067.623985639864], "value": 17065.734544849576, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [541.7979739738148, 542.2516926641233, 553.6456667732651, 561.3768716724433, 562.721452880824, 21517.892410537945, 22287.888560557196, 22292.66561001586, 22311.665325020138, 22410.0, 23031.078756849747, 24012.159574414898, 25481.0, 25489.61765573518, 25618.615720764203, 6584.374484423972, 6742.52802303839, 6749.156355455568, 6750.1562304711915, 6751.493637977147, 5975.910361344583, 6503.252126005499, 6597.2083349998065, 6608.933910660904, 6637.502187335969, 6463.030545418178, 6465.321141280168, 6469.7412103516035, 6473.482121430275, 6476.417122458978, 6464.806055818333, 6470.191226096738, 6479.384458476461, 6483.0275458681335, 6493.805185844432, 6005.219321488178, 6010.609310394811, 6023.0, 6030.035194368924, 6045.697715114226, 28528.714712852812, 28637.713622863714, 29162.833486660507, 29198.978035768647, 29883.70116298831, 2024.2611446821952, 2057.753069631654, 2061.29915828619, 2063.5769667218183, 2066.028966385796, 19431.902840485847, 19631.803681963283, 19639.509012274666, 19667.606647867105, 19721.704174437396, 2367.263127016727, 2408.3256688127176, 2459.9508009839883, 2460.4463995601027, 2475.851448913053, 125682.0, 130855.34572327172, 132102.0, 132299.33850330784, 132490.0, 13664.590062298146, 14370.425182992563, 14754.63113422173, 14765.630859228606, 15167.241637918281, 23051.19320823763, 23058.308250752503, 23136.65295020576, 23139.537209256057, 23140.305790826304, 9943.701688949342, 10005.349652272578, 10037.79924401508, 10138.290319677551, 10185.847212291821, 9388.2020028297, 9422.670206542874, 9447.433154010781, 9449.716508504756, 9456.763580910532, 7504.324610784946, 8921.420107692978, 10911.727206819893, 11296.378699171624, 11581.0, 3503.5445392098864, 3699.7780133191554, 3872.980635096863, 4002.0, 4321.69748117635, 915.354674954169, 937.4141161773891, 955.3551352836807, 957.8132264208413, 973.4110862927968, 19562.217511299674, 19567.90216048939, 19630.21479140847, 19738.60522789536, 19802.0, 991.4943378876825, 1023.4933707814679, 1028.4600584693096, 1042.525650828871, 1047.701405099543, 10325.586976520855, 10743.946280268548, 10924.344539327665, 13387.598372048855, 14003.649908752159, 543291.0, 560492.0, 565355.0, 566422.0, 573899.0, 114443.0, 118179.0, 118659.40670296767, 120109.0, 122439.38780306041, 1215.3558613934629, 1235.3884827010688, 1251.3305381620607, 1252.2799390350585, 1252.511520507003, 20949.581008380053, 21112.26107086306, 21922.780772192713, 22010.779892200873, 22314.10743570239, 58016.0, 58697.70651146717, 60198.0, 60696.0, 61357.69321153365, 67442.0, 67718.66140669266, 67889.66055169693, 68058.31941680718, 68606.65696671484, 14615.561533154023, 14787.778183327473, 14868.405263789327, 14899.702005959603, 14981.250937453302, 23255.883720581965, 23568.0572777087, 23726.288211353687, 23808.0, 23815.761842382046, 18483.537911552317, 18593.72109418333, 18777.0, 18831.6233675323, 19006.23975040983, 16919.830801691827, 17029.91485042567, 17193.0, 17393.304267829146, 17810.910945445707, 17820.0, 19190.80809191946, 19331.903340483208, 19438.319658811874, 19512.414627561197, 14206.715865682836, 14686.926565367105, 14706.411743530567, 14737.0, 14757.0, 16864.831351686327, 17040.744388834424, 18712.719309210646, 18864.52838679044, 18897.0], "value": 12243.847823214337, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1281.5130250504806, 1326.4959315460123, 1332.6202032420763, 1333.0868355176706, 1338.0098726942065, 14267.857321426782, 14939.103653780767, 15006.924965375172, 15044.323005464754, 15343.0, 15461.92269038656, 15672.764908526382, 15682.921585392058, 15692.0, 15746.0, 2718.6329845470905, 2771.2933202033455, 2774.0706863200753, 2775.5697866830674, 2777.750002499775, 2647.9205623831317, 2741.0817376179, 2818.8308701477877, 2819.3797364579864, 2819.8872045118273, 2682.168527756392, 2703.8512881791494, 2741.7806575473915, 2744.5059889219933, 2745.6156138140677, 2653.01838319821, 2654.8539830309323, 2693.892244310225, 2695.3800625856065, 2729.890804367823, 2661.7072122066556, 2670.3457652874977, 2683.7987150963754, 2688.502627014009, 2691.6407214356623, 14419.711605767827, 14584.781228281585, 14587.854121458755, 14591.124532528082, 14615.561533154023, 7615.31462168402, 7880.408969327324, 7884.17216192303, 7907.090684571291, 7919.683212671486, 5204.594759414937, 5291.656042357235, 5342.492463216027, 5401.108817045183, 5416.972915135439, 1833.230043381783, 1862.5436767991794, 1906.1517624657033, 1927.1905799564076, 1957.667196576571, 24321.635175472384, 25133.874330628412, 25140.497190056463, 25159.24522264335, 25191.118310859216, 7047.718091276394, 7089.82275443118, 7171.92828071723, 7342.926570734331, 7347.88978165328, 16294.185290735415, 16519.587010324598, 16520.586985325463, 16548.089855057846, 16574.83425165733, 7303.087114110736, 7404.407647388196, 7439.03292571973, 7445.181030086686, 7459.440541959375, 6865.210500792373, 6934.271901450376, 6949.791506254821, 7000.579965202104, 7012.894806577905, 5146.382434107931, 5262.868428289246, 5281.181416880389, 5300.787968481218, 5314.1497360422145, 1514.4245186829155, 1524.5350168198584, 1547.2186545794302, 1547.2186545794302, 1550.4650895441055, 896.1755185229605, 897.5736525150501, 902.8194361127801, 909.8498747706554, 916.0793402630314, 7541.886871696929, 7625.847483050419, 7648.885266720892, 7662.61686915652, 7692.230776922263, 690.2993461636443, 695.4818660098158, 701.1340993872564, 713.1905287498727, 719.9460040496983, 4540.682152249311, 4541.2733962565835, 4668.743219122981, 4691.812327506861, 4741.217699079613, 19595.412137635893, 19857.801421985594, 20069.0, 20475.38573842787, 20670.17319307241, 20481.180752770022, 20524.95975840085, 20873.47816304574, 20929.58140837175, 20974.790252097588, 10251.38491690486, 10416.479176041168, 10544.683659490227, 10618.628348007936, 10803.081738052353, 7928.920710793049, 8132.87800683002, 8169.101398846241, 8218.383621228551, 8274.627641756015, 17324.39364622232, 17792.822071779116, 17914.0, 18057.18742656557, 18211.726824097914, 18285.81714182841, 18401.263949441873, 18697.971611561494, 18728.53178670544, 18898.338558150397, 11655.53377864876, 11993.700157496132, 12079.698007549881, 12119.45462454174, 12120.939395302967, 13431.664208394868, 13639.0, 13686.0, 13693.589192324247, 13713.588592342247, 10774.838377424501, 10796.514156862802, 10823.134149267882, 10861.728456788644, 11003.724906877072, 10866.836997445203, 10886.40124793144, 10895.673129806119, 11157.66527004191, 11232.719182020188, 9613.615455381987, 9823.36148150368, 9828.852567211354, 9829.606815727291, 9833.557489913113, 7088.787336379917, 7150.356467917913, 7152.177499587406, 7165.104361954755, 7177.102862142232, 7465.06686664167, 7481.289277518627, 7508.587027713528, 7515.511491753018, 7557.130929942907], "value": 6498.481325830033, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [501.86591172307317, 503.0456675814462, 515.3146315400518, 517.813587108641, 524.512203650605, 6288.962321216999, 6352.841178970528, 6400.359964003597, 6428.678566071701, 6440.645764482952, 6041.728122234499, 6214.130021796941, 6227.28386235583, 6234.84412889678, 6242.875142497159, 5009.2486127080865, 5015.423226328964, 5015.473375295596, 5050.217216331474, 5056.620753443488, 4872.415310162785, 4997.600671811899, 5019.389917243031, 5027.949720502803, 5068.974655126719, 4932.804901818692, 4973.900521989558, 5006.123928312549, 5014.8244811431605, 5019.322391477159, 5003.849884503435, 5006.123928312567, 5006.523075692679, 5032.370953630796, 5032.798688052492, 4466.12910482456, 4491.910161796745, 4495.820167193308, 4515.706479078849, 4529.705569137996, 6138.263408391022, 6144.477719393857, 6145.539084568675, 6176.8764624707255, 6195.969020154916, 5715.971420142872, 5731.598788084836, 5742.655440673531, 5750.0, 5755.0, 6497.447716944065, 6516.609003459807, 6571.638559879205, 6604.438622717074, 6665.366790154976, 4769.708900858396, 4770.260609605517, 4819.445763737145, 4829.5653391194965, 4832.371791667061, 6834.496410789718, 6895.34494223048, 6932.376086152271, 6939.88961766115, 6944.4444444444325, 4914.0, 4957.677750946177, 5014.774335154927, 5043.218301163325, 5047.722375269322, 5021.8242361517905, 5023.623228257895, 5027.271045698353, 5065.594752419797, 5095.464976177522, 5681.0910254359105, 5682.602217844794, 5705.343885453143, 5720.542356611544, 5746.281714785652, 6553.394418367435, 6585.769498067641, 6624.979381288656, 6663.0, 6692.498062645321, 1620.6434584391416, 1663.4344322930262, 1728.9827101728822, 1778.3953455824824, 1910.2740958435984, 1409.7180563887264, 1416.6529200345776, 1418.4609848257596, 1443.9928499385653, 1452.1359790924382, 5668.291463567054, 5693.914591281134, 5717.828465146053, 5744.684042377709, 5756.480408994627, 6615.536912416181, 6653.800385988428, 6670.199576050905, 6679.960617918835, 6824.829379265459, 5694.430556944272, 5789.942100579025, 5807.680577568274, 5839.124131380242, 5865.384134665884, 3548.27260411615, 3568.3398571264397, 3594.25678545905, 3622.7645203061716, 3649.5802982656805, 6488.0, 6523.64119973406, 6588.40704336612, 6602.3397660233595, 6604.900926486107, 6933.757318493828, 7025.789226323219, 7038.3665470107935, 7055.470839687044, 7074.469414793911, 6477.481801455872, 6487.026945958144, 6545.149130613085, 6608.1827497437625, 6637.900431493532, 6748.392644661808, 6773.93226067733, 6779.661016949034, 6811.467419830498, 6874.3909447540445, 6715.496337774589, 6742.0, 6779.186497620317, 6804.523683342119, 6832.692528836114, 6511.3163117871945, 6546.901796472961, 6576.868462630625, 6617.503687223574, 6651.368120028589, 4975.651704380659, 4993.15116430215, 5025.74871256443, 5060.0, 5073.746312684277, 6876.140482439695, 6880.724771009103, 6884.277150899284, 6885.586864788129, 6895.379415852597, 6345.8106953101105, 6399.6800159992745, 6473.827824365932, 6548.639824809681, 6586.1438013058, 6484.383983521558, 6542.663241746648, 6587.176602924635, 6600.504962127764, 6634.734610615521, 6121.32665406796, 6356.506221038145, 6374.131640761769, 6479.352064793483, 6485.221773387224, 6504.837379065561, 6523.7716679915975, 6637.103990961158, 6676.699548520425, 6705.161854768154, 6364.968175159094, 6400.679966001774, 6436.388543088399, 6449.226092868698, 6476.158099447231], "value": 5103.579409734594, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [841.2722994609661, 841.7685136587442, 851.7146755836794, 878.3719640457074, 900.6082354175936, 19007.0, 19675.901620491895, 19714.309999150013, 19714.605707885832, 22255.44361390962, 20720.792792072076, 20819.479513012182, 21257.468563285925, 21278.89360553199, 21328.786712132875, 940.2384068904175, 941.0542404883083, 943.32552225159, 943.5423819447562, 947.1806887042693, 870.9782255443602, 930.5626355612851, 951.7525443384701, 952.5475399185401, 953.0850383631687, 992.846108853129, 996.2229461020415, 999.9300048996573, 1002.5989604158322, 1003.1924300937778, 983.4787562591802, 991.3258983890951, 992.0823238504355, 992.6228033347318, 993.7316924430436, 727.5598263050831, 728.8287252495659, 729.9233580474026, 730.2004305285703, 730.4448619049507, 19189.232430702756, 19194.712079318822, 19217.327393541298, 19633.214671413127, 19863.503412414662, 20635.0, 20655.89672051645, 21063.789362106338, 21137.47156321089, 21167.364979050653, 16439.506814795575, 16923.746143807854, 17101.743473847902, 17107.82892171075, 17318.134093295284, 3686.8525258989607, 3700.000999730085, 3712.1276500022473, 3712.9628703713156, 3715.7398982071268, 117246.41376793147, 120377.0, 121087.78912210853, 123444.0, 124608.0, 15744.134072626115, 16163.595910102342, 16224.188790560425, 16241.756373654405, 16356.345746170258, 29930.25174370658, 30064.54903176454, 30818.53772193419, 30838.38323233523, 30845.228869278, 7248.6738096785775, 7554.0, 8122.350211983027, 8266.586670666564, 8405.0, 4211.936820947688, 4290.635295999875, 4338.501072376654, 4347.304431290978, 4362.476502819619, 4839.830605928775, 4889.853304400874, 4896.069746748107, 4951.603871690256, 4959.156943319645, 948.7533241357158, 958.5590628311055, 970.0978090376018, 970.7815741458115, 971.6064993677572, 638.0, 687.7695971849387, 702.1784512120729, 710.2364957670525, 710.9786706398817, 11842.763144737057, 11897.405129743478, 13221.338933053308, 13653.658658533615, 13780.140005185482, 693.4209934704477, 699.1330749870125, 699.576756062585, 701.5229643842237, 705.4603228530178, 6259.311475737665, 6269.310375858652, 6451.000094985191, 6490.350964903566, 6558.213014438203, 103818.0, 108982.0, 109949.45025274823, 111523.88476115135, 114003.42998284956, 97042.0, 99226.0, 100799.0, 100971.0, 101285.49357253166, 84472.99999999878, 85962.0, 86967.56516217504, 87786.0, 89232.5538372304, 12643.87356126427, 13098.738025239632, 13195.736085278433, 13253.07228493996, 13285.202887826761, 42821.57178428176, 42913.57086429096, 43168.56831431646, 43848.0, 44452.0, 74102.25897740954, 75451.62274188593, 76040.23959760331, 76685.61657191678, 77698.6115069421, 18570.864350599313, 19157.233710651417, 19341.903290483457, 19408.0, 19454.0, 17629.382971595933, 19908.60182796365, 21520.677189842478, 21957.231496897533, 22579.43551411162, 18408.447746567625, 18697.90651046736, 18737.812621873607, 18758.437246882615, 19774.70337944903, 18123.81876181274, 18279.1774370151, 18519.53701157482, 20019.0, 20099.995000249637, 18350.0, 18496.81503184951, 18824.435266942015, 18943.90528047351, 19004.619907601493, 13297.26865022433, 13619.863801362257, 13947.302634868012, 14366.928165359106, 14523.273836307932, 13287.80409763126, 13997.741332613807, 14435.78346324827, 14552.708945821236, 14650.34073466718], "value": 9201.030407375223, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1502.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 107.0, 100.0, 107.0, 107.0, 109.0, 108.0, 99.0, 111.0, 108.0], "value": 105.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 118.0, 150.0, 182.0, 122.0, 147.0, 143.0, 120.0, 172.0, 117.0], "value": 142.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 162.0, 161.0, 164.0, 164.0, 163.0, 166.0, 164.0, 165.0], "value": 163.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 73.0, 74.0, 168.0, 76.0, 75.0, 76.0, 73.0, 166.0, 75.0], "value": 93.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 126.0, 80.0, 78.0, 81.0, 131.0, 79.0, 79.0, 128.0], "value": 93.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 114.0, 115.0, 114.0, 117.0, 116.0, 117.0, 117.0, 114.0], "value": 115.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 151.0, 149.0, 152.0, 148.0, 149.0, 148.0, 148.0, 147.0], "value": 149.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 99.0, 99.0, 99.0, 95.0], "value": 95.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 55.0, 59.0, 51.0, 53.0, 54.0, 55.0, 54.0, 52.0, 50.0], "value": 53.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 59.0, 56.0, 54.0, 55.0, 58.0, 56.0, 55.0, 55.0], "value": 55.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 76.0, 76.0, 74.0, 74.0, 82.0, 74.0, 75.0, 76.0], "value": 75.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 119.0, 118.0, 116.0, 118.0, 116.0, 125.0, 125.0, 119.0, 155.0], "value": 123.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 167.0, 170.0, 167.0, 176.0, 173.0, 176.0, 173.0, 169.0, 176.0], "value": 171.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 63.0, 62.0, 63.0, 63.0, 61.0, 63.0, 65.0, 68.0], "value": 64.2, "unit": "score"}]}]} 18:24:41 INFO - Return code: 0 18:24:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:24:41 INFO - # TBPL SUCCESS # 18:24:41 INFO - Running post-action listener: _package_coverage_data 18:24:41 INFO - Running post-action listener: _resource_record_post_action 18:24:41 INFO - [mozharness: 2017-08-25 01:24:41.155456Z] Finished run-tests step (success) 18:24:41 INFO - Running post-run listener: _resource_record_post_run 18:24:41 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 4096; Write bytes: 427388928; Read time: 12; Write time: 286744 18:24:41 INFO - TinderboxPrint: CPU usage
13.1% 18:24:41 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 18:24:41 INFO - TinderboxPrint: I/O write bytes / time
427,388,928 / 286,744 18:24:41 INFO - TinderboxPrint: CPU idle
8,251.3 (86.9%) 18:24:41 INFO - TinderboxPrint: CPU user
1,195.0 (12.6%) 18:24:41 INFO - TinderboxPrint: Swap in / out
0 / 0 18:24:41 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 106610688; Read time: 0; Write time: 77596 18:24:41 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 18:24:41 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 4096; Write bytes: 320368640; Read time: 12; Write time: 209120 18:24:41 INFO - Running post-run listener: _upload_blobber_files 18:24:41 INFO - Blob upload gear active. 18:24:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:24:41 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 18:24:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:24:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:24:42 INFO - (blobuploader) - INFO - Open directory for files ... 18:24:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:24:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:24:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:24:43 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:24:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:24:43 INFO - (blobuploader) - INFO - Done attempting. 18:24:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:24:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:24:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:24:46 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:24:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:24:46 INFO - (blobuploader) - INFO - Done attempting. 18:24:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:24:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:24:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:24:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:24:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:24:47 INFO - (blobuploader) - INFO - Done attempting. 18:24:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:24:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:24:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:24:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:24:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:24:48 INFO - (blobuploader) - INFO - Done attempting. 18:24:48 INFO - (blobuploader) - INFO - Iteration through files over. 18:24:48 INFO - Return code: 0 18:24:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:24:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:24:48 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/00b7e882e3203f479de8ec14221f5048822874e39814ea4eaca162ded3c6dd326492ac21b2055796498ba5eea0b6904ae96e328e89ad901043021eccd0d8c9a6", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/139eb69438fc67653940485be3e5a50fdba1f5689c3b4ca0ab5c57d3f265bcabd9d77aaa282e65bc9ca2c40632056a075ba1def6acfccc712a99bcba1bf76ca8", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a4f0940a5aac1f70bb66d2a2dda02b0779822a0bbbe9c42ff0ccbf4b7e8f0e8cb3c2ef32af90d20cf6a5a5c989acdfac2ff8ef09763dc950ce2d2e1562ac8064", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/df570e2ea5b3895cb32416a8cbc808d31e7319b4c3022430864b42678b5045c0cc8a574edfb3f916661fcac90c1c658d4269d4ca76a1412497d5752b5b660ecc"} 18:24:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:24:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:24:48 INFO - Contents: 18:24:48 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/00b7e882e3203f479de8ec14221f5048822874e39814ea4eaca162ded3c6dd326492ac21b2055796498ba5eea0b6904ae96e328e89ad901043021eccd0d8c9a6", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/139eb69438fc67653940485be3e5a50fdba1f5689c3b4ca0ab5c57d3f265bcabd9d77aaa282e65bc9ca2c40632056a075ba1def6acfccc712a99bcba1bf76ca8", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a4f0940a5aac1f70bb66d2a2dda02b0779822a0bbbe9c42ff0ccbf4b7e8f0e8cb3c2ef32af90d20cf6a5a5c989acdfac2ff8ef09763dc950ce2d2e1562ac8064", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/df570e2ea5b3895cb32416a8cbc808d31e7319b4c3022430864b42678b5045c0cc8a574edfb3f916661fcac90c1c658d4269d4ca76a1412497d5752b5b660ecc"} 18:24:48 INFO - Running post-run listener: copy_logs_to_upload_dir 18:24:48 INFO - Copying logs to upload dir... 18:24:48 INFO - mkdir: /builds/slave/test/build/upload/logs 18:24:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.036211 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2017-08-24 18:24:48.161088) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-24 18:24:48.164559) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=930c87414c89fc706ee2024c000001c6-1503623036.536778-49172866 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/00b7e882e3203f479de8ec14221f5048822874e39814ea4eaca162ded3c6dd326492ac21b2055796498ba5eea0b6904ae96e328e89ad901043021eccd0d8c9a6", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/139eb69438fc67653940485be3e5a50fdba1f5689c3b4ca0ab5c57d3f265bcabd9d77aaa282e65bc9ca2c40632056a075ba1def6acfccc712a99bcba1bf76ca8", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a4f0940a5aac1f70bb66d2a2dda02b0779822a0bbbe9c42ff0ccbf4b7e8f0e8cb3c2ef32af90d20cf6a5a5c989acdfac2ff8ef09763dc950ce2d2e1562ac8064", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/df570e2ea5b3895cb32416a8cbc808d31e7319b4c3022430864b42678b5045c0cc8a574edfb3f916661fcac90c1c658d4269d4ca76a1412497d5752b5b660ecc"} build_url:https://queue.taskcluster.net/v1/task/Amqe4HB8QW2hqpHBtZHK2w/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011319 build_url: 'https://queue.taskcluster.net/v1/task/Amqe4HB8QW2hqpHBtZHK2w/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/00b7e882e3203f479de8ec14221f5048822874e39814ea4eaca162ded3c6dd326492ac21b2055796498ba5eea0b6904ae96e328e89ad901043021eccd0d8c9a6", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/139eb69438fc67653940485be3e5a50fdba1f5689c3b4ca0ab5c57d3f265bcabd9d77aaa282e65bc9ca2c40632056a075ba1def6acfccc712a99bcba1bf76ca8", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a4f0940a5aac1f70bb66d2a2dda02b0779822a0bbbe9c42ff0ccbf4b7e8f0e8cb3c2ef32af90d20cf6a5a5c989acdfac2ff8ef09763dc950ce2d2e1562ac8064", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/df570e2ea5b3895cb32416a8cbc808d31e7319b4c3022430864b42678b5045c0cc8a574edfb3f916661fcac90c1c658d4269d4ca76a1412497d5752b5b660ecc"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-24 18:24:48.190781) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-24 18:24:48.191132) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=930c87414c89fc706ee2024c000001c6-1503623036.536778-49172866 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004582 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-24 18:24:48.277190) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-24 18:24:48.277507) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-24 18:24:48.277800) ========= ========= Total master_lag: 0.19 =========