builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0379 starttime: 1497371752.81 results: success (0) revision: 5158df62b3a98f530c7f8e27c70223aab8d0f90b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-13 09:35:52.807593) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-13 09:35:52.808088) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-13 09:35:52.808374) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-13 09:35:52.832950) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 09:35:52.833235) ========= 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: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.gHtpHGBQ7G/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.WBSGcwmklB/Listeners TMPDIR=/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-06-13 09:35:52-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to 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% 181M=0s 2017-06-13 09:35:52 (181 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.140274 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 09:35:52.996260) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 09:35:52.996583) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.gHtpHGBQ7G/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.WBSGcwmklB/Listeners TMPDIR=/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.026238 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 09:35:53.044820) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-13 09:35:53.045262) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 5158df62b3a98f530c7f8e27c70223aab8d0f90b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 5158df62b3a98f530c7f8e27c70223aab8d0f90b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.gHtpHGBQ7G/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.WBSGcwmklB/Listeners TMPDIR=/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-06-13 09:35:53,159 truncating revision to first 12 chars 2017-06-13 09:35:53,160 Setting DEBUG logging. 2017-06-13 09:35:53,160 attempt 1/10 2017-06-13 09:35:53,160 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/5158df62b3a9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-13 09:35:54,208 unpacking tar archive at: mozilla-beta-5158df62b3a9/testing/mozharness/ program finished with exit code 0 elapsedTime=1.328017 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-13 09:35:54.394742) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-13 09:35:54.395084) ========= script_repo_revision: 5158df62b3a98f530c7f8e27c70223aab8d0f90b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-13 09:35:54.395479) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-13 09:35:54.395750) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-13 09:35:54.413819) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 9 secs) (at 2017-06-13 09:35:54.414117) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.gHtpHGBQ7G/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.WBSGcwmklB/Listeners TMPDIR=/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:35:54 INFO - MultiFileLogger online at 20170613 09:35:54 in /builds/slave/test 09:35:54 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 09:35:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:35:54 INFO - {'append_to_log': False, 09:35:54 INFO - 'base_work_dir': '/builds/slave/test', 09:35:54 INFO - 'blob_upload_branch': 'Mozilla-Beta', 09:35:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:35:54 INFO - 'branch': 'Mozilla-Beta', 09:35:54 INFO - 'buildbot_json_path': 'buildprops.json', 09:35:54 INFO - 'config_files': ('talos/mac_config.py',), 09:35:54 INFO - 'default_actions': ('clobber', 09:35:54 INFO - 'read-buildbot-config', 09:35:54 INFO - 'download-and-extract', 09:35:54 INFO - 'populate-webroot', 09:35:54 INFO - 'create-virtualenv', 09:35:54 INFO - 'install', 09:35:54 INFO - 'setup-mitmproxy', 09:35:54 INFO - 'run-tests'), 09:35:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:35:54 INFO - 'download_minidump_stackwalk': True, 09:35:54 INFO - 'download_symbols': 'ondemand', 09:35:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:35:54 INFO - 'tooltool.py': '/tools/tooltool.py', 09:35:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:35:54 INFO - '/tools/misc-python/virtualenv.py')}, 09:35:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:35:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:35:54 INFO - 'gecko_profile': False, 09:35:54 INFO - 'gecko_profile_interval': 0, 09:35:54 INFO - 'installer_path': 'installer.exe', 09:35:54 INFO - 'log_level': 'info', 09:35:54 INFO - 'log_name': 'talos', 09:35:54 INFO - 'log_to_console': True, 09:35:54 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:35:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:35:54 INFO - 'opt_config_files': (), 09:35:54 INFO - 'pip_index': False, 09:35:54 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:35:54 INFO - 'cmd': ('bash', 09:35:54 INFO - '-c', 09:35:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:35:54 INFO - 'enabled': True, 09:35:54 INFO - 'halt_on_failure': False, 09:35:54 INFO - 'name': 'check_screen_resolution'},), 09:35:54 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:35:54 INFO - 'cmd': ('bash', 09:35:54 INFO - '-c', 09:35:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:35:54 INFO - 'enabled': True, 09:35:54 INFO - 'halt_on_failure': False, 09:35:54 INFO - 'name': 'check_screen_resolution'},), 09:35:54 INFO - 'run_cmd_checks_enabled': True, 09:35:54 INFO - 'suite': 'dromaeojs-e10s', 09:35:54 INFO - 'system_bits': '32', 09:35:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:35:54 INFO - 'title': 't-yosemite-r7-0379', 09:35:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:35:54 INFO - 'use_talos_json': True, 09:35:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:35:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:35:54 INFO - 'work_dir': 'build'} 09:35:54 INFO - [mozharness: 2017-06-13 16:35:54.636081Z] Running clobber step. 09:35:54 INFO - Running pre-action listener: _resource_record_pre_action 09:35:54 INFO - Running main action method: clobber 09:35:54 INFO - rmtree: /builds/slave/test/build 09:35:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:35:57 INFO - Running post-action listener: _resource_record_post_action 09:35:57 INFO - [mozharness: 2017-06-13 16:35:57.068459Z] Finished clobber step (success) 09:35:57 INFO - [mozharness: 2017-06-13 16:35:57.068639Z] Running read-buildbot-config step. 09:35:57 INFO - Running pre-action listener: _resource_record_pre_action 09:35:57 INFO - Running main action method: read_buildbot_config 09:35:57 INFO - Using buildbot properties: 09:35:57 INFO - { 09:35:57 INFO - "project": "", 09:35:57 INFO - "product": "firefox", 09:35:57 INFO - "who": "ryanvm@gmail.com", 09:35:57 INFO - "installer_path": "public/build/firefox-55.0.en-US.mac.dmg", 09:35:57 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 09:35:57 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta/", 09:35:57 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 09:35:57 INFO - "stage_platform": "macosx64", 09:35:57 INFO - "basedir": "/builds/slave/test", 09:35:57 INFO - "buildnumber": 1, 09:35:57 INFO - "platform": "yosemite_r7", 09:35:57 INFO - "parent_task_id": "bdSLEiAYSCufuGbqsWsdJQ", 09:35:57 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 09:35:57 INFO - "slavebuilddir": "test", 09:35:57 INFO - "taskId": "Yv86Am72Q5alGD7QfkYGig", 09:35:57 INFO - "branch": "mozilla-beta", 09:35:57 INFO - "script_repo_revision": "production", 09:35:57 INFO - "revision": "5158df62b3a98f530c7f8e27c70223aab8d0f90b", 09:35:57 INFO - "slavename": "t-yosemite-r7-0379", 09:35:57 INFO - "repo_path": "releases/mozilla-beta" 09:35:57 INFO - } 09:35:57 INFO - Finding installer, test and symbols from parent task. 09:35:57 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Yv86Am72Q5alGD7QfkYGig'}, attempt #1 09:35:57 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Yv86Am72Q5alGD7QfkYGig'}, attempt #1 09:35:58 INFO - Task dependencies: HlP27FCDQlOIkVVQs1fpPw bdSLEiAYSCufuGbqsWsdJQ 09:35:58 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bdSLEiAYSCufuGbqsWsdJQ'}, attempt #1 09:35:58 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/bdSLEiAYSCufuGbqsWsdJQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg 09:35:58 INFO - Set test_url: https://queue.taskcluster.net/v1/task/bdSLEiAYSCufuGbqsWsdJQ/artifacts/public/build/test_packages.json 09:35:58 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/bdSLEiAYSCufuGbqsWsdJQ/artifacts/public/build/target.crashreporter-symbols.zip 09:35:58 INFO - Running post-action listener: _resource_record_post_action 09:35:58 INFO - [mozharness: 2017-06-13 16:35:58.848742Z] Finished read-buildbot-config step (success) 09:35:58 INFO - [mozharness: 2017-06-13 16:35:58.848881Z] Running download-and-extract step. 09:35:58 INFO - Running pre-action listener: _resource_record_pre_action 09:35:58 INFO - Running main action method: download_and_extract 09:35:58 INFO - mkdir: /builds/slave/test/build/tests 09:35:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:35:58 INFO - trying https://queue.taskcluster.net/v1/task/bdSLEiAYSCufuGbqsWsdJQ/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json 09:35:58 INFO - Downloading https://queue.taskcluster.net/v1/task/bdSLEiAYSCufuGbqsWsdJQ/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 09:35:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bdSLEiAYSCufuGbqsWsdJQ/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json'}, attempt #1 09:35:59 INFO - Downloaded 1290 bytes. 09:35:59 INFO - Reading from file /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 09:35:59 INFO - Using the following test package requirements: 09:35:59 INFO - {u'awsy': [u'firefox-55.0.en-US.mac.common.tests.zip', 09:35:59 INFO - u'firefox-55.0.en-US.mac.awsy.tests.zip'], 09:35:59 INFO - u'common': [u'firefox-55.0.en-US.mac.common.tests.zip'], 09:35:59 INFO - u'cppunittest': [u'firefox-55.0.en-US.mac.common.tests.zip', 09:35:59 INFO - u'firefox-55.0.en-US.mac.cppunittest.tests.zip'], 09:35:59 INFO - u'gtest': [u'firefox-55.0.en-US.mac.common.tests.zip', 09:35:59 INFO - u'firefox-55.0.en-US.mac.gtest.tests.zip'], 09:35:59 INFO - u'jittest': [u'firefox-55.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 09:35:59 INFO - u'mochitest': [u'firefox-55.0.en-US.mac.common.tests.zip', 09:35:59 INFO - u'firefox-55.0.en-US.mac.mochitest.tests.zip'], 09:35:59 INFO - u'mozbase': [u'firefox-55.0.en-US.mac.common.tests.zip'], 09:35:59 INFO - u'reftest': [u'firefox-55.0.en-US.mac.common.tests.zip', 09:35:59 INFO - u'firefox-55.0.en-US.mac.reftest.tests.zip'], 09:35:59 INFO - u'talos': [u'firefox-55.0.en-US.mac.common.tests.zip', 09:35:59 INFO - u'firefox-55.0.en-US.mac.talos.tests.zip'], 09:35:59 INFO - u'web-platform': [u'firefox-55.0.en-US.mac.common.tests.zip', 09:35:59 INFO - u'firefox-55.0.en-US.mac.web-platform.tests.tar.gz'], 09:35:59 INFO - u'xpcshell': [u'firefox-55.0.en-US.mac.common.tests.zip', 09:35:59 INFO - u'firefox-55.0.en-US.mac.xpcshell.tests.zip']} 09:35:59 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip'] for test suite category: common 09:35:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bdSLEiAYSCufuGbqsWsdJQ/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 09:35:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bdSLEiAYSCufuGbqsWsdJQ/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 09:35:59 INFO - Fetch https://queue.taskcluster.net/v1/task/bdSLEiAYSCufuGbqsWsdJQ/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 09:36:01 INFO - Content-Length response header: 58980917 09:36:01 INFO - Bytes received: 58980917 09:36:19 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip', u'firefox-55.0.en-US.mac.talos.tests.zip'] for test suite category: talos 09:36:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bdSLEiAYSCufuGbqsWsdJQ/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 09:36:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bdSLEiAYSCufuGbqsWsdJQ/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 09:36:19 INFO - Fetch https://queue.taskcluster.net/v1/task/bdSLEiAYSCufuGbqsWsdJQ/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 09:36:21 INFO - Content-Length response header: 58980917 09:36:21 INFO - Bytes received: 58980917 09:36:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bdSLEiAYSCufuGbqsWsdJQ/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip 09:36:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bdSLEiAYSCufuGbqsWsdJQ/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip'}, attempt #1 09:36:38 INFO - Fetch https://queue.taskcluster.net/v1/task/bdSLEiAYSCufuGbqsWsdJQ/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip into memory 09:36:39 INFO - Content-Length response header: 14450018 09:36:39 INFO - Bytes received: 14450018 09:36: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')]} 09:36:39 INFO - trying https://queue.taskcluster.net/v1/task/bdSLEiAYSCufuGbqsWsdJQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg 09:36:39 INFO - Downloading https://queue.taskcluster.net/v1/task/bdSLEiAYSCufuGbqsWsdJQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0.en-US.mac.dmg 09:36:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bdSLEiAYSCufuGbqsWsdJQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0.en-US.mac.dmg'}, attempt #1 09:36:41 INFO - Downloaded 51079381 bytes. 09:36:41 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bdSLEiAYSCufuGbqsWsdJQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg 09:36:41 INFO - mkdir: /builds/slave/test/properties 09:36:41 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:36:41 INFO - Writing to file /builds/slave/test/properties/build_url 09:36:41 INFO - Contents: 09:36:41 INFO - build_url:https://queue.taskcluster.net/v1/task/bdSLEiAYSCufuGbqsWsdJQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg 09:36:41 INFO - Running post-action listener: _resource_record_post_action 09:36:41 INFO - Running post-action listener: set_extra_try_arguments 09:36:41 INFO - [mozharness: 2017-06-13 16:36:41.881759Z] Finished download-and-extract step (success) 09:36:41 INFO - [mozharness: 2017-06-13 16:36:41.881892Z] Running populate-webroot step. 09:36:41 INFO - Running pre-action listener: _resource_record_pre_action 09:36:41 INFO - Running main action method: populate_webroot 09:36:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:36:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:36:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:36:41 INFO - '--tppagecycles', 09:36:41 INFO - '7'], 09:36:41 INFO - 'tests': ['tsvgm']}}, 09:36:41 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:36:41 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:36:41 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:36:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:36:41 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:36:41 INFO - 'talos_options': ['--disable-e10s'], 09:36:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:36:41 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:36:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:36:41 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:36:41 INFO - 'talos_options': ['--disable-e10s'], 09:36:41 INFO - 'tests': ['damp', 'tps']}, 09:36:41 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:36:41 INFO - 'tests': ['damp', 'tps']}, 09:36:41 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:36:41 INFO - 'tests': ['dromaeo_dom']}, 09:36:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:36:41 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:36:41 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 09:36:41 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 09:36:41 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 09:36:41 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 09:36:41 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:36:41 INFO - 'tests': ['a11yr', 09:36:41 INFO - 'ts_paint', 09:36:41 INFO - 'tpaint', 09:36:41 INFO - 'sessionrestore', 09:36:41 INFO - 'sessionrestore_no_auto_restore', 09:36:41 INFO - 'tabpaint']}, 09:36:41 INFO - 'other-e10s': {'tests': ['a11yr', 09:36:41 INFO - 'ts_paint', 09:36:41 INFO - 'tpaint', 09:36:41 INFO - 'sessionrestore', 09:36:41 INFO - 'sessionrestore_no_auto_restore', 09:36:41 INFO - 'tabpaint']}, 09:36:41 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:36:41 INFO - 'ts_paint', 09:36:41 INFO - 'tpaint', 09:36:41 INFO - 'sessionrestore', 09:36:41 INFO - 'sessionrestore_no_auto_restore', 09:36:41 INFO - 'tabpaint']}, 09:36:41 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:36:41 INFO - 'ts_paint', 09:36:41 INFO - 'tpaint', 09:36:41 INFO - 'sessionrestore', 09:36:41 INFO - 'sessionrestore_no_auto_restore', 09:36:41 INFO - 'tabpaint']}, 09:36:41 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:36:41 INFO - 'tests': ['a11yr', 09:36:41 INFO - 'ts_paint', 09:36:41 INFO - 'tpaint', 09:36:41 INFO - 'sessionrestore', 09:36:41 INFO - 'sessionrestore_no_auto_restore', 09:36:41 INFO - 'tabpaint']}, 09:36:41 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:36:41 INFO - 'tests': ['a11yr', 09:36:41 INFO - 'ts_paint', 09:36:41 INFO - 'tpaint', 09:36:41 INFO - 'sessionrestore', 09:36:41 INFO - 'sessionrestore_no_auto_restore', 09:36:41 INFO - 'tabpaint']}, 09:36:41 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 09:36:41 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 09:36:41 INFO - 'bloom_basic_ref']}, 09:36:41 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 09:36:41 INFO - 'talos_options': ['--mitmproxy', 09:36:41 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 09:36:41 INFO - '--firstNonBlankPaint'], 09:36:41 INFO - 'tests': ['quantum_pageload_google', 09:36:41 INFO - 'quantum_pageload_youtube', 09:36:41 INFO - 'quantum_pageload_amazon', 09:36:41 INFO - 'quantum_pageload_facebook']}, 09:36:41 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:36:41 INFO - 'tests': ['tsvgx', 09:36:41 INFO - 'tsvgr_opacity', 09:36:41 INFO - 'tart', 09:36:41 INFO - 'tscrollx', 09:36:41 INFO - 'cart', 09:36:41 INFO - 'tsvg_static']}, 09:36:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:36:41 INFO - 'tsvgr_opacity', 09:36:41 INFO - 'tart', 09:36:41 INFO - 'tscrollx', 09:36:41 INFO - 'cart', 09:36:41 INFO - 'tsvg_static']}, 09:36:41 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:36:41 INFO - 'talos_options': ['--disable-e10s'], 09:36:41 INFO - 'tests': ['tp5o']}, 09:36:41 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:36:41 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:36:41 INFO - 'talos_options': ['--disable-e10s', 09:36:41 INFO - '--xperf_path', 09:36:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:36:41 INFO - 'tests': ['tp5n']}, 09:36:41 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:36:41 INFO - 'talos_options': ['--xperf_path', 09:36:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:36:41 INFO - 'tests': ['tp5n']}}, 09:36:41 INFO - 'talos.zip': {'path': '', 09:36:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:36:41 INFO - Running post-action listener: _resource_record_post_action 09:36:41 INFO - [mozharness: 2017-06-13 16:36:41.904770Z] Finished populate-webroot step (success) 09:36:41 INFO - [mozharness: 2017-06-13 16:36:41.904905Z] Running create-virtualenv step. 09:36:41 INFO - Running pre-action listener: _resource_record_pre_action 09:36:41 INFO - Running main action method: create_virtualenv 09:36:41 INFO - Creating virtualenv /builds/slave/test/build/venv 09:36:41 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 09:36:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:36:41 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 09:36:42 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:36:42 INFO - Using real prefix '/tools/python27' 09:36:42 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:36:42 INFO - Installing distribute.............................................................................................................................................................................................done. 09:36:45 INFO - Installing pip.................done. 09:36:45 INFO - Return code: 0 09:36:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:36:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:36:45 INFO - Reading from file tmpfile_stdout 09:36:45 INFO - Output received: 09:36:45 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:36:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:36: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')]} 09:36:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36: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')]} 09:36:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105a27c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a2b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ef055af40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10577dd30>, '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 0x105747cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10576ebe8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gHtpHGBQ7G/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WBSGcwmklB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:36:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:36:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:36:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gHtpHGBQ7G/Render', 09:36:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:36:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:36:45 INFO - 'HOME': '/Users/cltbld', 09:36:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:36:45 INFO - 'LOGNAME': 'cltbld', 09:36:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:45 INFO - 'MOZ_NO_REMOTE': '1', 09:36:45 INFO - 'NO_EM_RESTART': '1', 09:36:45 INFO - 'PAGER': '/bin/cat', 09:36:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:36:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:45 INFO - 'PWD': '/builds/slave/test', 09:36:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:36:45 INFO - 'SHELL': '/bin/bash', 09:36:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WBSGcwmklB/Listeners', 09:36:45 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 09:36:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:36:45 INFO - 'USER': 'cltbld', 09:36:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:36:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:36:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:45 INFO - 'XPC_FLAGS': '0x0', 09:36:45 INFO - 'XPC_SERVICE_NAME': '0', 09:36:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:36:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:46 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 09:36:46 INFO - Cleaning up... 09:36:46 INFO - Return code: 0 09:36:46 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:36: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')]} 09:36:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36: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')]} 09:36:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36: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', '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 0x105a27c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a2b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ef055af40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10577dd30>, '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 0x105747cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10576ebe8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gHtpHGBQ7G/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WBSGcwmklB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:36: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', 'psutil>=3.1.1'] in /builds/slave/test/build 09:36: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 psutil>=3.1.1 09:36:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gHtpHGBQ7G/Render', 09:36:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:36:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:36:46 INFO - 'HOME': '/Users/cltbld', 09:36:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:36:46 INFO - 'LOGNAME': 'cltbld', 09:36:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:46 INFO - 'MOZ_NO_REMOTE': '1', 09:36:46 INFO - 'NO_EM_RESTART': '1', 09:36:46 INFO - 'PAGER': '/bin/cat', 09:36:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:36:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:46 INFO - 'PWD': '/builds/slave/test', 09:36:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:36:46 INFO - 'SHELL': '/bin/bash', 09:36:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WBSGcwmklB/Listeners', 09:36:46 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 09:36:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:36:46 INFO - 'USER': 'cltbld', 09:36:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:36:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:36:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:46 INFO - 'XPC_FLAGS': '0x0', 09:36:46 INFO - 'XPC_SERVICE_NAME': '0', 09:36:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:36:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:46 INFO - Downloading/unpacking psutil>=3.1.1 09:36: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 09:36: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 09:36: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 09:36: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 09:36:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:36:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:36:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:36:48 INFO - Installing collected packages: psutil 09:36:48 INFO - Running setup.py install for psutil 09:36:48 INFO - building 'psutil._psutil_osx' extension 09:36:48 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:36:49 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:36:49 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:36:49 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:36:49 INFO - building 'psutil._psutil_posix' extension 09:36:49 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:36:49 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:36:49 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:36:49 INFO - ^ 09:36:49 INFO - 1 warning generated. 09:36:49 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:36:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:36:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:36:49 INFO - Successfully installed psutil 09:36:49 INFO - Cleaning up... 09:36:49 INFO - Return code: 0 09:36:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:36:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:36:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:36:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:50 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 0x105a27c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a2b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ef055af40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10577dd30>, '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 0x105747cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10576ebe8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gHtpHGBQ7G/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WBSGcwmklB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:36:50 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 09:36:50 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 09:36:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gHtpHGBQ7G/Render', 09:36:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:36:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:36:50 INFO - 'HOME': '/Users/cltbld', 09:36:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:36:50 INFO - 'LOGNAME': 'cltbld', 09:36:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:50 INFO - 'MOZ_NO_REMOTE': '1', 09:36:50 INFO - 'NO_EM_RESTART': '1', 09:36:50 INFO - 'PAGER': '/bin/cat', 09:36:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:36:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:50 INFO - 'PWD': '/builds/slave/test', 09:36:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:36:50 INFO - 'SHELL': '/bin/bash', 09:36:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WBSGcwmklB/Listeners', 09:36:50 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 09:36:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:36:50 INFO - 'USER': 'cltbld', 09:36:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:36:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:36:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:50 INFO - 'XPC_FLAGS': '0x0', 09:36:50 INFO - 'XPC_SERVICE_NAME': '0', 09:36:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:36:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:36:50 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 09:36:50 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 09:36:50 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 09:36:50 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 09:36:52 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:36:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:36:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:36:52 INFO - Installing collected packages: mozsystemmonitor 09:36:52 INFO - Running setup.py install for mozsystemmonitor 09:36:52 INFO - Successfully installed mozsystemmonitor 09:36:52 INFO - Cleaning up... 09:36:52 INFO - Return code: 0 09:36:52 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:36:52 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')]} 09:36:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:52 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')]} 09:36:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:52 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 0x105a27c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a2b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ef055af40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10577dd30>, '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 0x105747cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10576ebe8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gHtpHGBQ7G/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WBSGcwmklB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:36:52 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 09:36:52 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 09:36:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gHtpHGBQ7G/Render', 09:36:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:36:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:36:52 INFO - 'HOME': '/Users/cltbld', 09:36:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:36:52 INFO - 'LOGNAME': 'cltbld', 09:36:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:52 INFO - 'MOZ_NO_REMOTE': '1', 09:36:52 INFO - 'NO_EM_RESTART': '1', 09:36:52 INFO - 'PAGER': '/bin/cat', 09:36:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:36:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:52 INFO - 'PWD': '/builds/slave/test', 09:36:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:36:52 INFO - 'SHELL': '/bin/bash', 09:36:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WBSGcwmklB/Listeners', 09:36:52 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 09:36:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:36:52 INFO - 'USER': 'cltbld', 09:36:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:36:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:36:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:52 INFO - 'XPC_FLAGS': '0x0', 09:36:52 INFO - 'XPC_SERVICE_NAME': '0', 09:36:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:36:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:52 INFO - Downloading/unpacking jsonschema==2.5.1 09:36:52 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 09:36:52 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 09:36:52 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 09:36:52 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 09:36:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:36:54 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:36:54 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 09:36:54 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 09:36:54 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 09:36:54 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 09:36:54 INFO - Downloading functools32-3.2.3-2.tar.gz 09:36:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:36:54 INFO - warning: no files found matching '*.txt' 09:36:54 INFO - no previously-included directories found matching 'build' 09:36:54 INFO - no previously-included directories found matching 'dist' 09:36:54 INFO - no previously-included directories found matching '.git*' 09:36:54 INFO - Installing collected packages: jsonschema, functools32 09:36:54 INFO - Running setup.py install for functools32 09:36:54 INFO - warning: no files found matching '*.txt' 09:36:54 INFO - no previously-included directories found matching 'build' 09:36:54 INFO - no previously-included directories found matching 'dist' 09:36:54 INFO - no previously-included directories found matching '.git*' 09:36:54 INFO - Successfully installed jsonschema functools32 09:36:54 INFO - Cleaning up... 09:36:54 INFO - Return code: 0 09:36:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:36: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')]} 09:36:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36: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')]} 09:36:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:54 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 0x105a27c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a2b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ef055af40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10577dd30>, '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 0x105747cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10576ebe8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gHtpHGBQ7G/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WBSGcwmklB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:36:54 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 09:36:54 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 09:36:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gHtpHGBQ7G/Render', 09:36:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:36:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:36:54 INFO - 'HOME': '/Users/cltbld', 09:36:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:36:54 INFO - 'LOGNAME': 'cltbld', 09:36:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:54 INFO - 'MOZ_NO_REMOTE': '1', 09:36:54 INFO - 'NO_EM_RESTART': '1', 09:36:54 INFO - 'PAGER': '/bin/cat', 09:36:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:36:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:54 INFO - 'PWD': '/builds/slave/test', 09:36:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:36:54 INFO - 'SHELL': '/bin/bash', 09:36:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WBSGcwmklB/Listeners', 09:36:54 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 09:36:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:36:54 INFO - 'USER': 'cltbld', 09:36:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:36:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:36:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:54 INFO - 'XPC_FLAGS': '0x0', 09:36:54 INFO - 'XPC_SERVICE_NAME': '0', 09:36:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:36:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:55 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:36:55 INFO - Cleaning up... 09:36:55 INFO - Return code: 0 09:36:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:36:55 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')]} 09:36:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:55 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')]} 09:36:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:55 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 0x105a27c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a2b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ef055af40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10577dd30>, '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 0x105747cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10576ebe8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gHtpHGBQ7G/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WBSGcwmklB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:36:55 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 09:36:55 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 09:36:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gHtpHGBQ7G/Render', 09:36:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:36:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:36:55 INFO - 'HOME': '/Users/cltbld', 09:36:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:36:55 INFO - 'LOGNAME': 'cltbld', 09:36:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:55 INFO - 'MOZ_NO_REMOTE': '1', 09:36:55 INFO - 'NO_EM_RESTART': '1', 09:36:55 INFO - 'PAGER': '/bin/cat', 09:36:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:36:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:55 INFO - 'PWD': '/builds/slave/test', 09:36:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:36:55 INFO - 'SHELL': '/bin/bash', 09:36:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WBSGcwmklB/Listeners', 09:36:55 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 09:36:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:36:55 INFO - 'USER': 'cltbld', 09:36:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:36:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:36:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:55 INFO - 'XPC_FLAGS': '0x0', 09:36:55 INFO - 'XPC_SERVICE_NAME': '0', 09:36:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:36:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:55 INFO - Downloading/unpacking blobuploader==1.2.4 09:36: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 09:36: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 09:36: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 09:36: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 09:36:56 INFO - Downloading blobuploader-1.2.4.tar.gz 09:36:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:36:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:36:56 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 09:36:56 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 09:36:56 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 09:36:56 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 09:36:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:36:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:36:57 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 09:36:57 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 09:36:57 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 09:36:57 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 09:36:57 INFO - Downloading docopt-0.6.1.tar.gz 09:36:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:36:57 INFO - Installing collected packages: blobuploader, requests, docopt 09:36:57 INFO - Running setup.py install for blobuploader 09:36:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:36:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:36:57 INFO - Running setup.py install for requests 09:36:58 INFO - Running setup.py install for docopt 09:36:58 INFO - Successfully installed blobuploader requests docopt 09:36:58 INFO - Cleaning up... 09:36:58 INFO - Return code: 0 09:36:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:36:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:36:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:36:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:58 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 0x105a27c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a2b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ef055af40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10577dd30>, '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 0x105747cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10576ebe8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gHtpHGBQ7G/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WBSGcwmklB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:36:58 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 09:36:58 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 09:36:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gHtpHGBQ7G/Render', 09:36:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:36:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:36:58 INFO - 'HOME': '/Users/cltbld', 09:36:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:36:58 INFO - 'LOGNAME': 'cltbld', 09:36:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:58 INFO - 'MOZ_NO_REMOTE': '1', 09:36:58 INFO - 'NO_EM_RESTART': '1', 09:36:58 INFO - 'PAGER': '/bin/cat', 09:36:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:36:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:58 INFO - 'PWD': '/builds/slave/test', 09:36:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:36:58 INFO - 'SHELL': '/bin/bash', 09:36:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WBSGcwmklB/Listeners', 09:36:58 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 09:36:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:36:58 INFO - 'USER': 'cltbld', 09:36:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:36:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:36:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:58 INFO - 'XPC_FLAGS': '0x0', 09:36:58 INFO - 'XPC_SERVICE_NAME': '0', 09:36:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:36:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:36:58 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-LstknA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:36:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:36:58 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-bDVXgQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:36:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:36:59 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-4gAVsM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:36:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:36:59 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-1B8ZI0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:36:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:36:59 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-93QEbZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:36:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:36:59 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-qX39Kt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:36:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:36:59 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-ZOlQtq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:36:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:36:59 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-S_SV1k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:36:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:36:59 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-_BVRNS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:36:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:36:59 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-1NZMG6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:36:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:36:59 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-dMREky-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:36:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:36:59 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-xpPapG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:37:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:37:00 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-s3vGF9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:37:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:37:00 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-VxyzGs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:37:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:37:00 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-hI4aH3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:37:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:37:00 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-nRbb7Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:37:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:37:00 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-VjZZPI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:37:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:37:00 INFO - Running setup.py install for manifestparser 09:37:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:37:00 INFO - Running setup.py install for mozcrash 09:37:00 INFO - Running setup.py install for mozdebug 09:37:01 INFO - Running setup.py install for mozdevice 09:37:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:37:01 INFO - Running setup.py install for mozfile 09:37:01 INFO - Running setup.py install for mozhttpd 09:37:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:37:01 INFO - Running setup.py install for mozinfo 09:37:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:37:01 INFO - Running setup.py install for mozInstall 09:37:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:37:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:37:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:37:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:37:01 INFO - Running setup.py install for mozleak 09:37:01 INFO - Running setup.py install for mozlog 09:37:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:37:02 INFO - Running setup.py install for moznetwork 09:37:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:37:02 INFO - Running setup.py install for mozprocess 09:37:02 INFO - Running setup.py install for mozprofile 09:37:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:37:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:37:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:37:02 INFO - Running setup.py install for mozrunner 09:37:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:37:02 INFO - Running setup.py install for mozscreenshot 09:37:02 INFO - Running setup.py install for moztest 09:37:03 INFO - Running setup.py install for mozversion 09:37:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:37:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:37:03 INFO - Cleaning up... 09:37:03 INFO - Return code: 0 09:37:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:37:03 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')]} 09:37:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:03 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')]} 09:37:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:03 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 0x105a27c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a2b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ef055af40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10577dd30>, '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 0x105747cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10576ebe8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gHtpHGBQ7G/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WBSGcwmklB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:37:03 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 09:37:03 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 09:37:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gHtpHGBQ7G/Render', 09:37:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:03 INFO - 'HOME': '/Users/cltbld', 09:37:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:03 INFO - 'LOGNAME': 'cltbld', 09:37:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:03 INFO - 'MOZ_NO_REMOTE': '1', 09:37:03 INFO - 'NO_EM_RESTART': '1', 09:37:03 INFO - 'PAGER': '/bin/cat', 09:37:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:03 INFO - 'PWD': '/builds/slave/test', 09:37:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:03 INFO - 'SHELL': '/bin/bash', 09:37:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WBSGcwmklB/Listeners', 09:37:03 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 09:37:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:03 INFO - 'USER': 'cltbld', 09:37:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:03 INFO - 'XPC_FLAGS': '0x0', 09:37:03 INFO - 'XPC_SERVICE_NAME': '0', 09:37:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:37:03 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-ah9aTR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:37:03 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)) 09:37:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:37:03 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-GE1dP8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:37:03 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)) 09:37:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:37:03 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-CVt9YE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:37:03 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)) 09:37:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:37:03 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-A2G3ql-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:37:03 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)) 09:37:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:37:03 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-CjBT6E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:37:03 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)) 09:37:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:37:03 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-CQBf0V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:37:04 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)) 09:37:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:37:04 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-Rcfo_4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:37:04 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)) 09:37:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:37:04 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-WJknPt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:37:04 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)) 09:37:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:37:04 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-sK_pdq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:37:04 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)) 09:37:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:37:04 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-2Vj6Wd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:37:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 09:37:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:37:04 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-MHKAZA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:37:04 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)) 09:37:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:37:04 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-RnYuHN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:37:04 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)) 09:37:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:37:04 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-6L51OJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:37:04 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)) 09:37:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:37:04 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-loQDdC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:37:04 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)) 09:37:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:37:04 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-Kh5GpS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:37:05 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)) 09:37:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:37:05 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-hc8HZg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:37:05 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)) 09:37:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:37:05 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-tka7We-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:37:05 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)) 09:37:05 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)) 09:37:05 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)) 09:37:05 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)) 09:37:05 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)) 09:37:05 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)) 09:37:05 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)) 09:37:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:37:05 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 09:37:05 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 09:37:05 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 09:37:05 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 09:37:06 INFO - Downloading blessings-1.6.tar.gz 09:37:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:37:06 INFO - Installing collected packages: blessings 09:37:06 INFO - Running setup.py install for blessings 09:37:06 INFO - Successfully installed blessings 09:37:06 INFO - Cleaning up... 09:37:06 INFO - Return code: 0 09:37:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:37:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:37:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:37:07 INFO - Reading from file tmpfile_stdout 09:37:07 INFO - Current package versions: 09:37:07 INFO - blessings == 1.6 09:37:07 INFO - blobuploader == 1.2.4 09:37:07 INFO - docopt == 0.6.1 09:37:07 INFO - functools32 == 3.2.3-2 09:37:07 INFO - jsonschema == 2.5.1 09:37:07 INFO - manifestparser == 1.1 09:37:07 INFO - mozInstall == 1.13 09:37:07 INFO - mozcrash == 1.0 09:37:07 INFO - mozdebug == 0.1 09:37:07 INFO - mozdevice == 0.51 09:37:07 INFO - mozfile == 1.2 09:37:07 INFO - mozhttpd == 0.7 09:37:07 INFO - mozinfo == 0.10 09:37:07 INFO - mozleak == 0.1 09:37:07 INFO - mozlog == 3.4 09:37:07 INFO - moznetwork == 0.27 09:37:07 INFO - mozprocess == 0.25 09:37:07 INFO - mozprofile == 0.28 09:37:07 INFO - mozrunner == 6.13 09:37:07 INFO - mozscreenshot == 0.1 09:37:07 INFO - mozsystemmonitor == 0.3 09:37:07 INFO - moztest == 0.8 09:37:07 INFO - mozversion == 1.4 09:37:07 INFO - psutil == 3.1.1 09:37:07 INFO - requests == 1.2.3 09:37:07 INFO - wsgiref == 0.1.2 09:37:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:37: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')]} 09:37:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37: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')]} 09:37:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:07 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 0x105a27c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a2b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ef055af40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10577dd30>, '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 0x105747cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10576ebe8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gHtpHGBQ7G/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WBSGcwmklB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:37:07 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 09:37:07 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 09:37:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gHtpHGBQ7G/Render', 09:37:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:07 INFO - 'HOME': '/Users/cltbld', 09:37:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:07 INFO - 'LOGNAME': 'cltbld', 09:37:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:07 INFO - 'MOZ_NO_REMOTE': '1', 09:37:07 INFO - 'NO_EM_RESTART': '1', 09:37:07 INFO - 'PAGER': '/bin/cat', 09:37:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:07 INFO - 'PWD': '/builds/slave/test', 09:37:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:07 INFO - 'SHELL': '/bin/bash', 09:37:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WBSGcwmklB/Listeners', 09:37:07 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 09:37:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:07 INFO - 'USER': 'cltbld', 09:37:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:07 INFO - 'XPC_FLAGS': '0x0', 09:37:07 INFO - 'XPC_SERVICE_NAME': '0', 09:37:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:07 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)) 09:37:07 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)) 09:37:07 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)) 09:37:07 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)) 09:37:07 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)) 09:37:07 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)) 09:37:07 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)) 09:37:07 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)) 09:37:07 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)) 09:37:07 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 09:37:07 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 09:37:07 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 09:37:07 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 09:37:07 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 09:37:08 INFO - Downloading marionette_driver-1.4.0.tar.gz 09:37:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 09:37:09 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)) 09:37:09 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)) 09:37:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 09:37:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 09:37:09 INFO - Installing collected packages: marionette-driver 09:37:09 INFO - Running setup.py install for marionette-driver 09:37:09 INFO - Successfully installed marionette-driver 09:37:09 INFO - Cleaning up... 09:37:09 INFO - Return code: 0 09:37:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:37:09 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')]} 09:37:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:09 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')]} 09:37:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:09 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 0x105a27c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a2b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ef055af40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10577dd30>, '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 0x105747cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10576ebe8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gHtpHGBQ7G/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WBSGcwmklB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:37:09 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 09:37:09 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 09:37:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gHtpHGBQ7G/Render', 09:37:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:09 INFO - 'HOME': '/Users/cltbld', 09:37:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:09 INFO - 'LOGNAME': 'cltbld', 09:37:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:09 INFO - 'MOZ_NO_REMOTE': '1', 09:37:09 INFO - 'NO_EM_RESTART': '1', 09:37:09 INFO - 'PAGER': '/bin/cat', 09:37:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:09 INFO - 'PWD': '/builds/slave/test', 09:37:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:09 INFO - 'SHELL': '/bin/bash', 09:37:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WBSGcwmklB/Listeners', 09:37:09 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 09:37:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:09 INFO - 'USER': 'cltbld', 09:37:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:09 INFO - 'XPC_FLAGS': '0x0', 09:37:09 INFO - 'XPC_SERVICE_NAME': '0', 09:37:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:37:09 INFO - Cleaning up... 09:37:09 INFO - Return code: 0 09:37:09 INFO - Running post-action listener: _resource_record_post_action 09:37:09 INFO - Running post-action listener: _start_resource_monitoring 09:37:09 INFO - Starting resource monitoring. 09:37:09 INFO - [mozharness: 2017-06-13 16:37:09.655999Z] Finished create-virtualenv step (success) 09:37:09 INFO - [mozharness: 2017-06-13 16:37:09.657033Z] Running install step. 09:37:09 INFO - Running pre-action listener: _resource_record_pre_action 09:37:09 INFO - Running main action method: install 09:37:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:37:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:37:09 INFO - Reading from file tmpfile_stdout 09:37:09 INFO - Detecting whether we're running mozinstall >=1.0... 09:37:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:37:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:37:10 INFO - Reading from file tmpfile_stdout 09:37:10 INFO - Output received: 09:37:10 INFO - Usage: mozinstall [options] installer 09:37:10 INFO - Options: 09:37:10 INFO - -h, --help show this help message and exit 09:37:10 INFO - -d DEST, --destination=DEST 09:37:10 INFO - Directory to install application into. [default: 09:37:10 INFO - "/builds/slave/test"] 09:37:10 INFO - --app=APP Application being installed. [default: firefox] 09:37:10 INFO - mkdir: /builds/slave/test/build/application 09:37:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:37:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0.en-US.mac.dmg --destination /builds/slave/test/build/application 09:37:25 INFO - Reading from file tmpfile_stdout 09:37:25 INFO - Output received: 09:37:25 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 09:37:25 INFO - Running post-action listener: _resource_record_post_action 09:37:25 INFO - [mozharness: 2017-06-13 16:37:25.746972Z] Finished install step (success) 09:37:25 INFO - [mozharness: 2017-06-13 16:37:25.747117Z] Running setup-mitmproxy step. 09:37:25 INFO - Running pre-action listener: _resource_record_pre_action 09:37:25 INFO - Running main action method: setup_mitmproxy 09:37:25 INFO - Skipping: mitmproxy is not required 09:37:25 INFO - Running post-action listener: _resource_record_post_action 09:37:25 INFO - [mozharness: 2017-06-13 16:37:25.747744Z] Finished setup-mitmproxy step (success) 09:37:25 INFO - [mozharness: 2017-06-13 16:37:25.747887Z] Running run-tests step. 09:37:25 INFO - Running pre-action listener: _resource_record_pre_action 09:37:25 INFO - Running main action method: run_tests 09:37:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:37:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:37:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:37:25 INFO - 2017-06-13 09:37:25.763 screenresolution[1633:7431] starting screenresolution argv=screenresolution get 09:37:25 INFO - 2017-06-13 09:37:25.765 screenresolution[1633:7431] Display 0: 1600x1200x32@60 09:37:25 INFO - 2017-06-13 09:37:25.771 screenresolution[1634:7433] starting screenresolution argv=screenresolution list 09:37:25 INFO - Available Modes on Display 0 09:37:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:37:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:37:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:37:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:37:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:37:26 INFO - Intel Iris: 09:37:26 INFO - Chipset Model: Intel Iris 09:37:26 INFO - Type: GPU 09:37:26 INFO - Bus: Built-In 09:37:26 INFO - VRAM (Dynamic, Max): 1536 MB 09:37:26 INFO - Vendor: Intel (0x8086) 09:37:26 INFO - Device ID: 0x0a2e 09:37:26 INFO - Revision ID: 0x0009 09:37:26 INFO - Displays: 09:37:26 INFO - Display: 09:37:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:37:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:37:26 INFO - Display Serial Number: 1600x1200 60 09:37:26 INFO - Main Display: Yes 09:37:26 INFO - Mirror: Off 09:37:26 INFO - Online: Yes 09:37:26 INFO - Rotation: Supported 09:37:26 INFO - Return code: 0 09:37:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:37:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:37:26 INFO - Python 2.7.3 09:37:26 INFO - Return code: 0 09:37:26 INFO - grabbing minidump binary from tooltool 09:37:26 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')]} 09:37:26 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/macosx64/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 0x10577dd30>, '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 0x105747cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10576ebe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:37:26 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/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:37:26 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/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:37:26 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/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:37:26 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:37:26 INFO - Return code: 0 09:37:26 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:37:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:37:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:37:26 INFO - ENV: RUST_BACKTRACE is now 1 09:37:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:37:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:37:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bdSLEiAYSCufuGbqsWsdJQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0379', '--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 09:37:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/bdSLEiAYSCufuGbqsWsdJQ/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0379 --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 09:37:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gHtpHGBQ7G/Render', 09:37:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:26 INFO - 'HOME': '/Users/cltbld', 09:37:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:26 INFO - 'LOGNAME': 'cltbld', 09:37:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:37:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:37:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:26 INFO - 'MOZ_NO_REMOTE': '1', 09:37:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:37:26 INFO - 'NO_EM_RESTART': '1', 09:37:26 INFO - 'PAGER': '/bin/cat', 09:37:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:26 INFO - 'PWD': '/builds/slave/test', 09:37:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:37:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:26 INFO - 'RUST_BACKTRACE': '1', 09:37:26 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 09:37:26 INFO - 'SHELL': '/bin/bash', 09:37:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WBSGcwmklB/Listeners', 09:37:26 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 09:37:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:26 INFO - 'USER': 'cltbld', 09:37:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:26 INFO - 'XPC_FLAGS': '0x0', 09:37:26 INFO - 'XPC_SERVICE_NAME': '0', 09:37:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:26 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bdSLEiAYSCufuGbqsWsdJQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0379', '--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 09:37:26 INFO - mozversion application_buildid: 20170613080545 09:37:26 INFO - mozversion application_changeset: 5158df62b3a98f530c7f8e27c70223aab8d0f90b 09:37:26 INFO - mozversion application_display_name: Firefox 09:37:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:37:26 INFO - mozversion application_name: Firefox 09:37:26 INFO - mozversion application_remotingname: firefox 09:37:26 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 09:37:26 INFO - mozversion application_vendor: Mozilla 09:37:26 INFO - mozversion application_version: 55.0 09:37:26 INFO - mozversion platform_buildid: 20170613080545 09:37:26 INFO - mozversion platform_changeset: 5158df62b3a98f530c7f8e27c70223aab8d0f90b 09:37:26 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 09:37:26 INFO - mozversion platform_version: 55.0 09:37:26 INFO - using testdate: 1497371846 09:37:26 INFO - actual date: 1497371846 09:37:26 INFO - starting webserver on 'localhost:49232' 09:37:26 INFO - SUITE-START | Running 2 tests 09:37:26 INFO - TEST-START | dromaeo_css 09:37:26 INFO - operating with platform_type : mac_ 09:37:26 INFO - Initialising browser for dromaeo_css test... 09:37:26 INFO - Application command: /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox http://localhost:49232/getInfo.html -foreground -profile /var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/tmphQrjFp/profile 09:37:26 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox http://localhost:49232/getInfo.html -foreground) 09:37:59 INFO - TEST-INFO | 1644: killed by SIGTERM 09:37:59 INFO - Browser initialized. 09:37:59 INFO - Running cycle 1/1 for dromaeo_css test... 09:37:59 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/tmphQrjFp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:38:01 INFO - PID 1654 | 2017-06-13 09:38:01.199 plugin-container[1655:7969] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444b, name = 'com.apple.tsm.portname' 09:38:01 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:38:01 INFO - PID 1654 | 2017-06-13 09:38:01.199 plugin-container[1655:7969] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x480b, name = 'com.apple.CFPasteboardClient' 09:38:01 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:38:01 INFO - PID 1654 | 2017-06-13 09:38:01.199 plugin-container[1655:7969] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:38:11 INFO - PID 1654 | RSS: Main: 184868864 09:38:11 INFO - PID 1654 | 09:41:13 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49232/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49232/tests/dromaeo/cssquery-ext.html) 09:41:16 INFO - PID 1654 | RSS: Main: 199069696 09:41:16 INFO - PID 1654 | 09:44:17 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49232/tests/dromaeo/cssquery-ext.html (next: http://localhost:49232/tests/dromaeo/cssquery-jquery.html) 09:44:18 INFO - PID 1654 | RSS: Main: 208044032 09:44:18 INFO - PID 1654 | 09:47:19 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49232/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49232/tests/dromaeo/cssquery-mootools.html) 09:47:20 INFO - PID 1654 | RSS: Main: 212779008 09:47:20 INFO - PID 1654 | 09:50:22 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49232/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49232/tests/dromaeo/cssquery-prototype.html) 09:50:22 INFO - PID 1654 | RSS: Main: 215207936 09:50:22 INFO - PID 1654 | 09:53:25 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49232/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49232/tests/dromaeo/cssquery-yui.html) 09:53:26 INFO - PID 1654 | RSS: Main: 215867392 09:53:26 INFO - PID 1654 | 09:56:27 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49232/tests/dromaeo/cssquery-yui.html (next: http://localhost:49232/tests/dromaeo/cssquery-dojo.html) 09:56:28 INFO - PID 1654 | RSS: Main: 216186880 09:56:28 INFO - PID 1654 | 09:56:28 INFO - PID 1654 | __start_tp_report 09:56:28 INFO - PID 1654 | _x_x_mozilla_page_load 09:56:28 INFO - PID 1654 | _x_x_mozilla_page_load_details 09:56:28 INFO - PID 1654 | |i|pagename|runs| 09:56:28 INFO - PID 1654 | |0;dojo.html;3033.620797400325;3797.4303854421833;3801.695864330854;3837.750546214496;3859.3632050711626;6352.650604216767;6359.968200158993;6374.840628984278;6391.776287829927;6419.90370144447;6507.8373040674005;6529.379708927644;6533.085368048465;6533.8366540836505;6538.411542961145;5716.657000579979;5756;5759.568032397566;5921;5969.970150149265;5823.592348535604;5920.615160014585;5955.52355811534;5960.463558279754;5992.910106348387;5576.219329293902;5633.15502674598;5741.425857414267;5806.506446952014;5807.128930660414;5618.690971996539;5715.371309155989;5772.567057470665;5785.82642520725;5808.88382232353;5690;5729.742161602738;5732.656040637575;5778.42215778423;5786.68173250471;6412.422881940648;6471.482281417475;6488.643124628144;6493.902591461132;6550.574212676209;7092.219855815856;7110.32451917067;7125.7505987290715;7136.215016348197;7137.82155446118;6894;6976.686049127801;7006.229314775371;7072.5402848814665;7178.17450993132;6586.7035983380865;6627.701753421059;6665.433438157714;6673.733050677967;6692.799216023527;7289.161746399126;7352.154502232205;7416.40668746499;7419.146798118286;7436.851262974818;6690.59856408617;6753.1220941277315;6802.897956530656;6985.5808651481075;7008.088948436671;6998.720051197895;7031.874900015973;7053.647317634098;7195.784126476214;7204.423646108299;7054.964725176341;7059.5058345915295;7060.117485314336;7066.611336376446;7081.964590177119;6742.49431292655;7046.823829404306;7094.751683691046;7102.964485177645;7108.609026503488;4775.474697783241;4797.064572408417;5380.219868119101;5608.130739735348;5690.260266165372;4984.327115839388;5080.898382032413;5088.542031217208;5090.821821236239;5094.465081166424;5873.148393483007;6645.900311495331;6655.900161497581;6724.159480064992;6742.190937087581;7195.13658361;7248.383887369527;7252.492325537161;7262.164851042092;7275;5828.53371730269;5854.121881717691;6711.832204194934;6713.932860671429;6738;6106.236720409949;6125.989211780006;6135.60015773269;6157.261128664589;6171.47542458896;7112.466565007645;7329.780106596811;7399.371053460516;7428.442866784904;7429.4799364044;5834.533237341087;7213.9278607213255;7219.917012448175;7220.133583969957;7230.349268565854;6957.860842783015;7351.558906465629;7411.851762964603;7425.8143546411775;7434.36807871337;7064.081669382947;7066.681999309941;7068.787936361918;7079.575225486487;7092.184398794205;6867.141607299088;6987.965060174666;6997.090378250999;7185.568865868065;7255.746048888263;6767.695453704497;6790.96604516994;6882;6975.8604827902145;7006.194287656985;6827.590344579341;6948.374646281859;6980.685869135799;6991.230964593992;6993.755218567326;6442.226932768098;6660.433863121689;6677.632730199692;6826.965865170841;6835;5524.751386187711;6734.393904548614;6785.558938669168;6824.556403833735;6948.687309071205;6149.169862068564;6620.139381880324;6812.659367031728;6832.658367081725;6833.555818871758 09:56:28 INFO - PID 1654 | |1;ext.html;779.8557266905623;844.2528362399274;865.273170536749;871.5685735560896;893.2496702769674;39496.40755388668;40348.39477407838;40843.59156408435;40857.38713919286;40861.97845053868;39261;39744.20511589767;39780.403293950614;40119.99700007501;40265.59734402655;8781.517016564087;8931.062238464965;8941.821163576724;8946.86579701305;8951.194392504673;8592.8711069334;8737;8753.824923501557;8759.299256059532;8763.51800650964;8689.435186712843;8725.38922275441;8768.254698350647;8768.868466973001;8772;8643.394962352639;8703.651853925836;8706.738797836044;8736.956315218447;8749.03760586335;8593.398462107654;8636.222739953435;8663.740087797376;8668.30653547721;8674.826503469896;38238.80880595607;38340.61659383426;38406.80796596027;38472;38479.23041539153;3170.3500782339797;3686.8340924658455;3708.796016219107;3716.1267102230954;3723.553173619156;30861.91983280597;30939.38121237585;30976;30978.845105774326;30989;3272.0347497488297;3301.636819949804;3355.74831887607;3357.7145942594907;3378.4763361678733;259081.4091859076;262986;263234;328715;332959;42608.5087021959;46419.5358046422;49010.26484602663;49260.50739492559;49309.2603610939;50215.497845021084;50358.244626330634;50442;50530.74734626377;50545.24182137271;16173.272202750904;16242.675146497004;16292.185390730654;16345.427910023092;16433.506994790176;13331.733365332833;13361.265130417725;13394.598162055154;13410.329483525784;13423.194608323533;12127.272363658209;12129.757404851853;12201.328926908927;12213.633590992284;12274.324912129918;5353.277307563507;5365.892682146413;5385.865353366119;5402.270693456333;5427;999.9550020249106;1006.3458751811235;1013.7566983923953;1066.9626563070256;1079.9946000270106;17563.20965556553;20561.588768224552;23185.973325340994;23401.882990584938;23570;1067.9320679320679;1129.5255992483176;1138.7587529592784;1157.0801213035656;1183.3255044624482;15811.367545298059;16507;16708.74936875948;16960.406385776438;16996.915015425093;347223.26388367894;347848;348480;349636;350070.2496487501;57767.711161444764;58155.8368832621;58365.832683346096;58532.4146758527;58601;1497.9176145312113;1552.5963249555198;1568.1061794777095;1569.7488401855649;1574.2915687940244;29048.12855614335;30556.84721576378;30645.693543065176;30754.71038557011;30881.382372352877;38209.2358152841;38351.80824095861;38491.807540962116;38552.614473854905;38646.6135338643;298095;299813;299858.001419983;300086.9982600292;301851;29450.558241625964;31989.68010319867;32132.518012230303;34106.14734631654;34329.313413731084;33608.99173024813;38871.41692874666;42725.572744273406;43084;43114.13771724611;27966.440671186054;28521.714782852738;28578.285542861595;28791.712082878905;29781;28766.424671506873;28843.5673464894;30023.099307020824;30484.542731859485;30690.772369104987;36059;36279.274414511034;36459;36544;36668.63331366652;28129.71870281271;29853;30286.091417257518;32603.85886493961;32753.836230818695;21239.68140477925;25112;25128.409714257363;25211.49577008486;25279.988800447776 09:56:28 INFO - PID 1654 | |2;jquery.html;489.7037292438075;527.9441117764471;531.9946800531993;542.2841848759634;543.8042304770283;23723.881380593117;24007.39981500463;24272.87863560682;24822.255332340013;24837.50324993499;21842.672359914613;24353.63469547958;25334.733263336853;26247.475050499026;26301.605475917877;6775.695093720782;6869.793906182823;6918.481113916451;6944.4444444444325;6944.444444444458;6480.02799580062;6820.488463365243;6866.485013623973;6867.107276054106;6888.104546408985;6229.844253893643;6582.769603063894;6584.539082264243;6609;6611.867762644744;6564.606123632573;6600.570962887397;6602.438792702626;6606.933930660728;6613.867722645544;6064.241969753781;6095.024796032658;6101;6102.481289090432;6105.511559075263;29385.41229175426;31777.205569860707;32011.83994080038;32061.87783427569;32098.197545061328;2324.151684635113;2360.5986982213067;2374.964375534368;2377.9048838046456;2380.5000949800565;18384.448466546026;20570.5885882283;20579.868107254097;20631.69052464214;20731.067101980447;1972.467433792868;2193;2279.7036385269976;2310.8613483190893;2339.777721116491;203059;203094;208220.95889520607;208637.9568102165;209973.9002609985;13120.862763267582;16029.839701603069;16065;16115.758263626056;16196.75704864428;21010;21260.57478850445;21302;21305.78694213038;21306.147754089663;10579.788404232026;10698.037176653983;10926.726831829268;10939.781204375868;11043.488213544704;10326.070653641209;10411.739706507247;10430.895691043144;10448.477576121164;10493.475326233658;9326.528469955467;9434.622615095457;9445.197158241626;9601.951990240004;9608.991055939026;2261.49116448801;2307.746147923727;2661.921921621746;2746.8489233091973;2768.1418760184406;941;969.5491596407704;984.1536278800226;1014.014921359066;1023.8771347438355;21370.893145534174;21397.67903481449;21625.35123946284;21809.90950452503;22380;991.2565575818137;1012.964546240878;1025.3489034463169;1050.4537640427088;1053.2153545608571;11098.722531936766;11126.833097503544;11227.607033753779;14490.85509144916;15682.529524114294;593166;604526;616594;620433;623525;161595;161710;161988;166816;168361;1281.2824818101892;1297.5328881602804;1298.3508245877063;1310.8033794930645;1311.0887932886742;23134.190303339303;23513.529729405658;24514.774261286508;24655;24767.88544515465;72969.27030729625;74367.62816185884;74925;76864;77295.61352193203;95851;98348;103825;104426.47786761017;104896.47551762192;18038;18796.906015469835;18869.622607548048;18886.244550217838;18903.810961890755;14687.632809179857;15152.924235379194;15204.239788010776;19002.904985474983;19597.824130551642;16051.759223611889;16086.517404477885;16148.11185384768;16250;16413.589660258112;13662.525304648101;13863.584092477242;14049.367778450198;14574.271286435423;14977.625559361104;15542.9222853885;16655.41706040283;16960.57598560046;16982.320707172068;16985;18037.151117369824;18299.72550411718;18562.721559176895;18694.971776552422;18793.90603046976;15208.391664333303;15934.28295726672;16231.756523652391;16307;16353.754693679366 09:56:28 INFO - PID 1654 | |3;mootools.html;1091.3560999010585;1430.4993252361671;1473.4548217159652;1480.1858977562338;1484.3394689363238;12602.054845886549;13978.09142405743;14185.148891066543;14624.853751462482;14671.633209169777;14082.507112251073;14291.857081429183;14311.427542898271;14477;14497.49258775943;2434.2697190842678;2877.784166187539;2938.1626236522598;2947.042211281331;2947.1747910584963;2354.6585745066955;2797.2447439191405;2936.809107408022;2937.2656835791054;2947.778916581265;2430.805535557151;2856.471552762735;2952.1438782752975;2959.1566403574984;2964.762818974488;2930.2820808901743;2932.912012639624;2935.6183696119583;2940.5736168255485;2961.2596850787304;2861.556458748897;2886.033178885076;2889.9133026009254;2911.2285244410036;2913.1697466222026;14149;14155.43378264868;14322.78515822253;14338.856611433961;14349.928250358786;7923.12845586985;8086.878696819553;8115.472494287911;8192.672293108268;8241.917580824234;4968.478309777457;5148.304978827848;5151.150060240056;5241.187615919539;5245.711485868275;1466.4134346261474;1491.3065424577514;1495.4691084665005;1517.7116347893975;1520.7870898074168;21297.36107916765;21414.892925535427;21563.568728625498;21614.56770864574;21614.67577986316;6886.793396198122;6927.099477067949;6956.304369563104;7008.509404341749;7051;14674.633134171518;14854.480093196686;14884.404623814926;14890.478833240784;14895.404183832525;7749.612519374009;7771.72798952034;7774.533527988338;7919;7924;7125.2874712529365;7147.642617869085;7291.270872912666;7354.632268386559;7365.9631701841145;5220.660657057355;5412.945870541323;5416.2688037114485;5423.620346575702;5454.809081682122;1325.377072775813;1417.744805934921;1587.6348439858898;1592.6575786206022;1594.3702237616076;760.2055851635055;832.0296541347452;843.0810416645885;854.6325080215566;867.2714919467552;6702.966485167543;7967.56178410193;8118.309943654737;8378.958105209435;8635.78410539729;991.9107280344804;994.2068700271544;1013.8138378097643;1034.5292891734243;1042.3433237060603;4806.831760888422;4889.315495830587;4891.755412229374;4994.901121753209;5078.873028174251;18701.971391573323;19427.93146376963;19476.902615487114;19486.512837179183;19540.70688939667;18523.814761852478;19119.80880191208;19252.711209331872;19404.2238310466;19438.514037148903;10115.898841011496;10267.845982310271;10289.456581512834;10290.02244786744;10296.124829389586;7614.581198034161;7840.686372545034;8468;8527.360447966303;8756.25571826402;17406;17857.928524288585;18063.909680451514;18121.36575219861;18262.72605910886;17359.739603906204;18151;18462;18593.53516162107;18690.439286821416;11022.889771102185;11194.608188713355;11279;11407.372594507382;11459;13049.608511744664;13182.868171318163;13186.274754888189;13193;13205;8645.654173832976;10114.140298074746;10141.695749127539;10237.539310730886;10267.178625709776;7659.923400766144;10059.547320370748;10264.384136951807;10366.06705396518;10457.26799124054;9514.904850951401;9584.424934503952;9623.845138583414;9627.663031793854;9636.132748052709;6657;6996.370326670421;7022.367986881;7155.356017958408;7246.673899674422;6761.93238067613;6955.652217389212;7023.578585284899;7086.326798954091;7383.741569045058 09:56:28 INFO - PID 1654 | |4;prototype.html;432.3795353667487;457.7390887194301;465.25559105431313;493.47691447066114;495.28184133007136;6598.406143447089;6658.101156343898;6659.800205993822;6663.833404164899;6689.732410703566;6029.6382217067;6200.379962003797;6375.023742639773;6445.258795238549;6450.870982580345;4942.901141977159;4945.208766597345;4959.925601115986;4960.553550180483;4974.552290293873;4902.509749025105;4906.533879281463;4910.656254062217;4957.752112394366;4968.329275547809;4851.805927762885;4858.344123543312;4879.853604391857;4885.340479035338;4893.094777466163;3759.454879042551;4700.952990470086;4884.5603895649565;4895.7062576245535;4906.362172917497;3548.201654627714;3730.309892669865;4306.901740056297;4329.588689074533;4337.609615134653;5505.421930697299;6399.392057754506;6407.199100112486;6414.454771344394;6429;4427.4908385535755;5167.948320516822;5778.59549831512;5787.942120578782;5819.37057623866;6042.637441753509;6586.835329116762;7021.82445438863;7033.628442453723;7047.330503602201;4879.951200487985;4906.975465122688;4911;4929.1374009548545;4944;7119.899629609277;7269.6728647211;7315.743948961761;7354.521956072838;7410.777676669654;4639.816846704069;5024.899502009939;5026;5028.446870844205;5030.144875371196;4344.717593356485;5115.616328775282;5133.332666753298;5172.922406163911;5210.947890521046;5793.1475568747155;5801.6809075500405;5820.272465941757;5832.62087964281;5835.416458354215;6991.475639327071;7021;7028.613426261605;7029.472789540805;7036.542624729376;1623.602217456707;1735.2798588585847;1744.3981826269917;1754.4824276838108;1767.460924418065;1048.9213309001702;1131.3947038334616;1198.4966314148078;1202.9834789602771;1203.7351782607811;5401.810936617199;6369.681316101108;6525.771597994048;6577.14497115372;6624.735010599618;7026.578405295699;7067.89398159028;7111.502194846415;7247.369341898137;7250.818729531698;5985.9401405986255;6076.574639775174;6504.121943537656;6563.803085907431;6635.402813746786;3700.2229531798084;3733.775973441602;3742.4760533525327;3753.935335565428;3756.1173124315765;7451.354769056907;7452;7475.4767166298925;7484.251574842581;7522.811929701692;6110.847228819315;6382.776602818879;7302.200847978173;7398.852022959511;7438.516496427715;6792.72829086831;7127.893081603676;7287.817804554928;7446;7450.329470347587;7200.747973820891;7211.927880721126;7213.278672132745;7222.025026621339;7250.746223882139;5352.652077614943;5822.505087067647;6116.785912493041;6538.248101468392;7030.964845175741;5655.660660360391;7147.928520714726;7215.350618444365;7435.776926692208;7447.702091916262;4406.250937340596;4888.633352498506;4900.730459824601;4930.580900623487;4965.1807451770055;6892.276310987274;7077.398421134056;7206.2793720627515;7240.167380751281;7244.746433874789;6305.369463053658;6320.207875274982;6929.653517324013;6962.582245065312;6985.1617805863625;5467.562594992471;5927.525797936241;6903.412215190635;6950.20072691647;6963.234044255027;5596.405024567978;6742.078507625303;6748.426383757435;6755.6622168892345;6828.317168283331;6917.619530925646;6930.5148639594745;6940.479464040116;6948.722051118101;6961.756338528127;6154.569180157346;6758;6775;6782.28785977485;6799.422049125682 09:56:28 INFO - PID 1654 | |5;yui.html;761.9009528761261;849.834282314948;854.9700760473381;883.0904168706231;884.0893879304318;16220;16340.591485212875;16476.09381484018;16491.505254842345;16505.504834854957;18419.17113729882;19227.903860480677;19354.838709677395;19443.805561944377;19478.41564753056;1039.3971496532013;1051.479517638766;1052.2844465763278;1052.899974502421;1055.0873494427303;1054.741588310861;1058.6241884131139;1058.925875188737;1060.522764755859;1062.0282441565957;966.7003228998998;1022.3559157730656;1097.0839349143475;1098.9395583242917;1099.2195541165781;964.9758756031085;1041.7083216699336;1082.312731415549;1087.396494945305;1092.721356054209;794.1938931984041;810.4650930385976;810.5501446697081;811.6185392865344;815.889854869591;17979.10104494783;18424.90787546067;18625.627487450176;18639.81360186408;19161.61676766471;18417.35539256133;18589.907050464662;18605.255789768395;18641.254349826126;18973.43079707611;15310.540683779505;15337;15348.616284592863;15390.461333853373;15490.30293636789;3562.447820587813;3619.27614477103;3629.0927268182954;3681.871134510306;3690.7231957603017;131617;151920;159222.20388898096;162292;163846;13733;14365.856341436662;14557.344919478437;14610.926945365418;14995.17526536051;27230.591541126898;27712.30719231995;28001;28226.15321540357;28355.58222088887;8285.710000149966;8287.751367458986;8378.664853405795;8381.874271885927;8393.496390216484;4064.166845796605;4091.284025295561;4135.234981528427;4139.110091330378;4145.253854306254;4753.191957367256;4813.989062296887;4861.708297502161;4873.80504779812;4895.437024742129;1067.4449286371043;1105.3423213188141;1122.0406552397747;1122.994385028086;1127.7969965406141;565.8953093677683;674.9730010799512;676.4993904510627;684.674779479753;689.1007235557645;11541.134414918915;12762.978961683044;13088.738225235442;13094.345282735825;13114.868851311365;654.5450911616476;659.4559488422071;674.0832467843728;687.2474640268897;689.4691087862318;5629.408912064257;5740.253767010262;5747.39652336507;5808.448197421239;6034.825870646766;141171.2941435307;144885;149199;150066;152635;112401;118124;119933;120163;125693.7430625682;103702;105041;105195.42206866902;110822.44588777004;112184;10843.186760993114;10865.348079115278;12580;12592.559260425842;12878;40562;46724;47050;48959.510404895496;49496.75251623863;84628;84989;86332;86859.56570217361;92790;18557.072146392897;18607.906960465112;18733.34433294828;18775.436736897915;18942.52643683919;18892.055397230357;18961.620767584845;19401.32095376655;19532.60934781325;20146;17723.73414398811;18512.1669524869;18885.527861803566;18902.905485472485;18927.810721892605;17318.65362692714;17391;17669.646607068044;17696.380626678005;17795.644087118446;16733.414330498374;17745.733813993058;17768.289268429635;17773.555661108574;18160.18279177414;11538;11712;12572.931300839531;12720.68198295013;12731.55439559611;13381;14447.855521444651;14695.779563306773;14766.409343626565;14819.629509262355 09:56:28 INFO - PID 1654 | __end_tp_report 09:56:28 INFO - PID 1654 | __start_cc_report 09:56:28 INFO - PID 1654 | _x_x_mozilla_cycle_collect,383 09:56:28 INFO - PID 1654 | __end_cc_report 09:56:28 INFO - PID 1654 | __startTimestamp1497372988133__endTimestamp 09:56:28 INFO - PID 1654 | 09:56:28 INFO - PID 1654 | ------- Summary: start ------- 09:56:28 INFO - PID 1654 | Number of tests: 6 09:56:28 INFO - PID 1654 | 09:56:28 INFO - PID 1654 | [#0] dojo.html Cycles:165 Average:6493.50 Median:6738.00 stddev:808.79 (12.0%) stddev-sans-first:764.37 09:56:28 INFO - PID 1654 | Values: 3033.6 3797.4 3801.7 3837.8 3859.4 6352.7 6360.0 6374.8 6391.8 6419.9 6507.8 6529.4 6533.1 6533.8 6538.4 5716.7 5756.0 5759.6 5921.0 5970.0 5823.6 5920.6 5955.5 5960.5 5992.9 5576.2 5633.2 5741.4 5806.5 5807.1 5618.7 5715.4 5772.6 5785.8 5808.9 5690.0 5729.7 5732.7 5778.4 5786.7 6412.4 6471.5 6488.6 6493.9 6550.6 7092.2 7110.3 7125.8 7136.2 7137.8 6894.0 6976.7 7006.2 7072.5 7178.2 6586.7 6627.7 6665.4 6673.7 6692.8 7289.2 7352.2 7416.4 7419.1 7436.9 6690.6 6753.1 6802.9 6985.6 7008.1 6998.7 7031.9 7053.6 7195.8 7204.4 7055.0 7059.5 7060.1 7066.6 7082.0 6742.5 7046.8 7094.8 7103.0 7108.6 4775.5 4797.1 5380.2 5608.1 5690.3 4984.3 5080.9 5088.5 5090.8 5094.5 5873.1 6645.9 6655.9 6724.2 6742.2 7195.1 7248.4 7252.5 7262.2 7275.0 5828.5 5854.1 6711.8 6713.9 6738.0 6106.2 6126.0 6135.6 6157.3 6171.5 7112.5 7329.8 7399.4 7428.4 7429.5 5834.5 7213.9 7219.9 7220.1 7230.3 6957.9 7351.6 7411.9 7425.8 7434.4 7064.1 7066.7 7068.8 7079.6 7092.2 6867.1 6988.0 6997.1 7185.6 7255.7 6767.7 6791.0 6882.0 6975.9 7006.2 6827.6 6948.4 6980.7 6991.2 6993.8 6442.2 6660.4 6677.6 6827.0 6835.0 5524.8 6734.4 6785.6 6824.6 6948.7 6149.2 6620.1 6812.7 6832.7 6833.6 09:56:28 INFO - PID 1654 | 09:56:28 INFO - PID 1654 | [#1] ext.html Cycles:180 Average:46553.75 Median:190776.29 stddev:82506.52 (43.2%) stddev-sans-first:82666.40 09:56:28 INFO - PID 1654 | Values: 779.9 844.3 865.3 871.6 893.2 39496.4 40348.4 40843.6 40857.4 40862.0 39261.0 39744.2 39780.4 40120.0 40265.6 8781.5 8931.1 8941.8 8946.9 8951.2 8592.9 8737.0 8753.8 8759.3 8763.5 8689.4 8725.4 8768.3 8768.9 8772.0 8643.4 8703.7 8706.7 8737.0 8749.0 8593.4 8636.2 8663.7 8668.3 8674.8 38238.8 38340.6 38406.8 38472.0 38479.2 3170.4 3686.8 3708.8 3716.1 3723.6 30861.9 30939.4 30976.0 30978.8 30989.0 3272.0 3301.6 3355.7 3357.7 3378.5 259081.4 262986.0 263234.0 328715.0 332959.0 42608.5 46419.5 49010.3 49260.5 49309.3 50215.5 50358.2 50442.0 50530.7 50545.2 16173.3 16242.7 16292.2 16345.4 16433.5 13331.7 13361.3 13394.6 13410.3 13423.2 12127.3 12129.8 12201.3 12213.6 12274.3 5353.3 5365.9 5385.9 5402.3 5427.0 1000.0 1006.3 1013.8 1067.0 1080.0 17563.2 20561.6 23186.0 23401.9 23570.0 1067.9 1129.5 1138.8 1157.1 1183.3 15811.4 16507.0 16708.7 16960.4 16996.9 347223.3 347848.0 348480.0 349636.0 350070.2 57767.7 58155.8 58365.8 58532.4 58601.0 1497.9 1552.6 1568.1 1569.7 1574.3 29048.1 30556.8 30645.7 30754.7 30881.4 38209.2 38351.8 38491.8 38552.6 38646.6 298095.0 299813.0 299858.0 300087.0 301851.0 29450.6 31989.7 32132.5 34106.1 34329.3 33609.0 38871.4 42725.6 43084.0 43114.1 27966.4 28521.7 28578.3 28791.7 29781.0 28766.4 28843.6 30023.1 30484.5 30690.8 36059.0 36279.3 36459.0 36544.0 36668.6 28129.7 29853.0 30286.1 32603.9 32753.8 21239.7 25112.0 25128.4 25211.5 25280.0 09:56:28 INFO - PID 1654 | 09:56:28 INFO - PID 1654 | [#2] jquery.html Cycles:180 Average:42965.26 Median:21384.29 stddev:105761.51 (494.6%) stddev-sans-first:106010.11 09:56:28 INFO - PID 1654 | Values: 489.7 527.9 532.0 542.3 543.8 23723.9 24007.4 24272.9 24822.3 24837.5 21842.7 24353.6 25334.7 26247.5 26301.6 6775.7 6869.8 6918.5 6944.4 6944.4 6480.0 6820.5 6866.5 6867.1 6888.1 6229.8 6582.8 6584.5 6609.0 6611.9 6564.6 6600.6 6602.4 6606.9 6613.9 6064.2 6095.0 6101.0 6102.5 6105.5 29385.4 31777.2 32011.8 32061.9 32098.2 2324.2 2360.6 2375.0 2377.9 2380.5 18384.4 20570.6 20579.9 20631.7 20731.1 1972.5 2193.0 2279.7 2310.9 2339.8 203059.0 203094.0 208221.0 208638.0 209973.9 13120.9 16029.8 16065.0 16115.8 16196.8 21010.0 21260.6 21302.0 21305.8 21306.1 10579.8 10698.0 10926.7 10939.8 11043.5 10326.1 10411.7 10430.9 10448.5 10493.5 9326.5 9434.6 9445.2 9602.0 9609.0 2261.5 2307.7 2661.9 2746.8 2768.1 941.0 969.5 984.2 1014.0 1023.9 21370.9 21397.7 21625.4 21809.9 22380.0 991.3 1013.0 1025.3 1050.5 1053.2 11098.7 11126.8 11227.6 14490.9 15682.5 593166.0 604526.0 616594.0 620433.0 623525.0 161595.0 161710.0 161988.0 166816.0 168361.0 1281.3 1297.5 1298.4 1310.8 1311.1 23134.2 23513.5 24514.8 24655.0 24767.9 72969.3 74367.6 74925.0 76864.0 77295.6 95851.0 98348.0 103825.0 104426.5 104896.5 18038.0 18796.9 18869.6 18886.2 18903.8 14687.6 15152.9 15204.2 19002.9 19597.8 16051.8 16086.5 16148.1 16250.0 16413.6 13662.5 13863.6 14049.4 14574.3 14977.6 15542.9 16655.4 16960.6 16982.3 16985.0 18037.2 18299.7 18562.7 18695.0 18793.9 15208.4 15934.3 16231.8 16307.0 16353.8 09:56:28 INFO - PID 1654 | 09:56:28 INFO - PID 1654 | [#3] mootools.html Cycles:180 Average:8721.27 Median:2859.01 stddev:5852.23 (204.7%) stddev-sans-first:5840.56 09:56:28 INFO - PID 1654 | Values: 1091.4 1430.5 1473.5 1480.2 1484.3 12602.1 13978.1 14185.1 14624.9 14671.6 14082.5 14291.9 14311.4 14477.0 14497.5 2434.3 2877.8 2938.2 2947.0 2947.2 2354.7 2797.2 2936.8 2937.3 2947.8 2430.8 2856.5 2952.1 2959.2 2964.8 2930.3 2932.9 2935.6 2940.6 2961.3 2861.6 2886.0 2889.9 2911.2 2913.2 14149.0 14155.4 14322.8 14338.9 14349.9 7923.1 8086.9 8115.5 8192.7 8241.9 4968.5 5148.3 5151.2 5241.2 5245.7 1466.4 1491.3 1495.5 1517.7 1520.8 21297.4 21414.9 21563.6 21614.6 21614.7 6886.8 6927.1 6956.3 7008.5 7051.0 14674.6 14854.5 14884.4 14890.5 14895.4 7749.6 7771.7 7774.5 7919.0 7924.0 7125.3 7147.6 7291.3 7354.6 7366.0 5220.7 5412.9 5416.3 5423.6 5454.8 1325.4 1417.7 1587.6 1592.7 1594.4 760.2 832.0 843.1 854.6 867.3 6703.0 7967.6 8118.3 8379.0 8635.8 991.9 994.2 1013.8 1034.5 1042.3 4806.8 4889.3 4891.8 4994.9 5078.9 18702.0 19427.9 19476.9 19486.5 19540.7 18523.8 19119.8 19252.7 19404.2 19438.5 10115.9 10267.8 10289.5 10290.0 10296.1 7614.6 7840.7 8468.0 8527.4 8756.3 17406.0 17857.9 18063.9 18121.4 18262.7 17359.7 18151.0 18462.0 18593.5 18690.4 11022.9 11194.6 11279.0 11407.4 11459.0 13049.6 13182.9 13186.3 13193.0 13205.0 8645.7 10114.1 10141.7 10237.5 10267.2 7659.9 10059.5 10264.4 10366.1 10457.3 9514.9 9584.4 9623.8 9627.7 9636.1 6657.0 6996.4 7022.4 7155.4 7246.7 6761.9 6955.7 7023.6 7086.3 7383.7 09:56:28 INFO - PID 1654 | 09:56:28 INFO - PID 1654 | [#4] prototype.html Cycles:180 Average:5662.77 Median:6252.87 stddev:1728.12 (27.6%) stddev-sans-first:1687.79 09:56:28 INFO - PID 1654 | Values: 432.4 457.7 465.3 493.5 495.3 6598.4 6658.1 6659.8 6663.8 6689.7 6029.6 6200.4 6375.0 6445.3 6450.9 4942.9 4945.2 4959.9 4960.6 4974.6 4902.5 4906.5 4910.7 4957.8 4968.3 4851.8 4858.3 4879.9 4885.3 4893.1 3759.5 4701.0 4884.6 4895.7 4906.4 3548.2 3730.3 4306.9 4329.6 4337.6 5505.4 6399.4 6407.2 6414.5 6429.0 4427.5 5167.9 5778.6 5787.9 5819.4 6042.6 6586.8 7021.8 7033.6 7047.3 4880.0 4907.0 4911.0 4929.1 4944.0 7119.9 7269.7 7315.7 7354.5 7410.8 4639.8 5024.9 5026.0 5028.4 5030.1 4344.7 5115.6 5133.3 5172.9 5210.9 5793.1 5801.7 5820.3 5832.6 5835.4 6991.5 7021.0 7028.6 7029.5 7036.5 1623.6 1735.3 1744.4 1754.5 1767.5 1048.9 1131.4 1198.5 1203.0 1203.7 5401.8 6369.7 6525.8 6577.1 6624.7 7026.6 7067.9 7111.5 7247.4 7250.8 5985.9 6076.6 6504.1 6563.8 6635.4 3700.2 3733.8 3742.5 3753.9 3756.1 7451.4 7452.0 7475.5 7484.3 7522.8 6110.8 6382.8 7302.2 7398.9 7438.5 6792.7 7127.9 7287.8 7446.0 7450.3 7200.7 7211.9 7213.3 7222.0 7250.7 5352.7 5822.5 6116.8 6538.2 7031.0 5655.7 7147.9 7215.4 7435.8 7447.7 4406.3 4888.6 4900.7 4930.6 4965.2 6892.3 7077.4 7206.3 7240.2 7244.7 6305.4 6320.2 6929.7 6962.6 6985.2 5467.6 5927.5 6903.4 6950.2 6963.2 5596.4 6742.1 6748.4 6755.7 6828.3 6917.6 6930.5 6940.5 6948.7 6961.8 6154.6 6758.0 6775.0 6782.3 6799.4 09:56:28 INFO - PID 1654 | 09:56:28 INFO - PID 1654 | [#5] yui.html Cycles:180 Average:26959.49 Median:18418.26 stddev:40959.40 (222.4%) stddev-sans-first:41027.07 09:56:28 INFO - PID 1654 | Values: 761.9 849.8 855.0 883.1 884.1 16220.0 16340.6 16476.1 16491.5 16505.5 18419.2 19227.9 19354.8 19443.8 19478.4 1039.4 1051.5 1052.3 1052.9 1055.1 1054.7 1058.6 1058.9 1060.5 1062.0 966.7 1022.4 1097.1 1098.9 1099.2 965.0 1041.7 1082.3 1087.4 1092.7 794.2 810.5 810.6 811.6 815.9 17979.1 18424.9 18625.6 18639.8 19161.6 18417.4 18589.9 18605.3 18641.3 18973.4 15310.5 15337.0 15348.6 15390.5 15490.3 3562.4 3619.3 3629.1 3681.9 3690.7 131617.0 151920.0 159222.2 162292.0 163846.0 13733.0 14365.9 14557.3 14610.9 14995.2 27230.6 27712.3 28001.0 28226.2 28355.6 8285.7 8287.8 8378.7 8381.9 8393.5 4064.2 4091.3 4135.2 4139.1 4145.3 4753.2 4814.0 4861.7 4873.8 4895.4 1067.4 1105.3 1122.0 1123.0 1127.8 565.9 675.0 676.5 684.7 689.1 11541.1 12763.0 13088.7 13094.3 13114.9 654.5 659.5 674.1 687.2 689.5 5629.4 5740.3 5747.4 5808.4 6034.8 141171.3 144885.0 149199.0 150066.0 152635.0 112401.0 118124.0 119933.0 120163.0 125693.7 103702.0 105041.0 105195.4 110822.4 112184.0 10843.2 10865.3 12580.0 12592.6 12878.0 40562.0 46724.0 47050.0 48959.5 49496.8 84628.0 84989.0 86332.0 86859.6 92790.0 18557.1 18607.9 18733.3 18775.4 18942.5 18892.1 18961.6 19401.3 19532.6 20146.0 17723.7 18512.2 18885.5 18902.9 18927.8 17318.7 17391.0 17669.6 17696.4 17795.6 16733.4 17745.7 17768.3 17773.6 18160.2 11538.0 11712.0 12572.9 12720.7 12731.6 13381.0 14447.9 14695.8 14766.4 14819.6 09:56:28 INFO - PID 1654 | -------- Summary: end -------- 09:56:28 INFO - PID 1654 | 09:56:28 INFO - TEST-INFO | 1654: exit 0 09:56:28 INFO - TEST-OK | dromaeo_css | took 1142251ms 09:56:28 INFO - TEST-START | kraken 09:56:28 INFO - operating with platform_type : mac_ 09:56:28 INFO - Initialising browser for kraken test... 09:56:28 INFO - Application command: /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox http://localhost:49232/getInfo.html -foreground -profile /var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/tmpUiSvzJ/profile 09:56:28 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox http://localhost:49232/getInfo.html -foreground) 09:57:01 INFO - TEST-INFO | 1663: killed by SIGTERM 09:57:01 INFO - Browser initialized. 09:57:01 INFO - Running cycle 1/1 for kraken test... 09:57:01 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/tmpUiSvzJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:57:03 INFO - PID 1666 | 2017-06-13 09:57:03.223 plugin-container[1667:10434] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9343, name = 'com.apple.tsm.portname' 09:57:03 INFO - PID 1666 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:57:03 INFO - PID 1666 | 2017-06-13 09:57:03.224 plugin-container[1667:10434] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x481b, name = 'com.apple.CFPasteboardClient' 09:57:03 INFO - PID 1666 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:57:03 INFO - PID 1666 | 2017-06-13 09:57:03.224 plugin-container[1667:10434] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:57:13 INFO - PID 1666 | RSS: Main: 185032704 09:57:13 INFO - PID 1666 | 09:57:16 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49232/tests/kraken/driver.html?testName=audio-beat-detection) 09:57:17 INFO - PID 1666 | RSS: Main: 198451200 09:57:17 INFO - PID 1666 | 09:57:19 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49232/tests/kraken/driver.html?testName=audio-dft) 09:57:20 INFO - PID 1666 | RSS: Main: 207728640 09:57:20 INFO - PID 1666 | 09:57:23 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49232/tests/kraken/driver.html?testName=audio-fft) 09:57:24 INFO - PID 1666 | RSS: Main: 215187456 09:57:24 INFO - PID 1666 | 09:57:26 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49232/tests/kraken/driver.html?testName=audio-oscillator) 09:57:27 INFO - PID 1666 | RSS: Main: 216571904 09:57:27 INFO - PID 1666 | 09:57:29 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49232/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:57:29 INFO - PID 1666 | RSS: Main: 216858624 09:57:29 INFO - PID 1666 | 09:57:32 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49232/tests/kraken/driver.html?testName=imaging-darkroom) 09:57:33 INFO - PID 1666 | RSS: Main: 219430912 09:57:33 INFO - PID 1666 | 09:57:35 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49232/tests/kraken/driver.html?testName=imaging-desaturate) 09:57:36 INFO - PID 1666 | RSS: Main: 219598848 09:57:36 INFO - PID 1666 | 09:57:39 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49232/tests/kraken/driver.html?testName=json-parse-financial) 09:57:39 INFO - PID 1666 | RSS: Main: 219807744 09:57:39 INFO - PID 1666 | 09:57:41 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49232/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:57:42 INFO - PID 1666 | RSS: Main: 218763264 09:57:42 INFO - PID 1666 | 09:57:43 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49232/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:57:44 INFO - PID 1666 | RSS: Main: 218705920 09:57:44 INFO - PID 1666 | 09:57:46 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49232/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:57:47 INFO - PID 1666 | RSS: Main: 218923008 09:57:47 INFO - PID 1666 | 09:57:50 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49232/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:57:50 INFO - PID 1666 | RSS: Main: 219123712 09:57:50 INFO - PID 1666 | 09:57:53 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49232/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:57:54 INFO - PID 1666 | RSS: Main: 219414528 09:57:54 INFO - PID 1666 | 09:57:55 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49232/tests/kraken/driver.html?testName=ai-astar) 09:57:56 INFO - PID 1666 | RSS: Main: 221192192 09:57:56 INFO - PID 1666 | 09:57:56 INFO - PID 1666 | __start_tp_report 09:57:56 INFO - PID 1666 | _x_x_mozilla_page_load 09:57:56 INFO - PID 1666 | _x_x_mozilla_page_load_details 09:57:56 INFO - PID 1666 | |i|pagename|runs| 09:57:56 INFO - PID 1666 | |0;ai-astar;102;115;104;126;155;167;97;155;165;164 09:57:56 INFO - PID 1666 | |1;audio-beat-detection;139;112;148;150;108;128;124;116;149;110 09:57:56 INFO - PID 1666 | |2;audio-dft;199;231;269;208;194;196;196;221;281;206 09:57:56 INFO - PID 1666 | |3;audio-fft;69;84;76;147;61;58;70;86;171;80 09:57:56 INFO - PID 1666 | |4;audio-oscillator;69;67;115;69;67;74;110;70;72;106 09:57:56 INFO - PID 1666 | |5;imaging-gaussian-blur;90;92;89;90;92;89;99;92;89;89 09:57:56 INFO - PID 1666 | |6;imaging-darkroom;95;95;96;96;96;96;98;96;104;95 09:57:56 INFO - PID 1666 | |7;imaging-desaturate;90;90;90;93;92;88;91;94;91;90 09:57:56 INFO - PID 1666 | |8;json-parse-financial;46;54;76;79;76;53;52;51;58;76 09:57:56 INFO - PID 1666 | |9;json-stringify-tinderbox;54;57;58;56;59;55;55;73;69;56 09:57:56 INFO - PID 1666 | |10;stanford-crypto-aes;81;79;101;68;73;102;69;84;102;104 09:57:56 INFO - PID 1666 | |11;stanford-crypto-ccm;131;132;130;131;132;142;138;149;133;179 09:57:56 INFO - PID 1666 | |12;stanford-crypto-pbkdf2;176;175;177;174;172;183;174;176;178;187 09:57:56 INFO - PID 1666 | |13;stanford-crypto-sha256-iterative;65;63;60;64;62;65;64;65;66;66 09:57:56 INFO - PID 1666 | __end_tp_report 09:57:56 INFO - PID 1666 | __start_cc_report 09:57:56 INFO - PID 1666 | _x_x_mozilla_cycle_collect,976 09:57:56 INFO - PID 1666 | __end_cc_report 09:57:56 INFO - PID 1666 | __startTimestamp1497373076182__endTimestamp 09:57:56 INFO - PID 1666 | 09:57:56 INFO - PID 1666 | ------- Summary: start ------- 09:57:56 INFO - PID 1666 | Number of tests: 14 09:57:56 INFO - PID 1666 | 09:57:56 INFO - PID 1666 | [#0] ai-astar Cycles:10 Average:135.00 Median:159.50 stddev:28.94 (18.1%) stddev-sans-first:28.13 09:57:56 INFO - PID 1666 | Values: 102.0 115.0 104.0 126.0 155.0 167.0 97.0 155.0 165.0 164.0 09:57:56 INFO - PID 1666 | 09:57:56 INFO - PID 1666 | [#1] audio-beat-detection Cycles:10 Average:128.40 Median:133.50 stddev:16.95 (12.7%) stddev-sans-first:17.53 09:57:56 INFO - PID 1666 | Values: 139.0 112.0 148.0 150.0 108.0 128.0 124.0 116.0 149.0 110.0 09:57:56 INFO - PID 1666 | 09:57:56 INFO - PID 1666 | [#2] audio-dft Cycles:10 Average:220.10 Median:214.50 stddev:31.36 (14.6%) stddev-sans-first:32.32 09:57:56 INFO - PID 1666 | Values: 199.0 231.0 269.0 208.0 194.0 196.0 196.0 221.0 281.0 206.0 09:57:56 INFO - PID 1666 | 09:57:56 INFO - PID 1666 | [#3] audio-fft Cycles:10 Average:90.20 Median:73.00 stddev:37.81 (51.8%) stddev-sans-first:39.31 09:57:56 INFO - PID 1666 | Values: 69.0 84.0 76.0 147.0 61.0 58.0 70.0 86.0 171.0 80.0 09:57:56 INFO - PID 1666 | 09:57:56 INFO - PID 1666 | [#4] audio-oscillator Cycles:10 Average:81.90 Median:69.00 stddev:19.85 (28.8%) stddev-sans-first:20.49 09:57:56 INFO - PID 1666 | Values: 69.0 67.0 115.0 69.0 67.0 74.0 110.0 70.0 72.0 106.0 09:57:56 INFO - PID 1666 | 09:57:56 INFO - PID 1666 | [#5] imaging-gaussian-blur Cycles:10 Average:91.10 Median:91.00 stddev:3.07 (3.4%) stddev-sans-first:3.23 09:57:56 INFO - PID 1666 | Values: 90.0 92.0 89.0 90.0 92.0 89.0 99.0 92.0 89.0 89.0 09:57:56 INFO - PID 1666 | 09:57:56 INFO - PID 1666 | [#6] imaging-darkroom Cycles:10 Average:96.70 Median:96.00 stddev:2.71 (2.8%) stddev-sans-first:2.80 09:57:56 INFO - PID 1666 | Values: 95.0 95.0 96.0 96.0 96.0 96.0 98.0 96.0 104.0 95.0 09:57:56 INFO - PID 1666 | 09:57:56 INFO - PID 1666 | [#7] imaging-desaturate Cycles:10 Average:90.90 Median:91.00 stddev:1.73 (1.9%) stddev-sans-first:1.80 09:57:56 INFO - PID 1666 | Values: 90.0 90.0 90.0 93.0 92.0 88.0 91.0 94.0 91.0 90.0 09:57:56 INFO - PID 1666 | 09:57:56 INFO - PID 1666 | [#8] json-parse-financial Cycles:10 Average:62.10 Median:67.00 stddev:12.97 (19.4%) stddev-sans-first:12.38 09:57:56 INFO - PID 1666 | Values: 46.0 54.0 76.0 79.0 76.0 53.0 52.0 51.0 58.0 76.0 09:57:56 INFO - PID 1666 | 09:57:56 INFO - PID 1666 | [#9] json-stringify-tinderbox Cycles:10 Average:59.20 Median:57.50 stddev:6.46 (11.2%) stddev-sans-first:6.57 09:57:56 INFO - PID 1666 | Values: 54.0 57.0 58.0 56.0 59.0 55.0 55.0 73.0 69.0 56.0 09:57:56 INFO - PID 1666 | 09:57:56 INFO - PID 1666 | [#10] stanford-crypto-aes Cycles:10 Average:86.30 Median:71.00 stddev:14.61 (20.6%) stddev-sans-first:15.37 09:57:56 INFO - PID 1666 | Values: 81.0 79.0 101.0 68.0 73.0 102.0 69.0 84.0 102.0 104.0 09:57:56 INFO - PID 1666 | 09:57:56 INFO - PID 1666 | [#11] stanford-crypto-ccm Cycles:10 Average:139.70 Median:135.50 stddev:15.09 (11.1%) stddev-sans-first:15.67 09:57:56 INFO - PID 1666 | Values: 131.0 132.0 130.0 131.0 132.0 142.0 138.0 149.0 133.0 179.0 09:57:56 INFO - PID 1666 | 09:57:56 INFO - PID 1666 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.20 Median:176.50 stddev:4.54 (2.6%) stddev-sans-first:4.80 09:57:56 INFO - PID 1666 | Values: 176.0 175.0 177.0 174.0 172.0 183.0 174.0 176.0 178.0 187.0 09:57:56 INFO - PID 1666 | 09:57:56 INFO - PID 1666 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.00 Median:65.00 stddev:1.89 (2.9%) stddev-sans-first:1.96 09:57:56 INFO - PID 1666 | Values: 65.0 63.0 60.0 64.0 62.0 65.0 64.0 65.0 66.0 66.0 09:57:56 INFO - PID 1666 | -------- Summary: end -------- 09:57:56 INFO - PID 1666 | 09:57:56 INFO - TEST-INFO | 1666: exit 0 09:57:56 INFO - TEST-OK | kraken | took 88316ms 09:57:56 INFO - SUITE-END | took 1230s 09:57:57 INFO - Completed test suite (00:20:31) 09:57:57 INFO - javascript benchmark 09:57:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8752.364639876196, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3033.620797400325, 3797.4303854421833, 3801.695864330854, 3837.750546214496, 3859.3632050711626, 6352.650604216767, 6359.968200158993, 6374.840628984278, 6391.776287829927, 6419.90370144447, 6507.8373040674005, 6529.379708927644, 6533.085368048465, 6533.8366540836505, 6538.411542961145, 5716.657000579979, 5756.0, 5759.568032397566, 5921.0, 5969.970150149265, 5823.592348535604, 5920.615160014585, 5955.52355811534, 5960.463558279754, 5992.910106348387, 5576.219329293902, 5633.15502674598, 5741.425857414267, 5806.506446952014, 5807.128930660414, 5618.690971996539, 5715.371309155989, 5772.567057470665, 5785.82642520725, 5808.88382232353, 5690.0, 5729.742161602738, 5732.656040637575, 5778.42215778423, 5786.68173250471, 6412.422881940648, 6471.482281417475, 6488.643124628144, 6493.902591461132, 6550.574212676209, 7092.219855815856, 7110.32451917067, 7125.7505987290715, 7136.215016348197, 7137.82155446118, 6894.0, 6976.686049127801, 7006.229314775371, 7072.5402848814665, 7178.17450993132, 6586.7035983380865, 6627.701753421059, 6665.433438157714, 6673.733050677967, 6692.799216023527, 7289.161746399126, 7352.154502232205, 7416.40668746499, 7419.146798118286, 7436.851262974818, 6690.59856408617, 6753.1220941277315, 6802.897956530656, 6985.5808651481075, 7008.088948436671, 6998.720051197895, 7031.874900015973, 7053.647317634098, 7195.784126476214, 7204.423646108299, 7054.964725176341, 7059.5058345915295, 7060.117485314336, 7066.611336376446, 7081.964590177119, 6742.49431292655, 7046.823829404306, 7094.751683691046, 7102.964485177645, 7108.609026503488, 4775.474697783241, 4797.064572408417, 5380.219868119101, 5608.130739735348, 5690.260266165372, 4984.327115839388, 5080.898382032413, 5088.542031217208, 5090.821821236239, 5094.465081166424, 5873.148393483007, 6645.900311495331, 6655.900161497581, 6724.159480064992, 6742.190937087581, 7195.13658361, 7248.383887369527, 7252.492325537161, 7262.164851042092, 7275.0, 5828.53371730269, 5854.121881717691, 6711.832204194934, 6713.932860671429, 6738.0, 6106.236720409949, 6125.989211780006, 6135.60015773269, 6157.261128664589, 6171.47542458896, 7112.466565007645, 7329.780106596811, 7399.371053460516, 7428.442866784904, 7429.4799364044, 5834.533237341087, 7213.9278607213255, 7219.917012448175, 7220.133583969957, 7230.349268565854, 6957.860842783015, 7351.558906465629, 7411.851762964603, 7425.8143546411775, 7434.36807871337, 7064.081669382947, 7066.681999309941, 7068.787936361918, 7079.575225486487, 7092.184398794205, 6867.141607299088, 6987.965060174666, 6997.090378250999, 7185.568865868065, 7255.746048888263, 6767.695453704497, 6790.96604516994, 6882.0, 6975.8604827902145, 7006.194287656985, 6827.590344579341, 6948.374646281859, 6980.685869135799, 6991.230964593992, 6993.755218567326, 6442.226932768098, 6660.433863121689, 6677.632730199692, 6826.965865170841, 6835.0, 5524.751386187711, 6734.393904548614, 6785.558938669168, 6824.556403833735, 6948.687309071205, 6149.169862068564, 6620.139381880324, 6812.659367031728, 6832.658367081725, 6833.555818871758], "value": 6437.786419736601, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [779.8557266905623, 844.2528362399274, 865.273170536749, 871.5685735560896, 893.2496702769674, 39496.40755388668, 40348.39477407838, 40843.59156408435, 40857.38713919286, 40861.97845053868, 39261.0, 39744.20511589767, 39780.403293950614, 40119.99700007501, 40265.59734402655, 8781.517016564087, 8931.062238464965, 8941.821163576724, 8946.86579701305, 8951.194392504673, 8592.8711069334, 8737.0, 8753.824923501557, 8759.299256059532, 8763.51800650964, 8689.435186712843, 8725.38922275441, 8768.254698350647, 8768.868466973001, 8772.0, 8643.394962352639, 8703.651853925836, 8706.738797836044, 8736.956315218447, 8749.03760586335, 8593.398462107654, 8636.222739953435, 8663.740087797376, 8668.30653547721, 8674.826503469896, 38238.80880595607, 38340.61659383426, 38406.80796596027, 38472.0, 38479.23041539153, 3170.3500782339797, 3686.8340924658455, 3708.796016219107, 3716.1267102230954, 3723.553173619156, 30861.91983280597, 30939.38121237585, 30976.0, 30978.845105774326, 30989.0, 3272.0347497488297, 3301.636819949804, 3355.74831887607, 3357.7145942594907, 3378.4763361678733, 259081.4091859076, 262986.0, 263234.0, 328715.0, 332959.0, 42608.5087021959, 46419.5358046422, 49010.26484602663, 49260.50739492559, 49309.2603610939, 50215.497845021084, 50358.244626330634, 50442.0, 50530.74734626377, 50545.24182137271, 16173.272202750904, 16242.675146497004, 16292.185390730654, 16345.427910023092, 16433.506994790176, 13331.733365332833, 13361.265130417725, 13394.598162055154, 13410.329483525784, 13423.194608323533, 12127.272363658209, 12129.757404851853, 12201.328926908927, 12213.633590992284, 12274.324912129918, 5353.277307563507, 5365.892682146413, 5385.865353366119, 5402.270693456333, 5427.0, 999.9550020249106, 1006.3458751811235, 1013.7566983923953, 1066.9626563070256, 1079.9946000270106, 17563.20965556553, 20561.588768224552, 23185.973325340994, 23401.882990584938, 23570.0, 1067.9320679320679, 1129.5255992483176, 1138.7587529592784, 1157.0801213035656, 1183.3255044624482, 15811.367545298059, 16507.0, 16708.74936875948, 16960.406385776438, 16996.915015425093, 347223.26388367894, 347848.0, 348480.0, 349636.0, 350070.2496487501, 57767.711161444764, 58155.8368832621, 58365.832683346096, 58532.4146758527, 58601.0, 1497.9176145312113, 1552.5963249555198, 1568.1061794777095, 1569.7488401855649, 1574.2915687940244, 29048.12855614335, 30556.84721576378, 30645.693543065176, 30754.71038557011, 30881.382372352877, 38209.2358152841, 38351.80824095861, 38491.807540962116, 38552.614473854905, 38646.6135338643, 298095.0, 299813.0, 299858.001419983, 300086.9982600292, 301851.0, 29450.558241625964, 31989.68010319867, 32132.518012230303, 34106.14734631654, 34329.313413731084, 33608.99173024813, 38871.41692874666, 42725.572744273406, 43084.0, 43114.13771724611, 27966.440671186054, 28521.714782852738, 28578.285542861595, 28791.712082878905, 29781.0, 28766.424671506873, 28843.5673464894, 30023.099307020824, 30484.542731859485, 30690.772369104987, 36059.0, 36279.274414511034, 36459.0, 36544.0, 36668.63331366652, 28129.71870281271, 29853.0, 30286.091417257518, 32603.85886493961, 32753.836230818695, 21239.68140477925, 25112.0, 25128.409714257363, 25211.49577008486, 25279.988800447776], "value": 17953.463641124825, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [489.7037292438075, 527.9441117764471, 531.9946800531993, 542.2841848759634, 543.8042304770283, 23723.881380593117, 24007.39981500463, 24272.87863560682, 24822.255332340013, 24837.50324993499, 21842.672359914613, 24353.63469547958, 25334.733263336853, 26247.475050499026, 26301.605475917877, 6775.695093720782, 6869.793906182823, 6918.481113916451, 6944.4444444444325, 6944.444444444458, 6480.02799580062, 6820.488463365243, 6866.485013623973, 6867.107276054106, 6888.104546408985, 6229.844253893643, 6582.769603063894, 6584.539082264243, 6609.0, 6611.867762644744, 6564.606123632573, 6600.570962887397, 6602.438792702626, 6606.933930660728, 6613.867722645544, 6064.241969753781, 6095.024796032658, 6101.0, 6102.481289090432, 6105.511559075263, 29385.41229175426, 31777.205569860707, 32011.83994080038, 32061.87783427569, 32098.197545061328, 2324.151684635113, 2360.5986982213067, 2374.964375534368, 2377.9048838046456, 2380.5000949800565, 18384.448466546026, 20570.5885882283, 20579.868107254097, 20631.69052464214, 20731.067101980447, 1972.467433792868, 2193.0, 2279.7036385269976, 2310.8613483190893, 2339.777721116491, 203059.0, 203094.0, 208220.95889520607, 208637.9568102165, 209973.9002609985, 13120.862763267582, 16029.839701603069, 16065.0, 16115.758263626056, 16196.75704864428, 21010.0, 21260.57478850445, 21302.0, 21305.78694213038, 21306.147754089663, 10579.788404232026, 10698.037176653983, 10926.726831829268, 10939.781204375868, 11043.488213544704, 10326.070653641209, 10411.739706507247, 10430.895691043144, 10448.477576121164, 10493.475326233658, 9326.528469955467, 9434.622615095457, 9445.197158241626, 9601.951990240004, 9608.991055939026, 2261.49116448801, 2307.746147923727, 2661.921921621746, 2746.8489233091973, 2768.1418760184406, 941.0, 969.5491596407704, 984.1536278800226, 1014.014921359066, 1023.8771347438355, 21370.893145534174, 21397.67903481449, 21625.35123946284, 21809.90950452503, 22380.0, 991.2565575818137, 1012.964546240878, 1025.3489034463169, 1050.4537640427088, 1053.2153545608571, 11098.722531936766, 11126.833097503544, 11227.607033753779, 14490.85509144916, 15682.529524114294, 593166.0, 604526.0, 616594.0, 620433.0, 623525.0, 161595.0, 161710.0, 161988.0, 166816.0, 168361.0, 1281.2824818101892, 1297.5328881602804, 1298.3508245877063, 1310.8033794930645, 1311.0887932886742, 23134.190303339303, 23513.529729405658, 24514.774261286508, 24655.0, 24767.88544515465, 72969.27030729625, 74367.62816185884, 74925.0, 76864.0, 77295.61352193203, 95851.0, 98348.0, 103825.0, 104426.47786761017, 104896.47551762192, 18038.0, 18796.906015469835, 18869.622607548048, 18886.244550217838, 18903.810961890755, 14687.632809179857, 15152.924235379194, 15204.239788010776, 19002.904985474983, 19597.824130551642, 16051.759223611889, 16086.517404477885, 16148.11185384768, 16250.0, 16413.589660258112, 13662.525304648101, 13863.584092477242, 14049.367778450198, 14574.271286435423, 14977.625559361104, 15542.9222853885, 16655.41706040283, 16960.57598560046, 16982.320707172068, 16985.0, 18037.151117369824, 18299.72550411718, 18562.721559176895, 18694.971776552422, 18793.90603046976, 15208.391664333303, 15934.28295726672, 16231.756523652391, 16307.0, 16353.754693679366], "value": 12720.565685093567, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1091.3560999010585, 1430.4993252361671, 1473.4548217159652, 1480.1858977562338, 1484.3394689363238, 12602.054845886549, 13978.09142405743, 14185.148891066543, 14624.853751462482, 14671.633209169777, 14082.507112251073, 14291.857081429183, 14311.427542898271, 14477.0, 14497.49258775943, 2434.2697190842678, 2877.784166187539, 2938.1626236522598, 2947.042211281331, 2947.1747910584963, 2354.6585745066955, 2797.2447439191405, 2936.809107408022, 2937.2656835791054, 2947.778916581265, 2430.805535557151, 2856.471552762735, 2952.1438782752975, 2959.1566403574984, 2964.762818974488, 2930.2820808901743, 2932.912012639624, 2935.6183696119583, 2940.5736168255485, 2961.2596850787304, 2861.556458748897, 2886.033178885076, 2889.9133026009254, 2911.2285244410036, 2913.1697466222026, 14149.0, 14155.43378264868, 14322.78515822253, 14338.856611433961, 14349.928250358786, 7923.12845586985, 8086.878696819553, 8115.472494287911, 8192.672293108268, 8241.917580824234, 4968.478309777457, 5148.304978827848, 5151.150060240056, 5241.187615919539, 5245.711485868275, 1466.4134346261474, 1491.3065424577514, 1495.4691084665005, 1517.7116347893975, 1520.7870898074168, 21297.36107916765, 21414.892925535427, 21563.568728625498, 21614.56770864574, 21614.67577986316, 6886.793396198122, 6927.099477067949, 6956.304369563104, 7008.509404341749, 7051.0, 14674.633134171518, 14854.480093196686, 14884.404623814926, 14890.478833240784, 14895.404183832525, 7749.612519374009, 7771.72798952034, 7774.533527988338, 7919.0, 7924.0, 7125.2874712529365, 7147.642617869085, 7291.270872912666, 7354.632268386559, 7365.9631701841145, 5220.660657057355, 5412.945870541323, 5416.2688037114485, 5423.620346575702, 5454.809081682122, 1325.377072775813, 1417.744805934921, 1587.6348439858898, 1592.6575786206022, 1594.3702237616076, 760.2055851635055, 832.0296541347452, 843.0810416645885, 854.6325080215566, 867.2714919467552, 6702.966485167543, 7967.56178410193, 8118.309943654737, 8378.958105209435, 8635.78410539729, 991.9107280344804, 994.2068700271544, 1013.8138378097643, 1034.5292891734243, 1042.3433237060603, 4806.831760888422, 4889.315495830587, 4891.755412229374, 4994.901121753209, 5078.873028174251, 18701.971391573323, 19427.93146376963, 19476.902615487114, 19486.512837179183, 19540.70688939667, 18523.814761852478, 19119.80880191208, 19252.711209331872, 19404.2238310466, 19438.514037148903, 10115.898841011496, 10267.845982310271, 10289.456581512834, 10290.02244786744, 10296.124829389586, 7614.581198034161, 7840.686372545034, 8468.0, 8527.360447966303, 8756.25571826402, 17406.0, 17857.928524288585, 18063.909680451514, 18121.36575219861, 18262.72605910886, 17359.739603906204, 18151.0, 18462.0, 18593.53516162107, 18690.439286821416, 11022.889771102185, 11194.608188713355, 11279.0, 11407.372594507382, 11459.0, 13049.608511744664, 13182.868171318163, 13186.274754888189, 13193.0, 13205.0, 8645.654173832976, 10114.140298074746, 10141.695749127539, 10237.539310730886, 10267.178625709776, 7659.923400766144, 10059.547320370748, 10264.384136951807, 10366.06705396518, 10457.26799124054, 9514.904850951401, 9584.424934503952, 9623.845138583414, 9627.663031793854, 9636.132748052709, 6657.0, 6996.370326670421, 7022.367986881, 7155.356017958408, 7246.673899674422, 6761.93238067613, 6955.652217389212, 7023.578585284899, 7086.326798954091, 7383.741569045058], "value": 6411.441688448782, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [432.3795353667487, 457.7390887194301, 465.25559105431313, 493.47691447066114, 495.28184133007136, 6598.406143447089, 6658.101156343898, 6659.800205993822, 6663.833404164899, 6689.732410703566, 6029.6382217067, 6200.379962003797, 6375.023742639773, 6445.258795238549, 6450.870982580345, 4942.901141977159, 4945.208766597345, 4959.925601115986, 4960.553550180483, 4974.552290293873, 4902.509749025105, 4906.533879281463, 4910.656254062217, 4957.752112394366, 4968.329275547809, 4851.805927762885, 4858.344123543312, 4879.853604391857, 4885.340479035338, 4893.094777466163, 3759.454879042551, 4700.952990470086, 4884.5603895649565, 4895.7062576245535, 4906.362172917497, 3548.201654627714, 3730.309892669865, 4306.901740056297, 4329.588689074533, 4337.609615134653, 5505.421930697299, 6399.392057754506, 6407.199100112486, 6414.454771344394, 6429.0, 4427.4908385535755, 5167.948320516822, 5778.59549831512, 5787.942120578782, 5819.37057623866, 6042.637441753509, 6586.835329116762, 7021.82445438863, 7033.628442453723, 7047.330503602201, 4879.951200487985, 4906.975465122688, 4911.0, 4929.1374009548545, 4944.0, 7119.899629609277, 7269.6728647211, 7315.743948961761, 7354.521956072838, 7410.777676669654, 4639.816846704069, 5024.899502009939, 5026.0, 5028.446870844205, 5030.144875371196, 4344.717593356485, 5115.616328775282, 5133.332666753298, 5172.922406163911, 5210.947890521046, 5793.1475568747155, 5801.6809075500405, 5820.272465941757, 5832.62087964281, 5835.416458354215, 6991.475639327071, 7021.0, 7028.613426261605, 7029.472789540805, 7036.542624729376, 1623.602217456707, 1735.2798588585847, 1744.3981826269917, 1754.4824276838108, 1767.460924418065, 1048.9213309001702, 1131.3947038334616, 1198.4966314148078, 1202.9834789602771, 1203.7351782607811, 5401.810936617199, 6369.681316101108, 6525.771597994048, 6577.14497115372, 6624.735010599618, 7026.578405295699, 7067.89398159028, 7111.502194846415, 7247.369341898137, 7250.818729531698, 5985.9401405986255, 6076.574639775174, 6504.121943537656, 6563.803085907431, 6635.402813746786, 3700.2229531798084, 3733.775973441602, 3742.4760533525327, 3753.935335565428, 3756.1173124315765, 7451.354769056907, 7452.0, 7475.4767166298925, 7484.251574842581, 7522.811929701692, 6110.847228819315, 6382.776602818879, 7302.200847978173, 7398.852022959511, 7438.516496427715, 6792.72829086831, 7127.893081603676, 7287.817804554928, 7446.0, 7450.329470347587, 7200.747973820891, 7211.927880721126, 7213.278672132745, 7222.025026621339, 7250.746223882139, 5352.652077614943, 5822.505087067647, 6116.785912493041, 6538.248101468392, 7030.964845175741, 5655.660660360391, 7147.928520714726, 7215.350618444365, 7435.776926692208, 7447.702091916262, 4406.250937340596, 4888.633352498506, 4900.730459824601, 4930.580900623487, 4965.1807451770055, 6892.276310987274, 7077.398421134056, 7206.2793720627515, 7240.167380751281, 7244.746433874789, 6305.369463053658, 6320.207875274982, 6929.653517324013, 6962.582245065312, 6985.1617805863625, 5467.562594992471, 5927.525797936241, 6903.412215190635, 6950.20072691647, 6963.234044255027, 5596.405024567978, 6742.078507625303, 6748.426383757435, 6755.6622168892345, 6828.317168283331, 6917.619530925646, 6930.5148639594745, 6940.479464040116, 6948.722051118101, 6961.756338528127, 6154.569180157346, 6758.0, 6775.0, 6782.28785977485, 6799.422049125682], "value": 5142.763082788492, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [761.9009528761261, 849.834282314948, 854.9700760473381, 883.0904168706231, 884.0893879304318, 16220.0, 16340.591485212875, 16476.09381484018, 16491.505254842345, 16505.504834854957, 18419.17113729882, 19227.903860480677, 19354.838709677395, 19443.805561944377, 19478.41564753056, 1039.3971496532013, 1051.479517638766, 1052.2844465763278, 1052.899974502421, 1055.0873494427303, 1054.741588310861, 1058.6241884131139, 1058.925875188737, 1060.522764755859, 1062.0282441565957, 966.7003228998998, 1022.3559157730656, 1097.0839349143475, 1098.9395583242917, 1099.2195541165781, 964.9758756031085, 1041.7083216699336, 1082.312731415549, 1087.396494945305, 1092.721356054209, 794.1938931984041, 810.4650930385976, 810.5501446697081, 811.6185392865344, 815.889854869591, 17979.10104494783, 18424.90787546067, 18625.627487450176, 18639.81360186408, 19161.61676766471, 18417.35539256133, 18589.907050464662, 18605.255789768395, 18641.254349826126, 18973.43079707611, 15310.540683779505, 15337.0, 15348.616284592863, 15390.461333853373, 15490.30293636789, 3562.447820587813, 3619.27614477103, 3629.0927268182954, 3681.871134510306, 3690.7231957603017, 131617.0, 151920.0, 159222.20388898096, 162292.0, 163846.0, 13733.0, 14365.856341436662, 14557.344919478437, 14610.926945365418, 14995.17526536051, 27230.591541126898, 27712.30719231995, 28001.0, 28226.15321540357, 28355.58222088887, 8285.710000149966, 8287.751367458986, 8378.664853405795, 8381.874271885927, 8393.496390216484, 4064.166845796605, 4091.284025295561, 4135.234981528427, 4139.110091330378, 4145.253854306254, 4753.191957367256, 4813.989062296887, 4861.708297502161, 4873.80504779812, 4895.437024742129, 1067.4449286371043, 1105.3423213188141, 1122.0406552397747, 1122.994385028086, 1127.7969965406141, 565.8953093677683, 674.9730010799512, 676.4993904510627, 684.674779479753, 689.1007235557645, 11541.134414918915, 12762.978961683044, 13088.738225235442, 13094.345282735825, 13114.868851311365, 654.5450911616476, 659.4559488422071, 674.0832467843728, 687.2474640268897, 689.4691087862318, 5629.408912064257, 5740.253767010262, 5747.39652336507, 5808.448197421239, 6034.825870646766, 141171.2941435307, 144885.0, 149199.0, 150066.0, 152635.0, 112401.0, 118124.0, 119933.0, 120163.0, 125693.7430625682, 103702.0, 105041.0, 105195.42206866902, 110822.44588777004, 112184.0, 10843.186760993114, 10865.348079115278, 12580.0, 12592.559260425842, 12878.0, 40562.0, 46724.0, 47050.0, 48959.510404895496, 49496.75251623863, 84628.0, 84989.0, 86332.0, 86859.56570217361, 92790.0, 18557.072146392897, 18607.906960465112, 18733.34433294828, 18775.436736897915, 18942.52643683919, 18892.055397230357, 18961.620767584845, 19401.32095376655, 19532.60934781325, 20146.0, 17723.73414398811, 18512.1669524869, 18885.527861803566, 18902.905485472485, 18927.810721892605, 17318.65362692714, 17391.0, 17669.646607068044, 17696.380626678005, 17795.644087118446, 16733.414330498374, 17745.733813993058, 17768.289268429635, 17773.555661108574, 18160.18279177414, 11538.0, 11712.0, 12572.931300839531, 12720.68198295013, 12731.55439559611, 13381.0, 14447.855521444651, 14695.779563306773, 14766.409343626565, 14819.629509262355], "value": 9272.180554482598, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1522.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 115.0, 104.0, 126.0, 155.0, 167.0, 97.0, 155.0, 165.0, 164.0], "value": 135.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 112.0, 148.0, 150.0, 108.0, 128.0, 124.0, 116.0, 149.0, 110.0], "value": 128.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 231.0, 269.0, 208.0, 194.0, 196.0, 196.0, 221.0, 281.0, 206.0], "value": 220.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 84.0, 76.0, 147.0, 61.0, 58.0, 70.0, 86.0, 171.0, 80.0], "value": 90.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 115.0, 69.0, 67.0, 74.0, 110.0, 70.0, 72.0, 106.0], "value": 81.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 92.0, 89.0, 90.0, 92.0, 89.0, 99.0, 92.0, 89.0, 89.0], "value": 91.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 96.0, 96.0, 96.0, 98.0, 96.0, 104.0, 95.0], "value": 96.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 90.0, 90.0, 93.0, 92.0, 88.0, 91.0, 94.0, 91.0, 90.0], "value": 90.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [46.0, 54.0, 76.0, 79.0, 76.0, 53.0, 52.0, 51.0, 58.0, 76.0], "value": 62.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 57.0, 58.0, 56.0, 59.0, 55.0, 55.0, 73.0, 69.0, 56.0], "value": 59.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 79.0, 101.0, 68.0, 73.0, 102.0, 69.0, 84.0, 102.0, 104.0], "value": 86.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 132.0, 130.0, 131.0, 132.0, 142.0, 138.0, 149.0, 133.0, 179.0], "value": 139.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 175.0, 177.0, 174.0, 172.0, 183.0, 174.0, 176.0, 178.0, 187.0], "value": 177.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 63.0, 60.0, 64.0, 62.0, 65.0, 64.0, 65.0, 66.0, 66.0], "value": 64.0, "unit": "score"}]}]} 09:57:57 INFO - Return code: 0 09:57:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:57:57 INFO - # TBPL SUCCESS # 09:57:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:57:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:57:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:57:57 INFO - 2017-06-13 09:57:57.600 screenresolution[1673:10832] starting screenresolution argv=screenresolution get 09:57:57 INFO - 2017-06-13 09:57:57.602 screenresolution[1673:10832] Display 0: 1600x1200x32@60 09:57:57 INFO - 2017-06-13 09:57:57.608 screenresolution[1674:10835] starting screenresolution argv=screenresolution list 09:57:57 INFO - Available Modes on Display 0 09:57:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:57:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:57:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:57:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:57:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:57:57 INFO - Intel Iris: 09:57:57 INFO - Chipset Model: Intel Iris 09:57:57 INFO - Type: GPU 09:57:57 INFO - Bus: Built-In 09:57:57 INFO - VRAM (Dynamic, Max): 1536 MB 09:57:57 INFO - Vendor: Intel (0x8086) 09:57:57 INFO - Device ID: 0x0a2e 09:57:57 INFO - Revision ID: 0x0009 09:57:57 INFO - Displays: 09:57:57 INFO - Display: 09:57:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:57:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:57:57 INFO - Display Serial Number: 1600x1200 60 09:57:57 INFO - Main Display: Yes 09:57:57 INFO - Mirror: Off 09:57:57 INFO - Online: Yes 09:57:57 INFO - Rotation: Supported 09:57:57 INFO - Return code: 0 09:57:57 INFO - Running post-action listener: _resource_record_post_action 09:57:57 INFO - [mozharness: 2017-06-13 16:57:57.845011Z] Finished run-tests step (success) 09:57:57 INFO - Running post-run listener: _resource_record_post_run 09:57:57 INFO - Total resource usage - Wall time: 1247s; CPU: 53.0%; Read bytes: 40684032; Write bytes: 472244736; Read time: 266; Write time: 1533 09:57:57 INFO - TinderboxPrint: CPU usage
53.0% 09:57:57 INFO - TinderboxPrint: I/O read bytes / time
40,684,032 / 266 09:57:57 INFO - TinderboxPrint: I/O write bytes / time
472,244,736 / 1,533 09:57:57 INFO - TinderboxPrint: CPU idle
2,344.8 (47.0%) 09:57:57 INFO - TinderboxPrint: CPU system
1,011.1 (20.3%) 09:57:57 INFO - TinderboxPrint: CPU user
1,633.8 (32.7%) 09:57:57 INFO - TinderboxPrint: Swap in / out
194,490,368 / 0 09:57:57 INFO - install - Wall time: 16s; CPU: 54.0%; Read bytes: 154647040; Write bytes: 157652992; Read time: 12111; Write time: 345 09:57:57 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 09:57:57 INFO - run-tests - Wall time: 1232s; CPU: 53.0%; Read bytes: 36424192; Write bytes: 313190912; Read time: 237; Write time: 1184 09:57:58 INFO - Running post-run listener: _upload_blobber_files 09:57:58 INFO - Blob upload gear active. 09:57:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:57:58 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 09:57:58 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', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:57:58 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 Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:57:58 INFO - (blobuploader) - INFO - Open directory for files ... 09:57:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:57:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:57:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:57:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:57:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:57:59 INFO - (blobuploader) - INFO - Done attempting. 09:57:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:57:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:57:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:58:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:58:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:58:00 INFO - (blobuploader) - INFO - Done attempting. 09:58:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:58:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:58:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:58:01 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:58:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:58:01 INFO - (blobuploader) - INFO - Done attempting. 09:58:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:58:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:58:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:58:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:58:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:58:03 INFO - (blobuploader) - INFO - Done attempting. 09:58:03 INFO - (blobuploader) - INFO - Iteration through files over. 09:58:03 INFO - Return code: 0 09:58:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:58:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:58:03 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3b4e791d4c61d998b1c3b818189b64c58b9435fdb59eb8d1e556d9ec966515597cf32050ab745aae151dd8100beb148aff5ced93de23b1296e1b1d668104de08", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0a28a36f5bc30e4b2549b3bdc13515b0f1ed4cf938aab853b1eed24c0a117f730e61c7697d3df0b16e26820ed5abfc376d5e51c9cecc99374cc7a6261771fabe", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b2eaad38eb1b9e84ae18806d0d4e72f48558f5c2d94d3ba5cddb716354f835d496e6b05b8f194c9ec0bb1700dcedd2ff3b9e52bc4a44fdc11300b63ba6bdf7a9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cffa087e3d1147c419fa60241302e811a45cc643fcd8d2579e63091664a219f81340a8bfd70f569d3ea46e05a11a7d203c4e300ebd3533c9d1ef564f71b31ccf"} 09:58:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:58:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:58:03 INFO - Contents: 09:58:03 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3b4e791d4c61d998b1c3b818189b64c58b9435fdb59eb8d1e556d9ec966515597cf32050ab745aae151dd8100beb148aff5ced93de23b1296e1b1d668104de08", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0a28a36f5bc30e4b2549b3bdc13515b0f1ed4cf938aab853b1eed24c0a117f730e61c7697d3df0b16e26820ed5abfc376d5e51c9cecc99374cc7a6261771fabe", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b2eaad38eb1b9e84ae18806d0d4e72f48558f5c2d94d3ba5cddb716354f835d496e6b05b8f194c9ec0bb1700dcedd2ff3b9e52bc4a44fdc11300b63ba6bdf7a9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cffa087e3d1147c419fa60241302e811a45cc643fcd8d2579e63091664a219f81340a8bfd70f569d3ea46e05a11a7d203c4e300ebd3533c9d1ef564f71b31ccf"} 09:58:03 INFO - Running post-run listener: copy_logs_to_upload_dir 09:58:03 INFO - Copying logs to upload dir... 09:58:03 INFO - mkdir: /builds/slave/test/build/upload/logs 09:58:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1329.488705 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 9 secs) (at 2017-06-13 09:58:03.947981) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-13 09:58:03.951336) ========= 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: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.gHtpHGBQ7G/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test/properties RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.WBSGcwmklB/Listeners TMPDIR=/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3b4e791d4c61d998b1c3b818189b64c58b9435fdb59eb8d1e556d9ec966515597cf32050ab745aae151dd8100beb148aff5ced93de23b1296e1b1d668104de08", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0a28a36f5bc30e4b2549b3bdc13515b0f1ed4cf938aab853b1eed24c0a117f730e61c7697d3df0b16e26820ed5abfc376d5e51c9cecc99374cc7a6261771fabe", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b2eaad38eb1b9e84ae18806d0d4e72f48558f5c2d94d3ba5cddb716354f835d496e6b05b8f194c9ec0bb1700dcedd2ff3b9e52bc4a44fdc11300b63ba6bdf7a9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cffa087e3d1147c419fa60241302e811a45cc643fcd8d2579e63091664a219f81340a8bfd70f569d3ea46e05a11a7d203c4e300ebd3533c9d1ef564f71b31ccf"} build_url:https://queue.taskcluster.net/v1/task/bdSLEiAYSCufuGbqsWsdJQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011278 build_url: 'https://queue.taskcluster.net/v1/task/bdSLEiAYSCufuGbqsWsdJQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3b4e791d4c61d998b1c3b818189b64c58b9435fdb59eb8d1e556d9ec966515597cf32050ab745aae151dd8100beb148aff5ced93de23b1296e1b1d668104de08", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0a28a36f5bc30e4b2549b3bdc13515b0f1ed4cf938aab853b1eed24c0a117f730e61c7697d3df0b16e26820ed5abfc376d5e51c9cecc99374cc7a6261771fabe", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b2eaad38eb1b9e84ae18806d0d4e72f48558f5c2d94d3ba5cddb716354f835d496e6b05b8f194c9ec0bb1700dcedd2ff3b9e52bc4a44fdc11300b63ba6bdf7a9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cffa087e3d1147c419fa60241302e811a45cc643fcd8d2579e63091664a219f81340a8bfd70f569d3ea46e05a11a7d203c4e300ebd3533c9d1ef564f71b31ccf"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-13 09:58:03.984770) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 09:58:03.985286) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.gHtpHGBQ7G/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.WBSGcwmklB/Listeners TMPDIR=/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005289 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 09:58:04.034483) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-13 09:58:04.034918) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-13 09:58:04.035213) ========= ========= Total master_lag: 0.18 =========