builder: mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s slave: talos-linux64-ix-042 starttime: 1501722962.71 results: success (0) revision: 792d0a2e04d7bc337c77f4777848337ac6ba6dd6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-02 18:16:02.708465) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-02 18:16:02.708987) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-02 18:16:02.709399) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-02 18:16:02.747602) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-02 18:16:02.748014) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1501711782.305107-586941734 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-02 18:16:03-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 87.0M=0s 2017-08-02 18:16:03 (87.0 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.204045 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-02 18:16:03.010720) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-02 18:16:03.011221) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1501711782.305107-586941734 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020411 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-02 18:16:03.059364) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-02 18:16:03.063720) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 792d0a2e04d7bc337c77f4777848337ac6ba6dd6 --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 792d0a2e04d7bc337c77f4777848337ac6ba6dd6 --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=74d6aec007b0fa31a864ebd7000001f8-1501711782.305107-586941734 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-02 18:16:03,351 truncating revision to first 12 chars 2017-08-02 18:16:03,351 Setting DEBUG logging. 2017-08-02 18:16:03,351 attempt 1/10 2017-08-02 18:16:03,351 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/792d0a2e04d7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-02 18:16:04,369 unpacking tar archive at: mozilla-central-792d0a2e04d7/testing/mozharness/ program finished with exit code 0 elapsedTime=1.367010 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-02 18:16:04.450469) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-02 18:16:04.450833) ========= script_repo_revision: 792d0a2e04d7bc337c77f4777848337ac6ba6dd6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-02 18:16:04.454425) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-02 18:16:04.454814) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-02 18:16:04.464990) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2017-08-02 18:16:04.468755) ========= /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=74d6aec007b0fa31a864ebd7000001f8-1501711782.305107-586941734 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:16:04 INFO - MultiFileLogger online at 20170802 18:16:04 in /builds/slave/test 18:16:04 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 18:16:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:16:04 INFO - {'append_to_log': False, 18:16:04 INFO - 'base_work_dir': '/builds/slave/test', 18:16:04 INFO - 'blob_upload_branch': 'Firefox', 18:16:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:16:04 INFO - 'branch': 'Firefox', 18:16:04 INFO - 'buildbot_json_path': 'buildprops.json', 18:16:04 INFO - 'code_coverage': False, 18:16:04 INFO - 'config_files': ('talos/linux_config.py',), 18:16:04 INFO - 'default_actions': ('clobber', 18:16:04 INFO - 'read-buildbot-config', 18:16:04 INFO - 'download-and-extract', 18:16:04 INFO - 'populate-webroot', 18:16:04 INFO - 'create-virtualenv', 18:16:04 INFO - 'install', 18:16:04 INFO - 'setup-mitmproxy', 18:16:04 INFO - 'run-tests'), 18:16:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:16:04 INFO - 'disable_ccov_upload': False, 18:16:04 INFO - 'download_minidump_stackwalk': True, 18:16:04 INFO - 'download_symbols': 'ondemand', 18:16:04 INFO - 'enable_stylo': False, 18:16:04 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:16:04 INFO - 'tooltool.py': '/tools/tooltool.py', 18:16:04 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:16:04 INFO - '/tools/misc-python/virtualenv.py')}, 18:16:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:16:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:16:04 INFO - 'gecko_profile': False, 18:16:04 INFO - 'gecko_profile_interval': 0, 18:16:04 INFO - 'installer_path': 'installer.exe', 18:16:04 INFO - 'log_level': 'info', 18:16:04 INFO - 'log_name': 'talos', 18:16:04 INFO - 'log_to_console': True, 18:16:04 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:16:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:16:04 INFO - 'opt_config_files': (), 18:16:04 INFO - 'pip_index': False, 18:16:04 INFO - 'suite': 'dromaeojs-e10s', 18:16:04 INFO - 'system_bits': '32', 18:16:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:16:04 INFO - 'title': 'talos-linux64-ix-042', 18:16:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:16:04 INFO - 'use_talos_json': True, 18:16:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:16:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:16:04 INFO - 'work_dir': 'build'} 18:16:04 INFO - [mozharness: 2017-08-03 01:16:04.954614Z] Running clobber step. 18:16:04 INFO - Running pre-action listener: _resource_record_pre_action 18:16:04 INFO - Running main action method: clobber 18:16:04 INFO - rmtree: /builds/slave/test/build 18:16:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:16:05 INFO - Running post-action listener: _resource_record_post_action 18:16:05 INFO - [mozharness: 2017-08-03 01:16:05.935477Z] Finished clobber step (success) 18:16:05 INFO - [mozharness: 2017-08-03 01:16:05.935579Z] Running read-buildbot-config step. 18:16:05 INFO - Running pre-action listener: _resource_record_pre_action 18:16:05 INFO - Running main action method: read_buildbot_config 18:16:05 INFO - Using buildbot properties: 18:16:05 INFO - { 18:16:05 INFO - "project": "", 18:16:05 INFO - "product": "firefox", 18:16:05 INFO - "who": "mh@glandium.org", 18:16:05 INFO - "installer_path": "public/build/target.tar.bz2", 18:16:05 INFO - "builddir": "mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s", 18:16:05 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 18:16:05 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo-sequential mozilla-central talos dromaeojs-e10s", 18:16:05 INFO - "stage_platform": "linux64-stylosequential", 18:16:05 INFO - "basedir": "/builds/slave/test", 18:16:05 INFO - "buildnumber": 15, 18:16:05 INFO - "platform": "ubuntu64_hw_styloseq", 18:16:05 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 18:16:05 INFO - "slavebuilddir": "test", 18:16:05 INFO - "taskId": "Twy2JTZ8Qq-vfAj7-jUFJw", 18:16:05 INFO - "branch": "mozilla-central", 18:16:05 INFO - "script_repo_revision": "production", 18:16:05 INFO - "revision": "792d0a2e04d7bc337c77f4777848337ac6ba6dd6", 18:16:05 INFO - "slavename": "talos-linux64-ix-042", 18:16:05 INFO - "repo_path": "mozilla-central" 18:16:05 INFO - } 18:16:05 INFO - Finding installer, test and symbols from parent task. 18:16:05 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Twy2JTZ8Qq-vfAj7-jUFJw'}, attempt #1 18:16:06 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Twy2JTZ8Qq-vfAj7-jUFJw'}, attempt #1 18:16:06 INFO - Task dependencies: HQaDFxsZT1qGSdnXmYiyWQ 18:16:06 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HQaDFxsZT1qGSdnXmYiyWQ'}, attempt #1 18:16:07 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/HQaDFxsZT1qGSdnXmYiyWQ/artifacts/public/build/target.tar.bz2 18:16:07 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/HQaDFxsZT1qGSdnXmYiyWQ/artifacts/public/build/target.test_packages.json 18:16:07 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/HQaDFxsZT1qGSdnXmYiyWQ/artifacts/public/build/target.crashreporter-symbols.zip 18:16:07 INFO - Running post-action listener: _resource_record_post_action 18:16:07 INFO - [mozharness: 2017-08-03 01:16:07.293159Z] Finished read-buildbot-config step (success) 18:16:07 INFO - [mozharness: 2017-08-03 01:16:07.293426Z] Running download-and-extract step. 18:16:07 INFO - Running pre-action listener: _resource_record_pre_action 18:16:07 INFO - Running main action method: download_and_extract 18:16:07 INFO - mkdir: /builds/slave/test/build/tests 18:16:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:07 INFO - trying https://queue.taskcluster.net/v1/task/HQaDFxsZT1qGSdnXmYiyWQ/artifacts/public/build/target.test_packages.json 18:16:07 INFO - Downloading https://queue.taskcluster.net/v1/task/HQaDFxsZT1qGSdnXmYiyWQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 18:16:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HQaDFxsZT1qGSdnXmYiyWQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 18:16:07 INFO - Downloaded 989 bytes. 18:16:07 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 18:16:07 INFO - Using the following test package requirements: 18:16:07 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 18:16:07 INFO - u'common': [u'target.common.tests.zip'], 18:16:07 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 18:16:07 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 18:16:07 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 18:16:07 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 18:16:07 INFO - u'mozbase': [u'target.common.tests.zip'], 18:16:07 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 18:16:07 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 18:16:07 INFO - u'web-platform': [u'target.common.tests.zip', 18:16:07 INFO - u'target.web-platform.tests.tar.gz'], 18:16:07 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 18:16:07 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 18:16:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HQaDFxsZT1qGSdnXmYiyWQ/artifacts/public/build/target.common.tests.zip 18:16:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HQaDFxsZT1qGSdnXmYiyWQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:16:07 INFO - Fetch https://queue.taskcluster.net/v1/task/HQaDFxsZT1qGSdnXmYiyWQ/artifacts/public/build/target.common.tests.zip into memory 18:16:09 INFO - Content-Length response header: 72347795 18:16:09 INFO - Bytes received: 72347795 18:16:20 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 18:16:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HQaDFxsZT1qGSdnXmYiyWQ/artifacts/public/build/target.common.tests.zip 18:16:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HQaDFxsZT1qGSdnXmYiyWQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:16:20 INFO - Fetch https://queue.taskcluster.net/v1/task/HQaDFxsZT1qGSdnXmYiyWQ/artifacts/public/build/target.common.tests.zip into memory 18:16:21 INFO - Content-Length response header: 72347795 18:16:21 INFO - Bytes received: 72347795 18:16:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HQaDFxsZT1qGSdnXmYiyWQ/artifacts/public/build/target.talos.tests.zip 18:16:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HQaDFxsZT1qGSdnXmYiyWQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 18:16:34 INFO - Fetch https://queue.taskcluster.net/v1/task/HQaDFxsZT1qGSdnXmYiyWQ/artifacts/public/build/target.talos.tests.zip into memory 18:16:35 INFO - Content-Length response header: 14496464 18:16:35 INFO - Bytes received: 14496464 18:16:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:35 INFO - trying https://queue.taskcluster.net/v1/task/HQaDFxsZT1qGSdnXmYiyWQ/artifacts/public/build/target.tar.bz2 18:16:35 INFO - Downloading https://queue.taskcluster.net/v1/task/HQaDFxsZT1qGSdnXmYiyWQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 18:16:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HQaDFxsZT1qGSdnXmYiyWQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 18:16:36 INFO - Downloaded 62240631 bytes. 18:16:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HQaDFxsZT1qGSdnXmYiyWQ/artifacts/public/build/target.tar.bz2 18:16:36 INFO - mkdir: /builds/slave/test/properties 18:16:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:16:36 INFO - Writing to file /builds/slave/test/properties/build_url 18:16:36 INFO - Contents: 18:16:36 INFO - build_url:https://queue.taskcluster.net/v1/task/HQaDFxsZT1qGSdnXmYiyWQ/artifacts/public/build/target.tar.bz2 18:16:36 INFO - Running post-action listener: _resource_record_post_action 18:16:36 INFO - Running post-action listener: set_extra_try_arguments 18:16:36 INFO - [mozharness: 2017-08-03 01:16:36.707656Z] Finished download-and-extract step (success) 18:16:36 INFO - [mozharness: 2017-08-03 01:16:36.707754Z] Running populate-webroot step. 18:16:36 INFO - Running pre-action listener: _resource_record_pre_action 18:16:36 INFO - Running main action method: populate_webroot 18:16:36 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:16:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:16:36 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:16:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:16:36 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:16:36 INFO - 'tests': ['damp', 'tps']}, 18:16:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:16:36 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:16:36 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:16:36 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:16:36 INFO - 'other-e10s': {'tests': ['a11yr', 18:16:36 INFO - 'ts_paint', 18:16:36 INFO - 'tpaint', 18:16:36 INFO - 'sessionrestore', 18:16:36 INFO - 'sessionrestore_many_windows', 18:16:36 INFO - 'sessionrestore_no_auto_restore', 18:16:36 INFO - 'tabpaint']}, 18:16:36 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 18:16:36 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 18:16:36 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:16:36 INFO - 'talos_options': ['--mitmproxy', 18:16:36 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:16:36 INFO - '--firstNonBlankPaint'], 18:16:36 INFO - 'tests': ['quantum_pageload_google', 18:16:36 INFO - 'quantum_pageload_youtube', 18:16:36 INFO - 'quantum_pageload_amazon', 18:16:36 INFO - 'quantum_pageload_facebook']}, 18:16:36 INFO - 'quantum-pageload-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:16:36 INFO - 'talos_options': ['--stylo', 18:16:36 INFO - '--mitmproxy', 18:16:36 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:16:36 INFO - '--firstNonBlankPaint'], 18:16:36 INFO - 'tests': ['quantum_pageload_google', 18:16:36 INFO - 'quantum_pageload_youtube', 18:16:36 INFO - 'quantum_pageload_amazon', 18:16:36 INFO - 'quantum_pageload_facebook']}, 18:16:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:16:36 INFO - 'tsvgr_opacity', 18:16:36 INFO - 'tart', 18:16:36 INFO - 'tscrollx', 18:16:36 INFO - 'cart', 18:16:36 INFO - 'tsvg_static']}, 18:16:36 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:16:36 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:16:36 INFO - 'talos_options': ['--stylo', 18:16:36 INFO - '--stylo-threads=1', 18:16:36 INFO - '--mitmproxy', 18:16:36 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:16:36 INFO - '--firstNonBlankPaint'], 18:16:36 INFO - 'tests': ['tp6_google', 18:16:36 INFO - 'tp6_youtube', 18:16:36 INFO - 'tp6_amazon', 18:16:36 INFO - 'tp6_facebook']}, 18:16:36 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:16:36 INFO - 'talos_options': ['--xperf_path', 18:16:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:16:36 INFO - 'tests': ['tp5n']}}} 18:16:36 INFO - Running post-action listener: _resource_record_post_action 18:16:36 INFO - [mozharness: 2017-08-03 01:16:36.716460Z] Finished populate-webroot step (success) 18:16:36 INFO - [mozharness: 2017-08-03 01:16:36.716551Z] Running create-virtualenv step. 18:16:36 INFO - Running pre-action listener: _resource_record_pre_action 18:16:36 INFO - Running main action method: create_virtualenv 18:16:36 INFO - Creating virtualenv /builds/slave/test/build/venv 18:16:36 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:16:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:16:36 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 18:16:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:16:36 INFO - Using real prefix '/usr' 18:16:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:16:37 INFO - Installing distribute.............................................................................................................................................................................................done. 18:16:39 INFO - Installing pip.................done. 18:16:39 INFO - Return code: 0 18:16:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:16:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:16:39 INFO - Reading from file tmpfile_stdout 18:16:39 INFO - Output received: 18:16:39 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 18:16:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:16: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')]} 18:16:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16: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')]} 18:16:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:39 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 0x1f2d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20f9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x229cd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2299eb0>, '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 0x229adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x229b240>, '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': '74d6aec007b0fa31a864ebd7000001f8-1501711782.305107-586941734', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:16:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:16:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:16:39 INFO - Using env: {'DISPLAY': ':0', 18:16:39 INFO - 'HOME': '/home/cltbld', 18:16:39 INFO - 'LANG': 'en_US.UTF-8', 18:16:39 INFO - 'LANGUAGE': 'en_US:en', 18:16:39 INFO - 'LOGNAME': 'cltbld', 18:16:39 INFO - 'MAIL': '/var/mail/cltbld', 18:16:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:39 INFO - 'MOZ_NO_REMOTE': '1', 18:16:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:16:39 INFO - 'NO_EM_RESTART': '1', 18:16:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:16:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:39 INFO - 'PWD': '/builds/slave/test', 18:16:39 INFO - 'SHELL': '/bin/bash', 18:16:39 INFO - 'SHLVL': '1', 18:16:39 INFO - 'TERM': 'linux', 18:16:39 INFO - 'TMOUT': '86400', 18:16:39 INFO - 'USER': 'cltbld', 18:16:39 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1501711782.305107-586941734', 18:16:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:39 INFO - '_': '/tools/buildbot/bin/python'} 18:16:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:39 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 18:16:39 INFO - Cleaning up... 18:16:39 INFO - Return code: 0 18:16:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:16: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')]} 18:16:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16: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')]} 18:16:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:39 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 0x1f2d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20f9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x229cd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2299eb0>, '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 0x229adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x229b240>, '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': '74d6aec007b0fa31a864ebd7000001f8-1501711782.305107-586941734', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:16:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:16:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:16:39 INFO - Using env: {'DISPLAY': ':0', 18:16:39 INFO - 'HOME': '/home/cltbld', 18:16:39 INFO - 'LANG': 'en_US.UTF-8', 18:16:39 INFO - 'LANGUAGE': 'en_US:en', 18:16:39 INFO - 'LOGNAME': 'cltbld', 18:16:39 INFO - 'MAIL': '/var/mail/cltbld', 18:16:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:39 INFO - 'MOZ_NO_REMOTE': '1', 18:16:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:16:39 INFO - 'NO_EM_RESTART': '1', 18:16:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:16:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:39 INFO - 'PWD': '/builds/slave/test', 18:16:39 INFO - 'SHELL': '/bin/bash', 18:16:39 INFO - 'SHLVL': '1', 18:16:39 INFO - 'TERM': 'linux', 18:16:39 INFO - 'TMOUT': '86400', 18:16:39 INFO - 'USER': 'cltbld', 18:16:39 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1501711782.305107-586941734', 18:16:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:39 INFO - '_': '/tools/buildbot/bin/python'} 18:16:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:40 INFO - Downloading/unpacking psutil>=3.1.1 18:16:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:16:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:16:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:16:41 INFO - Installing collected packages: psutil 18:16:41 INFO - Running setup.py install for psutil 18:16:41 INFO - building 'psutil._psutil_linux' extension 18:16:41 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 18:16:42 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 18:16:42 INFO - building 'psutil._psutil_posix' extension 18:16:42 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 18:16:42 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 18:16:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:16:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:16:42 INFO - Successfully installed psutil 18:16:42 INFO - Cleaning up... 18:16:42 INFO - Return code: 0 18:16:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:16: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')]} 18:16:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16: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')]} 18:16:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:42 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 0x1f2d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20f9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x229cd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2299eb0>, '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 0x229adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x229b240>, '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': '74d6aec007b0fa31a864ebd7000001f8-1501711782.305107-586941734', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:16:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:16:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:16:42 INFO - Using env: {'DISPLAY': ':0', 18:16:42 INFO - 'HOME': '/home/cltbld', 18:16:42 INFO - 'LANG': 'en_US.UTF-8', 18:16:42 INFO - 'LANGUAGE': 'en_US:en', 18:16:42 INFO - 'LOGNAME': 'cltbld', 18:16:42 INFO - 'MAIL': '/var/mail/cltbld', 18:16:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:42 INFO - 'MOZ_NO_REMOTE': '1', 18:16:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:16:42 INFO - 'NO_EM_RESTART': '1', 18:16:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:16:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:42 INFO - 'PWD': '/builds/slave/test', 18:16:42 INFO - 'SHELL': '/bin/bash', 18:16:42 INFO - 'SHLVL': '1', 18:16:42 INFO - 'TERM': 'linux', 18:16:42 INFO - 'TMOUT': '86400', 18:16:42 INFO - 'USER': 'cltbld', 18:16:42 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1501711782.305107-586941734', 18:16:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:42 INFO - '_': '/tools/buildbot/bin/python'} 18:16:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:16: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 18:16: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 18:16: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 18:16: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 18:16:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:16:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:16:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:16:44 INFO - Installing collected packages: mozsystemmonitor 18:16:44 INFO - Running setup.py install for mozsystemmonitor 18:16:44 INFO - Successfully installed mozsystemmonitor 18:16:44 INFO - Cleaning up... 18:16:44 INFO - Return code: 0 18:16:44 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:16:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:44 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 0x1f2d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20f9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x229cd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2299eb0>, '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 0x229adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x229b240>, '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': '74d6aec007b0fa31a864ebd7000001f8-1501711782.305107-586941734', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:16:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:16:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:16:44 INFO - Using env: {'DISPLAY': ':0', 18:16:44 INFO - 'HOME': '/home/cltbld', 18:16:44 INFO - 'LANG': 'en_US.UTF-8', 18:16:44 INFO - 'LANGUAGE': 'en_US:en', 18:16:44 INFO - 'LOGNAME': 'cltbld', 18:16:44 INFO - 'MAIL': '/var/mail/cltbld', 18:16:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:44 INFO - 'MOZ_NO_REMOTE': '1', 18:16:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:16:44 INFO - 'NO_EM_RESTART': '1', 18:16:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:16:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:44 INFO - 'PWD': '/builds/slave/test', 18:16:44 INFO - 'SHELL': '/bin/bash', 18:16:44 INFO - 'SHLVL': '1', 18:16:44 INFO - 'TERM': 'linux', 18:16:44 INFO - 'TMOUT': '86400', 18:16:44 INFO - 'USER': 'cltbld', 18:16:44 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1501711782.305107-586941734', 18:16:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:44 INFO - '_': '/tools/buildbot/bin/python'} 18:16:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:44 INFO - Downloading/unpacking jsonschema==2.5.1 18:16:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:16:45 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:16:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:45 INFO - Downloading functools32-3.2.3-2.tar.gz 18:16:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:16:45 INFO - warning: no files found matching '*.txt' 18:16:45 INFO - no previously-included directories found matching 'build' 18:16:45 INFO - no previously-included directories found matching 'dist' 18:16:45 INFO - no previously-included directories found matching '.git*' 18:16:45 INFO - Installing collected packages: jsonschema, functools32 18:16:45 INFO - Running setup.py install for functools32 18:16:45 INFO - warning: no files found matching '*.txt' 18:16:45 INFO - no previously-included directories found matching 'build' 18:16:45 INFO - no previously-included directories found matching 'dist' 18:16:45 INFO - no previously-included directories found matching '.git*' 18:16:45 INFO - Successfully installed jsonschema functools32 18:16:45 INFO - Cleaning up... 18:16:45 INFO - Return code: 0 18:16:45 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:16: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')]} 18:16:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16: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')]} 18:16:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:46 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 0x1f2d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20f9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x229cd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2299eb0>, '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 0x229adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x229b240>, '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': '74d6aec007b0fa31a864ebd7000001f8-1501711782.305107-586941734', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:16:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:16:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:16:46 INFO - Using env: {'DISPLAY': ':0', 18:16:46 INFO - 'HOME': '/home/cltbld', 18:16:46 INFO - 'LANG': 'en_US.UTF-8', 18:16:46 INFO - 'LANGUAGE': 'en_US:en', 18:16:46 INFO - 'LOGNAME': 'cltbld', 18:16:46 INFO - 'MAIL': '/var/mail/cltbld', 18:16:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:46 INFO - 'MOZ_NO_REMOTE': '1', 18:16:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:16:46 INFO - 'NO_EM_RESTART': '1', 18:16:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:16:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:46 INFO - 'PWD': '/builds/slave/test', 18:16:46 INFO - 'SHELL': '/bin/bash', 18:16:46 INFO - 'SHLVL': '1', 18:16:46 INFO - 'TERM': 'linux', 18:16:46 INFO - 'TMOUT': '86400', 18:16:46 INFO - 'USER': 'cltbld', 18:16:46 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1501711782.305107-586941734', 18:16:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:46 INFO - '_': '/tools/buildbot/bin/python'} 18:16:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:46 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:16:46 INFO - Cleaning up... 18:16:46 INFO - Return code: 0 18:16:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:16:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:46 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 0x1f2d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20f9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x229cd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2299eb0>, '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 0x229adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x229b240>, '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': '74d6aec007b0fa31a864ebd7000001f8-1501711782.305107-586941734', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:16:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:16:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:16:46 INFO - Using env: {'DISPLAY': ':0', 18:16:46 INFO - 'HOME': '/home/cltbld', 18:16:46 INFO - 'LANG': 'en_US.UTF-8', 18:16:46 INFO - 'LANGUAGE': 'en_US:en', 18:16:46 INFO - 'LOGNAME': 'cltbld', 18:16:46 INFO - 'MAIL': '/var/mail/cltbld', 18:16:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:46 INFO - 'MOZ_NO_REMOTE': '1', 18:16:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:16:46 INFO - 'NO_EM_RESTART': '1', 18:16:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:16:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:46 INFO - 'PWD': '/builds/slave/test', 18:16:46 INFO - 'SHELL': '/bin/bash', 18:16:46 INFO - 'SHLVL': '1', 18:16:46 INFO - 'TERM': 'linux', 18:16:46 INFO - 'TMOUT': '86400', 18:16:46 INFO - 'USER': 'cltbld', 18:16:46 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1501711782.305107-586941734', 18:16:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:46 INFO - '_': '/tools/buildbot/bin/python'} 18:16:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:46 INFO - Downloading/unpacking blobuploader==1.2.4 18:16:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:47 INFO - Downloading blobuploader-1.2.4.tar.gz 18:16:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:16:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:16:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:16:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:16:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:48 INFO - Downloading docopt-0.6.1.tar.gz 18:16:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:16:48 INFO - Installing collected packages: blobuploader, requests, docopt 18:16:48 INFO - Running setup.py install for blobuploader 18:16:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:16:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:16:48 INFO - Running setup.py install for requests 18:16:48 INFO - Running setup.py install for docopt 18:16:48 INFO - Successfully installed blobuploader requests docopt 18:16:48 INFO - Cleaning up... 18:16:48 INFO - Return code: 0 18:16:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:16:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:48 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 0x1f2d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20f9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x229cd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2299eb0>, '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 0x229adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x229b240>, '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': '74d6aec007b0fa31a864ebd7000001f8-1501711782.305107-586941734', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:16:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:16:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:16:48 INFO - Using env: {'DISPLAY': ':0', 18:16:48 INFO - 'HOME': '/home/cltbld', 18:16:48 INFO - 'LANG': 'en_US.UTF-8', 18:16:48 INFO - 'LANGUAGE': 'en_US:en', 18:16:48 INFO - 'LOGNAME': 'cltbld', 18:16:48 INFO - 'MAIL': '/var/mail/cltbld', 18:16:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:48 INFO - 'MOZ_NO_REMOTE': '1', 18:16:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:16:48 INFO - 'NO_EM_RESTART': '1', 18:16:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:16:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:48 INFO - 'PWD': '/builds/slave/test', 18:16:48 INFO - 'SHELL': '/bin/bash', 18:16:48 INFO - 'SHLVL': '1', 18:16:48 INFO - 'TERM': 'linux', 18:16:48 INFO - 'TMOUT': '86400', 18:16:48 INFO - 'USER': 'cltbld', 18:16:48 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1501711782.305107-586941734', 18:16:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:48 INFO - '_': '/tools/buildbot/bin/python'} 18:16:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:16:48 INFO - Running setup.py (path:/tmp/pip-SCMMlN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:16:48 INFO - Running setup.py (path:/tmp/pip-jFxTLA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:16:49 INFO - Running setup.py (path:/tmp/pip-tWxGaC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:16:49 INFO - Running setup.py (path:/tmp/pip-DAGzbn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:16:49 INFO - Running setup.py (path:/tmp/pip-Bgn8cN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:16:49 INFO - Running setup.py (path:/tmp/pip-v7qDRA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:16:49 INFO - Running setup.py (path:/tmp/pip-AphgVd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:16:49 INFO - Running setup.py (path:/tmp/pip-l3Uv_F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:16:49 INFO - Running setup.py (path:/tmp/pip-ym_7L2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:16:49 INFO - Running setup.py (path:/tmp/pip-pqbEJh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:16:49 INFO - Running setup.py (path:/tmp/pip-KvIsg_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:16:49 INFO - Running setup.py (path:/tmp/pip-wKrQ5f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:16:49 INFO - Running setup.py (path:/tmp/pip-zqhUDj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:16:49 INFO - Running setup.py (path:/tmp/pip-2qGky7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:16:49 INFO - Running setup.py (path:/tmp/pip-6UiE7l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:16:49 INFO - Running setup.py (path:/tmp/pip-YJX5_B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:16:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:16:50 INFO - Running setup.py (path:/tmp/pip-GhkPNI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:16:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:16:50 INFO - Running setup.py install for manifestparser 18:16:50 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:16:50 INFO - Running setup.py install for mozcrash 18:16:50 INFO - Running setup.py install for mozdebug 18:16:50 INFO - Running setup.py install for mozdevice 18:16:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:16:50 INFO - Running setup.py install for mozfile 18:16:50 INFO - Running setup.py install for mozhttpd 18:16:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:16:50 INFO - Running setup.py install for mozinfo 18:16:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:16:50 INFO - Running setup.py install for mozInstall 18:16:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:16:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:16:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:16:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:16:50 INFO - Running setup.py install for mozleak 18:16:50 INFO - Running setup.py install for mozlog 18:16:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:16:51 INFO - Running setup.py install for moznetwork 18:16:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:16:51 INFO - Running setup.py install for mozprocess 18:16:51 INFO - Running setup.py install for mozprofile 18:16:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:16:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:16:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:16:51 INFO - Running setup.py install for mozrunner 18:16:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:16:51 INFO - Running setup.py install for mozscreenshot 18:16:51 INFO - Running setup.py install for moztest 18:16:51 INFO - Running setup.py install for mozversion 18:16:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:16:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:16:51 INFO - Cleaning up... 18:16:51 INFO - Return code: 0 18:16:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:16:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:51 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 0x1f2d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20f9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x229cd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2299eb0>, '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 0x229adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x229b240>, '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': '74d6aec007b0fa31a864ebd7000001f8-1501711782.305107-586941734', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:16:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:16:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:16:51 INFO - Using env: {'DISPLAY': ':0', 18:16:51 INFO - 'HOME': '/home/cltbld', 18:16:51 INFO - 'LANG': 'en_US.UTF-8', 18:16:51 INFO - 'LANGUAGE': 'en_US:en', 18:16:51 INFO - 'LOGNAME': 'cltbld', 18:16:51 INFO - 'MAIL': '/var/mail/cltbld', 18:16:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:51 INFO - 'MOZ_NO_REMOTE': '1', 18:16:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:16:51 INFO - 'NO_EM_RESTART': '1', 18:16:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:16:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:51 INFO - 'PWD': '/builds/slave/test', 18:16:51 INFO - 'SHELL': '/bin/bash', 18:16:51 INFO - 'SHLVL': '1', 18:16:51 INFO - 'TERM': 'linux', 18:16:51 INFO - 'TMOUT': '86400', 18:16:51 INFO - 'USER': 'cltbld', 18:16:51 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1501711782.305107-586941734', 18:16:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:51 INFO - '_': '/tools/buildbot/bin/python'} 18:16:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:16:52 INFO - Running setup.py (path:/tmp/pip-uMx0N5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:16:52 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 18:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:16:52 INFO - Running setup.py (path:/tmp/pip-RoI5Dh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:16:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:16:52 INFO - Running setup.py (path:/tmp/pip-F5ROTB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:16:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:16:52 INFO - Running setup.py (path:/tmp/pip-Y_6iq6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:16:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:16:52 INFO - Running setup.py (path:/tmp/pip-to2b0L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:16:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 18:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:16:52 INFO - Running setup.py (path:/tmp/pip-g58ftS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:16:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 18:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:16:52 INFO - Running setup.py (path:/tmp/pip-OIIzbt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:16:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 18:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:16:52 INFO - Running setup.py (path:/tmp/pip-SIAmA7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:16:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:16:52 INFO - Running setup.py (path:/tmp/pip-huPUJx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:16:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 18:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:16:52 INFO - Running setup.py (path:/tmp/pip-ZZRSEw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:16:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:16:52 INFO - Running setup.py (path:/tmp/pip-5ISBAa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:16:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 18:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:16:52 INFO - Running setup.py (path:/tmp/pip-FupAT6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:16:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 18:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:16:53 INFO - Running setup.py (path:/tmp/pip-4oUhtZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:16:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 18:16:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:16:53 INFO - Running setup.py (path:/tmp/pip-uy9K6V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:16:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 18:16:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:16:53 INFO - Running setup.py (path:/tmp/pip-u6gGxS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:16:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 18:16:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:16:53 INFO - Running setup.py (path:/tmp/pip-IbvWaF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:16:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 18:16:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:16:53 INFO - Running setup.py (path:/tmp/pip-aVlJn5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:16:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 18:16:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:16:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:16:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:16:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:16:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:16:53 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:16:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:16:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:54 INFO - Downloading blessings-1.6.tar.gz 18:16:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:16:54 INFO - Installing collected packages: blessings 18:16:54 INFO - Running setup.py install for blessings 18:16:54 INFO - Successfully installed blessings 18:16:54 INFO - Cleaning up... 18:16:54 INFO - Return code: 0 18:16:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:16:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:16:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:16:54 INFO - Reading from file tmpfile_stdout 18:16:54 INFO - Current package versions: 18:16:54 INFO - argparse == 1.2.1 18:16:54 INFO - blessings == 1.6 18:16:54 INFO - blobuploader == 1.2.4 18:16:54 INFO - docopt == 0.6.1 18:16:54 INFO - functools32 == 3.2.3-2 18:16:54 INFO - jsonschema == 2.5.1 18:16:54 INFO - manifestparser == 1.1 18:16:54 INFO - mozInstall == 1.13 18:16:54 INFO - mozcrash == 1.0 18:16:54 INFO - mozdebug == 0.1 18:16:54 INFO - mozdevice == 0.51 18:16:54 INFO - mozfile == 1.2 18:16:54 INFO - mozhttpd == 0.7 18:16:54 INFO - mozinfo == 0.10 18:16:54 INFO - mozleak == 0.1 18:16:54 INFO - mozlog == 3.5 18:16:54 INFO - moznetwork == 0.27 18:16:54 INFO - mozprocess == 0.25 18:16:54 INFO - mozprofile == 0.28 18:16:54 INFO - mozrunner == 6.13 18:16:54 INFO - mozscreenshot == 0.1 18:16:54 INFO - mozsystemmonitor == 0.3 18:16:54 INFO - moztest == 0.8 18:16:54 INFO - mozversion == 1.4 18:16:54 INFO - psutil == 3.1.1 18:16:54 INFO - requests == 1.2.3 18:16:54 INFO - wsgiref == 0.1.2 18:16:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:16:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:54 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 0x1f2d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20f9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x229cd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2299eb0>, '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 0x229adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x229b240>, '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': '74d6aec007b0fa31a864ebd7000001f8-1501711782.305107-586941734', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:16:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 18:16:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:16:54 INFO - Using env: {'DISPLAY': ':0', 18:16:54 INFO - 'HOME': '/home/cltbld', 18:16:54 INFO - 'LANG': 'en_US.UTF-8', 18:16:54 INFO - 'LANGUAGE': 'en_US:en', 18:16:54 INFO - 'LOGNAME': 'cltbld', 18:16:54 INFO - 'MAIL': '/var/mail/cltbld', 18:16:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:54 INFO - 'MOZ_NO_REMOTE': '1', 18:16:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:16:54 INFO - 'NO_EM_RESTART': '1', 18:16:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:16:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:54 INFO - 'PWD': '/builds/slave/test', 18:16:54 INFO - 'SHELL': '/bin/bash', 18:16:54 INFO - 'SHLVL': '1', 18:16:54 INFO - 'TERM': 'linux', 18:16:54 INFO - 'TMOUT': '86400', 18:16:54 INFO - 'USER': 'cltbld', 18:16:54 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1501711782.305107-586941734', 18:16:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:54 INFO - '_': '/tools/buildbot/bin/python'} 18:16:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:16:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 18:16:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 18:16:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:16:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 18:16:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 18:16:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 18:16:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 18:16:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 18:16:55 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:16:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 18:16:56 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:16:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:16:56 INFO - Installing collected packages: simplejson 18:16:56 INFO - Running setup.py install for simplejson 18:16:56 INFO - building 'simplejson._speedups' extension 18:16:56 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 18:16:57 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 18:16:57 INFO - Successfully installed simplejson 18:16:57 INFO - Cleaning up... 18:16:57 INFO - Return code: 0 18:16:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:16:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:57 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 0x1f2d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20f9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x229cd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2299eb0>, '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 0x229adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x229b240>, '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': '74d6aec007b0fa31a864ebd7000001f8-1501711782.305107-586941734', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:16:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:16:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:16:57 INFO - Using env: {'DISPLAY': ':0', 18:16:57 INFO - 'HOME': '/home/cltbld', 18:16:57 INFO - 'LANG': 'en_US.UTF-8', 18:16:57 INFO - 'LANGUAGE': 'en_US:en', 18:16:57 INFO - 'LOGNAME': 'cltbld', 18:16:57 INFO - 'MAIL': '/var/mail/cltbld', 18:16:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:57 INFO - 'MOZ_NO_REMOTE': '1', 18:16:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:16:57 INFO - 'NO_EM_RESTART': '1', 18:16:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:16:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:57 INFO - 'PWD': '/builds/slave/test', 18:16:57 INFO - 'SHELL': '/bin/bash', 18:16:57 INFO - 'SHLVL': '1', 18:16:57 INFO - 'TERM': 'linux', 18:16:57 INFO - 'TMOUT': '86400', 18:16:57 INFO - 'USER': 'cltbld', 18:16:57 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1501711782.305107-586941734', 18:16:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:57 INFO - '_': '/tools/buildbot/bin/python'} 18:16:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:57 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:16:57 INFO - Cleaning up... 18:16:57 INFO - Return code: 0 18:16:57 INFO - Running post-action listener: _resource_record_post_action 18:16:57 INFO - Running post-action listener: _start_resource_monitoring 18:16:57 INFO - Starting resource monitoring. 18:16:57 INFO - [mozharness: 2017-08-03 01:16:57.659231Z] Finished create-virtualenv step (success) 18:16:57 INFO - [mozharness: 2017-08-03 01:16:57.660238Z] Running install step. 18:16:57 INFO - Running pre-action listener: _resource_record_pre_action 18:16:57 INFO - Running main action method: install 18:16:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:16:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:16:57 INFO - Reading from file tmpfile_stdout 18:16:57 INFO - Detecting whether we're running mozinstall >=1.0... 18:16:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:16:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:16:58 INFO - Reading from file tmpfile_stdout 18:16:58 INFO - Output received: 18:16:58 INFO - Usage: mozinstall [options] installer 18:16:58 INFO - Options: 18:16:58 INFO - -h, --help show this help message and exit 18:16:58 INFO - -d DEST, --destination=DEST 18:16:58 INFO - Directory to install application into. [default: 18:16:58 INFO - "/builds/slave/test"] 18:16:58 INFO - --app=APP Application being installed. [default: firefox] 18:16:58 INFO - mkdir: /builds/slave/test/build/application 18:16:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 18:16:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 18:17:16 INFO - Reading from file tmpfile_stdout 18:17:16 INFO - Output received: 18:17:16 INFO - /builds/slave/test/build/application/firefox/firefox 18:17:16 INFO - Running post-action listener: _resource_record_post_action 18:17:16 INFO - [mozharness: 2017-08-03 01:17:16.069678Z] Finished install step (success) 18:17:16 INFO - [mozharness: 2017-08-03 01:17:16.069932Z] Running setup-mitmproxy step. 18:17:16 INFO - Running pre-action listener: _resource_record_pre_action 18:17:16 INFO - Running main action method: setup_mitmproxy 18:17:16 INFO - Skipping: mitmproxy is not required 18:17:16 INFO - Running post-action listener: _resource_record_post_action 18:17:16 INFO - [mozharness: 2017-08-03 01:17:16.071061Z] Finished setup-mitmproxy step (success) 18:17:16 INFO - [mozharness: 2017-08-03 01:17:16.071284Z] Running run-tests step. 18:17:16 INFO - Running pre-action listener: _resource_record_pre_action 18:17:16 INFO - Running pre-action listener: _set_gcov_prefix 18:17:16 INFO - Running main action method: run_tests 18:17:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:17:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:17:16 INFO - Python 2.7.3 18:17:16 INFO - Return code: 0 18:17:16 INFO - grabbing minidump binary from tooltool 18:17:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:17:16 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--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 0x2299eb0>, '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 0x229adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x229b240>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:17:16 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 18:17:16 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 18:17:16 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 18:17:16 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:17:16 INFO - Return code: 0 18:17:16 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:17:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:17:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:17:16 INFO - ENV: RUST_BACKTRACE is now 1 18:17:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:17:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:17:16 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/HQaDFxsZT1qGSdnXmYiyWQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-042', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 18:17:16 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/HQaDFxsZT1qGSdnXmYiyWQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-042 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 18:17:16 INFO - Using env: {'DISPLAY': ':0', 18:17:16 INFO - 'HOME': '/home/cltbld', 18:17:16 INFO - 'LANG': 'en_US.UTF-8', 18:17:16 INFO - 'LANGUAGE': 'en_US:en', 18:17:16 INFO - 'LOGNAME': 'cltbld', 18:17:16 INFO - 'MAIL': '/var/mail/cltbld', 18:17:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:17:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:17:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:17:16 INFO - 'MOZ_NO_REMOTE': '1', 18:17:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:17:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:17:16 INFO - 'NO_EM_RESTART': '1', 18:17:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:17:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:17:16 INFO - 'PWD': '/builds/slave/test', 18:17:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:17:16 INFO - 'RUST_BACKTRACE': '1', 18:17:16 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:17:16 INFO - 'SHELL': '/bin/bash', 18:17:16 INFO - 'SHLVL': '1', 18:17:16 INFO - 'STYLO_FORCE_ENABLED': '1', 18:17:16 INFO - 'TERM': 'linux', 18:17:16 INFO - 'TMOUT': '86400', 18:17:16 INFO - 'USER': 'cltbld', 18:17:16 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1501711782.305107-586941734', 18:17:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:17:16 INFO - '_': '/tools/buildbot/bin/python'} 18:17:16 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/HQaDFxsZT1qGSdnXmYiyWQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-042', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 18:17:16 INFO - mozversion application_buildid: 20170802231601 18:17:16 INFO - mozversion application_changeset: 792d0a2e04d7bc337c77f4777848337ac6ba6dd6 18:17:16 INFO - mozversion application_display_name: Nightly 18:17:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:17:16 INFO - mozversion application_name: Firefox 18:17:16 INFO - mozversion application_remotingname: firefox 18:17:16 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:17:16 INFO - mozversion application_vendor: Mozilla 18:17:16 INFO - mozversion application_version: 57.0a1 18:17:16 INFO - mozversion platform_buildid: 20170802231601 18:17:16 INFO - mozversion platform_changeset: 792d0a2e04d7bc337c77f4777848337ac6ba6dd6 18:17:16 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:17:16 INFO - mozversion platform_version: 57.0a1 18:17:16 INFO - using testdate: 1501723036 18:17:16 INFO - actual date: 1501723036 18:17:16 INFO - starting webserver on 'localhost:59265' 18:17:16 INFO - SUITE-START | Running 2 tests 18:17:16 INFO - TEST-START | dromaeo_css 18:17:16 INFO - Initialising browser for dromaeo_css test... 18:17:16 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:59265/getInfo.html -profile /tmp/tmpekdB1T/profile 18:17:16 INFO - TEST-INFO | started process 3347 (/builds/slave/test/build/application/firefox/firefox http://localhost:59265/getInfo.html) 18:17:25 INFO - TEST-INFO | 3347: exit 0 18:17:25 INFO - Browser initialized. 18:17:25 INFO - Running cycle 1/1 for dromaeo_css test... 18:17:25 INFO - TEST-INFO | started process 3576 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpekdB1T/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:17:26 INFO - PID 3576 | 18:17:26 INFO - PID 3576 | (/builds/slave/test/build/application/firefox/firefox:3630): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:17:26 INFO - PID 3576 | 18:17:36 INFO - PID 3576 | RSS: Main: 173789184 18:17:36 INFO - PID 3576 | 18:20:35 INFO - PID 3576 | Cycle 1(1): loaded http://localhost:59265/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59265/tests/dromaeo/cssquery-ext.html) 18:20:37 INFO - PID 3576 | RSS: Main: 173527040 18:20:37 INFO - PID 3576 | 18:23:38 INFO - PID 3576 | Cycle 1(1): loaded http://localhost:59265/tests/dromaeo/cssquery-ext.html (next: http://localhost:59265/tests/dromaeo/cssquery-jquery.html) 18:23:39 INFO - PID 3576 | RSS: Main: 173793280 18:23:39 INFO - PID 3576 | 18:26:41 INFO - PID 3576 | Cycle 1(1): loaded http://localhost:59265/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59265/tests/dromaeo/cssquery-mootools.html) 18:26:41 INFO - PID 3576 | RSS: Main: 175841280 18:26:41 INFO - PID 3576 | 18:29:43 INFO - PID 3576 | Cycle 1(1): loaded http://localhost:59265/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59265/tests/dromaeo/cssquery-prototype.html) 18:29:44 INFO - PID 3576 | RSS: Main: 174465024 18:29:44 INFO - PID 3576 | 18:32:48 INFO - PID 3576 | Cycle 1(1): loaded http://localhost:59265/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59265/tests/dromaeo/cssquery-yui.html) 18:32:48 INFO - PID 3576 | RSS: Main: 177557504 18:32:48 INFO - PID 3576 | 18:35:50 INFO - PID 3576 | Cycle 1(1): loaded http://localhost:59265/tests/dromaeo/cssquery-yui.html (next: http://localhost:59265/tests/dromaeo/cssquery-dojo.html) 18:35:50 INFO - PID 3576 | RSS: Main: 178290688 18:35:50 INFO - PID 3576 | 18:35:50 INFO - PID 3576 | __start_tp_report 18:35:50 INFO - PID 3576 | _x_x_mozilla_page_load 18:35:50 INFO - PID 3576 | _x_x_mozilla_page_load_details 18:35:50 INFO - PID 3576 | |i|pagename|runs| 18:35:50 INFO - PID 3576 | |0;dojo.html;3074.5695602615638;3110.3001824589464;3126.312211313511;3127.687231276874;3163.161762133035;6019.337872833985;6107.114468402079;6166.414190651892;6170.660613666247;6200.937990620092;5970.761169553223;6080.2703675559;6089.573729838913;6090.604110732799;6094.695265236732;5508.614396992212;5573.972130139344;5578.386377498472;5598.552115830744;5617.01702202115;5617.213590097369;5639.859003524924;5643.209950606918;5648.745806438699;5651.886962260753;5565.443455654443;5612.66324020558;5640.689762063085;5649.1243857202;5674.233978412903;5582.525485333752;5592.944070559283;5621.634593751434;5684.658920464785;5708.686022268795;5061.44324124346;5431.212474191259;5469.589780766459;5485.9725701371235;5491.176323551459;5900.527957763412;6003.939960600382;6062.030075187993;6065.5754097212775;6075.665838378887;6471.191101112361;6499.69260912374;6581.539292249545;6600.735970561172;6617.437517810992;6437.420632143083;6456.935430645681;6525.93474065258;6659.200895892475;6662.300458451841;6164.784232551884;6182.84542886427;6183.969080154616;6187.783427580058;6207.193064901534;6695.732170713117;6714.1271634688155;6792.049113124167;6813.2846051163915;6824.112865327475;6270.874582508415;6444.065610486453;6463.385978332051;6491.740330386731;6553.967230163914;6527.967360163264;6535.052417399451;6536;6539.247986481616;6543.476521878239;6432.453241474527;6486.513511486657;6486.740530378732;6511.218653761484;6518.120053792677;6570.113034740344;6614.933850661528;6615.801525954229;6622.635755033423;6627.635480048547;5256.999999999924;5334.199870019451;5353.411124776271;5353.7590808413715;5358.785648574091;4548.340490628841;4584.220682483986;4585.862424127282;4598.793054312497;4620.306953956866;5542.584306176972;5634.690092044977;5646.520045796154;5707.885842283131;5725.112607545837;6634.734610615618;6639.668016599151;6651.600903945682;6655.900161497581;6667.933320666828;5641.633293835887;5733.397993210736;5735.283089613798;5742.368339482654;5748.45389687979;5689.203511508392;5772.04761214395;5780.566457515703;5860.8241752746635;5875.471207591252;6388.041793730978;6389.648569328731;6411.839704007344;6412.39082287182;6412.551121421549;6529.7061632227615;6658.500612453989;6660.234073081464;6670.7665231716655;6676.0987266718375;6285.088662144055;6350.523710721622;6376.4261216488885;6376.776812811529;6431.421172094534;6400.711967961546;6404.295527492065;6505.642189679613;6514.6416947068365;6515.511336649865;6475.417212450902;6584.374484423972;6586.506012049212;6597.60414375139;6597.736090556324;6345.206849143857;6355.1420558224045;6371.331010243857;6375.107484952017;6436;6430.678466076771;6445.16212892321;6463.25672547663;6465.709043092978;6466.256380516113;6319.431251187231;6389.041643753289;6423.03654451836;6424.646644434601;6444.387783160592;6413.1021656969;6439.774607888701;6469.579477334147;6472.223333200046;6490.448311893542;6012.9098063529955;6130.969345153246;6135.417135372132;6142.201513803177;6147.323794382525 18:35:50 INFO - PID 3576 | |1;ext.html;834.145001373592;860.9225169734724;890.924271436928;894.2756367342449;1144.1704764046074;31563.84218078909;42810.57189428105;43299.56700432995;43459.78270108645;43886.341704874416;42336.88315584224;43064;43164;43273.13453730923;43482.565174348245;7273.781786546412;7488.063992001;7834.0990786059765;7894.289513943744;7912.604369781517;7700.730474433396;7827.960860195691;7828.491148075357;7832.843343133134;7835.686572537092;7788.493747906368;7861;7862.960685196566;7871.291583757461;7871.409644276673;7743.303102720726;7806;7812.687492500293;7847.293743563078;7851.450398472138;7642.961785191094;7713.228677132298;7721.305082542598;7726.729564465217;7741.651625676802;40964.38553421701;41296;41331.1733765326;41361.79319103415;41400;3486.7210623150086;3532.452469867175;3554.431290993429;3558.1638314995957;3563;32873.50689739656;33106.6689333106;33209.33581328384;33223;33317.50023749645;4834.371531700856;4840.975795121002;4872.585830204437;4878.487758785312;4954.9752251238515;195818;196327;196677;197395;197517;50035;50130.74934625303;50267.24599131016;50380.244296335586;50448.49551504438;52154;52715.68210794684;52723;52741;52786.73606631942;15917.68164636724;16321.755173672404;16369;16373.590660233589;16395.508134755975;12460.376981150906;12654;13338.266395348155;13622.318884055758;13628.250446225553;13626.727465450636;13845.446182152802;13871.653208669864;13933;13989.440422382992;5500.80747173847;5525.281713377316;5539.362973258047;5642.435756424325;5707.1153971134545;995.4624502768569;1002.1882275356984;1012.4583347908833;1012.9088382045651;1025.958961641526;23256.53486930252;28721.425571488453;28922;29608.851955740083;29811.850940745157;1088.472091035847;1097.8133717268083;1098.8791232964368;1109.1071687291646;1110.5058249079243;12417.254964702146;15918.840811591968;16311.42909998168;16555.751663725056;16665.583360415843;222721;224124;224861;225294;225324;59250.11124833131;59617.70191149016;59732.40267597355;59797.70101149466;59851.40148598545;1398.4126666799968;1458.3364569121024;1487.1448916872841;1489.2181604657642;1489.8882583806042;27839.721602783713;27930.720692792813;28146.85926570354;28437.573436398055;28736.856315718287;40280;40473.39289910595;40485.39271910865;40491.39262911;40507.3923891136;203392;203966;204346;204731;204845;34065.48901766525;35909.64090359063;35912.82043589765;35944;36004.819975899954;44103.77948110239;44446;45002.099958;45081;45092.32361514645;32150.678493214768;32532.186695332806;32765;32825.17937051593;33248.501272481415;31888.840555797073;32709;32867.178320542174;33069.17327066843;33385.83307083449;36024.27951441009;36371;36430.8178459106;36598.08504787402;36721.26557468782;30831;30897.227569310948;31219.687803122586;31254.687453125178;31422.685773141977;22792.886035569714;23290.06839726392;23680.644790327813;25693.871530642227;25921 18:35:50 INFO - PID 3576 | |2;jquery.html;557.1253132581843;567.8551969247841;570.2415787003287;585.9003969325214;587.8412828536295;22591.322260332196;22625;23194.42013949652;23220;23228.535429291405;25909.74090259097;26732.866335668296;26763.73236267637;26842.731572684268;26932.057377991776;6668.833279168011;6839.829004274908;6849.2465828758795;6852.725890964355;6853.040574319599;6460.450861676763;6742.966285168567;6747.966260168692;6800.353966373186;6857.828554286133;6630.966845165792;6682.899756503656;6684.3315668433015;6754.763583274586;6755.121834161552;6644.66776661165;6678.632675202862;6694.330566943291;6706.429953453962;6723.058771771955;6199.504039676815;6211.47202487789;6229.25248970122;6240.31356550779;6273.309935907047;29121.708782912323;29993.550096748568;30331.393372132654;30620;30665.846670766725;1923.1345894347598;1942.6406114868653;1953.394447721211;1954.7947465516058;1955.452473307476;19323;20009.8999505003;20075.598488030304;20103.698444523343;20109.49726256855;2264.750877403484;2295.540891821626;2312.2600767754325;2313.3869524576003;2447.9510409791706;143454;146097.26951365176;146369;146402;148847.25576372157;14378.496752613608;15276.312565934559;15472.535823925075;15485;15961.760573591406;23621;24577.13980010691;24823.25530234096;24852.390498997345;24863;10363.740906477398;10430.843537346946;10458.163346932228;10468.52891619879;10528.789424211625;9442.10300021497;9535.713928582154;9593.520323983774;9711.417314961125;9711.523561491775;9381.390209636489;9733.2213422926;9798.804023919623;9899.851502227473;9958.601655933682;2656.5218260712873;2658.5081759874483;2711.715269896672;2787.3031742064486;2793.7625301849166;942.495764765847;947.5404428852002;957.9042095790365;963.2871674960479;979.3976704326788;19961.700574491395;20003.899980500006;20218.393448196577;20619.278325258543;20696.379108626767;1026.0765311219814;1030.2376241581326;1039.298473530364;1043.1289872956015;1044.5926088825365;11234.314852771986;11277.492512836941;11398.772024559628;11457.14071444645;11460.828087578693;519263;540253;541341;570219;576942;113214;115256;115986;116806.41596791962;121586.39206803909;1218.61004478566;1220.4446976625613;1291.3478693259844;1298.8181654568368;1302.9804552931714;21699;21873.67189492127;21924.780752192273;22092.226772062902;22104.33686989393;61364.38635613587;61600;61967.07049394353;62657.373426265156;63808;73234;73646.26353736394;74288;74375;74667;15111.773323400377;15484.5354639361;15488.53534393925;15516.22418879029;15565.221738913235;24412.389690257885;24460.143894963592;24689.25932222036;24690.25929222126;24745.886435110104;18679.71980420322;19792.307269245506;19794.109265082818;19827.80172198317;20044.098015589625;14985.2507374633;17655.823441765933;17738.290468381638;17788.91105544464;18220.36228731988;19926.402207933785;19979.500512487302;20147;20214.696779548613;20237.898810505852;14567.854321457075;15047.322870470636;15344;15375.30811113525;15418.614534636723;18218.18018189158;18574;18701.34545290908;18724.25102995865;18955 18:35:50 INFO - PID 3576 | |3;mootools.html;1316.289203829932;1389.9235542045183;1394.4491925689354;1394.9372278247472;1396.3367400484776;13423.261720605375;13982.790258146111;14105.435782568698;14124.011319207646;14131.081479703813;13107.606771796862;13374.59876203713;13387.39756710948;13420.932895335509;13432.3955422006;2651.0191229245206;2672.358633927853;2696.2046196372116;2698.9460210795773;2700.0144947094363;2669.9332516687045;2776.972230277702;2785.1644506648026;2795.664520257572;2797.608334833125;2658.2689760315875;2665.933351666214;2689.3814422682794;2707.905223317184;2719.4697034078363;2640.075973409301;2679.678438587372;2695.8517281549507;2735.302497863042;2737.2883050407036;2619.9607005894927;2626.9737302626922;2640.2211347652296;2662.853543055131;2674.3314171457137;13488.595342139653;13727.245001524912;13773.449062037507;13774;13836.446542138303;7610.467267291292;7904.683812647487;7919.049714034295;7927.326177274881;7937.801554961115;4959.677620954626;5143.691378517264;5165.380154381461;5210.374755029421;5237.554807841338;1743.947681569555;1789.2216885654782;1822.3348477805505;1844.529644940545;1856.4337876947525;20820.271290504912;21123.999999999847;21390.786092139035;21425.464363390885;21447.034883430282;6711.261761206263;6773.695183716744;6882.242953275136;6965.407940325028;6969.6515174241085;17054.14729263532;17069.7439538407;17099.829001710073;17113.48659540216;17118.48644540666;7332.96333518329;7642.426817988673;7655.349295309849;7663.386929045663;7684.3084122429245;6596.571222870498;6892.896606550906;6987.475939304471;7160.283971602798;7174.067371241809;5317.9202311965355;5434.619576629677;5514.6691198528215;5543.667379957215;5573.080441727151;1687.64559442516;1707.5304291319987;1711.6405554833625;1751.2119546204003;1772.4239622122861;863.8315528471887;896.188949001155;908.3051465628799;909.8180363927241;913.1690161952591;7245.710171593183;8707.346948978851;8888.511131887679;8946.239569636524;9047.185753282105;979.4612962870393;995.0546980368534;1018.9235807314333;1020.05644778581;1028.922830787679;4782.760861956888;4821.807127714852;4987.825426110068;5071.290019397287;5149.304843846033;18350.35773747939;18773.90613046926;18830.623387532174;19213.903930480257;19214.327498537485;18696.626067478574;18745.968971706687;18842.15210315539;19082.809171908382;19125.808741912682;9988.151007164328;10152.593896244067;10338.483075846329;10379.896201038044;10404.843927341097;8057.153998830273;8162.387820913574;8166.346692264723;8173.305269052197;8386.706465273686;16586.83413165853;16752.32990680408;17223.91388043052;17330.220140093475;17489.475315740547;16666.58333541671;17315.56711082233;17426.128693565017;17475.12624368802;17708.734368984216;11270.94364528172;11622.128340374675;11623.186376953532;11630.476628551905;11665;12703.555375561811;12897.613071607866;12938.741225175632;13038;13038.282894440517;10398;10634.202434817265;10675.252732308663;10692.465376730977;11019.283746556448;10241.43672098012;10269.229807764597;10317.226208034577;10321.329113607591;10473.162147028372;9213.953930230575;9386.389884657749;9388.248940084914;9392.765180870532;9403.858942115738;7168.354848063699;7185.82035449097;7244;7261.237570055279;7287.161976372785;7325.4505912057875;7359.337659610446;7380.1512826023545;7481.7755467336065;7506.887396688945 18:35:50 INFO - PID 3576 | |4;prototype.html;501.76667849449996;502.4949925325049;528.4847273907938;532.6165161084019;537.373959337372;6005.579609427342;6149.310413751747;6157.1687822143995;6166.722497487613;6202.4417802397775;5743.62666426682;5955.877944967325;5991.880162396749;6006.129111278873;6025.698715064251;4928.433230178522;4934.40787105546;4937.604991600672;4945.703257804544;4951.613548206508;4944.258361245824;4945.35710357653;4959.528844759742;4960.7767650455635;4976.576990955773;4983.127952608279;5108.8211912583065;5116.130257856174;5129.2306154077;5144.948550514484;5125.513076257731;5141.845744627649;5162;5184.274201611777;5186;4766.30888521166;4772.80908763649;4774.069056533977;4777.498362671939;4783.617310615177;6091.238595175603;6104.267487901436;6128.44843964041;6145.293291271516;6157.815265542041;5978.880422391528;5994.820155395345;5998.40015998401;6003.969980150115;6013.849653758691;6555.47556195508;6595.835104122388;6607.768728094494;6609.834754131137;6652.334766523309;5245.4754524547625;5251.264822924793;5282.550983166436;5294.497022782868;5301.60237982149;6898.033845446456;7001.719931202746;7005.264447233069;7017.789466316019;7039.155301363818;5417.404085550586;5470.617056806065;5471.480209380103;5483.7806487740845;5523.171524271392;5465.9726701366235;5534.944650553523;5544.362398324163;5563.13771365439;5569.721513924287;5940.990031694622;5955.940440595538;5974.731137098754;5984.940150598525;5989.610675306091;6511.73953041873;6612.933870661328;6619.139511863427;6630.635315057718;6643;1832.6884429646725;1892.9526761831064;1905.6665083610312;1938.6219687160867;1943.504406376365;1422.6230049037013;1426.486464872645;1448.6957738874744;1460.678650696845;1463.370750577262;5653.208550802891;5676.659400435987;5677.460641239076;5698.202251684767;5742.7702891883855;6610.504212184105;6628.767993120314;6687.352290158294;6688.933110668831;6720.8319792005595;5515.448455154533;5652.3217213934595;5720.570957178228;5738.340090889517;5787.739551720183;3504.8247587621026;3548.27260411615;3554.9466757998653;3563.1448452371246;3567.6075631680496;6436.806895793134;6443.903341449882;6444.677766111676;6496.935030649633;6544.247411547638;6814.420774234147;6830.702166588431;6850.520463567503;6853.17761868579;6853.990415606826;6459.677016149174;6476.125723027425;6486.610803351814;6512.381323774234;6552.82049231143;6586.736530538917;6626.503012274003;6633.369829866155;6665.533412661067;6719.630420326929;6597.472202223715;6607.669616519059;6612.5040621952585;6667.466602671871;6696.5647232929705;6401.903971440525;6427.164468619049;6441.67791610427;6460.580062295936;6489.513286503437;4920.318426457661;4933.087378834927;4980.4272508660515;5004.47453017428;5046.873828154326;6756.5811179650855;6782.152230971129;6791.3548212919695;6800.183977922681;6822.556533825285;6449.4840412765925;6474.676266186766;6559.311272316338;6570.455942853521;6592.505562082959;6430.582012169194;6444.4533312005715;6445.1943507061615;6460.224773027267;6460.3862633049785;6108.450239478469;6400.359964003563;6429.421352078149;6465.741370345134;6486.740530378732;6367.87264254703;6382.489630703002;6508.511861610493;6546.810041573492;6606.504512161511;6261.7182226799;6355.713992870239;6362.172917520693;6377.075324078076;6411.275208036659 18:35:50 INFO - PID 3576 | |5;yui.html;907.3376435202298;910.7950711090004;934.5467448287575;938.5448057692018;952.1525842000616;18430.354937577187;19340.323088691915;19343.70984435231;19354.806451935478;19811.900940495314;20974.265900693477;21490.462738431546;21558.245461408853;21666.3500094997;21746.565068698616;1007.0433088565874;1041;1042.405828677654;1042.4370839746528;1042.7653777899989;955.0211033690454;1045.9058684718375;1051.621416290135;1051.8001579699871;1053.1837825685118;1104.9944750276238;1108.146727020194;1115.1970581181536;1115.8214685650298;1116.983245251322;1104.9668509944674;1106.5241945963232;1108.0692218536387;1110.2783190925882;1110.8222684370462;809.76516810125;815.013833261754;816.7672213419147;817.2685446525327;818.1327792539868;18536.351227706964;18624.348147814762;18632.161552730024;19124.5218869528;20514.589708205902;19844.503887402785;20015.299464518674;20203.19187232509;20268.493287667778;20278.69581956272;16538.6692266154;16630.66738665232;16635.334586616522;16676.499705008868;16792.412265570645;3536.5579302587175;3568.232829941575;3570.625084366156;3570.80360580168;3571.5714114306447;124576.75423245833;125314;125813;127387;127594.3620281902;15998.200089995686;16125.516234512983;16360.691144708395;16558.751618725728;16579.253933573018;30343.848280758455;30352.54471182934;30926.38147237088;31019.53470697941;31061.68938310633;8163.142869998684;8297.25324720778;8299.709510167237;8304.8339033219;8305.37709671777;4050.2911990401562;4181.1010632714115;4184.121334519724;4195.475565554306;4196.874093777192;5082.593392528589;5174.301469301671;5225.790968361299;5227.111391063529;5240.68555886648;964.8842138943372;978.3836183204536;992.1814503035025;994.4331730913455;995.6963126246565;670.085333519742;689.7965100295424;692.1071817355555;695.7877847256635;707.0066556488142;11038.834417483744;11488.253263537843;11528.76942461146;11546.510932610492;11629.418529073513;553.9169124631256;563.650536667269;567.6282035266906;567.6650776042155;576.2940398012402;5295.285136506599;5395.136778115482;5439.918401223985;5468.781248750085;5571.4428557143965;99359;100121;100402;100461;102516;87704.56147719348;89383.55308223417;89612.5519372412;91793.54103229441;92078.53960730153;77018.61490692623;80350;81791;82012.58993704994;82170.17829821745;11786.233894796811;12322.383880806103;12326.506939722669;12740.4266807992;12858.485660573473;30686.386272274874;31683;32327.676723232467;35784.284314313045;36208.45687314745;64385.67807160934;66358.33641663521;67058;67582.66208668925;67618.3238167612;16985.49043528696;18829.811701883355;19088.904555477133;19274.132664029872;19301.80698193;17852;17998.5500362492;18161;18199.45401637953;18420.63158736791;17938;18173.27306907762;18286.908565457088;18431.354902578347;18574.164162612986;17925.910370448066;18055.819441805415;18092.276308947494;18102.27590896402;18225.45323640293;16216.432424865074;16315.59211019734;16415.91792041032;16623;16671;12307.138500304893;12687.492500299884;12825;12897.935510322388;12931.482740690268;13330.733385332045;13536.59390218295;13579.25314107694;13618.77431031212;13779.931100344435 18:35:50 INFO - PID 3576 | __end_tp_report 18:35:50 INFO - PID 3576 | __start_cc_report 18:35:50 INFO - PID 3576 | _x_x_mozilla_cycle_collect,403 18:35:50 INFO - PID 3576 | __end_cc_report 18:35:50 INFO - PID 3576 | __startTimestamp1501724150915__endTimestamp 18:35:50 INFO - PID 3576 | 18:35:50 INFO - PID 3576 | ------- Summary: start ------- 18:35:50 INFO - PID 3576 | Number of tests: 6 18:35:50 INFO - PID 3576 | 18:35:50 INFO - PID 3576 | [#0] dojo.html Cycles:165 Average:6040.13 Median:6319.43 stddev:714.03 (11.3%) stddev-sans-first:677.26 18:35:50 INFO - PID 3576 | Values: 3074.6 3110.3 3126.3 3127.7 3163.2 6019.3 6107.1 6166.4 6170.7 6200.9 5970.8 6080.3 6089.6 6090.6 6094.7 5508.6 5574.0 5578.4 5598.6 5617.0 5617.2 5639.9 5643.2 5648.7 5651.9 5565.4 5612.7 5640.7 5649.1 5674.2 5582.5 5592.9 5621.6 5684.7 5708.7 5061.4 5431.2 5469.6 5486.0 5491.2 5900.5 6003.9 6062.0 6065.6 6075.7 6471.2 6499.7 6581.5 6600.7 6617.4 6437.4 6456.9 6525.9 6659.2 6662.3 6164.8 6182.8 6184.0 6187.8 6207.2 6695.7 6714.1 6792.0 6813.3 6824.1 6270.9 6444.1 6463.4 6491.7 6554.0 6528.0 6535.1 6536.0 6539.2 6543.5 6432.5 6486.5 6486.7 6511.2 6518.1 6570.1 6614.9 6615.8 6622.6 6627.6 5257.0 5334.2 5353.4 5353.8 5358.8 4548.3 4584.2 4585.9 4598.8 4620.3 5542.6 5634.7 5646.5 5707.9 5725.1 6634.7 6639.7 6651.6 6655.9 6667.9 5641.6 5733.4 5735.3 5742.4 5748.5 5689.2 5772.0 5780.6 5860.8 5875.5 6388.0 6389.6 6411.8 6412.4 6412.6 6529.7 6658.5 6660.2 6670.8 6676.1 6285.1 6350.5 6376.4 6376.8 6431.4 6400.7 6404.3 6505.6 6514.6 6515.5 6475.4 6584.4 6586.5 6597.6 6597.7 6345.2 6355.1 6371.3 6375.1 6436.0 6430.7 6445.2 6463.3 6465.7 6466.3 6319.4 6389.0 6423.0 6424.6 6444.4 6413.1 6439.8 6469.6 6472.2 6490.4 6012.9 6131.0 6135.4 6142.2 6147.3 18:35:50 INFO - PID 3576 | 18:35:50 INFO - PID 3576 | [#1] ext.html Cycles:180 Average:38806.64 Median:3560.58 stddev:54050.94 (1518.0%) stddev-sans-first:54127.37 18:35:50 INFO - PID 3576 | Values: 834.1 860.9 890.9 894.3 1144.2 31563.8 42810.6 43299.6 43459.8 43886.3 42336.9 43064.0 43164.0 43273.1 43482.6 7273.8 7488.1 7834.1 7894.3 7912.6 7700.7 7828.0 7828.5 7832.8 7835.7 7788.5 7861.0 7863.0 7871.3 7871.4 7743.3 7806.0 7812.7 7847.3 7851.5 7643.0 7713.2 7721.3 7726.7 7741.7 40964.4 41296.0 41331.2 41361.8 41400.0 3486.7 3532.5 3554.4 3558.2 3563.0 32873.5 33106.7 33209.3 33223.0 33317.5 4834.4 4841.0 4872.6 4878.5 4955.0 195818.0 196327.0 196677.0 197395.0 197517.0 50035.0 50130.7 50267.2 50380.2 50448.5 52154.0 52715.7 52723.0 52741.0 52786.7 15917.7 16321.8 16369.0 16373.6 16395.5 12460.4 12654.0 13338.3 13622.3 13628.3 13626.7 13845.4 13871.7 13933.0 13989.4 5500.8 5525.3 5539.4 5642.4 5707.1 995.5 1002.2 1012.5 1012.9 1026.0 23256.5 28721.4 28922.0 29608.9 29811.9 1088.5 1097.8 1098.9 1109.1 1110.5 12417.3 15918.8 16311.4 16555.8 16665.6 222721.0 224124.0 224861.0 225294.0 225324.0 59250.1 59617.7 59732.4 59797.7 59851.4 1398.4 1458.3 1487.1 1489.2 1489.9 27839.7 27930.7 28146.9 28437.6 28736.9 40280.0 40473.4 40485.4 40491.4 40507.4 203392.0 203966.0 204346.0 204731.0 204845.0 34065.5 35909.6 35912.8 35944.0 36004.8 44103.8 44446.0 45002.1 45081.0 45092.3 32150.7 32532.2 32765.0 32825.2 33248.5 31888.8 32709.0 32867.2 33069.2 33385.8 36024.3 36371.0 36430.8 36598.1 36721.3 30831.0 30897.2 31219.7 31254.7 31422.7 22792.9 23290.1 23680.6 25693.9 25921.0 18:35:50 INFO - PID 3576 | 18:35:50 INFO - PID 3576 | [#2] jquery.html Cycles:180 Average:37402.43 Median:12753.90 stddev:92142.15 (722.5%) stddev-sans-first:92359.10 18:35:50 INFO - PID 3576 | Values: 557.1 567.9 570.2 585.9 587.8 22591.3 22625.0 23194.4 23220.0 23228.5 25909.7 26732.9 26763.7 26842.7 26932.1 6668.8 6839.8 6849.2 6852.7 6853.0 6460.5 6743.0 6748.0 6800.4 6857.8 6631.0 6682.9 6684.3 6754.8 6755.1 6644.7 6678.6 6694.3 6706.4 6723.1 6199.5 6211.5 6229.3 6240.3 6273.3 29121.7 29993.6 30331.4 30620.0 30665.8 1923.1 1942.6 1953.4 1954.8 1955.5 19323.0 20009.9 20075.6 20103.7 20109.5 2264.8 2295.5 2312.3 2313.4 2448.0 143454.0 146097.3 146369.0 146402.0 148847.3 14378.5 15276.3 15472.5 15485.0 15961.8 23621.0 24577.1 24823.3 24852.4 24863.0 10363.7 10430.8 10458.2 10468.5 10528.8 9442.1 9535.7 9593.5 9711.4 9711.5 9381.4 9733.2 9798.8 9899.9 9958.6 2656.5 2658.5 2711.7 2787.3 2793.8 942.5 947.5 957.9 963.3 979.4 19961.7 20003.9 20218.4 20619.3 20696.4 1026.1 1030.2 1039.3 1043.1 1044.6 11234.3 11277.5 11398.8 11457.1 11460.8 519263.0 540253.0 541341.0 570219.0 576942.0 113214.0 115256.0 115986.0 116806.4 121586.4 1218.6 1220.4 1291.3 1298.8 1303.0 21699.0 21873.7 21924.8 22092.2 22104.3 61364.4 61600.0 61967.1 62657.4 63808.0 73234.0 73646.3 74288.0 74375.0 74667.0 15111.8 15484.5 15488.5 15516.2 15565.2 24412.4 24460.1 24689.3 24690.3 24745.9 18679.7 19792.3 19794.1 19827.8 20044.1 14985.3 17655.8 17738.3 17788.9 18220.4 19926.4 19979.5 20147.0 20214.7 20237.9 14567.9 15047.3 15344.0 15375.3 15418.6 18218.2 18574.0 18701.3 18724.3 18955.0 18:35:50 INFO - PID 3576 | 18:35:50 INFO - PID 3576 | [#3] mootools.html Cycles:180 Average:8699.55 Median:2645.62 stddev:5765.93 (217.9%) stddev-sans-first:5755.42 18:35:50 INFO - PID 3576 | Values: 1316.3 1389.9 1394.4 1394.9 1396.3 13423.3 13982.8 14105.4 14124.0 14131.1 13107.6 13374.6 13387.4 13420.9 13432.4 2651.0 2672.4 2696.2 2698.9 2700.0 2669.9 2777.0 2785.2 2795.7 2797.6 2658.3 2665.9 2689.4 2707.9 2719.5 2640.1 2679.7 2695.9 2735.3 2737.3 2620.0 2627.0 2640.2 2662.9 2674.3 13488.6 13727.2 13773.4 13774.0 13836.4 7610.5 7904.7 7919.0 7927.3 7937.8 4959.7 5143.7 5165.4 5210.4 5237.6 1743.9 1789.2 1822.3 1844.5 1856.4 20820.3 21124.0 21390.8 21425.5 21447.0 6711.3 6773.7 6882.2 6965.4 6969.7 17054.1 17069.7 17099.8 17113.5 17118.5 7333.0 7642.4 7655.3 7663.4 7684.3 6596.6 6892.9 6987.5 7160.3 7174.1 5317.9 5434.6 5514.7 5543.7 5573.1 1687.6 1707.5 1711.6 1751.2 1772.4 863.8 896.2 908.3 909.8 913.2 7245.7 8707.3 8888.5 8946.2 9047.2 979.5 995.1 1018.9 1020.1 1028.9 4782.8 4821.8 4987.8 5071.3 5149.3 18350.4 18773.9 18830.6 19213.9 19214.3 18696.6 18746.0 18842.2 19082.8 19125.8 9988.2 10152.6 10338.5 10379.9 10404.8 8057.2 8162.4 8166.3 8173.3 8386.7 16586.8 16752.3 17223.9 17330.2 17489.5 16666.6 17315.6 17426.1 17475.1 17708.7 11270.9 11622.1 11623.2 11630.5 11665.0 12703.6 12897.6 12938.7 13038.0 13038.3 10398.0 10634.2 10675.3 10692.5 11019.3 10241.4 10269.2 10317.2 10321.3 10473.2 9214.0 9386.4 9388.2 9392.8 9403.9 7168.4 7185.8 7244.0 7261.2 7287.2 7325.5 7359.3 7380.2 7481.8 7506.9 18:35:50 INFO - PID 3576 | 18:35:50 INFO - PID 3576 | [#4] prototype.html Cycles:180 Average:5570.51 Median:6058.47 stddev:1499.77 (24.8%) stddev-sans-first:1454.92 18:35:50 INFO - PID 3576 | Values: 501.8 502.5 528.5 532.6 537.4 6005.6 6149.3 6157.2 6166.7 6202.4 5743.6 5955.9 5991.9 6006.1 6025.7 4928.4 4934.4 4937.6 4945.7 4951.6 4944.3 4945.4 4959.5 4960.8 4976.6 4983.1 5108.8 5116.1 5129.2 5144.9 5125.5 5141.8 5162.0 5184.3 5186.0 4766.3 4772.8 4774.1 4777.5 4783.6 6091.2 6104.3 6128.4 6145.3 6157.8 5978.9 5994.8 5998.4 6004.0 6013.8 6555.5 6595.8 6607.8 6609.8 6652.3 5245.5 5251.3 5282.6 5294.5 5301.6 6898.0 7001.7 7005.3 7017.8 7039.2 5417.4 5470.6 5471.5 5483.8 5523.2 5466.0 5534.9 5544.4 5563.1 5569.7 5941.0 5955.9 5974.7 5984.9 5989.6 6511.7 6612.9 6619.1 6630.6 6643.0 1832.7 1893.0 1905.7 1938.6 1943.5 1422.6 1426.5 1448.7 1460.7 1463.4 5653.2 5676.7 5677.5 5698.2 5742.8 6610.5 6628.8 6687.4 6688.9 6720.8 5515.4 5652.3 5720.6 5738.3 5787.7 3504.8 3548.3 3554.9 3563.1 3567.6 6436.8 6443.9 6444.7 6496.9 6544.2 6814.4 6830.7 6850.5 6853.2 6854.0 6459.7 6476.1 6486.6 6512.4 6552.8 6586.7 6626.5 6633.4 6665.5 6719.6 6597.5 6607.7 6612.5 6667.5 6696.6 6401.9 6427.2 6441.7 6460.6 6489.5 4920.3 4933.1 4980.4 5004.5 5046.9 6756.6 6782.2 6791.4 6800.2 6822.6 6449.5 6474.7 6559.3 6570.5 6592.5 6430.6 6444.5 6445.2 6460.2 6460.4 6108.5 6400.4 6429.4 6465.7 6486.7 6367.9 6382.5 6508.5 6546.8 6606.5 6261.7 6355.7 6362.2 6377.1 6411.3 18:35:50 INFO - PID 3576 | 18:35:50 INFO - PID 3576 | [#5] yui.html Cycles:180 Average:22660.62 Median:19583.35 stddev:30464.72 (155.6%) stddev-sans-first:30506.39 18:35:50 INFO - PID 3576 | Values: 907.3 910.8 934.5 938.5 952.2 18430.4 19340.3 19343.7 19354.8 19811.9 20974.3 21490.5 21558.2 21666.4 21746.6 1007.0 1041.0 1042.4 1042.4 1042.8 955.0 1045.9 1051.6 1051.8 1053.2 1105.0 1108.1 1115.2 1115.8 1117.0 1105.0 1106.5 1108.1 1110.3 1110.8 809.8 815.0 816.8 817.3 818.1 18536.4 18624.3 18632.2 19124.5 20514.6 19844.5 20015.3 20203.2 20268.5 20278.7 16538.7 16630.7 16635.3 16676.5 16792.4 3536.6 3568.2 3570.6 3570.8 3571.6 124576.8 125314.0 125813.0 127387.0 127594.4 15998.2 16125.5 16360.7 16558.8 16579.3 30343.8 30352.5 30926.4 31019.5 31061.7 8163.1 8297.3 8299.7 8304.8 8305.4 4050.3 4181.1 4184.1 4195.5 4196.9 5082.6 5174.3 5225.8 5227.1 5240.7 964.9 978.4 992.2 994.4 995.7 670.1 689.8 692.1 695.8 707.0 11038.8 11488.3 11528.8 11546.5 11629.4 553.9 563.7 567.6 567.7 576.3 5295.3 5395.1 5439.9 5468.8 5571.4 99359.0 100121.0 100402.0 100461.0 102516.0 87704.6 89383.6 89612.6 91793.5 92078.5 77018.6 80350.0 81791.0 82012.6 82170.2 11786.2 12322.4 12326.5 12740.4 12858.5 30686.4 31683.0 32327.7 35784.3 36208.5 64385.7 66358.3 67058.0 67582.7 67618.3 16985.5 18829.8 19088.9 19274.1 19301.8 17852.0 17998.6 18161.0 18199.5 18420.6 17938.0 18173.3 18286.9 18431.4 18574.2 17925.9 18055.8 18092.3 18102.3 18225.5 16216.4 16315.6 16415.9 16623.0 16671.0 12307.1 12687.5 12825.0 12897.9 12931.5 13330.7 13536.6 13579.3 13618.8 13779.9 18:35:50 INFO - PID 3576 | -------- Summary: end -------- 18:35:50 INFO - PID 3576 | 18:35:51 INFO - TEST-INFO | 3576: exit 0 18:35:51 INFO - TEST-OK | dromaeo_css | took 1115094ms 18:35:51 INFO - TEST-START | kraken 18:35:51 INFO - Initialising browser for kraken test... 18:35:51 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:59265/getInfo.html -profile /tmp/tmpeJDCPC/profile 18:35:51 INFO - TEST-INFO | started process 3948 (/builds/slave/test/build/application/firefox/firefox http://localhost:59265/getInfo.html) 18:35:57 INFO - TEST-INFO | 3948: exit 0 18:35:57 INFO - Browser initialized. 18:35:57 INFO - Running cycle 1/1 for kraken test... 18:35:57 INFO - TEST-INFO | started process 4175 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeJDCPC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:35:58 INFO - PID 4175 | 18:35:58 INFO - PID 4175 | (/builds/slave/test/build/application/firefox/firefox:4229): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:35:58 INFO - PID 4175 | 18:36:09 INFO - PID 4175 | RSS: Main: 174379008 18:36:09 INFO - PID 4175 | 18:36:11 INFO - PID 4175 | Cycle 1(1): loaded http://localhost:59265/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59265/tests/kraken/driver.html?testName=audio-beat-detection) 18:36:12 INFO - PID 4175 | RSS: Main: 179707904 18:36:12 INFO - PID 4175 | 18:36:15 INFO - PID 4175 | Cycle 1(1): loaded http://localhost:59265/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59265/tests/kraken/driver.html?testName=audio-dft) 18:36:16 INFO - PID 4175 | RSS: Main: 179785728 18:36:16 INFO - PID 4175 | 18:36:19 INFO - PID 4175 | Cycle 1(1): loaded http://localhost:59265/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59265/tests/kraken/driver.html?testName=audio-fft) 18:36:19 INFO - PID 4175 | RSS: Main: 174743552 18:36:19 INFO - PID 4175 | 18:36:22 INFO - PID 4175 | Cycle 1(1): loaded http://localhost:59265/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59265/tests/kraken/driver.html?testName=audio-oscillator) 18:36:22 INFO - PID 4175 | RSS: Main: 175419392 18:36:22 INFO - PID 4175 | 18:36:24 INFO - PID 4175 | Cycle 1(1): loaded http://localhost:59265/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59265/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:36:25 INFO - PID 4175 | RSS: Main: 176230400 18:36:25 INFO - PID 4175 | 18:36:28 INFO - PID 4175 | Cycle 1(1): loaded http://localhost:59265/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59265/tests/kraken/driver.html?testName=imaging-darkroom) 18:36:29 INFO - PID 4175 | RSS: Main: 178343936 18:36:29 INFO - PID 4175 | 18:36:32 INFO - PID 4175 | Cycle 1(1): loaded http://localhost:59265/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59265/tests/kraken/driver.html?testName=imaging-desaturate) 18:36:33 INFO - PID 4175 | RSS: Main: 177614848 18:36:33 INFO - PID 4175 | 18:36:36 INFO - PID 4175 | Cycle 1(1): loaded http://localhost:59265/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59265/tests/kraken/driver.html?testName=json-parse-financial) 18:36:37 INFO - PID 4175 | RSS: Main: 177999872 18:36:37 INFO - PID 4175 | 18:36:38 INFO - PID 4175 | Cycle 1(1): loaded http://localhost:59265/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59265/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:36:39 INFO - PID 4175 | RSS: Main: 178417664 18:36:39 INFO - PID 4175 | 18:36:41 INFO - PID 4175 | Cycle 1(1): loaded http://localhost:59265/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59265/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:36:41 INFO - PID 4175 | RSS: Main: 175620096 18:36:41 INFO - PID 4175 | 18:36:44 INFO - PID 4175 | Cycle 1(1): loaded http://localhost:59265/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59265/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:36:44 INFO - PID 4175 | RSS: Main: 175546368 18:36:44 INFO - PID 4175 | 18:36:47 INFO - PID 4175 | Cycle 1(1): loaded http://localhost:59265/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59265/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:36:48 INFO - PID 4175 | RSS: Main: 175919104 18:36:48 INFO - PID 4175 | 18:36:51 INFO - PID 4175 | Cycle 1(1): loaded http://localhost:59265/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59265/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:36:52 INFO - PID 4175 | RSS: Main: 175783936 18:36:52 INFO - PID 4175 | 18:36:53 INFO - PID 4175 | Cycle 1(1): loaded http://localhost:59265/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59265/tests/kraken/driver.html?testName=ai-astar) 18:36:54 INFO - PID 4175 | RSS: Main: 177774592 18:36:54 INFO - PID 4175 | 18:36:54 INFO - PID 4175 | __start_tp_report 18:36:54 INFO - PID 4175 | _x_x_mozilla_page_load 18:36:54 INFO - PID 4175 | _x_x_mozilla_page_load_details 18:36:54 INFO - PID 4175 | |i|pagename|runs| 18:36:54 INFO - PID 4175 | |0;ai-astar;98;107;104;109;112;109;109;101;108;108 18:36:54 INFO - PID 4175 | |1;audio-beat-detection;151;123;146;164;125;153;152;127;164;126 18:36:54 INFO - PID 4175 | |2;audio-dft;163;165;162;167;163;163;165;175;163;163 18:36:54 INFO - PID 4175 | |3;audio-fft;75;77;79;181;81;81;77;76;159;76 18:36:54 INFO - PID 4175 | |4;audio-oscillator;79;84;144;79;80;78;141;80;78;132 18:36:54 INFO - PID 4175 | |5;imaging-gaussian-blur;114;114;114;114;115;117;118;118;117;119 18:36:54 INFO - PID 4175 | |6;imaging-darkroom;151;153;151;155;153;151;152;151;151;149 18:36:54 INFO - PID 4175 | |7;imaging-desaturate;94;94;93;95;93;93;94;93;94;95 18:36:54 INFO - PID 4175 | |8;json-parse-financial;56;61;58;62;57;61;59;56;58;58 18:36:54 INFO - PID 4175 | |9;json-stringify-tinderbox;55;54;54;54;55;54;59;60;56;55 18:36:54 INFO - PID 4175 | |10;stanford-crypto-aes;79;84;79;79;84;79;85;77;75;81 18:36:54 INFO - PID 4175 | |11;stanford-crypto-ccm;133;141;125;128;134;137;135;133;126;164 18:36:54 INFO - PID 4175 | |12;stanford-crypto-pbkdf2;174;174;178;172;179;176;179;184;175;196 18:36:54 INFO - PID 4175 | |13;stanford-crypto-sha256-iterative;66;68;64;67;74;66;64;80;65;69 18:36:54 INFO - PID 4175 | __end_tp_report 18:36:54 INFO - PID 4175 | __start_cc_report 18:36:54 INFO - PID 4175 | _x_x_mozilla_cycle_collect,986 18:36:54 INFO - PID 4175 | __end_cc_report 18:36:54 INFO - PID 4175 | __startTimestamp1501724214104__endTimestamp 18:36:54 INFO - PID 4175 | 18:36:54 INFO - PID 4175 | ------- Summary: start ------- 18:36:54 INFO - PID 4175 | Number of tests: 14 18:36:54 INFO - PID 4175 | 18:36:54 INFO - PID 4175 | [#0] ai-astar Cycles:10 Average:106.50 Median:109.00 stddev:4.25 (3.9%) stddev-sans-first:3.21 18:36:54 INFO - PID 4175 | Values: 98.0 107.0 104.0 109.0 112.0 109.0 109.0 101.0 108.0 108.0 18:36:54 INFO - PID 4175 | 18:36:54 INFO - PID 4175 | [#1] audio-beat-detection Cycles:10 Average:143.10 Median:151.50 stddev:16.35 (10.8%) stddev-sans-first:17.09 18:36:54 INFO - PID 4175 | Values: 151.0 123.0 146.0 164.0 125.0 153.0 152.0 127.0 164.0 126.0 18:36:54 INFO - PID 4175 | 18:36:54 INFO - PID 4175 | [#2] audio-dft Cycles:10 Average:164.90 Median:164.00 stddev:3.84 (2.3%) stddev-sans-first:4.01 18:36:54 INFO - PID 4175 | Values: 163.0 165.0 162.0 167.0 163.0 163.0 165.0 175.0 163.0 163.0 18:36:54 INFO - PID 4175 | 18:36:54 INFO - PID 4175 | [#3] audio-fft Cycles:10 Average:96.20 Median:77.00 stddev:39.29 (51.0%) stddev-sans-first:40.92 18:36:54 INFO - PID 4175 | Values: 75.0 77.0 79.0 181.0 81.0 81.0 77.0 76.0 159.0 76.0 18:36:54 INFO - PID 4175 | 18:36:54 INFO - PID 4175 | [#4] audio-oscillator Cycles:10 Average:97.50 Median:79.00 stddev:28.84 (36.5%) stddev-sans-first:29.80 18:36:54 INFO - PID 4175 | Values: 79.0 84.0 144.0 79.0 80.0 78.0 141.0 80.0 78.0 132.0 18:36:54 INFO - PID 4175 | 18:36:54 INFO - PID 4175 | [#5] imaging-gaussian-blur Cycles:10 Average:116.00 Median:117.00 stddev:2.00 (1.7%) stddev-sans-first:1.99 18:36:54 INFO - PID 4175 | Values: 114.0 114.0 114.0 114.0 115.0 117.0 118.0 118.0 117.0 119.0 18:36:54 INFO - PID 4175 | 18:36:54 INFO - PID 4175 | [#6] imaging-darkroom Cycles:10 Average:151.70 Median:151.50 stddev:1.64 (1.1%) stddev-sans-first:1.72 18:36:54 INFO - PID 4175 | Values: 151.0 153.0 151.0 155.0 153.0 151.0 152.0 151.0 151.0 149.0 18:36:54 INFO - PID 4175 | 18:36:54 INFO - PID 4175 | [#7] imaging-desaturate Cycles:10 Average:93.80 Median:94.00 stddev:0.79 (0.8%) stddev-sans-first:0.83 18:36:54 INFO - PID 4175 | Values: 94.0 94.0 93.0 95.0 93.0 93.0 94.0 93.0 94.0 95.0 18:36:54 INFO - PID 4175 | 18:36:54 INFO - PID 4175 | [#8] json-parse-financial Cycles:10 Average:58.60 Median:58.50 stddev:2.12 (3.6%) stddev-sans-first:2.03 18:36:54 INFO - PID 4175 | Values: 56.0 61.0 58.0 62.0 57.0 61.0 59.0 56.0 58.0 58.0 18:36:54 INFO - PID 4175 | 18:36:54 INFO - PID 4175 | [#9] json-stringify-tinderbox Cycles:10 Average:55.60 Median:55.00 stddev:2.17 (3.9%) stddev-sans-first:2.29 18:36:54 INFO - PID 4175 | Values: 55.0 54.0 54.0 54.0 55.0 54.0 59.0 60.0 56.0 55.0 18:36:54 INFO - PID 4175 | 18:36:54 INFO - PID 4175 | [#10] stanford-crypto-aes Cycles:10 Average:80.20 Median:80.00 stddev:3.26 (4.1%) stddev-sans-first:3.43 18:36:54 INFO - PID 4175 | Values: 79.0 84.0 79.0 79.0 84.0 79.0 85.0 77.0 75.0 81.0 18:36:54 INFO - PID 4175 | 18:36:54 INFO - PID 4175 | [#11] stanford-crypto-ccm Cycles:10 Average:135.60 Median:134.50 stddev:11.14 (8.3%) stddev-sans-first:11.77 18:36:54 INFO - PID 4175 | Values: 133.0 141.0 125.0 128.0 134.0 137.0 135.0 133.0 126.0 164.0 18:36:54 INFO - PID 4175 | 18:36:54 INFO - PID 4175 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.70 Median:178.50 stddev:6.98 (3.9%) stddev-sans-first:7.19 18:36:54 INFO - PID 4175 | Values: 174.0 174.0 178.0 172.0 179.0 176.0 179.0 184.0 175.0 196.0 18:36:54 INFO - PID 4175 | 18:36:54 INFO - PID 4175 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.30 Median:67.50 stddev:5.06 (7.5%) stddev-sans-first:5.29 18:36:54 INFO - PID 4175 | Values: 66.0 68.0 64.0 67.0 74.0 66.0 64.0 80.0 65.0 69.0 18:36:54 INFO - PID 4175 | -------- Summary: end -------- 18:36:54 INFO - PID 4175 | 18:36:54 INFO - PID 4175 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 18:36:54 INFO - TEST-INFO | 4175: exit 0 18:36:54 INFO - TEST-OK | kraken | took 63191ms 18:36:54 INFO - SUITE-END | took 1178s 18:36:55 INFO - Completed test suite (00:19:38) 18:36:55 INFO - javascript benchmark 18:36:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8549.358077917186, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3074.5695602615638, 3110.3001824589464, 3126.312211313511, 3127.687231276874, 3163.161762133035, 6019.337872833985, 6107.114468402079, 6166.414190651892, 6170.660613666247, 6200.937990620092, 5970.761169553223, 6080.2703675559, 6089.573729838913, 6090.604110732799, 6094.695265236732, 5508.614396992212, 5573.972130139344, 5578.386377498472, 5598.552115830744, 5617.01702202115, 5617.213590097369, 5639.859003524924, 5643.209950606918, 5648.745806438699, 5651.886962260753, 5565.443455654443, 5612.66324020558, 5640.689762063085, 5649.1243857202, 5674.233978412903, 5582.525485333752, 5592.944070559283, 5621.634593751434, 5684.658920464785, 5708.686022268795, 5061.44324124346, 5431.212474191259, 5469.589780766459, 5485.9725701371235, 5491.176323551459, 5900.527957763412, 6003.939960600382, 6062.030075187993, 6065.5754097212775, 6075.665838378887, 6471.191101112361, 6499.69260912374, 6581.539292249545, 6600.735970561172, 6617.437517810992, 6437.420632143083, 6456.935430645681, 6525.93474065258, 6659.200895892475, 6662.300458451841, 6164.784232551884, 6182.84542886427, 6183.969080154616, 6187.783427580058, 6207.193064901534, 6695.732170713117, 6714.1271634688155, 6792.049113124167, 6813.2846051163915, 6824.112865327475, 6270.874582508415, 6444.065610486453, 6463.385978332051, 6491.740330386731, 6553.967230163914, 6527.967360163264, 6535.052417399451, 6536.0, 6539.247986481616, 6543.476521878239, 6432.453241474527, 6486.513511486657, 6486.740530378732, 6511.218653761484, 6518.120053792677, 6570.113034740344, 6614.933850661528, 6615.801525954229, 6622.635755033423, 6627.635480048547, 5256.999999999924, 5334.199870019451, 5353.411124776271, 5353.7590808413715, 5358.785648574091, 4548.340490628841, 4584.220682483986, 4585.862424127282, 4598.793054312497, 4620.306953956866, 5542.584306176972, 5634.690092044977, 5646.520045796154, 5707.885842283131, 5725.112607545837, 6634.734610615618, 6639.668016599151, 6651.600903945682, 6655.900161497581, 6667.933320666828, 5641.633293835887, 5733.397993210736, 5735.283089613798, 5742.368339482654, 5748.45389687979, 5689.203511508392, 5772.04761214395, 5780.566457515703, 5860.8241752746635, 5875.471207591252, 6388.041793730978, 6389.648569328731, 6411.839704007344, 6412.39082287182, 6412.551121421549, 6529.7061632227615, 6658.500612453989, 6660.234073081464, 6670.7665231716655, 6676.0987266718375, 6285.088662144055, 6350.523710721622, 6376.4261216488885, 6376.776812811529, 6431.421172094534, 6400.711967961546, 6404.295527492065, 6505.642189679613, 6514.6416947068365, 6515.511336649865, 6475.417212450902, 6584.374484423972, 6586.506012049212, 6597.60414375139, 6597.736090556324, 6345.206849143857, 6355.1420558224045, 6371.331010243857, 6375.107484952017, 6436.0, 6430.678466076771, 6445.16212892321, 6463.25672547663, 6465.709043092978, 6466.256380516113, 6319.431251187231, 6389.041643753289, 6423.03654451836, 6424.646644434601, 6444.387783160592, 6413.1021656969, 6439.774607888701, 6469.579477334147, 6472.223333200046, 6490.448311893542, 6012.9098063529955, 6130.969345153246, 6135.417135372132, 6142.201513803177, 6147.323794382525], "value": 5986.846536748309, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [834.145001373592, 860.9225169734724, 890.924271436928, 894.2756367342449, 1144.1704764046074, 31563.84218078909, 42810.57189428105, 43299.56700432995, 43459.78270108645, 43886.341704874416, 42336.88315584224, 43064.0, 43164.0, 43273.13453730923, 43482.565174348245, 7273.781786546412, 7488.063992001, 7834.0990786059765, 7894.289513943744, 7912.604369781517, 7700.730474433396, 7827.960860195691, 7828.491148075357, 7832.843343133134, 7835.686572537092, 7788.493747906368, 7861.0, 7862.960685196566, 7871.291583757461, 7871.409644276673, 7743.303102720726, 7806.0, 7812.687492500293, 7847.293743563078, 7851.450398472138, 7642.961785191094, 7713.228677132298, 7721.305082542598, 7726.729564465217, 7741.651625676802, 40964.38553421701, 41296.0, 41331.1733765326, 41361.79319103415, 41400.0, 3486.7210623150086, 3532.452469867175, 3554.431290993429, 3558.1638314995957, 3563.0, 32873.50689739656, 33106.6689333106, 33209.33581328384, 33223.0, 33317.50023749645, 4834.371531700856, 4840.975795121002, 4872.585830204437, 4878.487758785312, 4954.9752251238515, 195818.0, 196327.0, 196677.0, 197395.0, 197517.0, 50035.0, 50130.74934625303, 50267.24599131016, 50380.244296335586, 50448.49551504438, 52154.0, 52715.68210794684, 52723.0, 52741.0, 52786.73606631942, 15917.68164636724, 16321.755173672404, 16369.0, 16373.590660233589, 16395.508134755975, 12460.376981150906, 12654.0, 13338.266395348155, 13622.318884055758, 13628.250446225553, 13626.727465450636, 13845.446182152802, 13871.653208669864, 13933.0, 13989.440422382992, 5500.80747173847, 5525.281713377316, 5539.362973258047, 5642.435756424325, 5707.1153971134545, 995.4624502768569, 1002.1882275356984, 1012.4583347908833, 1012.9088382045651, 1025.958961641526, 23256.53486930252, 28721.425571488453, 28922.0, 29608.851955740083, 29811.850940745157, 1088.472091035847, 1097.8133717268083, 1098.8791232964368, 1109.1071687291646, 1110.5058249079243, 12417.254964702146, 15918.840811591968, 16311.42909998168, 16555.751663725056, 16665.583360415843, 222721.0, 224124.0, 224861.0, 225294.0, 225324.0, 59250.11124833131, 59617.70191149016, 59732.40267597355, 59797.70101149466, 59851.40148598545, 1398.4126666799968, 1458.3364569121024, 1487.1448916872841, 1489.2181604657642, 1489.8882583806042, 27839.721602783713, 27930.720692792813, 28146.85926570354, 28437.573436398055, 28736.856315718287, 40280.0, 40473.39289910595, 40485.39271910865, 40491.39262911, 40507.3923891136, 203392.0, 203966.0, 204346.0, 204731.0, 204845.0, 34065.48901766525, 35909.64090359063, 35912.82043589765, 35944.0, 36004.819975899954, 44103.77948110239, 44446.0, 45002.099958, 45081.0, 45092.32361514645, 32150.678493214768, 32532.186695332806, 32765.0, 32825.17937051593, 33248.501272481415, 31888.840555797073, 32709.0, 32867.178320542174, 33069.17327066843, 33385.83307083449, 36024.27951441009, 36371.0, 36430.8178459106, 36598.08504787402, 36721.26557468782, 30831.0, 30897.227569310948, 31219.687803122586, 31254.687453125178, 31422.685773141977, 22792.886035569714, 23290.06839726392, 23680.644790327813, 25693.871530642227, 25921.0], "value": 17775.276781080378, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [557.1253132581843, 567.8551969247841, 570.2415787003287, 585.9003969325214, 587.8412828536295, 22591.322260332196, 22625.0, 23194.42013949652, 23220.0, 23228.535429291405, 25909.74090259097, 26732.866335668296, 26763.73236267637, 26842.731572684268, 26932.057377991776, 6668.833279168011, 6839.829004274908, 6849.2465828758795, 6852.725890964355, 6853.040574319599, 6460.450861676763, 6742.966285168567, 6747.966260168692, 6800.353966373186, 6857.828554286133, 6630.966845165792, 6682.899756503656, 6684.3315668433015, 6754.763583274586, 6755.121834161552, 6644.66776661165, 6678.632675202862, 6694.330566943291, 6706.429953453962, 6723.058771771955, 6199.504039676815, 6211.47202487789, 6229.25248970122, 6240.31356550779, 6273.309935907047, 29121.708782912323, 29993.550096748568, 30331.393372132654, 30620.0, 30665.846670766725, 1923.1345894347598, 1942.6406114868653, 1953.394447721211, 1954.7947465516058, 1955.452473307476, 19323.0, 20009.8999505003, 20075.598488030304, 20103.698444523343, 20109.49726256855, 2264.750877403484, 2295.540891821626, 2312.2600767754325, 2313.3869524576003, 2447.9510409791706, 143454.0, 146097.26951365176, 146369.0, 146402.0, 148847.25576372157, 14378.496752613608, 15276.312565934559, 15472.535823925075, 15485.0, 15961.760573591406, 23621.0, 24577.13980010691, 24823.25530234096, 24852.390498997345, 24863.0, 10363.740906477398, 10430.843537346946, 10458.163346932228, 10468.52891619879, 10528.789424211625, 9442.10300021497, 9535.713928582154, 9593.520323983774, 9711.417314961125, 9711.523561491775, 9381.390209636489, 9733.2213422926, 9798.804023919623, 9899.851502227473, 9958.601655933682, 2656.5218260712873, 2658.5081759874483, 2711.715269896672, 2787.3031742064486, 2793.7625301849166, 942.495764765847, 947.5404428852002, 957.9042095790365, 963.2871674960479, 979.3976704326788, 19961.700574491395, 20003.899980500006, 20218.393448196577, 20619.278325258543, 20696.379108626767, 1026.0765311219814, 1030.2376241581326, 1039.298473530364, 1043.1289872956015, 1044.5926088825365, 11234.314852771986, 11277.492512836941, 11398.772024559628, 11457.14071444645, 11460.828087578693, 519263.0, 540253.0, 541341.0, 570219.0, 576942.0, 113214.0, 115256.0, 115986.0, 116806.41596791962, 121586.39206803909, 1218.61004478566, 1220.4446976625613, 1291.3478693259844, 1298.8181654568368, 1302.9804552931714, 21699.0, 21873.67189492127, 21924.780752192273, 22092.226772062902, 22104.33686989393, 61364.38635613587, 61600.0, 61967.07049394353, 62657.373426265156, 63808.0, 73234.0, 73646.26353736394, 74288.0, 74375.0, 74667.0, 15111.773323400377, 15484.5354639361, 15488.53534393925, 15516.22418879029, 15565.221738913235, 24412.389690257885, 24460.143894963592, 24689.25932222036, 24690.25929222126, 24745.886435110104, 18679.71980420322, 19792.307269245506, 19794.109265082818, 19827.80172198317, 20044.098015589625, 14985.2507374633, 17655.823441765933, 17738.290468381638, 17788.91105544464, 18220.36228731988, 19926.402207933785, 19979.500512487302, 20147.0, 20214.696779548613, 20237.898810505852, 14567.854321457075, 15047.322870470636, 15344.0, 15375.30811113525, 15418.614534636723, 18218.18018189158, 18574.0, 18701.34545290908, 18724.25102995865, 18955.0], "value": 12423.35561548624, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1316.289203829932, 1389.9235542045183, 1394.4491925689354, 1394.9372278247472, 1396.3367400484776, 13423.261720605375, 13982.790258146111, 14105.435782568698, 14124.011319207646, 14131.081479703813, 13107.606771796862, 13374.59876203713, 13387.39756710948, 13420.932895335509, 13432.3955422006, 2651.0191229245206, 2672.358633927853, 2696.2046196372116, 2698.9460210795773, 2700.0144947094363, 2669.9332516687045, 2776.972230277702, 2785.1644506648026, 2795.664520257572, 2797.608334833125, 2658.2689760315875, 2665.933351666214, 2689.3814422682794, 2707.905223317184, 2719.4697034078363, 2640.075973409301, 2679.678438587372, 2695.8517281549507, 2735.302497863042, 2737.2883050407036, 2619.9607005894927, 2626.9737302626922, 2640.2211347652296, 2662.853543055131, 2674.3314171457137, 13488.595342139653, 13727.245001524912, 13773.449062037507, 13774.0, 13836.446542138303, 7610.467267291292, 7904.683812647487, 7919.049714034295, 7927.326177274881, 7937.801554961115, 4959.677620954626, 5143.691378517264, 5165.380154381461, 5210.374755029421, 5237.554807841338, 1743.947681569555, 1789.2216885654782, 1822.3348477805505, 1844.529644940545, 1856.4337876947525, 20820.271290504912, 21123.999999999847, 21390.786092139035, 21425.464363390885, 21447.034883430282, 6711.261761206263, 6773.695183716744, 6882.242953275136, 6965.407940325028, 6969.6515174241085, 17054.14729263532, 17069.7439538407, 17099.829001710073, 17113.48659540216, 17118.48644540666, 7332.96333518329, 7642.426817988673, 7655.349295309849, 7663.386929045663, 7684.3084122429245, 6596.571222870498, 6892.896606550906, 6987.475939304471, 7160.283971602798, 7174.067371241809, 5317.9202311965355, 5434.619576629677, 5514.6691198528215, 5543.667379957215, 5573.080441727151, 1687.64559442516, 1707.5304291319987, 1711.6405554833625, 1751.2119546204003, 1772.4239622122861, 863.8315528471887, 896.188949001155, 908.3051465628799, 909.8180363927241, 913.1690161952591, 7245.710171593183, 8707.346948978851, 8888.511131887679, 8946.239569636524, 9047.185753282105, 979.4612962870393, 995.0546980368534, 1018.9235807314333, 1020.05644778581, 1028.922830787679, 4782.760861956888, 4821.807127714852, 4987.825426110068, 5071.290019397287, 5149.304843846033, 18350.35773747939, 18773.90613046926, 18830.623387532174, 19213.903930480257, 19214.327498537485, 18696.626067478574, 18745.968971706687, 18842.15210315539, 19082.809171908382, 19125.808741912682, 9988.151007164328, 10152.593896244067, 10338.483075846329, 10379.896201038044, 10404.843927341097, 8057.153998830273, 8162.387820913574, 8166.346692264723, 8173.305269052197, 8386.706465273686, 16586.83413165853, 16752.32990680408, 17223.91388043052, 17330.220140093475, 17489.475315740547, 16666.58333541671, 17315.56711082233, 17426.128693565017, 17475.12624368802, 17708.734368984216, 11270.94364528172, 11622.128340374675, 11623.186376953532, 11630.476628551905, 11665.0, 12703.555375561811, 12897.613071607866, 12938.741225175632, 13038.0, 13038.282894440517, 10398.0, 10634.202434817265, 10675.252732308663, 10692.465376730977, 11019.283746556448, 10241.43672098012, 10269.229807764597, 10317.226208034577, 10321.329113607591, 10473.162147028372, 9213.953930230575, 9386.389884657749, 9388.248940084914, 9392.765180870532, 9403.858942115738, 7168.354848063699, 7185.82035449097, 7244.0, 7261.237570055279, 7287.161976372785, 7325.4505912057875, 7359.337659610446, 7380.1512826023545, 7481.7755467336065, 7506.887396688945], "value": 6436.427215007274, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [501.76667849449996, 502.4949925325049, 528.4847273907938, 532.6165161084019, 537.373959337372, 6005.579609427342, 6149.310413751747, 6157.1687822143995, 6166.722497487613, 6202.4417802397775, 5743.62666426682, 5955.877944967325, 5991.880162396749, 6006.129111278873, 6025.698715064251, 4928.433230178522, 4934.40787105546, 4937.604991600672, 4945.703257804544, 4951.613548206508, 4944.258361245824, 4945.35710357653, 4959.528844759742, 4960.7767650455635, 4976.576990955773, 4983.127952608279, 5108.8211912583065, 5116.130257856174, 5129.2306154077, 5144.948550514484, 5125.513076257731, 5141.845744627649, 5162.0, 5184.274201611777, 5186.0, 4766.30888521166, 4772.80908763649, 4774.069056533977, 4777.498362671939, 4783.617310615177, 6091.238595175603, 6104.267487901436, 6128.44843964041, 6145.293291271516, 6157.815265542041, 5978.880422391528, 5994.820155395345, 5998.40015998401, 6003.969980150115, 6013.849653758691, 6555.47556195508, 6595.835104122388, 6607.768728094494, 6609.834754131137, 6652.334766523309, 5245.4754524547625, 5251.264822924793, 5282.550983166436, 5294.497022782868, 5301.60237982149, 6898.033845446456, 7001.719931202746, 7005.264447233069, 7017.789466316019, 7039.155301363818, 5417.404085550586, 5470.617056806065, 5471.480209380103, 5483.7806487740845, 5523.171524271392, 5465.9726701366235, 5534.944650553523, 5544.362398324163, 5563.13771365439, 5569.721513924287, 5940.990031694622, 5955.940440595538, 5974.731137098754, 5984.940150598525, 5989.610675306091, 6511.73953041873, 6612.933870661328, 6619.139511863427, 6630.635315057718, 6643.0, 1832.6884429646725, 1892.9526761831064, 1905.6665083610312, 1938.6219687160867, 1943.504406376365, 1422.6230049037013, 1426.486464872645, 1448.6957738874744, 1460.678650696845, 1463.370750577262, 5653.208550802891, 5676.659400435987, 5677.460641239076, 5698.202251684767, 5742.7702891883855, 6610.504212184105, 6628.767993120314, 6687.352290158294, 6688.933110668831, 6720.8319792005595, 5515.448455154533, 5652.3217213934595, 5720.570957178228, 5738.340090889517, 5787.739551720183, 3504.8247587621026, 3548.27260411615, 3554.9466757998653, 3563.1448452371246, 3567.6075631680496, 6436.806895793134, 6443.903341449882, 6444.677766111676, 6496.935030649633, 6544.247411547638, 6814.420774234147, 6830.702166588431, 6850.520463567503, 6853.17761868579, 6853.990415606826, 6459.677016149174, 6476.125723027425, 6486.610803351814, 6512.381323774234, 6552.82049231143, 6586.736530538917, 6626.503012274003, 6633.369829866155, 6665.533412661067, 6719.630420326929, 6597.472202223715, 6607.669616519059, 6612.5040621952585, 6667.466602671871, 6696.5647232929705, 6401.903971440525, 6427.164468619049, 6441.67791610427, 6460.580062295936, 6489.513286503437, 4920.318426457661, 4933.087378834927, 4980.4272508660515, 5004.47453017428, 5046.873828154326, 6756.5811179650855, 6782.152230971129, 6791.3548212919695, 6800.183977922681, 6822.556533825285, 6449.4840412765925, 6474.676266186766, 6559.311272316338, 6570.455942853521, 6592.505562082959, 6430.582012169194, 6444.4533312005715, 6445.1943507061615, 6460.224773027267, 6460.3862633049785, 6108.450239478469, 6400.359964003563, 6429.421352078149, 6465.741370345134, 6486.740530378732, 6367.87264254703, 6382.489630703002, 6508.511861610493, 6546.810041573492, 6606.504512161511, 6261.7182226799, 6355.713992870239, 6362.172917520693, 6377.075324078076, 6411.275208036659], "value": 5143.026842685302, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [907.3376435202298, 910.7950711090004, 934.5467448287575, 938.5448057692018, 952.1525842000616, 18430.354937577187, 19340.323088691915, 19343.70984435231, 19354.806451935478, 19811.900940495314, 20974.265900693477, 21490.462738431546, 21558.245461408853, 21666.3500094997, 21746.565068698616, 1007.0433088565874, 1041.0, 1042.405828677654, 1042.4370839746528, 1042.7653777899989, 955.0211033690454, 1045.9058684718375, 1051.621416290135, 1051.8001579699871, 1053.1837825685118, 1104.9944750276238, 1108.146727020194, 1115.1970581181536, 1115.8214685650298, 1116.983245251322, 1104.9668509944674, 1106.5241945963232, 1108.0692218536387, 1110.2783190925882, 1110.8222684370462, 809.76516810125, 815.013833261754, 816.7672213419147, 817.2685446525327, 818.1327792539868, 18536.351227706964, 18624.348147814762, 18632.161552730024, 19124.5218869528, 20514.589708205902, 19844.503887402785, 20015.299464518674, 20203.19187232509, 20268.493287667778, 20278.69581956272, 16538.6692266154, 16630.66738665232, 16635.334586616522, 16676.499705008868, 16792.412265570645, 3536.5579302587175, 3568.232829941575, 3570.625084366156, 3570.80360580168, 3571.5714114306447, 124576.75423245833, 125314.0, 125813.0, 127387.0, 127594.3620281902, 15998.200089995686, 16125.516234512983, 16360.691144708395, 16558.751618725728, 16579.253933573018, 30343.848280758455, 30352.54471182934, 30926.38147237088, 31019.53470697941, 31061.68938310633, 8163.142869998684, 8297.25324720778, 8299.709510167237, 8304.8339033219, 8305.37709671777, 4050.2911990401562, 4181.1010632714115, 4184.121334519724, 4195.475565554306, 4196.874093777192, 5082.593392528589, 5174.301469301671, 5225.790968361299, 5227.111391063529, 5240.68555886648, 964.8842138943372, 978.3836183204536, 992.1814503035025, 994.4331730913455, 995.6963126246565, 670.085333519742, 689.7965100295424, 692.1071817355555, 695.7877847256635, 707.0066556488142, 11038.834417483744, 11488.253263537843, 11528.76942461146, 11546.510932610492, 11629.418529073513, 553.9169124631256, 563.650536667269, 567.6282035266906, 567.6650776042155, 576.2940398012402, 5295.285136506599, 5395.136778115482, 5439.918401223985, 5468.781248750085, 5571.4428557143965, 99359.0, 100121.0, 100402.0, 100461.0, 102516.0, 87704.56147719348, 89383.55308223417, 89612.5519372412, 91793.54103229441, 92078.53960730153, 77018.61490692623, 80350.0, 81791.0, 82012.58993704994, 82170.17829821745, 11786.233894796811, 12322.383880806103, 12326.506939722669, 12740.4266807992, 12858.485660573473, 30686.386272274874, 31683.0, 32327.676723232467, 35784.284314313045, 36208.45687314745, 64385.67807160934, 66358.33641663521, 67058.0, 67582.66208668925, 67618.3238167612, 16985.49043528696, 18829.811701883355, 19088.904555477133, 19274.132664029872, 19301.80698193, 17852.0, 17998.5500362492, 18161.0, 18199.45401637953, 18420.63158736791, 17938.0, 18173.27306907762, 18286.908565457088, 18431.354902578347, 18574.164162612986, 17925.910370448066, 18055.819441805415, 18092.276308947494, 18102.27590896402, 18225.45323640293, 16216.432424865074, 16315.59211019734, 16415.91792041032, 16623.0, 16671.0, 12307.138500304893, 12687.492500299884, 12825.0, 12897.935510322388, 12931.482740690268, 13330.733385332045, 13536.59390218295, 13579.25314107694, 13618.77431031212, 13779.931100344435], "value": 8921.877290242452, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1546.6999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 107.0, 104.0, 109.0, 112.0, 109.0, 109.0, 101.0, 108.0, 108.0], "value": 106.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 123.0, 146.0, 164.0, 125.0, 153.0, 152.0, 127.0, 164.0, 126.0], "value": 143.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 165.0, 162.0, 167.0, 163.0, 163.0, 165.0, 175.0, 163.0, 163.0], "value": 164.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 79.0, 181.0, 81.0, 81.0, 77.0, 76.0, 159.0, 76.0], "value": 96.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 84.0, 144.0, 79.0, 80.0, 78.0, 141.0, 80.0, 78.0, 132.0], "value": 97.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 114.0, 115.0, 117.0, 118.0, 118.0, 117.0, 119.0], "value": 116.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 153.0, 151.0, 155.0, 153.0, 151.0, 152.0, 151.0, 151.0, 149.0], "value": 151.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 93.0, 95.0, 93.0, 93.0, 94.0, 93.0, 94.0, 95.0], "value": 93.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 61.0, 58.0, 62.0, 57.0, 61.0, 59.0, 56.0, 58.0, 58.0], "value": 58.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 54.0, 54.0, 55.0, 54.0, 59.0, 60.0, 56.0, 55.0], "value": 55.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 84.0, 79.0, 79.0, 84.0, 79.0, 85.0, 77.0, 75.0, 81.0], "value": 80.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 141.0, 125.0, 128.0, 134.0, 137.0, 135.0, 133.0, 126.0, 164.0], "value": 135.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 174.0, 178.0, 172.0, 179.0, 176.0, 179.0, 184.0, 175.0, 196.0], "value": 178.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 64.0, 67.0, 74.0, 66.0, 64.0, 80.0, 65.0, 69.0], "value": 68.3, "unit": "score"}]}]} 18:36:55 INFO - Return code: 0 18:36:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:36:55 INFO - # TBPL SUCCESS # 18:36:55 INFO - Running post-action listener: _package_coverage_data 18:36:55 INFO - Running post-action listener: _resource_record_post_action 18:36:55 INFO - [mozharness: 2017-08-03 01:36:55.403204Z] Finished run-tests step (success) 18:36:55 INFO - Running post-run listener: _resource_record_post_run 18:36:55 INFO - Total resource usage - Wall time: 1197s; CPU: 13.0%; Read bytes: 7417856; Write bytes: 443310080; Read time: 2380; Write time: 470308 18:36:55 INFO - TinderboxPrint: CPU usage
13.2% 18:36:55 INFO - TinderboxPrint: I/O read bytes / time
7,417,856 / 2,380 18:36:55 INFO - TinderboxPrint: I/O write bytes / time
443,310,080 / 470,308 18:36:55 INFO - TinderboxPrint: CPU idle
8,282.5 (86.8%) 18:36:55 INFO - TinderboxPrint: CPU user
1,205.7 (12.6%) 18:36:55 INFO - TinderboxPrint: Swap in / out
0 / 0 18:36:55 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 4096; Write bytes: 119275520; Read time: 204; Write time: 101872 18:36:55 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 18:36:55 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 5177344; Write bytes: 324034560; Read time: 2016; Write time: 368436 18:36:56 INFO - Running post-run listener: _upload_blobber_files 18:36:56 INFO - Blob upload gear active. 18:36:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:36:56 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 18:36:56 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'] 18:36:56 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 18:36:56 INFO - (blobuploader) - INFO - Open directory for files ... 18:36:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:36:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:36:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:36:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:36:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:36:57 INFO - (blobuploader) - INFO - Done attempting. 18:36:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:36:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:36:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:36:58 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:36:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:36:58 INFO - (blobuploader) - INFO - Done attempting. 18:36:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:36:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:36:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:36:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:36:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:36:58 INFO - (blobuploader) - INFO - Done attempting. 18:36:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:36:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:36:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:37:02 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:37:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:37:02 INFO - (blobuploader) - INFO - Done attempting. 18:37:02 INFO - (blobuploader) - INFO - Iteration through files over. 18:37:02 INFO - Return code: 0 18:37:02 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:37:02 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:37:02 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b29b0a85a5ab9e9e4641d1cd4179b29f22f89db0bff914b67fdf09311a2d47bfc8c475b39e9667f92304e0a35b21db0d3aa09ec12953baddbd548f5d78a28b3c", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9ddb90990d9665db3953d5ec2d0d66cffba140c7b8b5f21bddfd25a81553a95df33d12ff22d425a3f0c96e124738cdda4190a0ebbe37c29e4ebf46af44f76587", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4653760403fb7d9627f8277abb2ae39732a8f466fc276adde1f77c04cb03bcf038dffdb5e126770e591879f745ca52bd173e132cc5c03b8238e3049e71ccf3f2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/40f59b745e0e4d5975924beb7829691d2752b1726544f5900056236c27a25264533b396a19e2b6f3a05aafe96d0c7b48d8db3de5a46023ff319e8bcf5ee1fc14"} 18:37:02 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:37:02 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:37:02 INFO - Contents: 18:37:02 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b29b0a85a5ab9e9e4641d1cd4179b29f22f89db0bff914b67fdf09311a2d47bfc8c475b39e9667f92304e0a35b21db0d3aa09ec12953baddbd548f5d78a28b3c", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9ddb90990d9665db3953d5ec2d0d66cffba140c7b8b5f21bddfd25a81553a95df33d12ff22d425a3f0c96e124738cdda4190a0ebbe37c29e4ebf46af44f76587", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4653760403fb7d9627f8277abb2ae39732a8f466fc276adde1f77c04cb03bcf038dffdb5e126770e591879f745ca52bd173e132cc5c03b8238e3049e71ccf3f2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/40f59b745e0e4d5975924beb7829691d2752b1726544f5900056236c27a25264533b396a19e2b6f3a05aafe96d0c7b48d8db3de5a46023ff319e8bcf5ee1fc14"} 18:37:02 INFO - Running post-run listener: copy_logs_to_upload_dir 18:37:02 INFO - Copying logs to upload dir... 18:37:02 INFO - mkdir: /builds/slave/test/build/upload/logs 18:37:02 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1257.615513 ========= master_lag: -0.08 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2017-08-02 18:37:01.999608) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-02 18:37:02.005515) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1501711782.305107-586941734 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b29b0a85a5ab9e9e4641d1cd4179b29f22f89db0bff914b67fdf09311a2d47bfc8c475b39e9667f92304e0a35b21db0d3aa09ec12953baddbd548f5d78a28b3c", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9ddb90990d9665db3953d5ec2d0d66cffba140c7b8b5f21bddfd25a81553a95df33d12ff22d425a3f0c96e124738cdda4190a0ebbe37c29e4ebf46af44f76587", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4653760403fb7d9627f8277abb2ae39732a8f466fc276adde1f77c04cb03bcf038dffdb5e126770e591879f745ca52bd173e132cc5c03b8238e3049e71ccf3f2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/40f59b745e0e4d5975924beb7829691d2752b1726544f5900056236c27a25264533b396a19e2b6f3a05aafe96d0c7b48d8db3de5a46023ff319e8bcf5ee1fc14"} build_url:https://queue.taskcluster.net/v1/task/HQaDFxsZT1qGSdnXmYiyWQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011181 build_url: 'https://queue.taskcluster.net/v1/task/HQaDFxsZT1qGSdnXmYiyWQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b29b0a85a5ab9e9e4641d1cd4179b29f22f89db0bff914b67fdf09311a2d47bfc8c475b39e9667f92304e0a35b21db0d3aa09ec12953baddbd548f5d78a28b3c", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9ddb90990d9665db3953d5ec2d0d66cffba140c7b8b5f21bddfd25a81553a95df33d12ff22d425a3f0c96e124738cdda4190a0ebbe37c29e4ebf46af44f76587", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4653760403fb7d9627f8277abb2ae39732a8f466fc276adde1f77c04cb03bcf038dffdb5e126770e591879f745ca52bd173e132cc5c03b8238e3049e71ccf3f2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/40f59b745e0e4d5975924beb7829691d2752b1726544f5900056236c27a25264533b396a19e2b6f3a05aafe96d0c7b48d8db3de5a46023ff319e8bcf5ee1fc14"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-02 18:37:02.033507) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-02 18:37:02.033854) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1501711782.305107-586941734 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004441 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-02 18:37:02.113999) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-02 18:37:02.114316) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-02 18:37:02.114593) ========= ========= Total master_lag: 0.11 =========