builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0361 starttime: 1496454946.04 results: success (0) revision: 43039280fe464869428f03b047bb7c762784f44b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-02 18:55:46.043574) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-02 18:55:46.044468) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-02 18:55:46.044755) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-02 18:55:46.071080) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 18:55:46.071587) ========= 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.RcvYUxpHPY/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.mqx6ZICjv1/Listeners TMPDIR=/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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-02 18:55:46-- 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% 18.0M=0.001s 2017-06-02 18:55:46 (18.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.153719 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 18:55:46.249247) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 18:55:46.249595) ========= 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.RcvYUxpHPY/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.mqx6ZICjv1/Listeners TMPDIR=/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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.029960 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 18:55:46.301409) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-02 18:55:46.301743) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 43039280fe464869428f03b047bb7c762784f44b --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 43039280fe464869428f03b047bb7c762784f44b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.RcvYUxpHPY/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.mqx6ZICjv1/Listeners TMPDIR=/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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-02 18:55:46,400 truncating revision to first 12 chars 2017-06-02 18:55:46,400 Setting DEBUG logging. 2017-06-02 18:55:46,400 attempt 1/10 2017-06-02 18:55:46,400 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/43039280fe46?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-02 18:55:47,451 unpacking tar archive at: mozilla-central-43039280fe46/testing/mozharness/ program finished with exit code 0 elapsedTime=1.333760 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-02 18:55:47.657242) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-02 18:55:47.657580) ========= script_repo_revision: 43039280fe464869428f03b047bb7c762784f44b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-02 18:55:47.657956) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-02 18:55:47.658238) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-02 18:55:47.676502) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-06-02 18:55:47.676917) ========= /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.RcvYUxpHPY/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.mqx6ZICjv1/Listeners TMPDIR=/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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 18:55:47 INFO - MultiFileLogger online at 20170602 18:55:47 in /builds/slave/test 18:55:47 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 18:55:47 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:55:47 INFO - {'append_to_log': False, 18:55:47 INFO - 'base_work_dir': '/builds/slave/test', 18:55:47 INFO - 'blob_upload_branch': 'Firefox', 18:55:47 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:55:47 INFO - 'branch': 'Firefox', 18:55:47 INFO - 'buildbot_json_path': 'buildprops.json', 18:55:47 INFO - 'config_files': ('talos/mac_config.py',), 18:55:47 INFO - 'default_actions': ('clobber', 18:55:47 INFO - 'read-buildbot-config', 18:55:47 INFO - 'download-and-extract', 18:55:47 INFO - 'populate-webroot', 18:55:47 INFO - 'create-virtualenv', 18:55:47 INFO - 'install', 18:55:47 INFO - 'setup-mitmproxy', 18:55:47 INFO - 'run-tests'), 18:55:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:55:47 INFO - 'download_minidump_stackwalk': True, 18:55:47 INFO - 'download_symbols': 'ondemand', 18:55:47 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:55:47 INFO - 'tooltool.py': '/tools/tooltool.py', 18:55:47 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:55:47 INFO - '/tools/misc-python/virtualenv.py')}, 18:55:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:55:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:55:47 INFO - 'gecko_profile': False, 18:55:47 INFO - 'gecko_profile_interval': 0, 18:55:47 INFO - 'installer_path': 'installer.exe', 18:55:47 INFO - 'log_level': 'info', 18:55:47 INFO - 'log_name': 'talos', 18:55:47 INFO - 'log_to_console': True, 18:55:47 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:55:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:55:47 INFO - 'opt_config_files': (), 18:55:47 INFO - 'pip_index': False, 18:55:47 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:55:47 INFO - 'cmd': ('bash', 18:55:47 INFO - '-c', 18:55:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:55:47 INFO - 'enabled': True, 18:55:47 INFO - 'halt_on_failure': False, 18:55:47 INFO - 'name': 'check_screen_resolution'},), 18:55:47 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:55:47 INFO - 'cmd': ('bash', 18:55:47 INFO - '-c', 18:55:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:55:47 INFO - 'enabled': True, 18:55:47 INFO - 'halt_on_failure': False, 18:55:47 INFO - 'name': 'check_screen_resolution'},), 18:55:47 INFO - 'run_cmd_checks_enabled': True, 18:55:47 INFO - 'suite': 'dromaeojs-e10s', 18:55:47 INFO - 'system_bits': '32', 18:55:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:55:47 INFO - 'title': 't-yosemite-r7-0361', 18:55:47 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:55:47 INFO - 'use_talos_json': True, 18:55:47 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:55:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:55:47 INFO - 'work_dir': 'build'} 18:55:47 INFO - [mozharness: 2017-06-03 01:55:47.886841Z] Running clobber step. 18:55:47 INFO - Running pre-action listener: _resource_record_pre_action 18:55:47 INFO - Running main action method: clobber 18:55:47 INFO - rmtree: /builds/slave/test/build 18:55:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:55:50 INFO - Running post-action listener: _resource_record_post_action 18:55:50 INFO - [mozharness: 2017-06-03 01:55:50.504152Z] Finished clobber step (success) 18:55:50 INFO - [mozharness: 2017-06-03 01:55:50.504275Z] Running read-buildbot-config step. 18:55:50 INFO - Running pre-action listener: _resource_record_pre_action 18:55:50 INFO - Running main action method: read_buildbot_config 18:55:50 INFO - Using buildbot properties: 18:55:50 INFO - { 18:55:50 INFO - "project": "", 18:55:50 INFO - "product": "firefox", 18:55:50 INFO - "who": "kwierso@gmail.com", 18:55:50 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 18:55:50 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 18:55:50 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 18:55:50 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 18:55:50 INFO - "stage_platform": "macosx64", 18:55:50 INFO - "basedir": "/builds/slave/test", 18:55:50 INFO - "buildnumber": 40, 18:55:50 INFO - "platform": "yosemite_r7", 18:55:50 INFO - "parent_task_id": "Wh2i0bLsSKKEwXrRcwVYZw", 18:55:50 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 18:55:50 INFO - "slavebuilddir": "test", 18:55:50 INFO - "taskId": "GDZdDrZMTNKEv0UovFHCYA", 18:55:50 INFO - "branch": "mozilla-central", 18:55:50 INFO - "script_repo_revision": "production", 18:55:50 INFO - "revision": "43039280fe464869428f03b047bb7c762784f44b", 18:55:50 INFO - "slavename": "t-yosemite-r7-0361", 18:55:50 INFO - "repo_path": "mozilla-central" 18:55:50 INFO - } 18:55:50 INFO - Finding installer, test and symbols from parent task. 18:55:50 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GDZdDrZMTNKEv0UovFHCYA'}, attempt #1 18:55:51 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GDZdDrZMTNKEv0UovFHCYA'}, attempt #1 18:55:51 INFO - Task dependencies: aC_zZPE0TgqZIbGELvX1dA Wh2i0bLsSKKEwXrRcwVYZw 18:55:51 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Wh2i0bLsSKKEwXrRcwVYZw'}, attempt #1 18:55:52 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Wh2i0bLsSKKEwXrRcwVYZw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:55:52 INFO - Set test_url: https://queue.taskcluster.net/v1/task/Wh2i0bLsSKKEwXrRcwVYZw/artifacts/public/build/test_packages.json 18:55:52 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Wh2i0bLsSKKEwXrRcwVYZw/artifacts/public/build/target.crashreporter-symbols.zip 18:55:52 INFO - Running post-action listener: _resource_record_post_action 18:55:52 INFO - [mozharness: 2017-06-03 01:55:52.209599Z] Finished read-buildbot-config step (success) 18:55:52 INFO - [mozharness: 2017-06-03 01:55:52.209726Z] Running download-and-extract step. 18:55:52 INFO - Running pre-action listener: _resource_record_pre_action 18:55:52 INFO - Running main action method: download_and_extract 18:55:52 INFO - mkdir: /builds/slave/test/build/tests 18:55:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:55:52 INFO - trying https://queue.taskcluster.net/v1/task/Wh2i0bLsSKKEwXrRcwVYZw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 18:55:52 INFO - Downloading https://queue.taskcluster.net/v1/task/Wh2i0bLsSKKEwXrRcwVYZw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 18:55:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Wh2i0bLsSKKEwXrRcwVYZw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 18:55:53 INFO - Downloaded 1328 bytes. 18:55:53 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 18:55:53 INFO - Using the following test package requirements: 18:55:53 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:55:53 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 18:55:53 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 18:55:53 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:55:53 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 18:55:53 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:55:53 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 18:55:53 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:55:53 INFO - u'jsshell-mac.zip'], 18:55:53 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:55:53 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 18:55:53 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 18:55:53 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:55:53 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 18:55:53 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:55:53 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 18:55:53 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:55:53 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.tar.gz'], 18:55:53 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:55:53 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 18:55:53 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:55:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Wh2i0bLsSKKEwXrRcwVYZw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 18:55:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Wh2i0bLsSKKEwXrRcwVYZw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:55:53 INFO - Fetch https://queue.taskcluster.net/v1/task/Wh2i0bLsSKKEwXrRcwVYZw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 18:55:55 INFO - Content-Length response header: 57631410 18:55:55 INFO - Bytes received: 57631410 18:56:13 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 18:56:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Wh2i0bLsSKKEwXrRcwVYZw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 18:56:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Wh2i0bLsSKKEwXrRcwVYZw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:56:13 INFO - Fetch https://queue.taskcluster.net/v1/task/Wh2i0bLsSKKEwXrRcwVYZw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 18:56:14 INFO - Content-Length response header: 57631410 18:56:14 INFO - Bytes received: 57631410 18:56:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Wh2i0bLsSKKEwXrRcwVYZw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 18:56:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Wh2i0bLsSKKEwXrRcwVYZw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:56:32 INFO - Fetch https://queue.taskcluster.net/v1/task/Wh2i0bLsSKKEwXrRcwVYZw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 18:56:33 INFO - Content-Length response header: 14447425 18:56:33 INFO - Bytes received: 14447425 18:56: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')]} 18:56:34 INFO - trying https://queue.taskcluster.net/v1/task/Wh2i0bLsSKKEwXrRcwVYZw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:56:34 INFO - Downloading https://queue.taskcluster.net/v1/task/Wh2i0bLsSKKEwXrRcwVYZw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 18:56:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Wh2i0bLsSKKEwXrRcwVYZw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 18:56:35 INFO - Downloaded 67783481 bytes. 18:56:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Wh2i0bLsSKKEwXrRcwVYZw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:56:35 INFO - mkdir: /builds/slave/test/properties 18:56:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:56:35 INFO - Writing to file /builds/slave/test/properties/build_url 18:56:35 INFO - Contents: 18:56:35 INFO - build_url:https://queue.taskcluster.net/v1/task/Wh2i0bLsSKKEwXrRcwVYZw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:56:35 INFO - Running post-action listener: _resource_record_post_action 18:56:35 INFO - Running post-action listener: set_extra_try_arguments 18:56:35 INFO - [mozharness: 2017-06-03 01:56:35.921960Z] Finished download-and-extract step (success) 18:56:35 INFO - [mozharness: 2017-06-03 01:56:35.922076Z] Running populate-webroot step. 18:56:35 INFO - Running pre-action listener: _resource_record_pre_action 18:56:35 INFO - Running main action method: populate_webroot 18:56:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:56:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:56:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:56:35 INFO - '--tppagecycles', 18:56:35 INFO - '7'], 18:56:35 INFO - 'tests': ['tsvgm']}}, 18:56:35 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:56:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:56:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:56:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:56:35 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:56:35 INFO - 'talos_options': ['--disable-e10s'], 18:56:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:56:35 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:56:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:56:35 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:56:35 INFO - 'talos_options': ['--disable-e10s'], 18:56:35 INFO - 'tests': ['damp', 'tps']}, 18:56:35 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:56:35 INFO - 'tests': ['damp', 'tps']}, 18:56:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:56:35 INFO - 'tests': ['dromaeo_dom']}, 18:56:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:56:35 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:56:35 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 18:56:35 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:56:35 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:56:35 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:56:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:56:35 INFO - 'tests': ['a11yr', 18:56:35 INFO - 'ts_paint', 18:56:35 INFO - 'tpaint', 18:56:35 INFO - 'sessionrestore', 18:56:35 INFO - 'sessionrestore_no_auto_restore', 18:56:35 INFO - 'tabpaint']}, 18:56:35 INFO - 'other-e10s': {'tests': ['a11yr', 18:56:35 INFO - 'ts_paint', 18:56:35 INFO - 'tpaint', 18:56:35 INFO - 'sessionrestore', 18:56:35 INFO - 'sessionrestore_no_auto_restore', 18:56:35 INFO - 'tabpaint']}, 18:56:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:56:35 INFO - 'ts_paint', 18:56:35 INFO - 'tpaint', 18:56:35 INFO - 'sessionrestore', 18:56:35 INFO - 'sessionrestore_no_auto_restore', 18:56:35 INFO - 'tabpaint']}, 18:56:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:56:35 INFO - 'ts_paint', 18:56:35 INFO - 'tpaint', 18:56:35 INFO - 'sessionrestore', 18:56:35 INFO - 'sessionrestore_no_auto_restore', 18:56:35 INFO - 'tabpaint']}, 18:56:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:56:35 INFO - 'tests': ['a11yr', 18:56:35 INFO - 'ts_paint', 18:56:35 INFO - 'tpaint', 18:56:35 INFO - 'sessionrestore', 18:56:35 INFO - 'sessionrestore_no_auto_restore', 18:56:35 INFO - 'tabpaint']}, 18:56:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:56:35 INFO - 'tests': ['a11yr', 18:56:35 INFO - 'ts_paint', 18:56:35 INFO - 'tpaint', 18:56:35 INFO - 'sessionrestore', 18:56:35 INFO - 'sessionrestore_no_auto_restore', 18:56:35 INFO - 'tabpaint']}, 18:56:35 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 18:56:35 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 18:56:35 INFO - 'bloom_basic_ref']}, 18:56:35 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 18:56:35 INFO - 'talos_options': ['--mitmproxy', 18:56:35 INFO - 'mitmproxy-recording-1.mp', 18:56:35 INFO - '--firstNonBlankPaint'], 18:56:35 INFO - 'tests': ['Quantum_1']}, 18:56:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:56:35 INFO - 'tests': ['tsvgx', 18:56:35 INFO - 'tsvgr_opacity', 18:56:35 INFO - 'tart', 18:56:35 INFO - 'tscrollx', 18:56:35 INFO - 'cart', 18:56:35 INFO - 'tsvg_static']}, 18:56:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:56:35 INFO - 'tsvgr_opacity', 18:56:35 INFO - 'tart', 18:56:35 INFO - 'tscrollx', 18:56:35 INFO - 'cart', 18:56:35 INFO - 'tsvg_static']}, 18:56:35 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:56:35 INFO - 'talos_options': ['--disable-e10s'], 18:56:35 INFO - 'tests': ['tp5o']}, 18:56:35 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:56:35 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:56:35 INFO - 'talos_options': ['--disable-e10s', 18:56:35 INFO - '--xperf_path', 18:56:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:56:35 INFO - 'tests': ['tp5n']}, 18:56:35 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:56:35 INFO - 'talos_options': ['--xperf_path', 18:56:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:56:35 INFO - 'tests': ['tp5n']}}, 18:56:35 INFO - 'talos.zip': {'path': '', 18:56:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:56:35 INFO - Running post-action listener: _resource_record_post_action 18:56:35 INFO - [mozharness: 2017-06-03 01:56:35.944208Z] Finished populate-webroot step (success) 18:56:35 INFO - [mozharness: 2017-06-03 01:56:35.944308Z] Running create-virtualenv step. 18:56:35 INFO - Running pre-action listener: _resource_record_pre_action 18:56:35 INFO - Running main action method: create_virtualenv 18:56:35 INFO - Creating virtualenv /builds/slave/test/build/venv 18:56:35 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:56:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:56:35 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 18:56:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:56:36 INFO - Using real prefix '/tools/python27' 18:56:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:56:36 INFO - Installing distribute.............................................................................................................................................................................................done. 18:56:39 INFO - Installing pip.................done. 18:56:39 INFO - Return code: 0 18:56:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:56:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:56:39 INFO - Reading from file tmpfile_stdout 18:56:39 INFO - Output received: 18:56:39 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) 18:56:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:56:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108a58c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a5c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee3a574b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089e1c60>, '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 0x108776cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10879fbe8>, '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.RcvYUxpHPY/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.mqx6ZICjv1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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 18:56:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:56:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:56:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RcvYUxpHPY/Render', 18:56:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:39 INFO - 'HOME': '/Users/cltbld', 18:56:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:39 INFO - 'LOGNAME': 'cltbld', 18:56:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:39 INFO - 'MOZ_NO_REMOTE': '1', 18:56:39 INFO - 'NO_EM_RESTART': '1', 18:56:39 INFO - 'PAGER': '/bin/cat', 18:56:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:39 INFO - 'PWD': '/builds/slave/test', 18:56:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:39 INFO - 'SHELL': '/bin/bash', 18:56:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mqx6ZICjv1/Listeners', 18:56:39 INFO - 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/', 18:56:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:39 INFO - 'USER': 'cltbld', 18:56:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:39 INFO - 'XPC_FLAGS': '0x0', 18:56:39 INFO - 'XPC_SERVICE_NAME': '0', 18:56:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:40 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 18:56:40 INFO - Cleaning up... 18:56:40 INFO - Return code: 0 18:56:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:56: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')]} 18:56:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56: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')]} 18:56:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56: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', '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 0x108a58c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a5c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee3a574b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089e1c60>, '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 0x108776cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10879fbe8>, '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.RcvYUxpHPY/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.mqx6ZICjv1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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 18:56: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', 'psutil>=3.1.1'] in /builds/slave/test/build 18:56: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 psutil>=3.1.1 18:56:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RcvYUxpHPY/Render', 18:56:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:40 INFO - 'HOME': '/Users/cltbld', 18:56:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:40 INFO - 'LOGNAME': 'cltbld', 18:56:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:40 INFO - 'MOZ_NO_REMOTE': '1', 18:56:40 INFO - 'NO_EM_RESTART': '1', 18:56:40 INFO - 'PAGER': '/bin/cat', 18:56:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:40 INFO - 'PWD': '/builds/slave/test', 18:56:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:40 INFO - 'SHELL': '/bin/bash', 18:56:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mqx6ZICjv1/Listeners', 18:56:40 INFO - 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/', 18:56:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:40 INFO - 'USER': 'cltbld', 18:56:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:40 INFO - 'XPC_FLAGS': '0x0', 18:56:40 INFO - 'XPC_SERVICE_NAME': '0', 18:56:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:40 INFO - Downloading/unpacking psutil>=3.1.1 18:56:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:56:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:56:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:56:42 INFO - Installing collected packages: psutil 18:56:42 INFO - Running setup.py install for psutil 18:56:42 INFO - building 'psutil._psutil_osx' extension 18:56:42 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 18:56:43 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 18:56:43 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 18:56:43 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 18:56:43 INFO - building 'psutil._psutil_posix' extension 18:56:43 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 18:56:43 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:56:43 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:56:43 INFO - ^ 18:56:43 INFO - 1 warning generated. 18:56:43 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 18:56:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:56:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:56:43 INFO - Successfully installed psutil 18:56:43 INFO - Cleaning up... 18:56:43 INFO - Return code: 0 18:56:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:56:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:43 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 0x108a58c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a5c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee3a574b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089e1c60>, '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 0x108776cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10879fbe8>, '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.RcvYUxpHPY/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.mqx6ZICjv1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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 18:56:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:56:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:56:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RcvYUxpHPY/Render', 18:56:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:43 INFO - 'HOME': '/Users/cltbld', 18:56:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:43 INFO - 'LOGNAME': 'cltbld', 18:56:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:43 INFO - 'MOZ_NO_REMOTE': '1', 18:56:43 INFO - 'NO_EM_RESTART': '1', 18:56:43 INFO - 'PAGER': '/bin/cat', 18:56:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:43 INFO - 'PWD': '/builds/slave/test', 18:56:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:43 INFO - 'SHELL': '/bin/bash', 18:56:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mqx6ZICjv1/Listeners', 18:56:43 INFO - 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/', 18:56:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:43 INFO - 'USER': 'cltbld', 18:56:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:43 INFO - 'XPC_FLAGS': '0x0', 18:56:43 INFO - 'XPC_SERVICE_NAME': '0', 18:56:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:56:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:56:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:56:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:56:45 INFO - Installing collected packages: mozsystemmonitor 18:56:45 INFO - Running setup.py install for mozsystemmonitor 18:56:45 INFO - Successfully installed mozsystemmonitor 18:56:45 INFO - Cleaning up... 18:56:45 INFO - Return code: 0 18:56:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:56:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108a58c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a5c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee3a574b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089e1c60>, '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 0x108776cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10879fbe8>, '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.RcvYUxpHPY/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.mqx6ZICjv1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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 18:56:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:56:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:56:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RcvYUxpHPY/Render', 18:56:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:45 INFO - 'HOME': '/Users/cltbld', 18:56:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:45 INFO - 'LOGNAME': 'cltbld', 18:56:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:45 INFO - 'MOZ_NO_REMOTE': '1', 18:56:45 INFO - 'NO_EM_RESTART': '1', 18:56:45 INFO - 'PAGER': '/bin/cat', 18:56:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:45 INFO - 'PWD': '/builds/slave/test', 18:56:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:45 INFO - 'SHELL': '/bin/bash', 18:56:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mqx6ZICjv1/Listeners', 18:56:45 INFO - 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/', 18:56:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:45 INFO - 'USER': 'cltbld', 18:56:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:45 INFO - 'XPC_FLAGS': '0x0', 18:56:45 INFO - 'XPC_SERVICE_NAME': '0', 18:56:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:46 INFO - Downloading/unpacking jsonschema==2.5.1 18:56:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:56:47 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:56:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:47 INFO - Downloading functools32-3.2.3-2.tar.gz 18:56:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:56:47 INFO - warning: no files found matching '*.txt' 18:56:47 INFO - no previously-included directories found matching 'build' 18:56:47 INFO - no previously-included directories found matching 'dist' 18:56:47 INFO - no previously-included directories found matching '.git*' 18:56:47 INFO - Installing collected packages: jsonschema, functools32 18:56:48 INFO - Running setup.py install for functools32 18:56:48 INFO - warning: no files found matching '*.txt' 18:56:48 INFO - no previously-included directories found matching 'build' 18:56:48 INFO - no previously-included directories found matching 'dist' 18:56:48 INFO - no previously-included directories found matching '.git*' 18:56:48 INFO - Successfully installed jsonschema functools32 18:56:48 INFO - Cleaning up... 18:56:48 INFO - Return code: 0 18:56:48 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:56:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:48 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 0x108a58c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a5c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee3a574b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089e1c60>, '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 0x108776cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10879fbe8>, '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.RcvYUxpHPY/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.mqx6ZICjv1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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 18:56:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:56:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:56:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RcvYUxpHPY/Render', 18:56:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:48 INFO - 'HOME': '/Users/cltbld', 18:56:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:48 INFO - 'LOGNAME': 'cltbld', 18:56:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:48 INFO - 'MOZ_NO_REMOTE': '1', 18:56:48 INFO - 'NO_EM_RESTART': '1', 18:56:48 INFO - 'PAGER': '/bin/cat', 18:56:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:48 INFO - 'PWD': '/builds/slave/test', 18:56:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:48 INFO - 'SHELL': '/bin/bash', 18:56:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mqx6ZICjv1/Listeners', 18:56:48 INFO - 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/', 18:56:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:48 INFO - 'USER': 'cltbld', 18:56:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:48 INFO - 'XPC_FLAGS': '0x0', 18:56:48 INFO - 'XPC_SERVICE_NAME': '0', 18:56:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:48 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:56:48 INFO - Cleaning up... 18:56:48 INFO - Return code: 0 18:56:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:56:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:48 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 0x108a58c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a5c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee3a574b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089e1c60>, '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 0x108776cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10879fbe8>, '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.RcvYUxpHPY/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.mqx6ZICjv1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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 18:56:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:56:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:56:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RcvYUxpHPY/Render', 18:56:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:48 INFO - 'HOME': '/Users/cltbld', 18:56:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:48 INFO - 'LOGNAME': 'cltbld', 18:56:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:48 INFO - 'MOZ_NO_REMOTE': '1', 18:56:48 INFO - 'NO_EM_RESTART': '1', 18:56:48 INFO - 'PAGER': '/bin/cat', 18:56:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:48 INFO - 'PWD': '/builds/slave/test', 18:56:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:48 INFO - 'SHELL': '/bin/bash', 18:56:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mqx6ZICjv1/Listeners', 18:56:48 INFO - 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/', 18:56:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:48 INFO - 'USER': 'cltbld', 18:56:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:48 INFO - 'XPC_FLAGS': '0x0', 18:56:48 INFO - 'XPC_SERVICE_NAME': '0', 18:56:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:48 INFO - Downloading/unpacking blobuploader==1.2.4 18:56:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:50 INFO - Downloading blobuploader-1.2.4.tar.gz 18:56:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:56:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:56:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:56:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:56:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:50 INFO - Downloading docopt-0.6.1.tar.gz 18:56:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:56:51 INFO - Installing collected packages: blobuploader, requests, docopt 18:56:51 INFO - Running setup.py install for blobuploader 18:56:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:56:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:56:51 INFO - Running setup.py install for requests 18:56:51 INFO - Running setup.py install for docopt 18:56:51 INFO - Successfully installed blobuploader requests docopt 18:56:51 INFO - Cleaning up... 18:56:51 INFO - Return code: 0 18:56:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:56:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:52 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 0x108a58c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a5c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee3a574b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089e1c60>, '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 0x108776cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10879fbe8>, '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.RcvYUxpHPY/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.mqx6ZICjv1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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 18:56:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:56:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:56:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RcvYUxpHPY/Render', 18:56:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:52 INFO - 'HOME': '/Users/cltbld', 18:56:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:52 INFO - 'LOGNAME': 'cltbld', 18:56:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:52 INFO - 'MOZ_NO_REMOTE': '1', 18:56:52 INFO - 'NO_EM_RESTART': '1', 18:56:52 INFO - 'PAGER': '/bin/cat', 18:56:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:52 INFO - 'PWD': '/builds/slave/test', 18:56:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:52 INFO - 'SHELL': '/bin/bash', 18:56:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mqx6ZICjv1/Listeners', 18:56:52 INFO - 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/', 18:56:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:52 INFO - 'USER': 'cltbld', 18:56:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:52 INFO - 'XPC_FLAGS': '0x0', 18:56:52 INFO - 'XPC_SERVICE_NAME': '0', 18:56:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:56:52 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-3Pw5zu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:56:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:56:52 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-JutvGn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:56:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:56:52 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-qie357-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:56:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:56:52 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-up9rZ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:56:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:56:52 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-ABY7xp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:56:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:56:52 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-CmuQVW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:56:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:56:52 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-Ruv0VQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:56:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:56:52 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-99fr67-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:56:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:56:53 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-t8Vvd1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:56:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:56:53 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-bZLrdV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:56:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:56:53 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-zGkNBC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:56:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:56:53 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-O0Fs_u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:56:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:56:53 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-ArhOm2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:56:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:56:53 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-6YbYpH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:56:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:56:53 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-ari3y0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:56:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:56:53 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-Ks7GKc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:56:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:56:53 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-VEiew_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:56:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:56:54 INFO - Running setup.py install for manifestparser 18:56:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:56:54 INFO - Running setup.py install for mozcrash 18:56:54 INFO - Running setup.py install for mozdebug 18:56:54 INFO - Running setup.py install for mozdevice 18:56:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:56:54 INFO - Running setup.py install for mozfile 18:56:54 INFO - Running setup.py install for mozhttpd 18:56:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:56:54 INFO - Running setup.py install for mozinfo 18:56:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:56:55 INFO - Running setup.py install for mozInstall 18:56:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:56:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:56:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:56:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:56:55 INFO - Running setup.py install for mozleak 18:56:55 INFO - Running setup.py install for mozlog 18:56:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:56:55 INFO - Running setup.py install for moznetwork 18:56:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:56:55 INFO - Running setup.py install for mozprocess 18:56:55 INFO - Running setup.py install for mozprofile 18:56:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:56:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:56:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:56:55 INFO - Running setup.py install for mozrunner 18:56:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:56:56 INFO - Running setup.py install for mozscreenshot 18:56:56 INFO - Running setup.py install for moztest 18:56:56 INFO - Running setup.py install for mozversion 18:56:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:56:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:56:56 INFO - Cleaning up... 18:56:56 INFO - Return code: 0 18:56:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:56:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:56 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 0x108a58c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a5c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee3a574b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089e1c60>, '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 0x108776cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10879fbe8>, '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.RcvYUxpHPY/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.mqx6ZICjv1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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 18:56:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:56:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:56:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RcvYUxpHPY/Render', 18:56:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:56 INFO - 'HOME': '/Users/cltbld', 18:56:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:56 INFO - 'LOGNAME': 'cltbld', 18:56:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:56 INFO - 'MOZ_NO_REMOTE': '1', 18:56:56 INFO - 'NO_EM_RESTART': '1', 18:56:56 INFO - 'PAGER': '/bin/cat', 18:56:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:56 INFO - 'PWD': '/builds/slave/test', 18:56:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:56 INFO - 'SHELL': '/bin/bash', 18:56:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mqx6ZICjv1/Listeners', 18:56:56 INFO - 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/', 18:56:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:56 INFO - 'USER': 'cltbld', 18:56:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:56 INFO - 'XPC_FLAGS': '0x0', 18:56:56 INFO - 'XPC_SERVICE_NAME': '0', 18:56:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:56:56 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-6X8BST-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:56:56 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 18:56:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:56:56 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-frQQ7j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:56:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:56:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:56:57 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-GmoKSa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:56:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:56:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:56:57 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-8rE6gi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:56:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:56:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:56:57 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-vBatu1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:56:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 18:56:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:56:57 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-wugZ4B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:56:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 18:56:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:56:57 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-4U194F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:56:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 18:56:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:56:57 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-TJjzos-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:56:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:56:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:56:57 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-tpVFhU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:56:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 18:56:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:56:57 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-SIvtWd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:56:57 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)) 18:56:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:56:57 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-N0o7TZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:56:57 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 18:56:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:56:57 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-x39Lye-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:56:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 18:56:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:56:58 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-XHgiGQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:56:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 18:56:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:56:58 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-NH6Da_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:56:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 18:56:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:56:58 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-c7Lh5h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:56:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 18:56:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:56:58 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-PJeLPJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:56:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 18:56:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:56:58 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-zRvCGW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:56:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 18:56:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:56:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:56:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:56:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:56:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:56:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:56:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:59 INFO - Downloading blessings-1.6.tar.gz 18:57:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:57:00 INFO - Installing collected packages: blessings 18:57:00 INFO - Running setup.py install for blessings 18:57:00 INFO - Successfully installed blessings 18:57:00 INFO - Cleaning up... 18:57:00 INFO - Return code: 0 18:57:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:57:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:57:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:57:00 INFO - Reading from file tmpfile_stdout 18:57:00 INFO - Current package versions: 18:57:00 INFO - blessings == 1.6 18:57:00 INFO - blobuploader == 1.2.4 18:57:00 INFO - docopt == 0.6.1 18:57:00 INFO - functools32 == 3.2.3-2 18:57:00 INFO - jsonschema == 2.5.1 18:57:00 INFO - manifestparser == 1.1 18:57:00 INFO - mozInstall == 1.12 18:57:00 INFO - mozcrash == 1.0 18:57:00 INFO - mozdebug == 0.1 18:57:00 INFO - mozdevice == 0.51 18:57:00 INFO - mozfile == 1.2 18:57:00 INFO - mozhttpd == 0.7 18:57:00 INFO - mozinfo == 0.9 18:57:00 INFO - mozleak == 0.1 18:57:00 INFO - mozlog == 3.4 18:57:00 INFO - moznetwork == 0.27 18:57:00 INFO - mozprocess == 0.25 18:57:00 INFO - mozprofile == 0.28 18:57:00 INFO - mozrunner == 6.13 18:57:00 INFO - mozscreenshot == 0.1 18:57:00 INFO - mozsystemmonitor == 0.3 18:57:00 INFO - moztest == 0.8 18:57:00 INFO - mozversion == 1.4 18:57:00 INFO - psutil == 3.1.1 18:57:00 INFO - requests == 1.2.3 18:57:00 INFO - wsgiref == 0.1.2 18:57:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:57:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:57:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:57:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:57:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:57:00 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 0x108a58c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a5c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee3a574b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089e1c60>, '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 0x108776cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10879fbe8>, '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.RcvYUxpHPY/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.mqx6ZICjv1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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 18:57:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 18:57:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:57:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RcvYUxpHPY/Render', 18:57:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:57:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:57:00 INFO - 'HOME': '/Users/cltbld', 18:57:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:57:00 INFO - 'LOGNAME': 'cltbld', 18:57:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:57:00 INFO - 'MOZ_NO_REMOTE': '1', 18:57:00 INFO - 'NO_EM_RESTART': '1', 18:57:00 INFO - 'PAGER': '/bin/cat', 18:57:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:57:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:57:00 INFO - 'PWD': '/builds/slave/test', 18:57:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:57:00 INFO - 'SHELL': '/bin/bash', 18:57:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mqx6ZICjv1/Listeners', 18:57:00 INFO - 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/', 18:57:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:57:00 INFO - 'USER': 'cltbld', 18:57:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:57:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:57:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:57:00 INFO - 'XPC_FLAGS': '0x0', 18:57:00 INFO - 'XPC_SERVICE_NAME': '0', 18:57:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:57:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:57:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:57:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 18:57:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 18:57:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:57:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 18:57:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 18:57:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 18:57:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 18:57:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 18:57:00 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:57:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:57:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:57:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:57:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:57:02 INFO - Downloading marionette_driver-1.4.0.tar.gz 18:57:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 18:57:02 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:57:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:57:02 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)) 18:57:02 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)) 18:57:02 INFO - Installing collected packages: marionette-driver 18:57:02 INFO - Running setup.py install for marionette-driver 18:57:02 INFO - Successfully installed marionette-driver 18:57:02 INFO - Cleaning up... 18:57:02 INFO - Return code: 0 18:57:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:57:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:57:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:57:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:57:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:57:02 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 0x108a58c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a5c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee3a574b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089e1c60>, '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 0x108776cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10879fbe8>, '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.RcvYUxpHPY/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.mqx6ZICjv1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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 18:57:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:57:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:57:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RcvYUxpHPY/Render', 18:57:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:57:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:57:02 INFO - 'HOME': '/Users/cltbld', 18:57:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:57:02 INFO - 'LOGNAME': 'cltbld', 18:57:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:57:02 INFO - 'MOZ_NO_REMOTE': '1', 18:57:02 INFO - 'NO_EM_RESTART': '1', 18:57:02 INFO - 'PAGER': '/bin/cat', 18:57:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:57:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:57:02 INFO - 'PWD': '/builds/slave/test', 18:57:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:57:02 INFO - 'SHELL': '/bin/bash', 18:57:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mqx6ZICjv1/Listeners', 18:57:02 INFO - 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/', 18:57:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:57:02 INFO - 'USER': 'cltbld', 18:57:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:57:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:57:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:57:02 INFO - 'XPC_FLAGS': '0x0', 18:57:02 INFO - 'XPC_SERVICE_NAME': '0', 18:57:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:57:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:57:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:57:02 INFO - Cleaning up... 18:57:02 INFO - Return code: 0 18:57:02 INFO - Running post-action listener: _resource_record_post_action 18:57:02 INFO - Running post-action listener: _start_resource_monitoring 18:57:02 INFO - Starting resource monitoring. 18:57:02 INFO - [mozharness: 2017-06-03 01:57:02.935290Z] Finished create-virtualenv step (success) 18:57:02 INFO - [mozharness: 2017-06-03 01:57:02.936267Z] Running install step. 18:57:02 INFO - Running pre-action listener: _resource_record_pre_action 18:57:02 INFO - Running main action method: install 18:57:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:57:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:57:03 INFO - Reading from file tmpfile_stdout 18:57:03 INFO - Detecting whether we're running mozinstall >=1.0... 18:57:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:57:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:57:03 INFO - Reading from file tmpfile_stdout 18:57:03 INFO - Output received: 18:57:03 INFO - Usage: mozinstall [options] installer 18:57:03 INFO - Options: 18:57:03 INFO - -h, --help show this help message and exit 18:57:03 INFO - -d DEST, --destination=DEST 18:57:03 INFO - Directory to install application into. [default: 18:57:03 INFO - "/builds/slave/test"] 18:57:03 INFO - --app=APP Application being installed. [default: firefox] 18:57:03 INFO - mkdir: /builds/slave/test/build/application 18:57:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 18:57:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 18:57:24 INFO - Reading from file tmpfile_stdout 18:57:24 INFO - Output received: 18:57:24 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:57:24 INFO - Running post-action listener: _resource_record_post_action 18:57:24 INFO - [mozharness: 2017-06-03 01:57:24.799306Z] Finished install step (success) 18:57:24 INFO - [mozharness: 2017-06-03 01:57:24.799442Z] Running setup-mitmproxy step. 18:57:24 INFO - Running pre-action listener: _resource_record_pre_action 18:57:24 INFO - Running main action method: setup_mitmproxy 18:57:24 INFO - Skipping: mitmproxy is not required 18:57:24 INFO - Running post-action listener: _resource_record_post_action 18:57:24 INFO - [mozharness: 2017-06-03 01:57:24.800087Z] Finished setup-mitmproxy step (success) 18:57:24 INFO - [mozharness: 2017-06-03 01:57:24.800205Z] Running run-tests step. 18:57:24 INFO - Running pre-action listener: _resource_record_pre_action 18:57:24 INFO - Running main action method: run_tests 18:57:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:57:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:57:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:57:24 INFO - 2017-06-02 18:57:24.815 screenresolution[1637:8883] starting screenresolution argv=screenresolution get 18:57:24 INFO - 2017-06-02 18:57:24.817 screenresolution[1637:8883] Display 0: 1600x1200x32@60 18:57:24 INFO - 2017-06-02 18:57:24.823 screenresolution[1638:8885] starting screenresolution argv=screenresolution list 18:57:24 INFO - Available Modes on Display 0 18:57:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:57:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:57:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:57:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:57:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:57:25 INFO - Intel Iris: 18:57:25 INFO - Chipset Model: Intel Iris 18:57:25 INFO - Type: GPU 18:57:25 INFO - Bus: Built-In 18:57:25 INFO - VRAM (Dynamic, Max): 1536 MB 18:57:25 INFO - Vendor: Intel (0x8086) 18:57:25 INFO - Device ID: 0x0a2e 18:57:25 INFO - Revision ID: 0x0009 18:57:25 INFO - Displays: 18:57:25 INFO - Display: 18:57:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:57:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:57:25 INFO - Display Serial Number: 1600x1200 60 18:57:25 INFO - Main Display: Yes 18:57:25 INFO - Mirror: Off 18:57:25 INFO - Online: Yes 18:57:25 INFO - Rotation: Supported 18:57:25 INFO - Return code: 0 18:57:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:57:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:57:25 INFO - Python 2.7.3 18:57:25 INFO - Return code: 0 18:57:25 INFO - grabbing minidump binary from tooltool 18:57:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:57:25 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 0x1089e1c60>, '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 0x108776cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10879fbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:57:25 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 18:57:25 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 18:57:25 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 18:57:25 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:57:25 INFO - Return code: 0 18:57:25 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:57:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:57:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:57:25 INFO - ENV: RUST_BACKTRACE is now 1 18:57:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:57:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:57:25 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/Wh2i0bLsSKKEwXrRcwVYZw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0361', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 18:57:25 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/Wh2i0bLsSKKEwXrRcwVYZw/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0361 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 18:57:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RcvYUxpHPY/Render', 18:57:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:57:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:57:25 INFO - 'HOME': '/Users/cltbld', 18:57:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:57:25 INFO - 'LOGNAME': 'cltbld', 18:57:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:57:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:57:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:57:25 INFO - 'MOZ_NO_REMOTE': '1', 18:57:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:57:25 INFO - 'NO_EM_RESTART': '1', 18:57:25 INFO - 'PAGER': '/bin/cat', 18:57:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:57:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:57:25 INFO - 'PWD': '/builds/slave/test', 18:57:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:57:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:57:25 INFO - 'RUST_BACKTRACE': '1', 18:57:25 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:57:25 INFO - 'SHELL': '/bin/bash', 18:57:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mqx6ZICjv1/Listeners', 18:57:25 INFO - 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/', 18:57:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:57:25 INFO - 'USER': 'cltbld', 18:57:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:57:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:57:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:57:25 INFO - 'XPC_FLAGS': '0x0', 18:57:25 INFO - 'XPC_SERVICE_NAME': '0', 18:57:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:57:25 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/Wh2i0bLsSKKEwXrRcwVYZw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0361', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 18:57:25 INFO - mozversion application_buildid: 20170602172656 18:57:25 INFO - mozversion application_changeset: 43039280fe464869428f03b047bb7c762784f44b 18:57:25 INFO - mozversion application_display_name: Nightly 18:57:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:57:25 INFO - mozversion application_name: Firefox 18:57:25 INFO - mozversion application_remotingname: firefox 18:57:25 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:57:25 INFO - mozversion application_vendor: Mozilla 18:57:25 INFO - mozversion application_version: 55.0a1 18:57:25 INFO - mozversion platform_buildid: 20170602172656 18:57:25 INFO - mozversion platform_changeset: 43039280fe464869428f03b047bb7c762784f44b 18:57:25 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:57:25 INFO - mozversion platform_version: 55.0a1 18:57:25 INFO - using testdate: 1496455045 18:57:25 INFO - actual date: 1496455045 18:57:25 INFO - starting webserver on 'localhost:49228' 18:57:25 INFO - SUITE-START | Running 2 tests 18:57:25 INFO - TEST-START | dromaeo_css 18:57:25 INFO - operating with platform_type : mac_ 18:57:25 INFO - Initialising browser for dromaeo_css test... 18:57:25 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground -profile /var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/tmpnRIcLu/profile 18:57:25 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground) 18:57:59 INFO - TEST-INFO | 1648: killed by SIGTERM 18:57:59 INFO - Browser initialized. 18:57:59 INFO - Running cycle 1/1 for dromaeo_css test... 18:57:59 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/tmpnRIcLu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:58:01 INFO - PID 1657 | 2017-06-02 18:58:01.968 plugin-container[1658:9388] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x983f, name = 'com.apple.tsm.portname' 18:58:01 INFO - PID 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:58:01 INFO - PID 1657 | 2017-06-02 18:58:01.969 plugin-container[1658:9388] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d0b, name = 'com.apple.CFPasteboardClient' 18:58:01 INFO - PID 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:58:01 INFO - PID 1657 | 2017-06-02 18:58:01.969 plugin-container[1658:9388] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:58:02 INFO - PID 1657 | 2017-06-02 18:58:02.395 plugin-container[1659:9437] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4743, name = 'com.apple.tsm.portname' 18:58:02 INFO - PID 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:58:02 INFO - PID 1657 | 2017-06-02 18:58:02.396 plugin-container[1659:9437] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x481b, name = 'com.apple.CFPasteboardClient' 18:58:02 INFO - PID 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:58:02 INFO - PID 1657 | 2017-06-02 18:58:02.396 plugin-container[1659:9437] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:58:11 INFO - PID 1657 | RSS: Main: 268308480 18:58:11 INFO - PID 1657 | 19:01:13 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49228/tests/dromaeo/cssquery-ext.html) 19:01:15 INFO - PID 1657 | RSS: Main: 266375168 19:01:15 INFO - PID 1657 | 19:04:17 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-ext.html (next: http://localhost:49228/tests/dromaeo/cssquery-jquery.html) 19:04:17 INFO - PID 1657 | RSS: Main: 283381760 19:04:17 INFO - PID 1657 | 19:07:19 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49228/tests/dromaeo/cssquery-mootools.html) 19:07:20 INFO - PID 1657 | RSS: Main: 284303360 19:07:20 INFO - PID 1657 | 19:10:21 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49228/tests/dromaeo/cssquery-prototype.html) 19:10:22 INFO - PID 1657 | RSS: Main: 288022528 19:10:22 INFO - PID 1657 | 19:13:25 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49228/tests/dromaeo/cssquery-yui.html) 19:13:26 INFO - PID 1657 | RSS: Main: 288026624 19:13:26 INFO - PID 1657 | 19:16:27 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-yui.html (next: http://localhost:49228/tests/dromaeo/cssquery-dojo.html) 19:16:28 INFO - PID 1657 | RSS: Main: 289648640 19:16:28 INFO - PID 1657 | 19:16:28 INFO - PID 1657 | __start_tp_report 19:16:28 INFO - PID 1657 | _x_x_mozilla_page_load 19:16:28 INFO - PID 1657 | _x_x_mozilla_page_load_details 19:16:28 INFO - PID 1657 | |i|pagename|runs| 19:16:28 INFO - PID 1657 | |0;dojo.html;2924.839133847639;3249.853756580954;3545.3441113394006;3555.093451169951;3565.4651802229646;6030.5477089218375;6097.390260973901;6143.170671959284;6177.845553861156;6208.19293491846;5018.64869459138;6077.635341879491;6100.399513635572;6128.747575574754;6185.474234690057;5011.198208286684;5411.215373770801;5439.836804895859;5444.91832622511;5516.0071187186495;4938.328872478301;5289.100852855024;5510.476504732044;5558;5587.441255874421;5295.841124766263;5354.384245811742;5416.0792665247;5453.400125986138;5512.614117011811;5388.838334849922;5392.622516423852;5397.136458166714;5444.3194600674915;5447.210154527611;5363.892722145535;5365.624406291561;5388.757505912205;5407.83776486706;5416;4971.906180640253;6074.331823499411;6196.411340922605;6211.68941552925;6215.223097112861;6417.101605775195;6521.706523206467;6609.834754131137;6627.569208001512;6678.499112566528;6707.3628005339415;6733.494987875929;6741.426978706816;6742.932570674279;6762;6285.308616052231;6292.339304373067;6297.464715499188;6297.527685423612;6310.274318453345;6153.2000839890525;6456.966885298423;6592.769253076215;6834.589924604539;6881.41507971818;6194.039923811816;6507.023946408077;6518.511111666644;6524.217093948661;6566.376194261537;6499.447546958467;6629.701663425158;6681.365270299314;6695;6702.865942681216;6545;6571.178602674666;6597.901031484435;6617.139771829728;6620.834479137964;6369.617822930639;6400.327965563642;6514.6416947068365;6528.08606795049;6599.241087274929;5326.2543243945875;5326.387465441447;5328.467153284718;5334.786608535693;5345.9465405345445;4707.7175369477945;4732.763361831895;4738.3129446230105;4766.785494652749;4786.473487916326;5082.74586270685;5309.522143007148;6089.360617135226;6263.96868015657;6279;6706.597604143767;6732.3267673232285;6744.089547911068;6772.085768421299;6778.254392016875;4990.725510096907;6059.757609695563;6238.313785483593;6240.375962403814;6256.124142620037;5696.088625819849;5770.913436298459;5797.101449275369;5858.150568167677;5869.559783016291;6808.217055038734;6820.31796820324;6824.624645644537;6842.7605033823575;6867.416269616939;6642.933570664231;6676.83307917306;6687.1975362956755;6689.665516724242;6710.19477662664;6595;6792.320767923168;6844.349786770249;6855.07456493367;6857.55425897315;6386.840328991812;6470.579412338183;6529.967350163409;6589.17635295588;6616.172978377703;6751.223609284995;6770.796876093725;6772.322767723188;6773.221079575912;6778.559393639398;6339.96830015847;6388.297287298301;6427.228732551936;6521.445677117498;6553;5204.713740744295;5641.928033673589;6311.179546659088;6433;6504.902426463702;6317.747290108344;6380.968095159495;6386.265579458422;6421.454176394872;6439.645819479786;6330.461910737639;6353.8729225416155;6532.673366331569;6536.248331441945;6558.737650493927;6288.654124023223;6291.811245662638;6311.27420346666;6328.873422531431;6335.809925702236 19:16:28 INFO - PID 1657 | |1;ext.html;658.6871236162823;757.4016526943711;784.544963920926;1259.4773169134814;1325.7348530293932;32546.18634534138;36259.81870090649;38733.41899871504;39002.60997390025;39002.82991510252;39975.40036899449;40017;40207.994800130014;40256;40263.59736402635;7888.645010974491;8041.437099403044;8272.545010024447;8453.154684531559;8460.957695211546;8288.917110828905;8351.498910065415;8361;8445.493270403766;8465.195806398382;8408.789780255482;8453.788655283606;8462.746117616482;8467.83064338713;8488.702895398663;8429.367797415187;8449.831003379959;8450.19723126302;8451.239388455007;8458.111898250656;8324.334053275785;8330.916690833075;8354.081051084377;8361.080281169066;8395.580220988988;36886.631133688585;36893;37044;37185.814070929744;37287.627123728686;3628.9455658165148;3668.468072129553;3673.6326367363317;3675.6324367563298;3676.4117741161554;29932;30198.54702179469;30206.395872082434;30282.69717302821;30436.086917392513;3095.0095969289837;3116.8597413116463;3139.293658926746;3139.372125574894;3145.8584363703458;217574;219135;242074;267015;270196;38188;46943.5305646946;47042.76478617585;47083.293750593766;47123.643817808974;49198.77003074952;49642.01431942682;49778.25332620014;53218.467815322125;54052.45947540553;14543.490977815725;14652.853471465362;14661.706765864836;14681.265936703123;14693.55919322422;11653.53385864573;11741.060715142768;11845.703857403634;11846.526138954345;11857.644270671894;13827.930860345836;13836.716941704708;13870.930645346709;13910.930445347709;13983.37074831635;6144.846378840565;6171.395437186274;6193.102000209944;6214.5960512566535;6221.4089661482085;747.1557140431278;839.886615306938;875.146731936357;1003.996003996004;1038.8857225705167;22958.852057397064;23144.074237030665;23368;24139;24649.753502465104;1065.1798115451058;1076.5532304093713;1122.5173175534599;1124.9493772780245;1130.559081958037;10498.95010498944;11505.482253298622;12291.402117724638;12672.619821405373;12683.746325073631;264979;329236;329950;331027;331718;42791.144177116905;55252.447475525536;55574.44425555773;55707.88584228293;55822.883542329735;1461.3496993837853;1511.4709851551868;1526.137732181314;1532.923353832304;1535.6851845371673;21203.833789141747;21533.46166345854;21890.781092188874;23487.647685285076;24190.371444283497;38668.806655967666;38747.806260968515;38770.80614596909;38866;38998.4150237741;272830;274129;274580;275657.2434275712;276004;34107.31785364328;34309.656903430645;34930;35562.64437355697;35643.82178089093;44046;45259.32111018272;45269;45579.54420455886;45644.63066108022;29344.26639334034;29740;30803;30815.383692326475;31445.025445165345;28259.01093461801;28504.013209420125;29373.853130735064;29910.102696919126;31075.53386699156;35931.82034089813;37104.88685339444;37341;37383.25233495369;37503.81248093742;29679.406411872074;30317.090487285415;30336.83107522484;30918.69081309158;31617;23362.299131026095;24448.87775561111;24651.013959442138;24765.380865478506;24805.50388992246 19:16:28 INFO - PID 1657 | |2;jquery.html;342.9142714321419;386.6462187098803;419.4044456871243;430.54147333090236;436.6179592856252;14572.417103315882;15214;21519.924003799806;22108.778912210873;22457.887710561467;18726;23906;23962.52074958504;24019.519609607796;24128.63807042896;6038.275406951161;6198.256209254896;6252.812415627538;6255.030470277115;6262.405071518198;6123.877522449548;6225.906611400833;6233.470155036827;6240.063990401431;6245.687715614201;6199.628022318653;6201.193844800169;6519.413252807246;6833.965830170842;6902.102726645554;6764.864702705943;6787.083743694588;6795.422389096883;6803.319668033207;6826.965865170667;6650.434713049397;6667.433268172211;6670.299618540032;6672;6686.264510903797;29134.27164320888;29612;29996.400071998654;30043.549346759817;30048.399032019457;2277.943051423728;2321.373229228099;2329.5224478981936;2330.685357476736;2331.347222777608;18744.15651295681;19274.807251927443;19290.71063934042;19296.903515482474;19407.611847763106;2228.6322756745117;2245.65192395179;2304.8271379646594;2305.377548062014;2306.100620757889;189902.10097898982;194208.0289598557;195380;197652;197898.01050994653;19888;23098.422539436648;24034.39914002129;25145.874270628894;25291.143272350757;32733.345333093683;41773.95565110836;41918.58081419208;41955.7902210487;41980.79009604932;11076.169287303484;11101.944490277658;11164.218504704755;11177.72055698614;11231.157663175294;8736.69421570242;8803.434608983373;8832.55837208137;8907.73276801697;8918.955405222932;7707.499012564165;9060.456372617664;11765.235259708092;11919.582814601446;12004.639860804189;1893.9526511837314;2254.31243470743;2282.6233671444356;2288.3363824491043;2449.252977841775;836.8409752492842;850.510956200175;975.4634950777045;1020.403064207447;1027.8920713324994;20097;21125.049372778263;21172.729636221877;21411.571768564543;21461.785382146292;855.9015713192938;873.3929918706565;968.1286841842398;986.8026394721085;1027.527337424793;9117.857303533665;10432.843507347396;11021.944890275658;11113.944430277797;11321.490532926038;555232;556855;562210.4786951152;571682;573275;143502.28248858688;146011;146893;146949.5305046936;147806;1140.863096428434;1160.4197901049474;1185.4961641302411;1189.2567145534042;1191.3804821492774;17659.38192163268;17889.910550447163;18706.15822287973;18942.715859261847;19244.903775481034;63832;65655;66956;67417.3258267411;67514.64970700657;68921;90677.54661226651;91055;91528.54235728779;91637.54181229051;9822.5088745561;10295.691129266133;10356.948215258875;10407.94796026015;10412;13199.538016169388;13837.238951857342;17007.088369674795;18073.909630451762;18295.451136465927;14506.839452843536;14823.962322637743;14827.851721482648;14849.628759281104;14940;12832.679183020498;13253.602391927872;13278.80081798793;13373.659609568092;13390.598282051555;14920.253987300808;15071.397144114113;15245.3901843925;15327.693446131238;15353.925225234127;10107.949460252652;10129.442880641636;10225.26170551023;10228;10248.846267305848;14592.321882983593;14649.92675036618;14674.486392976194;14696;14798.926005369904 19:16:28 INFO - PID 1657 | |3;mootools.html;1252.3675543850354;1421.8340960412465;1429.7569413199756;1440.2438719672177;1443.7762146867233;11602.245854019482;11938.820917686242;12189.329586872718;12226.633201003973;12362.876371236285;9085.636574537028;9401.952990235057;9715.077067678578;11131.276467029638;11240.943795281033;2402.5915594348917;2533.480636469519;2535.4422027153996;2537.4925014996984;2537.883331334207;2037.2869495676543;2125.1818050050765;2192.9342119736352;2455.1897873701723;2587.8317909335833;2535.8858851351733;2546.363409147713;2568.2166939083572;2572.536943350196;2577.574700174469;2527.2165628655084;2537.086648806428;2541.4027703489664;2541.7712405883467;2542.6058960861096;2434.1237154624323;2463.6797216361756;2465.778079972811;2466.7903228225623;2490.601503759408;11639.47622356996;11724.941375293154;11799.823002654966;11825.645230643095;11843.289402635783;6396.1045453636525;6645.700943457556;6649.468042556584;6652.800415987528;6679.398854103106;4054.3107671695884;4253.544620517114;4346.695731298811;4364.738115713068;4371.825126994916;1243.8134279858002;1253.8996880249558;1259.118616968126;1281.4297637551294;1287.594407761552;16889.14219435861;16984;17016.40442584516;17016.89390189646;17040.06279654631;5545.670818256342;5618.747156377973;5621.891790578533;5750.7987220447085;5767.192593036978;12457.750844983231;12650.177738447155;12669.366531673379;12684.682882928;12687.365631718561;6370.267419246753;6508.674566271668;6514.446272066927;6523.412892839668;6529.902051469232;6066.939330606638;6146.200993870768;6165.32181460039;6178.7219575119225;6183.567150299526;4427.180971520214;4507.414036175342;4513.842015529441;4546.704464209815;4553.772311384484;1151.8617765867982;1173.9647810565696;1257.1011726615332;1277.2145130744525;1284.05621867927;804.0672819529318;810.6473683947502;825.41395609117;834.7871292820292;861.1345597674423;5709.7716091356715;5945.524358051345;6961.512694111364;7247.710091596277;7382.1141463023705;942.330945029037;948;957.8084383123403;962.3936919740659;982.0964712464407;3961.7226794124713;4127.318992366226;4150.252954468165;4185.958140418617;4223;14932.32804523799;15166.696666066617;15179.544613661608;15317.770233446285;15333.233338333039;14860.479883204252;15117.657017098323;15214;15228.847711522743;15253.847461525465;8531.317494600416;8569.442986205877;8601.096884827128;8631.136886311318;8694.17405346491;5443.918341224806;6867.690953907186;7017.298270173146;7114.50198486101;7286.599237042014;13550.322483875963;13922.791158132433;13935.790963135763;14002.719945601235;14088.30940287172;13464.596062118153;14193.503227386991;14323.427062917366;14533.781993269899;14553;8818.250448711675;9039.005709371831;9044.819103618023;9048.185663290073;9048.909510904807;8576.742697718828;10718.89281107179;10841.620543280947;10908.290961087503;10982.725431863948;7613.200613935457;9063.773405664911;9345.15893569582;9421.95289023578;9486.573104210189;7336.816579585553;9133.269338453041;9192.724218273463;9196.172344489029;9201.171894529525;8161.632726527439;8178.386621003329;8208.138145494637;8276.627551760064;8315;5816.381700669056;5876.206712093813;6148.569600127948;6299.590526615755;6315.652639104894;6098.237720284965;6113.694315284307;6369;6385.233771947396;6595.8021259362295 19:16:28 INFO - PID 1657 | |4;prototype.html;340.25314434815573;347.87998140641486;381.87016414419094;396.3044856277233;407.2994449546778;5733.713314334277;6161.876762464759;6363.745450181993;6432.903506447407;6465.418112369885;6133.969330153354;6239.594426362283;6263.404976527234;6267.592606480575;6297.748090076403;3731.9550525852806;4130.917381652365;4586.757249544175;4590.586847183753;4613.054323863616;3991.6607088397523;4602.677812553123;4622.052479241765;4627.6529260305615;4634.467036290819;3787.8674246401383;4528.913060865401;4559.726416415026;4582.495925448198;4593.609543188816;3763.642453966855;4525.818967241306;4547.5452454754595;4551.590356867898;4571.405717256735;3928.723164971367;3937.271604753101;3940.1134744682504;3941.8620348287673;3948.9012774680577;6079.513638908877;6142.570020098595;6161.9691901540655;6162.168107305546;6162.322144564095;4900.852974410774;5365.651232669864;5403.162509810985;5471.440639417747;5511.145772405283;5543.085390910495;5602.467765562265;6423.430627748891;6779.457643388517;6789.1852977643;4447.866564003085;4464.665150113755;4478;4480.462344518679;4495.98840260942;6760.492963027793;6999.405050570656;7145.4283657307815;7211.783646490614;7213.537501999017;3820.7325487215626;3951.8419263229725;4107.268666336852;4487.977560112244;4554.157480866051;4168.958310416918;4464.896896656793;4613.377194078824;4619.815207391666;4621.745803980813;5397.649152805055;5416.431274716177;5428.321459817523;5434.91847622286;5437.564994800407;6559;6808.425576572339;6817.863642727217;6823.795286141423;6826.965865170741;1442.084276484419;1501.2343704710458;1546.7935010691679;1585.3987472652705;1588.793456850602;955.5126885288414;1005.8642083318805;1008.3143462445461;1046.3355769086684;1059.8092343378112;5865.296164460292;6326.810195694136;6357.141785858942;6368.235811702625;6387.297397286295;6997.755078572327;7023.33278338557;7043.436525077981;7052.471064670068;7055.2239253682055;5562.554995600342;6329.145565348711;6337.33457986914;6365.809025729235;6374.378575532702;3475.6003059648465;3555.1289933966345;3644.6173151819207;3646.997075804175;3673.504076949631;5768.451997060272;6692.431143352772;7203.711851525985;7231.2768723127265;7263.491555591164;7145.783987447431;7169.462290328246;7201.099862517185;7262.9273707262255;7305;6155;7099.396551293198;7193.424526038009;7219.639018049182;7224.963875180695;6924.653767311514;6981.476389270927;7012.544184627983;7087.858242835217;7100.431965442649;6636.369544893228;6897.517173797986;6897.89653155193;6907;6930.712864754518;5329.709583784936;5682.5453963681975;5783.884322313615;6655.3344665535;7137.892931605927;3845.403962385835;4495.955040449554;4502.369668246382;4514.88712782183;4527.320901864747;6378.840528986812;6612.8346791330605;7046.154461464657;7051.682674279567;7053.5312640575385;5697;5894.882102357843;6779.559328643622;6805.931940680728;6825.35159159879;5924.733386997509;6018.789342372996;6799.150106236721;6803.625800580818;6811.2167100784045;6081.878362432815;6658.234303055211;6679.866402672016;6695.254945472528;6728.394444500019;5412.7564259607625;5636.774529018958;6782.559133656297;6796.72813087471;6831.214410342675;6136.20229370179;6592.340765923369;6618.7352505899225;6632.60204387719;6633.540621063358 19:16:28 INFO - PID 1657 | |5;yui.html;802.1537278171528;815.5188438821094;822.4777266435811;855.9300873907615;859.1108203009884;14826.703465930676;15461.845381546182;17105;17560.736588951175;17584.384546540874;17148.22832972516;17606.20772065257;17922.37271695491;18010.189541470634;18025.09874506273;1007.4207330784791;1009.7475631092227;1017.694691592523;1023.1712313026435;1023.5291765787747;1004.9497525123751;1007.9647212347569;1009.2934945538116;1012.9341592796445;1035.5288343803552;685.9039734437183;686.2519853359837;1017.1608423051013;1054.4200689620716;1070.9732256693567;1048.7535429174177;1057.2968975631195;1058.1851973979992;1062.2458054781114;1065.6856227412893;722.2344315026089;777.0869228656363;782.7416952405749;786.2844811221818;786.4416264452245;16868.493945181664;16941.407050753165;17082.743758843626;17222.569435764082;17224.22490987896;16847.57881052971;17226.82773172265;17380.21789019497;17677.73483397737;17725.645487090187;14634.268286585735;14875.107493550422;14928.402863885432;14934.850651493454;14978.251087445693;3491.9825400873087;3541.8937431876825;3553.6979356754705;3565.8038807865555;3567.019069755812;135896;140181;141473;141611;142462;13307;13682.794758078637;13727.176369417866;13731.313434328244;13755;26538.46923061528;26555.336116596853;26849.060282890005;27051.917923283243;27081;7694.730684426018;7880.763577092696;7882.802929926683;7965.442419030613;7979.5611241382285;3983;4061.3704875744306;4063;4063.0858056936954;4074.7351422157467;3693.501377314028;4362.672799540047;4447.599716025573;4467.262901621196;4641;948.5162567090833;959.5346257065318;966.7728083900278;976.8046390721884;978.6281213138961;634.9619022858643;641.2209165863499;655.1417642887737;666.560070353574;680.9012693159464;11145.888541114648;11493.290680698168;11738.823917641243;11946;12799.360031998549;586.9434053336552;628.6982248520736;652.1782553981922;658.6114192626278;661.1933441201726;5387.9461205388225;5521.337439507285;5537.252470916455;5698.943010569924;5715.628484148517;121446;121854;124857.75142248643;125272;126458;99403.50298248463;101292;102988;103733.96266037395;103788.48105759574;72843;85184;97664;98396;101098.99999999853;11606.88393116058;12560.246385216917;12729.681757956125;12882.871171288167;12890.834375558066;42592.78703606586;42625;42648.78675606602;43556.782216088715;43820;48971;51940.06569375903;57379;57799.79801817689;57994.7100264496;16291.511254661906;16392.2623481946;16679;16951.576210594834;16989.235484403478;18286.994215318286;18689.065546722337;18712.719309210646;18811.965341906325;18925.716114258023;13712.65718357009;15764.60588485297;17198.22607982619;17325.480235592953;17471.126443677513;16477.34090636361;16786.24461899242;17174.45429911314;17232;18071.81928180754;14716.632084197981;17371.131443428032;17728.55678608045;17781.644367112844;17856.910715446338;10495.685129446128;11702.648920532398;11985.22096063753;12389.75220495603;12428.316442595744;12641.557545485864;13379.063465557318;13446.394912228778;13614.406673826868;13734.793978090136 19:16:28 INFO - PID 1657 | __end_tp_report 19:16:28 INFO - PID 1657 | __start_cc_report 19:16:28 INFO - PID 1657 | _x_x_mozilla_cycle_collect,461 19:16:28 INFO - PID 1657 | __end_cc_report 19:16:28 INFO - PID 1657 | __startTimestamp1496456188218__endTimestamp 19:16:28 INFO - PID 1657 | 19:16:28 INFO - PID 1657 | ------- Summary: start ------- 19:16:28 INFO - PID 1657 | Number of tests: 6 19:16:28 INFO - PID 1657 | 19:16:28 INFO - PID 1657 | [#0] dojo.html Cycles:165 Average:6068.35 Median:6311.27 stddev:753.69 (11.9%) stddev-sans-first:714.52 19:16:28 INFO - PID 1657 | Values: 2924.8 3249.9 3545.3 3555.1 3565.5 6030.5 6097.4 6143.2 6177.8 6208.2 5018.6 6077.6 6100.4 6128.7 6185.5 5011.2 5411.2 5439.8 5444.9 5516.0 4938.3 5289.1 5510.5 5558.0 5587.4 5295.8 5354.4 5416.1 5453.4 5512.6 5388.8 5392.6 5397.1 5444.3 5447.2 5363.9 5365.6 5388.8 5407.8 5416.0 4971.9 6074.3 6196.4 6211.7 6215.2 6417.1 6521.7 6609.8 6627.6 6678.5 6707.4 6733.5 6741.4 6742.9 6762.0 6285.3 6292.3 6297.5 6297.5 6310.3 6153.2 6457.0 6592.8 6834.6 6881.4 6194.0 6507.0 6518.5 6524.2 6566.4 6499.4 6629.7 6681.4 6695.0 6702.9 6545.0 6571.2 6597.9 6617.1 6620.8 6369.6 6400.3 6514.6 6528.1 6599.2 5326.3 5326.4 5328.5 5334.8 5345.9 4707.7 4732.8 4738.3 4766.8 4786.5 5082.7 5309.5 6089.4 6264.0 6279.0 6706.6 6732.3 6744.1 6772.1 6778.3 4990.7 6059.8 6238.3 6240.4 6256.1 5696.1 5770.9 5797.1 5858.2 5869.6 6808.2 6820.3 6824.6 6842.8 6867.4 6642.9 6676.8 6687.2 6689.7 6710.2 6595.0 6792.3 6844.3 6855.1 6857.6 6386.8 6470.6 6530.0 6589.2 6616.2 6751.2 6770.8 6772.3 6773.2 6778.6 6340.0 6388.3 6427.2 6521.4 6553.0 5204.7 5641.9 6311.2 6433.0 6504.9 6317.7 6381.0 6386.3 6421.5 6439.6 6330.5 6353.9 6532.7 6536.2 6558.7 6288.7 6291.8 6311.3 6328.9 6335.8 19:16:28 INFO - PID 1657 | 19:16:28 INFO - PID 1657 | [#1] ext.html Cycles:180 Average:43268.92 Median:34208.49 stddev:73526.65 (214.9%) stddev-sans-first:73663.31 19:16:28 INFO - PID 1657 | Values: 658.7 757.4 784.5 1259.5 1325.7 32546.2 36259.8 38733.4 39002.6 39002.8 39975.4 40017.0 40208.0 40256.0 40263.6 7888.6 8041.4 8272.5 8453.2 8461.0 8288.9 8351.5 8361.0 8445.5 8465.2 8408.8 8453.8 8462.7 8467.8 8488.7 8429.4 8449.8 8450.2 8451.2 8458.1 8324.3 8330.9 8354.1 8361.1 8395.6 36886.6 36893.0 37044.0 37185.8 37287.6 3628.9 3668.5 3673.6 3675.6 3676.4 29932.0 30198.5 30206.4 30282.7 30436.1 3095.0 3116.9 3139.3 3139.4 3145.9 217574.0 219135.0 242074.0 267015.0 270196.0 38188.0 46943.5 47042.8 47083.3 47123.6 49198.8 49642.0 49778.3 53218.5 54052.5 14543.5 14652.9 14661.7 14681.3 14693.6 11653.5 11741.1 11845.7 11846.5 11857.6 13827.9 13836.7 13870.9 13910.9 13983.4 6144.8 6171.4 6193.1 6214.6 6221.4 747.2 839.9 875.1 1004.0 1038.9 22958.9 23144.1 23368.0 24139.0 24649.8 1065.2 1076.6 1122.5 1124.9 1130.6 10499.0 11505.5 12291.4 12672.6 12683.7 264979.0 329236.0 329950.0 331027.0 331718.0 42791.1 55252.4 55574.4 55707.9 55822.9 1461.3 1511.5 1526.1 1532.9 1535.7 21203.8 21533.5 21890.8 23487.6 24190.4 38668.8 38747.8 38770.8 38866.0 38998.4 272830.0 274129.0 274580.0 275657.2 276004.0 34107.3 34309.7 34930.0 35562.6 35643.8 44046.0 45259.3 45269.0 45579.5 45644.6 29344.3 29740.0 30803.0 30815.4 31445.0 28259.0 28504.0 29373.9 29910.1 31075.5 35931.8 37104.9 37341.0 37383.3 37503.8 29679.4 30317.1 30336.8 30918.7 31617.0 23362.3 24448.9 24651.0 24765.4 24805.5 19:16:28 INFO - PID 1657 | 19:16:28 INFO - PID 1657 | [#2] jquery.html Cycles:180 Average:39736.84 Median:2280.28 stddev:97404.92 (4271.6%) stddev-sans-first:97633.25 19:16:28 INFO - PID 1657 | Values: 342.9 386.6 419.4 430.5 436.6 14572.4 15214.0 21519.9 22108.8 22457.9 18726.0 23906.0 23962.5 24019.5 24128.6 6038.3 6198.3 6252.8 6255.0 6262.4 6123.9 6225.9 6233.5 6240.1 6245.7 6199.6 6201.2 6519.4 6834.0 6902.1 6764.9 6787.1 6795.4 6803.3 6827.0 6650.4 6667.4 6670.3 6672.0 6686.3 29134.3 29612.0 29996.4 30043.5 30048.4 2277.9 2321.4 2329.5 2330.7 2331.3 18744.2 19274.8 19290.7 19296.9 19407.6 2228.6 2245.7 2304.8 2305.4 2306.1 189902.1 194208.0 195380.0 197652.0 197898.0 19888.0 23098.4 24034.4 25145.9 25291.1 32733.3 41774.0 41918.6 41955.8 41980.8 11076.2 11101.9 11164.2 11177.7 11231.2 8736.7 8803.4 8832.6 8907.7 8919.0 7707.5 9060.5 11765.2 11919.6 12004.6 1894.0 2254.3 2282.6 2288.3 2449.3 836.8 850.5 975.5 1020.4 1027.9 20097.0 21125.0 21172.7 21411.6 21461.8 855.9 873.4 968.1 986.8 1027.5 9117.9 10432.8 11021.9 11113.9 11321.5 555232.0 556855.0 562210.5 571682.0 573275.0 143502.3 146011.0 146893.0 146949.5 147806.0 1140.9 1160.4 1185.5 1189.3 1191.4 17659.4 17889.9 18706.2 18942.7 19244.9 63832.0 65655.0 66956.0 67417.3 67514.6 68921.0 90677.5 91055.0 91528.5 91637.5 9822.5 10295.7 10356.9 10407.9 10412.0 13199.5 13837.2 17007.1 18073.9 18295.5 14506.8 14824.0 14827.9 14849.6 14940.0 12832.7 13253.6 13278.8 13373.7 13390.6 14920.3 15071.4 15245.4 15327.7 15353.9 10107.9 10129.4 10225.3 10228.0 10248.8 14592.3 14649.9 14674.5 14696.0 14798.9 19:16:28 INFO - PID 1657 | 19:16:28 INFO - PID 1657 | [#3] mootools.html Cycles:180 Average:7157.91 Median:4204.48 stddev:4588.11 (109.1%) stddev-sans-first:4579.51 19:16:28 INFO - PID 1657 | Values: 1252.4 1421.8 1429.8 1440.2 1443.8 11602.2 11938.8 12189.3 12226.6 12362.9 9085.6 9402.0 9715.1 11131.3 11240.9 2402.6 2533.5 2535.4 2537.5 2537.9 2037.3 2125.2 2192.9 2455.2 2587.8 2535.9 2546.4 2568.2 2572.5 2577.6 2527.2 2537.1 2541.4 2541.8 2542.6 2434.1 2463.7 2465.8 2466.8 2490.6 11639.5 11724.9 11799.8 11825.6 11843.3 6396.1 6645.7 6649.5 6652.8 6679.4 4054.3 4253.5 4346.7 4364.7 4371.8 1243.8 1253.9 1259.1 1281.4 1287.6 16889.1 16984.0 17016.4 17016.9 17040.1 5545.7 5618.7 5621.9 5750.8 5767.2 12457.8 12650.2 12669.4 12684.7 12687.4 6370.3 6508.7 6514.4 6523.4 6529.9 6066.9 6146.2 6165.3 6178.7 6183.6 4427.2 4507.4 4513.8 4546.7 4553.8 1151.9 1174.0 1257.1 1277.2 1284.1 804.1 810.6 825.4 834.8 861.1 5709.8 5945.5 6961.5 7247.7 7382.1 942.3 948.0 957.8 962.4 982.1 3961.7 4127.3 4150.3 4186.0 4223.0 14932.3 15166.7 15179.5 15317.8 15333.2 14860.5 15117.7 15214.0 15228.8 15253.8 8531.3 8569.4 8601.1 8631.1 8694.2 5443.9 6867.7 7017.3 7114.5 7286.6 13550.3 13922.8 13935.8 14002.7 14088.3 13464.6 14193.5 14323.4 14533.8 14553.0 8818.3 9039.0 9044.8 9048.2 9048.9 8576.7 10718.9 10841.6 10908.3 10982.7 7613.2 9063.8 9345.2 9422.0 9486.6 7336.8 9133.3 9192.7 9196.2 9201.2 8161.6 8178.4 8208.1 8276.6 8315.0 5816.4 5876.2 6148.6 6299.6 6315.7 6098.2 6113.7 6369.0 6385.2 6595.8 19:16:28 INFO - PID 1657 | 19:16:28 INFO - PID 1657 | [#4] prototype.html Cycles:180 Average:5406.79 Median:6107.92 stddev:1738.08 (28.5%) stddev-sans-first:1700.84 19:16:28 INFO - PID 1657 | Values: 340.3 347.9 381.9 396.3 407.3 5733.7 6161.9 6363.7 6432.9 6465.4 6134.0 6239.6 6263.4 6267.6 6297.7 3732.0 4130.9 4586.8 4590.6 4613.1 3991.7 4602.7 4622.1 4627.7 4634.5 3787.9 4528.9 4559.7 4582.5 4593.6 3763.6 4525.8 4547.5 4551.6 4571.4 3928.7 3937.3 3940.1 3941.9 3948.9 6079.5 6142.6 6162.0 6162.2 6162.3 4900.9 5365.7 5403.2 5471.4 5511.1 5543.1 5602.5 6423.4 6779.5 6789.2 4447.9 4464.7 4478.0 4480.5 4496.0 6760.5 6999.4 7145.4 7211.8 7213.5 3820.7 3951.8 4107.3 4488.0 4554.2 4169.0 4464.9 4613.4 4619.8 4621.7 5397.6 5416.4 5428.3 5434.9 5437.6 6559.0 6808.4 6817.9 6823.8 6827.0 1442.1 1501.2 1546.8 1585.4 1588.8 955.5 1005.9 1008.3 1046.3 1059.8 5865.3 6326.8 6357.1 6368.2 6387.3 6997.8 7023.3 7043.4 7052.5 7055.2 5562.6 6329.1 6337.3 6365.8 6374.4 3475.6 3555.1 3644.6 3647.0 3673.5 5768.5 6692.4 7203.7 7231.3 7263.5 7145.8 7169.5 7201.1 7262.9 7305.0 6155.0 7099.4 7193.4 7219.6 7225.0 6924.7 6981.5 7012.5 7087.9 7100.4 6636.4 6897.5 6897.9 6907.0 6930.7 5329.7 5682.5 5783.9 6655.3 7137.9 3845.4 4496.0 4502.4 4514.9 4527.3 6378.8 6612.8 7046.2 7051.7 7053.5 5697.0 5894.9 6779.6 6805.9 6825.4 5924.7 6018.8 6799.2 6803.6 6811.2 6081.9 6658.2 6679.9 6695.3 6728.4 5412.8 5636.8 6782.6 6796.7 6831.2 6136.2 6592.3 6618.7 6632.6 6633.5 19:16:28 INFO - PID 1657 | 19:16:28 INFO - PID 1657 | [#5] yui.html Cycles:180 Average:23557.93 Median:17515.93 stddev:34807.88 (198.7%) stddev-sans-first:34863.59 19:16:28 INFO - PID 1657 | Values: 802.2 815.5 822.5 855.9 859.1 14826.7 15461.8 17105.0 17560.7 17584.4 17148.2 17606.2 17922.4 18010.2 18025.1 1007.4 1009.7 1017.7 1023.2 1023.5 1004.9 1008.0 1009.3 1012.9 1035.5 685.9 686.3 1017.2 1054.4 1071.0 1048.8 1057.3 1058.2 1062.2 1065.7 722.2 777.1 782.7 786.3 786.4 16868.5 16941.4 17082.7 17222.6 17224.2 16847.6 17226.8 17380.2 17677.7 17725.6 14634.3 14875.1 14928.4 14934.9 14978.3 3492.0 3541.9 3553.7 3565.8 3567.0 135896.0 140181.0 141473.0 141611.0 142462.0 13307.0 13682.8 13727.2 13731.3 13755.0 26538.5 26555.3 26849.1 27051.9 27081.0 7694.7 7880.8 7882.8 7965.4 7979.6 3983.0 4061.4 4063.0 4063.1 4074.7 3693.5 4362.7 4447.6 4467.3 4641.0 948.5 959.5 966.8 976.8 978.6 635.0 641.2 655.1 666.6 680.9 11145.9 11493.3 11738.8 11946.0 12799.4 586.9 628.7 652.2 658.6 661.2 5387.9 5521.3 5537.3 5698.9 5715.6 121446.0 121854.0 124857.8 125272.0 126458.0 99403.5 101292.0 102988.0 103734.0 103788.5 72843.0 85184.0 97664.0 98396.0 101099.0 11606.9 12560.2 12729.7 12882.9 12890.8 42592.8 42625.0 42648.8 43556.8 43820.0 48971.0 51940.1 57379.0 57799.8 57994.7 16291.5 16392.3 16679.0 16951.6 16989.2 18287.0 18689.1 18712.7 18812.0 18925.7 13712.7 15764.6 17198.2 17325.5 17471.1 16477.3 16786.2 17174.5 17232.0 18071.8 14716.6 17371.1 17728.6 17781.6 17856.9 10495.7 11702.6 11985.2 12389.8 12428.3 12641.6 13379.1 13446.4 13614.4 13734.8 19:16:28 INFO - PID 1657 | -------- Summary: end -------- 19:16:28 INFO - PID 1657 | 19:16:28 INFO - TEST-INFO | 1657: exit 0 19:16:28 INFO - TEST-OK | dromaeo_css | took 1143406ms 19:16:28 INFO - TEST-START | kraken 19:16:28 INFO - operating with platform_type : mac_ 19:16:28 INFO - Initialising browser for kraken test... 19:16:28 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground -profile /var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/tmpaOiq9v/profile 19:16:28 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground) 19:17:02 INFO - TEST-INFO | 1666: killed by SIGTERM 19:17:02 INFO - Browser initialized. 19:17:02 INFO - Running cycle 1/1 for kraken test... 19:17:02 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/tmpaOiq9v/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:17:05 INFO - PID 1670 | 2017-06-02 19:17:05.050 plugin-container[1671:11955] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9943, name = 'com.apple.tsm.portname' 19:17:05 INFO - PID 1670 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:17:05 INFO - PID 1670 | 2017-06-02 19:17:05.051 plugin-container[1671:11955] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 19:17:05 INFO - PID 1670 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:17:05 INFO - PID 1670 | 2017-06-02 19:17:05.051 plugin-container[1671:11955] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:17:05 INFO - PID 1670 | 2017-06-02 19:17:05.404 plugin-container[1672:12003] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x952b, name = 'com.apple.tsm.portname' 19:17:05 INFO - PID 1670 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:17:05 INFO - PID 1670 | 2017-06-02 19:17:05.404 plugin-container[1672:12003] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 19:17:05 INFO - PID 1670 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:17:05 INFO - PID 1670 | 2017-06-02 19:17:05.404 plugin-container[1672:12003] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:17:14 INFO - PID 1670 | RSS: Main: 269721600 19:17:14 INFO - PID 1670 | 19:17:17 INFO - PID 1670 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection) 19:17:18 INFO - PID 1670 | RSS: Main: 276545536 19:17:18 INFO - PID 1670 | 19:17:21 INFO - PID 1670 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-dft) 19:17:21 INFO - PID 1670 | RSS: Main: 286679040 19:17:21 INFO - PID 1670 | 19:17:25 INFO - PID 1670 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-fft) 19:17:25 INFO - PID 1670 | RSS: Main: 284643328 19:17:25 INFO - PID 1670 | 19:17:27 INFO - PID 1670 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator) 19:17:28 INFO - PID 1670 | RSS: Main: 286580736 19:17:28 INFO - PID 1670 | 19:17:30 INFO - PID 1670 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:17:31 INFO - PID 1670 | RSS: Main: 287338496 19:17:31 INFO - PID 1670 | 19:17:33 INFO - PID 1670 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom) 19:17:34 INFO - PID 1670 | RSS: Main: 292327424 19:17:34 INFO - PID 1670 | 19:17:37 INFO - PID 1670 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate) 19:17:38 INFO - PID 1670 | RSS: Main: 292536320 19:17:38 INFO - PID 1670 | 19:17:41 INFO - PID 1670 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial) 19:17:41 INFO - PID 1670 | RSS: Main: 292724736 19:17:41 INFO - PID 1670 | 19:17:43 INFO - PID 1670 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:17:43 INFO - PID 1670 | RSS: Main: 291790848 19:17:43 INFO - PID 1670 | 19:17:45 INFO - PID 1670 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:17:46 INFO - PID 1670 | RSS: Main: 292012032 19:17:46 INFO - PID 1670 | 19:17:48 INFO - PID 1670 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:17:49 INFO - PID 1670 | RSS: Main: 291880960 19:17:49 INFO - PID 1670 | 19:17:52 INFO - PID 1670 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:17:53 INFO - PID 1670 | RSS: Main: 292134912 19:17:53 INFO - PID 1670 | 19:17:56 INFO - PID 1670 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:17:57 INFO - PID 1670 | RSS: Main: 292851712 19:17:57 INFO - PID 1670 | 19:17:58 INFO - PID 1670 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49228/tests/kraken/driver.html?testName=ai-astar) 19:17:59 INFO - PID 1670 | RSS: Main: 294240256 19:17:59 INFO - PID 1670 | 19:17:59 INFO - PID 1670 | __start_tp_report 19:17:59 INFO - PID 1670 | _x_x_mozilla_page_load 19:17:59 INFO - PID 1670 | _x_x_mozilla_page_load_details 19:17:59 INFO - PID 1670 | |i|pagename|runs| 19:17:59 INFO - PID 1670 | |0;ai-astar;104;117;115;114;134;170;113;94;171;165 19:17:59 INFO - PID 1670 | |1;audio-beat-detection;144;112;108;158;113;111;113;103;149;146 19:17:59 INFO - PID 1670 | |2;audio-dft;198;198;234;234;209;223;200;199;253;228 19:17:59 INFO - PID 1670 | |3;audio-fft;78;58;59;84;60;77;87;76;77;58 19:17:59 INFO - PID 1670 | |4;audio-oscillator;84;68;88;72;69;69;88;69;69;90 19:17:59 INFO - PID 1670 | |5;imaging-gaussian-blur;90;91;89;89;89;89;95;91;94;115 19:17:59 INFO - PID 1670 | |6;imaging-darkroom;96;97;124;98;98;98;97;96;97;122 19:17:59 INFO - PID 1670 | |7;imaging-desaturate;90;93;92;91;91;93;91;92;91;90 19:17:59 INFO - PID 1670 | |8;json-parse-financial;49;50;48;47;51;50;51;49;51;50 19:17:59 INFO - PID 1670 | |9;json-stringify-tinderbox;58;65;58;59;64;62;57;77;88;82 19:17:59 INFO - PID 1670 | |10;stanford-crypto-aes;87;97;100;115;115;86;91;89;126;92 19:17:59 INFO - PID 1670 | |11;stanford-crypto-ccm;178;165;123;136;164;155;122;142;149;137 19:17:59 INFO - PID 1670 | |12;stanford-crypto-pbkdf2;192;184;191;227;236;226;164;220;245;223 19:17:59 INFO - PID 1670 | |13;stanford-crypto-sha256-iterative;66;68;63;65;70;65;65;94;99;98 19:17:59 INFO - PID 1670 | __end_tp_report 19:17:59 INFO - PID 1670 | __start_cc_report 19:17:59 INFO - PID 1670 | _x_x_mozilla_cycle_collect,1206 19:17:59 INFO - PID 1670 | __end_cc_report 19:17:59 INFO - PID 1670 | __startTimestamp1496456279040__endTimestamp 19:17:59 INFO - PID 1670 | 19:17:59 INFO - PID 1670 | ------- Summary: start ------- 19:17:59 INFO - PID 1670 | Number of tests: 14 19:17:59 INFO - PID 1670 | 19:17:59 INFO - PID 1670 | [#0] ai-astar Cycles:10 Average:129.70 Median:149.50 stddev:28.74 (19.2%) stddev-sans-first:28.94 19:17:59 INFO - PID 1670 | Values: 104.0 117.0 115.0 114.0 134.0 170.0 113.0 94.0 171.0 165.0 19:17:59 INFO - PID 1670 | 19:17:59 INFO - PID 1670 | [#1] audio-beat-detection Cycles:10 Average:125.70 Median:128.50 stddev:20.78 (16.2%) stddev-sans-first:20.96 19:17:59 INFO - PID 1670 | Values: 144.0 112.0 108.0 158.0 113.0 111.0 113.0 103.0 149.0 146.0 19:17:59 INFO - PID 1670 | 19:17:59 INFO - PID 1670 | [#2] audio-dft Cycles:10 Average:217.60 Median:225.50 stddev:19.51 (8.7%) stddev-sans-first:19.36 19:17:59 INFO - PID 1670 | Values: 198.0 198.0 234.0 234.0 209.0 223.0 200.0 199.0 253.0 228.0 19:17:59 INFO - PID 1670 | 19:17:59 INFO - PID 1670 | [#3] audio-fft Cycles:10 Average:71.40 Median:77.00 stddev:11.41 (14.8%) stddev-sans-first:11.85 19:17:59 INFO - PID 1670 | Values: 78.0 58.0 59.0 84.0 60.0 77.0 87.0 76.0 77.0 58.0 19:17:59 INFO - PID 1670 | 19:17:59 INFO - PID 1670 | [#4] audio-oscillator Cycles:10 Average:76.60 Median:78.00 stddev:9.55 (12.2%) stddev-sans-first:9.74 19:17:59 INFO - PID 1670 | Values: 84.0 68.0 88.0 72.0 69.0 69.0 88.0 69.0 69.0 90.0 19:17:59 INFO - PID 1670 | 19:17:59 INFO - PID 1670 | [#5] imaging-gaussian-blur Cycles:10 Average:93.20 Median:90.50 stddev:7.96 (8.8%) stddev-sans-first:8.35 19:17:59 INFO - PID 1670 | Values: 90.0 91.0 89.0 89.0 89.0 89.0 95.0 91.0 94.0 115.0 19:17:59 INFO - PID 1670 | 19:17:59 INFO - PID 1670 | [#6] imaging-darkroom Cycles:10 Average:102.30 Median:97.00 stddev:10.94 (11.3%) stddev-sans-first:11.37 19:17:59 INFO - PID 1670 | Values: 96.0 97.0 124.0 98.0 98.0 98.0 97.0 96.0 97.0 122.0 19:17:59 INFO - PID 1670 | 19:17:59 INFO - PID 1670 | [#7] imaging-desaturate Cycles:10 Average:91.40 Median:91.50 stddev:1.07 (1.2%) stddev-sans-first:1.01 19:17:59 INFO - PID 1670 | Values: 90.0 93.0 92.0 91.0 91.0 93.0 91.0 92.0 91.0 90.0 19:17:59 INFO - PID 1670 | 19:17:59 INFO - PID 1670 | [#8] json-parse-financial Cycles:10 Average:49.60 Median:50.00 stddev:1.35 (2.7%) stddev-sans-first:1.41 19:17:59 INFO - PID 1670 | Values: 49.0 50.0 48.0 47.0 51.0 50.0 51.0 49.0 51.0 50.0 19:17:59 INFO - PID 1670 | 19:17:59 INFO - PID 1670 | [#9] json-stringify-tinderbox Cycles:10 Average:67.00 Median:64.50 stddev:11.21 (17.4%) stddev-sans-first:11.40 19:17:59 INFO - PID 1670 | Values: 58.0 65.0 58.0 59.0 64.0 62.0 57.0 77.0 88.0 82.0 19:17:59 INFO - PID 1670 | 19:17:59 INFO - PID 1670 | [#10] stanford-crypto-aes Cycles:10 Average:99.80 Median:88.00 stddev:14.01 (15.9%) stddev-sans-first:14.07 19:17:59 INFO - PID 1670 | Values: 87.0 97.0 100.0 115.0 115.0 86.0 91.0 89.0 126.0 92.0 19:17:59 INFO - PID 1670 | 19:17:59 INFO - PID 1670 | [#11] stanford-crypto-ccm Cycles:10 Average:147.10 Median:152.00 stddev:18.53 (12.2%) stddev-sans-first:15.92 19:17:59 INFO - PID 1670 | Values: 178.0 165.0 123.0 136.0 164.0 155.0 122.0 142.0 149.0 137.0 19:17:59 INFO - PID 1670 | 19:17:59 INFO - PID 1670 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:210.80 Median:224.50 stddev:26.22 (11.7%) stddev-sans-first:26.91 19:17:59 INFO - PID 1670 | Values: 192.0 184.0 191.0 227.0 236.0 226.0 164.0 220.0 245.0 223.0 19:17:59 INFO - PID 1670 | 19:17:59 INFO - PID 1670 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.30 Median:69.00 stddev:15.14 (21.9%) stddev-sans-first:15.68 19:17:59 INFO - PID 1670 | Values: 66.0 68.0 63.0 65.0 70.0 65.0 65.0 94.0 99.0 98.0 19:17:59 INFO - PID 1670 | -------- Summary: end -------- 19:17:59 INFO - PID 1670 | 19:17:59 INFO - TEST-INFO | 1670: exit 0 19:17:59 INFO - TEST-OK | kraken | took 91048ms 19:17:59 INFO - SUITE-END | took 1234s 19:18:00 INFO - Completed test suite (00:20:34) 19:18:00 INFO - javascript benchmark 19:18:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8079.298249057227, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2924.839133847639, 3249.853756580954, 3545.3441113394006, 3555.093451169951, 3565.4651802229646, 6030.5477089218375, 6097.390260973901, 6143.170671959284, 6177.845553861156, 6208.19293491846, 5018.64869459138, 6077.635341879491, 6100.399513635572, 6128.747575574754, 6185.474234690057, 5011.198208286684, 5411.215373770801, 5439.836804895859, 5444.91832622511, 5516.0071187186495, 4938.328872478301, 5289.100852855024, 5510.476504732044, 5558.0, 5587.441255874421, 5295.841124766263, 5354.384245811742, 5416.0792665247, 5453.400125986138, 5512.614117011811, 5388.838334849922, 5392.622516423852, 5397.136458166714, 5444.3194600674915, 5447.210154527611, 5363.892722145535, 5365.624406291561, 5388.757505912205, 5407.83776486706, 5416.0, 4971.906180640253, 6074.331823499411, 6196.411340922605, 6211.68941552925, 6215.223097112861, 6417.101605775195, 6521.706523206467, 6609.834754131137, 6627.569208001512, 6678.499112566528, 6707.3628005339415, 6733.494987875929, 6741.426978706816, 6742.932570674279, 6762.0, 6285.308616052231, 6292.339304373067, 6297.464715499188, 6297.527685423612, 6310.274318453345, 6153.2000839890525, 6456.966885298423, 6592.769253076215, 6834.589924604539, 6881.41507971818, 6194.039923811816, 6507.023946408077, 6518.511111666644, 6524.217093948661, 6566.376194261537, 6499.447546958467, 6629.701663425158, 6681.365270299314, 6695.0, 6702.865942681216, 6545.0, 6571.178602674666, 6597.901031484435, 6617.139771829728, 6620.834479137964, 6369.617822930639, 6400.327965563642, 6514.6416947068365, 6528.08606795049, 6599.241087274929, 5326.2543243945875, 5326.387465441447, 5328.467153284718, 5334.786608535693, 5345.9465405345445, 4707.7175369477945, 4732.763361831895, 4738.3129446230105, 4766.785494652749, 4786.473487916326, 5082.74586270685, 5309.522143007148, 6089.360617135226, 6263.96868015657, 6279.0, 6706.597604143767, 6732.3267673232285, 6744.089547911068, 6772.085768421299, 6778.254392016875, 4990.725510096907, 6059.757609695563, 6238.313785483593, 6240.375962403814, 6256.124142620037, 5696.088625819849, 5770.913436298459, 5797.101449275369, 5858.150568167677, 5869.559783016291, 6808.217055038734, 6820.31796820324, 6824.624645644537, 6842.7605033823575, 6867.416269616939, 6642.933570664231, 6676.83307917306, 6687.1975362956755, 6689.665516724242, 6710.19477662664, 6595.0, 6792.320767923168, 6844.349786770249, 6855.07456493367, 6857.55425897315, 6386.840328991812, 6470.579412338183, 6529.967350163409, 6589.17635295588, 6616.172978377703, 6751.223609284995, 6770.796876093725, 6772.322767723188, 6773.221079575912, 6778.559393639398, 6339.96830015847, 6388.297287298301, 6427.228732551936, 6521.445677117498, 6553.0, 5204.713740744295, 5641.928033673589, 6311.179546659088, 6433.0, 6504.902426463702, 6317.747290108344, 6380.968095159495, 6386.265579458422, 6421.454176394872, 6439.645819479786, 6330.461910737639, 6353.8729225416155, 6532.673366331569, 6536.248331441945, 6558.737650493927, 6288.654124023223, 6291.811245662638, 6311.27420346666, 6328.873422531431, 6335.809925702236], "value": 6016.986590458978, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [658.6871236162823, 757.4016526943711, 784.544963920926, 1259.4773169134814, 1325.7348530293932, 32546.18634534138, 36259.81870090649, 38733.41899871504, 39002.60997390025, 39002.82991510252, 39975.40036899449, 40017.0, 40207.994800130014, 40256.0, 40263.59736402635, 7888.645010974491, 8041.437099403044, 8272.545010024447, 8453.154684531559, 8460.957695211546, 8288.917110828905, 8351.498910065415, 8361.0, 8445.493270403766, 8465.195806398382, 8408.789780255482, 8453.788655283606, 8462.746117616482, 8467.83064338713, 8488.702895398663, 8429.367797415187, 8449.831003379959, 8450.19723126302, 8451.239388455007, 8458.111898250656, 8324.334053275785, 8330.916690833075, 8354.081051084377, 8361.080281169066, 8395.580220988988, 36886.631133688585, 36893.0, 37044.0, 37185.814070929744, 37287.627123728686, 3628.9455658165148, 3668.468072129553, 3673.6326367363317, 3675.6324367563298, 3676.4117741161554, 29932.0, 30198.54702179469, 30206.395872082434, 30282.69717302821, 30436.086917392513, 3095.0095969289837, 3116.8597413116463, 3139.293658926746, 3139.372125574894, 3145.8584363703458, 217574.0, 219135.0, 242074.0, 267015.0, 270196.0, 38188.0, 46943.5305646946, 47042.76478617585, 47083.293750593766, 47123.643817808974, 49198.77003074952, 49642.01431942682, 49778.25332620014, 53218.467815322125, 54052.45947540553, 14543.490977815725, 14652.853471465362, 14661.706765864836, 14681.265936703123, 14693.55919322422, 11653.53385864573, 11741.060715142768, 11845.703857403634, 11846.526138954345, 11857.644270671894, 13827.930860345836, 13836.716941704708, 13870.930645346709, 13910.930445347709, 13983.37074831635, 6144.846378840565, 6171.395437186274, 6193.102000209944, 6214.5960512566535, 6221.4089661482085, 747.1557140431278, 839.886615306938, 875.146731936357, 1003.996003996004, 1038.8857225705167, 22958.852057397064, 23144.074237030665, 23368.0, 24139.0, 24649.753502465104, 1065.1798115451058, 1076.5532304093713, 1122.5173175534599, 1124.9493772780245, 1130.559081958037, 10498.95010498944, 11505.482253298622, 12291.402117724638, 12672.619821405373, 12683.746325073631, 264979.0, 329236.0, 329950.0, 331027.0, 331718.0, 42791.144177116905, 55252.447475525536, 55574.44425555773, 55707.88584228293, 55822.883542329735, 1461.3496993837853, 1511.4709851551868, 1526.137732181314, 1532.923353832304, 1535.6851845371673, 21203.833789141747, 21533.46166345854, 21890.781092188874, 23487.647685285076, 24190.371444283497, 38668.806655967666, 38747.806260968515, 38770.80614596909, 38866.0, 38998.4150237741, 272830.0, 274129.0, 274580.0, 275657.2434275712, 276004.0, 34107.31785364328, 34309.656903430645, 34930.0, 35562.64437355697, 35643.82178089093, 44046.0, 45259.32111018272, 45269.0, 45579.54420455886, 45644.63066108022, 29344.26639334034, 29740.0, 30803.0, 30815.383692326475, 31445.025445165345, 28259.01093461801, 28504.013209420125, 29373.853130735064, 29910.102696919126, 31075.53386699156, 35931.82034089813, 37104.88685339444, 37341.0, 37383.25233495369, 37503.81248093742, 29679.406411872074, 30317.090487285415, 30336.83107522484, 30918.69081309158, 31617.0, 23362.299131026095, 24448.87775561111, 24651.013959442138, 24765.380865478506, 24805.50388992246], "value": 17361.745997090635, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [342.9142714321419, 386.6462187098803, 419.4044456871243, 430.54147333090236, 436.6179592856252, 14572.417103315882, 15214.0, 21519.924003799806, 22108.778912210873, 22457.887710561467, 18726.0, 23906.0, 23962.52074958504, 24019.519609607796, 24128.63807042896, 6038.275406951161, 6198.256209254896, 6252.812415627538, 6255.030470277115, 6262.405071518198, 6123.877522449548, 6225.906611400833, 6233.470155036827, 6240.063990401431, 6245.687715614201, 6199.628022318653, 6201.193844800169, 6519.413252807246, 6833.965830170842, 6902.102726645554, 6764.864702705943, 6787.083743694588, 6795.422389096883, 6803.319668033207, 6826.965865170667, 6650.434713049397, 6667.433268172211, 6670.299618540032, 6672.0, 6686.264510903797, 29134.27164320888, 29612.0, 29996.400071998654, 30043.549346759817, 30048.399032019457, 2277.943051423728, 2321.373229228099, 2329.5224478981936, 2330.685357476736, 2331.347222777608, 18744.15651295681, 19274.807251927443, 19290.71063934042, 19296.903515482474, 19407.611847763106, 2228.6322756745117, 2245.65192395179, 2304.8271379646594, 2305.377548062014, 2306.100620757889, 189902.10097898982, 194208.0289598557, 195380.0, 197652.0, 197898.01050994653, 19888.0, 23098.422539436648, 24034.39914002129, 25145.874270628894, 25291.143272350757, 32733.345333093683, 41773.95565110836, 41918.58081419208, 41955.7902210487, 41980.79009604932, 11076.169287303484, 11101.944490277658, 11164.218504704755, 11177.72055698614, 11231.157663175294, 8736.69421570242, 8803.434608983373, 8832.55837208137, 8907.73276801697, 8918.955405222932, 7707.499012564165, 9060.456372617664, 11765.235259708092, 11919.582814601446, 12004.639860804189, 1893.9526511837314, 2254.31243470743, 2282.6233671444356, 2288.3363824491043, 2449.252977841775, 836.8409752492842, 850.510956200175, 975.4634950777045, 1020.403064207447, 1027.8920713324994, 20097.0, 21125.049372778263, 21172.729636221877, 21411.571768564543, 21461.785382146292, 855.9015713192938, 873.3929918706565, 968.1286841842398, 986.8026394721085, 1027.527337424793, 9117.857303533665, 10432.843507347396, 11021.944890275658, 11113.944430277797, 11321.490532926038, 555232.0, 556855.0, 562210.4786951152, 571682.0, 573275.0, 143502.28248858688, 146011.0, 146893.0, 146949.5305046936, 147806.0, 1140.863096428434, 1160.4197901049474, 1185.4961641302411, 1189.2567145534042, 1191.3804821492774, 17659.38192163268, 17889.910550447163, 18706.15822287973, 18942.715859261847, 19244.903775481034, 63832.0, 65655.0, 66956.0, 67417.3258267411, 67514.64970700657, 68921.0, 90677.54661226651, 91055.0, 91528.54235728779, 91637.54181229051, 9822.5088745561, 10295.691129266133, 10356.948215258875, 10407.94796026015, 10412.0, 13199.538016169388, 13837.238951857342, 17007.088369674795, 18073.909630451762, 18295.451136465927, 14506.839452843536, 14823.962322637743, 14827.851721482648, 14849.628759281104, 14940.0, 12832.679183020498, 13253.602391927872, 13278.80081798793, 13373.659609568092, 13390.598282051555, 14920.253987300808, 15071.397144114113, 15245.3901843925, 15327.693446131238, 15353.925225234127, 10107.949460252652, 10129.442880641636, 10225.26170551023, 10228.0, 10248.846267305848, 14592.321882983593, 14649.92675036618, 14674.486392976194, 14696.0, 14798.926005369904], "value": 11801.972332213967, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1252.3675543850354, 1421.8340960412465, 1429.7569413199756, 1440.2438719672177, 1443.7762146867233, 11602.245854019482, 11938.820917686242, 12189.329586872718, 12226.633201003973, 12362.876371236285, 9085.636574537028, 9401.952990235057, 9715.077067678578, 11131.276467029638, 11240.943795281033, 2402.5915594348917, 2533.480636469519, 2535.4422027153996, 2537.4925014996984, 2537.883331334207, 2037.2869495676543, 2125.1818050050765, 2192.9342119736352, 2455.1897873701723, 2587.8317909335833, 2535.8858851351733, 2546.363409147713, 2568.2166939083572, 2572.536943350196, 2577.574700174469, 2527.2165628655084, 2537.086648806428, 2541.4027703489664, 2541.7712405883467, 2542.6058960861096, 2434.1237154624323, 2463.6797216361756, 2465.778079972811, 2466.7903228225623, 2490.601503759408, 11639.47622356996, 11724.941375293154, 11799.823002654966, 11825.645230643095, 11843.289402635783, 6396.1045453636525, 6645.700943457556, 6649.468042556584, 6652.800415987528, 6679.398854103106, 4054.3107671695884, 4253.544620517114, 4346.695731298811, 4364.738115713068, 4371.825126994916, 1243.8134279858002, 1253.8996880249558, 1259.118616968126, 1281.4297637551294, 1287.594407761552, 16889.14219435861, 16984.0, 17016.40442584516, 17016.89390189646, 17040.06279654631, 5545.670818256342, 5618.747156377973, 5621.891790578533, 5750.7987220447085, 5767.192593036978, 12457.750844983231, 12650.177738447155, 12669.366531673379, 12684.682882928, 12687.365631718561, 6370.267419246753, 6508.674566271668, 6514.446272066927, 6523.412892839668, 6529.902051469232, 6066.939330606638, 6146.200993870768, 6165.32181460039, 6178.7219575119225, 6183.567150299526, 4427.180971520214, 4507.414036175342, 4513.842015529441, 4546.704464209815, 4553.772311384484, 1151.8617765867982, 1173.9647810565696, 1257.1011726615332, 1277.2145130744525, 1284.05621867927, 804.0672819529318, 810.6473683947502, 825.41395609117, 834.7871292820292, 861.1345597674423, 5709.7716091356715, 5945.524358051345, 6961.512694111364, 7247.710091596277, 7382.1141463023705, 942.330945029037, 948.0, 957.8084383123403, 962.3936919740659, 982.0964712464407, 3961.7226794124713, 4127.318992366226, 4150.252954468165, 4185.958140418617, 4223.0, 14932.32804523799, 15166.696666066617, 15179.544613661608, 15317.770233446285, 15333.233338333039, 14860.479883204252, 15117.657017098323, 15214.0, 15228.847711522743, 15253.847461525465, 8531.317494600416, 8569.442986205877, 8601.096884827128, 8631.136886311318, 8694.17405346491, 5443.918341224806, 6867.690953907186, 7017.298270173146, 7114.50198486101, 7286.599237042014, 13550.322483875963, 13922.791158132433, 13935.790963135763, 14002.719945601235, 14088.30940287172, 13464.596062118153, 14193.503227386991, 14323.427062917366, 14533.781993269899, 14553.0, 8818.250448711675, 9039.005709371831, 9044.819103618023, 9048.185663290073, 9048.909510904807, 8576.742697718828, 10718.89281107179, 10841.620543280947, 10908.290961087503, 10982.725431863948, 7613.200613935457, 9063.773405664911, 9345.15893569582, 9421.95289023578, 9486.573104210189, 7336.816579585553, 9133.269338453041, 9192.724218273463, 9196.172344489029, 9201.171894529525, 8161.632726527439, 8178.386621003329, 8208.138145494637, 8276.627551760064, 8315.0, 5816.381700669056, 5876.206712093813, 6148.569600127948, 6299.590526615755, 6315.652639104894, 6098.237720284965, 6113.694315284307, 6369.0, 6385.233771947396, 6595.8021259362295], "value": 5403.846416398783, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.25314434815573, 347.87998140641486, 381.87016414419094, 396.3044856277233, 407.2994449546778, 5733.713314334277, 6161.876762464759, 6363.745450181993, 6432.903506447407, 6465.418112369885, 6133.969330153354, 6239.594426362283, 6263.404976527234, 6267.592606480575, 6297.748090076403, 3731.9550525852806, 4130.917381652365, 4586.757249544175, 4590.586847183753, 4613.054323863616, 3991.6607088397523, 4602.677812553123, 4622.052479241765, 4627.6529260305615, 4634.467036290819, 3787.8674246401383, 4528.913060865401, 4559.726416415026, 4582.495925448198, 4593.609543188816, 3763.642453966855, 4525.818967241306, 4547.5452454754595, 4551.590356867898, 4571.405717256735, 3928.723164971367, 3937.271604753101, 3940.1134744682504, 3941.8620348287673, 3948.9012774680577, 6079.513638908877, 6142.570020098595, 6161.9691901540655, 6162.168107305546, 6162.322144564095, 4900.852974410774, 5365.651232669864, 5403.162509810985, 5471.440639417747, 5511.145772405283, 5543.085390910495, 5602.467765562265, 6423.430627748891, 6779.457643388517, 6789.1852977643, 4447.866564003085, 4464.665150113755, 4478.0, 4480.462344518679, 4495.98840260942, 6760.492963027793, 6999.405050570656, 7145.4283657307815, 7211.783646490614, 7213.537501999017, 3820.7325487215626, 3951.8419263229725, 4107.268666336852, 4487.977560112244, 4554.157480866051, 4168.958310416918, 4464.896896656793, 4613.377194078824, 4619.815207391666, 4621.745803980813, 5397.649152805055, 5416.431274716177, 5428.321459817523, 5434.91847622286, 5437.564994800407, 6559.0, 6808.425576572339, 6817.863642727217, 6823.795286141423, 6826.965865170741, 1442.084276484419, 1501.2343704710458, 1546.7935010691679, 1585.3987472652705, 1588.793456850602, 955.5126885288414, 1005.8642083318805, 1008.3143462445461, 1046.3355769086684, 1059.8092343378112, 5865.296164460292, 6326.810195694136, 6357.141785858942, 6368.235811702625, 6387.297397286295, 6997.755078572327, 7023.33278338557, 7043.436525077981, 7052.471064670068, 7055.2239253682055, 5562.554995600342, 6329.145565348711, 6337.33457986914, 6365.809025729235, 6374.378575532702, 3475.6003059648465, 3555.1289933966345, 3644.6173151819207, 3646.997075804175, 3673.504076949631, 5768.451997060272, 6692.431143352772, 7203.711851525985, 7231.2768723127265, 7263.491555591164, 7145.783987447431, 7169.462290328246, 7201.099862517185, 7262.9273707262255, 7305.0, 6155.0, 7099.396551293198, 7193.424526038009, 7219.639018049182, 7224.963875180695, 6924.653767311514, 6981.476389270927, 7012.544184627983, 7087.858242835217, 7100.431965442649, 6636.369544893228, 6897.517173797986, 6897.89653155193, 6907.0, 6930.712864754518, 5329.709583784936, 5682.5453963681975, 5783.884322313615, 6655.3344665535, 7137.892931605927, 3845.403962385835, 4495.955040449554, 4502.369668246382, 4514.88712782183, 4527.320901864747, 6378.840528986812, 6612.8346791330605, 7046.154461464657, 7051.682674279567, 7053.5312640575385, 5697.0, 5894.882102357843, 6779.559328643622, 6805.931940680728, 6825.35159159879, 5924.733386997509, 6018.789342372996, 6799.150106236721, 6803.625800580818, 6811.2167100784045, 6081.878362432815, 6658.234303055211, 6679.866402672016, 6695.254945472528, 6728.394444500019, 5412.7564259607625, 5636.774529018958, 6782.559133656297, 6796.72813087471, 6831.214410342675, 6136.20229370179, 6592.340765923369, 6618.7352505899225, 6632.60204387719, 6633.540621063358], "value": 4853.587805979688, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [802.1537278171528, 815.5188438821094, 822.4777266435811, 855.9300873907615, 859.1108203009884, 14826.703465930676, 15461.845381546182, 17105.0, 17560.736588951175, 17584.384546540874, 17148.22832972516, 17606.20772065257, 17922.37271695491, 18010.189541470634, 18025.09874506273, 1007.4207330784791, 1009.7475631092227, 1017.694691592523, 1023.1712313026435, 1023.5291765787747, 1004.9497525123751, 1007.9647212347569, 1009.2934945538116, 1012.9341592796445, 1035.5288343803552, 685.9039734437183, 686.2519853359837, 1017.1608423051013, 1054.4200689620716, 1070.9732256693567, 1048.7535429174177, 1057.2968975631195, 1058.1851973979992, 1062.2458054781114, 1065.6856227412893, 722.2344315026089, 777.0869228656363, 782.7416952405749, 786.2844811221818, 786.4416264452245, 16868.493945181664, 16941.407050753165, 17082.743758843626, 17222.569435764082, 17224.22490987896, 16847.57881052971, 17226.82773172265, 17380.21789019497, 17677.73483397737, 17725.645487090187, 14634.268286585735, 14875.107493550422, 14928.402863885432, 14934.850651493454, 14978.251087445693, 3491.9825400873087, 3541.8937431876825, 3553.6979356754705, 3565.8038807865555, 3567.019069755812, 135896.0, 140181.0, 141473.0, 141611.0, 142462.0, 13307.0, 13682.794758078637, 13727.176369417866, 13731.313434328244, 13755.0, 26538.46923061528, 26555.336116596853, 26849.060282890005, 27051.917923283243, 27081.0, 7694.730684426018, 7880.763577092696, 7882.802929926683, 7965.442419030613, 7979.5611241382285, 3983.0, 4061.3704875744306, 4063.0, 4063.0858056936954, 4074.7351422157467, 3693.501377314028, 4362.672799540047, 4447.599716025573, 4467.262901621196, 4641.0, 948.5162567090833, 959.5346257065318, 966.7728083900278, 976.8046390721884, 978.6281213138961, 634.9619022858643, 641.2209165863499, 655.1417642887737, 666.560070353574, 680.9012693159464, 11145.888541114648, 11493.290680698168, 11738.823917641243, 11946.0, 12799.360031998549, 586.9434053336552, 628.6982248520736, 652.1782553981922, 658.6114192626278, 661.1933441201726, 5387.9461205388225, 5521.337439507285, 5537.252470916455, 5698.943010569924, 5715.628484148517, 121446.0, 121854.0, 124857.75142248643, 125272.0, 126458.0, 99403.50298248463, 101292.0, 102988.0, 103733.96266037395, 103788.48105759574, 72843.0, 85184.0, 97664.0, 98396.0, 101098.99999999853, 11606.88393116058, 12560.246385216917, 12729.681757956125, 12882.871171288167, 12890.834375558066, 42592.78703606586, 42625.0, 42648.78675606602, 43556.782216088715, 43820.0, 48971.0, 51940.06569375903, 57379.0, 57799.79801817689, 57994.7100264496, 16291.511254661906, 16392.2623481946, 16679.0, 16951.576210594834, 16989.235484403478, 18286.994215318286, 18689.065546722337, 18712.719309210646, 18811.965341906325, 18925.716114258023, 13712.65718357009, 15764.60588485297, 17198.22607982619, 17325.480235592953, 17471.126443677513, 16477.34090636361, 16786.24461899242, 17174.45429911314, 17232.0, 18071.81928180754, 14716.632084197981, 17371.131443428032, 17728.55678608045, 17781.644367112844, 17856.910715446338, 10495.685129446128, 11702.648920532398, 11985.22096063753, 12389.75220495603, 12428.316442595744, 12641.557545485864, 13379.063465557318, 13446.394912228778, 13614.406673826868, 13734.793978090136], "value": 8600.369646732033, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1557.4999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 117.0, 115.0, 114.0, 134.0, 170.0, 113.0, 94.0, 171.0, 165.0], "value": 129.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 112.0, 108.0, 158.0, 113.0, 111.0, 113.0, 103.0, 149.0, 146.0], "value": 125.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 198.0, 234.0, 234.0, 209.0, 223.0, 200.0, 199.0, 253.0, 228.0], "value": 217.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 58.0, 59.0, 84.0, 60.0, 77.0, 87.0, 76.0, 77.0, 58.0], "value": 71.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 68.0, 88.0, 72.0, 69.0, 69.0, 88.0, 69.0, 69.0, 90.0], "value": 76.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 91.0, 89.0, 89.0, 89.0, 89.0, 95.0, 91.0, 94.0, 115.0], "value": 93.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 124.0, 98.0, 98.0, 98.0, 97.0, 96.0, 97.0, 122.0], "value": 102.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 93.0, 92.0, 91.0, 91.0, 93.0, 91.0, 92.0, 91.0, 90.0], "value": 91.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 50.0, 48.0, 47.0, 51.0, 50.0, 51.0, 49.0, 51.0, 50.0], "value": 49.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 65.0, 58.0, 59.0, 64.0, 62.0, 57.0, 77.0, 88.0, 82.0], "value": 67.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 97.0, 100.0, 115.0, 115.0, 86.0, 91.0, 89.0, 126.0, 92.0], "value": 99.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 165.0, 123.0, 136.0, 164.0, 155.0, 122.0, 142.0, 149.0, 137.0], "value": 147.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [192.0, 184.0, 191.0, 227.0, 236.0, 226.0, 164.0, 220.0, 245.0, 223.0], "value": 210.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 63.0, 65.0, 70.0, 65.0, 65.0, 94.0, 99.0, 98.0], "value": 75.3, "unit": "score"}]}]} 19:18:00 INFO - Return code: 0 19:18:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:18:00 INFO - # TBPL SUCCESS # 19:18:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:18:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:18:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:18:00 INFO - 2017-06-02 19:18:00.457 screenresolution[1678:12398] starting screenresolution argv=screenresolution get 19:18:00 INFO - 2017-06-02 19:18:00.460 screenresolution[1678:12398] Display 0: 1600x1200x32@60 19:18:00 INFO - 2017-06-02 19:18:00.465 screenresolution[1679:12401] starting screenresolution argv=screenresolution list 19:18:00 INFO - Available Modes on Display 0 19:18:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:18:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:18:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:18:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:18:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:18:00 INFO - Intel Iris: 19:18:00 INFO - Chipset Model: Intel Iris 19:18:00 INFO - Type: GPU 19:18:00 INFO - Bus: Built-In 19:18:00 INFO - VRAM (Dynamic, Max): 1536 MB 19:18:00 INFO - Vendor: Intel (0x8086) 19:18:00 INFO - Device ID: 0x0a2e 19:18:00 INFO - Revision ID: 0x0009 19:18:00 INFO - Displays: 19:18:00 INFO - Display: 19:18:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:18:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:18:00 INFO - Display Serial Number: 1600x1200 60 19:18:00 INFO - Main Display: Yes 19:18:00 INFO - Mirror: Off 19:18:00 INFO - Online: Yes 19:18:00 INFO - Rotation: Supported 19:18:00 INFO - Return code: 0 19:18:00 INFO - Running post-action listener: _resource_record_post_action 19:18:00 INFO - [mozharness: 2017-06-03 02:18:00.711399Z] Finished run-tests step (success) 19:18:00 INFO - Running post-run listener: _resource_record_post_run 19:18:00 INFO - Total resource usage - Wall time: 1257s; CPU: 53.0%; Read bytes: 33365504; Write bytes: 571944448; Read time: 233; Write time: 1760 19:18:00 INFO - TinderboxPrint: CPU usage
53.1% 19:18:00 INFO - TinderboxPrint: I/O read bytes / time
33,365,504 / 233 19:18:00 INFO - TinderboxPrint: I/O write bytes / time
571,944,448 / 1,760 19:18:00 INFO - TinderboxPrint: CPU idle
2,356.6 (46.9%) 19:18:00 INFO - TinderboxPrint: CPU system
1,018.1 (20.2%) 19:18:00 INFO - TinderboxPrint: CPU user
1,655.1 (32.9%) 19:18:00 INFO - TinderboxPrint: Swap in / out
290,058,240 / 0 19:18:00 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 229200384; Write bytes: 230553600; Read time: 17051; Write time: 495 19:18:00 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 19:18:00 INFO - run-tests - Wall time: 1236s; CPU: 53.0%; Read bytes: 32435712; Write bytes: 322881024; Read time: 221; Write time: 1219 19:18:01 INFO - Running post-run listener: _upload_blobber_files 19:18:01 INFO - Blob upload gear active. 19:18:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:18:01 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 19:18:01 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'] 19:18:01 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 19:18:01 INFO - (blobuploader) - INFO - Open directory for files ... 19:18:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:18:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:18:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:18:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:18:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:18:02 INFO - (blobuploader) - INFO - Done attempting. 19:18:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:18:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:18:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:18:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:18:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:18:03 INFO - (blobuploader) - INFO - Done attempting. 19:18:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:18:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:18:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:18:04 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:18:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:18:04 INFO - (blobuploader) - INFO - Done attempting. 19:18:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:18:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:18:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:18:06 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:18:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:18:06 INFO - (blobuploader) - INFO - Done attempting. 19:18:06 INFO - (blobuploader) - INFO - Iteration through files over. 19:18:06 INFO - Return code: 0 19:18:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:18:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:18:06 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bcbf7b78caf12a4e159c80704ec122137ef0d31a0fd44b38396b8120382630eef0a1f32565aba0d72369405818dbcdc5d1b6c03b4ba41af4c486917d501da7d1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/014534fa2387b36bd70a48d552466f3c8548cc55823f7929f6336de64a1d0f5f8b7b93219e5fef9cebbfc0c344a3cc435162583e4b5b3e116528de88e9c9f6e2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/35370adb1e2d54723d0812c725381ad57fc77706414825765fd54c48e3941d5ad80d11f3ce4b5ae18a9d270fe210171d4af44840ffc24a42ee4186c0aead86d2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b4527f611a97a287bc995c99dec49fe31b54636f5ae3180d5d4b459d14114263920a1cd098a00e17332daaf9275eafe0a5d416af2f4a263a81d4baefe794237"} 19:18:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:18:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:18:06 INFO - Contents: 19:18:06 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bcbf7b78caf12a4e159c80704ec122137ef0d31a0fd44b38396b8120382630eef0a1f32565aba0d72369405818dbcdc5d1b6c03b4ba41af4c486917d501da7d1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/014534fa2387b36bd70a48d552466f3c8548cc55823f7929f6336de64a1d0f5f8b7b93219e5fef9cebbfc0c344a3cc435162583e4b5b3e116528de88e9c9f6e2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/35370adb1e2d54723d0812c725381ad57fc77706414825765fd54c48e3941d5ad80d11f3ce4b5ae18a9d270fe210171d4af44840ffc24a42ee4186c0aead86d2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b4527f611a97a287bc995c99dec49fe31b54636f5ae3180d5d4b459d14114263920a1cd098a00e17332daaf9275eafe0a5d416af2f4a263a81d4baefe794237"} 19:18:06 INFO - Running post-run listener: copy_logs_to_upload_dir 19:18:06 INFO - Copying logs to upload dir... 19:18:06 INFO - mkdir: /builds/slave/test/build/upload/logs 19:18:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1339.358492 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-06-02 19:18:07.079155) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-02 19:18:07.082630) ========= 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.RcvYUxpHPY/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.mqx6ZICjv1/Listeners TMPDIR=/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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/bcbf7b78caf12a4e159c80704ec122137ef0d31a0fd44b38396b8120382630eef0a1f32565aba0d72369405818dbcdc5d1b6c03b4ba41af4c486917d501da7d1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/014534fa2387b36bd70a48d552466f3c8548cc55823f7929f6336de64a1d0f5f8b7b93219e5fef9cebbfc0c344a3cc435162583e4b5b3e116528de88e9c9f6e2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/35370adb1e2d54723d0812c725381ad57fc77706414825765fd54c48e3941d5ad80d11f3ce4b5ae18a9d270fe210171d4af44840ffc24a42ee4186c0aead86d2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b4527f611a97a287bc995c99dec49fe31b54636f5ae3180d5d4b459d14114263920a1cd098a00e17332daaf9275eafe0a5d416af2f4a263a81d4baefe794237"} build_url:https://queue.taskcluster.net/v1/task/Wh2i0bLsSKKEwXrRcwVYZw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011011 build_url: 'https://queue.taskcluster.net/v1/task/Wh2i0bLsSKKEwXrRcwVYZw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bcbf7b78caf12a4e159c80704ec122137ef0d31a0fd44b38396b8120382630eef0a1f32565aba0d72369405818dbcdc5d1b6c03b4ba41af4c486917d501da7d1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/014534fa2387b36bd70a48d552466f3c8548cc55823f7929f6336de64a1d0f5f8b7b93219e5fef9cebbfc0c344a3cc435162583e4b5b3e116528de88e9c9f6e2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/35370adb1e2d54723d0812c725381ad57fc77706414825765fd54c48e3941d5ad80d11f3ce4b5ae18a9d270fe210171d4af44840ffc24a42ee4186c0aead86d2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b4527f611a97a287bc995c99dec49fe31b54636f5ae3180d5d4b459d14114263920a1cd098a00e17332daaf9275eafe0a5d416af2f4a263a81d4baefe794237"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-02 19:18:07.116667) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 19:18:07.117018) ========= 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.RcvYUxpHPY/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.mqx6ZICjv1/Listeners TMPDIR=/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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.005330 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 19:18:07.166227) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-02 19:18:07.166582) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-02 19:18:07.166952) ========= ========= Total master_lag: 0.18 =========