builder: mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s slave: talos-linux64-ix-055 starttime: 1504034935.07 results: success (0) revision: 6c3510bac83299cbaffd2d5ecfa8ef114da6c0c4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-29 12:28:55.065657) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-29 12:28:55.066158) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-29 12:28:55.066513) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-29 12:28:55.082589) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-29 12:28:55.082967) ========= 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=bb9b54edd4a927dc735d112f000001d9-1504034934.378726-75640050 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-29 12:28:55-- 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% 50.3M=0s 2017-08-29 12:28:55 (50.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.087316 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-29 12:28:55.185827) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-29 12:28:55.186152) ========= 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=bb9b54edd4a927dc735d112f000001d9-1504034934.378726-75640050 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017380 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-29 12:28:55.236064) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-29 12:28:55.236525) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 6c3510bac83299cbaffd2d5ecfa8ef114da6c0c4 --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 6c3510bac83299cbaffd2d5ecfa8ef114da6c0c4 --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=bb9b54edd4a927dc735d112f000001d9-1504034934.378726-75640050 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-29 12:28:55,281 truncating revision to first 12 chars 2017-08-29 12:28:55,282 Setting DEBUG logging. 2017-08-29 12:28:55,282 attempt 1/10 2017-08-29 12:28:55,282 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/6c3510bac832?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-29 12:28:56,079 unpacking tar archive at: mozilla-central-6c3510bac832/testing/mozharness/ program finished with exit code 0 elapsedTime=1.108445 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-29 12:28:56.359954) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-29 12:28:56.360414) ========= script_repo_revision: 6c3510bac83299cbaffd2d5ecfa8ef114da6c0c4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-29 12:28:56.360851) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-29 12:28:56.361127) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-29 12:28:56.373363) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2017-08-29 12:28:56.373691) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=bb9b54edd4a927dc735d112f000001d9-1504034934.378726-75640050 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:28:56 INFO - MultiFileLogger online at 20170829 12:28:56 in /builds/slave/test 12:28:56 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 12:28:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:28:56 INFO - {'append_to_log': False, 12:28:56 INFO - 'base_work_dir': '/builds/slave/test', 12:28:56 INFO - 'blob_upload_branch': 'Firefox', 12:28:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:28:56 INFO - 'branch': 'Firefox', 12:28:56 INFO - 'buildbot_json_path': 'buildprops.json', 12:28:56 INFO - 'code_coverage': False, 12:28:56 INFO - 'config_files': ('talos/linux_config.py',), 12:28:56 INFO - 'default_actions': ('clobber', 12:28:56 INFO - 'read-buildbot-config', 12:28:56 INFO - 'download-and-extract', 12:28:56 INFO - 'populate-webroot', 12:28:56 INFO - 'create-virtualenv', 12:28:56 INFO - 'install', 12:28:56 INFO - 'setup-mitmproxy', 12:28:56 INFO - 'run-tests'), 12:28:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:28:56 INFO - 'disable_ccov_upload': False, 12:28:56 INFO - 'disable_stylo': False, 12:28:56 INFO - 'download_minidump_stackwalk': True, 12:28:56 INFO - 'download_symbols': 'ondemand', 12:28:56 INFO - 'enable_stylo': False, 12:28:56 INFO - 'enable_webrender': False, 12:28:56 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 12:28:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:28:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:28:56 INFO - 'gecko_profile': False, 12:28:56 INFO - 'gecko_profile_interval': 0, 12:28:56 INFO - 'installer_path': 'installer.exe', 12:28:56 INFO - 'log_level': 'info', 12:28:56 INFO - 'log_name': 'talos', 12:28:56 INFO - 'log_to_console': True, 12:28:56 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:28:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:28:56 INFO - 'opt_config_files': (), 12:28:56 INFO - 'pip_index': False, 12:28:56 INFO - 'suite': 'dromaeojs-e10s', 12:28:56 INFO - 'system_bits': '32', 12:28:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:28:56 INFO - 'title': 'talos-linux64-ix-055', 12:28:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:28:56 INFO - 'use_talos_json': True, 12:28:56 INFO - 'verify': 'False', 12:28:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:28:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:28:56 INFO - 'work_dir': 'build'} 12:28:56 INFO - [mozharness: 2017-08-29 19:28:56.566272Z] Running clobber step. 12:28:56 INFO - Running pre-action listener: _resource_record_pre_action 12:28:56 INFO - Running main action method: clobber 12:28:56 INFO - rmtree: /builds/slave/test/build 12:28:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:28:57 INFO - Running post-action listener: _resource_record_post_action 12:28:57 INFO - [mozharness: 2017-08-29 19:28:57.587545Z] Finished clobber step (success) 12:28:57 INFO - [mozharness: 2017-08-29 19:28:57.587642Z] Running read-buildbot-config step. 12:28:57 INFO - Running pre-action listener: _resource_record_pre_action 12:28:57 INFO - Running main action method: read_buildbot_config 12:28:57 INFO - Using buildbot properties: 12:28:57 INFO - { 12:28:57 INFO - "project": "", 12:28:57 INFO - "product": "firefox", 12:28:57 INFO - "who": "archaeopteryx@coole-files.de", 12:28:57 INFO - "installer_path": "public/build/target.tar.bz2", 12:28:57 INFO - "builddir": "mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s", 12:28:57 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 12:28:57 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo-sequential mozilla-central talos dromaeojs-e10s", 12:28:57 INFO - "stage_platform": "linux64-stylosequential", 12:28:57 INFO - "basedir": "/builds/slave/test", 12:28:57 INFO - "buildnumber": 218, 12:28:57 INFO - "platform": "ubuntu64_hw_styloseq", 12:28:57 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 12:28:57 INFO - "slavebuilddir": "test", 12:28:57 INFO - "taskId": "DRa96j2_SQS08RPcXL_2HA", 12:28:57 INFO - "branch": "mozilla-central", 12:28:57 INFO - "script_repo_revision": "production", 12:28:57 INFO - "revision": "6c3510bac83299cbaffd2d5ecfa8ef114da6c0c4", 12:28:57 INFO - "slavename": "talos-linux64-ix-055", 12:28:57 INFO - "repo_path": "mozilla-central" 12:28:57 INFO - } 12:28:57 INFO - Finding installer, test and symbols from parent task. 12:28:57 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DRa96j2_SQS08RPcXL_2HA'}, attempt #1 12:28:58 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DRa96j2_SQS08RPcXL_2HA'}, attempt #1 12:28:58 INFO - Task dependencies: HdbSpWL2QCWufyiDij7DYg 12:28:58 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg'}, attempt #1 12:28:58 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.tar.bz2 12:28:58 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.test_packages.json 12:28:58 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.crashreporter-symbols.zip 12:28:58 INFO - Running post-action listener: _resource_record_post_action 12:28:58 INFO - [mozharness: 2017-08-29 19:28:58.982607Z] Finished read-buildbot-config step (success) 12:28:58 INFO - [mozharness: 2017-08-29 19:28:58.982824Z] Running download-and-extract step. 12:28:58 INFO - Running pre-action listener: _resource_record_pre_action 12:28:58 INFO - Running main action method: download_and_extract 12:28:58 INFO - mkdir: /builds/slave/test/build/tests 12:28:58 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')]} 12:28:58 INFO - trying https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.test_packages.json 12:28:58 INFO - Downloading https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 12:28:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 12:28:59 INFO - Downloaded 989 bytes. 12:28:59 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 12:28:59 INFO - Using the following test package requirements: 12:28:59 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 12:28:59 INFO - u'common': [u'target.common.tests.zip'], 12:28:59 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 12:28:59 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 12:28:59 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 12:28:59 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 12:28:59 INFO - u'mozbase': [u'target.common.tests.zip'], 12:28:59 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 12:28:59 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 12:28:59 INFO - u'web-platform': [u'target.common.tests.zip', 12:28:59 INFO - u'target.web-platform.tests.tar.gz'], 12:28:59 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 12:28:59 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 12:28:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.common.tests.zip 12:28:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.common.tests.zip'}, attempt #1 12:28:59 INFO - Fetch https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.common.tests.zip into memory 12:29:00 INFO - Content-Length response header: 54980744 12:29:00 INFO - Bytes received: 54980744 12:29:11 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 12:29:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.common.tests.zip 12:29:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.common.tests.zip'}, attempt #1 12:29:11 INFO - Fetch https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.common.tests.zip into memory 12:29:12 INFO - Content-Length response header: 54980744 12:29:12 INFO - Bytes received: 54980744 12:29:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.talos.tests.zip 12:29:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 12:29:24 INFO - Fetch https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.talos.tests.zip into memory 12:29:25 INFO - Content-Length response header: 14496610 12:29:25 INFO - Bytes received: 14496610 12:29:25 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')]} 12:29:25 INFO - trying https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.tar.bz2 12:29:25 INFO - Downloading https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 12:29:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 12:29:26 INFO - Downloaded 62660028 bytes. 12:29:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.tar.bz2 12:29:26 INFO - mkdir: /builds/slave/test/properties 12:29:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:29:26 INFO - Writing to file /builds/slave/test/properties/build_url 12:29:26 INFO - Contents: 12:29:26 INFO - build_url:https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.tar.bz2 12:29:26 INFO - Running post-action listener: _resource_record_post_action 12:29:26 INFO - Running post-action listener: find_tests_for_verification 12:29:26 INFO - Running post-action listener: set_extra_try_arguments 12:29:26 INFO - [mozharness: 2017-08-29 19:29:26.624038Z] Finished download-and-extract step (success) 12:29:26 INFO - [mozharness: 2017-08-29 19:29:26.624158Z] Running populate-webroot step. 12:29:26 INFO - Running pre-action listener: _resource_record_pre_action 12:29:26 INFO - Running main action method: populate_webroot 12:29:26 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:29:26 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 12:29:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:29:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:29:26 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 12:29:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:29:26 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:29:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:29:26 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 12:29:26 INFO - 'talos_options': ['--enable-stylo'], 12:29:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:29:26 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:29:26 INFO - 'tests': ['damp', 'tps']}, 12:29:26 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 12:29:26 INFO - 'talos_options': ['--enable-stylo'], 12:29:26 INFO - 'tests': ['damp', 'tps']}, 12:29:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:29:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 12:29:26 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 12:29:26 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 12:29:26 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 12:29:26 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 12:29:26 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 12:29:26 INFO - 'talos_options': ['--enable-stylo'], 12:29:26 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 12:29:26 INFO - 'other-e10s': {'tests': ['a11yr', 12:29:26 INFO - 'ts_paint', 12:29:26 INFO - 'tpaint', 12:29:26 INFO - 'sessionrestore', 12:29:26 INFO - 'sessionrestore_many_windows', 12:29:26 INFO - 'sessionrestore_no_auto_restore', 12:29:26 INFO - 'tabpaint']}, 12:29:26 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 12:29:26 INFO - 'tests': ['a11yr', 12:29:26 INFO - 'ts_paint', 12:29:26 INFO - 'tpaint', 12:29:26 INFO - 'sessionrestore', 12:29:26 INFO - 'sessionrestore_many_windows', 12:29:26 INFO - 'sessionrestore_no_auto_restore', 12:29:26 INFO - 'tabpaint']}, 12:29:26 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 12:29:26 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 12:29:26 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 12:29:26 INFO - 'tests': ['bloom_basic_singleton']}, 12:29:26 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 12:29:26 INFO - 'tests': ['bloom_basic']}, 12:29:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:29:26 INFO - 'tsvgr_opacity', 12:29:26 INFO - 'tart', 12:29:26 INFO - 'tscrollx', 12:29:26 INFO - 'tsvg_static']}, 12:29:26 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 12:29:26 INFO - 'tests': ['tsvgx', 12:29:26 INFO - 'tsvgr_opacity', 12:29:26 INFO - 'tart', 12:29:26 INFO - 'tscrollx', 12:29:26 INFO - 'tsvg_static']}, 12:29:26 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:29:26 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 12:29:26 INFO - 'talos_options': ['--enable-stylo'], 12:29:26 INFO - 'tests': ['tp5o']}, 12:29:26 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:29:26 INFO - 'talos_options': ['--mitmproxy', 12:29:26 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:29:26 INFO - '--firstNonBlankPaint'], 12:29:26 INFO - 'tests': ['tp6_google', 12:29:26 INFO - 'tp6_youtube', 12:29:26 INFO - 'tp6_amazon', 12:29:26 INFO - 'tp6_facebook']}, 12:29:26 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:29:26 INFO - 'talos_options': ['--enable-stylo', 12:29:26 INFO - '--mitmproxy', 12:29:26 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:29:26 INFO - '--firstNonBlankPaint'], 12:29:26 INFO - 'tests': ['tp6_google', 12:29:26 INFO - 'tp6_youtube', 12:29:26 INFO - 'tp6_amazon', 12:29:26 INFO - 'tp6_facebook']}, 12:29:26 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:29:26 INFO - 'talos_options': ['--enable-stylo', 12:29:26 INFO - '--stylo-threads=1', 12:29:26 INFO - '--mitmproxy', 12:29:26 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:29:26 INFO - '--firstNonBlankPaint'], 12:29:26 INFO - 'tests': ['tp6_google', 12:29:26 INFO - 'tp6_youtube', 12:29:26 INFO - 'tp6_amazon', 12:29:26 INFO - 'tp6_facebook']}, 12:29:26 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:29:26 INFO - 'talos_options': ['--xperf_path', 12:29:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:29:26 INFO - 'tests': ['tp5n']}, 12:29:26 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 12:29:26 INFO - 'talos_options': ['--enable-stylo', 12:29:26 INFO - '--xperf_path', 12:29:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:29:26 INFO - 'tests': ['tp5n']}}} 12:29:26 INFO - Running post-action listener: _resource_record_post_action 12:29:26 INFO - [mozharness: 2017-08-29 19:29:26.638287Z] Finished populate-webroot step (success) 12:29:26 INFO - [mozharness: 2017-08-29 19:29:26.638375Z] Running create-virtualenv step. 12:29:26 INFO - Running pre-action listener: _resource_record_pre_action 12:29:26 INFO - Running main action method: create_virtualenv 12:29:26 INFO - Creating virtualenv /builds/slave/test/build/venv 12:29:26 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 12:29:26 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 12:29:26 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 12:29:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:29:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:29:27 INFO - Installing distribute.............................................................................................................................................................................................done. 12:29:27 INFO - Installing pip...............done. 12:29:27 INFO - Return code: 0 12:29:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:29:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:29:27 INFO - Reading from file tmpfile_stdout 12:29:27 INFO - Output received: 12:29:27 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 12:29:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:29:27 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')]} 12:29:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:29:27 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')]} 12:29:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:29:27 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 0x20591f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2221a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23c8b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23c5580>, '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 0x23c6480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23c6910>, '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': 'bb9b54edd4a927dc735d112f000001d9-1504034934.378726-75640050', '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 12:29:27 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 12:29:27 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 12:29:27 INFO - Using env: {'DISPLAY': ':0', 12:29:27 INFO - 'HOME': '/home/cltbld', 12:29:27 INFO - 'LANG': 'en_US.UTF-8', 12:29:27 INFO - 'LANGUAGE': 'en_US:en', 12:29:27 INFO - 'LOGNAME': 'cltbld', 12:29:27 INFO - 'MAIL': '/var/mail/cltbld', 12:29:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:27 INFO - 'MOZ_NO_REMOTE': '1', 12:29:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:29:27 INFO - 'NO_EM_RESTART': '1', 12:29:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:29:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:27 INFO - 'PWD': '/builds/slave/test', 12:29:27 INFO - 'SHELL': '/bin/bash', 12:29:27 INFO - 'SHLVL': '1', 12:29:27 INFO - 'TERM': 'linux', 12:29:27 INFO - 'TMOUT': '86400', 12:29:27 INFO - 'USER': 'cltbld', 12:29:27 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1504034934.378726-75640050', 12:29:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:27 INFO - '_': '/tools/buildbot/bin/python'} 12:29:28 INFO - Ignoring indexes: http://pypi.python.org/simple/ 12:29:28 INFO - Downloading/unpacking pip>=1.5 12:29:28 INFO - Running setup.py egg_info for package pip 12:29:28 INFO - warning: no files found matching 'pip/cacert.pem' 12:29:28 INFO - warning: no files found matching '*.html' under directory 'docs' 12:29:28 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 12:29:28 INFO - no previously-included directories found matching 'docs/_build/_sources' 12:29:28 INFO - Installing collected packages: pip 12:29:28 INFO - Found existing installation: pip 1.1 12:29:28 INFO - Uninstalling pip: 12:29:28 INFO - Successfully uninstalled pip 12:29:28 INFO - Running setup.py install for pip 12:29:28 INFO - warning: no files found matching 'pip/cacert.pem' 12:29:28 INFO - warning: no files found matching '*.html' under directory 'docs' 12:29:28 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 12:29:28 INFO - no previously-included directories found matching 'docs/_build/_sources' 12:29:28 INFO - Installing pip script to /builds/slave/test/build/venv/bin 12:29:28 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 12:29:28 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 12:29:28 INFO - Successfully installed pip 12:29:28 INFO - Cleaning up... 12:29:28 INFO - Return code: 0 12:29:28 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:29:28 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')]} 12:29:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:29:28 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')]} 12:29:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:29:29 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 0x20591f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2221a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23c8b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23c5580>, '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 0x23c6480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23c6910>, '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': 'bb9b54edd4a927dc735d112f000001d9-1504034934.378726-75640050', '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 12:29:29 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 12:29:29 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 12:29:29 INFO - Using env: {'DISPLAY': ':0', 12:29:29 INFO - 'HOME': '/home/cltbld', 12:29:29 INFO - 'LANG': 'en_US.UTF-8', 12:29:29 INFO - 'LANGUAGE': 'en_US:en', 12:29:29 INFO - 'LOGNAME': 'cltbld', 12:29:29 INFO - 'MAIL': '/var/mail/cltbld', 12:29:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:29 INFO - 'MOZ_NO_REMOTE': '1', 12:29:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:29:29 INFO - 'NO_EM_RESTART': '1', 12:29:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:29:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:29 INFO - 'PWD': '/builds/slave/test', 12:29:29 INFO - 'SHELL': '/bin/bash', 12:29:29 INFO - 'SHLVL': '1', 12:29:29 INFO - 'TERM': 'linux', 12:29:29 INFO - 'TMOUT': '86400', 12:29:29 INFO - 'USER': 'cltbld', 12:29:29 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1504034934.378726-75640050', 12:29:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:29 INFO - '_': '/tools/buildbot/bin/python'} 12:29:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:29:29 INFO - Downloading/unpacking psutil>=3.1.1 12:29:29 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 12:29:29 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 12:29:29 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 12:29:29 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 12:29:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:29:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:29:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:29:30 INFO - Installing collected packages: psutil 12:29:30 INFO - Running setup.py install for psutil 12:29:30 INFO - building 'psutil._psutil_linux' extension 12:29:30 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 12:29:30 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 12:29:30 INFO - building 'psutil._psutil_posix' extension 12:29:30 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 12:29:30 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 12:29:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:29:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:29:30 INFO - Successfully installed psutil 12:29:30 INFO - Cleaning up... 12:29:30 INFO - Return code: 0 12:29:30 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:29:30 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')]} 12:29:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:29:30 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')]} 12:29:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:29:30 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 0x20591f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2221a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23c8b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23c5580>, '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 0x23c6480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23c6910>, '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': 'bb9b54edd4a927dc735d112f000001d9-1504034934.378726-75640050', '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 12:29:30 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 12:29:30 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 12:29:30 INFO - Using env: {'DISPLAY': ':0', 12:29:30 INFO - 'HOME': '/home/cltbld', 12:29:30 INFO - 'LANG': 'en_US.UTF-8', 12:29:30 INFO - 'LANGUAGE': 'en_US:en', 12:29:30 INFO - 'LOGNAME': 'cltbld', 12:29:30 INFO - 'MAIL': '/var/mail/cltbld', 12:29:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:30 INFO - 'MOZ_NO_REMOTE': '1', 12:29:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:29:30 INFO - 'NO_EM_RESTART': '1', 12:29:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:29:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:30 INFO - 'PWD': '/builds/slave/test', 12:29:30 INFO - 'SHELL': '/bin/bash', 12:29:30 INFO - 'SHLVL': '1', 12:29:30 INFO - 'TERM': 'linux', 12:29:30 INFO - 'TMOUT': '86400', 12:29:30 INFO - 'USER': 'cltbld', 12:29:30 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1504034934.378726-75640050', 12:29:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:30 INFO - '_': '/tools/buildbot/bin/python'} 12:29:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:29:31 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:29:31 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 12:29:31 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 12:29:31 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 12:29:31 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 12:29:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:29:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:29:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:29:32 INFO - Installing collected packages: mozsystemmonitor 12:29:32 INFO - Running setup.py install for mozsystemmonitor 12:29:32 INFO - Successfully installed mozsystemmonitor 12:29:32 INFO - Cleaning up... 12:29:32 INFO - Return code: 0 12:29:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:29: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')]} 12:29:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:29: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')]} 12:29:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:29: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', '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 0x20591f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2221a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23c8b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23c5580>, '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 0x23c6480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23c6910>, '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': 'bb9b54edd4a927dc735d112f000001d9-1504034934.378726-75640050', '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 12:29: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:29: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 jsonschema==2.5.1 12:29:32 INFO - Using env: {'DISPLAY': ':0', 12:29:32 INFO - 'HOME': '/home/cltbld', 12:29:32 INFO - 'LANG': 'en_US.UTF-8', 12:29:32 INFO - 'LANGUAGE': 'en_US:en', 12:29:32 INFO - 'LOGNAME': 'cltbld', 12:29:32 INFO - 'MAIL': '/var/mail/cltbld', 12:29:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:32 INFO - 'MOZ_NO_REMOTE': '1', 12:29:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:29:32 INFO - 'NO_EM_RESTART': '1', 12:29:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:29:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:32 INFO - 'PWD': '/builds/slave/test', 12:29:32 INFO - 'SHELL': '/bin/bash', 12:29:32 INFO - 'SHLVL': '1', 12:29:32 INFO - 'TERM': 'linux', 12:29:32 INFO - 'TMOUT': '86400', 12:29:32 INFO - 'USER': 'cltbld', 12:29:32 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1504034934.378726-75640050', 12:29:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:32 INFO - '_': '/tools/buildbot/bin/python'} 12:29:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:29:32 INFO - Downloading/unpacking jsonschema==2.5.1 12:29:32 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 12:29:32 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 12:29:32 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 12:29:32 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 12:29:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:29:33 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:29: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 12:29: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 12:29: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 12:29: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 12:29:33 INFO - Downloading functools32-3.2.3-2.tar.gz 12:29:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:29:33 INFO - warning: no files found matching '*.txt' 12:29:33 INFO - no previously-included directories found matching 'build' 12:29:33 INFO - no previously-included directories found matching 'dist' 12:29:33 INFO - no previously-included directories found matching '.git*' 12:29:33 INFO - Installing collected packages: jsonschema, functools32 12:29:33 INFO - Running setup.py install for functools32 12:29:33 INFO - warning: no files found matching '*.txt' 12:29:33 INFO - no previously-included directories found matching 'build' 12:29:33 INFO - no previously-included directories found matching 'dist' 12:29:33 INFO - no previously-included directories found matching '.git*' 12:29:34 INFO - Successfully installed jsonschema functools32 12:29:34 INFO - Cleaning up... 12:29:34 INFO - Return code: 0 12:29:34 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:29:34 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')]} 12:29:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:29:34 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')]} 12:29:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:29:34 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 0x20591f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2221a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23c8b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23c5580>, '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 0x23c6480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23c6910>, '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': 'bb9b54edd4a927dc735d112f000001d9-1504034934.378726-75640050', '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 12:29:34 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 12:29:34 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 12:29:34 INFO - Using env: {'DISPLAY': ':0', 12:29:34 INFO - 'HOME': '/home/cltbld', 12:29:34 INFO - 'LANG': 'en_US.UTF-8', 12:29:34 INFO - 'LANGUAGE': 'en_US:en', 12:29:34 INFO - 'LOGNAME': 'cltbld', 12:29:34 INFO - 'MAIL': '/var/mail/cltbld', 12:29:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:34 INFO - 'MOZ_NO_REMOTE': '1', 12:29:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:29:34 INFO - 'NO_EM_RESTART': '1', 12:29:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:29:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:34 INFO - 'PWD': '/builds/slave/test', 12:29:34 INFO - 'SHELL': '/bin/bash', 12:29:34 INFO - 'SHLVL': '1', 12:29:34 INFO - 'TERM': 'linux', 12:29:34 INFO - 'TMOUT': '86400', 12:29:34 INFO - 'USER': 'cltbld', 12:29:34 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1504034934.378726-75640050', 12:29:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:34 INFO - '_': '/tools/buildbot/bin/python'} 12:29:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:29:34 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:29:34 INFO - Cleaning up... 12:29:34 INFO - Return code: 0 12:29:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:29:34 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')]} 12:29:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:29:34 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')]} 12:29:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:29:34 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 0x20591f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2221a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23c8b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23c5580>, '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 0x23c6480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23c6910>, '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': 'bb9b54edd4a927dc735d112f000001d9-1504034934.378726-75640050', '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 12:29:34 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 12:29:34 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 12:29:34 INFO - Using env: {'DISPLAY': ':0', 12:29:34 INFO - 'HOME': '/home/cltbld', 12:29:34 INFO - 'LANG': 'en_US.UTF-8', 12:29:34 INFO - 'LANGUAGE': 'en_US:en', 12:29:34 INFO - 'LOGNAME': 'cltbld', 12:29:34 INFO - 'MAIL': '/var/mail/cltbld', 12:29:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:34 INFO - 'MOZ_NO_REMOTE': '1', 12:29:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:29:34 INFO - 'NO_EM_RESTART': '1', 12:29:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:29:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:34 INFO - 'PWD': '/builds/slave/test', 12:29:34 INFO - 'SHELL': '/bin/bash', 12:29:34 INFO - 'SHLVL': '1', 12:29:34 INFO - 'TERM': 'linux', 12:29:34 INFO - 'TMOUT': '86400', 12:29:34 INFO - 'USER': 'cltbld', 12:29:34 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1504034934.378726-75640050', 12:29:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:34 INFO - '_': '/tools/buildbot/bin/python'} 12:29:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:29:34 INFO - Downloading/unpacking blobuploader==1.2.4 12:29:34 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 12:29:34 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 12:29:34 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 12:29:34 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 12:29:35 INFO - Downloading blobuploader-1.2.4.tar.gz 12:29:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:29:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:29: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 12:29: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 12:29: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 12:29: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 12:29:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:29:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:29: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 12:29: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 12:29: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 12:29: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 12:29:36 INFO - Downloading docopt-0.6.1.tar.gz 12:29:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:29:36 INFO - Installing collected packages: blobuploader, requests, docopt 12:29:36 INFO - Running setup.py install for blobuploader 12:29:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:29:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:29:36 INFO - Running setup.py install for requests 12:29:36 INFO - Running setup.py install for docopt 12:29:36 INFO - Successfully installed blobuploader requests docopt 12:29:36 INFO - Cleaning up... 12:29:36 INFO - Return code: 0 12:29:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:29: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')]} 12:29:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:29: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')]} 12:29:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:29:36 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 0x20591f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2221a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23c8b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23c5580>, '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 0x23c6480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23c6910>, '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': 'bb9b54edd4a927dc735d112f000001d9-1504034934.378726-75640050', '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 12:29:36 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 12:29:36 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 12:29:36 INFO - Using env: {'DISPLAY': ':0', 12:29:36 INFO - 'HOME': '/home/cltbld', 12:29:36 INFO - 'LANG': 'en_US.UTF-8', 12:29:36 INFO - 'LANGUAGE': 'en_US:en', 12:29:36 INFO - 'LOGNAME': 'cltbld', 12:29:36 INFO - 'MAIL': '/var/mail/cltbld', 12:29:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:36 INFO - 'MOZ_NO_REMOTE': '1', 12:29:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:29:36 INFO - 'NO_EM_RESTART': '1', 12:29:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:29:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:36 INFO - 'PWD': '/builds/slave/test', 12:29:36 INFO - 'SHELL': '/bin/bash', 12:29:36 INFO - 'SHLVL': '1', 12:29:36 INFO - 'TERM': 'linux', 12:29:36 INFO - 'TMOUT': '86400', 12:29:36 INFO - 'USER': 'cltbld', 12:29:36 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1504034934.378726-75640050', 12:29:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:36 INFO - '_': '/tools/buildbot/bin/python'} 12:29:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:29:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:29:36 INFO - Running setup.py (path:/tmp/pip-vAOlUG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:29:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:29:37 INFO - Running setup.py (path:/tmp/pip-3zJr5p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:29:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:29:37 INFO - Running setup.py (path:/tmp/pip-ukvRyx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:29:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:29:37 INFO - Running setup.py (path:/tmp/pip-YxPGs3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:29:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:29:37 INFO - Running setup.py (path:/tmp/pip-NGV4Q7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:29:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:29:37 INFO - Running setup.py (path:/tmp/pip-QBWtEh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:29:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:29:37 INFO - Running setup.py (path:/tmp/pip-coxEPK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:29:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:29:37 INFO - Running setup.py (path:/tmp/pip-i502pp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:29:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:29:37 INFO - Running setup.py (path:/tmp/pip-1KJjna-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:29:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:29:37 INFO - Running setup.py (path:/tmp/pip-hSJ3Hi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:29:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:29:37 INFO - Running setup.py (path:/tmp/pip-vDHEOf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:29:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:29:37 INFO - Running setup.py (path:/tmp/pip-aYsWoS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:29:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:29:37 INFO - Running setup.py (path:/tmp/pip-ZdBHvt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:29:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:29:37 INFO - Running setup.py (path:/tmp/pip-Nd8Hin-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:29:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:29:37 INFO - Running setup.py (path:/tmp/pip-oBZ7Pe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:29:38 INFO - Running setup.py (path:/tmp/pip-IxAEIo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:29:38 INFO - Running setup.py (path:/tmp/pip-ho8nHS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:29:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:29:38 INFO - Running setup.py install for manifestparser 12:29:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:29:38 INFO - Running setup.py install for mozcrash 12:29:38 INFO - Running setup.py install for mozdebug 12:29:38 INFO - Running setup.py install for mozdevice 12:29:38 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:29:38 INFO - Running setup.py install for mozfile 12:29:38 INFO - Running setup.py install for mozhttpd 12:29:38 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:29:38 INFO - Running setup.py install for mozinfo 12:29:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:29:38 INFO - Running setup.py install for mozInstall 12:29:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:29:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:29:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:29:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:29:38 INFO - Running setup.py install for mozleak 12:29:39 INFO - Running setup.py install for mozlog 12:29:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:29:39 INFO - Running setup.py install for moznetwork 12:29:39 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:29:39 INFO - Running setup.py install for mozprocess 12:29:39 INFO - Running setup.py install for mozprofile 12:29:39 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:29:39 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:29:39 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:29:39 INFO - Running setup.py install for mozrunner 12:29:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:29:39 INFO - Running setup.py install for mozscreenshot 12:29:39 INFO - Running setup.py install for moztest 12:29:39 INFO - Running setup.py install for mozversion 12:29:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:29:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:29:39 INFO - Cleaning up... 12:29:39 INFO - Return code: 0 12:29:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:29:39 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')]} 12:29:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:29:39 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')]} 12:29:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:29:39 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 0x20591f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2221a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23c8b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23c5580>, '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 0x23c6480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23c6910>, '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': 'bb9b54edd4a927dc735d112f000001d9-1504034934.378726-75640050', '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 12:29:39 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 12:29:39 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 12:29:39 INFO - Using env: {'DISPLAY': ':0', 12:29:39 INFO - 'HOME': '/home/cltbld', 12:29:39 INFO - 'LANG': 'en_US.UTF-8', 12:29:39 INFO - 'LANGUAGE': 'en_US:en', 12:29:39 INFO - 'LOGNAME': 'cltbld', 12:29:39 INFO - 'MAIL': '/var/mail/cltbld', 12:29:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:39 INFO - 'MOZ_NO_REMOTE': '1', 12:29:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:29:39 INFO - 'NO_EM_RESTART': '1', 12:29:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:29:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:39 INFO - 'PWD': '/builds/slave/test', 12:29:39 INFO - 'SHELL': '/bin/bash', 12:29:39 INFO - 'SHLVL': '1', 12:29:39 INFO - 'TERM': 'linux', 12:29:39 INFO - 'TMOUT': '86400', 12:29:39 INFO - 'USER': 'cltbld', 12:29:39 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1504034934.378726-75640050', 12:29:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:39 INFO - '_': '/tools/buildbot/bin/python'} 12:29:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:29:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:29:40 INFO - Running setup.py (path:/tmp/pip-SE3qD6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:29:40 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)) 12:29:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:29:40 INFO - Running setup.py (path:/tmp/pip-dHtG47-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:29:40 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)) 12:29:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:29:40 INFO - Running setup.py (path:/tmp/pip-ORn_kg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:29:40 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)) 12:29:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:29:40 INFO - Running setup.py (path:/tmp/pip-aV4lNl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:29:40 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)) 12:29:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:29:40 INFO - Running setup.py (path:/tmp/pip-dfaUr3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:29:40 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)) 12:29:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:29:40 INFO - Running setup.py (path:/tmp/pip-zBZWGy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:29:40 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)) 12:29:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:29:40 INFO - Running setup.py (path:/tmp/pip-QZwko2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:29:40 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)) 12:29:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:29:40 INFO - Running setup.py (path:/tmp/pip-TZwQL4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:29:40 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)) 12:29:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:29:40 INFO - Running setup.py (path:/tmp/pip-MGAt4v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:29:40 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)) 12:29:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:29:40 INFO - Running setup.py (path:/tmp/pip-QqMHDA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:29:40 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)) 12:29:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:29:40 INFO - Running setup.py (path:/tmp/pip-BudFk_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:29:40 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)) 12:29:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:29:40 INFO - Running setup.py (path:/tmp/pip-bvoaz0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:29:41 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)) 12:29:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:29:41 INFO - Running setup.py (path:/tmp/pip-aTcFu1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:29:41 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)) 12:29:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:29:41 INFO - Running setup.py (path:/tmp/pip-S5KhXj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:29:41 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)) 12:29:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:29:41 INFO - Running setup.py (path:/tmp/pip-TwTZoF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:29:41 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)) 12:29:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:29:41 INFO - Running setup.py (path:/tmp/pip-K3jGCt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:29:41 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)) 12:29:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:29:41 INFO - Running setup.py (path:/tmp/pip-NjIDs0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:29:41 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)) 12:29:41 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)) 12:29:41 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)) 12:29:41 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)) 12:29:41 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)) 12:29:41 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)) 12:29:41 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)) 12:29:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:29:41 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 12:29:41 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 12:29:41 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 12:29:41 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 12:29:42 INFO - Downloading blessings-1.6.tar.gz 12:29:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:29:42 INFO - Installing collected packages: blessings 12:29:42 INFO - Running setup.py install for blessings 12:29:42 INFO - Successfully installed blessings 12:29:42 INFO - Cleaning up... 12:29:42 INFO - Return code: 0 12:29:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:29:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:29:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:29:42 INFO - Reading from file tmpfile_stdout 12:29:42 INFO - Current package versions: 12:29:42 INFO - argparse == 1.2.1 12:29:42 INFO - blessings == 1.6 12:29:42 INFO - blobuploader == 1.2.4 12:29:42 INFO - docopt == 0.6.1 12:29:42 INFO - functools32 == 3.2.3-2 12:29:42 INFO - jsonschema == 2.5.1 12:29:42 INFO - manifestparser == 1.1 12:29:42 INFO - mozInstall == 1.13 12:29:42 INFO - mozcrash == 1.0 12:29:42 INFO - mozdebug == 0.1 12:29:42 INFO - mozdevice == 0.51 12:29:42 INFO - mozfile == 1.2 12:29:42 INFO - mozhttpd == 0.7 12:29:42 INFO - mozinfo == 0.10 12:29:42 INFO - mozleak == 0.1 12:29:42 INFO - mozlog == 3.5 12:29:42 INFO - moznetwork == 0.27 12:29:42 INFO - mozprocess == 0.25 12:29:42 INFO - mozprofile == 0.28 12:29:42 INFO - mozrunner == 6.13 12:29:42 INFO - mozscreenshot == 0.1 12:29:42 INFO - mozsystemmonitor == 0.3 12:29:42 INFO - moztest == 0.8 12:29:42 INFO - mozversion == 1.4 12:29:42 INFO - psutil == 3.1.1 12:29:42 INFO - requests == 1.2.3 12:29:42 INFO - wsgiref == 0.1.2 12:29:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:29:42 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')]} 12:29:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:29:42 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')]} 12:29:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:29:42 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 0x20591f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2221a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23c8b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23c5580>, '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 0x23c6480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23c6910>, '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': 'bb9b54edd4a927dc735d112f000001d9-1504034934.378726-75640050', '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 12:29:42 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 12:29:42 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 12:29:42 INFO - Using env: {'DISPLAY': ':0', 12:29:42 INFO - 'HOME': '/home/cltbld', 12:29:42 INFO - 'LANG': 'en_US.UTF-8', 12:29:42 INFO - 'LANGUAGE': 'en_US:en', 12:29:42 INFO - 'LOGNAME': 'cltbld', 12:29:42 INFO - 'MAIL': '/var/mail/cltbld', 12:29:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:42 INFO - 'MOZ_NO_REMOTE': '1', 12:29:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:29:42 INFO - 'NO_EM_RESTART': '1', 12:29:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:29:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:42 INFO - 'PWD': '/builds/slave/test', 12:29:42 INFO - 'SHELL': '/bin/bash', 12:29:42 INFO - 'SHLVL': '1', 12:29:42 INFO - 'TERM': 'linux', 12:29:42 INFO - 'TMOUT': '86400', 12:29:42 INFO - 'USER': 'cltbld', 12:29:42 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1504034934.378726-75640050', 12:29:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:42 INFO - '_': '/tools/buildbot/bin/python'} 12:29:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:29:43 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)) 12:29:43 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)) 12:29:43 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)) 12:29:43 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)) 12:29:43 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)) 12:29:43 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)) 12:29:43 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)) 12:29:43 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)) 12:29:43 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)) 12:29:43 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 12:29:43 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 12:29:43 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 12:29:43 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 12:29:43 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 12:29:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 12:29:44 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)) 12:29:44 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)) 12:29:44 INFO - Installing collected packages: simplejson 12:29:44 INFO - Running setup.py install for simplejson 12:29:44 INFO - building 'simplejson._speedups' extension 12:29:44 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 12:29:45 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 12:29:45 INFO - Successfully installed simplejson 12:29:45 INFO - Cleaning up... 12:29:45 INFO - Return code: 0 12:29:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:29:45 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')]} 12:29:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:29:45 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')]} 12:29:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:29:45 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 0x20591f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2221a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23c8b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23c5580>, '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 0x23c6480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23c6910>, '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': 'bb9b54edd4a927dc735d112f000001d9-1504034934.378726-75640050', '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 12:29:45 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 12:29:45 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 12:29:45 INFO - Using env: {'DISPLAY': ':0', 12:29:45 INFO - 'HOME': '/home/cltbld', 12:29:45 INFO - 'LANG': 'en_US.UTF-8', 12:29:45 INFO - 'LANGUAGE': 'en_US:en', 12:29:45 INFO - 'LOGNAME': 'cltbld', 12:29:45 INFO - 'MAIL': '/var/mail/cltbld', 12:29:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:45 INFO - 'MOZ_NO_REMOTE': '1', 12:29:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:29:45 INFO - 'NO_EM_RESTART': '1', 12:29:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:29:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:45 INFO - 'PWD': '/builds/slave/test', 12:29:45 INFO - 'SHELL': '/bin/bash', 12:29:45 INFO - 'SHLVL': '1', 12:29:45 INFO - 'TERM': 'linux', 12:29:45 INFO - 'TMOUT': '86400', 12:29:45 INFO - 'USER': 'cltbld', 12:29:45 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1504034934.378726-75640050', 12:29:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:45 INFO - '_': '/tools/buildbot/bin/python'} 12:29:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:29:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:29:45 INFO - Cleaning up... 12:29:45 INFO - Return code: 0 12:29:45 INFO - Running post-action listener: _resource_record_post_action 12:29:45 INFO - Running post-action listener: _start_resource_monitoring 12:29:45 INFO - Starting resource monitoring. 12:29:45 INFO - [mozharness: 2017-08-29 19:29:45.613700Z] Finished create-virtualenv step (success) 12:29:45 INFO - [mozharness: 2017-08-29 19:29:45.614225Z] Running install step. 12:29:45 INFO - Running pre-action listener: _resource_record_pre_action 12:29:45 INFO - Running main action method: install 12:29:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:29:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:29:45 INFO - Reading from file tmpfile_stdout 12:29:45 INFO - Detecting whether we're running mozinstall >=1.0... 12:29:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:29:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:29:45 INFO - Reading from file tmpfile_stdout 12:29:45 INFO - Output received: 12:29:45 INFO - Usage: mozinstall [options] installer 12:29:45 INFO - Options: 12:29:45 INFO - -h, --help show this help message and exit 12:29:45 INFO - -d DEST, --destination=DEST 12:29:45 INFO - Directory to install application into. [default: 12:29:45 INFO - "/builds/slave/test"] 12:29:45 INFO - --app=APP Application being installed. [default: firefox] 12:29:45 INFO - mkdir: /builds/slave/test/build/application 12:29:45 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'] 12:29:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 12:30:02 INFO - Reading from file tmpfile_stdout 12:30:02 INFO - Output received: 12:30:02 INFO - /builds/slave/test/build/application/firefox/firefox 12:30:02 INFO - Running post-action listener: _resource_record_post_action 12:30:02 INFO - [mozharness: 2017-08-29 19:30:02.448863Z] Finished install step (success) 12:30:02 INFO - [mozharness: 2017-08-29 19:30:02.449110Z] Running setup-mitmproxy step. 12:30:02 INFO - Running pre-action listener: _resource_record_pre_action 12:30:02 INFO - Running main action method: setup_mitmproxy 12:30:02 INFO - Skipping: mitmproxy is not required 12:30:02 INFO - Running post-action listener: _resource_record_post_action 12:30:02 INFO - [mozharness: 2017-08-29 19:30:02.450213Z] Finished setup-mitmproxy step (success) 12:30:02 INFO - [mozharness: 2017-08-29 19:30:02.450428Z] Running run-tests step. 12:30:02 INFO - Running pre-action listener: _resource_record_pre_action 12:30:02 INFO - Running pre-action listener: _set_gcov_prefix 12:30:02 INFO - Running main action method: run_tests 12:30:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:30:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:30:02 INFO - Python 2.7.3 12:30:02 INFO - Return code: 0 12:30:02 INFO - grabbing minidump binary from tooltool 12:30:02 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')]} 12:30:02 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 0x23c5580>, '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 0x23c6480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23c6910>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:30:02 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 12:30:02 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 12:30:02 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 12:30:02 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:30:02 INFO - Return code: 0 12:30:02 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:30:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:30:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:30:02 INFO - ENV: RUST_BACKTRACE is now full 12:30:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:30:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:30:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-055', '--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 12:30:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-055 --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 12:30:02 INFO - Using env: {'DISPLAY': ':0', 12:30:02 INFO - 'HOME': '/home/cltbld', 12:30:02 INFO - 'LANG': 'en_US.UTF-8', 12:30:02 INFO - 'LANGUAGE': 'en_US:en', 12:30:02 INFO - 'LOGNAME': 'cltbld', 12:30:02 INFO - 'MAIL': '/var/mail/cltbld', 12:30:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:30:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:30:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:30:02 INFO - 'MOZ_NO_REMOTE': '1', 12:30:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:30:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:30:02 INFO - 'NO_EM_RESTART': '1', 12:30:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:30:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:30:02 INFO - 'PWD': '/builds/slave/test', 12:30:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:30:02 INFO - 'RUST_BACKTRACE': 'full', 12:30:02 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:30:02 INFO - 'SHELL': '/bin/bash', 12:30:02 INFO - 'SHLVL': '1', 12:30:02 INFO - 'STYLO_FORCE_ENABLED': '1', 12:30:02 INFO - 'STYLO_THREADS': '1', 12:30:02 INFO - 'TERM': 'linux', 12:30:02 INFO - 'TMOUT': '86400', 12:30:02 INFO - 'USER': 'cltbld', 12:30:02 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1504034934.378726-75640050', 12:30:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:30:02 INFO - '_': '/tools/buildbot/bin/python'} 12:30:02 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-055', '--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 12:30:02 INFO - mozversion application_buildid: 20170829183112 12:30:02 INFO - mozversion application_changeset: 6c3510bac83299cbaffd2d5ecfa8ef114da6c0c4 12:30:02 INFO - mozversion application_display_name: Nightly 12:30:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:30:02 INFO - mozversion application_name: Firefox 12:30:02 INFO - mozversion application_remotingname: firefox 12:30:02 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:30:02 INFO - mozversion application_vendor: Mozilla 12:30:02 INFO - mozversion application_version: 57.0a1 12:30:02 INFO - mozversion platform_buildid: 20170829183112 12:30:02 INFO - mozversion platform_changeset: 6c3510bac83299cbaffd2d5ecfa8ef114da6c0c4 12:30:02 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:30:02 INFO - mozversion platform_version: 57.0a1 12:30:02 INFO - using testdate: 1504035002 12:30:02 INFO - actual date: 1504035002 12:30:02 INFO - starting webserver on 'localhost:59028' 12:30:02 INFO - SUITE-START | Running 2 tests 12:30:02 INFO - TEST-START | dromaeo_css 12:30:02 INFO - Initialising browser for dromaeo_css test... 12:30:02 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:59028/getInfo.html -profile /tmp/tmpmdA2GL/profile 12:30:02 INFO - TEST-INFO | started process 25454 (/builds/slave/test/build/application/firefox/firefox http://localhost:59028/getInfo.html) 12:30:08 INFO - TEST-INFO | 25454: exit 0 12:30:08 INFO - Browser initialized. 12:30:08 INFO - Running cycle 1/1 for dromaeo_css test... 12:30:08 INFO - Using env: {'DISPLAY': ':0', 12:30:08 INFO - 'HOME': '/home/cltbld', 12:30:08 INFO - 'JSGC_DISABLE_POISONING': '1', 12:30:08 INFO - 'LANG': 'en_US.UTF-8', 12:30:08 INFO - 'LANGUAGE': 'en_US:en', 12:30:08 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 12:30:08 INFO - 'LOGNAME': 'cltbld', 12:30:08 INFO - 'MAIL': '/var/mail/cltbld', 12:30:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:30:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:30:08 INFO - 'MOZ_CRASHREPORTER': '1', 12:30:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:30:08 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 12:30:08 INFO - 'MOZ_NO_REMOTE': '1', 12:30:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:30:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:30:08 INFO - 'NO_EM_RESTART': '1', 12:30:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:30:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:30:08 INFO - 'PWD': '/builds/slave/test', 12:30:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:30:08 INFO - 'RUST_BACKTRACE': 'full', 12:30:08 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:30:08 INFO - 'SHELL': '/bin/bash', 12:30:08 INFO - 'SHLVL': '1', 12:30:08 INFO - 'STYLO_FORCE_ENABLED': '1', 12:30:08 INFO - 'STYLO_THREADS': '1', 12:30:08 INFO - 'TERM': 'linux', 12:30:08 INFO - 'TMOUT': '86400', 12:30:08 INFO - 'USER': 'cltbld', 12:30:08 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1504034934.378726-75640050', 12:30:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:30:08 INFO - '_': '/tools/buildbot/bin/python'} 12:30:08 INFO - TEST-INFO | started process 25634 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmdA2GL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 12:30:09 INFO - PID 25634 | 12:30:09 INFO - PID 25634 | (/builds/slave/test/build/application/firefox/firefox:25685): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 12:30:09 INFO - PID 25634 | 12:30:19 INFO - PID 25634 | RSS: Main: 172408832 12:30:19 INFO - PID 25634 | 12:33:17 INFO - PID 25634 | Cycle 1(1): loaded http://localhost:59028/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59028/tests/dromaeo/cssquery-ext.html) 12:33:19 INFO - PID 25634 | RSS: Main: 173993984 12:33:19 INFO - PID 25634 | 12:36:21 INFO - PID 25634 | Cycle 1(1): loaded http://localhost:59028/tests/dromaeo/cssquery-ext.html (next: http://localhost:59028/tests/dromaeo/cssquery-jquery.html) 12:36:22 INFO - PID 25634 | RSS: Main: 170180608 12:36:22 INFO - PID 25634 | 12:39:23 INFO - PID 25634 | Cycle 1(1): loaded http://localhost:59028/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59028/tests/dromaeo/cssquery-mootools.html) 12:39:24 INFO - PID 25634 | RSS: Main: 173436928 12:39:24 INFO - PID 25634 | 12:42:26 INFO - PID 25634 | Cycle 1(1): loaded http://localhost:59028/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59028/tests/dromaeo/cssquery-prototype.html) 12:42:26 INFO - PID 25634 | RSS: Main: 171708416 12:42:26 INFO - PID 25634 | 12:45:30 INFO - PID 25634 | Cycle 1(1): loaded http://localhost:59028/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59028/tests/dromaeo/cssquery-yui.html) 12:45:31 INFO - PID 25634 | RSS: Main: 172756992 12:45:31 INFO - PID 25634 | 12:48:32 INFO - PID 25634 | Cycle 1(1): loaded http://localhost:59028/tests/dromaeo/cssquery-yui.html (next: http://localhost:59028/tests/dromaeo/cssquery-dojo.html) 12:48:33 INFO - PID 25634 | RSS: Main: 175521792 12:48:33 INFO - PID 25634 | 12:48:33 INFO - PID 25634 | __start_tp_report 12:48:33 INFO - PID 25634 | _x_x_mozilla_page_load 12:48:33 INFO - PID 25634 | _x_x_mozilla_page_load_details 12:48:33 INFO - PID 25634 | |i|pagename|runs| 12:48:33 INFO - PID 25634 | |0;dojo.html;3284.770066095374;3302.785318954266;3340.0647818610782;3341.799492030478;3352.4300868852283;6381.872362552746;6490.675466226693;6506.154199954011;6522.445592124664;6529.085927970082;6286.937130628692;6289.842753931154;6385.840353991152;6387.776427825027;6402.327755585655;5883.646981181121;5888.381719919411;5931.199288096116;5951.2560929883875;5967.85080372992;618.7341086845134;5857.150713146591;5884;5955.315138759033;5983.521318294526;5843.181954526349;5864.2962844458725;5939.70301484924;5955.987482128048;5964.671943043131;5885.352611212763;5897.970510147465;5974.701264936736;5978.282606087254;5982.461578457916;5806.1000544915605;5847.444492773222;5874.236349274567;5922.3781502942;5936.5250779937505;6344.7462101515885;6359.836775071101;6362.363763623647;6406.103145559626;6460.870782584369;6652.800415987528;6747.426468750113;6777.423918966893;6777.93222067778;6867.690953907087;6914.930850691479;6950.860982780367;6966;6970.024196612479;6978;6351.618902865843;6361.109444677749;6432;6464.612123272619;6465.579737317059;6875.931240687529;7020.964895175492;7027.227005029443;7047.224805271416;7135.714571417189;6685.933140668531;6717.361850624281;6719.529632925746;6723.899141512881;6726.260111387744;6720.630365329856;6813.284605116491;6839.726410943606;6844.863102737917;6898.2756810535175;6830.863382732317;6830.93169068303;6847.075644787989;6849.657517124224;6866.210385805596;6835.179778426621;6882.724691012303;6906.171259448898;6932.688029038705;6934.16789985205;5302.28419163408;5328.600354973392;5423.83728488146;5430.484104010113;5431.103867861838;4610.354550362952;4677.64917631179;4692.6245900327895;4735;4762.92834112322;5912.586118971717;5967.313758917693;5972.283326000907;5973.342932277446;6048.455638992424;6873.175218973755;6955.791326260221;6962.025316455701;6971.65141742921;6973;5994.9700251499335;6015.127806468037;6081.543884208702;6085.117657939574;6106.419890110521;5790.71046447676;5847.590668653153;5884.764609415661;5941.079132734433;5959.165716799652;6639.86720265592;6647;6672.099266599044;6679.699413526403;6681.031250468752;6762.898556521558;6931.514793964475;6939.271376505394;6939.548929319578;6940.7917762467205;6582.506312026521;6591.34086591337;6599.934000659932;6694.330566943267;6702.765403210864;6562.442192413508;6672.165979252593;6693.497987650849;6817.761378351734;6819.045333653392;6897.998531490642;6950.65246737651;6965.44276457872;6974.860502790018;7004.649767511706;6625.138731964814;6642.368974947372;6718.093057437183;6723.226828914738;6729.764458243938;6634;6636.170478690164;6647.401733843977;6728.798136055926;6737.124173857564;6548.869022619616;6551.737930482727;6556.409923106943;6562.376574225441;6652.667366631746;6607;6608.074869518176;6686.498512611476;6757.695903684445;6783.321667833177;6433.453156481752;6441.903371449525;6443.581167224115;6470;6476.287608362982 12:48:33 INFO - PID 25634 | |1;ext.html;778.5289899610735;854.679495189304;869.991300086999;888.1606881496984;1144.8855114488547;30625.38749225014;31500.212494687643;40967.97580060496;41980.160396792126;42091.368629470584;42286.15427691444;42359.15281694364;42430.575694243045;42525.362119568155;42553;7405.407567394623;7548.282913123245;7775.339096176817;7986;8004.319632831216;7834.05991281047;7939.761807145795;7945.64244608994;7951.522908625472;7951.602419878983;7936.960315198445;7972.20277972204;7977.601119944009;7979.840403191933;7981.840363192733;7910.762677119696;7940.483868548584;7942.046954365514;7944.324732397724;7947.96026019872;7895.052593688737;7926.24700653437;7931.762047138595;7932.405069619802;7944.8411031779615;37599.62400375988;37680.81159594212;37791.81104094489;37825.62174378248;37913.810430947946;3506.68439840413;3519.137811236238;3519.979206030248;3522.8590856365713;3525.5593050868642;30853.8457307712;30893.53659695084;30933.22666933322;30990.535141972887;31003;5163.3545806774155;5179.300794392747;5184.222366645034;5204.089284375257;5219.791208351661;180003;180710;180793;182145.0892745541;182314;47529.76235118802;47561.76219118882;47640;47774.283385749244;47858.521414785406;49477.50522494801;49668.50331496639;49669.75165124151;49675.50324496781;49714.52569268783;16012.839871601367;16190.676186476205;16200.351985920666;16229.2696828643;16260.268287927072;11909.88090119105;12064.57773977924;13357.398917048562;13547.932260338832;13553.932230338785;14110.788338174936;14224.644383890485;14255.50105746294;14288.642783930278;14409.351579178961;5775.16260142277;5821.536185969341;5824.621399609012;5855.414458554111;5957.582969192115;992.2954702161402;995.6564985080136;1015.8679371681634;1024.4007255755319;1040.5681642118436;22745.317640470814;28303.858480707877;28383.29041773972;28431.857840710665;28875.278118047216;1073.592035026685;1101.3777215873006;1101.6309536305428;1110.7667389848223;1114.8272017837248;12260.570880019157;12300.507979680713;12327.075469339836;12357.691057723448;12460.688482787822;233065;234415;235192;235463;235593;50961.23558146631;51113.74443127835;51186.74406627943;51233.743831281354;51350.486495135316;1396.0855639556107;1441.7693169092895;1452.4262916148061;1452.5642307307764;1474.8156480439945;21939.67090493612;22408.775912241323;22411.32766017022;22493.77506224917;22761.658575121055;36840.63159368479;37038.81480592688;37101;37115.44326835153;37119.44320835135;200791;202129;202353;202629;203300;29528;30390;30390.84804575963;31857.8407107963;32775.67224327822;41058.38412423876;41416.1716765657;41439;41551;42082.57917420909;30438;30466.238344041576;30525.236869077562;30527.847360763055;30601.693983060773;30398;30468;30719.846400767852;30831.2292192697;30841.537376939814;34474.82762586171;34498.31003379868;34678.82660586681;34739.826300868335;34848;29801.5529767058;29965.550516742704;29990.25024374408;30148.54777178388;30161;22307.553848923257;22555.43611409728;22754.31737047891;22918.770812291663;23214 12:48:33 INFO - PID 25634 | |2;jquery.html;560.1317957166392;560.3108176942361;560.4983539731937;563.7744902039184;571.7941541045225;21562.568748625017;22094.33716988493;22269.109235630574;22304.107835686555;22320.21879234227;24275.271741847773;24841.13056043039;24851.378715532122;24856.378590535245;24895.62656560153;6523.18460192476;6669.933300666979;6670.833229169261;6679.599224046548;6681.298463661295;4014.1770936957855;4186.246475634384;4188.371744238373;6473.029045643144;6519.445847103002;6921;7153.713851445936;7181.281871812803;7186.748463803768;7187;7153.427725781925;7168.390686791629;7180.712771489134;7220.530665506752;7221.963890180542;7023.051887995158;7057.929420705778;7069.151701795766;7074.469414793911;7088.432925365958;29933;30225.848870755726;30589.388212235852;30780.692193078008;31374.37251254985;2061.9278325258542;2110.377438655585;2110.6200883841057;2116.0477784996815;2116.43914362694;20420;20803.58392832135;20863.79136208649;20881.5823683527;20983.790162098336;2444.4133407982135;2483.1805504183576;2544.1477105169793;2602.4014476670473;2617;122343;125556.37221813924;125893;126438.99999999908;126770.36614816959;19471.955444678388;21232.469188270108;21276.574468510542;21795.44706691773;21820;42627.57372426236;42769.144617107486;42821.57178428238;42965.78517107394;42984;11555.711107222218;11783.52865885355;11893.821592676117;11926.224795388272;12020.434938178405;9760.267979901535;9797.510124493747;9889.653862114936;9938.552765125587;10019.79960400788;8688.869666955;8840.071792461698;9134.862977055349;11092.279001864854;11271.211015229019;2668.399610087715;2680.5309070912904;2740.7259274072435;2805.466961277351;2826.3781967967016;961.2310151878469;969.8642190093393;983.9754006149903;992.0753737748744;1000.0899181744578;19304.806951930303;19556.80443195578;19880;19906.104225309897;23547.41131471727;1013.7719013221966;1044.5769463367349;1051.2273478992934;1053.820850455409;1058.8888166742536;10655.25413221067;11208.775824483464;11249.212555121227;11264.211505194722;11302.491387887565;540289;544594;558761;563528;573712;116681.41659291649;120911;121880.39059804645;121980;122009;1218.2933898338943;1247.607003793802;1260.155695683894;1265.3989355056274;1271.8537368202776;17898.463046108638;18207.45377638673;18325.908370458063;18350.90824545922;18496.537586560444;58132;59079;59338.70330648319;59449.40550594612;60468;71786.64106679433;72055;72868.27131728826;73201.63399183183;73830;12302.200356976768;14109.788353174505;14370.712585748435;14400.415954245234;14549.19979401143;22256.66615000744;22362.664560031288;22438.663420049037;22528.098876044776;22702.545949080595;17067.231974560927;17190.742138867678;17320.566985825455;18188;18311.35910243135;16551.66896662036;16785.244663990394;16861.241244144287;16888.83111168922;17311.48065558035;19601.901990489958;19709.704354434984;19727.21091156395;19747.802521974598;19799.703004455234;14215.715685686435;14499.420023198954;14710.485133020293;15004.324805384002;15063.924680376527;19174.13716382738;19229.8077019228;19312.8068719311;19460.221591136196;19498.122584483448 12:48:33 INFO - PID 25634 | |3;mootools.html;1296.4749276543;1358.191875833879;1363.2774629446394;1364.9044566880316;1371.7050834070674;13823.723525529484;14291.071080379768;14337.211453370073;14352.784708229372;14561.854381456182;15184.924075379608;15426.61433464164;15428.3828646854;15430.922845385785;15431.845681543182;2625.488029834183;2674.652295201621;2730.5358089124798;2735.37086470112;2736.767374773137;2730.085421383839;2762.7927905407073;2766.667999840022;2769.0862015534926;2774.4728501584736;2622.514834755567;2664.9333766655795;2678.4776968491055;2725.4821583899093;2726.3865630233236;2592.688877334713;2603.6615240018864;2607.3090630982806;2615.725348838373;2618.5810270356747;2597.9350516237055;2602.6746656667915;2606.192080455065;2617.227917764245;2627.3825650972008;13614.455421783117;13926.234057126854;13930.512432064828;13933.930330348385;13942.790858137136;7788.987431633851;8005.479643823133;8025.23760242776;8030.196980301923;8069.6368663410285;5101.540861322499;5287.391949925769;5339.572834173296;5399.5680345572655;5430.5384042356445;1895.2797936784073;1896.5543097372106;1897.4971632517395;1937.903104844752;1959.2163134746072;25042.749572504224;25200;25200.49599008028;25225;25306;6530.412262896362;6626.602403855784;6689.866202675919;6698.933010669928;6699.229588597276;15455.38178472871;15498.14760188178;15507.922460387626;15509.689806203813;15539.689206215813;7672.846543069108;7989.680412783424;7990.840183196304;7991.120976692559;7991.72028978983;7262.455315851333;7374;7405.1854296027395;7426.480146389809;7461.477696561297;5101.8724531886255;5299.205119232069;5316.388615309212;5323.121684922022;5366.758495867695;1513.8410466901037;1560.1964988031173;1563.093405824619;1580.083551540104;1612.524305329907;901.1304091551684;929.3773171974793;930.3115694386106;934.247930416008;942.6276620734772;7933.801654958672;8309.418340716213;8310;8412.663493460315;8510.191531804468;1010.4897027001317;1015.304516406264;1018.2007124407453;1019.3170575714289;1021.5658345203259;4602.723836569817;4604.424446944132;4706.576408123216;4724.126036683225;4802.639802014863;19474.707879381822;20383;20398.796012039988;20400.59198816045;20425.69361459579;19627.31304404339;20838.27066052681;20920.476988075116;20952.895235523723;20968.895155524126;10524.736881578021;10719.303245289031;10734.78530429387;10737.087347575543;10767.676969690921;7620.5808680523105;8792.956035219782;8805.471671699463;8848.690295839615;8859.60131794058;16308.755368669243;16363.263653135875;16370.590735231715;16498.42255521051;17545;18246.270149194414;18273.908630457296;18343.54141146482;18456;18554.536136596693;13208.66978325511;13635.318234088058;13644.863551364358;13646.385912634154;13648.93175534116;13446.99147563917;13526;13572.79640805369;13696.041277110906;13722.862771372158;10413.323133996266;10441.843372349269;10542.789144216918;10595.72851257854;10751.408672522773;10697.57209711634;10779.245452818228;10784.137269018303;10809.459527023775;11092.279001864854;9427.198688111313;9657.034296570288;9658.420494770337;9662.323637345318;9662.661806836726;6860.211075726355;6874.140732408449;6897.310268973062;6926.896096558657;6933.0986971693355;6892.620905850226;6911;6922.93077069243;6949.895751563629;6971.372576468142 12:48:33 INFO - PID 25634 | |4;prototype.html;462.4889497105696;477.119714127435;500.234640999271;507.4114027727836;518.2614773947124;6456.870862582746;6478.416942475176;6481.89338464999;6492.902606460907;6513.120728701624;6120.387961203888;6241.65670888101;6308.116863639083;6311.747530098802;6332.651704156269;5038.899222015558;5067.34124563808;5067.668872318729;5072.0870243356185;5086.211637196222;4852.830150944717;5059.380998080615;5068.5438310552045;5083.593312535007;5084.822031228908;4971.975140124312;5033.597312215033;5038.697678139305;5051.873703157414;5058.696478211301;5026.145555255597;5039.387396033271;5047.671901326402;5051.974740126313;5058.570021548173;4449.443819522559;4459.286514157751;4465.553444655541;4472.865814025585;4492;6233.158523599302;6248.87502249957;6256.2492500899725;6269.652024814687;6274.592151510183;5675.722962333483;5679.403662615448;5714;5753;5753.827385178452;6570.474362051024;6579.210494740614;6589.209294884597;6681.265060843303;6708.4968627352655;4779.80880764769;4814.494478079821;4829.830955916526;4838.225883858566;4865.124277630025;6870.519063665545;6987.266337034589;7039.54242974205;7087.8228044298785;7153.427725781925;4906.920477494946;4923.163062279421;4951.257311403318;5019.974900125476;5037.204351584905;5027.673201241908;5037.3955125384455;5045.646804723708;5063.291139240509;5090.77091530882;5724.398938111522;5754.510866576305;5759.65442073477;5767.653940763567;5780.450857168562;6735.696893639798;6766.898496522556;6791.966040169767;6798.456123510206;6840.144981877265;1629.3482606957311;1648.6892920128525;1703.608170120879;1703.807334865599;1740.4082611911758;1140.7946569617382;1176.6999415149082;1188.102982248397;1192.6720151958107;1192.9642110736693;5800.680962547016;5867.677277749679;5914.260717410324;5936.228290322317;5974.223350964434;6781;6792.864142717217;6814.454843612499;6814.591124532544;6854.177498700188;5832.571020100017;5876.823695289148;5921.851953701192;5955.970220148872;5956.076808094752;3542.8051457169586;3548.8048157351595;3588.6411358863907;3617.168051348152;3641.362761516777;6508.381703738138;6511.837204069936;6575.4410875076155;6576.671166441659;6578.8355291118105;6603.867922641616;6968.581885086911;7003.264657211022;7043.330883566053;7057.8235544110785;6545.9018114728315;6582;6593.175853018372;6601.966990165019;6626.912522555283;6803.095133362666;6822.829429264308;6849;6870.625874824955;6873.793786186422;6737.764178253738;6751.696173672099;6796.524243303119;6821.931780682328;6837.384635382839;6370.936290637034;6489.286178520453;6533.934660653333;6538;6551.377619126175;4864.494785185703;4869.732164730832;4878.878028049186;4896.90206195867;4903.730294833818;6718.9428809890405;6727.394534491919;6819.761308354184;6891.896621550581;6949.861002779815;6455.61266324022;6512.153420055362;6596.175478065242;6612.90080648781;6631.635260060743;6384.201974753156;6431.903521447089;6503.73985040612;6532.804015879531;6582.8354291143105;6533.542652014314;6541.967290163519;6557.082008518716;6562.146920900444;6583.604983700993;6659.20089589233;6666.500012499179;6672;6681.265060843206;6700.4639628830555;6410.9038364425505;6430.807075787734;6436.3563643637135;6444.709988050643;6453.257875344396 12:48:33 INFO - PID 25634 | |5;yui.html;880.969166079187;896.4128495835226;898.910108989101;911.3438324406434;921.4056933278035;19180.808191918077;20262.6960595591;23095.538089238245;23133.537329253446;23308.5338293234;18970.336038238664;19021.429357119276;21455.249066282682;21572.568548629017;21586.676199857015;954.5895265036032;979.5298256836705;1006.2503434940979;1007.6876168387787;1010.7473131717071;968.5883499512714;968.6464440479209;969.3844408800427;969.7333233360812;1006.4967516241879;1048.1405247696891;1053.2995557953952;1055.3245922609535;1057.9259451838375;1058.6083149234755;1045.372776334201;1048.5858086056041;1049.6588608702202;1049.790041991597;1049.9265051446403;767.9385649148055;771.938244940409;772.2432016623741;772.8724760414525;773.6093272897207;17590.82409175905;18251.634967300713;18363.35728249505;18597.34909278169;18613.906930465397;17692.91153544224;18035.27858885643;18115.54711132219;18119.909400452914;21360.679589806165;17130.571735706584;17442.563935901704;17537.123143842757;17570.648587028314;17580.472585822445;3698.6671199591897;3758.1168425420005;3761.4733937248807;3765.0963768695588;3766.2844059628583;125442;128519.35740321239;130288.04567931393;130557;131311;16021;16117.113558754381;16146.192690365435;16175.514734557983;16706.331746729993;24399.63400548993;28838;28875.42249155047;29003.709962900102;29007.85496072506;8865.734027979172;9076.546172691338;9084.81830363389;9094.954525227331;9107.22588579965;4123.9381409278885;4134.421181034658;4176.644985176234;4215.346621273708;4216.093539888877;5133.922991155136;5232.686038837682;5259.579233661298;5263.6315457918345;5271.20931860224;1020.0411613083678;1041.5677493839971;1052.910502607287;1054.7468607534136;1058.0583280880023;704.1303989572873;711.2460791560962;718.2996578336185;730.0800990751585;735.4704612678863;11915.463804128834;11981.580644677395;12094.827284586803;12197.878021219674;12729.744045893936;669.8526324208666;676.3709749932511;679.3647939176885;690.0752990992095;690.5304393012799;5399;5434.565234781208;5485;5525.088360420566;5561.972190139023;107536.46231768948;107746;107964.46017770018;109063.45468272608;111872.44063779792;89330;90670;93089;94087;96400;87607;87981;88613.11386886178;89991;91041.08958910458;12989.675258118623;13214.942804575803;13232.933835330761;13284.269365185008;13307;37752.43371349377;39184.608153918096;39234.215315692956;39272.410913835745;40400.39399408952;49771.751141244065;50051.499485004686;50309.49690503048;51176.74411627918;52283.215751763;17176.570585735455;17647.117644117487;17775.377861774778;17781.911090444464;17822.5544361392;20265.898670506554;20713.89643051775;21108;21613.243536476148;21647;19402.51493712612;20239.79760202379;20289.49276268105;20334.084966176855;20334.186632535126;17585.384511542034;17592.03243821602;18087.276508939496;18191.363302284357;18283.26866925361;18964.525886852938;19175.424737257905;19555.31556395519;19673.901630491757;19795.406137815888;12856.678583035498;12931.224126552437;13007;13209.075364724376;13414.664633384244;14449.349779260168;14920.328585213474;14977;15114.622134446727;15294.770578441556 12:48:33 INFO - PID 25634 | __end_tp_report 12:48:33 INFO - PID 25634 | __start_cc_report 12:48:33 INFO - PID 25634 | _x_x_mozilla_cycle_collect,361 12:48:33 INFO - PID 25634 | __end_cc_report 12:48:33 INFO - PID 25634 | __startTimestamp1504036113016__endTimestamp 12:48:33 INFO - PID 25634 | 12:48:33 INFO - PID 25634 | ------- Summary: start ------- 12:48:33 INFO - PID 25634 | Number of tests: 6 12:48:33 INFO - PID 25634 | 12:48:33 INFO - PID 25634 | [#0] dojo.html Cycles:165 Average:6284.85 Median:6556.41 stddev:861.53 (13.1%) stddev-sans-first:831.41 12:48:33 INFO - PID 25634 | Values: 3284.8 3302.8 3340.1 3341.8 3352.4 6381.9 6490.7 6506.2 6522.4 6529.1 6286.9 6289.8 6385.8 6387.8 6402.3 5883.6 5888.4 5931.2 5951.3 5967.9 618.7 5857.2 5884.0 5955.3 5983.5 5843.2 5864.3 5939.7 5956.0 5964.7 5885.4 5898.0 5974.7 5978.3 5982.5 5806.1 5847.4 5874.2 5922.4 5936.5 6344.7 6359.8 6362.4 6406.1 6460.9 6652.8 6747.4 6777.4 6777.9 6867.7 6914.9 6950.9 6966.0 6970.0 6978.0 6351.6 6361.1 6432.0 6464.6 6465.6 6875.9 7021.0 7027.2 7047.2 7135.7 6685.9 6717.4 6719.5 6723.9 6726.3 6720.6 6813.3 6839.7 6844.9 6898.3 6830.9 6830.9 6847.1 6849.7 6866.2 6835.2 6882.7 6906.2 6932.7 6934.2 5302.3 5328.6 5423.8 5430.5 5431.1 4610.4 4677.6 4692.6 4735.0 4762.9 5912.6 5967.3 5972.3 5973.3 6048.5 6873.2 6955.8 6962.0 6971.7 6973.0 5995.0 6015.1 6081.5 6085.1 6106.4 5790.7 5847.6 5884.8 5941.1 5959.2 6639.9 6647.0 6672.1 6679.7 6681.0 6762.9 6931.5 6939.3 6939.5 6940.8 6582.5 6591.3 6599.9 6694.3 6702.8 6562.4 6672.2 6693.5 6817.8 6819.0 6898.0 6950.7 6965.4 6974.9 7004.6 6625.1 6642.4 6718.1 6723.2 6729.8 6634.0 6636.2 6647.4 6728.8 6737.1 6548.9 6551.7 6556.4 6562.4 6652.7 6607.0 6608.1 6686.5 6757.7 6783.3 6433.5 6441.9 6443.6 6470.0 6476.3 12:48:33 INFO - PID 25634 | 12:48:33 INFO - PID 25634 | [#1] ext.html Cycles:180 Average:37146.57 Median:33625.25 stddev:53666.77 (159.6%) stddev-sans-first:53747.84 12:48:33 INFO - PID 25634 | Values: 778.5 854.7 870.0 888.2 1144.9 30625.4 31500.2 40968.0 41980.2 42091.4 42286.2 42359.2 42430.6 42525.4 42553.0 7405.4 7548.3 7775.3 7986.0 8004.3 7834.1 7939.8 7945.6 7951.5 7951.6 7937.0 7972.2 7977.6 7979.8 7981.8 7910.8 7940.5 7942.0 7944.3 7948.0 7895.1 7926.2 7931.8 7932.4 7944.8 37599.6 37680.8 37791.8 37825.6 37913.8 3506.7 3519.1 3520.0 3522.9 3525.6 30853.8 30893.5 30933.2 30990.5 31003.0 5163.4 5179.3 5184.2 5204.1 5219.8 180003.0 180710.0 180793.0 182145.1 182314.0 47529.8 47561.8 47640.0 47774.3 47858.5 49477.5 49668.5 49669.8 49675.5 49714.5 16012.8 16190.7 16200.4 16229.3 16260.3 11909.9 12064.6 13357.4 13547.9 13553.9 14110.8 14224.6 14255.5 14288.6 14409.4 5775.2 5821.5 5824.6 5855.4 5957.6 992.3 995.7 1015.9 1024.4 1040.6 22745.3 28303.9 28383.3 28431.9 28875.3 1073.6 1101.4 1101.6 1110.8 1114.8 12260.6 12300.5 12327.1 12357.7 12460.7 233065.0 234415.0 235192.0 235463.0 235593.0 50961.2 51113.7 51186.7 51233.7 51350.5 1396.1 1441.8 1452.4 1452.6 1474.8 21939.7 22408.8 22411.3 22493.8 22761.7 36840.6 37038.8 37101.0 37115.4 37119.4 200791.0 202129.0 202353.0 202629.0 203300.0 29528.0 30390.0 30390.8 31857.8 32775.7 41058.4 41416.2 41439.0 41551.0 42082.6 30438.0 30466.2 30525.2 30527.8 30601.7 30398.0 30468.0 30719.8 30831.2 30841.5 34474.8 34498.3 34678.8 34739.8 34848.0 29801.6 29965.6 29990.3 30148.5 30161.0 22307.6 22555.4 22754.3 22918.8 23214.0 12:48:33 INFO - PID 25634 | 12:48:33 INFO - PID 25634 | [#2] jquery.html Cycles:180 Average:37411.66 Median:22262.89 stddev:92556.45 (415.7%) stddev-sans-first:92774.74 12:48:33 INFO - PID 25634 | Values: 560.1 560.3 560.5 563.8 571.8 21562.6 22094.3 22269.1 22304.1 22320.2 24275.3 24841.1 24851.4 24856.4 24895.6 6523.2 6669.9 6670.8 6679.6 6681.3 4014.2 4186.2 4188.4 6473.0 6519.4 6921.0 7153.7 7181.3 7186.7 7187.0 7153.4 7168.4 7180.7 7220.5 7222.0 7023.1 7057.9 7069.2 7074.5 7088.4 29933.0 30225.8 30589.4 30780.7 31374.4 2061.9 2110.4 2110.6 2116.0 2116.4 20420.0 20803.6 20863.8 20881.6 20983.8 2444.4 2483.2 2544.1 2602.4 2617.0 122343.0 125556.4 125893.0 126439.0 126770.4 19472.0 21232.5 21276.6 21795.4 21820.0 42627.6 42769.1 42821.6 42965.8 42984.0 11555.7 11783.5 11893.8 11926.2 12020.4 9760.3 9797.5 9889.7 9938.6 10019.8 8688.9 8840.1 9134.9 11092.3 11271.2 2668.4 2680.5 2740.7 2805.5 2826.4 961.2 969.9 984.0 992.1 1000.1 19304.8 19556.8 19880.0 19906.1 23547.4 1013.8 1044.6 1051.2 1053.8 1058.9 10655.3 11208.8 11249.2 11264.2 11302.5 540289.0 544594.0 558761.0 563528.0 573712.0 116681.4 120911.0 121880.4 121980.0 122009.0 1218.3 1247.6 1260.2 1265.4 1271.9 17898.5 18207.5 18325.9 18350.9 18496.5 58132.0 59079.0 59338.7 59449.4 60468.0 71786.6 72055.0 72868.3 73201.6 73830.0 12302.2 14109.8 14370.7 14400.4 14549.2 22256.7 22362.7 22438.7 22528.1 22702.5 17067.2 17190.7 17320.6 18188.0 18311.4 16551.7 16785.2 16861.2 16888.8 17311.5 19601.9 19709.7 19727.2 19747.8 19799.7 14215.7 14499.4 14710.5 15004.3 15063.9 19174.1 19229.8 19312.8 19460.2 19498.1 12:48:33 INFO - PID 25634 | 12:48:33 INFO - PID 25634 | [#3] mootools.html Cycles:180 Average:9028.04 Median:2595.31 stddev:6287.46 (242.3%) stddev-sans-first:6278.26 12:48:33 INFO - PID 25634 | Values: 1296.5 1358.2 1363.3 1364.9 1371.7 13823.7 14291.1 14337.2 14352.8 14561.9 15184.9 15426.6 15428.4 15430.9 15431.8 2625.5 2674.7 2730.5 2735.4 2736.8 2730.1 2762.8 2766.7 2769.1 2774.5 2622.5 2664.9 2678.5 2725.5 2726.4 2592.7 2603.7 2607.3 2615.7 2618.6 2597.9 2602.7 2606.2 2617.2 2627.4 13614.5 13926.2 13930.5 13933.9 13942.8 7789.0 8005.5 8025.2 8030.2 8069.6 5101.5 5287.4 5339.6 5399.6 5430.5 1895.3 1896.6 1897.5 1937.9 1959.2 25042.7 25200.0 25200.5 25225.0 25306.0 6530.4 6626.6 6689.9 6698.9 6699.2 15455.4 15498.1 15507.9 15509.7 15539.7 7672.8 7989.7 7990.8 7991.1 7991.7 7262.5 7374.0 7405.2 7426.5 7461.5 5101.9 5299.2 5316.4 5323.1 5366.8 1513.8 1560.2 1563.1 1580.1 1612.5 901.1 929.4 930.3 934.2 942.6 7933.8 8309.4 8310.0 8412.7 8510.2 1010.5 1015.3 1018.2 1019.3 1021.6 4602.7 4604.4 4706.6 4724.1 4802.6 19474.7 20383.0 20398.8 20400.6 20425.7 19627.3 20838.3 20920.5 20952.9 20968.9 10524.7 10719.3 10734.8 10737.1 10767.7 7620.6 8793.0 8805.5 8848.7 8859.6 16308.8 16363.3 16370.6 16498.4 17545.0 18246.3 18273.9 18343.5 18456.0 18554.5 13208.7 13635.3 13644.9 13646.4 13648.9 13447.0 13526.0 13572.8 13696.0 13722.9 10413.3 10441.8 10542.8 10595.7 10751.4 10697.6 10779.2 10784.1 10809.5 11092.3 9427.2 9657.0 9658.4 9662.3 9662.7 6860.2 6874.1 6897.3 6926.9 6933.1 6892.6 6911.0 6922.9 6949.9 6971.4 12:48:33 INFO - PID 25634 | 12:48:33 INFO - PID 25634 | [#4] prototype.html Cycles:180 Average:5571.96 Median:6272.12 stddev:1591.38 (25.4%) stddev-sans-first:1548.94 12:48:33 INFO - PID 25634 | Values: 462.5 477.1 500.2 507.4 518.3 6456.9 6478.4 6481.9 6492.9 6513.1 6120.4 6241.7 6308.1 6311.7 6332.7 5038.9 5067.3 5067.7 5072.1 5086.2 4852.8 5059.4 5068.5 5083.6 5084.8 4972.0 5033.6 5038.7 5051.9 5058.7 5026.1 5039.4 5047.7 5052.0 5058.6 4449.4 4459.3 4465.6 4472.9 4492.0 6233.2 6248.9 6256.2 6269.7 6274.6 5675.7 5679.4 5714.0 5753.0 5753.8 6570.5 6579.2 6589.2 6681.3 6708.5 4779.8 4814.5 4829.8 4838.2 4865.1 6870.5 6987.3 7039.5 7087.8 7153.4 4906.9 4923.2 4951.3 5020.0 5037.2 5027.7 5037.4 5045.6 5063.3 5090.8 5724.4 5754.5 5759.7 5767.7 5780.5 6735.7 6766.9 6792.0 6798.5 6840.1 1629.3 1648.7 1703.6 1703.8 1740.4 1140.8 1176.7 1188.1 1192.7 1193.0 5800.7 5867.7 5914.3 5936.2 5974.2 6781.0 6792.9 6814.5 6814.6 6854.2 5832.6 5876.8 5921.9 5956.0 5956.1 3542.8 3548.8 3588.6 3617.2 3641.4 6508.4 6511.8 6575.4 6576.7 6578.8 6603.9 6968.6 7003.3 7043.3 7057.8 6545.9 6582.0 6593.2 6602.0 6626.9 6803.1 6822.8 6849.0 6870.6 6873.8 6737.8 6751.7 6796.5 6821.9 6837.4 6370.9 6489.3 6533.9 6538.0 6551.4 4864.5 4869.7 4878.9 4896.9 4903.7 6718.9 6727.4 6819.8 6891.9 6949.9 6455.6 6512.2 6596.2 6612.9 6631.6 6384.2 6431.9 6503.7 6532.8 6582.8 6533.5 6542.0 6557.1 6562.1 6583.6 6659.2 6666.5 6672.0 6681.3 6700.5 6410.9 6430.8 6436.4 6444.7 6453.3 12:48:33 INFO - PID 25634 | 12:48:33 INFO - PID 25634 | [#5] yui.html Cycles:180 Average:23227.65 Median:20311.79 stddev:31476.00 (155.0%) stddev-sans-first:31519.57 12:48:33 INFO - PID 25634 | Values: 881.0 896.4 898.9 911.3 921.4 19180.8 20262.7 23095.5 23133.5 23308.5 18970.3 19021.4 21455.2 21572.6 21586.7 954.6 979.5 1006.3 1007.7 1010.7 968.6 968.6 969.4 969.7 1006.5 1048.1 1053.3 1055.3 1057.9 1058.6 1045.4 1048.6 1049.7 1049.8 1049.9 767.9 771.9 772.2 772.9 773.6 17590.8 18251.6 18363.4 18597.3 18613.9 17692.9 18035.3 18115.5 18119.9 21360.7 17130.6 17442.6 17537.1 17570.6 17580.5 3698.7 3758.1 3761.5 3765.1 3766.3 125442.0 128519.4 130288.0 130557.0 131311.0 16021.0 16117.1 16146.2 16175.5 16706.3 24399.6 28838.0 28875.4 29003.7 29007.9 8865.7 9076.5 9084.8 9095.0 9107.2 4123.9 4134.4 4176.6 4215.3 4216.1 5133.9 5232.7 5259.6 5263.6 5271.2 1020.0 1041.6 1052.9 1054.7 1058.1 704.1 711.2 718.3 730.1 735.5 11915.5 11981.6 12094.8 12197.9 12729.7 669.9 676.4 679.4 690.1 690.5 5399.0 5434.6 5485.0 5525.1 5562.0 107536.5 107746.0 107964.5 109063.5 111872.4 89330.0 90670.0 93089.0 94087.0 96400.0 87607.0 87981.0 88613.1 89991.0 91041.1 12989.7 13214.9 13232.9 13284.3 13307.0 37752.4 39184.6 39234.2 39272.4 40400.4 49771.8 50051.5 50309.5 51176.7 52283.2 17176.6 17647.1 17775.4 17781.9 17822.6 20265.9 20713.9 21108.0 21613.2 21647.0 19402.5 20239.8 20289.5 20334.1 20334.2 17585.4 17592.0 18087.3 18191.4 18283.3 18964.5 19175.4 19555.3 19673.9 19795.4 12856.7 12931.2 13007.0 13209.1 13414.7 14449.3 14920.3 14977.0 15114.6 15294.8 12:48:33 INFO - PID 25634 | -------- Summary: end -------- 12:48:33 INFO - PID 25634 | 12:48:33 INFO - TEST-INFO | 25634: exit 0 12:48:33 INFO - TEST-OK | dromaeo_css | took 1110926ms 12:48:33 INFO - TEST-START | kraken 12:48:33 INFO - Initialising browser for kraken test... 12:48:33 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:59028/getInfo.html -profile /tmp/tmpa3yst4/profile 12:48:33 INFO - TEST-INFO | started process 26007 (/builds/slave/test/build/application/firefox/firefox http://localhost:59028/getInfo.html) 12:48:39 INFO - TEST-INFO | 26007: exit 0 12:48:39 INFO - Browser initialized. 12:48:39 INFO - Running cycle 1/1 for kraken test... 12:48:39 INFO - Using env: {'DISPLAY': ':0', 12:48:39 INFO - 'HOME': '/home/cltbld', 12:48:39 INFO - 'JSGC_DISABLE_POISONING': '1', 12:48:39 INFO - 'LANG': 'en_US.UTF-8', 12:48:39 INFO - 'LANGUAGE': 'en_US:en', 12:48:39 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 12:48:39 INFO - 'LOGNAME': 'cltbld', 12:48:39 INFO - 'MAIL': '/var/mail/cltbld', 12:48:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:48:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:48:39 INFO - 'MOZ_CRASHREPORTER': '1', 12:48:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:48:39 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 12:48:39 INFO - 'MOZ_NO_REMOTE': '1', 12:48:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:48:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:48:39 INFO - 'NO_EM_RESTART': '1', 12:48:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:48:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:48:39 INFO - 'PWD': '/builds/slave/test', 12:48:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:48:39 INFO - 'RUST_BACKTRACE': 'full', 12:48:39 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:48:39 INFO - 'SHELL': '/bin/bash', 12:48:39 INFO - 'SHLVL': '1', 12:48:39 INFO - 'STYLO_FORCE_ENABLED': '1', 12:48:39 INFO - 'STYLO_THREADS': '1', 12:48:39 INFO - 'TERM': 'linux', 12:48:39 INFO - 'TMOUT': '86400', 12:48:39 INFO - 'USER': 'cltbld', 12:48:39 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1504034934.378726-75640050', 12:48:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:48:39 INFO - '_': '/tools/buildbot/bin/python'} 12:48:39 INFO - TEST-INFO | started process 26195 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpa3yst4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 12:48:40 INFO - PID 26195 | 12:48:40 INFO - PID 26195 | (/builds/slave/test/build/application/firefox/firefox:26246): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 12:48:40 INFO - PID 26195 | 12:48:51 INFO - PID 26195 | RSS: Main: 168022016 12:48:51 INFO - PID 26195 | 12:48:53 INFO - PID 26195 | Cycle 1(1): loaded http://localhost:59028/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59028/tests/kraken/driver.html?testName=audio-beat-detection) 12:48:54 INFO - PID 26195 | RSS: Main: 173527040 12:48:54 INFO - PID 26195 | 12:48:57 INFO - PID 26195 | Cycle 1(1): loaded http://localhost:59028/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59028/tests/kraken/driver.html?testName=audio-dft) 12:48:57 INFO - PID 26195 | RSS: Main: 173776896 12:48:57 INFO - PID 26195 | 12:49:00 INFO - PID 26195 | Cycle 1(1): loaded http://localhost:59028/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59028/tests/kraken/driver.html?testName=audio-fft) 12:49:01 INFO - PID 26195 | RSS: Main: 167342080 12:49:01 INFO - PID 26195 | 12:49:03 INFO - PID 26195 | Cycle 1(1): loaded http://localhost:59028/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59028/tests/kraken/driver.html?testName=audio-oscillator) 12:49:04 INFO - PID 26195 | RSS: Main: 168411136 12:49:04 INFO - PID 26195 | 12:49:06 INFO - PID 26195 | Cycle 1(1): loaded http://localhost:59028/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59028/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:49:06 INFO - PID 26195 | RSS: Main: 168222720 12:49:06 INFO - PID 26195 | 12:49:10 INFO - PID 26195 | Cycle 1(1): loaded http://localhost:59028/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59028/tests/kraken/driver.html?testName=imaging-darkroom) 12:49:10 INFO - PID 26195 | RSS: Main: 171069440 12:49:10 INFO - PID 26195 | 12:49:14 INFO - PID 26195 | Cycle 1(1): loaded http://localhost:59028/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59028/tests/kraken/driver.html?testName=imaging-desaturate) 12:49:14 INFO - PID 26195 | RSS: Main: 171470848 12:49:14 INFO - PID 26195 | 12:49:17 INFO - PID 26195 | Cycle 1(1): loaded http://localhost:59028/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59028/tests/kraken/driver.html?testName=json-parse-financial) 12:49:18 INFO - PID 26195 | RSS: Main: 171462656 12:49:18 INFO - PID 26195 | 12:49:19 INFO - PID 26195 | Cycle 1(1): loaded http://localhost:59028/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59028/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:49:20 INFO - PID 26195 | RSS: Main: 170967040 12:49:20 INFO - PID 26195 | 12:49:22 INFO - PID 26195 | Cycle 1(1): loaded http://localhost:59028/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59028/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:49:22 INFO - PID 26195 | RSS: Main: 171184128 12:49:22 INFO - PID 26195 | 12:49:24 INFO - PID 26195 | Cycle 1(1): loaded http://localhost:59028/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59028/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:49:25 INFO - PID 26195 | RSS: Main: 171237376 12:49:25 INFO - PID 26195 | 12:49:28 INFO - PID 26195 | Cycle 1(1): loaded http://localhost:59028/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59028/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:49:29 INFO - PID 26195 | RSS: Main: 171642880 12:49:29 INFO - PID 26195 | 12:49:31 INFO - PID 26195 | Cycle 1(1): loaded http://localhost:59028/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59028/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:49:32 INFO - PID 26195 | RSS: Main: 171864064 12:49:32 INFO - PID 26195 | 12:49:34 INFO - PID 26195 | Cycle 1(1): loaded http://localhost:59028/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59028/tests/kraken/driver.html?testName=ai-astar) 12:49:34 INFO - PID 26195 | RSS: Main: 173133824 12:49:34 INFO - PID 26195 | 12:49:34 INFO - PID 26195 | __start_tp_report 12:49:34 INFO - PID 26195 | _x_x_mozilla_page_load 12:49:34 INFO - PID 26195 | _x_x_mozilla_page_load_details 12:49:34 INFO - PID 26195 | |i|pagename|runs| 12:49:34 INFO - PID 26195 | |0;ai-astar;99;107;99;108;108;110;108;98;109;106 12:49:34 INFO - PID 26195 | |1;audio-beat-detection;151;116;144;185;120;143;149;116;169;125 12:49:34 INFO - PID 26195 | |2;audio-dft;164;162;164;164;163;161;163;167;170;163 12:49:34 INFO - PID 26195 | |3;audio-fft;74;83;73;181;75;73;73;86;95;73 12:49:34 INFO - PID 26195 | |4;audio-oscillator;79;80;128;79;79;78;129;79;78;121 12:49:34 INFO - PID 26195 | |5;imaging-gaussian-blur;114;114;114;115;114;114;114;122;114;114 12:49:34 INFO - PID 26195 | |6;imaging-darkroom;149;148;149;148;150;148;149;147;148;147 12:49:34 INFO - PID 26195 | |7;imaging-desaturate;94;94;94;94;94;94;93;94;93;94 12:49:34 INFO - PID 26195 | |8;json-parse-financial;53;52;52;52;53;53;52;52;54;52 12:49:34 INFO - PID 26195 | |9;json-stringify-tinderbox;38;38;38;38;38;38;38;38;38;38 12:49:34 INFO - PID 26195 | |10;stanford-crypto-aes;75;73;75;75;75;74;76;74;74;74 12:49:34 INFO - PID 26195 | |11;stanford-crypto-ccm;122;130;124;121;120;121;118;120;115;168 12:49:34 INFO - PID 26195 | |12;stanford-crypto-pbkdf2;174;173;182;180;173;173;174;173;181;170 12:49:34 INFO - PID 26195 | |13;stanford-crypto-sha256-iterative;67;71;64;62;63;63;62;64;64;63 12:49:34 INFO - PID 26195 | __end_tp_report 12:49:34 INFO - PID 26195 | __start_cc_report 12:49:34 INFO - PID 26195 | _x_x_mozilla_cycle_collect,955 12:49:34 INFO - PID 26195 | __end_cc_report 12:49:34 INFO - PID 26195 | __startTimestamp1504036174625__endTimestamp 12:49:34 INFO - PID 26195 | 12:49:34 INFO - PID 26195 | ------- Summary: start ------- 12:49:34 INFO - PID 26195 | Number of tests: 14 12:49:34 INFO - PID 26195 | 12:49:34 INFO - PID 26195 | [#0] ai-astar Cycles:10 Average:105.20 Median:109.50 stddev:4.64 (4.2%) stddev-sans-first:4.34 12:49:34 INFO - PID 26195 | Values: 99.0 107.0 99.0 108.0 108.0 110.0 108.0 98.0 109.0 106.0 12:49:34 INFO - PID 26195 | 12:49:34 INFO - PID 26195 | [#1] audio-beat-detection Cycles:10 Average:141.80 Median:146.50 stddev:23.18 (15.8%) stddev-sans-first:24.35 12:49:34 INFO - PID 26195 | Values: 151.0 116.0 144.0 185.0 120.0 143.0 149.0 116.0 169.0 125.0 12:49:34 INFO - PID 26195 | 12:49:34 INFO - PID 26195 | [#2] audio-dft Cycles:10 Average:164.10 Median:164.00 stddev:2.60 (1.6%) stddev-sans-first:2.76 12:49:34 INFO - PID 26195 | Values: 164.0 162.0 164.0 164.0 163.0 161.0 163.0 167.0 170.0 163.0 12:49:34 INFO - PID 26195 | 12:49:34 INFO - PID 26195 | [#3] audio-fft Cycles:10 Average:88.60 Median:74.50 stddev:33.31 (44.7%) stddev-sans-first:34.91 12:49:34 INFO - PID 26195 | Values: 74.0 83.0 73.0 181.0 75.0 73.0 73.0 86.0 95.0 73.0 12:49:34 INFO - PID 26195 | 12:49:34 INFO - PID 26195 | [#4] audio-oscillator Cycles:10 Average:93.00 Median:79.00 stddev:22.87 (29.0%) stddev-sans-first:23.69 12:49:34 INFO - PID 26195 | Values: 79.0 80.0 128.0 79.0 79.0 78.0 129.0 79.0 78.0 121.0 12:49:34 INFO - PID 26195 | 12:49:34 INFO - PID 26195 | [#5] imaging-gaussian-blur Cycles:10 Average:114.90 Median:114.00 stddev:2.51 (2.2%) stddev-sans-first:2.65 12:49:34 INFO - PID 26195 | Values: 114.0 114.0 114.0 115.0 114.0 114.0 114.0 122.0 114.0 114.0 12:49:34 INFO - PID 26195 | 12:49:34 INFO - PID 26195 | [#6] imaging-darkroom Cycles:10 Average:148.30 Median:148.50 stddev:0.95 (0.6%) stddev-sans-first:0.97 12:49:34 INFO - PID 26195 | Values: 149.0 148.0 149.0 148.0 150.0 148.0 149.0 147.0 148.0 147.0 12:49:34 INFO - PID 26195 | 12:49:34 INFO - PID 26195 | [#7] imaging-desaturate Cycles:10 Average:93.80 Median:94.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 12:49:34 INFO - PID 26195 | Values: 94.0 94.0 94.0 94.0 94.0 94.0 93.0 94.0 93.0 94.0 12:49:34 INFO - PID 26195 | 12:49:34 INFO - PID 26195 | [#8] json-parse-financial Cycles:10 Average:52.50 Median:52.50 stddev:0.71 (1.3%) stddev-sans-first:0.73 12:49:34 INFO - PID 26195 | Values: 53.0 52.0 52.0 52.0 53.0 53.0 52.0 52.0 54.0 52.0 12:49:34 INFO - PID 26195 | 12:49:34 INFO - PID 26195 | [#9] json-stringify-tinderbox Cycles:10 Average:38.00 Median:38.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 12:49:34 INFO - PID 26195 | Values: 38.0 38.0 38.0 38.0 38.0 38.0 38.0 38.0 38.0 38.0 12:49:34 INFO - PID 26195 | 12:49:34 INFO - PID 26195 | [#10] stanford-crypto-aes Cycles:10 Average:74.50 Median:75.00 stddev:0.85 (1.1%) stddev-sans-first:0.88 12:49:34 INFO - PID 26195 | Values: 75.0 73.0 75.0 75.0 75.0 74.0 76.0 74.0 74.0 74.0 12:49:34 INFO - PID 26195 | 12:49:34 INFO - PID 26195 | [#11] stanford-crypto-ccm Cycles:10 Average:125.90 Median:121.50 stddev:15.30 (12.6%) stddev-sans-first:16.16 12:49:34 INFO - PID 26195 | Values: 122.0 130.0 124.0 121.0 120.0 121.0 118.0 120.0 115.0 168.0 12:49:34 INFO - PID 26195 | 12:49:34 INFO - PID 26195 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.30 Median:174.00 stddev:4.11 (2.4%) stddev-sans-first:4.33 12:49:34 INFO - PID 26195 | Values: 174.0 173.0 182.0 180.0 173.0 173.0 174.0 173.0 181.0 170.0 12:49:34 INFO - PID 26195 | 12:49:34 INFO - PID 26195 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.30 Median:64.00 stddev:2.75 (4.3%) stddev-sans-first:2.74 12:49:34 INFO - PID 26195 | Values: 67.0 71.0 64.0 62.0 63.0 63.0 62.0 64.0 64.0 63.0 12:49:34 INFO - PID 26195 | -------- Summary: end -------- 12:49:34 INFO - PID 26195 | 12:49:34 INFO - PID 26195 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 12:49:35 INFO - TEST-INFO | 26195: exit 0 12:49:35 INFO - TEST-OK | kraken | took 61533ms 12:49:35 INFO - SUITE-END | took 1172s 12:49:35 INFO - Completed test suite (00:19:32) 12:49:35 INFO - javascript benchmark 12:49:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8602.608552613308, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3284.770066095374, 3302.785318954266, 3340.0647818610782, 3341.799492030478, 3352.4300868852283, 6381.872362552746, 6490.675466226693, 6506.154199954011, 6522.445592124664, 6529.085927970082, 6286.937130628692, 6289.842753931154, 6385.840353991152, 6387.776427825027, 6402.327755585655, 5883.646981181121, 5888.381719919411, 5931.199288096116, 5951.2560929883875, 5967.85080372992, 618.7341086845134, 5857.150713146591, 5884.0, 5955.315138759033, 5983.521318294526, 5843.181954526349, 5864.2962844458725, 5939.70301484924, 5955.987482128048, 5964.671943043131, 5885.352611212763, 5897.970510147465, 5974.701264936736, 5978.282606087254, 5982.461578457916, 5806.1000544915605, 5847.444492773222, 5874.236349274567, 5922.3781502942, 5936.5250779937505, 6344.7462101515885, 6359.836775071101, 6362.363763623647, 6406.103145559626, 6460.870782584369, 6652.800415987528, 6747.426468750113, 6777.423918966893, 6777.93222067778, 6867.690953907087, 6914.930850691479, 6950.860982780367, 6966.0, 6970.024196612479, 6978.0, 6351.618902865843, 6361.109444677749, 6432.0, 6464.612123272619, 6465.579737317059, 6875.931240687529, 7020.964895175492, 7027.227005029443, 7047.224805271416, 7135.714571417189, 6685.933140668531, 6717.361850624281, 6719.529632925746, 6723.899141512881, 6726.260111387744, 6720.630365329856, 6813.284605116491, 6839.726410943606, 6844.863102737917, 6898.2756810535175, 6830.863382732317, 6830.93169068303, 6847.075644787989, 6849.657517124224, 6866.210385805596, 6835.179778426621, 6882.724691012303, 6906.171259448898, 6932.688029038705, 6934.16789985205, 5302.28419163408, 5328.600354973392, 5423.83728488146, 5430.484104010113, 5431.103867861838, 4610.354550362952, 4677.64917631179, 4692.6245900327895, 4735.0, 4762.92834112322, 5912.586118971717, 5967.313758917693, 5972.283326000907, 5973.342932277446, 6048.455638992424, 6873.175218973755, 6955.791326260221, 6962.025316455701, 6971.65141742921, 6973.0, 5994.9700251499335, 6015.127806468037, 6081.543884208702, 6085.117657939574, 6106.419890110521, 5790.71046447676, 5847.590668653153, 5884.764609415661, 5941.079132734433, 5959.165716799652, 6639.86720265592, 6647.0, 6672.099266599044, 6679.699413526403, 6681.031250468752, 6762.898556521558, 6931.514793964475, 6939.271376505394, 6939.548929319578, 6940.7917762467205, 6582.506312026521, 6591.34086591337, 6599.934000659932, 6694.330566943267, 6702.765403210864, 6562.442192413508, 6672.165979252593, 6693.497987650849, 6817.761378351734, 6819.045333653392, 6897.998531490642, 6950.65246737651, 6965.44276457872, 6974.860502790018, 7004.649767511706, 6625.138731964814, 6642.368974947372, 6718.093057437183, 6723.226828914738, 6729.764458243938, 6634.0, 6636.170478690164, 6647.401733843977, 6728.798136055926, 6737.124173857564, 6548.869022619616, 6551.737930482727, 6556.409923106943, 6562.376574225441, 6652.667366631746, 6607.0, 6608.074869518176, 6686.498512611476, 6757.695903684445, 6783.321667833177, 6433.453156481752, 6441.903371449525, 6443.581167224115, 6470.0, 6476.287608362982], "value": 6225.226025750125, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [778.5289899610735, 854.679495189304, 869.991300086999, 888.1606881496984, 1144.8855114488547, 30625.38749225014, 31500.212494687643, 40967.97580060496, 41980.160396792126, 42091.368629470584, 42286.15427691444, 42359.15281694364, 42430.575694243045, 42525.362119568155, 42553.0, 7405.407567394623, 7548.282913123245, 7775.339096176817, 7986.0, 8004.319632831216, 7834.05991281047, 7939.761807145795, 7945.64244608994, 7951.522908625472, 7951.602419878983, 7936.960315198445, 7972.20277972204, 7977.601119944009, 7979.840403191933, 7981.840363192733, 7910.762677119696, 7940.483868548584, 7942.046954365514, 7944.324732397724, 7947.96026019872, 7895.052593688737, 7926.24700653437, 7931.762047138595, 7932.405069619802, 7944.8411031779615, 37599.62400375988, 37680.81159594212, 37791.81104094489, 37825.62174378248, 37913.810430947946, 3506.68439840413, 3519.137811236238, 3519.979206030248, 3522.8590856365713, 3525.5593050868642, 30853.8457307712, 30893.53659695084, 30933.22666933322, 30990.535141972887, 31003.0, 5163.3545806774155, 5179.300794392747, 5184.222366645034, 5204.089284375257, 5219.791208351661, 180003.0, 180710.0, 180793.0, 182145.0892745541, 182314.0, 47529.76235118802, 47561.76219118882, 47640.0, 47774.283385749244, 47858.521414785406, 49477.50522494801, 49668.50331496639, 49669.75165124151, 49675.50324496781, 49714.52569268783, 16012.839871601367, 16190.676186476205, 16200.351985920666, 16229.2696828643, 16260.268287927072, 11909.88090119105, 12064.57773977924, 13357.398917048562, 13547.932260338832, 13553.932230338785, 14110.788338174936, 14224.644383890485, 14255.50105746294, 14288.642783930278, 14409.351579178961, 5775.16260142277, 5821.536185969341, 5824.621399609012, 5855.414458554111, 5957.582969192115, 992.2954702161402, 995.6564985080136, 1015.8679371681634, 1024.4007255755319, 1040.5681642118436, 22745.317640470814, 28303.858480707877, 28383.29041773972, 28431.857840710665, 28875.278118047216, 1073.592035026685, 1101.3777215873006, 1101.6309536305428, 1110.7667389848223, 1114.8272017837248, 12260.570880019157, 12300.507979680713, 12327.075469339836, 12357.691057723448, 12460.688482787822, 233065.0, 234415.0, 235192.0, 235463.0, 235593.0, 50961.23558146631, 51113.74443127835, 51186.74406627943, 51233.743831281354, 51350.486495135316, 1396.0855639556107, 1441.7693169092895, 1452.4262916148061, 1452.5642307307764, 1474.8156480439945, 21939.67090493612, 22408.775912241323, 22411.32766017022, 22493.77506224917, 22761.658575121055, 36840.63159368479, 37038.81480592688, 37101.0, 37115.44326835153, 37119.44320835135, 200791.0, 202129.0, 202353.0, 202629.0, 203300.0, 29528.0, 30390.0, 30390.84804575963, 31857.8407107963, 32775.67224327822, 41058.38412423876, 41416.1716765657, 41439.0, 41551.0, 42082.57917420909, 30438.0, 30466.238344041576, 30525.236869077562, 30527.847360763055, 30601.693983060773, 30398.0, 30468.0, 30719.846400767852, 30831.2292192697, 30841.537376939814, 34474.82762586171, 34498.31003379868, 34678.82660586681, 34739.826300868335, 34848.0, 29801.5529767058, 29965.550516742704, 29990.25024374408, 30148.54777178388, 30161.0, 22307.553848923257, 22555.43611409728, 22754.31737047891, 22918.770812291663, 23214.0], "value": 17051.76427768866, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [560.1317957166392, 560.3108176942361, 560.4983539731937, 563.7744902039184, 571.7941541045225, 21562.568748625017, 22094.33716988493, 22269.109235630574, 22304.107835686555, 22320.21879234227, 24275.271741847773, 24841.13056043039, 24851.378715532122, 24856.378590535245, 24895.62656560153, 6523.18460192476, 6669.933300666979, 6670.833229169261, 6679.599224046548, 6681.298463661295, 4014.1770936957855, 4186.246475634384, 4188.371744238373, 6473.029045643144, 6519.445847103002, 6921.0, 7153.713851445936, 7181.281871812803, 7186.748463803768, 7187.0, 7153.427725781925, 7168.390686791629, 7180.712771489134, 7220.530665506752, 7221.963890180542, 7023.051887995158, 7057.929420705778, 7069.151701795766, 7074.469414793911, 7088.432925365958, 29933.0, 30225.848870755726, 30589.388212235852, 30780.692193078008, 31374.37251254985, 2061.9278325258542, 2110.377438655585, 2110.6200883841057, 2116.0477784996815, 2116.43914362694, 20420.0, 20803.58392832135, 20863.79136208649, 20881.5823683527, 20983.790162098336, 2444.4133407982135, 2483.1805504183576, 2544.1477105169793, 2602.4014476670473, 2617.0, 122343.0, 125556.37221813924, 125893.0, 126438.99999999908, 126770.36614816959, 19471.955444678388, 21232.469188270108, 21276.574468510542, 21795.44706691773, 21820.0, 42627.57372426236, 42769.144617107486, 42821.57178428238, 42965.78517107394, 42984.0, 11555.711107222218, 11783.52865885355, 11893.821592676117, 11926.224795388272, 12020.434938178405, 9760.267979901535, 9797.510124493747, 9889.653862114936, 9938.552765125587, 10019.79960400788, 8688.869666955, 8840.071792461698, 9134.862977055349, 11092.279001864854, 11271.211015229019, 2668.399610087715, 2680.5309070912904, 2740.7259274072435, 2805.466961277351, 2826.3781967967016, 961.2310151878469, 969.8642190093393, 983.9754006149903, 992.0753737748744, 1000.0899181744578, 19304.806951930303, 19556.80443195578, 19880.0, 19906.104225309897, 23547.41131471727, 1013.7719013221966, 1044.5769463367349, 1051.2273478992934, 1053.820850455409, 1058.8888166742536, 10655.25413221067, 11208.775824483464, 11249.212555121227, 11264.211505194722, 11302.491387887565, 540289.0, 544594.0, 558761.0, 563528.0, 573712.0, 116681.41659291649, 120911.0, 121880.39059804645, 121980.0, 122009.0, 1218.2933898338943, 1247.607003793802, 1260.155695683894, 1265.3989355056274, 1271.8537368202776, 17898.463046108638, 18207.45377638673, 18325.908370458063, 18350.90824545922, 18496.537586560444, 58132.0, 59079.0, 59338.70330648319, 59449.40550594612, 60468.0, 71786.64106679433, 72055.0, 72868.27131728826, 73201.63399183183, 73830.0, 12302.200356976768, 14109.788353174505, 14370.712585748435, 14400.415954245234, 14549.19979401143, 22256.66615000744, 22362.664560031288, 22438.663420049037, 22528.098876044776, 22702.545949080595, 17067.231974560927, 17190.742138867678, 17320.566985825455, 18188.0, 18311.35910243135, 16551.66896662036, 16785.244663990394, 16861.241244144287, 16888.83111168922, 17311.48065558035, 19601.901990489958, 19709.704354434984, 19727.21091156395, 19747.802521974598, 19799.703004455234, 14215.715685686435, 14499.420023198954, 14710.485133020293, 15004.324805384002, 15063.924680376527, 19174.13716382738, 19229.8077019228, 19312.8068719311, 19460.221591136196, 19498.122584483448], "value": 12562.92081137547, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1296.4749276543, 1358.191875833879, 1363.2774629446394, 1364.9044566880316, 1371.7050834070674, 13823.723525529484, 14291.071080379768, 14337.211453370073, 14352.784708229372, 14561.854381456182, 15184.924075379608, 15426.61433464164, 15428.3828646854, 15430.922845385785, 15431.845681543182, 2625.488029834183, 2674.652295201621, 2730.5358089124798, 2735.37086470112, 2736.767374773137, 2730.085421383839, 2762.7927905407073, 2766.667999840022, 2769.0862015534926, 2774.4728501584736, 2622.514834755567, 2664.9333766655795, 2678.4776968491055, 2725.4821583899093, 2726.3865630233236, 2592.688877334713, 2603.6615240018864, 2607.3090630982806, 2615.725348838373, 2618.5810270356747, 2597.9350516237055, 2602.6746656667915, 2606.192080455065, 2617.227917764245, 2627.3825650972008, 13614.455421783117, 13926.234057126854, 13930.512432064828, 13933.930330348385, 13942.790858137136, 7788.987431633851, 8005.479643823133, 8025.23760242776, 8030.196980301923, 8069.6368663410285, 5101.540861322499, 5287.391949925769, 5339.572834173296, 5399.5680345572655, 5430.5384042356445, 1895.2797936784073, 1896.5543097372106, 1897.4971632517395, 1937.903104844752, 1959.2163134746072, 25042.749572504224, 25200.0, 25200.49599008028, 25225.0, 25306.0, 6530.412262896362, 6626.602403855784, 6689.866202675919, 6698.933010669928, 6699.229588597276, 15455.38178472871, 15498.14760188178, 15507.922460387626, 15509.689806203813, 15539.689206215813, 7672.846543069108, 7989.680412783424, 7990.840183196304, 7991.120976692559, 7991.72028978983, 7262.455315851333, 7374.0, 7405.1854296027395, 7426.480146389809, 7461.477696561297, 5101.8724531886255, 5299.205119232069, 5316.388615309212, 5323.121684922022, 5366.758495867695, 1513.8410466901037, 1560.1964988031173, 1563.093405824619, 1580.083551540104, 1612.524305329907, 901.1304091551684, 929.3773171974793, 930.3115694386106, 934.247930416008, 942.6276620734772, 7933.801654958672, 8309.418340716213, 8310.0, 8412.663493460315, 8510.191531804468, 1010.4897027001317, 1015.304516406264, 1018.2007124407453, 1019.3170575714289, 1021.5658345203259, 4602.723836569817, 4604.424446944132, 4706.576408123216, 4724.126036683225, 4802.639802014863, 19474.707879381822, 20383.0, 20398.796012039988, 20400.59198816045, 20425.69361459579, 19627.31304404339, 20838.27066052681, 20920.476988075116, 20952.895235523723, 20968.895155524126, 10524.736881578021, 10719.303245289031, 10734.78530429387, 10737.087347575543, 10767.676969690921, 7620.5808680523105, 8792.956035219782, 8805.471671699463, 8848.690295839615, 8859.60131794058, 16308.755368669243, 16363.263653135875, 16370.590735231715, 16498.42255521051, 17545.0, 18246.270149194414, 18273.908630457296, 18343.54141146482, 18456.0, 18554.536136596693, 13208.66978325511, 13635.318234088058, 13644.863551364358, 13646.385912634154, 13648.93175534116, 13446.99147563917, 13526.0, 13572.79640805369, 13696.041277110906, 13722.862771372158, 10413.323133996266, 10441.843372349269, 10542.789144216918, 10595.72851257854, 10751.408672522773, 10697.57209711634, 10779.245452818228, 10784.137269018303, 10809.459527023775, 11092.279001864854, 9427.198688111313, 9657.034296570288, 9658.420494770337, 9662.323637345318, 9662.661806836726, 6860.211075726355, 6874.140732408449, 6897.310268973062, 6926.896096558657, 6933.0986971693355, 6892.620905850226, 6911.0, 6922.93077069243, 6949.895751563629, 6971.372576468142], "value": 6547.260750628548, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [462.4889497105696, 477.119714127435, 500.234640999271, 507.4114027727836, 518.2614773947124, 6456.870862582746, 6478.416942475176, 6481.89338464999, 6492.902606460907, 6513.120728701624, 6120.387961203888, 6241.65670888101, 6308.116863639083, 6311.747530098802, 6332.651704156269, 5038.899222015558, 5067.34124563808, 5067.668872318729, 5072.0870243356185, 5086.211637196222, 4852.830150944717, 5059.380998080615, 5068.5438310552045, 5083.593312535007, 5084.822031228908, 4971.975140124312, 5033.597312215033, 5038.697678139305, 5051.873703157414, 5058.696478211301, 5026.145555255597, 5039.387396033271, 5047.671901326402, 5051.974740126313, 5058.570021548173, 4449.443819522559, 4459.286514157751, 4465.553444655541, 4472.865814025585, 4492.0, 6233.158523599302, 6248.87502249957, 6256.2492500899725, 6269.652024814687, 6274.592151510183, 5675.722962333483, 5679.403662615448, 5714.0, 5753.0, 5753.827385178452, 6570.474362051024, 6579.210494740614, 6589.209294884597, 6681.265060843303, 6708.4968627352655, 4779.80880764769, 4814.494478079821, 4829.830955916526, 4838.225883858566, 4865.124277630025, 6870.519063665545, 6987.266337034589, 7039.54242974205, 7087.8228044298785, 7153.427725781925, 4906.920477494946, 4923.163062279421, 4951.257311403318, 5019.974900125476, 5037.204351584905, 5027.673201241908, 5037.3955125384455, 5045.646804723708, 5063.291139240509, 5090.77091530882, 5724.398938111522, 5754.510866576305, 5759.65442073477, 5767.653940763567, 5780.450857168562, 6735.696893639798, 6766.898496522556, 6791.966040169767, 6798.456123510206, 6840.144981877265, 1629.3482606957311, 1648.6892920128525, 1703.608170120879, 1703.807334865599, 1740.4082611911758, 1140.7946569617382, 1176.6999415149082, 1188.102982248397, 1192.6720151958107, 1192.9642110736693, 5800.680962547016, 5867.677277749679, 5914.260717410324, 5936.228290322317, 5974.223350964434, 6781.0, 6792.864142717217, 6814.454843612499, 6814.591124532544, 6854.177498700188, 5832.571020100017, 5876.823695289148, 5921.851953701192, 5955.970220148872, 5956.076808094752, 3542.8051457169586, 3548.8048157351595, 3588.6411358863907, 3617.168051348152, 3641.362761516777, 6508.381703738138, 6511.837204069936, 6575.4410875076155, 6576.671166441659, 6578.8355291118105, 6603.867922641616, 6968.581885086911, 7003.264657211022, 7043.330883566053, 7057.8235544110785, 6545.9018114728315, 6582.0, 6593.175853018372, 6601.966990165019, 6626.912522555283, 6803.095133362666, 6822.829429264308, 6849.0, 6870.625874824955, 6873.793786186422, 6737.764178253738, 6751.696173672099, 6796.524243303119, 6821.931780682328, 6837.384635382839, 6370.936290637034, 6489.286178520453, 6533.934660653333, 6538.0, 6551.377619126175, 4864.494785185703, 4869.732164730832, 4878.878028049186, 4896.90206195867, 4903.730294833818, 6718.9428809890405, 6727.394534491919, 6819.761308354184, 6891.896621550581, 6949.861002779815, 6455.61266324022, 6512.153420055362, 6596.175478065242, 6612.90080648781, 6631.635260060743, 6384.201974753156, 6431.903521447089, 6503.73985040612, 6532.804015879531, 6582.8354291143105, 6533.542652014314, 6541.967290163519, 6557.082008518716, 6562.146920900444, 6583.604983700993, 6659.20089589233, 6666.500012499179, 6672.0, 6681.265060843206, 6700.4639628830555, 6410.9038364425505, 6430.807075787734, 6436.3563643637135, 6444.709988050643, 6453.257875344396], "value": 5091.148630413791, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [880.969166079187, 896.4128495835226, 898.910108989101, 911.3438324406434, 921.4056933278035, 19180.808191918077, 20262.6960595591, 23095.538089238245, 23133.537329253446, 23308.5338293234, 18970.336038238664, 19021.429357119276, 21455.249066282682, 21572.568548629017, 21586.676199857015, 954.5895265036032, 979.5298256836705, 1006.2503434940979, 1007.6876168387787, 1010.7473131717071, 968.5883499512714, 968.6464440479209, 969.3844408800427, 969.7333233360812, 1006.4967516241879, 1048.1405247696891, 1053.2995557953952, 1055.3245922609535, 1057.9259451838375, 1058.6083149234755, 1045.372776334201, 1048.5858086056041, 1049.6588608702202, 1049.790041991597, 1049.9265051446403, 767.9385649148055, 771.938244940409, 772.2432016623741, 772.8724760414525, 773.6093272897207, 17590.82409175905, 18251.634967300713, 18363.35728249505, 18597.34909278169, 18613.906930465397, 17692.91153544224, 18035.27858885643, 18115.54711132219, 18119.909400452914, 21360.679589806165, 17130.571735706584, 17442.563935901704, 17537.123143842757, 17570.648587028314, 17580.472585822445, 3698.6671199591897, 3758.1168425420005, 3761.4733937248807, 3765.0963768695588, 3766.2844059628583, 125442.0, 128519.35740321239, 130288.04567931393, 130557.0, 131311.0, 16021.0, 16117.113558754381, 16146.192690365435, 16175.514734557983, 16706.331746729993, 24399.63400548993, 28838.0, 28875.42249155047, 29003.709962900102, 29007.85496072506, 8865.734027979172, 9076.546172691338, 9084.81830363389, 9094.954525227331, 9107.22588579965, 4123.9381409278885, 4134.421181034658, 4176.644985176234, 4215.346621273708, 4216.093539888877, 5133.922991155136, 5232.686038837682, 5259.579233661298, 5263.6315457918345, 5271.20931860224, 1020.0411613083678, 1041.5677493839971, 1052.910502607287, 1054.7468607534136, 1058.0583280880023, 704.1303989572873, 711.2460791560962, 718.2996578336185, 730.0800990751585, 735.4704612678863, 11915.463804128834, 11981.580644677395, 12094.827284586803, 12197.878021219674, 12729.744045893936, 669.8526324208666, 676.3709749932511, 679.3647939176885, 690.0752990992095, 690.5304393012799, 5399.0, 5434.565234781208, 5485.0, 5525.088360420566, 5561.972190139023, 107536.46231768948, 107746.0, 107964.46017770018, 109063.45468272608, 111872.44063779792, 89330.0, 90670.0, 93089.0, 94087.0, 96400.0, 87607.0, 87981.0, 88613.11386886178, 89991.0, 91041.08958910458, 12989.675258118623, 13214.942804575803, 13232.933835330761, 13284.269365185008, 13307.0, 37752.43371349377, 39184.608153918096, 39234.215315692956, 39272.410913835745, 40400.39399408952, 49771.751141244065, 50051.499485004686, 50309.49690503048, 51176.74411627918, 52283.215751763, 17176.570585735455, 17647.117644117487, 17775.377861774778, 17781.911090444464, 17822.5544361392, 20265.898670506554, 20713.89643051775, 21108.0, 21613.243536476148, 21647.0, 19402.51493712612, 20239.79760202379, 20289.49276268105, 20334.084966176855, 20334.186632535126, 17585.384511542034, 17592.03243821602, 18087.276508939496, 18191.363302284357, 18283.26866925361, 18964.525886852938, 19175.424737257905, 19555.31556395519, 19673.901630491757, 19795.406137815888, 12856.678583035498, 12931.224126552437, 13007.0, 13209.075364724376, 13414.664633384244, 14449.349779260168, 14920.328585213474, 14977.0, 15114.622134446727, 15294.770578441556], "value": 9117.259712866211, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1480.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 107.0, 99.0, 108.0, 108.0, 110.0, 108.0, 98.0, 109.0, 106.0], "value": 105.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 116.0, 144.0, 185.0, 120.0, 143.0, 149.0, 116.0, 169.0, 125.0], "value": 141.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 162.0, 164.0, 164.0, 163.0, 161.0, 163.0, 167.0, 170.0, 163.0], "value": 164.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 83.0, 73.0, 181.0, 75.0, 73.0, 73.0, 86.0, 95.0, 73.0], "value": 88.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 80.0, 128.0, 79.0, 79.0, 78.0, 129.0, 79.0, 78.0, 121.0], "value": 93.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 115.0, 114.0, 114.0, 114.0, 122.0, 114.0, 114.0], "value": 114.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 148.0, 149.0, 148.0, 150.0, 148.0, 149.0, 147.0, 148.0, 147.0], "value": 148.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 93.0, 94.0, 93.0, 94.0], "value": 93.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 52.0, 52.0, 52.0, 53.0, 53.0, 52.0, 52.0, 54.0, 52.0], "value": 52.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 38.0, 38.0, 38.0, 38.0, 38.0, 38.0, 38.0, 38.0, 38.0], "value": 38.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 73.0, 75.0, 75.0, 75.0, 74.0, 76.0, 74.0, 74.0, 74.0], "value": 74.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 130.0, 124.0, 121.0, 120.0, 121.0, 118.0, 120.0, 115.0, 168.0], "value": 125.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 173.0, 182.0, 180.0, 173.0, 173.0, 174.0, 173.0, 181.0, 170.0], "value": 175.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 71.0, 64.0, 62.0, 63.0, 63.0, 62.0, 64.0, 64.0, 63.0], "value": 64.3, "unit": "score"}]}]} 12:49:35 INFO - Return code: 0 12:49:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:49:35 INFO - # TBPL SUCCESS # 12:49:35 INFO - Running post-action listener: _package_coverage_data 12:49:35 INFO - Running post-action listener: _resource_record_post_action 12:49:35 INFO - [mozharness: 2017-08-29 19:49:35.424770Z] Finished run-tests step (success) 12:49:35 INFO - Running post-run listener: _resource_record_post_run 12:49:35 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 4096; Write bytes: 390004736; Read time: 8; Write time: 230628 12:49:35 INFO - TinderboxPrint: CPU usage
13.1% 12:49:35 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 12:49:35 INFO - TinderboxPrint: I/O write bytes / time
390,004,736 / 230,628 12:49:35 INFO - TinderboxPrint: CPU idle
8,239.4 (86.9%) 12:49:35 INFO - TinderboxPrint: CPU user
1,191.8 (12.6%) 12:49:35 INFO - TinderboxPrint: Swap in / out
0 / 0 12:49:35 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 105701376; Read time: 0; Write time: 80584 12:49:35 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 12:49:35 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 4096; Write bytes: 284303360; Read time: 8; Write time: 150044 12:49:36 INFO - Running post-run listener: _upload_blobber_files 12:49:36 INFO - Blob upload gear active. 12:49:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:49:36 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 12:49:36 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', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:49:36 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 -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:49:36 INFO - (blobuploader) - INFO - Open directory for files ... 12:49:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:49:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:49:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:49:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:49:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:49:37 INFO - (blobuploader) - INFO - Done attempting. 12:49:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:49:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:49:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:49:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:49:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:49:44 INFO - (blobuploader) - INFO - Done attempting. 12:49:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:49:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:49:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:49:46 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:49:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:49:46 INFO - (blobuploader) - INFO - Done attempting. 12:49:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:49:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:49:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:49:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:49:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:49:46 INFO - (blobuploader) - INFO - Done attempting. 12:49:46 INFO - (blobuploader) - INFO - Iteration through files over. 12:49:46 INFO - Return code: 0 12:49:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:49:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:49:46 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/655c8cc9ac228128d2e18462c582112f18c409f4f50fc3b395ad523f0ea4e0784c80e37b9b96423761cf0fe735137f54a153a43af59e606d9bd99d5bfcd6f533", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/170002a505b3b87d3cef5f435403eefd61c05b5f7196b0d78ff3ceb86252342c46149458815a5e550316dd984fbac1edca20e647fe603db59c7feab592d86b5d", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eeac6c716a4ab83462dedec281ba6603ddde787c71013802283727c14d5b1296774a48ac3e233ab15486ac92741f6cf5b86fb2d74d59bf7565c1038ffe3fdec2", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d4e7c0657fa454d9ffcd1c68a10d6ec40a575c631320f928cf914845ae68b1951ad1cefc22770130ddc11f66d2ebf9fa91a2b789a3a59cda4dc11c15a657d5bd"} 12:49:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:49:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:49:46 INFO - Contents: 12:49:46 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/655c8cc9ac228128d2e18462c582112f18c409f4f50fc3b395ad523f0ea4e0784c80e37b9b96423761cf0fe735137f54a153a43af59e606d9bd99d5bfcd6f533", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/170002a505b3b87d3cef5f435403eefd61c05b5f7196b0d78ff3ceb86252342c46149458815a5e550316dd984fbac1edca20e647fe603db59c7feab592d86b5d", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eeac6c716a4ab83462dedec281ba6603ddde787c71013802283727c14d5b1296774a48ac3e233ab15486ac92741f6cf5b86fb2d74d59bf7565c1038ffe3fdec2", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d4e7c0657fa454d9ffcd1c68a10d6ec40a575c631320f928cf914845ae68b1951ad1cefc22770130ddc11f66d2ebf9fa91a2b789a3a59cda4dc11c15a657d5bd"} 12:49:46 INFO - Running post-run listener: copy_logs_to_upload_dir 12:49:46 INFO - Copying logs to upload dir... 12:49:46 INFO - mkdir: /builds/slave/test/build/upload/logs 12:49:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.648751 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2017-08-29 12:49:47.054849) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-29 12:49:47.058290) ========= 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=bb9b54edd4a927dc735d112f000001d9-1504034934.378726-75640050 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/sha512/655c8cc9ac228128d2e18462c582112f18c409f4f50fc3b395ad523f0ea4e0784c80e37b9b96423761cf0fe735137f54a153a43af59e606d9bd99d5bfcd6f533", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/170002a505b3b87d3cef5f435403eefd61c05b5f7196b0d78ff3ceb86252342c46149458815a5e550316dd984fbac1edca20e647fe603db59c7feab592d86b5d", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eeac6c716a4ab83462dedec281ba6603ddde787c71013802283727c14d5b1296774a48ac3e233ab15486ac92741f6cf5b86fb2d74d59bf7565c1038ffe3fdec2", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d4e7c0657fa454d9ffcd1c68a10d6ec40a575c631320f928cf914845ae68b1951ad1cefc22770130ddc11f66d2ebf9fa91a2b789a3a59cda4dc11c15a657d5bd"} build_url:https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011483 build_url: 'https://queue.taskcluster.net/v1/task/HdbSpWL2QCWufyiDij7DYg/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/655c8cc9ac228128d2e18462c582112f18c409f4f50fc3b395ad523f0ea4e0784c80e37b9b96423761cf0fe735137f54a153a43af59e606d9bd99d5bfcd6f533", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/170002a505b3b87d3cef5f435403eefd61c05b5f7196b0d78ff3ceb86252342c46149458815a5e550316dd984fbac1edca20e647fe603db59c7feab592d86b5d", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eeac6c716a4ab83462dedec281ba6603ddde787c71013802283727c14d5b1296774a48ac3e233ab15486ac92741f6cf5b86fb2d74d59bf7565c1038ffe3fdec2", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d4e7c0657fa454d9ffcd1c68a10d6ec40a575c631320f928cf914845ae68b1951ad1cefc22770130ddc11f66d2ebf9fa91a2b789a3a59cda4dc11c15a657d5bd"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-29 12:49:47.085927) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-29 12:49:47.086260) ========= 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=bb9b54edd4a927dc735d112f000001d9-1504034934.378726-75640050 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004406 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-29 12:49:47.172090) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-29 12:49:47.172407) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-29 12:49:47.172713) ========= ========= Total master_lag: 0.19 =========