builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0350 starttime: 1497303983.95 results: success (0) revision: e3638c5119ed03dcd6ad243f99b08251194e375b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-12 14:46:23.966674) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-12 14:46:23.967170) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-12 14:46:23.967448) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-12 14:46:24.092349) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 14:46:24.094314) ========= 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.FJiNsBXElg/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.plOTrPUy6w/Listeners TMPDIR=/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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-12 14:46:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 23.6M=0s 2017-06-12 14:46:24 (23.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.518276 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 14:46:24.639398) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 14:46:24.639765) ========= 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.FJiNsBXElg/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.plOTrPUy6w/Listeners TMPDIR=/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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.026154 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 14:46:24.687105) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-12 14:46:24.687430) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev e3638c5119ed03dcd6ad243f99b08251194e375b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev e3638c5119ed03dcd6ad243f99b08251194e375b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.FJiNsBXElg/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.plOTrPUy6w/Listeners TMPDIR=/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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-12 14:46:25,057 truncating revision to first 12 chars 2017-06-12 14:46:25,057 Setting DEBUG logging. 2017-06-12 14:46:25,057 attempt 1/10 2017-06-12 14:46:25,057 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/e3638c5119ed?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-12 14:46:26,015 unpacking tar archive at: mozilla-central-e3638c5119ed/testing/mozharness/ program finished with exit code 0 elapsedTime=1.249373 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-12 14:46:25.957822) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-12 14:46:25.958122) ========= script_repo_revision: e3638c5119ed03dcd6ad243f99b08251194e375b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-12 14:46:25.958486) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-12 14:46:25.958759) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-12 14:46:25.977039) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 17 secs) (at 2017-06-12 14:46:25.977394) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.FJiNsBXElg/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.plOTrPUy6w/Listeners TMPDIR=/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 14:46:26 INFO - MultiFileLogger online at 20170612 14:46:26 in /builds/slave/test 14:46:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 14:46:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:46:26 INFO - {'append_to_log': False, 14:46:26 INFO - 'base_work_dir': '/builds/slave/test', 14:46:26 INFO - 'blob_upload_branch': 'Firefox', 14:46:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:46:26 INFO - 'branch': 'Firefox', 14:46:26 INFO - 'buildbot_json_path': 'buildprops.json', 14:46:26 INFO - 'config_files': ('talos/mac_config.py',), 14:46:26 INFO - 'default_actions': ('clobber', 14:46:26 INFO - 'read-buildbot-config', 14:46:26 INFO - 'download-and-extract', 14:46:26 INFO - 'populate-webroot', 14:46:26 INFO - 'create-virtualenv', 14:46:26 INFO - 'install', 14:46:26 INFO - 'setup-mitmproxy', 14:46:26 INFO - 'run-tests'), 14:46:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:46:26 INFO - 'download_minidump_stackwalk': True, 14:46:26 INFO - 'download_symbols': 'ondemand', 14:46:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:46:26 INFO - 'tooltool.py': '/tools/tooltool.py', 14:46:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:46:26 INFO - '/tools/misc-python/virtualenv.py')}, 14:46:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:46:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:46:26 INFO - 'gecko_profile': False, 14:46:26 INFO - 'gecko_profile_interval': 0, 14:46:26 INFO - 'installer_path': 'installer.exe', 14:46:26 INFO - 'log_level': 'info', 14:46:26 INFO - 'log_name': 'talos', 14:46:26 INFO - 'log_to_console': True, 14:46:26 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:46:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:46:26 INFO - 'opt_config_files': (), 14:46:26 INFO - 'pip_index': False, 14:46:26 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:46:26 INFO - 'cmd': ('bash', 14:46:26 INFO - '-c', 14:46:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:46:26 INFO - 'enabled': True, 14:46:26 INFO - 'halt_on_failure': False, 14:46:26 INFO - 'name': 'check_screen_resolution'},), 14:46:26 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:46:26 INFO - 'cmd': ('bash', 14:46:26 INFO - '-c', 14:46:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:46:26 INFO - 'enabled': True, 14:46:26 INFO - 'halt_on_failure': False, 14:46:26 INFO - 'name': 'check_screen_resolution'},), 14:46:26 INFO - 'run_cmd_checks_enabled': True, 14:46:26 INFO - 'suite': 'dromaeojs-e10s', 14:46:26 INFO - 'system_bits': '32', 14:46:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:46:26 INFO - 'title': 't-yosemite-r7-0350', 14:46:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:46:26 INFO - 'use_talos_json': True, 14:46:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:46:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:46:26 INFO - 'work_dir': 'build'} 14:46:26 INFO - [mozharness: 2017-06-12 21:46:26.460169Z] Running clobber step. 14:46:26 INFO - Running pre-action listener: _resource_record_pre_action 14:46:26 INFO - Running main action method: clobber 14:46:26 INFO - rmtree: /builds/slave/test/build 14:46:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:46:28 INFO - Running post-action listener: _resource_record_post_action 14:46:28 INFO - [mozharness: 2017-06-12 21:46:28.926411Z] Finished clobber step (success) 14:46:28 INFO - [mozharness: 2017-06-12 21:46:28.926547Z] Running read-buildbot-config step. 14:46:28 INFO - Running pre-action listener: _resource_record_pre_action 14:46:28 INFO - Running main action method: read_buildbot_config 14:46:28 INFO - Using buildbot properties: 14:46:28 INFO - { 14:46:28 INFO - "project": "", 14:46:28 INFO - "product": "firefox", 14:46:28 INFO - "who": "kwierso@gmail.com", 14:46:28 INFO - "installer_path": "public/build/firefox-56.0a1.en-US.mac.dmg", 14:46:28 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 14:46:28 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 14:46:28 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 14:46:28 INFO - "stage_platform": "macosx64", 14:46:28 INFO - "basedir": "/builds/slave/test", 14:46:28 INFO - "buildnumber": 93, 14:46:28 INFO - "platform": "yosemite_r7", 14:46:28 INFO - "parent_task_id": "RMESVxJzSV6NXsE8FceuUw", 14:46:28 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 14:46:28 INFO - "slavebuilddir": "test", 14:46:28 INFO - "taskId": "Ho0j4yECQBmgCDYbrI6qDQ", 14:46:28 INFO - "branch": "mozilla-central", 14:46:28 INFO - "script_repo_revision": "production", 14:46:28 INFO - "revision": "e3638c5119ed03dcd6ad243f99b08251194e375b", 14:46:28 INFO - "slavename": "t-yosemite-r7-0350", 14:46:28 INFO - "repo_path": "mozilla-central" 14:46:28 INFO - } 14:46:28 INFO - Finding installer, test and symbols from parent task. 14:46:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ho0j4yECQBmgCDYbrI6qDQ'}, attempt #1 14:46:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ho0j4yECQBmgCDYbrI6qDQ'}, attempt #1 14:46:30 INFO - Task dependencies: dQCX4QutSS6h3L7E0fUptQ RMESVxJzSV6NXsE8FceuUw 14:46:30 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RMESVxJzSV6NXsE8FceuUw'}, attempt #1 14:46:30 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/RMESVxJzSV6NXsE8FceuUw/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg 14:46:30 INFO - Set test_url: https://queue.taskcluster.net/v1/task/RMESVxJzSV6NXsE8FceuUw/artifacts/public/build/test_packages.json 14:46:30 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/RMESVxJzSV6NXsE8FceuUw/artifacts/public/build/target.crashreporter-symbols.zip 14:46:30 INFO - Running post-action listener: _resource_record_post_action 14:46:30 INFO - [mozharness: 2017-06-12 21:46:30.670262Z] Finished read-buildbot-config step (success) 14:46:30 INFO - [mozharness: 2017-06-12 21:46:30.670389Z] Running download-and-extract step. 14:46:30 INFO - Running pre-action listener: _resource_record_pre_action 14:46:30 INFO - Running main action method: download_and_extract 14:46:30 INFO - mkdir: /builds/slave/test/build/tests 14:46:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:46:30 INFO - trying https://queue.taskcluster.net/v1/task/RMESVxJzSV6NXsE8FceuUw/artifacts/public/build/firefox-56.0a1.en-US.mac.test_packages.json 14:46:30 INFO - Downloading https://queue.taskcluster.net/v1/task/RMESVxJzSV6NXsE8FceuUw/artifacts/public/build/firefox-56.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-56.0a1.en-US.mac.test_packages.json 14:46:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RMESVxJzSV6NXsE8FceuUw/artifacts/public/build/firefox-56.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-56.0a1.en-US.mac.test_packages.json'}, attempt #1 14:46:31 INFO - Downloaded 1328 bytes. 14:46:31 INFO - Reading from file /builds/slave/test/build/firefox-56.0a1.en-US.mac.test_packages.json 14:46:31 INFO - Using the following test package requirements: 14:46:31 INFO - {u'awsy': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 14:46:31 INFO - u'firefox-56.0a1.en-US.mac.awsy.tests.zip'], 14:46:31 INFO - u'common': [u'firefox-56.0a1.en-US.mac.common.tests.zip'], 14:46:31 INFO - u'cppunittest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 14:46:31 INFO - u'firefox-56.0a1.en-US.mac.cppunittest.tests.zip'], 14:46:31 INFO - u'gtest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 14:46:31 INFO - u'firefox-56.0a1.en-US.mac.gtest.tests.zip'], 14:46:31 INFO - u'jittest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 14:46:31 INFO - u'jsshell-mac.zip'], 14:46:31 INFO - u'mochitest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 14:46:31 INFO - u'firefox-56.0a1.en-US.mac.mochitest.tests.zip'], 14:46:31 INFO - u'mozbase': [u'firefox-56.0a1.en-US.mac.common.tests.zip'], 14:46:31 INFO - u'reftest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 14:46:31 INFO - u'firefox-56.0a1.en-US.mac.reftest.tests.zip'], 14:46:31 INFO - u'talos': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 14:46:31 INFO - u'firefox-56.0a1.en-US.mac.talos.tests.zip'], 14:46:31 INFO - u'web-platform': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 14:46:31 INFO - u'firefox-56.0a1.en-US.mac.web-platform.tests.tar.gz'], 14:46:31 INFO - u'xpcshell': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 14:46:31 INFO - u'firefox-56.0a1.en-US.mac.xpcshell.tests.zip']} 14:46:31 INFO - Downloading packages: [u'firefox-56.0a1.en-US.mac.common.tests.zip'] for test suite category: common 14:46:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RMESVxJzSV6NXsE8FceuUw/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip 14:46:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RMESVxJzSV6NXsE8FceuUw/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:46:31 INFO - Fetch https://queue.taskcluster.net/v1/task/RMESVxJzSV6NXsE8FceuUw/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip into memory 14:46:33 INFO - Content-Length response header: 59541361 14:46:33 INFO - Bytes received: 59541361 14:46:52 INFO - Downloading packages: [u'firefox-56.0a1.en-US.mac.common.tests.zip', u'firefox-56.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 14:46:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RMESVxJzSV6NXsE8FceuUw/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip 14:46:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RMESVxJzSV6NXsE8FceuUw/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:46:52 INFO - Fetch https://queue.taskcluster.net/v1/task/RMESVxJzSV6NXsE8FceuUw/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip into memory 14:46:53 INFO - Content-Length response header: 59541361 14:46:53 INFO - Bytes received: 59541361 14:47:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RMESVxJzSV6NXsE8FceuUw/artifacts/public/build/firefox-56.0a1.en-US.mac.talos.tests.zip 14:47:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RMESVxJzSV6NXsE8FceuUw/artifacts/public/build/firefox-56.0a1.en-US.mac.talos.tests.zip'}, attempt #1 14:47:10 INFO - Fetch https://queue.taskcluster.net/v1/task/RMESVxJzSV6NXsE8FceuUw/artifacts/public/build/firefox-56.0a1.en-US.mac.talos.tests.zip into memory 14:47:11 INFO - Content-Length response header: 14450010 14:47:11 INFO - Bytes received: 14450010 14:47:12 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')]} 14:47:12 INFO - trying https://queue.taskcluster.net/v1/task/RMESVxJzSV6NXsE8FceuUw/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg 14:47:12 INFO - Downloading https://queue.taskcluster.net/v1/task/RMESVxJzSV6NXsE8FceuUw/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg 14:47:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RMESVxJzSV6NXsE8FceuUw/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg'}, attempt #1 14:47:14 INFO - Downloaded 69310068 bytes. 14:47:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RMESVxJzSV6NXsE8FceuUw/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg 14:47:14 INFO - mkdir: /builds/slave/test/properties 14:47:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:47:14 INFO - Writing to file /builds/slave/test/properties/build_url 14:47:14 INFO - Contents: 14:47:14 INFO - build_url:https://queue.taskcluster.net/v1/task/RMESVxJzSV6NXsE8FceuUw/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg 14:47:14 INFO - Running post-action listener: _resource_record_post_action 14:47:14 INFO - Running post-action listener: set_extra_try_arguments 14:47:14 INFO - [mozharness: 2017-06-12 21:47:14.099810Z] Finished download-and-extract step (success) 14:47:14 INFO - [mozharness: 2017-06-12 21:47:14.099953Z] Running populate-webroot step. 14:47:14 INFO - Running pre-action listener: _resource_record_pre_action 14:47:14 INFO - Running main action method: populate_webroot 14:47:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:47:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:47:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:47:14 INFO - '--tppagecycles', 14:47:14 INFO - '7'], 14:47:14 INFO - 'tests': ['tsvgm']}}, 14:47:14 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:47:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:47:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:47:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:47:14 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 14:47:14 INFO - 'talos_options': ['--disable-e10s'], 14:47:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:47:14 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:47:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:47:14 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 14:47:14 INFO - 'talos_options': ['--disable-e10s'], 14:47:14 INFO - 'tests': ['damp', 'tps']}, 14:47:14 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:47:14 INFO - 'tests': ['damp', 'tps']}, 14:47:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:47:14 INFO - 'tests': ['dromaeo_dom']}, 14:47:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:47:14 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:47:14 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 14:47:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 14:47:14 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 14:47:14 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 14:47:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:47:14 INFO - 'tests': ['a11yr', 14:47:14 INFO - 'ts_paint', 14:47:14 INFO - 'tpaint', 14:47:14 INFO - 'sessionrestore', 14:47:14 INFO - 'sessionrestore_no_auto_restore', 14:47:14 INFO - 'tabpaint']}, 14:47:14 INFO - 'other-e10s': {'tests': ['a11yr', 14:47:14 INFO - 'ts_paint', 14:47:14 INFO - 'tpaint', 14:47:14 INFO - 'sessionrestore', 14:47:14 INFO - 'sessionrestore_no_auto_restore', 14:47:14 INFO - 'tabpaint']}, 14:47:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:47:14 INFO - 'ts_paint', 14:47:14 INFO - 'tpaint', 14:47:14 INFO - 'sessionrestore', 14:47:14 INFO - 'sessionrestore_no_auto_restore', 14:47:14 INFO - 'tabpaint']}, 14:47:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:47:14 INFO - 'ts_paint', 14:47:14 INFO - 'tpaint', 14:47:14 INFO - 'sessionrestore', 14:47:14 INFO - 'sessionrestore_no_auto_restore', 14:47:14 INFO - 'tabpaint']}, 14:47:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:47:14 INFO - 'tests': ['a11yr', 14:47:14 INFO - 'ts_paint', 14:47:14 INFO - 'tpaint', 14:47:14 INFO - 'sessionrestore', 14:47:14 INFO - 'sessionrestore_no_auto_restore', 14:47:14 INFO - 'tabpaint']}, 14:47:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:47:14 INFO - 'tests': ['a11yr', 14:47:14 INFO - 'ts_paint', 14:47:14 INFO - 'tpaint', 14:47:14 INFO - 'sessionrestore', 14:47:14 INFO - 'sessionrestore_no_auto_restore', 14:47:14 INFO - 'tabpaint']}, 14:47:14 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 14:47:14 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 14:47:14 INFO - 'bloom_basic_ref']}, 14:47:14 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 14:47:14 INFO - 'talos_options': ['--mitmproxy', 14:47:14 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 14:47:14 INFO - '--firstNonBlankPaint'], 14:47:14 INFO - 'tests': ['quantum_pageload_google', 14:47:14 INFO - 'quantum_pageload_youtube', 14:47:14 INFO - 'quantum_pageload_amazon', 14:47:14 INFO - 'quantum_pageload_facebook']}, 14:47:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:47:14 INFO - 'tests': ['tsvgx', 14:47:14 INFO - 'tsvgr_opacity', 14:47:14 INFO - 'tart', 14:47:14 INFO - 'tscrollx', 14:47:14 INFO - 'cart', 14:47:14 INFO - 'tsvg_static']}, 14:47:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:47:14 INFO - 'tsvgr_opacity', 14:47:14 INFO - 'tart', 14:47:14 INFO - 'tscrollx', 14:47:14 INFO - 'cart', 14:47:14 INFO - 'tsvg_static']}, 14:47:14 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 14:47:14 INFO - 'talos_options': ['--disable-e10s'], 14:47:14 INFO - 'tests': ['tp5o']}, 14:47:14 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:47:14 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 14:47:14 INFO - 'talos_options': ['--disable-e10s', 14:47:14 INFO - '--xperf_path', 14:47:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:47:14 INFO - 'tests': ['tp5n']}, 14:47:14 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:47:14 INFO - 'talos_options': ['--xperf_path', 14:47:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:47:14 INFO - 'tests': ['tp5n']}}, 14:47:14 INFO - 'talos.zip': {'path': '', 14:47:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:47:14 INFO - Running post-action listener: _resource_record_post_action 14:47:14 INFO - [mozharness: 2017-06-12 21:47:14.123308Z] Finished populate-webroot step (success) 14:47:14 INFO - [mozharness: 2017-06-12 21:47:14.123445Z] Running create-virtualenv step. 14:47:14 INFO - Running pre-action listener: _resource_record_pre_action 14:47:14 INFO - Running main action method: create_virtualenv 14:47:14 INFO - Creating virtualenv /builds/slave/test/build/venv 14:47:14 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 14:47:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:47:14 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 14:47:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:47:14 INFO - Using real prefix '/tools/python27' 14:47:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:47:15 INFO - Installing distribute.............................................................................................................................................................................................done. 14:47:17 INFO - Installing pip.................done. 14:47:17 INFO - Return code: 0 14:47:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:47:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:47:18 INFO - Reading from file tmpfile_stdout 14:47:18 INFO - Output received: 14:47:18 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) 14:47:18 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:47:18 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')]} 14:47:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:47:18 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')]} 14:47:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:47:18 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 0x10ee65c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee69768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa4214d8a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebbbd30>, '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 0x10eb85cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ebacbe8>, '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.FJiNsBXElg/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.plOTrPUy6w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 14:47:18 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 14:47:18 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 14:47:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FJiNsBXElg/Render', 14:47:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:47:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:47:18 INFO - 'HOME': '/Users/cltbld', 14:47:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:47:18 INFO - 'LOGNAME': 'cltbld', 14:47:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:47:18 INFO - 'MOZ_NO_REMOTE': '1', 14:47:18 INFO - 'NO_EM_RESTART': '1', 14:47:18 INFO - 'PAGER': '/bin/cat', 14:47:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:47:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:47:18 INFO - 'PWD': '/builds/slave/test', 14:47:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:47:18 INFO - 'SHELL': '/bin/bash', 14:47:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.plOTrPUy6w/Listeners', 14:47:18 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 14:47:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:47:18 INFO - 'USER': 'cltbld', 14:47:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:47:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:47:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:47:18 INFO - 'XPC_FLAGS': '0x0', 14:47:18 INFO - 'XPC_SERVICE_NAME': '0', 14:47:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:47:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:47:18 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 14:47:18 INFO - Cleaning up... 14:47:18 INFO - Return code: 0 14:47:18 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:47:18 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')]} 14:47:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:47:18 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')]} 14:47:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:47:18 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 0x10ee65c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee69768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa4214d8a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebbbd30>, '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 0x10eb85cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ebacbe8>, '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.FJiNsBXElg/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.plOTrPUy6w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 14:47:18 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 14:47:18 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 14:47:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FJiNsBXElg/Render', 14:47:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:47:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:47:18 INFO - 'HOME': '/Users/cltbld', 14:47:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:47:18 INFO - 'LOGNAME': 'cltbld', 14:47:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:47:18 INFO - 'MOZ_NO_REMOTE': '1', 14:47:18 INFO - 'NO_EM_RESTART': '1', 14:47:18 INFO - 'PAGER': '/bin/cat', 14:47:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:47:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:47:18 INFO - 'PWD': '/builds/slave/test', 14:47:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:47:18 INFO - 'SHELL': '/bin/bash', 14:47:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.plOTrPUy6w/Listeners', 14:47:18 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 14:47:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:47:18 INFO - 'USER': 'cltbld', 14:47:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:47:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:47:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:47:18 INFO - 'XPC_FLAGS': '0x0', 14:47:18 INFO - 'XPC_SERVICE_NAME': '0', 14:47:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:47:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:47:18 INFO - Downloading/unpacking psutil>=3.1.1 14:47:18 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 14:47:18 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 14:47:18 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 14:47:18 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 14:47:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:47:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:47:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:47:20 INFO - Installing collected packages: psutil 14:47:20 INFO - Running setup.py install for psutil 14:47:20 INFO - building 'psutil._psutil_osx' extension 14:47:20 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 14:47:21 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 14:47:21 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 14:47:21 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 14:47:21 INFO - building 'psutil._psutil_posix' extension 14:47:21 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 14:47:21 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:47:21 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:47:21 INFO - ^ 14:47:21 INFO - 1 warning generated. 14:47:21 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 14:47:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:47:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:47:22 INFO - Successfully installed psutil 14:47:22 INFO - Cleaning up... 14:47:22 INFO - Return code: 0 14:47:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:47:22 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')]} 14:47:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:47:22 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')]} 14:47:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:47:22 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 0x10ee65c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee69768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa4214d8a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebbbd30>, '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 0x10eb85cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ebacbe8>, '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.FJiNsBXElg/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.plOTrPUy6w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 14:47:22 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 14:47:22 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 14:47:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FJiNsBXElg/Render', 14:47:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:47:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:47:22 INFO - 'HOME': '/Users/cltbld', 14:47:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:47:22 INFO - 'LOGNAME': 'cltbld', 14:47:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:47:22 INFO - 'MOZ_NO_REMOTE': '1', 14:47:22 INFO - 'NO_EM_RESTART': '1', 14:47:22 INFO - 'PAGER': '/bin/cat', 14:47:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:47:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:47:22 INFO - 'PWD': '/builds/slave/test', 14:47:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:47:22 INFO - 'SHELL': '/bin/bash', 14:47:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.plOTrPUy6w/Listeners', 14:47:22 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 14:47:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:47:22 INFO - 'USER': 'cltbld', 14:47:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:47:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:47:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:47:22 INFO - 'XPC_FLAGS': '0x0', 14:47:22 INFO - 'XPC_SERVICE_NAME': '0', 14:47:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:47:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:47:22 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:47:22 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 14:47:22 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 14:47:22 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 14:47:22 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 14:47:23 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:47:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:47:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:47:23 INFO - Installing collected packages: mozsystemmonitor 14:47:23 INFO - Running setup.py install for mozsystemmonitor 14:47:23 INFO - Successfully installed mozsystemmonitor 14:47:23 INFO - Cleaning up... 14:47:23 INFO - Return code: 0 14:47:23 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:47:23 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')]} 14:47:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:47:24 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')]} 14:47:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:47:24 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 0x10ee65c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee69768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa4214d8a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebbbd30>, '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 0x10eb85cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ebacbe8>, '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.FJiNsBXElg/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.plOTrPUy6w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 14:47:24 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 14:47:24 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 14:47:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FJiNsBXElg/Render', 14:47:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:47:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:47:24 INFO - 'HOME': '/Users/cltbld', 14:47:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:47:24 INFO - 'LOGNAME': 'cltbld', 14:47:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:47:24 INFO - 'MOZ_NO_REMOTE': '1', 14:47:24 INFO - 'NO_EM_RESTART': '1', 14:47:24 INFO - 'PAGER': '/bin/cat', 14:47:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:47:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:47:24 INFO - 'PWD': '/builds/slave/test', 14:47:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:47:24 INFO - 'SHELL': '/bin/bash', 14:47:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.plOTrPUy6w/Listeners', 14:47:24 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 14:47:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:47:24 INFO - 'USER': 'cltbld', 14:47:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:47:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:47:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:47:24 INFO - 'XPC_FLAGS': '0x0', 14:47:24 INFO - 'XPC_SERVICE_NAME': '0', 14:47:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:47:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:47:24 INFO - Downloading/unpacking jsonschema==2.5.1 14:47:24 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 14:47:24 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 14:47:24 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 14:47:24 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 14:47:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:47:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:47:25 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 14:47:25 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 14:47:25 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 14:47:25 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 14:47:25 INFO - Downloading functools32-3.2.3-2.tar.gz 14:47:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:47:25 INFO - warning: no files found matching '*.txt' 14:47:26 INFO - no previously-included directories found matching 'build' 14:47:26 INFO - no previously-included directories found matching 'dist' 14:47:26 INFO - no previously-included directories found matching '.git*' 14:47:26 INFO - Installing collected packages: jsonschema, functools32 14:47:26 INFO - Running setup.py install for functools32 14:47:26 INFO - warning: no files found matching '*.txt' 14:47:26 INFO - no previously-included directories found matching 'build' 14:47:26 INFO - no previously-included directories found matching 'dist' 14:47:26 INFO - no previously-included directories found matching '.git*' 14:47:26 INFO - Successfully installed jsonschema functools32 14:47:26 INFO - Cleaning up... 14:47:26 INFO - Return code: 0 14:47:26 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:47: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')]} 14:47:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:47: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')]} 14:47:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:47:26 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 0x10ee65c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee69768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa4214d8a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebbbd30>, '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 0x10eb85cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ebacbe8>, '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.FJiNsBXElg/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.plOTrPUy6w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 14:47:26 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 14:47:26 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 14:47:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FJiNsBXElg/Render', 14:47:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:47:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:47:26 INFO - 'HOME': '/Users/cltbld', 14:47:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:47:26 INFO - 'LOGNAME': 'cltbld', 14:47:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:47:26 INFO - 'MOZ_NO_REMOTE': '1', 14:47:26 INFO - 'NO_EM_RESTART': '1', 14:47:26 INFO - 'PAGER': '/bin/cat', 14:47:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:47:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:47:26 INFO - 'PWD': '/builds/slave/test', 14:47:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:47:26 INFO - 'SHELL': '/bin/bash', 14:47:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.plOTrPUy6w/Listeners', 14:47:26 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 14:47:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:47:26 INFO - 'USER': 'cltbld', 14:47:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:47:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:47:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:47:26 INFO - 'XPC_FLAGS': '0x0', 14:47:26 INFO - 'XPC_SERVICE_NAME': '0', 14:47:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:47:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:47:26 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:47:26 INFO - Cleaning up... 14:47:26 INFO - Return code: 0 14:47:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:47: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')]} 14:47:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:47: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')]} 14:47:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:47:26 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 0x10ee65c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee69768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa4214d8a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebbbd30>, '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 0x10eb85cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ebacbe8>, '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.FJiNsBXElg/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.plOTrPUy6w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 14:47:26 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 14:47:26 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 14:47:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FJiNsBXElg/Render', 14:47:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:47:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:47:26 INFO - 'HOME': '/Users/cltbld', 14:47:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:47:26 INFO - 'LOGNAME': 'cltbld', 14:47:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:47:26 INFO - 'MOZ_NO_REMOTE': '1', 14:47:26 INFO - 'NO_EM_RESTART': '1', 14:47:26 INFO - 'PAGER': '/bin/cat', 14:47:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:47:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:47:26 INFO - 'PWD': '/builds/slave/test', 14:47:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:47:26 INFO - 'SHELL': '/bin/bash', 14:47:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.plOTrPUy6w/Listeners', 14:47:26 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 14:47:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:47:26 INFO - 'USER': 'cltbld', 14:47:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:47:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:47:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:47:26 INFO - 'XPC_FLAGS': '0x0', 14:47:26 INFO - 'XPC_SERVICE_NAME': '0', 14:47:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:47:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:47:26 INFO - Downloading/unpacking blobuploader==1.2.4 14:47:26 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 14:47:26 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 14:47:26 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 14:47:26 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 14:47:28 INFO - Downloading blobuploader-1.2.4.tar.gz 14:47:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:47:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:47:28 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 14:47:28 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 14:47:28 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 14:47:28 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 14:47:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:47:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:47:28 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 14:47:28 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 14:47:28 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 14:47:28 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 14:47:28 INFO - Downloading docopt-0.6.1.tar.gz 14:47:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:47:29 INFO - Installing collected packages: blobuploader, requests, docopt 14:47:29 INFO - Running setup.py install for blobuploader 14:47:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:47:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:47:29 INFO - Running setup.py install for requests 14:47:29 INFO - Running setup.py install for docopt 14:47:29 INFO - Successfully installed blobuploader requests docopt 14:47:29 INFO - Cleaning up... 14:47:29 INFO - Return code: 0 14:47:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:47:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:47:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:47:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:47:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:47:29 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 0x10ee65c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee69768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa4214d8a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebbbd30>, '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 0x10eb85cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ebacbe8>, '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.FJiNsBXElg/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.plOTrPUy6w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 14:47:29 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 14:47:29 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 14:47:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FJiNsBXElg/Render', 14:47:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:47:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:47:30 INFO - 'HOME': '/Users/cltbld', 14:47:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:47:30 INFO - 'LOGNAME': 'cltbld', 14:47:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:47:30 INFO - 'MOZ_NO_REMOTE': '1', 14:47:30 INFO - 'NO_EM_RESTART': '1', 14:47:30 INFO - 'PAGER': '/bin/cat', 14:47:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:47:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:47:30 INFO - 'PWD': '/builds/slave/test', 14:47:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:47:30 INFO - 'SHELL': '/bin/bash', 14:47:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.plOTrPUy6w/Listeners', 14:47:30 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 14:47:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:47:30 INFO - 'USER': 'cltbld', 14:47:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:47:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:47:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:47:30 INFO - 'XPC_FLAGS': '0x0', 14:47:30 INFO - 'XPC_SERVICE_NAME': '0', 14:47:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:47:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:47:30 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-On0Cmr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:47:30 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-MlXOmP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:47:30 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-nnysIi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:47:30 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-KqlMiu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:47:30 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-cWwK5U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:47:30 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-gV92yc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:47:30 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-qXrxIi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:47:30 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-n4Kk4c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:47:31 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-NoQuSp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:47:31 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-VEBkUO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:47:31 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-uG8IxQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:47:31 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-nwh7jK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:47:31 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-1XTR27-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:47:31 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-RzyFU7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:47:31 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-47hKTS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:47:31 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-NeDDso-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:47:31 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-IquzWt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:47:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:47:32 INFO - Running setup.py install for manifestparser 14:47:32 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:47:32 INFO - Running setup.py install for mozcrash 14:47:32 INFO - Running setup.py install for mozdebug 14:47:32 INFO - Running setup.py install for mozdevice 14:47:32 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:47:32 INFO - Running setup.py install for mozfile 14:47:32 INFO - Running setup.py install for mozhttpd 14:47:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:47:32 INFO - Running setup.py install for mozinfo 14:47:33 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:47:33 INFO - Running setup.py install for mozInstall 14:47:33 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:47:33 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:47:33 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:47:33 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:47:33 INFO - Running setup.py install for mozleak 14:47:33 INFO - Running setup.py install for mozlog 14:47:33 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:47:33 INFO - Running setup.py install for moznetwork 14:47:33 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:47:33 INFO - Running setup.py install for mozprocess 14:47:33 INFO - Running setup.py install for mozprofile 14:47:33 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:47:33 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:47:33 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:47:33 INFO - Running setup.py install for mozrunner 14:47:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:47:34 INFO - Running setup.py install for mozscreenshot 14:47:34 INFO - Running setup.py install for moztest 14:47:34 INFO - Running setup.py install for mozversion 14:47:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:47:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:47:34 INFO - Cleaning up... 14:47:34 INFO - Return code: 0 14:47:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:47:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:47:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:47:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:47:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:47:34 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 0x10ee65c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee69768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa4214d8a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebbbd30>, '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 0x10eb85cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ebacbe8>, '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.FJiNsBXElg/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.plOTrPUy6w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 14:47:34 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 14:47:34 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 14:47:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FJiNsBXElg/Render', 14:47:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:47:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:47:34 INFO - 'HOME': '/Users/cltbld', 14:47:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:47:34 INFO - 'LOGNAME': 'cltbld', 14:47:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:47:34 INFO - 'MOZ_NO_REMOTE': '1', 14:47:34 INFO - 'NO_EM_RESTART': '1', 14:47:34 INFO - 'PAGER': '/bin/cat', 14:47:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:47:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:47:34 INFO - 'PWD': '/builds/slave/test', 14:47:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:47:34 INFO - 'SHELL': '/bin/bash', 14:47:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.plOTrPUy6w/Listeners', 14:47:34 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 14:47:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:47:34 INFO - 'USER': 'cltbld', 14:47:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:47:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:47:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:47:34 INFO - 'XPC_FLAGS': '0x0', 14:47:34 INFO - 'XPC_SERVICE_NAME': '0', 14:47:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:47:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:47:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:47:34 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-yiaswW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:47:34 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)) 14:47:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:47:34 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-mGd6PD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:47:35 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)) 14:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:47:35 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-u7XmIs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:47:35 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)) 14:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:47:35 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-aulXFw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:47:35 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)) 14:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:47:35 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-NnayVl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:47:35 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)) 14:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:47:35 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-4IMozj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:47:35 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)) 14:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:47:35 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-2eAGRV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:47:35 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)) 14:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:47:35 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-0Bz6vu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:47:35 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)) 14:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:47:35 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-ImURbk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:47:35 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)) 14:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:47:35 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-h5tguU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:47:35 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)) 14:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:47:35 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-mdSrwF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:47:36 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)) 14:47:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:47:36 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-Qnj3vN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:47:36 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)) 14:47:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:47:36 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-PeZJDX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:47:36 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)) 14:47:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:47:36 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-UkK2LV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:47:36 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)) 14:47:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:47:36 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-6Egq7Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:47:36 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)) 14:47:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:47:36 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-KF9U8j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:47:36 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)) 14:47:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:47:36 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-XTYPYg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:47:36 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)) 14:47:36 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)) 14:47:36 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)) 14:47:36 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)) 14:47:36 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)) 14:47:36 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)) 14:47:36 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)) 14:47:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:47:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:47:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:47:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:47:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:47:38 INFO - Downloading blessings-1.6.tar.gz 14:47:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:47:38 INFO - Installing collected packages: blessings 14:47:38 INFO - Running setup.py install for blessings 14:47:38 INFO - Successfully installed blessings 14:47:38 INFO - Cleaning up... 14:47:38 INFO - Return code: 0 14:47:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:47:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:47:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:47:38 INFO - Reading from file tmpfile_stdout 14:47:38 INFO - Current package versions: 14:47:38 INFO - blessings == 1.6 14:47:38 INFO - blobuploader == 1.2.4 14:47:38 INFO - docopt == 0.6.1 14:47:38 INFO - functools32 == 3.2.3-2 14:47:38 INFO - jsonschema == 2.5.1 14:47:38 INFO - manifestparser == 1.1 14:47:38 INFO - mozInstall == 1.13 14:47:38 INFO - mozcrash == 1.0 14:47:38 INFO - mozdebug == 0.1 14:47:38 INFO - mozdevice == 0.51 14:47:38 INFO - mozfile == 1.2 14:47:38 INFO - mozhttpd == 0.7 14:47:38 INFO - mozinfo == 0.10 14:47:38 INFO - mozleak == 0.1 14:47:38 INFO - mozlog == 3.4 14:47:38 INFO - moznetwork == 0.27 14:47:38 INFO - mozprocess == 0.25 14:47:38 INFO - mozprofile == 0.28 14:47:38 INFO - mozrunner == 6.13 14:47:38 INFO - mozscreenshot == 0.1 14:47:38 INFO - mozsystemmonitor == 0.3 14:47:38 INFO - moztest == 0.8 14:47:38 INFO - mozversion == 1.4 14:47:38 INFO - psutil == 3.1.1 14:47:38 INFO - requests == 1.2.3 14:47:38 INFO - wsgiref == 0.1.2 14:47:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:47:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:47:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:47:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:47:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:47:38 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 0x10ee65c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee69768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa4214d8a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebbbd30>, '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 0x10eb85cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ebacbe8>, '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.FJiNsBXElg/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.plOTrPUy6w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 14:47:38 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 14:47:38 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 14:47:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FJiNsBXElg/Render', 14:47:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:47:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:47:38 INFO - 'HOME': '/Users/cltbld', 14:47:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:47:38 INFO - 'LOGNAME': 'cltbld', 14:47:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:47:38 INFO - 'MOZ_NO_REMOTE': '1', 14:47:38 INFO - 'NO_EM_RESTART': '1', 14:47:38 INFO - 'PAGER': '/bin/cat', 14:47:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:47:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:47:38 INFO - 'PWD': '/builds/slave/test', 14:47:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:47:38 INFO - 'SHELL': '/bin/bash', 14:47:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.plOTrPUy6w/Listeners', 14:47:38 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 14:47:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:47:38 INFO - 'USER': 'cltbld', 14:47:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:47:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:47:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:47:38 INFO - 'XPC_FLAGS': '0x0', 14:47:38 INFO - 'XPC_SERVICE_NAME': '0', 14:47:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:47:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:47:38 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)) 14:47:38 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)) 14:47:38 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)) 14:47:38 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)) 14:47:38 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)) 14:47:38 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)) 14:47:38 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)) 14:47:38 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)) 14:47:38 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)) 14:47:38 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 14:47:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:47:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:47:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:47:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:47:40 INFO - Downloading marionette_driver-1.4.0.tar.gz 14:47:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 14:47:40 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)) 14:47:40 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)) 14:47:40 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)) 14:47:40 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)) 14:47:40 INFO - Installing collected packages: marionette-driver 14:47:40 INFO - Running setup.py install for marionette-driver 14:47:40 INFO - Successfully installed marionette-driver 14:47:40 INFO - Cleaning up... 14:47:40 INFO - Return code: 0 14:47:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:47:40 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')]} 14:47:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:47:40 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')]} 14:47:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:47:40 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 0x10ee65c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee69768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa4214d8a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebbbd30>, '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 0x10eb85cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ebacbe8>, '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.FJiNsBXElg/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.plOTrPUy6w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 14:47:40 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 14:47:40 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 14:47:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FJiNsBXElg/Render', 14:47:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:47:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:47:40 INFO - 'HOME': '/Users/cltbld', 14:47:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:47:40 INFO - 'LOGNAME': 'cltbld', 14:47:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:47:40 INFO - 'MOZ_NO_REMOTE': '1', 14:47:40 INFO - 'NO_EM_RESTART': '1', 14:47:40 INFO - 'PAGER': '/bin/cat', 14:47:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:47:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:47:40 INFO - 'PWD': '/builds/slave/test', 14:47:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:47:40 INFO - 'SHELL': '/bin/bash', 14:47:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.plOTrPUy6w/Listeners', 14:47:40 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 14:47:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:47:40 INFO - 'USER': 'cltbld', 14:47:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:47:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:47:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:47:40 INFO - 'XPC_FLAGS': '0x0', 14:47:40 INFO - 'XPC_SERVICE_NAME': '0', 14:47:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:47:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:47:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:47:40 INFO - Cleaning up... 14:47:40 INFO - Return code: 0 14:47:40 INFO - Running post-action listener: _resource_record_post_action 14:47:40 INFO - Running post-action listener: _start_resource_monitoring 14:47:40 INFO - Starting resource monitoring. 14:47:40 INFO - [mozharness: 2017-06-12 21:47:40.976031Z] Finished create-virtualenv step (success) 14:47:40 INFO - [mozharness: 2017-06-12 21:47:40.977229Z] Running install step. 14:47:40 INFO - Running pre-action listener: _resource_record_pre_action 14:47:40 INFO - Running main action method: install 14:47:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:47:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:47:41 INFO - Reading from file tmpfile_stdout 14:47:41 INFO - Detecting whether we're running mozinstall >=1.0... 14:47:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:47:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:47:41 INFO - Reading from file tmpfile_stdout 14:47:41 INFO - Output received: 14:47:41 INFO - Usage: mozinstall [options] installer 14:47:41 INFO - Options: 14:47:41 INFO - -h, --help show this help message and exit 14:47:41 INFO - -d DEST, --destination=DEST 14:47:41 INFO - Directory to install application into. [default: 14:47:41 INFO - "/builds/slave/test"] 14:47:41 INFO - --app=APP Application being installed. [default: firefox] 14:47:41 INFO - mkdir: /builds/slave/test/build/application 14:47:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 14:47:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 14:48:02 INFO - Reading from file tmpfile_stdout 14:48:02 INFO - Output received: 14:48:02 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:48:02 INFO - Running post-action listener: _resource_record_post_action 14:48:02 INFO - [mozharness: 2017-06-12 21:48:02.875730Z] Finished install step (success) 14:48:02 INFO - [mozharness: 2017-06-12 21:48:02.875894Z] Running setup-mitmproxy step. 14:48:02 INFO - Running pre-action listener: _resource_record_pre_action 14:48:02 INFO - Running main action method: setup_mitmproxy 14:48:02 INFO - Skipping: mitmproxy is not required 14:48:02 INFO - Running post-action listener: _resource_record_post_action 14:48:02 INFO - [mozharness: 2017-06-12 21:48:02.876590Z] Finished setup-mitmproxy step (success) 14:48:02 INFO - [mozharness: 2017-06-12 21:48:02.876724Z] Running run-tests step. 14:48:02 INFO - Running pre-action listener: _resource_record_pre_action 14:48:02 INFO - Running main action method: run_tests 14:48:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:48:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:48:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:48:02 INFO - 2017-06-12 14:48:02.891 screenresolution[1631:7798] starting screenresolution argv=screenresolution get 14:48:02 INFO - 2017-06-12 14:48:02.894 screenresolution[1631:7798] Display 0: 1600x1200x32@60 14:48:02 INFO - 2017-06-12 14:48:02.900 screenresolution[1632:7801] starting screenresolution argv=screenresolution list 14:48:02 INFO - Available Modes on Display 0 14:48:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:48:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:48:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:48:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:48:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:48:03 INFO - Intel Iris: 14:48:03 INFO - Chipset Model: Intel Iris 14:48:03 INFO - Type: GPU 14:48:03 INFO - Bus: Built-In 14:48:03 INFO - VRAM (Dynamic, Max): 1536 MB 14:48:03 INFO - Vendor: Intel (0x8086) 14:48:03 INFO - Device ID: 0x0a2e 14:48:03 INFO - Revision ID: 0x0009 14:48:03 INFO - Displays: 14:48:03 INFO - Display: 14:48:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:48:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:48:03 INFO - Display Serial Number: 1600x1200 60 14:48:03 INFO - Main Display: Yes 14:48:03 INFO - Mirror: Off 14:48:03 INFO - Online: Yes 14:48:03 INFO - Rotation: Supported 14:48:03 INFO - Return code: 0 14:48:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:48:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:48:03 INFO - Python 2.7.3 14:48:03 INFO - Return code: 0 14:48:03 INFO - grabbing minidump binary from tooltool 14:48: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')]} 14:48:03 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 0x10ebbbd30>, '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 0x10eb85cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ebacbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:48:03 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 14:48:03 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 14:48:03 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 14:48:03 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:48:03 INFO - Return code: 0 14:48:03 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:48:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:48:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:48:03 INFO - ENV: RUST_BACKTRACE is now 1 14:48:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:48:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:48:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RMESVxJzSV6NXsE8FceuUw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0350', '--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 14:48:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RMESVxJzSV6NXsE8FceuUw/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0350 --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 14:48:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FJiNsBXElg/Render', 14:48:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:48:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:48:03 INFO - 'HOME': '/Users/cltbld', 14:48:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:48:03 INFO - 'LOGNAME': 'cltbld', 14:48:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:48:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:48:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:48:03 INFO - 'MOZ_NO_REMOTE': '1', 14:48:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:48:03 INFO - 'NO_EM_RESTART': '1', 14:48:03 INFO - 'PAGER': '/bin/cat', 14:48:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:48:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:48:03 INFO - 'PWD': '/builds/slave/test', 14:48:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:48:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:48:03 INFO - 'RUST_BACKTRACE': '1', 14:48:03 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 14:48:03 INFO - 'SHELL': '/bin/bash', 14:48:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.plOTrPUy6w/Listeners', 14:48:03 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 14:48:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:48:03 INFO - 'USER': 'cltbld', 14:48:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:48:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:48:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:48:03 INFO - 'XPC_FLAGS': '0x0', 14:48:03 INFO - 'XPC_SERVICE_NAME': '0', 14:48:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:48:03 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RMESVxJzSV6NXsE8FceuUw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0350', '--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 14:48:03 INFO - mozversion application_buildid: 20170612135527 14:48:03 INFO - mozversion application_changeset: e3638c5119ed03dcd6ad243f99b08251194e375b 14:48:03 INFO - mozversion application_display_name: Nightly 14:48:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:48:03 INFO - mozversion application_name: Firefox 14:48:03 INFO - mozversion application_remotingname: firefox 14:48:03 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 14:48:03 INFO - mozversion application_vendor: Mozilla 14:48:03 INFO - mozversion application_version: 56.0a1 14:48:03 INFO - mozversion platform_buildid: 20170612135527 14:48:03 INFO - mozversion platform_changeset: e3638c5119ed03dcd6ad243f99b08251194e375b 14:48:03 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 14:48:03 INFO - mozversion platform_version: 56.0a1 14:48:03 INFO - using testdate: 1497304083 14:48:03 INFO - actual date: 1497304083 14:48:03 INFO - starting webserver on 'localhost:49227' 14:48:03 INFO - SUITE-START | Running 2 tests 14:48:03 INFO - TEST-START | dromaeo_css 14:48:03 INFO - operating with platform_type : mac_ 14:48:03 INFO - Initialising browser for dromaeo_css test... 14:48:03 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49227/getInfo.html -foreground -profile /var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/tmpgWHXw2/profile 14:48:03 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49227/getInfo.html -foreground) 14:48:37 INFO - TEST-INFO | 1642: killed by SIGTERM 14:48:37 INFO - Browser initialized. 14:48:37 INFO - Running cycle 1/1 for dromaeo_css test... 14:48:37 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/tmpgWHXw2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:48:39 INFO - PID 1650 | 2017-06-12 14:48:39.294 plugin-container[1651:8304] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9843, name = 'com.apple.tsm.portname' 14:48:39 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:48:39 INFO - PID 1650 | 2017-06-12 14:48:39.295 plugin-container[1651:8304] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4907, name = 'com.apple.CFPasteboardClient' 14:48:39 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:48:39 INFO - PID 1650 | 2017-06-12 14:48:39.295 plugin-container[1651:8304] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:48:40 INFO - PID 1650 | 2017-06-12 14:48:40.034 plugin-container[1652:8350] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 14:48:40 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:48:40 INFO - PID 1650 | 2017-06-12 14:48:40.034 plugin-container[1652:8350] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d0b, name = 'com.apple.CFPasteboardClient' 14:48:40 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:48:40 INFO - PID 1650 | 2017-06-12 14:48:40.034 plugin-container[1652:8350] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:48:49 INFO - PID 1650 | RSS: Main: 252211200 14:48:49 INFO - PID 1650 | 14:51:51 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49227/tests/dromaeo/cssquery-ext.html) 14:51:53 INFO - PID 1650 | RSS: Main: 264531968 14:51:53 INFO - PID 1650 | 14:54:54 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-ext.html (next: http://localhost:49227/tests/dromaeo/cssquery-jquery.html) 14:54:55 INFO - PID 1650 | RSS: Main: 275013632 14:54:55 INFO - PID 1650 | 14:57:57 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49227/tests/dromaeo/cssquery-mootools.html) 14:57:57 INFO - PID 1650 | RSS: Main: 276885504 14:57:57 INFO - PID 1650 | 15:00:59 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49227/tests/dromaeo/cssquery-prototype.html) 15:01:00 INFO - PID 1650 | RSS: Main: 278188032 15:01:00 INFO - PID 1650 | 15:04:03 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49227/tests/dromaeo/cssquery-yui.html) 15:04:03 INFO - PID 1650 | RSS: Main: 279740416 15:04:03 INFO - PID 1650 | 15:07:05 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-yui.html (next: http://localhost:49227/tests/dromaeo/cssquery-dojo.html) 15:07:05 INFO - PID 1650 | RSS: Main: 279166976 15:07:05 INFO - PID 1650 | 15:07:05 INFO - PID 1650 | __start_tp_report 15:07:05 INFO - PID 1650 | _x_x_mozilla_page_load 15:07:05 INFO - PID 1650 | _x_x_mozilla_page_load_details 15:07:05 INFO - PID 1650 | |i|pagename|runs| 15:07:05 INFO - PID 1650 | |0;dojo.html;3033.469142899993;3244.1240864966467;3257.902262932113;3573.4103872860974;3635.309291234665;5059.291699162116;5724.828255152342;6227.719752611138;6295.685215739206;6299.685015749212;6233.750649973996;6257.124002639634;6293.968530157343;6305.558610897239;6310.463610593094;5567.387587365397;5567.582431317647;5602.1036634138545;5709.1150871614755;5769.7115144242825;5619.634723742964;5757.856053598673;5775.249217601727;5786.537077033849;5809.244798176232;5447.591430642718;5478.068728316196;5609;5611.579131565149;5631.493165615073;5498.560115190775;5519.613627046108;5545.72271386429;5577.386487486373;5609.691466969315;5486.972565137189;5522.917156242619;5530.695811730353;5585.916211256835;5632.436756324376;6154.199954005995;6270.717817698169;6274.309825919146;6297.685115744194;6309.305976342599;6666.833329166761;6741.191057073133;6748.527603067787;6793.592384456899;6813.386795188408;5582.944170558324;6011.428914253139;6324.209473815773;6795.93204067958;6834.2482326944;6269.185005949197;6326.778562750374;6332.208473940757;6379.425851673325;6380.298167201604;5967.492763115184;7057.576545407189;7064.682089305994;7091.680874360666;7099.82250443743;6441.484681225491;6503.967480162663;6697.799066028027;6704.363085506869;6708;5697.601167918288;6704.731810727614;6744.156980377453;6758.5269031167345;6769.255381907986;6142.406692085091;6609.86780264392;6661.26726060133;6761.053452516651;6767.39093481589;5944.791932282349;6133.640713961371;6774.288699686561;6788.253292137861;6794.694238759268;5392.056390131711;5398.379186393615;5406.91889621656;5413;5437.9939711153565;4770.928436073462;4773.5679296211;4778.211595086841;4801.591864691471;4809.158397280532;5280.577553795687;6109.222480666452;6299.590526615755;6327.367263273728;6426.3573642635365;6727.192736871607;6730.596164230162;6747.730090796412;6751.25736169021;6761.492888033417;6300.464460520815;6338.8415289617205;6361.4592759615025;6376.29860715321;6393.936060639334;5982.820515384546;6047.606905551125;6056.394360563909;6077.8480537986015;6123.4488895999575;5526.47498487643;6639.336066393322;6885.1049363582415;7008.159020917523;7050.083489146378;6627.569208001464;6836.760713375008;6868.793936181923;6883.655817209019;6917.688704008231;5138.665986710852;5799.565032622654;6914.135733033371;6931.272216417204;7077.6461176941975;6708.060871477899;6755.831104222434;6774.42417394527;6775.288594697683;6792.66036698173;5859.472647461749;6896.620685862325;6910.723571057101;6916.412104970933;6942.652867356713;6676.83307917306;6686.13080299558;6689.2307384651385;6708.295628958889;6713.597184168965;6447.9355206447335;6649.700763465752;6676.265610782712;6688.632125233159;6716.798496045127;6335.968320158369;6358.936410635834;6394.23269207698;6516.967415163083;6534.771283005072;6592.208934927839;6659.367360100783;6684.131062961784;6694.732210711517;6731.932680673131;6476.708548115252;6505.577137486238;6511.837204069936;6527.314631963575;6527.67361631907 15:07:05 INFO - PID 1650 | |1;ext.html;708.4828075504881;768.7885831396366;799.7640695994688;811.1037303779322;838.274892218231;36867.631323686786;38033.23933521328;38080.66717664882;38190.42714359287;38197.80901095494;37399.439008414825;37593.81203093987;37619;37873.81063094688;37907.81046094766;7169.462290328233;7260.745873894389;7270.127584689844;7283.963580182118;7286.417086633057;7227.602481863495;7278.490505664606;7327.047483827095;7330.2669733026805;7344.8898266525775;7229.132504099515;7242.710291588356;7299.41604671625;7305.963470182642;7343.082114735658;7264.5641261524215;7271.018412514349;7278.526895751812;7310.926890731078;7334.706611735551;6559.868802623969;6601.3728695773825;6613.537052406334;6628.5691430056895;7953.085395179571;35728.64271357305;35957.640423595694;36076.81961590175;36096.639033609594;36107.81946090279;3502.2995400919917;3508.035290295189;3554.0759402555263;3554.4845997330244;3577.606463289036;29186.562201566994;29307.413851722846;29314.560281595794;29344.706552934626;29359.972400965864;3055.5569442430947;3073.89241376553;3080.4609193391293;3090.9536356954663;3099.271671157299;223623;241270.7936460324;254470;292352.53823730745;303188;37815.43276850849;45744.77127614341;46442.303365449545;46487.535124648995;46766.5323346769;49592.0081598366;50081.49918500841;50141;50145.24782128271;50270.74864625726;13804.17174969505;13944.442222311196;13946.093503922213;13950.302484875716;13994.650133746534;11406.543738250377;11413.60052398162;11417.543298267976;11423.771524569462;11457.541698331974;13346.532871349456;13363.265020423776;13381.46474141043;13397.196168229937;13493.122947008413;4891.633127515451;4921.335619691367;5735.569832262597;5816.621919575214;5823.068309070529;990.9256805739599;1000.9049140331657;1016.425719468498;1041.890172400879;1056.3767377247461;22304.88847555752;22404.304637384324;22703.432414189778;22858.771412285998;23680.28959131229;1081.4106312059946;1086.2613422872523;1144.2447984330468;1147.632757517603;1148.2364227788594;11482.94258528702;11559.017483513995;11741.119416043832;11785.410729463492;12304.81542776859;340445;343212;343665;344496;344770;52339;53032.93934121296;53034.73482632639;53041.469585303654;53127.20309195365;1464.714380695754;1465.6076727109075;1506.2995706996298;1506.922550376486;1509.9622509437352;21744.565108698054;22005;22631.434214144778;22655.867206639272;23484.765152348256;37827.810860945516;37882.86351409462;37942.620573795015;37967.81016094902;37990.62009379871;283714.58142709156;283857;284044;284127;284603;29019.41961160807;29533.704662953096;30077.849610751808;33944.490832638025;34099.488507672904;37495.625043750304;40498.1900361997;40682.135197156225;43056.35415468708;43281;26907.730922690524;27222.319442013315;28491;29259.41481170407;30608.081757546417;28831.5675264867;29522.55716164302;30013.249668758457;30222.54666179965;30422.847885760428;32607.85872494546;35954.4606830903;36100.63899361078;36183.552657893386;36188.27623447569;27178.45643087167;28015.879364825178;28621.856890715415;29028.419431610826;29459.96890108836;19783.703244451055;20535.589288213854;22232.888835555717;23760;23823.16618918399 15:07:05 INFO - PID 1650 | |2;jquery.html;498.4541926590382;504.1278588042684;510.1837060702875;519.9922001169982;521.8877941242632;19493.73290736103;20245.493862653424;20459;21121.57756844862;21986.560268794616;22533.887330563364;22610.54778904421;22865.657015144785;22878.771212287873;22894.427639308982;5924.6445213287125;6025.758969641209;6098.024316109423;6107.725152368154;6112.144299798032;6002.8799424011495;6084.330723620398;6086.634801911878;6101.20684311039;6107.541934354919;5520.558355331564;6045.365236650155;6064.696765161746;6189.133521307021;6226.813195604139;6157.0456579230295;6217.968910155466;6219.875602487925;6222.315545290015;6239.34486878878;5651.660900345993;5655.095184770458;5656.490915817596;5657.321121465409;5657.773689052433;26231.73768262312;27019;27448.862755686092;27489.862550687118;27540.036098736648;2143.3998480425507;2247.505548779266;2266.648669456237;2271.4207876991427;2274.7384050834034;17863.73204401935;18119.637607247914;18242.908785456122;18330.541736456562;18365;1914.7702275726863;2128.8509804313703;2169.425102347879;2204.0963205085836;2205.691203231549;164077;172856;174029;174517;188322;11885.028742814296;12537.247765134121;12999.09006369564;14389.177711428823;14574.834013278913;19037.524061898286;19309.51726206856;19331.033448327526;19338.516537086685;19441.902790485958;9185.586648600829;9192.81614367709;9539.284553658503;9542.90457095434;9603.711888643353;8871.955640221886;8886.73339799807;8917.643294268157;8934.821303573892;8963.417377870417;8488.830223395498;8579.099194584478;8592.8711069334;8598;8600.247920665328;2553.6424900513944;2555.1695698897934;2570.293169378436;2571.151519998396;2707.9690232325756;970.8640790289365;975.5139131075445;988.2439933450916;997.6980041046359;1006.9496525173712;19782.30761923326;19982.900940448413;20115;20508;20733.65071911369;977.6578197630772;980.2256217588168;999.3703966501204;1037.6057098302597;1037.9221558383151;7938;10016.29885907994;10027.348222365523;10315.948420258;10479.580816767397;560306;563607;564241;566521.5500652269;573016;136174;137366.3131684355;141703.45741356548;142293;143016.28491857683;938.7324612485409;1146.856642919635;1209.612923864355;1253.11029169289;1257.43415463042;20137.6979345307;20431.182752689725;21451.570968580854;21680.67478987785;22556.887215563816;67955.66022170056;68877;70599.64700176466;70731.64634176796;70926.64536677283;70480;88953.55523222343;89868.65197021919;91456;92183.07816921745;10888.61889833852;10998.780024399628;11144.554217831195;11154.88845111571;11194.720131996764;14999.475018374305;17133.314667413164;17178.31286748566;17777.377791777224;17831.910840445715;13915.095518791246;14106.29468526549;14191.574252772434;14201.218932958787;14530.854691453373;12068.517259309529;12194.451249693608;12303.876961230273;12893.677658058623;13094;14492.782608260673;14685.706285874436;14704.264786760834;14718.926405367905;14723.631909202355;10494;11125.777484450427;11171.553137874718;11217.887821121685;11314.717132071764;14260.00179987391;14310.928445357707;14347.354369053626;14489.05821121666;14501.4924477647 15:07:05 INFO - PID 1650 | |3;mootools.html;1315.802629605559;1480.6520467690098;1504.76609180472;1509.5094094419317;1520.2322826972381;11305.943470282647;13399.665008374795;13438.328083595818;13476.932615336922;13492.190468571893;9327.6735314264;11335.603253886116;11507.367094809802;12997.935010324958;13451.66370840727;2702.945941081182;2715.049732593586;2728.6998430172666;2751.092139593939;2751.257160566646;2383.3088404362716;2691.4886171627427;2692.3538350795766;2716.769074628659;2741.7943654225915;2110.493481564421;2571.842670798139;2679.2364176209785;2714.9728502714916;2766.197802637243;2415.7825795678386;2541.9110331138413;2641.537730897095;2747.9038233661927;2748.6014527893544;2759.986200068987;2782.2627003844;2785.7492825645586;2788.121741651373;2791.3719413131885;13301.933490332487;13376.665583360396;13381.19712817234;13395;13418.46326146953;7369.6315184240575;7563.168051514329;7636.31273185416;7645.9235407645765;7651.273129052731;4445.666575006855;4652.069586082763;4693.108309421199;4756.191447453906;4757.26262429324;1375.1405371642722;1396.6578188343922;1419.9929000355035;1422.743906096902;1423.0323380101527;19583.804161958342;19590.314338998065;19637;19682.70475942862;19728.21087156512;6344.49244060474;6483.092367068614;6509.934900651027;6590.670466476657;6604.405603495612;13922.651933701536;14041.71916561663;14050.227237502035;14060.367283472267;14073.577792666236;7234.927650723531;7333.559986400833;7347.008153899262;7372.041634587469;7387.6675549600395;6406.391392817675;6819.386255237053;6826.24911259761;6834.897476537856;6841.486888483384;4755.976220118947;4931.137051016058;4954.47977962316;4969.428515720666;4977.676451030671;1425.5081996711117;1442.4879167895456;1449.8477659845776;1463.297617143777;1466.933987970544;783.7687882074802;832.2301870769514;858.9613467393982;864.9610767515477;869.7216890594949;6504.38208370204;7372.963135184397;7693.961530192313;7864.41016923733;8374.413791034569;933.4819175357605;968.0948313327061;973.8880028796779;977.2524019125375;990.4552496127101;4639.721616703008;5203.401608814959;5226.660267082628;5286;5534.252875861787;17809.7328540072;18155.909220454076;18263.17815698297;18313.992730399958;18318.90840545789;17590.296388144332;18240;18367.448976530726;18402.907985459988;18443.81556184421;9331.72004839856;9511.619535218513;9516.095970882756;9516.286278529;9527.475988820543;7430.814229644086;7803.960980195063;8058;8106.270435660819;8347.248747612744;16159.272832722321;16484.83515164833;16528.83471165273;16695.33218671288;16895.746563801305;15513.37946482128;15635.687286254439;15639.905206635882;16829;17077.658446831243;10718.624848130277;10919.290246133976;10923.508442119964;10956.233063685466;10959.506822192861;12189.329586872807;12196.268223906594;12202.32887191213;12324.32216228116;12416.75166496683;9572.573683561475;9773.12041916231;10031.448270345201;10238.385696858213;10308.845367319645;9004.009558948379;9274.44353338802;9394.483303418378;9575;9616.038396160327;7636.579988100486;8956.596953136994;8974.506402147683;9067.957184923554;9174.770630734285;6544.803655890331;6657.167854018247;6908.689108990007;6949.270326615632;6978.546394484342;5831.7667293307795;6174.66039367839;6802.387785099365;7160.749373771893;7164.2119366868565 15:07:05 INFO - PID 1650 | |4;prototype.html;399.95800440953707;405.8762077566342;454.6544626084177;463.2287241742499;469.1765950756422;5485.862853428666;6268.153799237108;6448.967755161229;6456.515761317903;6474.93163628002;6242.781502647408;6251.656158911258;6292.433680968712;6302.29837943755;6312.179416675837;4034.475518182632;4410.140022695576;4569;4784.234522476405;4856.684315519497;3622.6196249393697;4767.1419144553965;4775.9761201194115;4818.723038394828;4852.41770987482;4369.8217612129565;4644.8633628530915;4793.616510679138;4803.063402636487;4809.90380192396;3981.5222173339093;4744.454387745419;4763.833265835679;4783.928241076369;4826.0695721711245;4096.32410652242;4151.91696166078;4163.084121493267;4172.207280616703;4183.477065366829;5186.299849520305;5544.584156188302;6224.502039836802;6247.219097612799;6269.968650156766;4408.933865992013;5650.6892120933335;5662.433756624305;5689.544836413077;5695.572832037614;6642.27300901769;6821.964225919468;6898.344657257603;6901.309869013109;6912.965435172842;4332.9566704332865;4351.28203846365;4777.4267087949665;4779.378680771512;4814.253790662453;6964.616946067914;7242.45681573884;7385.778426647209;7403.814904627374;7409.296116868889;4545.636309107254;4710.29345598163;4721.836369632355;4837.879053023703;4843.24929635907;4035.4955630546183;4716.882077948078;4805.063012712558;4904.754762261872;4959.454460009396;4858.80564777412;5623.8594035149445;5638.182463542763;5657.51911087554;5685.971570142123;6814.284500127515;6823.1812182538415;6831.975203719482;6837.35045170708;6857.588544687334;1518.9468368607045;1555.4633651390252;1558.6906998121633;1622.399712106528;1707.5048236011667;1126.459299536211;1144.8626164860104;1154.6709187881581;1158.177693837368;1162.9301043040423;5932.970335148297;6136.325004249529;6506.869862602816;6510.283868774431;6521.9673901630185;5977.910331345033;6850.38346548803;7102;7113.466490013269;7132.572045677172;5559.555235581144;6049.941260279434;6139.877202455926;6354.887894618423;6441.1304473895425;3352.2625022495013;3504.807235602066;3700;3731.850725970931;3744.569374521965;7356.4919191565605;7360.668769905367;7375.88936165958;7380.594067326241;7408.073990751156;7097.148342198969;7189;7300.817479563053;7314.414846812242;7327.523710958771;7280.490365674352;7353.38195623932;7374.188839227681;7380.926190738024;7383.187849336461;6310.558260921875;6450.903236451363;6997.075804153898;7121.323474269936;7138.678759455733;6272.4982001440685;6938.895916561155;6986.288359351788;7021.894671579828;7036;6340.4293613575;7199.820004499929;7308.488405811542;7364.705411783469;7388.408927285912;4152.4601801765575;4883.462819089827;4891.33966914462;4898.53463920927;4924.522643207095;6387.93612063892;6911.447084233148;7038.2961703829205;7149.821254468472;7161.961515580316;6045.037931413488;6503.967480162569;6625.370589793962;6812.897806533005;6902.171739391104;5826.504747096545;6036.33600303975;6091.604045737013;6136.969315153396;6899;6222.968885155546;6731.148934043177;6741.966290168518;6757.763478278237;6781.728730850711;6070.332263450929;6544;6917.342852429011;6918.377346038881;7001.474889383215;5406.4052954175795;6736.090627765189;6742.561733487308;6744.22441419243;6779.288174741581 15:07:05 INFO - PID 1650 | |5;yui.html;712.188105559662;830.5888585150351;838.4131108224249;868.452874688946;868.8262347530491;16548.586285342873;16834.49496515105;16904;16925.153742312898;16970.915145424256;12768.42542085606;13193.14244574102;13260.46958121674;13386.66533336667;13674;678.8438659108408;962.1388856973005;983.1446641421973;983.7737320416309;984.1093810101869;1017.0744622393623;1018.1193267823317;1023.1967905194424;1024.6823484719762;1025.179856115105;1068.4924660786141;1069.262209075739;1073.2540884085524;1074.4198133008167;1079.0288740133904;950.2920324358328;989.6833013435703;1070.2454769387602;1070.925035247537;1072.0619457974271;792.4532072869702;795.0181525815613;796.2833449895082;798.5568009754584;799.848028874512;17233.655326893517;17345.306187752474;17361.218745156497;17383.30466781327;17392.565185870455;16781.328746850235;17435.912820435817;17682.292708291654;17739.11304434786;17815.643687126183;14244.287785610679;14521.491747788778;14668.853311466963;14746.115233085942;14879.107253564713;3613.4941108244866;3639.5632524097014;3643.8542458301636;3645.7994810285422;3649.4708267301094;90560.54719726359;145888;147968.52031479654;149063;152028;11813.350265735295;13038;14076.366563504667;14299.642508937151;14321.35553900077;25768;26096.8695156523;26584.06955756539;26869.596956045676;26951.595726064126;8003.559804210824;8112.716054938049;8122.025356957203;8128.349732021542;8147.226013528705;3947.684185265172;4022.0950286185375;4033.274010678106;4034.778087205173;4042.6159514846045;4667.346571479996;4687.859364219079;4710.976445117821;4736.881577960578;4757.09615173116;914.4558987402485;946.4699768129869;951.110711484764;963.9180669643159;964.0600414595854;626.6803929995735;640.9134766806515;651.1209866679944;661.5104822431366;689.0525527399825;11251;12131.150819442555;12285.38573071343;12345.876541234653;12449.315287659265;636.3350298937621;640.5196102922808;650.9121268628675;659.9175103112111;685.1675214614268;5314.5748340132695;5544.1960915667805;5656.8868622627315;5808.970955145282;5917.378675239038;132587.33706331407;133774;135027.32486337505;139675;144223;110443;120035.39982300032;124064;127317.72682273244;129948;74698.62650686712;100510;109532;110735.44632276786;112443.87556124335;10314.27800053989;11533.365664888512;12233.449494772578;12377.938110309391;12508.812367814307;38948.41577376285;42867;45643.40248091434;46273.537264626924;46314;68770.31229687839;74895;81633;81653.59173204334;85112.14887851042;16800;17189.57026074358;17504.94970301786;17821.73267401016;18138.093095345444;17406.651866962842;17420.47738567845;19320.516987075436;19475.22099116076;19498.512537186685;17167.31330746756;17734.645307093528;18158;18514.259429622663;18601.860786009784;17107.48677539676;17911.910440447715;18093.185806638998;18378.63242735111;18413.355532556296;16164.838351616803;16165.67668646644;16771.496855094367;17516.38692645751;17531.82468175353;10958.506867190836;11656.825147622962;11765.882341176479;12062.276263423872;12253.754924901274;13111.672208194872;13279.00407469463;13305.933470332586;13402.128861623964;13741.106828056145 15:07:05 INFO - PID 1650 | __end_tp_report 15:07:05 INFO - PID 1650 | __start_cc_report 15:07:05 INFO - PID 1650 | _x_x_mozilla_cycle_collect,469 15:07:05 INFO - PID 1650 | __end_cc_report 15:07:05 INFO - PID 1650 | __startTimestamp1497305225869__endTimestamp 15:07:05 INFO - PID 1650 | 15:07:05 INFO - PID 1650 | ------- Summary: start ------- 15:07:05 INFO - PID 1650 | Number of tests: 6 15:07:05 INFO - PID 1650 | 15:07:05 INFO - PID 1650 | [#0] dojo.html Cycles:165 Average:6182.09 Median:6358.94 stddev:753.49 (11.8%) stddev-sans-first:714.17 15:07:05 INFO - PID 1650 | Values: 3033.5 3244.1 3257.9 3573.4 3635.3 5059.3 5724.8 6227.7 6295.7 6299.7 6233.8 6257.1 6294.0 6305.6 6310.5 5567.4 5567.6 5602.1 5709.1 5769.7 5619.6 5757.9 5775.2 5786.5 5809.2 5447.6 5478.1 5609.0 5611.6 5631.5 5498.6 5519.6 5545.7 5577.4 5609.7 5487.0 5522.9 5530.7 5585.9 5632.4 6154.2 6270.7 6274.3 6297.7 6309.3 6666.8 6741.2 6748.5 6793.6 6813.4 5582.9 6011.4 6324.2 6795.9 6834.2 6269.2 6326.8 6332.2 6379.4 6380.3 5967.5 7057.6 7064.7 7091.7 7099.8 6441.5 6504.0 6697.8 6704.4 6708.0 5697.6 6704.7 6744.2 6758.5 6769.3 6142.4 6609.9 6661.3 6761.1 6767.4 5944.8 6133.6 6774.3 6788.3 6794.7 5392.1 5398.4 5406.9 5413.0 5438.0 4770.9 4773.6 4778.2 4801.6 4809.2 5280.6 6109.2 6299.6 6327.4 6426.4 6727.2 6730.6 6747.7 6751.3 6761.5 6300.5 6338.8 6361.5 6376.3 6393.9 5982.8 6047.6 6056.4 6077.8 6123.4 5526.5 6639.3 6885.1 7008.2 7050.1 6627.6 6836.8 6868.8 6883.7 6917.7 5138.7 5799.6 6914.1 6931.3 7077.6 6708.1 6755.8 6774.4 6775.3 6792.7 5859.5 6896.6 6910.7 6916.4 6942.7 6676.8 6686.1 6689.2 6708.3 6713.6 6447.9 6649.7 6676.3 6688.6 6716.8 6336.0 6358.9 6394.2 6517.0 6534.8 6592.2 6659.4 6684.1 6694.7 6731.9 6476.7 6505.6 6511.8 6527.3 6527.7 15:07:05 INFO - PID 1650 | 15:07:05 INFO - PID 1650 | [#1] ext.html Cycles:180 Average:43932.79 Median:188655.74 stddev:78716.42 (41.7%) stddev-sans-first:78870.34 15:07:05 INFO - PID 1650 | Values: 708.5 768.8 799.8 811.1 838.3 36867.6 38033.2 38080.7 38190.4 38197.8 37399.4 37593.8 37619.0 37873.8 37907.8 7169.5 7260.7 7270.1 7284.0 7286.4 7227.6 7278.5 7327.0 7330.3 7344.9 7229.1 7242.7 7299.4 7306.0 7343.1 7264.6 7271.0 7278.5 7310.9 7334.7 6559.9 6601.4 6613.5 6628.6 7953.1 35728.6 35957.6 36076.8 36096.6 36107.8 3502.3 3508.0 3554.1 3554.5 3577.6 29186.6 29307.4 29314.6 29344.7 29360.0 3055.6 3073.9 3080.5 3091.0 3099.3 223623.0 241270.8 254470.0 292352.5 303188.0 37815.4 45744.8 46442.3 46487.5 46766.5 49592.0 50081.5 50141.0 50145.2 50270.7 13804.2 13944.4 13946.1 13950.3 13994.7 11406.5 11413.6 11417.5 11423.8 11457.5 13346.5 13363.3 13381.5 13397.2 13493.1 4891.6 4921.3 5735.6 5816.6 5823.1 990.9 1000.9 1016.4 1041.9 1056.4 22304.9 22404.3 22703.4 22858.8 23680.3 1081.4 1086.3 1144.2 1147.6 1148.2 11482.9 11559.0 11741.1 11785.4 12304.8 340445.0 343212.0 343665.0 344496.0 344770.0 52339.0 53032.9 53034.7 53041.5 53127.2 1464.7 1465.6 1506.3 1506.9 1510.0 21744.6 22005.0 22631.4 22655.9 23484.8 37827.8 37882.9 37942.6 37967.8 37990.6 283714.6 283857.0 284044.0 284127.0 284603.0 29019.4 29533.7 30077.8 33944.5 34099.5 37495.6 40498.2 40682.1 43056.4 43281.0 26907.7 27222.3 28491.0 29259.4 30608.1 28831.6 29522.6 30013.2 30222.5 30422.8 32607.9 35954.5 36100.6 36183.6 36188.3 27178.5 28015.9 28621.9 29028.4 29460.0 19783.7 20535.6 22232.9 23760.0 23823.2 15:07:05 INFO - PID 1650 | 15:07:05 INFO - PID 1650 | [#2] jquery.html Cycles:180 Average:38104.59 Median:2226.60 stddev:96812.02 (4348.0%) stddev-sans-first:97042.43 15:07:05 INFO - PID 1650 | Values: 498.5 504.1 510.2 520.0 521.9 19493.7 20245.5 20459.0 21121.6 21986.6 22533.9 22610.5 22865.7 22878.8 22894.4 5924.6 6025.8 6098.0 6107.7 6112.1 6002.9 6084.3 6086.6 6101.2 6107.5 5520.6 6045.4 6064.7 6189.1 6226.8 6157.0 6218.0 6219.9 6222.3 6239.3 5651.7 5655.1 5656.5 5657.3 5657.8 26231.7 27019.0 27448.9 27489.9 27540.0 2143.4 2247.5 2266.6 2271.4 2274.7 17863.7 18119.6 18242.9 18330.5 18365.0 1914.8 2128.9 2169.4 2204.1 2205.7 164077.0 172856.0 174029.0 174517.0 188322.0 11885.0 12537.2 12999.1 14389.2 14574.8 19037.5 19309.5 19331.0 19338.5 19441.9 9185.6 9192.8 9539.3 9542.9 9603.7 8872.0 8886.7 8917.6 8934.8 8963.4 8488.8 8579.1 8592.9 8598.0 8600.2 2553.6 2555.2 2570.3 2571.2 2708.0 970.9 975.5 988.2 997.7 1006.9 19782.3 19982.9 20115.0 20508.0 20733.7 977.7 980.2 999.4 1037.6 1037.9 7938.0 10016.3 10027.3 10315.9 10479.6 560306.0 563607.0 564241.0 566521.6 573016.0 136174.0 137366.3 141703.5 142293.0 143016.3 938.7 1146.9 1209.6 1253.1 1257.4 20137.7 20431.2 21451.6 21680.7 22556.9 67955.7 68877.0 70599.6 70731.6 70926.6 70480.0 88953.6 89868.7 91456.0 92183.1 10888.6 10998.8 11144.6 11154.9 11194.7 14999.5 17133.3 17178.3 17777.4 17831.9 13915.1 14106.3 14191.6 14201.2 14530.9 12068.5 12194.5 12303.9 12893.7 13094.0 14492.8 14685.7 14704.3 14718.9 14723.6 10494.0 11125.8 11171.6 11217.9 11314.7 14260.0 14310.9 14347.4 14489.1 14501.5 15:07:05 INFO - PID 1650 | 15:07:05 INFO - PID 1650 | [#3] mootools.html Cycles:180 Average:8138.09 Median:2755.62 stddev:5376.76 (195.1%) stddev-sans-first:5367.41 15:07:05 INFO - PID 1650 | Values: 1315.8 1480.7 1504.8 1509.5 1520.2 11305.9 13399.7 13438.3 13476.9 13492.2 9327.7 11335.6 11507.4 12997.9 13451.7 2702.9 2715.0 2728.7 2751.1 2751.3 2383.3 2691.5 2692.4 2716.8 2741.8 2110.5 2571.8 2679.2 2715.0 2766.2 2415.8 2541.9 2641.5 2747.9 2748.6 2760.0 2782.3 2785.7 2788.1 2791.4 13301.9 13376.7 13381.2 13395.0 13418.5 7369.6 7563.2 7636.3 7645.9 7651.3 4445.7 4652.1 4693.1 4756.2 4757.3 1375.1 1396.7 1420.0 1422.7 1423.0 19583.8 19590.3 19637.0 19682.7 19728.2 6344.5 6483.1 6509.9 6590.7 6604.4 13922.7 14041.7 14050.2 14060.4 14073.6 7234.9 7333.6 7347.0 7372.0 7387.7 6406.4 6819.4 6826.2 6834.9 6841.5 4756.0 4931.1 4954.5 4969.4 4977.7 1425.5 1442.5 1449.8 1463.3 1466.9 783.8 832.2 859.0 865.0 869.7 6504.4 7373.0 7694.0 7864.4 8374.4 933.5 968.1 973.9 977.3 990.5 4639.7 5203.4 5226.7 5286.0 5534.3 17809.7 18155.9 18263.2 18314.0 18318.9 17590.3 18240.0 18367.4 18402.9 18443.8 9331.7 9511.6 9516.1 9516.3 9527.5 7430.8 7804.0 8058.0 8106.3 8347.2 16159.3 16484.8 16528.8 16695.3 16895.7 15513.4 15635.7 15639.9 16829.0 17077.7 10718.6 10919.3 10923.5 10956.2 10959.5 12189.3 12196.3 12202.3 12324.3 12416.8 9572.6 9773.1 10031.4 10238.4 10308.8 9004.0 9274.4 9394.5 9575.0 9616.0 7636.6 8956.6 8974.5 9068.0 9174.8 6544.8 6657.2 6908.7 6949.3 6978.5 5831.8 6174.7 6802.4 7160.7 7164.2 15:07:05 INFO - PID 1650 | 15:07:05 INFO - PID 1650 | [#4] prototype.html Cycles:180 Average:5553.57 Median:6181.42 stddev:1739.56 (28.1%) stddev-sans-first:1700.89 15:07:05 INFO - PID 1650 | Values: 400.0 405.9 454.7 463.2 469.2 5485.9 6268.2 6449.0 6456.5 6474.9 6242.8 6251.7 6292.4 6302.3 6312.2 4034.5 4410.1 4569.0 4784.2 4856.7 3622.6 4767.1 4776.0 4818.7 4852.4 4369.8 4644.9 4793.6 4803.1 4809.9 3981.5 4744.5 4763.8 4783.9 4826.1 4096.3 4151.9 4163.1 4172.2 4183.5 5186.3 5544.6 6224.5 6247.2 6270.0 4408.9 5650.7 5662.4 5689.5 5695.6 6642.3 6822.0 6898.3 6901.3 6913.0 4333.0 4351.3 4777.4 4779.4 4814.3 6964.6 7242.5 7385.8 7403.8 7409.3 4545.6 4710.3 4721.8 4837.9 4843.2 4035.5 4716.9 4805.1 4904.8 4959.5 4858.8 5623.9 5638.2 5657.5 5686.0 6814.3 6823.2 6832.0 6837.4 6857.6 1518.9 1555.5 1558.7 1622.4 1707.5 1126.5 1144.9 1154.7 1158.2 1162.9 5933.0 6136.3 6506.9 6510.3 6522.0 5977.9 6850.4 7102.0 7113.5 7132.6 5559.6 6049.9 6139.9 6354.9 6441.1 3352.3 3504.8 3700.0 3731.9 3744.6 7356.5 7360.7 7375.9 7380.6 7408.1 7097.1 7189.0 7300.8 7314.4 7327.5 7280.5 7353.4 7374.2 7380.9 7383.2 6310.6 6450.9 6997.1 7121.3 7138.7 6272.5 6938.9 6986.3 7021.9 7036.0 6340.4 7199.8 7308.5 7364.7 7388.4 4152.5 4883.5 4891.3 4898.5 4924.5 6387.9 6911.4 7038.3 7149.8 7162.0 6045.0 6504.0 6625.4 6812.9 6902.2 5826.5 6036.3 6091.6 6137.0 6899.0 6223.0 6731.1 6742.0 6757.8 6781.7 6070.3 6544.0 6917.3 6918.4 7001.5 5406.4 6736.1 6742.6 6744.2 6779.3 15:07:05 INFO - PID 1650 | 15:07:05 INFO - PID 1650 | [#5] yui.html Cycles:180 Average:25333.95 Median:17708.47 stddev:38553.91 (217.7%) stddev-sans-first:38617.74 15:07:05 INFO - PID 1650 | Values: 712.2 830.6 838.4 868.5 868.8 16548.6 16834.5 16904.0 16925.2 16970.9 12768.4 13193.1 13260.5 13386.7 13674.0 678.8 962.1 983.1 983.8 984.1 1017.1 1018.1 1023.2 1024.7 1025.2 1068.5 1069.3 1073.3 1074.4 1079.0 950.3 989.7 1070.2 1070.9 1072.1 792.5 795.0 796.3 798.6 799.8 17233.7 17345.3 17361.2 17383.3 17392.6 16781.3 17435.9 17682.3 17739.1 17815.6 14244.3 14521.5 14668.9 14746.1 14879.1 3613.5 3639.6 3643.9 3645.8 3649.5 90560.5 145888.0 147968.5 149063.0 152028.0 11813.4 13038.0 14076.4 14299.6 14321.4 25768.0 26096.9 26584.1 26869.6 26951.6 8003.6 8112.7 8122.0 8128.3 8147.2 3947.7 4022.1 4033.3 4034.8 4042.6 4667.3 4687.9 4711.0 4736.9 4757.1 914.5 946.5 951.1 963.9 964.1 626.7 640.9 651.1 661.5 689.1 11251.0 12131.2 12285.4 12345.9 12449.3 636.3 640.5 650.9 659.9 685.2 5314.6 5544.2 5656.9 5809.0 5917.4 132587.3 133774.0 135027.3 139675.0 144223.0 110443.0 120035.4 124064.0 127317.7 129948.0 74698.6 100510.0 109532.0 110735.4 112443.9 10314.3 11533.4 12233.4 12377.9 12508.8 38948.4 42867.0 45643.4 46273.5 46314.0 68770.3 74895.0 81633.0 81653.6 85112.1 16800.0 17189.6 17504.9 17821.7 18138.1 17406.7 17420.5 19320.5 19475.2 19498.5 17167.3 17734.6 18158.0 18514.3 18601.9 17107.5 17911.9 18093.2 18378.6 18413.4 16164.8 16165.7 16771.5 17516.4 17531.8 10958.5 11656.8 11765.9 12062.3 12253.8 13111.7 13279.0 13305.9 13402.1 13741.1 15:07:05 INFO - PID 1650 | -------- Summary: end -------- 15:07:05 INFO - PID 1650 | 15:07:06 INFO - TEST-INFO | 1650: exit 0 15:07:06 INFO - TEST-OK | dromaeo_css | took 1143033ms 15:07:06 INFO - TEST-START | kraken 15:07:06 INFO - operating with platform_type : mac_ 15:07:06 INFO - Initialising browser for kraken test... 15:07:06 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49227/getInfo.html -foreground -profile /var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/tmpe0UFo0/profile 15:07:06 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49227/getInfo.html -foreground) 15:07:40 INFO - TEST-INFO | 1659: killed by SIGTERM 15:07:40 INFO - Browser initialized. 15:07:40 INFO - Running cycle 1/1 for kraken test... 15:07:40 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/tmpe0UFo0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:07:42 INFO - PID 1663 | 2017-06-12 15:07:42.364 plugin-container[1664:10813] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9843, name = 'com.apple.tsm.portname' 15:07:42 INFO - PID 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:07:42 INFO - PID 1663 | 2017-06-12 15:07:42.364 plugin-container[1664:10813] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4907, name = 'com.apple.CFPasteboardClient' 15:07:42 INFO - PID 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:07:42 INFO - PID 1663 | 2017-06-12 15:07:42.365 plugin-container[1664:10813] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:07:43 INFO - PID 1663 | 2017-06-12 15:07:43.161 plugin-container[1665:10861] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4743, name = 'com.apple.tsm.portname' 15:07:43 INFO - PID 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:07:43 INFO - PID 1663 | 2017-06-12 15:07:43.162 plugin-container[1665:10861] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d0b, name = 'com.apple.CFPasteboardClient' 15:07:43 INFO - PID 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:07:43 INFO - PID 1663 | 2017-06-12 15:07:43.162 plugin-container[1665:10861] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:07:52 INFO - PID 1663 | RSS: Main: 253186048 15:07:52 INFO - PID 1663 | 15:07:55 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-beat-detection) 15:07:56 INFO - PID 1663 | RSS: Main: 265502720 15:07:56 INFO - PID 1663 | 15:07:58 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-dft) 15:07:59 INFO - PID 1663 | RSS: Main: 275214336 15:07:59 INFO - PID 1663 | 15:08:02 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-fft) 15:08:03 INFO - PID 1663 | RSS: Main: 278159360 15:08:03 INFO - PID 1663 | 15:08:05 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-oscillator) 15:08:05 INFO - PID 1663 | RSS: Main: 280203264 15:08:05 INFO - PID 1663 | 15:08:08 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49227/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:08:08 INFO - PID 1663 | RSS: Main: 280662016 15:08:08 INFO - PID 1663 | 15:08:11 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49227/tests/kraken/driver.html?testName=imaging-darkroom) 15:08:12 INFO - PID 1663 | RSS: Main: 286638080 15:08:12 INFO - PID 1663 | 15:08:15 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49227/tests/kraken/driver.html?testName=imaging-desaturate) 15:08:16 INFO - PID 1663 | RSS: Main: 288727040 15:08:16 INFO - PID 1663 | 15:08:19 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49227/tests/kraken/driver.html?testName=json-parse-financial) 15:08:20 INFO - PID 1663 | RSS: Main: 287612928 15:08:20 INFO - PID 1663 | 15:08:21 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49227/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:08:22 INFO - PID 1663 | RSS: Main: 287686656 15:08:22 INFO - PID 1663 | 15:08:23 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:08:24 INFO - PID 1663 | RSS: Main: 287666176 15:08:24 INFO - PID 1663 | 15:08:26 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:08:27 INFO - PID 1663 | RSS: Main: 287686656 15:08:27 INFO - PID 1663 | 15:08:30 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:08:31 INFO - PID 1663 | RSS: Main: 287830016 15:08:31 INFO - PID 1663 | 15:08:34 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:08:34 INFO - PID 1663 | RSS: Main: 287641600 15:08:34 INFO - PID 1663 | 15:08:36 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49227/tests/kraken/driver.html?testName=ai-astar) 15:08:36 INFO - PID 1663 | RSS: Main: 289304576 15:08:36 INFO - PID 1663 | 15:08:36 INFO - PID 1663 | __start_tp_report 15:08:36 INFO - PID 1663 | _x_x_mozilla_page_load 15:08:36 INFO - PID 1663 | _x_x_mozilla_page_load_details 15:08:36 INFO - PID 1663 | |i|pagename|runs| 15:08:36 INFO - PID 1663 | |0;ai-astar;105;115;130;119;116;131;167;120;116;117 15:08:36 INFO - PID 1663 | |1;audio-beat-detection;141;115;132;142;109;129;99;152;171;140 15:08:36 INFO - PID 1663 | |2;audio-dft;200;200;209;226;209;218;199;198;208;199 15:08:36 INFO - PID 1663 | |3;audio-fft;69;74;79;133;46;78;95;92;103;47 15:08:36 INFO - PID 1663 | |4;audio-oscillator;68;70;109;71;104;113;104;87;70;114 15:08:36 INFO - PID 1663 | |5;imaging-gaussian-blur;89;105;76;80;106;118;101;134;89;127 15:08:36 INFO - PID 1663 | |6;imaging-darkroom;142;150;96;96;121;152;129;95;126;113 15:08:36 INFO - PID 1663 | |7;imaging-desaturate;147;116;93;93;90;90;94;91;91;96 15:08:36 INFO - PID 1663 | |8;json-parse-financial;50;50;53;52;54;54;55;53;54;53 15:08:36 INFO - PID 1663 | |9;json-stringify-tinderbox;58;56;61;63;58;63;55;55;56;57 15:08:36 INFO - PID 1663 | |10;stanford-crypto-aes;79;106;80;81;81;78;82;87;101;102 15:08:36 INFO - PID 1663 | |11;stanford-crypto-ccm;134;140;136;133;162;142;162;160;164;172 15:08:36 INFO - PID 1663 | |12;stanford-crypto-pbkdf2;229;226;224;183;181;177;177;177;178;184 15:08:36 INFO - PID 1663 | |13;stanford-crypto-sha256-iterative;70;63;67;68;68;68;62;65;67;68 15:08:36 INFO - PID 1663 | __end_tp_report 15:08:36 INFO - PID 1663 | __start_cc_report 15:08:36 INFO - PID 1663 | _x_x_mozilla_cycle_collect,1162 15:08:36 INFO - PID 1663 | __end_cc_report 15:08:36 INFO - PID 1663 | __startTimestamp1497305316717__endTimestamp 15:08:36 INFO - PID 1663 | 15:08:36 INFO - PID 1663 | ------- Summary: start ------- 15:08:36 INFO - PID 1663 | Number of tests: 14 15:08:36 INFO - PID 1663 | 15:08:36 INFO - PID 1663 | [#0] ai-astar Cycles:10 Average:123.60 Median:119.50 stddev:16.97 (14.2%) stddev-sans-first:16.61 15:08:36 INFO - PID 1663 | Values: 105.0 115.0 130.0 119.0 116.0 131.0 167.0 120.0 116.0 117.0 15:08:36 INFO - PID 1663 | 15:08:36 INFO - PID 1663 | [#1] audio-beat-detection Cycles:10 Average:133.00 Median:141.50 stddev:21.27 (15.0%) stddev-sans-first:22.36 15:08:36 INFO - PID 1663 | Values: 141.0 115.0 132.0 142.0 109.0 129.0 99.0 152.0 171.0 140.0 15:08:36 INFO - PID 1663 | 15:08:36 INFO - PID 1663 | [#2] audio-dft Cycles:10 Average:206.60 Median:208.50 stddev:9.41 (4.5%) stddev-sans-first:9.67 15:08:36 INFO - PID 1663 | Values: 200.0 200.0 209.0 226.0 209.0 218.0 199.0 198.0 208.0 199.0 15:08:36 INFO - PID 1663 | 15:08:36 INFO - PID 1663 | [#3] audio-fft Cycles:10 Average:81.60 Median:76.00 stddev:26.01 (34.2%) stddev-sans-first:27.18 15:08:36 INFO - PID 1663 | Values: 69.0 74.0 79.0 133.0 46.0 78.0 95.0 92.0 103.0 47.0 15:08:36 INFO - PID 1663 | 15:08:36 INFO - PID 1663 | [#4] audio-oscillator Cycles:10 Average:91.00 Median:69.00 stddev:19.73 (28.6%) stddev-sans-first:19.09 15:08:36 INFO - PID 1663 | Values: 68.0 70.0 109.0 71.0 104.0 113.0 104.0 87.0 70.0 114.0 15:08:36 INFO - PID 1663 | 15:08:36 INFO - PID 1663 | [#5] imaging-gaussian-blur Cycles:10 Average:102.50 Median:105.00 stddev:19.51 (18.6%) stddev-sans-first:20.07 15:08:36 INFO - PID 1663 | Values: 89.0 105.0 76.0 80.0 106.0 118.0 101.0 134.0 89.0 127.0 15:08:36 INFO - PID 1663 | 15:08:36 INFO - PID 1663 | [#6] imaging-darkroom Cycles:10 Average:122.00 Median:151.00 stddev:21.89 (14.5%) stddev-sans-first:21.99 15:08:36 INFO - PID 1663 | Values: 142.0 150.0 96.0 96.0 121.0 152.0 129.0 95.0 126.0 113.0 15:08:36 INFO - PID 1663 | 15:08:36 INFO - PID 1663 | [#7] imaging-desaturate Cycles:10 Average:100.10 Median:92.00 stddev:18.19 (19.8%) stddev-sans-first:8.16 15:08:36 INFO - PID 1663 | Values: 147.0 116.0 93.0 93.0 90.0 90.0 94.0 91.0 91.0 96.0 15:08:36 INFO - PID 1663 | 15:08:36 INFO - PID 1663 | [#8] json-parse-financial Cycles:10 Average:52.80 Median:53.50 stddev:1.69 (3.2%) stddev-sans-first:1.45 15:08:36 INFO - PID 1663 | Values: 50.0 50.0 53.0 52.0 54.0 54.0 55.0 53.0 54.0 53.0 15:08:36 INFO - PID 1663 | 15:08:36 INFO - PID 1663 | [#9] json-stringify-tinderbox Cycles:10 Average:58.20 Median:58.00 stddev:3.08 (5.3%) stddev-sans-first:3.27 15:08:36 INFO - PID 1663 | Values: 58.0 56.0 61.0 63.0 58.0 63.0 55.0 55.0 56.0 57.0 15:08:36 INFO - PID 1663 | 15:08:36 INFO - PID 1663 | [#10] stanford-crypto-aes Cycles:10 Average:87.70 Median:80.50 stddev:10.89 (13.5%) stddev-sans-first:11.09 15:08:36 INFO - PID 1663 | Values: 79.0 106.0 80.0 81.0 81.0 78.0 82.0 87.0 101.0 102.0 15:08:36 INFO - PID 1663 | 15:08:36 INFO - PID 1663 | [#11] stanford-crypto-ccm Cycles:10 Average:150.50 Median:161.00 stddev:14.80 (9.2%) stddev-sans-first:14.44 15:08:36 INFO - PID 1663 | Values: 134.0 140.0 136.0 133.0 162.0 142.0 162.0 160.0 164.0 172.0 15:08:36 INFO - PID 1663 | 15:08:36 INFO - PID 1663 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:193.60 Median:183.50 stddev:22.76 (12.4%) stddev-sans-first:20.21 15:08:36 INFO - PID 1663 | Values: 229.0 226.0 224.0 183.0 181.0 177.0 177.0 177.0 178.0 184.0 15:08:36 INFO - PID 1663 | 15:08:36 INFO - PID 1663 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.60 Median:68.00 stddev:2.50 (3.7%) stddev-sans-first:2.33 15:08:36 INFO - PID 1663 | Values: 70.0 63.0 67.0 68.0 68.0 68.0 62.0 65.0 67.0 68.0 15:08:36 INFO - PID 1663 | -------- Summary: end -------- 15:08:36 INFO - PID 1663 | 15:08:37 INFO - TEST-INFO | 1663: exit 0 15:08:37 INFO - TEST-OK | kraken | took 91198ms 15:08:37 INFO - SUITE-END | took 1234s 15:08:37 INFO - Completed test suite (00:20:34) 15:08:37 INFO - javascript benchmark 15:08:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8206.555043557522, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3033.469142899993, 3244.1240864966467, 3257.902262932113, 3573.4103872860974, 3635.309291234665, 5059.291699162116, 5724.828255152342, 6227.719752611138, 6295.685215739206, 6299.685015749212, 6233.750649973996, 6257.124002639634, 6293.968530157343, 6305.558610897239, 6310.463610593094, 5567.387587365397, 5567.582431317647, 5602.1036634138545, 5709.1150871614755, 5769.7115144242825, 5619.634723742964, 5757.856053598673, 5775.249217601727, 5786.537077033849, 5809.244798176232, 5447.591430642718, 5478.068728316196, 5609.0, 5611.579131565149, 5631.493165615073, 5498.560115190775, 5519.613627046108, 5545.72271386429, 5577.386487486373, 5609.691466969315, 5486.972565137189, 5522.917156242619, 5530.695811730353, 5585.916211256835, 5632.436756324376, 6154.199954005995, 6270.717817698169, 6274.309825919146, 6297.685115744194, 6309.305976342599, 6666.833329166761, 6741.191057073133, 6748.527603067787, 6793.592384456899, 6813.386795188408, 5582.944170558324, 6011.428914253139, 6324.209473815773, 6795.93204067958, 6834.2482326944, 6269.185005949197, 6326.778562750374, 6332.208473940757, 6379.425851673325, 6380.298167201604, 5967.492763115184, 7057.576545407189, 7064.682089305994, 7091.680874360666, 7099.82250443743, 6441.484681225491, 6503.967480162663, 6697.799066028027, 6704.363085506869, 6708.0, 5697.601167918288, 6704.731810727614, 6744.156980377453, 6758.5269031167345, 6769.255381907986, 6142.406692085091, 6609.86780264392, 6661.26726060133, 6761.053452516651, 6767.39093481589, 5944.791932282349, 6133.640713961371, 6774.288699686561, 6788.253292137861, 6794.694238759268, 5392.056390131711, 5398.379186393615, 5406.91889621656, 5413.0, 5437.9939711153565, 4770.928436073462, 4773.5679296211, 4778.211595086841, 4801.591864691471, 4809.158397280532, 5280.577553795687, 6109.222480666452, 6299.590526615755, 6327.367263273728, 6426.3573642635365, 6727.192736871607, 6730.596164230162, 6747.730090796412, 6751.25736169021, 6761.492888033417, 6300.464460520815, 6338.8415289617205, 6361.4592759615025, 6376.29860715321, 6393.936060639334, 5982.820515384546, 6047.606905551125, 6056.394360563909, 6077.8480537986015, 6123.4488895999575, 5526.47498487643, 6639.336066393322, 6885.1049363582415, 7008.159020917523, 7050.083489146378, 6627.569208001464, 6836.760713375008, 6868.793936181923, 6883.655817209019, 6917.688704008231, 5138.665986710852, 5799.565032622654, 6914.135733033371, 6931.272216417204, 7077.6461176941975, 6708.060871477899, 6755.831104222434, 6774.42417394527, 6775.288594697683, 6792.66036698173, 5859.472647461749, 6896.620685862325, 6910.723571057101, 6916.412104970933, 6942.652867356713, 6676.83307917306, 6686.13080299558, 6689.2307384651385, 6708.295628958889, 6713.597184168965, 6447.9355206447335, 6649.700763465752, 6676.265610782712, 6688.632125233159, 6716.798496045127, 6335.968320158369, 6358.936410635834, 6394.23269207698, 6516.967415163083, 6534.771283005072, 6592.208934927839, 6659.367360100783, 6684.131062961784, 6694.732210711517, 6731.932680673131, 6476.708548115252, 6505.577137486238, 6511.837204069936, 6527.314631963575, 6527.67361631907], "value": 6132.464398043796, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [708.4828075504881, 768.7885831396366, 799.7640695994688, 811.1037303779322, 838.274892218231, 36867.631323686786, 38033.23933521328, 38080.66717664882, 38190.42714359287, 38197.80901095494, 37399.439008414825, 37593.81203093987, 37619.0, 37873.81063094688, 37907.81046094766, 7169.462290328233, 7260.745873894389, 7270.127584689844, 7283.963580182118, 7286.417086633057, 7227.602481863495, 7278.490505664606, 7327.047483827095, 7330.2669733026805, 7344.8898266525775, 7229.132504099515, 7242.710291588356, 7299.41604671625, 7305.963470182642, 7343.082114735658, 7264.5641261524215, 7271.018412514349, 7278.526895751812, 7310.926890731078, 7334.706611735551, 6559.868802623969, 6601.3728695773825, 6613.537052406334, 6628.5691430056895, 7953.085395179571, 35728.64271357305, 35957.640423595694, 36076.81961590175, 36096.639033609594, 36107.81946090279, 3502.2995400919917, 3508.035290295189, 3554.0759402555263, 3554.4845997330244, 3577.606463289036, 29186.562201566994, 29307.413851722846, 29314.560281595794, 29344.706552934626, 29359.972400965864, 3055.5569442430947, 3073.89241376553, 3080.4609193391293, 3090.9536356954663, 3099.271671157299, 223623.0, 241270.7936460324, 254470.0, 292352.53823730745, 303188.0, 37815.43276850849, 45744.77127614341, 46442.303365449545, 46487.535124648995, 46766.5323346769, 49592.0081598366, 50081.49918500841, 50141.0, 50145.24782128271, 50270.74864625726, 13804.17174969505, 13944.442222311196, 13946.093503922213, 13950.302484875716, 13994.650133746534, 11406.543738250377, 11413.60052398162, 11417.543298267976, 11423.771524569462, 11457.541698331974, 13346.532871349456, 13363.265020423776, 13381.46474141043, 13397.196168229937, 13493.122947008413, 4891.633127515451, 4921.335619691367, 5735.569832262597, 5816.621919575214, 5823.068309070529, 990.9256805739599, 1000.9049140331657, 1016.425719468498, 1041.890172400879, 1056.3767377247461, 22304.88847555752, 22404.304637384324, 22703.432414189778, 22858.771412285998, 23680.28959131229, 1081.4106312059946, 1086.2613422872523, 1144.2447984330468, 1147.632757517603, 1148.2364227788594, 11482.94258528702, 11559.017483513995, 11741.119416043832, 11785.410729463492, 12304.81542776859, 340445.0, 343212.0, 343665.0, 344496.0, 344770.0, 52339.0, 53032.93934121296, 53034.73482632639, 53041.469585303654, 53127.20309195365, 1464.714380695754, 1465.6076727109075, 1506.2995706996298, 1506.922550376486, 1509.9622509437352, 21744.565108698054, 22005.0, 22631.434214144778, 22655.867206639272, 23484.765152348256, 37827.810860945516, 37882.86351409462, 37942.620573795015, 37967.81016094902, 37990.62009379871, 283714.58142709156, 283857.0, 284044.0, 284127.0, 284603.0, 29019.41961160807, 29533.704662953096, 30077.849610751808, 33944.490832638025, 34099.488507672904, 37495.625043750304, 40498.1900361997, 40682.135197156225, 43056.35415468708, 43281.0, 26907.730922690524, 27222.319442013315, 28491.0, 29259.41481170407, 30608.081757546417, 28831.5675264867, 29522.55716164302, 30013.249668758457, 30222.54666179965, 30422.847885760428, 32607.85872494546, 35954.4606830903, 36100.63899361078, 36183.552657893386, 36188.27623447569, 27178.45643087167, 28015.879364825178, 28621.856890715415, 29028.419431610826, 29459.96890108836, 19783.703244451055, 20535.589288213854, 22232.888835555717, 23760.0, 23823.16618918399], "value": 16596.302201795508, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [498.4541926590382, 504.1278588042684, 510.1837060702875, 519.9922001169982, 521.8877941242632, 19493.73290736103, 20245.493862653424, 20459.0, 21121.57756844862, 21986.560268794616, 22533.887330563364, 22610.54778904421, 22865.657015144785, 22878.771212287873, 22894.427639308982, 5924.6445213287125, 6025.758969641209, 6098.024316109423, 6107.725152368154, 6112.144299798032, 6002.8799424011495, 6084.330723620398, 6086.634801911878, 6101.20684311039, 6107.541934354919, 5520.558355331564, 6045.365236650155, 6064.696765161746, 6189.133521307021, 6226.813195604139, 6157.0456579230295, 6217.968910155466, 6219.875602487925, 6222.315545290015, 6239.34486878878, 5651.660900345993, 5655.095184770458, 5656.490915817596, 5657.321121465409, 5657.773689052433, 26231.73768262312, 27019.0, 27448.862755686092, 27489.862550687118, 27540.036098736648, 2143.3998480425507, 2247.505548779266, 2266.648669456237, 2271.4207876991427, 2274.7384050834034, 17863.73204401935, 18119.637607247914, 18242.908785456122, 18330.541736456562, 18365.0, 1914.7702275726863, 2128.8509804313703, 2169.425102347879, 2204.0963205085836, 2205.691203231549, 164077.0, 172856.0, 174029.0, 174517.0, 188322.0, 11885.028742814296, 12537.247765134121, 12999.09006369564, 14389.177711428823, 14574.834013278913, 19037.524061898286, 19309.51726206856, 19331.033448327526, 19338.516537086685, 19441.902790485958, 9185.586648600829, 9192.81614367709, 9539.284553658503, 9542.90457095434, 9603.711888643353, 8871.955640221886, 8886.73339799807, 8917.643294268157, 8934.821303573892, 8963.417377870417, 8488.830223395498, 8579.099194584478, 8592.8711069334, 8598.0, 8600.247920665328, 2553.6424900513944, 2555.1695698897934, 2570.293169378436, 2571.151519998396, 2707.9690232325756, 970.8640790289365, 975.5139131075445, 988.2439933450916, 997.6980041046359, 1006.9496525173712, 19782.30761923326, 19982.900940448413, 20115.0, 20508.0, 20733.65071911369, 977.6578197630772, 980.2256217588168, 999.3703966501204, 1037.6057098302597, 1037.9221558383151, 7938.0, 10016.29885907994, 10027.348222365523, 10315.948420258, 10479.580816767397, 560306.0, 563607.0, 564241.0, 566521.5500652269, 573016.0, 136174.0, 137366.3131684355, 141703.45741356548, 142293.0, 143016.28491857683, 938.7324612485409, 1146.856642919635, 1209.612923864355, 1253.11029169289, 1257.43415463042, 20137.6979345307, 20431.182752689725, 21451.570968580854, 21680.67478987785, 22556.887215563816, 67955.66022170056, 68877.0, 70599.64700176466, 70731.64634176796, 70926.64536677283, 70480.0, 88953.55523222343, 89868.65197021919, 91456.0, 92183.07816921745, 10888.61889833852, 10998.780024399628, 11144.554217831195, 11154.88845111571, 11194.720131996764, 14999.475018374305, 17133.314667413164, 17178.31286748566, 17777.377791777224, 17831.910840445715, 13915.095518791246, 14106.29468526549, 14191.574252772434, 14201.218932958787, 14530.854691453373, 12068.517259309529, 12194.451249693608, 12303.876961230273, 12893.677658058623, 13094.0, 14492.782608260673, 14685.706285874436, 14704.264786760834, 14718.926405367905, 14723.631909202355, 10494.0, 11125.777484450427, 11171.553137874718, 11217.887821121685, 11314.717132071764, 14260.00179987391, 14310.928445357707, 14347.354369053626, 14489.05821121666, 14501.4924477647], "value": 11236.351770524336, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1315.802629605559, 1480.6520467690098, 1504.76609180472, 1509.5094094419317, 1520.2322826972381, 11305.943470282647, 13399.665008374795, 13438.328083595818, 13476.932615336922, 13492.190468571893, 9327.6735314264, 11335.603253886116, 11507.367094809802, 12997.935010324958, 13451.66370840727, 2702.945941081182, 2715.049732593586, 2728.6998430172666, 2751.092139593939, 2751.257160566646, 2383.3088404362716, 2691.4886171627427, 2692.3538350795766, 2716.769074628659, 2741.7943654225915, 2110.493481564421, 2571.842670798139, 2679.2364176209785, 2714.9728502714916, 2766.197802637243, 2415.7825795678386, 2541.9110331138413, 2641.537730897095, 2747.9038233661927, 2748.6014527893544, 2759.986200068987, 2782.2627003844, 2785.7492825645586, 2788.121741651373, 2791.3719413131885, 13301.933490332487, 13376.665583360396, 13381.19712817234, 13395.0, 13418.46326146953, 7369.6315184240575, 7563.168051514329, 7636.31273185416, 7645.9235407645765, 7651.273129052731, 4445.666575006855, 4652.069586082763, 4693.108309421199, 4756.191447453906, 4757.26262429324, 1375.1405371642722, 1396.6578188343922, 1419.9929000355035, 1422.743906096902, 1423.0323380101527, 19583.804161958342, 19590.314338998065, 19637.0, 19682.70475942862, 19728.21087156512, 6344.49244060474, 6483.092367068614, 6509.934900651027, 6590.670466476657, 6604.405603495612, 13922.651933701536, 14041.71916561663, 14050.227237502035, 14060.367283472267, 14073.577792666236, 7234.927650723531, 7333.559986400833, 7347.008153899262, 7372.041634587469, 7387.6675549600395, 6406.391392817675, 6819.386255237053, 6826.24911259761, 6834.897476537856, 6841.486888483384, 4755.976220118947, 4931.137051016058, 4954.47977962316, 4969.428515720666, 4977.676451030671, 1425.5081996711117, 1442.4879167895456, 1449.8477659845776, 1463.297617143777, 1466.933987970544, 783.7687882074802, 832.2301870769514, 858.9613467393982, 864.9610767515477, 869.7216890594949, 6504.38208370204, 7372.963135184397, 7693.961530192313, 7864.41016923733, 8374.413791034569, 933.4819175357605, 968.0948313327061, 973.8880028796779, 977.2524019125375, 990.4552496127101, 4639.721616703008, 5203.401608814959, 5226.660267082628, 5286.0, 5534.252875861787, 17809.7328540072, 18155.909220454076, 18263.17815698297, 18313.992730399958, 18318.90840545789, 17590.296388144332, 18240.0, 18367.448976530726, 18402.907985459988, 18443.81556184421, 9331.72004839856, 9511.619535218513, 9516.095970882756, 9516.286278529, 9527.475988820543, 7430.814229644086, 7803.960980195063, 8058.0, 8106.270435660819, 8347.248747612744, 16159.272832722321, 16484.83515164833, 16528.83471165273, 16695.33218671288, 16895.746563801305, 15513.37946482128, 15635.687286254439, 15639.905206635882, 16829.0, 17077.658446831243, 10718.624848130277, 10919.290246133976, 10923.508442119964, 10956.233063685466, 10959.506822192861, 12189.329586872807, 12196.268223906594, 12202.32887191213, 12324.32216228116, 12416.75166496683, 9572.573683561475, 9773.12041916231, 10031.448270345201, 10238.385696858213, 10308.845367319645, 9004.009558948379, 9274.44353338802, 9394.483303418378, 9575.0, 9616.038396160327, 7636.579988100486, 8956.596953136994, 8974.506402147683, 9067.957184923554, 9174.770630734285, 6544.803655890331, 6657.167854018247, 6908.689108990007, 6949.270326615632, 6978.546394484342, 5831.7667293307795, 6174.66039367839, 6802.387785099365, 7160.749373771893, 7164.2119366868565], "value": 6043.936220024469, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [399.95800440953707, 405.8762077566342, 454.6544626084177, 463.2287241742499, 469.1765950756422, 5485.862853428666, 6268.153799237108, 6448.967755161229, 6456.515761317903, 6474.93163628002, 6242.781502647408, 6251.656158911258, 6292.433680968712, 6302.29837943755, 6312.179416675837, 4034.475518182632, 4410.140022695576, 4569.0, 4784.234522476405, 4856.684315519497, 3622.6196249393697, 4767.1419144553965, 4775.9761201194115, 4818.723038394828, 4852.41770987482, 4369.8217612129565, 4644.8633628530915, 4793.616510679138, 4803.063402636487, 4809.90380192396, 3981.5222173339093, 4744.454387745419, 4763.833265835679, 4783.928241076369, 4826.0695721711245, 4096.32410652242, 4151.91696166078, 4163.084121493267, 4172.207280616703, 4183.477065366829, 5186.299849520305, 5544.584156188302, 6224.502039836802, 6247.219097612799, 6269.968650156766, 4408.933865992013, 5650.6892120933335, 5662.433756624305, 5689.544836413077, 5695.572832037614, 6642.27300901769, 6821.964225919468, 6898.344657257603, 6901.309869013109, 6912.965435172842, 4332.9566704332865, 4351.28203846365, 4777.4267087949665, 4779.378680771512, 4814.253790662453, 6964.616946067914, 7242.45681573884, 7385.778426647209, 7403.814904627374, 7409.296116868889, 4545.636309107254, 4710.29345598163, 4721.836369632355, 4837.879053023703, 4843.24929635907, 4035.4955630546183, 4716.882077948078, 4805.063012712558, 4904.754762261872, 4959.454460009396, 4858.80564777412, 5623.8594035149445, 5638.182463542763, 5657.51911087554, 5685.971570142123, 6814.284500127515, 6823.1812182538415, 6831.975203719482, 6837.35045170708, 6857.588544687334, 1518.9468368607045, 1555.4633651390252, 1558.6906998121633, 1622.399712106528, 1707.5048236011667, 1126.459299536211, 1144.8626164860104, 1154.6709187881581, 1158.177693837368, 1162.9301043040423, 5932.970335148297, 6136.325004249529, 6506.869862602816, 6510.283868774431, 6521.9673901630185, 5977.910331345033, 6850.38346548803, 7102.0, 7113.466490013269, 7132.572045677172, 5559.555235581144, 6049.941260279434, 6139.877202455926, 6354.887894618423, 6441.1304473895425, 3352.2625022495013, 3504.807235602066, 3700.0, 3731.850725970931, 3744.569374521965, 7356.4919191565605, 7360.668769905367, 7375.88936165958, 7380.594067326241, 7408.073990751156, 7097.148342198969, 7189.0, 7300.817479563053, 7314.414846812242, 7327.523710958771, 7280.490365674352, 7353.38195623932, 7374.188839227681, 7380.926190738024, 7383.187849336461, 6310.558260921875, 6450.903236451363, 6997.075804153898, 7121.323474269936, 7138.678759455733, 6272.4982001440685, 6938.895916561155, 6986.288359351788, 7021.894671579828, 7036.0, 6340.4293613575, 7199.820004499929, 7308.488405811542, 7364.705411783469, 7388.408927285912, 4152.4601801765575, 4883.462819089827, 4891.33966914462, 4898.53463920927, 4924.522643207095, 6387.93612063892, 6911.447084233148, 7038.2961703829205, 7149.821254468472, 7161.961515580316, 6045.037931413488, 6503.967480162569, 6625.370589793962, 6812.897806533005, 6902.171739391104, 5826.504747096545, 6036.33600303975, 6091.604045737013, 6136.969315153396, 6899.0, 6222.968885155546, 6731.148934043177, 6741.966290168518, 6757.763478278237, 6781.728730850711, 6070.332263450929, 6544.0, 6917.342852429011, 6918.377346038881, 7001.474889383215, 5406.4052954175795, 6736.090627765189, 6742.561733487308, 6744.22441419243, 6779.288174741581], "value": 5022.957074636109, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [712.188105559662, 830.5888585150351, 838.4131108224249, 868.452874688946, 868.8262347530491, 16548.586285342873, 16834.49496515105, 16904.0, 16925.153742312898, 16970.915145424256, 12768.42542085606, 13193.14244574102, 13260.46958121674, 13386.66533336667, 13674.0, 678.8438659108408, 962.1388856973005, 983.1446641421973, 983.7737320416309, 984.1093810101869, 1017.0744622393623, 1018.1193267823317, 1023.1967905194424, 1024.6823484719762, 1025.179856115105, 1068.4924660786141, 1069.262209075739, 1073.2540884085524, 1074.4198133008167, 1079.0288740133904, 950.2920324358328, 989.6833013435703, 1070.2454769387602, 1070.925035247537, 1072.0619457974271, 792.4532072869702, 795.0181525815613, 796.2833449895082, 798.5568009754584, 799.848028874512, 17233.655326893517, 17345.306187752474, 17361.218745156497, 17383.30466781327, 17392.565185870455, 16781.328746850235, 17435.912820435817, 17682.292708291654, 17739.11304434786, 17815.643687126183, 14244.287785610679, 14521.491747788778, 14668.853311466963, 14746.115233085942, 14879.107253564713, 3613.4941108244866, 3639.5632524097014, 3643.8542458301636, 3645.7994810285422, 3649.4708267301094, 90560.54719726359, 145888.0, 147968.52031479654, 149063.0, 152028.0, 11813.350265735295, 13038.0, 14076.366563504667, 14299.642508937151, 14321.35553900077, 25768.0, 26096.8695156523, 26584.06955756539, 26869.596956045676, 26951.595726064126, 8003.559804210824, 8112.716054938049, 8122.025356957203, 8128.349732021542, 8147.226013528705, 3947.684185265172, 4022.0950286185375, 4033.274010678106, 4034.778087205173, 4042.6159514846045, 4667.346571479996, 4687.859364219079, 4710.976445117821, 4736.881577960578, 4757.09615173116, 914.4558987402485, 946.4699768129869, 951.110711484764, 963.9180669643159, 964.0600414595854, 626.6803929995735, 640.9134766806515, 651.1209866679944, 661.5104822431366, 689.0525527399825, 11251.0, 12131.150819442555, 12285.38573071343, 12345.876541234653, 12449.315287659265, 636.3350298937621, 640.5196102922808, 650.9121268628675, 659.9175103112111, 685.1675214614268, 5314.5748340132695, 5544.1960915667805, 5656.8868622627315, 5808.970955145282, 5917.378675239038, 132587.33706331407, 133774.0, 135027.32486337505, 139675.0, 144223.0, 110443.0, 120035.39982300032, 124064.0, 127317.72682273244, 129948.0, 74698.62650686712, 100510.0, 109532.0, 110735.44632276786, 112443.87556124335, 10314.27800053989, 11533.365664888512, 12233.449494772578, 12377.938110309391, 12508.812367814307, 38948.41577376285, 42867.0, 45643.40248091434, 46273.537264626924, 46314.0, 68770.31229687839, 74895.0, 81633.0, 81653.59173204334, 85112.14887851042, 16800.0, 17189.57026074358, 17504.94970301786, 17821.73267401016, 18138.093095345444, 17406.651866962842, 17420.47738567845, 19320.516987075436, 19475.22099116076, 19498.512537186685, 17167.31330746756, 17734.645307093528, 18158.0, 18514.259429622663, 18601.860786009784, 17107.48677539676, 17911.910440447715, 18093.185806638998, 18378.63242735111, 18413.355532556296, 16164.838351616803, 16165.67668646644, 16771.496855094367, 17516.38692645751, 17531.82468175353, 10958.506867190836, 11656.825147622962, 11765.882341176479, 12062.276263423872, 12253.754924901274, 13111.672208194872, 13279.00407469463, 13305.933470332586, 13402.128861623964, 13741.106828056145], "value": 8798.091915376155, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1569.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 115.0, 130.0, 119.0, 116.0, 131.0, 167.0, 120.0, 116.0, 117.0], "value": 123.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 115.0, 132.0, 142.0, 109.0, 129.0, 99.0, 152.0, 171.0, 140.0], "value": 133.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 200.0, 209.0, 226.0, 209.0, 218.0, 199.0, 198.0, 208.0, 199.0], "value": 206.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 74.0, 79.0, 133.0, 46.0, 78.0, 95.0, 92.0, 103.0, 47.0], "value": 81.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 109.0, 71.0, 104.0, 113.0, 104.0, 87.0, 70.0, 114.0], "value": 91.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 105.0, 76.0, 80.0, 106.0, 118.0, 101.0, 134.0, 89.0, 127.0], "value": 102.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 150.0, 96.0, 96.0, 121.0, 152.0, 129.0, 95.0, 126.0, 113.0], "value": 122.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 116.0, 93.0, 93.0, 90.0, 90.0, 94.0, 91.0, 91.0, 96.0], "value": 100.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 53.0, 52.0, 54.0, 54.0, 55.0, 53.0, 54.0, 53.0], "value": 52.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 56.0, 61.0, 63.0, 58.0, 63.0, 55.0, 55.0, 56.0, 57.0], "value": 58.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 106.0, 80.0, 81.0, 81.0, 78.0, 82.0, 87.0, 101.0, 102.0], "value": 87.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 140.0, 136.0, 133.0, 162.0, 142.0, 162.0, 160.0, 164.0, 172.0], "value": 150.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [229.0, 226.0, 224.0, 183.0, 181.0, 177.0, 177.0, 177.0, 178.0, 184.0], "value": 193.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 63.0, 67.0, 68.0, 68.0, 68.0, 62.0, 65.0, 67.0, 68.0], "value": 66.6, "unit": "score"}]}]} 15:08:38 INFO - Return code: 0 15:08:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:08:38 INFO - # TBPL SUCCESS # 15:08:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:08:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:08:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:08:38 INFO - 2017-06-12 15:08:38.140 screenresolution[1671:11255] starting screenresolution argv=screenresolution get 15:08:38 INFO - 2017-06-12 15:08:38.143 screenresolution[1671:11255] Display 0: 1600x1200x32@60 15:08:38 INFO - 2017-06-12 15:08:38.149 screenresolution[1672:11258] starting screenresolution argv=screenresolution list 15:08:38 INFO - Available Modes on Display 0 15:08:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:08:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:08:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:08:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:08:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:08:38 INFO - Intel Iris: 15:08:38 INFO - Chipset Model: Intel Iris 15:08:38 INFO - Type: GPU 15:08:38 INFO - Bus: Built-In 15:08:38 INFO - VRAM (Dynamic, Max): 1536 MB 15:08:38 INFO - Vendor: Intel (0x8086) 15:08:38 INFO - Device ID: 0x0a2e 15:08:38 INFO - Revision ID: 0x0009 15:08:38 INFO - Displays: 15:08:38 INFO - Display: 15:08:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:08:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:08:38 INFO - Display Serial Number: 1600x1200 60 15:08:38 INFO - Main Display: Yes 15:08:38 INFO - Mirror: Off 15:08:38 INFO - Online: Yes 15:08:38 INFO - Rotation: Supported 15:08:38 INFO - Return code: 0 15:08:38 INFO - Running post-action listener: _resource_record_post_action 15:08:38 INFO - [mozharness: 2017-06-12 22:08:38.392447Z] Finished run-tests step (success) 15:08:38 INFO - Running post-run listener: _resource_record_post_run 15:08:38 INFO - Total resource usage - Wall time: 1256s; CPU: 53.0%; Read bytes: 35640320; Write bytes: 572114944; Read time: 230; Write time: 1741 15:08:38 INFO - TinderboxPrint: CPU usage
53.4% 15:08:38 INFO - TinderboxPrint: I/O read bytes / time
35,640,320 / 230 15:08:38 INFO - TinderboxPrint: I/O write bytes / time
572,114,944 / 1,741 15:08:38 INFO - TinderboxPrint: CPU idle
2,339.6 (46.6%) 15:08:38 INFO - TinderboxPrint: CPU system
1,021.6 (20.3%) 15:08:38 INFO - TinderboxPrint: CPU user
1,664.6 (33.1%) 15:08:38 INFO - TinderboxPrint: Swap in / out
283,340,800 / 0 15:08:38 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 234056192; Write bytes: 226739712; Read time: 16649; Write time: 462 15:08:38 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 15:08:38 INFO - run-tests - Wall time: 1236s; CPU: 53.0%; Read bytes: 33502208; Write bytes: 317010432; Read time: 201; Write time: 1204 15:08:38 INFO - Running post-run listener: _upload_blobber_files 15:08:38 INFO - Blob upload gear active. 15:08:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:08:38 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 15:08:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:08:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:08:39 INFO - (blobuploader) - INFO - Open directory for files ... 15:08:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:08:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:08:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:08:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:08:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:08:40 INFO - (blobuploader) - INFO - Done attempting. 15:08:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:08:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:08:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:08:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:08:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:08:40 INFO - (blobuploader) - INFO - Done attempting. 15:08:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:08:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:08:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:08:41 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:08:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:08:41 INFO - (blobuploader) - INFO - Done attempting. 15:08:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:08:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:08:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:08:43 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:08:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:08:43 INFO - (blobuploader) - INFO - Done attempting. 15:08:43 INFO - (blobuploader) - INFO - Iteration through files over. 15:08:43 INFO - Return code: 0 15:08:43 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:08:43 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:08:43 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eb3fb953d9df29b272997e04d18bdd6d7ac390572360054375f965701596fb47ae45b58095eba29ab4e825c2ff0ad4cd986e5dfbbd82e27e2158879dffd1c235", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2ef5742c8778ef3d77111e9de32ed234ffdde30b4df6632ba9be118d269bc48eda3fc45a8c3f63cba9d4614c7e1a48c428eef0b4652b87be67419aa1f064be34", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4392ac095e903422fb23360d0b2aef2c72d6308d7befb570daa42932decd378f0ccb9d5ab521236bf85f0f91fd482495268b171ed0e0c797e76dd3ac68f545e3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e6a41ff1eaf65b0456abaaa29eb679bf8338f8261467706707a8c855d8c53a6d8c43d8d0cde833ca0d0e576539a05258c57b1e214a2e03b5d74e20b49aa70191"} 15:08:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:08:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:08:43 INFO - Contents: 15:08:43 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eb3fb953d9df29b272997e04d18bdd6d7ac390572360054375f965701596fb47ae45b58095eba29ab4e825c2ff0ad4cd986e5dfbbd82e27e2158879dffd1c235", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2ef5742c8778ef3d77111e9de32ed234ffdde30b4df6632ba9be118d269bc48eda3fc45a8c3f63cba9d4614c7e1a48c428eef0b4652b87be67419aa1f064be34", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4392ac095e903422fb23360d0b2aef2c72d6308d7befb570daa42932decd378f0ccb9d5ab521236bf85f0f91fd482495268b171ed0e0c797e76dd3ac68f545e3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e6a41ff1eaf65b0456abaaa29eb679bf8338f8261467706707a8c855d8c53a6d8c43d8d0cde833ca0d0e576539a05258c57b1e214a2e03b5d74e20b49aa70191"} 15:08:43 INFO - Running post-run listener: copy_logs_to_upload_dir 15:08:43 INFO - Copying logs to upload dir... 15:08:43 INFO - mkdir: /builds/slave/test/build/upload/logs 15:08:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1337.732214 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 17 secs) (at 2017-06-12 15:08:43.741517) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-12 15:08:43.744876) ========= 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.FJiNsBXElg/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.plOTrPUy6w/Listeners TMPDIR=/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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/Firefox/sha512/eb3fb953d9df29b272997e04d18bdd6d7ac390572360054375f965701596fb47ae45b58095eba29ab4e825c2ff0ad4cd986e5dfbbd82e27e2158879dffd1c235", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2ef5742c8778ef3d77111e9de32ed234ffdde30b4df6632ba9be118d269bc48eda3fc45a8c3f63cba9d4614c7e1a48c428eef0b4652b87be67419aa1f064be34", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4392ac095e903422fb23360d0b2aef2c72d6308d7befb570daa42932decd378f0ccb9d5ab521236bf85f0f91fd482495268b171ed0e0c797e76dd3ac68f545e3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e6a41ff1eaf65b0456abaaa29eb679bf8338f8261467706707a8c855d8c53a6d8c43d8d0cde833ca0d0e576539a05258c57b1e214a2e03b5d74e20b49aa70191"} build_url:https://queue.taskcluster.net/v1/task/RMESVxJzSV6NXsE8FceuUw/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010632 build_url: 'https://queue.taskcluster.net/v1/task/RMESVxJzSV6NXsE8FceuUw/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eb3fb953d9df29b272997e04d18bdd6d7ac390572360054375f965701596fb47ae45b58095eba29ab4e825c2ff0ad4cd986e5dfbbd82e27e2158879dffd1c235", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2ef5742c8778ef3d77111e9de32ed234ffdde30b4df6632ba9be118d269bc48eda3fc45a8c3f63cba9d4614c7e1a48c428eef0b4652b87be67419aa1f064be34", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4392ac095e903422fb23360d0b2aef2c72d6308d7befb570daa42932decd378f0ccb9d5ab521236bf85f0f91fd482495268b171ed0e0c797e76dd3ac68f545e3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e6a41ff1eaf65b0456abaaa29eb679bf8338f8261467706707a8c855d8c53a6d8c43d8d0cde833ca0d0e576539a05258c57b1e214a2e03b5d74e20b49aa70191"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-12 15:08:43.777995) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 15:08:43.778309) ========= 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.FJiNsBXElg/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.plOTrPUy6w/Listeners TMPDIR=/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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.005049 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 15:08:43.826519) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-12 15:08:43.826843) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-12 15:08:43.827180) ========= ========= Total master_lag: 0.17 =========