builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0318 starttime: 1497173693.16 results: success (0) revision: a040e8315026ea31bd92aa26333d69f689b1ccd7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-11 02:34:53.160414) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-11 02:34:53.160857) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-11 02:34:53.161135) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-11 02:34:53.185818) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-11 02:34:53.186132) ========= 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.lcJIUdkDHT/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.4QtbeIopIn/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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-11 02:34:53-- 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% 20.8M=0.001s 2017-06-11 02:34:53 (20.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.478585 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-11 02:34:53.685049) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-11 02:34:53.685395) ========= 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.lcJIUdkDHT/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.4QtbeIopIn/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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.025872 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-11 02:34:53.733633) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-11 02:34:53.733932) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a040e8315026ea31bd92aa26333d69f689b1ccd7 --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 a040e8315026ea31bd92aa26333d69f689b1ccd7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.lcJIUdkDHT/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.4QtbeIopIn/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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-11 02:34:53,830 truncating revision to first 12 chars 2017-06-11 02:34:53,830 Setting DEBUG logging. 2017-06-11 02:34:53,830 attempt 1/10 2017-06-11 02:34:53,830 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a040e8315026?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-11 02:34:55,819 unpacking tar archive at: mozilla-central-a040e8315026/testing/mozharness/ program finished with exit code 0 elapsedTime=2.264963 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-11 02:34:56.020070) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-11 02:34:56.020397) ========= script_repo_revision: a040e8315026ea31bd92aa26333d69f689b1ccd7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-11 02:34:56.020772) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-11 02:34:56.021044) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-11 02:34:56.039650) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-06-11 02:34:56.039992) ========= /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.lcJIUdkDHT/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.4QtbeIopIn/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 02:34:56 INFO - MultiFileLogger online at 20170611 02:34:56 in /builds/slave/test 02:34:56 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 02:34:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:34:56 INFO - {'append_to_log': False, 02:34:56 INFO - 'base_work_dir': '/builds/slave/test', 02:34:56 INFO - 'blob_upload_branch': 'Firefox', 02:34:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:34:56 INFO - 'branch': 'Firefox', 02:34:56 INFO - 'buildbot_json_path': 'buildprops.json', 02:34:56 INFO - 'config_files': ('talos/mac_config.py',), 02:34:56 INFO - 'default_actions': ('clobber', 02:34:56 INFO - 'read-buildbot-config', 02:34:56 INFO - 'download-and-extract', 02:34:56 INFO - 'populate-webroot', 02:34:56 INFO - 'create-virtualenv', 02:34:56 INFO - 'install', 02:34:56 INFO - 'setup-mitmproxy', 02:34:56 INFO - 'run-tests'), 02:34:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:34:56 INFO - 'download_minidump_stackwalk': True, 02:34:56 INFO - 'download_symbols': 'ondemand', 02:34:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:34:56 INFO - 'tooltool.py': '/tools/tooltool.py', 02:34:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:34:56 INFO - '/tools/misc-python/virtualenv.py')}, 02:34:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:34:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:34:56 INFO - 'gecko_profile': False, 02:34:56 INFO - 'gecko_profile_interval': 0, 02:34:56 INFO - 'installer_path': 'installer.exe', 02:34:56 INFO - 'log_level': 'info', 02:34:56 INFO - 'log_name': 'talos', 02:34:56 INFO - 'log_to_console': True, 02:34:56 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:34:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:34:56 INFO - 'opt_config_files': (), 02:34:56 INFO - 'pip_index': False, 02:34:56 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:34:56 INFO - 'cmd': ('bash', 02:34:56 INFO - '-c', 02:34:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:34:56 INFO - 'enabled': True, 02:34:56 INFO - 'halt_on_failure': False, 02:34:56 INFO - 'name': 'check_screen_resolution'},), 02:34:56 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:34:56 INFO - 'cmd': ('bash', 02:34:56 INFO - '-c', 02:34:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:34:56 INFO - 'enabled': True, 02:34:56 INFO - 'halt_on_failure': False, 02:34:56 INFO - 'name': 'check_screen_resolution'},), 02:34:56 INFO - 'run_cmd_checks_enabled': True, 02:34:56 INFO - 'suite': 'dromaeojs-e10s', 02:34:56 INFO - 'system_bits': '32', 02:34:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:34:56 INFO - 'title': 't-yosemite-r7-0318', 02:34:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:34:56 INFO - 'use_talos_json': True, 02:34:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:34:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:34:56 INFO - 'work_dir': 'build'} 02:34:56 INFO - [mozharness: 2017-06-11 09:34:56.258446Z] Running clobber step. 02:34:56 INFO - Running pre-action listener: _resource_record_pre_action 02:34:56 INFO - Running main action method: clobber 02:34:56 INFO - rmtree: /builds/slave/test/build 02:34:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:34:58 INFO - Running post-action listener: _resource_record_post_action 02:34:58 INFO - [mozharness: 2017-06-11 09:34:58.667474Z] Finished clobber step (success) 02:34:58 INFO - [mozharness: 2017-06-11 09:34:58.667654Z] Running read-buildbot-config step. 02:34:58 INFO - Running pre-action listener: _resource_record_pre_action 02:34:58 INFO - Running main action method: read_buildbot_config 02:34:58 INFO - Using buildbot properties: 02:34:58 INFO - { 02:34:58 INFO - "project": "", 02:34:58 INFO - "product": "firefox", 02:34:58 INFO - "who": "archaeopteryx@coole-files.de", 02:34:58 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 02:34:58 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 02:34:58 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 02:34:58 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 02:34:58 INFO - "stage_platform": "macosx64", 02:34:58 INFO - "basedir": "/builds/slave/test", 02:34:58 INFO - "buildnumber": 22, 02:34:58 INFO - "platform": "yosemite_r7", 02:34:58 INFO - "parent_task_id": "VDZSI2FlSu2sxA6eEWR86A", 02:34:58 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 02:34:58 INFO - "slavebuilddir": "test", 02:34:58 INFO - "taskId": "K6Vvlk70TlqIByrS3tdo2A", 02:34:58 INFO - "branch": "mozilla-central", 02:34:58 INFO - "script_repo_revision": "production", 02:34:58 INFO - "revision": "a040e8315026ea31bd92aa26333d69f689b1ccd7", 02:34:58 INFO - "slavename": "t-yosemite-r7-0318", 02:34:58 INFO - "repo_path": "mozilla-central" 02:34:58 INFO - } 02:34:58 INFO - Finding installer, test and symbols from parent task. 02:34:58 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/K6Vvlk70TlqIByrS3tdo2A'}, attempt #1 02:34:59 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/K6Vvlk70TlqIByrS3tdo2A'}, attempt #1 02:35:00 INFO - Task dependencies: Z68l34naT-mSIGqP0w5Fkg VDZSI2FlSu2sxA6eEWR86A 02:35:00 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VDZSI2FlSu2sxA6eEWR86A'}, attempt #1 02:35:00 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/VDZSI2FlSu2sxA6eEWR86A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 02:35:00 INFO - Set test_url: https://queue.taskcluster.net/v1/task/VDZSI2FlSu2sxA6eEWR86A/artifacts/public/build/test_packages.json 02:35:00 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/VDZSI2FlSu2sxA6eEWR86A/artifacts/public/build/target.crashreporter-symbols.zip 02:35:00 INFO - Running post-action listener: _resource_record_post_action 02:35:00 INFO - [mozharness: 2017-06-11 09:35:00.519680Z] Finished read-buildbot-config step (success) 02:35:00 INFO - [mozharness: 2017-06-11 09:35:00.519797Z] Running download-and-extract step. 02:35:00 INFO - Running pre-action listener: _resource_record_pre_action 02:35:00 INFO - Running main action method: download_and_extract 02:35:00 INFO - mkdir: /builds/slave/test/build/tests 02:35: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')]} 02:35:00 INFO - trying https://queue.taskcluster.net/v1/task/VDZSI2FlSu2sxA6eEWR86A/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 02:35:00 INFO - Downloading https://queue.taskcluster.net/v1/task/VDZSI2FlSu2sxA6eEWR86A/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 02:35:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VDZSI2FlSu2sxA6eEWR86A/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 02:35:01 INFO - Downloaded 1328 bytes. 02:35:01 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 02:35:01 INFO - Using the following test package requirements: 02:35:01 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:35:01 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 02:35:01 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 02:35:01 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:35:01 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 02:35:01 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:35:01 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 02:35:01 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:35:01 INFO - u'jsshell-mac.zip'], 02:35:01 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:35:01 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 02:35:01 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 02:35:01 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:35:01 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 02:35:01 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:35:01 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 02:35:01 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:35:01 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.tar.gz'], 02:35:01 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:35:01 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 02:35:01 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 02:35:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VDZSI2FlSu2sxA6eEWR86A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 02:35:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VDZSI2FlSu2sxA6eEWR86A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 02:35:01 INFO - Fetch https://queue.taskcluster.net/v1/task/VDZSI2FlSu2sxA6eEWR86A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 02:35:03 INFO - Content-Length response header: 59541500 02:35:03 INFO - Bytes received: 59541500 02:35:21 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 02:35:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VDZSI2FlSu2sxA6eEWR86A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 02:35:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VDZSI2FlSu2sxA6eEWR86A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 02:35:21 INFO - Fetch https://queue.taskcluster.net/v1/task/VDZSI2FlSu2sxA6eEWR86A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 02:35:23 INFO - Content-Length response header: 59541500 02:35:23 INFO - Bytes received: 59541500 02:35:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VDZSI2FlSu2sxA6eEWR86A/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 02:35:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VDZSI2FlSu2sxA6eEWR86A/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 02:35:40 INFO - Fetch https://queue.taskcluster.net/v1/task/VDZSI2FlSu2sxA6eEWR86A/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 02:35:41 INFO - Content-Length response header: 14450010 02:35:41 INFO - Bytes received: 14450010 02:35:41 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')]} 02:35:41 INFO - trying https://queue.taskcluster.net/v1/task/VDZSI2FlSu2sxA6eEWR86A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 02:35:41 INFO - Downloading https://queue.taskcluster.net/v1/task/VDZSI2FlSu2sxA6eEWR86A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 02:35:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VDZSI2FlSu2sxA6eEWR86A/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 02:35:43 INFO - Downloaded 69238284 bytes. 02:35:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VDZSI2FlSu2sxA6eEWR86A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 02:35:43 INFO - mkdir: /builds/slave/test/properties 02:35:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:35:43 INFO - Writing to file /builds/slave/test/properties/build_url 02:35:43 INFO - Contents: 02:35:43 INFO - build_url:https://queue.taskcluster.net/v1/task/VDZSI2FlSu2sxA6eEWR86A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 02:35:43 INFO - Running post-action listener: _resource_record_post_action 02:35:43 INFO - Running post-action listener: set_extra_try_arguments 02:35:43 INFO - [mozharness: 2017-06-11 09:35:43.336711Z] Finished download-and-extract step (success) 02:35:43 INFO - [mozharness: 2017-06-11 09:35:43.336853Z] Running populate-webroot step. 02:35:43 INFO - Running pre-action listener: _resource_record_pre_action 02:35:43 INFO - Running main action method: populate_webroot 02:35:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:35:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:35:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:35:43 INFO - '--tppagecycles', 02:35:43 INFO - '7'], 02:35:43 INFO - 'tests': ['tsvgm']}}, 02:35:43 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:35:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:35:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:35:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:35:43 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 02:35:43 INFO - 'talos_options': ['--disable-e10s'], 02:35:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:35:43 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:35:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:35:43 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 02:35:43 INFO - 'talos_options': ['--disable-e10s'], 02:35:43 INFO - 'tests': ['damp', 'tps']}, 02:35:43 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:35:43 INFO - 'tests': ['damp', 'tps']}, 02:35:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:35:43 INFO - 'tests': ['dromaeo_dom']}, 02:35:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:35:43 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:35:43 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 02:35:43 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 02:35:43 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 02:35:43 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 02:35:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:35:43 INFO - 'tests': ['a11yr', 02:35:43 INFO - 'ts_paint', 02:35:43 INFO - 'tpaint', 02:35:43 INFO - 'sessionrestore', 02:35:43 INFO - 'sessionrestore_no_auto_restore', 02:35:43 INFO - 'tabpaint']}, 02:35:43 INFO - 'other-e10s': {'tests': ['a11yr', 02:35:43 INFO - 'ts_paint', 02:35:43 INFO - 'tpaint', 02:35:43 INFO - 'sessionrestore', 02:35:43 INFO - 'sessionrestore_no_auto_restore', 02:35:43 INFO - 'tabpaint']}, 02:35:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:35:43 INFO - 'ts_paint', 02:35:43 INFO - 'tpaint', 02:35:43 INFO - 'sessionrestore', 02:35:43 INFO - 'sessionrestore_no_auto_restore', 02:35:43 INFO - 'tabpaint']}, 02:35:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:35:43 INFO - 'ts_paint', 02:35:43 INFO - 'tpaint', 02:35:43 INFO - 'sessionrestore', 02:35:43 INFO - 'sessionrestore_no_auto_restore', 02:35:43 INFO - 'tabpaint']}, 02:35:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:35:43 INFO - 'tests': ['a11yr', 02:35:43 INFO - 'ts_paint', 02:35:43 INFO - 'tpaint', 02:35:43 INFO - 'sessionrestore', 02:35:43 INFO - 'sessionrestore_no_auto_restore', 02:35:43 INFO - 'tabpaint']}, 02:35:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:35:43 INFO - 'tests': ['a11yr', 02:35:43 INFO - 'ts_paint', 02:35:43 INFO - 'tpaint', 02:35:43 INFO - 'sessionrestore', 02:35:43 INFO - 'sessionrestore_no_auto_restore', 02:35:43 INFO - 'tabpaint']}, 02:35:43 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 02:35:43 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 02:35:43 INFO - 'bloom_basic_ref']}, 02:35:43 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:35:43 INFO - 'talos_options': ['--mitmproxy', 02:35:43 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:35:43 INFO - '--firstNonBlankPaint'], 02:35:43 INFO - 'tests': ['quantum_pageload_google', 02:35:43 INFO - 'quantum_pageload_youtube', 02:35:43 INFO - 'quantum_pageload_amazon', 02:35:43 INFO - 'quantum_pageload_facebook']}, 02:35:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:35:43 INFO - 'tests': ['tsvgx', 02:35:43 INFO - 'tsvgr_opacity', 02:35:43 INFO - 'tart', 02:35:43 INFO - 'tscrollx', 02:35:43 INFO - 'cart', 02:35:43 INFO - 'tsvg_static']}, 02:35:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:35:43 INFO - 'tsvgr_opacity', 02:35:43 INFO - 'tart', 02:35:43 INFO - 'tscrollx', 02:35:43 INFO - 'cart', 02:35:43 INFO - 'tsvg_static']}, 02:35:43 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 02:35:43 INFO - 'talos_options': ['--disable-e10s'], 02:35:43 INFO - 'tests': ['tp5o']}, 02:35:43 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:35:43 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 02:35:43 INFO - 'talos_options': ['--disable-e10s', 02:35:43 INFO - '--xperf_path', 02:35:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:35:43 INFO - 'tests': ['tp5n']}, 02:35:43 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:35:43 INFO - 'talos_options': ['--xperf_path', 02:35:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:35:43 INFO - 'tests': ['tp5n']}}, 02:35:43 INFO - 'talos.zip': {'path': '', 02:35:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:35:43 INFO - Running post-action listener: _resource_record_post_action 02:35:43 INFO - [mozharness: 2017-06-11 09:35:43.358989Z] Finished populate-webroot step (success) 02:35:43 INFO - [mozharness: 2017-06-11 09:35:43.359121Z] Running create-virtualenv step. 02:35:43 INFO - Running pre-action listener: _resource_record_pre_action 02:35:43 INFO - Running main action method: create_virtualenv 02:35:43 INFO - Creating virtualenv /builds/slave/test/build/venv 02:35:43 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 02:35:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:35:43 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 02:35:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:35:43 INFO - Using real prefix '/tools/python27' 02:35:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:35:44 INFO - Installing distribute.............................................................................................................................................................................................done. 02:35:47 INFO - Installing pip.................done. 02:35:47 INFO - Return code: 0 02:35:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:35:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:35:47 INFO - Reading from file tmpfile_stdout 02:35:47 INFO - Output received: 02:35:47 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) 02:35:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:35:47 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')]} 02:35:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:35:47 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')]} 02:35:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:35:47 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 0x10a928c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a92c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90d2562690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a67ed30>, '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 0x10a648cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a66fbe8>, '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.lcJIUdkDHT/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.4QtbeIopIn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 02:35:47 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 02:35:47 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 02:35:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lcJIUdkDHT/Render', 02:35:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:35:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:35:47 INFO - 'HOME': '/Users/cltbld', 02:35:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:35:47 INFO - 'LOGNAME': 'cltbld', 02:35:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:35:47 INFO - 'MOZ_NO_REMOTE': '1', 02:35:47 INFO - 'NO_EM_RESTART': '1', 02:35:47 INFO - 'PAGER': '/bin/cat', 02:35:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:35:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:35:47 INFO - 'PWD': '/builds/slave/test', 02:35:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:35:47 INFO - 'SHELL': '/bin/bash', 02:35:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4QtbeIopIn/Listeners', 02:35:47 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 02:35:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:35:47 INFO - 'USER': 'cltbld', 02:35:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:35:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:35:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:35:47 INFO - 'XPC_FLAGS': '0x0', 02:35:47 INFO - 'XPC_SERVICE_NAME': '0', 02:35:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:35:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:35:47 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 02:35:47 INFO - Cleaning up... 02:35:47 INFO - Return code: 0 02:35:47 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:35:47 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')]} 02:35:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:35:47 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')]} 02:35:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:35:47 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 0x10a928c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a92c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90d2562690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a67ed30>, '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 0x10a648cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a66fbe8>, '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.lcJIUdkDHT/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.4QtbeIopIn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 02:35:47 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 02:35:47 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 02:35:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lcJIUdkDHT/Render', 02:35:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:35:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:35:47 INFO - 'HOME': '/Users/cltbld', 02:35:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:35:47 INFO - 'LOGNAME': 'cltbld', 02:35:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:35:47 INFO - 'MOZ_NO_REMOTE': '1', 02:35:47 INFO - 'NO_EM_RESTART': '1', 02:35:47 INFO - 'PAGER': '/bin/cat', 02:35:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:35:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:35:47 INFO - 'PWD': '/builds/slave/test', 02:35:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:35:47 INFO - 'SHELL': '/bin/bash', 02:35:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4QtbeIopIn/Listeners', 02:35:47 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 02:35:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:35:47 INFO - 'USER': 'cltbld', 02:35:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:35:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:35:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:35:47 INFO - 'XPC_FLAGS': '0x0', 02:35:47 INFO - 'XPC_SERVICE_NAME': '0', 02:35:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:35:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:35:47 INFO - Downloading/unpacking psutil>=3.1.1 02:35: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 02:35: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 02:35: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 02:35: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 02:35:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:35:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:35:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:35:50 INFO - Installing collected packages: psutil 02:35:50 INFO - Running setup.py install for psutil 02:35:50 INFO - building 'psutil._psutil_osx' extension 02:35:50 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 02:35:51 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 02:35:51 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 02:35:51 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 02:35:51 INFO - building 'psutil._psutil_posix' extension 02:35:51 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 02:35:51 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:35:51 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:35:51 INFO - ^ 02:35:51 INFO - 1 warning generated. 02:35:51 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 02:35:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:35:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:35:51 INFO - Successfully installed psutil 02:35:51 INFO - Cleaning up... 02:35:51 INFO - Return code: 0 02:35:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:35: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')]} 02:35:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:35: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')]} 02:35:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:35:51 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 0x10a928c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a92c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90d2562690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a67ed30>, '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 0x10a648cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a66fbe8>, '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.lcJIUdkDHT/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.4QtbeIopIn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 02:35:51 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 02:35:51 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 02:35:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lcJIUdkDHT/Render', 02:35:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:35:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:35:51 INFO - 'HOME': '/Users/cltbld', 02:35:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:35:51 INFO - 'LOGNAME': 'cltbld', 02:35:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:35:51 INFO - 'MOZ_NO_REMOTE': '1', 02:35:51 INFO - 'NO_EM_RESTART': '1', 02:35:51 INFO - 'PAGER': '/bin/cat', 02:35:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:35:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:35:51 INFO - 'PWD': '/builds/slave/test', 02:35:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:35:51 INFO - 'SHELL': '/bin/bash', 02:35:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4QtbeIopIn/Listeners', 02:35:51 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 02:35:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:35:51 INFO - 'USER': 'cltbld', 02:35:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:35:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:35:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:35:51 INFO - 'XPC_FLAGS': '0x0', 02:35:51 INFO - 'XPC_SERVICE_NAME': '0', 02:35:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:35:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:35:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:35:51 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 02:35:51 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 02:35:51 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 02:35:51 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 02:35:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:35:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:35:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:35:53 INFO - Installing collected packages: mozsystemmonitor 02:35:53 INFO - Running setup.py install for mozsystemmonitor 02:35:53 INFO - Successfully installed mozsystemmonitor 02:35:53 INFO - Cleaning up... 02:35:53 INFO - Return code: 0 02:35:53 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:35:53 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')]} 02:35:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:35:53 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')]} 02:35:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:35:53 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 0x10a928c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a92c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90d2562690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a67ed30>, '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 0x10a648cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a66fbe8>, '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.lcJIUdkDHT/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.4QtbeIopIn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 02:35:53 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 02:35:53 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 02:35:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lcJIUdkDHT/Render', 02:35:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:35:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:35:53 INFO - 'HOME': '/Users/cltbld', 02:35:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:35:53 INFO - 'LOGNAME': 'cltbld', 02:35:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:35:53 INFO - 'MOZ_NO_REMOTE': '1', 02:35:53 INFO - 'NO_EM_RESTART': '1', 02:35:53 INFO - 'PAGER': '/bin/cat', 02:35:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:35:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:35:53 INFO - 'PWD': '/builds/slave/test', 02:35:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:35:53 INFO - 'SHELL': '/bin/bash', 02:35:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4QtbeIopIn/Listeners', 02:35:53 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 02:35:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:35:53 INFO - 'USER': 'cltbld', 02:35:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:35:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:35:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:35:53 INFO - 'XPC_FLAGS': '0x0', 02:35:53 INFO - 'XPC_SERVICE_NAME': '0', 02:35:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:35:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:35:53 INFO - Downloading/unpacking jsonschema==2.5.1 02:35:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:35:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:35:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:35:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:35:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:35:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:35:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:35:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:35:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:35:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:35:55 INFO - Downloading functools32-3.2.3-2.tar.gz 02:35:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:35:55 INFO - warning: no files found matching '*.txt' 02:35:55 INFO - no previously-included directories found matching 'build' 02:35:55 INFO - no previously-included directories found matching 'dist' 02:35:55 INFO - no previously-included directories found matching '.git*' 02:35:55 INFO - Installing collected packages: jsonschema, functools32 02:35:55 INFO - Running setup.py install for functools32 02:35:55 INFO - warning: no files found matching '*.txt' 02:35:55 INFO - no previously-included directories found matching 'build' 02:35:55 INFO - no previously-included directories found matching 'dist' 02:35:55 INFO - no previously-included directories found matching '.git*' 02:35:55 INFO - Successfully installed jsonschema functools32 02:35:55 INFO - Cleaning up... 02:35:55 INFO - Return code: 0 02:35:55 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:35:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:35:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:35:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:35:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:35:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a928c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a92c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90d2562690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a67ed30>, '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 0x10a648cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a66fbe8>, '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.lcJIUdkDHT/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.4QtbeIopIn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 02:35:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 02:35:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 02:35:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lcJIUdkDHT/Render', 02:35:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:35:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:35:55 INFO - 'HOME': '/Users/cltbld', 02:35:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:35:55 INFO - 'LOGNAME': 'cltbld', 02:35:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:35:55 INFO - 'MOZ_NO_REMOTE': '1', 02:35:55 INFO - 'NO_EM_RESTART': '1', 02:35:55 INFO - 'PAGER': '/bin/cat', 02:35:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:35:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:35:55 INFO - 'PWD': '/builds/slave/test', 02:35:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:35:55 INFO - 'SHELL': '/bin/bash', 02:35:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4QtbeIopIn/Listeners', 02:35:55 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 02:35:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:35:55 INFO - 'USER': 'cltbld', 02:35:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:35:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:35:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:35:55 INFO - 'XPC_FLAGS': '0x0', 02:35:55 INFO - 'XPC_SERVICE_NAME': '0', 02:35:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:35:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:35:56 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:35:56 INFO - Cleaning up... 02:35:56 INFO - Return code: 0 02:35:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:35: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')]} 02:35:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:35: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')]} 02:35:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:35:56 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 0x10a928c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a92c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90d2562690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a67ed30>, '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 0x10a648cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a66fbe8>, '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.lcJIUdkDHT/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.4QtbeIopIn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 02:35:56 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 02:35:56 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 02:35:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lcJIUdkDHT/Render', 02:35:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:35:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:35:56 INFO - 'HOME': '/Users/cltbld', 02:35:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:35:56 INFO - 'LOGNAME': 'cltbld', 02:35:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:35:56 INFO - 'MOZ_NO_REMOTE': '1', 02:35:56 INFO - 'NO_EM_RESTART': '1', 02:35:56 INFO - 'PAGER': '/bin/cat', 02:35:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:35:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:35:56 INFO - 'PWD': '/builds/slave/test', 02:35:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:35:56 INFO - 'SHELL': '/bin/bash', 02:35:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4QtbeIopIn/Listeners', 02:35:56 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 02:35:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:35:56 INFO - 'USER': 'cltbld', 02:35:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:35:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:35:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:35:56 INFO - 'XPC_FLAGS': '0x0', 02:35:56 INFO - 'XPC_SERVICE_NAME': '0', 02:35:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:35:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:35:56 INFO - Downloading/unpacking blobuploader==1.2.4 02:35:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:35:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:35:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:35:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:35:57 INFO - Downloading blobuploader-1.2.4.tar.gz 02:35:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:35:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:35:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:35:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:35:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:35:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:35:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:35:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:35: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 02:35: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 02:35: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 02:35: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 02:35:58 INFO - Downloading docopt-0.6.1.tar.gz 02:35:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:35:58 INFO - Installing collected packages: blobuploader, requests, docopt 02:35:58 INFO - Running setup.py install for blobuploader 02:35:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:35:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:35:58 INFO - Running setup.py install for requests 02:35:59 INFO - Running setup.py install for docopt 02:35:59 INFO - Successfully installed blobuploader requests docopt 02:35:59 INFO - Cleaning up... 02:35:59 INFO - Return code: 0 02:35:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:35:59 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')]} 02:35:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:35:59 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')]} 02:35:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:35:59 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 0x10a928c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a92c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90d2562690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a67ed30>, '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 0x10a648cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a66fbe8>, '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.lcJIUdkDHT/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.4QtbeIopIn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 02:35:59 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 02:35:59 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 02:35:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lcJIUdkDHT/Render', 02:35:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:35:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:35:59 INFO - 'HOME': '/Users/cltbld', 02:35:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:35:59 INFO - 'LOGNAME': 'cltbld', 02:35:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:35:59 INFO - 'MOZ_NO_REMOTE': '1', 02:35:59 INFO - 'NO_EM_RESTART': '1', 02:35:59 INFO - 'PAGER': '/bin/cat', 02:35:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:35:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:35:59 INFO - 'PWD': '/builds/slave/test', 02:35:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:35:59 INFO - 'SHELL': '/bin/bash', 02:35:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4QtbeIopIn/Listeners', 02:35:59 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 02:35:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:35:59 INFO - 'USER': 'cltbld', 02:35:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:35:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:35:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:35:59 INFO - 'XPC_FLAGS': '0x0', 02:35:59 INFO - 'XPC_SERVICE_NAME': '0', 02:35:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:35:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:35:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:35:59 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-pwuLy6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:35:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:35:59 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-cbcezs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:36:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:36:00 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-OAZhQW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:36:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:36:00 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-Vvt4AR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:36:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:36:00 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-mtTH74-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:36:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:36:00 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-bmzyrT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:36:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:36:00 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-Sf8NGj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:36:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:36:00 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-EfVZMt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:36:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:36:00 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-lNBz1H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:36:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:36:00 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-cfqDC4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:36:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:36:00 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-l2z9FK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:36:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:36:00 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-_g98QA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:36:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:36:01 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-9HV_zY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:36:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:36:01 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-7_gB8I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:36:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:36:01 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-R955e6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:36:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:36:01 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-c63oWb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:36:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:36:01 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-IL0EbN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:36:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:36:01 INFO - Running setup.py install for manifestparser 02:36:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:36:01 INFO - Running setup.py install for mozcrash 02:36:01 INFO - Running setup.py install for mozdebug 02:36:01 INFO - Running setup.py install for mozdevice 02:36:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:36:02 INFO - Running setup.py install for mozfile 02:36:02 INFO - Running setup.py install for mozhttpd 02:36:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:36:02 INFO - Running setup.py install for mozinfo 02:36:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:36:02 INFO - Running setup.py install for mozInstall 02:36:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:36:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:36:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:36:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:36:02 INFO - Running setup.py install for mozleak 02:36:02 INFO - Running setup.py install for mozlog 02:36:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:36:03 INFO - Running setup.py install for moznetwork 02:36:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:36:03 INFO - Running setup.py install for mozprocess 02:36:03 INFO - Running setup.py install for mozprofile 02:36:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:36:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:36:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:36:03 INFO - Running setup.py install for mozrunner 02:36:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:36:03 INFO - Running setup.py install for mozscreenshot 02:36:03 INFO - Running setup.py install for moztest 02:36:03 INFO - Running setup.py install for mozversion 02:36:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:36:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:36:04 INFO - Cleaning up... 02:36:04 INFO - Return code: 0 02:36:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:36:04 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')]} 02:36:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:36:04 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')]} 02:36:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:36:04 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 0x10a928c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a92c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90d2562690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a67ed30>, '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 0x10a648cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a66fbe8>, '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.lcJIUdkDHT/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.4QtbeIopIn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 02:36:04 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 02:36:04 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 02:36:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lcJIUdkDHT/Render', 02:36:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:36:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:36:04 INFO - 'HOME': '/Users/cltbld', 02:36:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:36:04 INFO - 'LOGNAME': 'cltbld', 02:36:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:36:04 INFO - 'MOZ_NO_REMOTE': '1', 02:36:04 INFO - 'NO_EM_RESTART': '1', 02:36:04 INFO - 'PAGER': '/bin/cat', 02:36:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:36:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:36:04 INFO - 'PWD': '/builds/slave/test', 02:36:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:36:04 INFO - 'SHELL': '/bin/bash', 02:36:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4QtbeIopIn/Listeners', 02:36:04 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 02:36:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:36:04 INFO - 'USER': 'cltbld', 02:36:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:36:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:36:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:36:04 INFO - 'XPC_FLAGS': '0x0', 02:36:04 INFO - 'XPC_SERVICE_NAME': '0', 02:36:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:36:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:36:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:36:04 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-hn9Kmg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:36:04 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)) 02:36:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:36:04 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-90DmGg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:36:04 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)) 02:36:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:36:04 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-IaBsPM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:36:04 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)) 02:36:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:36:04 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-wXXm5H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:36:04 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)) 02:36:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:36:04 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-mvPGqY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:36:04 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)) 02:36:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:36:04 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-JbOtSj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:36:05 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)) 02:36:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:36:05 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-6kwioI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:36:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 02:36:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:36:05 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-2dmILR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:36:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 02:36:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:36:05 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-7iLhhS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:36:05 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)) 02:36:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:36:05 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-2B_oUU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:36:05 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)) 02:36:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:36:05 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-Ul0Rhk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:36:05 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)) 02:36:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:36:05 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-XCImYz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:36:05 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)) 02:36:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:36:05 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-_cccA2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:36:05 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)) 02:36:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:36:05 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-6PtvVx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:36:05 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)) 02:36:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:36:05 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-tYhgW4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:36:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 02:36:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:36:05 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-SdCKhO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:36:06 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)) 02:36:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:36:06 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-VHn3xg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:36:06 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)) 02:36:06 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)) 02:36:06 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)) 02:36:06 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)) 02:36:06 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)) 02:36:06 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)) 02:36:06 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 02:36:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:36:06 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 02:36:06 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 02:36:06 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 02:36:06 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 02:36:07 INFO - Downloading blessings-1.6.tar.gz 02:36:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:36:07 INFO - Installing collected packages: blessings 02:36:07 INFO - Running setup.py install for blessings 02:36:07 INFO - Successfully installed blessings 02:36:07 INFO - Cleaning up... 02:36:07 INFO - Return code: 0 02:36:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:36:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:36:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:36:08 INFO - Reading from file tmpfile_stdout 02:36:08 INFO - Current package versions: 02:36:08 INFO - blessings == 1.6 02:36:08 INFO - blobuploader == 1.2.4 02:36:08 INFO - docopt == 0.6.1 02:36:08 INFO - functools32 == 3.2.3-2 02:36:08 INFO - jsonschema == 2.5.1 02:36:08 INFO - manifestparser == 1.1 02:36:08 INFO - mozInstall == 1.13 02:36:08 INFO - mozcrash == 1.0 02:36:08 INFO - mozdebug == 0.1 02:36:08 INFO - mozdevice == 0.51 02:36:08 INFO - mozfile == 1.2 02:36:08 INFO - mozhttpd == 0.7 02:36:08 INFO - mozinfo == 0.10 02:36:08 INFO - mozleak == 0.1 02:36:08 INFO - mozlog == 3.4 02:36:08 INFO - moznetwork == 0.27 02:36:08 INFO - mozprocess == 0.25 02:36:08 INFO - mozprofile == 0.28 02:36:08 INFO - mozrunner == 6.13 02:36:08 INFO - mozscreenshot == 0.1 02:36:08 INFO - mozsystemmonitor == 0.3 02:36:08 INFO - moztest == 0.8 02:36:08 INFO - mozversion == 1.4 02:36:08 INFO - psutil == 3.1.1 02:36:08 INFO - requests == 1.2.3 02:36:08 INFO - wsgiref == 0.1.2 02:36:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:36:08 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')]} 02:36:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:36:08 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')]} 02:36:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:36:08 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 0x10a928c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a92c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90d2562690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a67ed30>, '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 0x10a648cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a66fbe8>, '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.lcJIUdkDHT/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.4QtbeIopIn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 02:36:08 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 02:36:08 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 02:36:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lcJIUdkDHT/Render', 02:36:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:36:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:36:08 INFO - 'HOME': '/Users/cltbld', 02:36:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:36:08 INFO - 'LOGNAME': 'cltbld', 02:36:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:36:08 INFO - 'MOZ_NO_REMOTE': '1', 02:36:08 INFO - 'NO_EM_RESTART': '1', 02:36:08 INFO - 'PAGER': '/bin/cat', 02:36:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:36:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:36:08 INFO - 'PWD': '/builds/slave/test', 02:36:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:36:08 INFO - 'SHELL': '/bin/bash', 02:36:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4QtbeIopIn/Listeners', 02:36:08 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 02:36:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:36:08 INFO - 'USER': 'cltbld', 02:36:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:36:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:36:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:36:08 INFO - 'XPC_FLAGS': '0x0', 02:36:08 INFO - 'XPC_SERVICE_NAME': '0', 02:36:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:36:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:36:08 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)) 02:36:08 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)) 02:36:08 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)) 02:36:08 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)) 02:36:08 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)) 02:36:08 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)) 02:36:08 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)) 02:36:08 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)) 02:36:08 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)) 02:36:08 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 02:36:08 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 02:36:08 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 02:36:08 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 02:36:08 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 02:36:09 INFO - Downloading marionette_driver-1.4.0.tar.gz 02:36:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 02:36:09 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:36:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:36:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 02:36:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 02:36:09 INFO - Installing collected packages: marionette-driver 02:36:09 INFO - Running setup.py install for marionette-driver 02:36:10 INFO - Successfully installed marionette-driver 02:36:10 INFO - Cleaning up... 02:36:10 INFO - Return code: 0 02:36:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:36:10 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')]} 02:36:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:36:10 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')]} 02:36:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:36:10 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 0x10a928c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a92c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90d2562690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a67ed30>, '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 0x10a648cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a66fbe8>, '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.lcJIUdkDHT/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.4QtbeIopIn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 02:36:10 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 02:36:10 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 02:36:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lcJIUdkDHT/Render', 02:36:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:36:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:36:10 INFO - 'HOME': '/Users/cltbld', 02:36:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:36:10 INFO - 'LOGNAME': 'cltbld', 02:36:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:36:10 INFO - 'MOZ_NO_REMOTE': '1', 02:36:10 INFO - 'NO_EM_RESTART': '1', 02:36:10 INFO - 'PAGER': '/bin/cat', 02:36:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:36:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:36:10 INFO - 'PWD': '/builds/slave/test', 02:36:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:36:10 INFO - 'SHELL': '/bin/bash', 02:36:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4QtbeIopIn/Listeners', 02:36:10 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 02:36:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:36:10 INFO - 'USER': 'cltbld', 02:36:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:36:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:36:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:36:10 INFO - 'XPC_FLAGS': '0x0', 02:36:10 INFO - 'XPC_SERVICE_NAME': '0', 02:36:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:36:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:36:10 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:36:10 INFO - Cleaning up... 02:36:10 INFO - Return code: 0 02:36:10 INFO - Running post-action listener: _resource_record_post_action 02:36:10 INFO - Running post-action listener: _start_resource_monitoring 02:36:10 INFO - Starting resource monitoring. 02:36:10 INFO - [mozharness: 2017-06-11 09:36:10.472035Z] Finished create-virtualenv step (success) 02:36:10 INFO - [mozharness: 2017-06-11 09:36:10.473008Z] Running install step. 02:36:10 INFO - Running pre-action listener: _resource_record_pre_action 02:36:10 INFO - Running main action method: install 02:36:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:36:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:36:10 INFO - Reading from file tmpfile_stdout 02:36:10 INFO - Detecting whether we're running mozinstall >=1.0... 02:36:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:36:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:36:10 INFO - Reading from file tmpfile_stdout 02:36:10 INFO - Output received: 02:36:10 INFO - Usage: mozinstall [options] installer 02:36:10 INFO - Options: 02:36:10 INFO - -h, --help show this help message and exit 02:36:10 INFO - -d DEST, --destination=DEST 02:36:10 INFO - Directory to install application into. [default: 02:36:10 INFO - "/builds/slave/test"] 02:36:10 INFO - --app=APP Application being installed. [default: firefox] 02:36:10 INFO - mkdir: /builds/slave/test/build/application 02:36:10 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'] 02:36:10 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 02:36:33 INFO - Reading from file tmpfile_stdout 02:36:33 INFO - Output received: 02:36:33 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 02:36:33 INFO - Running post-action listener: _resource_record_post_action 02:36:33 INFO - [mozharness: 2017-06-11 09:36:33.053101Z] Finished install step (success) 02:36:33 INFO - [mozharness: 2017-06-11 09:36:33.053244Z] Running setup-mitmproxy step. 02:36:33 INFO - Running pre-action listener: _resource_record_pre_action 02:36:33 INFO - Running main action method: setup_mitmproxy 02:36:33 INFO - Skipping: mitmproxy is not required 02:36:33 INFO - Running post-action listener: _resource_record_post_action 02:36:33 INFO - [mozharness: 2017-06-11 09:36:33.053921Z] Finished setup-mitmproxy step (success) 02:36:33 INFO - [mozharness: 2017-06-11 09:36:33.054035Z] Running run-tests step. 02:36:33 INFO - Running pre-action listener: _resource_record_pre_action 02:36:33 INFO - Running main action method: run_tests 02:36:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:36:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:36:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:36:33 INFO - 2017-06-11 02:36:33.069 screenresolution[1642:12164] starting screenresolution argv=screenresolution get 02:36:33 INFO - 2017-06-11 02:36:33.071 screenresolution[1642:12164] Display 0: 1600x1200x32@60 02:36:33 INFO - 2017-06-11 02:36:33.077 screenresolution[1643:12167] starting screenresolution argv=screenresolution list 02:36:33 INFO - Available Modes on Display 0 02:36:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:36:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:36:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:36:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:36:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:36:33 INFO - Intel Iris: 02:36:33 INFO - Chipset Model: Intel Iris 02:36:33 INFO - Type: GPU 02:36:33 INFO - Bus: Built-In 02:36:33 INFO - VRAM (Dynamic, Max): 1536 MB 02:36:33 INFO - Vendor: Intel (0x8086) 02:36:33 INFO - Device ID: 0x0a2e 02:36:33 INFO - Revision ID: 0x0009 02:36:33 INFO - Displays: 02:36:33 INFO - Display: 02:36:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:36:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:36:33 INFO - Display Serial Number: 1600x1200 60 02:36:33 INFO - Main Display: Yes 02:36:33 INFO - Mirror: Off 02:36:33 INFO - Online: Yes 02:36:33 INFO - Rotation: Supported 02:36:33 INFO - Return code: 0 02:36:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:36:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:36:33 INFO - Python 2.7.3 02:36:33 INFO - Return code: 0 02:36:33 INFO - grabbing minidump binary from tooltool 02:36:33 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')]} 02:36:33 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 0x10a67ed30>, '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 0x10a648cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a66fbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:36:33 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 02:36:33 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 02:36:33 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 02:36:33 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:36:33 INFO - Return code: 0 02:36:33 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:36:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:36:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:36:33 INFO - ENV: RUST_BACKTRACE is now 1 02:36:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:36:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:36:33 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/VDZSI2FlSu2sxA6eEWR86A/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0318', '--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 02:36:33 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/VDZSI2FlSu2sxA6eEWR86A/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0318 --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 02:36:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lcJIUdkDHT/Render', 02:36:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:36:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:36:33 INFO - 'HOME': '/Users/cltbld', 02:36:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:36:33 INFO - 'LOGNAME': 'cltbld', 02:36:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:36:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:36:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:36:33 INFO - 'MOZ_NO_REMOTE': '1', 02:36:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:36:33 INFO - 'NO_EM_RESTART': '1', 02:36:33 INFO - 'PAGER': '/bin/cat', 02:36:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:36:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:36:33 INFO - 'PWD': '/builds/slave/test', 02:36:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:36:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:36:33 INFO - 'RUST_BACKTRACE': '1', 02:36:33 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 02:36:33 INFO - 'SHELL': '/bin/bash', 02:36:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4QtbeIopIn/Listeners', 02:36:33 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 02:36:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:36:33 INFO - 'USER': 'cltbld', 02:36:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:36:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:36:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:36:33 INFO - 'XPC_FLAGS': '0x0', 02:36:33 INFO - 'XPC_SERVICE_NAME': '0', 02:36:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:36:33 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/VDZSI2FlSu2sxA6eEWR86A/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0318', '--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 02:36:33 INFO - mozversion application_buildid: 20170611013723 02:36:33 INFO - mozversion application_changeset: a040e8315026ea31bd92aa26333d69f689b1ccd7 02:36:33 INFO - mozversion application_display_name: Nightly 02:36:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:36:33 INFO - mozversion application_name: Firefox 02:36:33 INFO - mozversion application_remotingname: firefox 02:36:33 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 02:36:33 INFO - mozversion application_vendor: Mozilla 02:36:33 INFO - mozversion application_version: 55.0a1 02:36:33 INFO - mozversion platform_buildid: 20170611013723 02:36:33 INFO - mozversion platform_changeset: a040e8315026ea31bd92aa26333d69f689b1ccd7 02:36:33 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 02:36:33 INFO - mozversion platform_version: 55.0a1 02:36:33 INFO - using testdate: 1497173793 02:36:33 INFO - actual date: 1497173793 02:36:33 INFO - starting webserver on 'localhost:49233' 02:36:33 INFO - SUITE-START | Running 2 tests 02:36:33 INFO - TEST-START | dromaeo_css 02:36:33 INFO - operating with platform_type : mac_ 02:36:33 INFO - Initialising browser for dromaeo_css test... 02:36:33 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49233/getInfo.html -foreground -profile /var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/tmplDLI9l/profile 02:36:33 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49233/getInfo.html -foreground) 02:37:07 INFO - TEST-INFO | 1653: killed by SIGTERM 02:37:07 INFO - Browser initialized. 02:37:07 INFO - Running cycle 1/1 for dromaeo_css test... 02:37:07 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/tmplDLI9l/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:37:09 INFO - PID 1661 | 2017-06-11 02:37:09.463 plugin-container[1662:12678] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x464b, name = 'com.apple.tsm.portname' 02:37:09 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:37:09 INFO - PID 1661 | 2017-06-11 02:37:09.463 plugin-container[1662:12678] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d0b, name = 'com.apple.CFPasteboardClient' 02:37:09 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:37:09 INFO - PID 1661 | 2017-06-11 02:37:09.463 plugin-container[1662:12678] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:37:10 INFO - PID 1661 | 2017-06-11 02:37:10.213 plugin-container[1663:12728] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x952b, name = 'com.apple.tsm.portname' 02:37:10 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:37:10 INFO - PID 1661 | 2017-06-11 02:37:10.213 plugin-container[1663:12728] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d0b, name = 'com.apple.CFPasteboardClient' 02:37:10 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:37:10 INFO - PID 1661 | 2017-06-11 02:37:10.213 plugin-container[1663:12728] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:37:19 INFO - PID 1661 | RSS: Main: 253227008 02:37:19 INFO - PID 1661 | 02:40:22 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49233/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49233/tests/dromaeo/cssquery-ext.html) 02:40:24 INFO - PID 1661 | RSS: Main: 263176192 02:40:24 INFO - PID 1661 | 02:43:25 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49233/tests/dromaeo/cssquery-ext.html (next: http://localhost:49233/tests/dromaeo/cssquery-jquery.html) 02:43:26 INFO - PID 1661 | RSS: Main: 276361216 02:43:26 INFO - PID 1661 | 02:46:28 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49233/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49233/tests/dromaeo/cssquery-mootools.html) 02:46:28 INFO - PID 1661 | RSS: Main: 276189184 02:46:28 INFO - PID 1661 | 02:49:30 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49233/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49233/tests/dromaeo/cssquery-prototype.html) 02:49:31 INFO - PID 1661 | RSS: Main: 279601152 02:49:31 INFO - PID 1661 | 02:52:34 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49233/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49233/tests/dromaeo/cssquery-yui.html) 02:52:35 INFO - PID 1661 | RSS: Main: 279023616 02:52:35 INFO - PID 1661 | 02:55:37 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49233/tests/dromaeo/cssquery-yui.html (next: http://localhost:49233/tests/dromaeo/cssquery-dojo.html) 02:55:37 INFO - PID 1661 | RSS: Main: 278147072 02:55:37 INFO - PID 1661 | 02:55:37 INFO - PID 1661 | __start_tp_report 02:55:37 INFO - PID 1661 | _x_x_mozilla_page_load 02:55:37 INFO - PID 1661 | _x_x_mozilla_page_load_details 02:55:37 INFO - PID 1661 | |i|pagename|runs| 02:55:37 INFO - PID 1661 | |0;dojo.html;3517.630648781878;3585.8924232273007;3594.442861356489;3620.67413932746;3634.1096431374317;6135.232666669988;6144.631322120676;6247.406496382842;6254.687265636717;6256.311805701369;5279.970405770867;5797.3043234811885;6191.81424557264;6199.163112979746;6294;5270.1040823060175;5439.836804895859;5449.155380915964;5458.208559758833;5511;5477.534409575171;5531.810660707947;5537.169424586304;5579.776808927638;5582.49757521823;5366.6779993200535;5428.91856622147;5439.510444060014;5471.8084867029465;5478.506934375885;5483.067878460672;5503.532199763025;5510.338759348863;5530.170474428827;5541.113421852484;5431.049566325917;5442.727863606804;5456.208849716769;5468.6171967962255;5498.560115190775;6135.87728245437;6152.630842149485;6197.287311959092;6234.158388617524;6250.812475625738;6705.6982435790505;6732.461403087741;6745.055692203046;6746.257911629717;6749.493787965922;6833.4191593714595;6834.077399551046;6846.657667116624;6848.041274221613;6863.588184708883;6259.593126446766;6304.306526282106;6317.3366796486625;6341.746330146835;6342.334054924167;6854.383105520427;6880.415164710956;6895.482838787111;6913.516053876281;6933.514653974174;5579.721013949286;6119.653676191231;6465.2564955030775;6509.511786616023;6593.472522198213;6781.4574834013165;6788.694508747019;6792.490563207681;6823.112995310578;6827.351401616838;5644.8871022579315;6263.467605253604;6681.899771503431;6690.431313338421;6728.831779205559;5591.440855914458;6597.967010164918;6686.83282917931;6701.195856497252;6716.093327400738;5340.893182136413;5341.278927344836;5345.54562862161;5355.116405793078;5395;4729;4754.453237877619;4755.619550435957;4762.880927976828;4781.497942716034;6182.381761823872;6242.09489624002;6309.495240380758;6318.6524741138755;6361.840953976095;6675.599464032077;6695.096162018162;6700.028495868072;6702.564333318416;6703.93296067033;6246.312905580474;6312.4634406076;6315.684215789192;6336.461400780985;6337.112804207414;5785.0454674979;5853.590248682552;5862.5896187267335;5928.051511758098;5995.070764031495;5995.820125396245;6951.582905025714;6965.47758918073;6978.162620485574;7023.438124949889;6709.228438729609;6790.388865002174;6796.626185559841;6813.523053386413;6817.829554260985;6765.2896445872475;6960.164780226405;6967.7212911482975;6976.5465244758925;6990.126234220723;5839.970800145972;6315.336889626523;6481.5786973848435;6558.901616475852;6675.666216689243;5936.961031819328;6596.60420374779;6815.829604259933;6825.385715285648;6834.248232694499;6345.1116843641;6468.870622587616;6571.277159512545;6595.637239951657;6598.538102332791;6372.458341041063;6557.803265902031;6574.638394888327;6580.5722628029025;6606.966965165144;6276.968615156895;6318;6353.777617783356;6410.8076757697345;6412.711427985659;6575.704093315717;6577.868442631217;6582.17722784652;6592.17597800275;6606.669666516751;6396.168498095218;6414.03789431589;6415.903761443676;6424.486041116793;6446.774362897276 02:55:37 INFO - PID 1661 | |1;ext.html;721.6865305144636;788.6293442082219;805.8670319397299;1233.8642749297576;1290.2903403128278;37903.62096379039;38784.418233726516;38823.22353552931;38836;38939.41590876139;32117.518237226403;34340.96977090685;38915.80542097292;39477.60522394775;39499.80250098753;7165.85668286634;7407.444441666869;8364.539950302731;8372.246497815195;8389.748307550783;8221.218984196492;8261.884645572862;8332.87506186663;8333.041700204494;8359.289460395874;7442.255774422569;8299;8357.289630381425;8372.28835548976;8386.412951093425;8315.376346773986;8328.958355208246;8335.54154521501;8352.24829765321;8378.874316885252;8134.349252059821;8150.429469937107;8216.753497395088;8220.712275070404;8225.876611850827;29248.56127158073;36796.81601591975;36845.447318290244;36921.44617830735;36986.630133698585;3113.237256872058;3537.7346698997676;3576.481410195533;3580.785152890809;3589.9820500897326;23744.76255237443;29318.853405733047;30001.249968750737;30274.697253027407;30323.93866214694;2746.5056289867816;3040.75673946086;3068.5550595163577;3095.8142511449387;3117.0492999635103;222362;232544;280268;291793;292771;39761.602383976366;48542;48804.7559762206;48921.02157956821;49098;51380;51441.74279128655;51446.742766285926;55120.44879551233;55791.721041394536;14489.855101448851;14524.49164279245;14688;14689.77965330521;14715.117092974457;11745.941270293593;11772.470238839272;11804.763904721858;11839.408029598486;11861.169718119649;14042.578722638336;14070.507532236323;14146.009779315555;14148.504802331869;14295.856331493664;5162.354705661793;5179.98990196908;6132.141500189977;6211.65835879031;6239.251289845157;978.9167920726818;1002.6039714312806;1016.786474840277;1050.7320633238476;1050.9001644843727;23030.884845576;23256;23435.179768708014;24122.03511859506;24225.27324180242;897.7351681254046;1091.143452389886;1133.9546418143182;1141.7088642396302;1145.0438883532177;11844.88155118455;11941.64175074749;11988.580399685969;12371.6907077322;12408.317542535247;320776;322002;322553;322695;322774;54876.72561637166;55156.72421637866;55212;55230;55240.61898452571;1479.52655150353;1487.21177775779;1512.8260250071162;1526.0996012352766;1530.4260902161689;22211;22773.202937897095;22892.885535572215;23855;24345.51308973846;36349.27301453903;38350.80824595859;38375.61624383832;38429.231415372094;38501.61498385092;271605;271729;271972;272154;272305;36259.818700906326;36482.27035459223;36524.81737591295;36542.086447839014;36700.81649591735;43273.13453730845;43470.565294347914;44018.559814402724;45594.54405455903;45848;29185.708142918298;29682.40635187241;30087.548686769278;30603.939606039217;30727.846360768053;26470.470590587694;29987;30022.84988575043;31068.533971989986;31642.10147391164;29788;36806.44790328201;36970.89087327384;36989.445158323186;37221.813890930374;21479.14083436645;28094.76077549292;28859.42281154323;29471.82112715553;29681;24125.155619553232;24384.878075609508;24576.6313505294;24660.753392465846;24788.25635230946 02:55:37 INFO - PID 1661 | |2;jquery.html;479.1590758219327;491.7148054128606;499.11905486815755;511.5856156513225;516.1947362115103;19430.805691943075;20879.791202087974;21543.6768448473;22067.779322206792;22972.8851355743;25345.366365840862;25915;26013.869930650366;26104.47791044178;26145.084922027734;7129.322714342129;7255.818604534876;7286.635668216594;7293.234210407911;7294.8905766413545;6881.380675739182;7106.964465177667;7111.964440177792;7129.108861392326;7191.604461754601;7096.077509923703;7099.787006389816;7101.538400004009;7107.111611048619;7108.253633368474;7062.82342941428;7070.8939365909555;7079.07971963644;7085.822854428629;7101.538400004009;6925.480588955848;6929.099217101795;6947.652617369111;6952.304769523058;6960.712268230393;30379.69620303813;31099;31202.843985780153;31815.88644397435;31921.563529641222;2260.1976298413992;2320.2111282164146;2323;2329.3827135809024;2335.1476711000364;20550.38348849522;20657.793422065737;20910.163593456244;20976.895115524323;20977.475563110893;2305.319930620471;2307.9653805192934;2368.230325144335;2377.227401094651;2410.4455975125643;169501.15249423796;170541.44187837193;178753;195476;207570.9621451898;22232.777672223394;25338.493230135293;25953.74046259551;26406.867965660047;27550.311242219104;40027.19877605515;41846.58153418427;44847;44975.32537011948;45004.549954500035;11138.554457821758;11168.274062186094;11291.854547635923;11349.205555611194;11419.257748246337;8864.0692727264;9000.414973026733;9003.324750643727;9059.773505662411;9079.364444488823;11015.953484418967;11564.653060408202;12384.690382740504;12391.93804030974;12555.811662825065;4252.787360632018;4821.710697358169;4824.903501929942;4925.605951523869;4946.975265123651;948.5020364308793;953.5102743903975;969.709087273815;1013.1084645511902;1013.8225810483283;21223.575528489342;21989.890050549966;22214.55570888573;22428.551428971656;22499.775002250095;993.3344659078434;1013.1135256650431;1031.4739482863642;1043.8277684182176;1047.8323468245044;10366.91147429524;10569.429772985555;10892.61875834342;11470.54117835294;11743.11926605508;491788;492080;493859;498124;548110;147923;149533;150561;150825.24587376992;151794;942.8403064231035;1002.318423472046;1142.828575713633;1180.3744015671537;1224.0330139190119;21915.561688766455;22979.6553051701;23806.761932380454;23865.522689545764;23913.521729564964;58560.707196465446;68491;69908.95136573057;70077.9488307686;73461.26538734544;75812;78519.60740196262;93612.59581106153;93693;94565.5271723637;16097.356105756107;16312.429064982669;16462.588435289214;16565.42021029258;16591.41930032443;16638.417655382003;18212.635747285247;18414.631707366047;18584.5353866149;18666;14709.55871323862;14835.85164148388;15205.923970380078;15236.466723665062;15258.618534536281;12806.67983300425;12832.871671283168;13579.388927498088;13647.249401282628;13745.862541374458;15181.848181518486;15243.085414875142;15359.462418815288;15445.613859653598;15541.533753987398;16094.873358865236;16227.837721623106;16415.91792041032;16467;16603.252853621376;12825.615231543068;14840.925795370955;14913.850861491246;14927.776083358976;14978.400863965318 02:55:37 INFO - PID 1661 | |3;mootools.html;1386.9306534673267;1443.6751730860565;1472.5287907869479;1473.2633683158424;1486.8774075572942;13231.603051908434;13300.866991330084;13774.862251377483;13795.862041379583;13858;13296.002799790007;13525.391357388917;13538.864611353883;13556.18662880228;13567.796483052738;2678.6116013178125;2720.319920019995;2742.3418379588857;2743.876525556355;2748.7938404619736;2667.4531720997147;2719.5376785946437;2721.7958653100995;2723.536998710214;2728.358835673615;2662.227953893378;2682.557378032622;2698.0961377938415;2698.406350602874;2704.445588654321;2648.589468632365;2665.760081592656;2669.159214847317;2672.5590277604174;2683.18162960297;2241.921532746346;2321.721393432782;2614.75159859813;2633.2363614551755;2644.5239856825765;10587.311824731369;13427.32863356838;13434.723701248366;13435.26106064166;13462.865371346259;6451.903221451682;7309.853802923912;7581.962090189514;7591.962040189819;7619.733309334147;4520.977395113036;4717.811287548494;4779.402574678165;4783.234682450826;4803.6397270204525;1343.3619030960313;1377.3044612470737;1390.7079513302117;1395.7696979998288;1395.9720805583934;19563.21747130113;19602.313919012908;19695.507612309662;19710;19754.308599199103;5451.073317536028;5516.724163791794;6399.380512963135;6434.678266086677;6473.611583305017;13932.164070155823;13978.231197284247;13979.720405592034;13987.510437134855;13993.580192594238;7097.787066388017;7351.742689005966;7385.81535461607;7395.926040739631;7397.519161254501;6626.966865165643;6723.125993620798;6761.256261811197;6767.120274364301;6874.6562671867205;4869.975650121727;5046.47012063728;5069.403138011515;5093.9490605094215;5107.514786095315;1457.6574504991318;1467.6404280951022;1469.7942288079678;1483.6439254579038;1490.3368001239344;765.6133652505449;815.388458656008;817.3134566963779;825.5748289630845;843.1905370843936;7643.4267429942975;7814.101378341564;7894.802629934183;8064.193580641889;8280.8343833123;849.995750021246;952.7284723853807;969.9030096990385;974.8050389922043;1010.242318261304;4380.759058251827;4430.291153415438;4633.907321853544;5458.208559758813;5473.480019398151;12471.93764031174;18805.90597047006;18814.811851881306;18818.435446936615;18832.435026949213;17511.299548018193;18138.909305453653;18276.177572009292;18313.267469301078;18335.35826246075;9176.95411522938;9375.48434836077;9381.108794664497;9396.624135034659;9401.717948461557;6738.7304507819135;7493.363064139391;8052.959735201286;8143.348532117535;8189.812477190655;16161.353545858034;16294;16364.509064728078;16457.753133702765;16689.248983795515;15928;16371.181440927669;16689.666206675556;16945.830541694424;17020.319187232868;10720.142388608736;10975;10988.725281868017;10989.340639561651;10998.34009959405;10374.740631484274;12422.751544968869;12455.128141030404;12556.87443125557;12629.11596188258;10200.948995254976;10210.285280030326;10294;10313.27807053499;10349.534270957976;7935.642896069806;9925.858526269283;10150.23873209527;10163.085322321027;10226.181905447398;8129.878051829109;8884.866726998971;9163.26693864476;9226.815463690555;9273.814523709352;6781.42357899584;6785.389314961677;6945.861082778417;6976.988336691253;7064.540804847668;6923.238443771081;7066.752663656747;7084.61034643079;7338.192798792022;7428.777136685908 02:55:37 INFO - PID 1661 | |4;prototype.html;392.7486408698432;436.66813221951327;448.3230322213458;449.92126377883864;467.86309268477606;6597.680463907213;6598.58130501942;6600.702968366423;6601.9766936124915;6623.86752264955;6146.815595532141;6407.48740100792;6418.229812422516;6442;6449.322821103787;4747.0268594938125;4779.689320194185;4800.4719480857075;4811.711297322172;4817.638677099214;4778.78495467703;4783.378160839086;4784.282357646346;4807.03859228154;4821.252705830602;4155.106652069804;4544.341070544769;4747.501512341206;4773.57037866592;4774.427068751755;4230.725002874803;4766.380370551823;4767.856964291077;4789.880252993703;4823.421189457253;3423.7945723256685;4126.133511962492;4154.210699967027;4210.200061988213;4222.050038741289;5447.346318441812;5857.619254748471;6320.810375688737;6336.3663633636725;6349.111124442582;4742.739149346785;5699.00267453194;5701.4298570142655;5709.000924838137;5752.568557358173;5721.283614915509;6770.52606317558;6922.5154239203275;6933.826654333682;6933.965330173317;3665.4685070664605;4468.8882777930485;4761.571458568746;4775.403074615673;4833.540813622701;5981.910271345933;6027.547933904974;7186.604736739425;7295.343419092307;7343.375813055896;4063.167050754588;4065.9796701016307;4797.808087676523;4875.7074575525585;4892.3884514435695;4591.8392856249875;4940.258961155855;4975.303457515951;5015.17249653803;5063.506095885931;4915.606751459874;5390.353157621033;5466.562674985991;5553.583481238923;5695.914561281584;6906.758263460755;6960.895586566105;6964.547304425196;6984.650767461708;7019.824504387329;1433.269032793283;1449.1884544655027;1691.4164613208422;1694.6610677864476;1695.6015336395938;927.248928368024;1025.8204814157493;1088.3633074651418;1153.140910022039;1155.5608868630036;5277.577793776489;5750.424957504216;5810.4189581041555;6246.000639897595;6622.867542649216;7158.425146467873;7175.605341706152;7207.315305046012;7211.495195336276;7211.927880721126;6529.934700652932;6551.639659818756;6565.063306324646;6567.44176744982;6570.835729106715;3484.564429446319;3485.721142308609;3759.962400376016;3769.905752356213;3775.5469343678933;7062.435005199674;7228.710851565878;7297.89053164203;7447.515911465737;7452.627368631547;5778.3643799182055;6477.287498375175;7221.638918054181;7357.41140708742;7408.407327413794;6119.785807496805;6520.576162549419;6869.003994420881;7077.363037326665;7441.590712510756;6278.4349408551625;7123.786286411416;7208.89186662211;7220.350168484862;7226.313500217471;6526.4452521536205;7089.291070892869;7117.857642847218;7122.857542849218;7123.7506687265695;6195.566310358412;6505.2844187138435;7354.8896766549615;7431.331180193592;7455.515391499535;4852.636052296021;4854.514548545118;4883.89992303313;4900.852974410774;4908.582770464551;6077.969610152098;7198.604076775828;7207.459440541876;7227.747028853965;7237.670671491173;5580.441955804549;6100.755969761338;6586.868262634816;6944.687489062903;6980.197277313174;6186.412290832363;6909;6916;6932.514723969273;6950.8609827804175;6741.764038258833;6748.156480439945;6768.323167683192;6782.525223234326;6804.489663275175;6427;6915.101036865175;6917.481188910751;6938.479614029069;7034.261402552863;6708.6310252936555;6716.429103526059;6733.898991515033;6748;6751.696173672295 02:55:37 INFO - PID 1661 | |5;yui.html;818.5252553518952;824.9298809601183;841.5119230846113;856.8518185631253;862.4523427623459;16417.671646567076;16611.91694041528;16813.243404046818;16829.07440090796;16874.915625421872;13926.930365348184;14957.878159138081;15140.621484462894;15377.92311038446;16204.918975405137;963.1716723617683;984.5913945712521;985.9309848310585;986.4919566423298;990.5641517732175;990.2869933647762;1011.9139873110795;1014.6093753904763;1014.9898501015006;1015.5988384588084;889.3552174673375;1045.8483519889612;1059.5443959097586;1061.4851796878509;1061.9362838229692;1050.0864248104199;1050.3015494696017;1050.6007717067541;1051.8842927277992;1054.4095306628312;771.726037256777;774.2837874965691;774.5631852912821;775.2209029924945;779.9532028078333;17015.808893377467;17025.318987240495;17050.06224657643;17214.569635759082;17240;16699.08155051472;16997.83002169975;17395.04327262;17424.390146344816;17434.302627894867;14095.78856317156;14367.425302987867;14433;14447.927760361132;14452.277386130756;3566.946495802565;3570.410882204433;3580.785152890835;3590.784552926833;3591.407417776064;105725.41411878828;126414.73585264213;127410;129173;129373;13362.599122026355;13910.304484775721;13912.51306204278;13920.930395347958;14102;26322.47355052888;26672;26720.465590688076;26793.732062679515;26878.5968210477;7448.366888814403;7480.438967077382;7483.513571617937;7486.6256687166515;7502.6623801929045;3859.7877116758286;3936.4488971544006;3940.566537680853;3945.743526670815;3951.4863067801;4290.313549832074;4346.21768081742;4412.845550405785;4419.049904270597;4422.181896349186;941.6892425499568;947.9477779664567;965.1169180200038;965.6764983730388;969.1665167955553;645.1548471502347;658.0754040573003;662.1292999705372;685.5132855672429;687.6286805125177;9279.72160835176;11441.485133169028;11585;12304.938475307745;12453.377331133406;637.5951270942985;638.8786130635165;641.2401304454237;651.8859199640139;661.9602823830586;5705.657660540381;5864.648121112747;5901.232839730807;5940.82177534666;5989.55078369124;145231;146357.53642463652;147054;147547.83582574557;148684.51315486923;122648.38675806743;124156;125648.37175814062;126500.36749816193;127791;81078.18921810706;101076.49461752645;103478;106003.93996060095;108150.91849081409;9896.45569493656;11723.296602203895;12199.817002745143;12227.69430764238;12297.631071067883;43752.90617734582;45326.32010519779;46423.30365044459;47070;47074.52925470839;60931.08603370864;79341;82031;83802;85707.57146214478;17877.553061173574;18190.90904545469;18227.270909163486;18272.908635456737;18278.543036424195;17491.56271093233;17940.192691329183;18629.44111676652;19149.808501914802;19417.2233110674;17512;17947.551311217325;18072.90963545174;18453.16960736797;18518.907405462887;17343.219555120304;17823.821761782216;18241.270349186416;18349.72475412843;18385.540361490534;14533.78199327032;17497.82502174962;17560.73658895092;17843.732344014592;18024.729629055837;11917.16579839403;11937.82093268619;12143;12544.059195560552;12591.496340146292;12565.74868502643;13112.934435327763;13292.534761283308;13706;14414.639634009234 02:55:37 INFO - PID 1661 | __end_tp_report 02:55:37 INFO - PID 1661 | __start_cc_report 02:55:37 INFO - PID 1661 | _x_x_mozilla_cycle_collect,448 02:55:37 INFO - PID 1661 | __end_cc_report 02:55:37 INFO - PID 1661 | __startTimestamp1497174937305__endTimestamp 02:55:37 INFO - PID 1661 | 02:55:37 INFO - PID 1661 | ------- Summary: start ------- 02:55:37 INFO - PID 1661 | Number of tests: 6 02:55:37 INFO - PID 1661 | 02:55:37 INFO - PID 1661 | [#0] dojo.html Cycles:165 Average:6157.74 Median:6337.11 stddev:723.89 (11.4%) stddev-sans-first:695.85 02:55:37 INFO - PID 1661 | Values: 3517.6 3585.9 3594.4 3620.7 3634.1 6135.2 6144.6 6247.4 6254.7 6256.3 5280.0 5797.3 6191.8 6199.2 6294.0 5270.1 5439.8 5449.2 5458.2 5511.0 5477.5 5531.8 5537.2 5579.8 5582.5 5366.7 5428.9 5439.5 5471.8 5478.5 5483.1 5503.5 5510.3 5530.2 5541.1 5431.0 5442.7 5456.2 5468.6 5498.6 6135.9 6152.6 6197.3 6234.2 6250.8 6705.7 6732.5 6745.1 6746.3 6749.5 6833.4 6834.1 6846.7 6848.0 6863.6 6259.6 6304.3 6317.3 6341.7 6342.3 6854.4 6880.4 6895.5 6913.5 6933.5 5579.7 6119.7 6465.3 6509.5 6593.5 6781.5 6788.7 6792.5 6823.1 6827.4 5644.9 6263.5 6681.9 6690.4 6728.8 5591.4 6598.0 6686.8 6701.2 6716.1 5340.9 5341.3 5345.5 5355.1 5395.0 4729.0 4754.5 4755.6 4762.9 4781.5 6182.4 6242.1 6309.5 6318.7 6361.8 6675.6 6695.1 6700.0 6702.6 6703.9 6246.3 6312.5 6315.7 6336.5 6337.1 5785.0 5853.6 5862.6 5928.1 5995.1 5995.8 6951.6 6965.5 6978.2 7023.4 6709.2 6790.4 6796.6 6813.5 6817.8 6765.3 6960.2 6967.7 6976.5 6990.1 5840.0 6315.3 6481.6 6558.9 6675.7 5937.0 6596.6 6815.8 6825.4 6834.2 6345.1 6468.9 6571.3 6595.6 6598.5 6372.5 6557.8 6574.6 6580.6 6607.0 6277.0 6318.0 6353.8 6410.8 6412.7 6575.7 6577.9 6582.2 6592.2 6606.7 6396.2 6414.0 6415.9 6424.5 6446.8 02:55:37 INFO - PID 1661 | 02:55:37 INFO - PID 1661 | [#1] ext.html Cycles:180 Average:43794.81 Median:322277.50 stddev:75432.77 (23.4%) stddev-sans-first:75575.05 02:55:37 INFO - PID 1661 | Values: 721.7 788.6 805.9 1233.9 1290.3 37903.6 38784.4 38823.2 38836.0 38939.4 32117.5 34341.0 38915.8 39477.6 39499.8 7165.9 7407.4 8364.5 8372.2 8389.7 8221.2 8261.9 8332.9 8333.0 8359.3 7442.3 8299.0 8357.3 8372.3 8386.4 8315.4 8329.0 8335.5 8352.2 8378.9 8134.3 8150.4 8216.8 8220.7 8225.9 29248.6 36796.8 36845.4 36921.4 36986.6 3113.2 3537.7 3576.5 3580.8 3590.0 23744.8 29318.9 30001.2 30274.7 30323.9 2746.5 3040.8 3068.6 3095.8 3117.0 222362.0 232544.0 280268.0 291793.0 292771.0 39761.6 48542.0 48804.8 48921.0 49098.0 51380.0 51441.7 51446.7 55120.4 55791.7 14489.9 14524.5 14688.0 14689.8 14715.1 11745.9 11772.5 11804.8 11839.4 11861.2 14042.6 14070.5 14146.0 14148.5 14295.9 5162.4 5180.0 6132.1 6211.7 6239.3 978.9 1002.6 1016.8 1050.7 1050.9 23030.9 23256.0 23435.2 24122.0 24225.3 897.7 1091.1 1134.0 1141.7 1145.0 11844.9 11941.6 11988.6 12371.7 12408.3 320776.0 322002.0 322553.0 322695.0 322774.0 54876.7 55156.7 55212.0 55230.0 55240.6 1479.5 1487.2 1512.8 1526.1 1530.4 22211.0 22773.2 22892.9 23855.0 24345.5 36349.3 38350.8 38375.6 38429.2 38501.6 271605.0 271729.0 271972.0 272154.0 272305.0 36259.8 36482.3 36524.8 36542.1 36700.8 43273.1 43470.6 44018.6 45594.5 45848.0 29185.7 29682.4 30087.5 30603.9 30727.8 26470.5 29987.0 30022.8 31068.5 31642.1 29788.0 36806.4 36970.9 36989.4 37221.8 21479.1 28094.8 28859.4 29471.8 29681.0 24125.2 24384.9 24576.6 24660.8 24788.3 02:55:37 INFO - PID 1661 | 02:55:37 INFO - PID 1661 | [#2] jquery.html Cycles:180 Average:39140.61 Median:2314.09 stddev:88092.70 (3806.8%) stddev-sans-first:88292.00 02:55:37 INFO - PID 1661 | Values: 479.2 491.7 499.1 511.6 516.2 19430.8 20879.8 21543.7 22067.8 22972.9 25345.4 25915.0 26013.9 26104.5 26145.1 7129.3 7255.8 7286.6 7293.2 7294.9 6881.4 7107.0 7112.0 7129.1 7191.6 7096.1 7099.8 7101.5 7107.1 7108.3 7062.8 7070.9 7079.1 7085.8 7101.5 6925.5 6929.1 6947.7 6952.3 6960.7 30379.7 31099.0 31202.8 31815.9 31921.6 2260.2 2320.2 2323.0 2329.4 2335.1 20550.4 20657.8 20910.2 20976.9 20977.5 2305.3 2308.0 2368.2 2377.2 2410.4 169501.2 170541.4 178753.0 195476.0 207571.0 22232.8 25338.5 25953.7 26406.9 27550.3 40027.2 41846.6 44847.0 44975.3 45004.5 11138.6 11168.3 11291.9 11349.2 11419.3 8864.1 9000.4 9003.3 9059.8 9079.4 11016.0 11564.7 12384.7 12391.9 12555.8 4252.8 4821.7 4824.9 4925.6 4947.0 948.5 953.5 969.7 1013.1 1013.8 21223.6 21989.9 22214.6 22428.6 22499.8 993.3 1013.1 1031.5 1043.8 1047.8 10366.9 10569.4 10892.6 11470.5 11743.1 491788.0 492080.0 493859.0 498124.0 548110.0 147923.0 149533.0 150561.0 150825.2 151794.0 942.8 1002.3 1142.8 1180.4 1224.0 21915.6 22979.7 23806.8 23865.5 23913.5 58560.7 68491.0 69909.0 70077.9 73461.3 75812.0 78519.6 93612.6 93693.0 94565.5 16097.4 16312.4 16462.6 16565.4 16591.4 16638.4 18212.6 18414.6 18584.5 18666.0 14709.6 14835.9 15205.9 15236.5 15258.6 12806.7 12832.9 13579.4 13647.2 13745.9 15181.8 15243.1 15359.5 15445.6 15541.5 16094.9 16227.8 16415.9 16467.0 16603.3 12825.6 14840.9 14913.9 14927.8 14978.4 02:55:37 INFO - PID 1661 | 02:55:37 INFO - PID 1661 | [#3] mootools.html Cycles:180 Average:8185.60 Median:2680.58 stddev:5419.07 (202.2%) stddev-sans-first:5410.19 02:55:37 INFO - PID 1661 | Values: 1386.9 1443.7 1472.5 1473.3 1486.9 13231.6 13300.9 13774.9 13795.9 13858.0 13296.0 13525.4 13538.9 13556.2 13567.8 2678.6 2720.3 2742.3 2743.9 2748.8 2667.5 2719.5 2721.8 2723.5 2728.4 2662.2 2682.6 2698.1 2698.4 2704.4 2648.6 2665.8 2669.2 2672.6 2683.2 2241.9 2321.7 2614.8 2633.2 2644.5 10587.3 13427.3 13434.7 13435.3 13462.9 6451.9 7309.9 7582.0 7592.0 7619.7 4521.0 4717.8 4779.4 4783.2 4803.6 1343.4 1377.3 1390.7 1395.8 1396.0 19563.2 19602.3 19695.5 19710.0 19754.3 5451.1 5516.7 6399.4 6434.7 6473.6 13932.2 13978.2 13979.7 13987.5 13993.6 7097.8 7351.7 7385.8 7395.9 7397.5 6627.0 6723.1 6761.3 6767.1 6874.7 4870.0 5046.5 5069.4 5093.9 5107.5 1457.7 1467.6 1469.8 1483.6 1490.3 765.6 815.4 817.3 825.6 843.2 7643.4 7814.1 7894.8 8064.2 8280.8 850.0 952.7 969.9 974.8 1010.2 4380.8 4430.3 4633.9 5458.2 5473.5 12471.9 18805.9 18814.8 18818.4 18832.4 17511.3 18138.9 18276.2 18313.3 18335.4 9177.0 9375.5 9381.1 9396.6 9401.7 6738.7 7493.4 8053.0 8143.3 8189.8 16161.4 16294.0 16364.5 16457.8 16689.2 15928.0 16371.2 16689.7 16945.8 17020.3 10720.1 10975.0 10988.7 10989.3 10998.3 10374.7 12422.8 12455.1 12556.9 12629.1 10200.9 10210.3 10294.0 10313.3 10349.5 7935.6 9925.9 10150.2 10163.1 10226.2 8129.9 8884.9 9163.3 9226.8 9273.8 6781.4 6785.4 6945.9 6977.0 7064.5 6923.2 7066.8 7084.6 7338.2 7428.8 02:55:37 INFO - PID 1661 | 02:55:37 INFO - PID 1661 | [#4] prototype.html Cycles:180 Average:5584.24 Median:6262.22 stddev:1746.81 (27.9%) stddev-sans-first:1707.70 02:55:37 INFO - PID 1661 | Values: 392.7 436.7 448.3 449.9 467.9 6597.7 6598.6 6600.7 6602.0 6623.9 6146.8 6407.5 6418.2 6442.0 6449.3 4747.0 4779.7 4800.5 4811.7 4817.6 4778.8 4783.4 4784.3 4807.0 4821.3 4155.1 4544.3 4747.5 4773.6 4774.4 4230.7 4766.4 4767.9 4789.9 4823.4 3423.8 4126.1 4154.2 4210.2 4222.1 5447.3 5857.6 6320.8 6336.4 6349.1 4742.7 5699.0 5701.4 5709.0 5752.6 5721.3 6770.5 6922.5 6933.8 6934.0 3665.5 4468.9 4761.6 4775.4 4833.5 5981.9 6027.5 7186.6 7295.3 7343.4 4063.2 4066.0 4797.8 4875.7 4892.4 4591.8 4940.3 4975.3 5015.2 5063.5 4915.6 5390.4 5466.6 5553.6 5695.9 6906.8 6960.9 6964.5 6984.7 7019.8 1433.3 1449.2 1691.4 1694.7 1695.6 927.2 1025.8 1088.4 1153.1 1155.6 5277.6 5750.4 5810.4 6246.0 6622.9 7158.4 7175.6 7207.3 7211.5 7211.9 6529.9 6551.6 6565.1 6567.4 6570.8 3484.6 3485.7 3760.0 3769.9 3775.5 7062.4 7228.7 7297.9 7447.5 7452.6 5778.4 6477.3 7221.6 7357.4 7408.4 6119.8 6520.6 6869.0 7077.4 7441.6 6278.4 7123.8 7208.9 7220.4 7226.3 6526.4 7089.3 7117.9 7122.9 7123.8 6195.6 6505.3 7354.9 7431.3 7455.5 4852.6 4854.5 4883.9 4900.9 4908.6 6078.0 7198.6 7207.5 7227.7 7237.7 5580.4 6100.8 6586.9 6944.7 6980.2 6186.4 6909.0 6916.0 6932.5 6950.9 6741.8 6748.2 6768.3 6782.5 6804.5 6427.0 6915.1 6917.5 6938.5 7034.3 6708.6 6716.4 6733.9 6748.0 6751.7 02:55:37 INFO - PID 1661 | 02:55:37 INFO - PID 1661 | [#5] yui.html Cycles:180 Average:25362.37 Median:17986.14 stddev:38299.63 (212.9%) stddev-sans-first:38362.73 02:55:37 INFO - PID 1661 | Values: 818.5 824.9 841.5 856.9 862.5 16417.7 16611.9 16813.2 16829.1 16874.9 13926.9 14957.9 15140.6 15377.9 16204.9 963.2 984.6 985.9 986.5 990.6 990.3 1011.9 1014.6 1015.0 1015.6 889.4 1045.8 1059.5 1061.5 1061.9 1050.1 1050.3 1050.6 1051.9 1054.4 771.7 774.3 774.6 775.2 780.0 17015.8 17025.3 17050.1 17214.6 17240.0 16699.1 16997.8 17395.0 17424.4 17434.3 14095.8 14367.4 14433.0 14447.9 14452.3 3566.9 3570.4 3580.8 3590.8 3591.4 105725.4 126414.7 127410.0 129173.0 129373.0 13362.6 13910.3 13912.5 13920.9 14102.0 26322.5 26672.0 26720.5 26793.7 26878.6 7448.4 7480.4 7483.5 7486.6 7502.7 3859.8 3936.4 3940.6 3945.7 3951.5 4290.3 4346.2 4412.8 4419.0 4422.2 941.7 947.9 965.1 965.7 969.2 645.2 658.1 662.1 685.5 687.6 9279.7 11441.5 11585.0 12304.9 12453.4 637.6 638.9 641.2 651.9 662.0 5705.7 5864.6 5901.2 5940.8 5989.6 145231.0 146357.5 147054.0 147547.8 148684.5 122648.4 124156.0 125648.4 126500.4 127791.0 81078.2 101076.5 103478.0 106003.9 108150.9 9896.5 11723.3 12199.8 12227.7 12297.6 43752.9 45326.3 46423.3 47070.0 47074.5 60931.1 79341.0 82031.0 83802.0 85707.6 17877.6 18190.9 18227.3 18272.9 18278.5 17491.6 17940.2 18629.4 19149.8 19417.2 17512.0 17947.6 18072.9 18453.2 18518.9 17343.2 17823.8 18241.3 18349.7 18385.5 14533.8 17497.8 17560.7 17843.7 18024.7 11917.2 11937.8 12143.0 12544.1 12591.5 12565.7 13112.9 13292.5 13706.0 14414.6 02:55:37 INFO - PID 1661 | -------- Summary: end -------- 02:55:37 INFO - PID 1661 | 02:55:37 INFO - TEST-INFO | 1661: exit 0 02:55:37 INFO - TEST-OK | dromaeo_css | took 1144225ms 02:55:37 INFO - TEST-START | kraken 02:55:37 INFO - operating with platform_type : mac_ 02:55:37 INFO - Initialising browser for kraken test... 02:55:37 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49233/getInfo.html -foreground -profile /var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/tmpvjemwF/profile 02:55:37 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49233/getInfo.html -foreground) 02:56:11 INFO - TEST-INFO | 1671: killed by SIGTERM 02:56:11 INFO - Browser initialized. 02:56:11 INFO - Running cycle 1/1 for kraken test... 02:56:11 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/tmpvjemwF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:56:13 INFO - PID 1675 | 2017-06-11 02:56:13.389 plugin-container[1676:15211] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x993f, name = 'com.apple.tsm.portname' 02:56:13 INFO - PID 1675 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:56:13 INFO - PID 1675 | 2017-06-11 02:56:13.390 plugin-container[1676:15211] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9f0f, name = 'com.apple.CFPasteboardClient' 02:56:13 INFO - PID 1675 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:56:13 INFO - PID 1675 | 2017-06-11 02:56:13.390 plugin-container[1676:15211] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:56:14 INFO - PID 1675 | 2017-06-11 02:56:14.195 plugin-container[1677:15256] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x962f, name = 'com.apple.tsm.portname' 02:56:14 INFO - PID 1675 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:56:14 INFO - PID 1675 | 2017-06-11 02:56:14.196 plugin-container[1677:15256] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d0b, name = 'com.apple.CFPasteboardClient' 02:56:14 INFO - PID 1675 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:56:14 INFO - PID 1675 | 2017-06-11 02:56:14.196 plugin-container[1677:15256] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:56:23 INFO - PID 1675 | RSS: Main: 252088320 02:56:23 INFO - PID 1675 | 02:56:26 INFO - PID 1675 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49233/tests/kraken/driver.html?testName=audio-beat-detection) 02:56:27 INFO - PID 1675 | RSS: Main: 265736192 02:56:27 INFO - PID 1675 | 02:56:29 INFO - PID 1675 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49233/tests/kraken/driver.html?testName=audio-dft) 02:56:30 INFO - PID 1675 | RSS: Main: 274669568 02:56:30 INFO - PID 1675 | 02:56:34 INFO - PID 1675 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49233/tests/kraken/driver.html?testName=audio-fft) 02:56:34 INFO - PID 1675 | RSS: Main: 277643264 02:56:34 INFO - PID 1675 | 02:56:36 INFO - PID 1675 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49233/tests/kraken/driver.html?testName=audio-oscillator) 02:56:37 INFO - PID 1675 | RSS: Main: 279490560 02:56:37 INFO - PID 1675 | 02:56:39 INFO - PID 1675 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49233/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:56:39 INFO - PID 1675 | RSS: Main: 280260608 02:56:39 INFO - PID 1675 | 02:56:42 INFO - PID 1675 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49233/tests/kraken/driver.html?testName=imaging-darkroom) 02:56:43 INFO - PID 1675 | RSS: Main: 284626944 02:56:43 INFO - PID 1675 | 02:56:46 INFO - PID 1675 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49233/tests/kraken/driver.html?testName=imaging-desaturate) 02:56:47 INFO - PID 1675 | RSS: Main: 286453760 02:56:47 INFO - PID 1675 | 02:56:50 INFO - PID 1675 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49233/tests/kraken/driver.html?testName=json-parse-financial) 02:56:50 INFO - PID 1675 | RSS: Main: 285163520 02:56:50 INFO - PID 1675 | 02:56:52 INFO - PID 1675 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49233/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:56:52 INFO - PID 1675 | RSS: Main: 285556736 02:56:52 INFO - PID 1675 | 02:56:54 INFO - PID 1675 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49233/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:56:55 INFO - PID 1675 | RSS: Main: 285294592 02:56:55 INFO - PID 1675 | 02:56:57 INFO - PID 1675 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49233/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:56:58 INFO - PID 1675 | RSS: Main: 285036544 02:56:58 INFO - PID 1675 | 02:57:01 INFO - PID 1675 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49233/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:57:01 INFO - PID 1675 | RSS: Main: 284864512 02:57:01 INFO - PID 1675 | 02:57:04 INFO - PID 1675 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49233/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:57:05 INFO - PID 1675 | RSS: Main: 284733440 02:57:05 INFO - PID 1675 | 02:57:07 INFO - PID 1675 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49233/tests/kraken/driver.html?testName=ai-astar) 02:57:07 INFO - PID 1675 | RSS: Main: 286699520 02:57:07 INFO - PID 1675 | 02:57:07 INFO - PID 1675 | __start_tp_report 02:57:07 INFO - PID 1675 | _x_x_mozilla_page_load 02:57:07 INFO - PID 1675 | _x_x_mozilla_page_load_details 02:57:07 INFO - PID 1675 | |i|pagename|runs| 02:57:07 INFO - PID 1675 | |0;ai-astar;112;117;112;120;118;117;118;128;168;160 02:57:07 INFO - PID 1675 | |1;audio-beat-detection;135;116;125;157;109;128;112;148;160;133 02:57:07 INFO - PID 1675 | |2;audio-dft;200;197;199;212;218;255;278;204;197;246 02:57:07 INFO - PID 1675 | |3;audio-fft;67;79;75;148;57;69;57;58;122;73 02:57:07 INFO - PID 1675 | |4;audio-oscillator;70;73;111;72;69;77;113;70;71;137 02:57:07 INFO - PID 1675 | |5;imaging-gaussian-blur;91;92;90;90;91;90;91;90;90;89 02:57:07 INFO - PID 1675 | |6;imaging-darkroom;100;128;144;99;99;98;98;98;100;100 02:57:07 INFO - PID 1675 | |7;imaging-desaturate;91;91;92;91;95;91;122;132;92;94 02:57:07 INFO - PID 1675 | |8;json-parse-financial;51;52;52;52;51;53;53;53;54;53 02:57:07 INFO - PID 1675 | |9;json-stringify-tinderbox;54;57;60;61;69;77;60;55;59;56 02:57:07 INFO - PID 1675 | |10;stanford-crypto-aes;100;109;96;83;86;84;90;90;105;87 02:57:07 INFO - PID 1675 | |11;stanford-crypto-ccm;145;147;128;136;156;152;143;139;136;138 02:57:07 INFO - PID 1675 | |12;stanford-crypto-pbkdf2;179;189;185;185;225;205;226;229;188;197 02:57:07 INFO - PID 1675 | |13;stanford-crypto-sha256-iterative;73;96;71;56;92;56;84;70;56;58 02:57:07 INFO - PID 1675 | __end_tp_report 02:57:07 INFO - PID 1675 | __start_cc_report 02:57:07 INFO - PID 1675 | _x_x_mozilla_cycle_collect,1229 02:57:07 INFO - PID 1675 | __end_cc_report 02:57:07 INFO - PID 1675 | __startTimestamp1497175027571__endTimestamp 02:57:07 INFO - PID 1675 | 02:57:07 INFO - PID 1675 | ------- Summary: start ------- 02:57:07 INFO - PID 1675 | Number of tests: 14 02:57:07 INFO - PID 1675 | 02:57:07 INFO - PID 1675 | [#0] ai-astar Cycles:10 Average:127.00 Median:119.00 stddev:20.09 (16.9%) stddev-sans-first:20.56 02:57:07 INFO - PID 1675 | Values: 112.0 117.0 112.0 120.0 118.0 117.0 118.0 128.0 168.0 160.0 02:57:07 INFO - PID 1675 | 02:57:07 INFO - PID 1675 | [#1] audio-beat-detection Cycles:10 Average:132.30 Median:134.00 stddev:18.02 (13.5%) stddev-sans-first:19.09 02:57:07 INFO - PID 1675 | Values: 135.0 116.0 125.0 157.0 109.0 128.0 112.0 148.0 160.0 133.0 02:57:07 INFO - PID 1675 | 02:57:07 INFO - PID 1675 | [#2] audio-dft Cycles:10 Average:220.60 Median:215.00 stddev:28.84 (13.4%) stddev-sans-first:29.61 02:57:07 INFO - PID 1675 | Values: 200.0 197.0 199.0 212.0 218.0 255.0 278.0 204.0 197.0 246.0 02:57:07 INFO - PID 1675 | 02:57:07 INFO - PID 1675 | [#3] audio-fft Cycles:10 Average:80.50 Median:68.00 stddev:30.35 (44.6%) stddev-sans-first:31.80 02:57:07 INFO - PID 1675 | Values: 67.0 79.0 75.0 148.0 57.0 69.0 57.0 58.0 122.0 73.0 02:57:07 INFO - PID 1675 | 02:57:07 INFO - PID 1675 | [#4] audio-oscillator Cycles:10 Average:86.30 Median:70.50 stddev:24.55 (34.8%) stddev-sans-first:25.33 02:57:07 INFO - PID 1675 | Values: 70.0 73.0 111.0 72.0 69.0 77.0 113.0 70.0 71.0 137.0 02:57:07 INFO - PID 1675 | 02:57:07 INFO - PID 1675 | [#5] imaging-gaussian-blur Cycles:10 Average:90.40 Median:90.50 stddev:0.84 (0.9%) stddev-sans-first:0.87 02:57:07 INFO - PID 1675 | Values: 91.0 92.0 90.0 90.0 91.0 90.0 91.0 90.0 90.0 89.0 02:57:07 INFO - PID 1675 | 02:57:07 INFO - PID 1675 | [#6] imaging-darkroom Cycles:10 Average:106.40 Median:98.00 stddev:16.07 (16.4%) stddev-sans-first:16.88 02:57:07 INFO - PID 1675 | Values: 100.0 128.0 144.0 99.0 99.0 98.0 98.0 98.0 100.0 100.0 02:57:07 INFO - PID 1675 | 02:57:07 INFO - PID 1675 | [#7] imaging-desaturate Cycles:10 Average:99.10 Median:91.50 stddev:14.96 (16.3%) stddev-sans-first:15.57 02:57:07 INFO - PID 1675 | Values: 91.0 91.0 92.0 91.0 95.0 91.0 122.0 132.0 92.0 94.0 02:57:07 INFO - PID 1675 | 02:57:07 INFO - PID 1675 | [#8] json-parse-financial Cycles:10 Average:52.40 Median:53.00 stddev:0.97 (1.8%) stddev-sans-first:0.88 02:57:07 INFO - PID 1675 | Values: 51.0 52.0 52.0 52.0 51.0 53.0 53.0 53.0 54.0 53.0 02:57:07 INFO - PID 1675 | 02:57:07 INFO - PID 1675 | [#9] json-stringify-tinderbox Cycles:10 Average:60.80 Median:60.00 stddev:7.08 (11.8%) stddev-sans-first:7.07 02:57:07 INFO - PID 1675 | Values: 54.0 57.0 60.0 61.0 69.0 77.0 60.0 55.0 59.0 56.0 02:57:07 INFO - PID 1675 | 02:57:07 INFO - PID 1675 | [#10] stanford-crypto-aes Cycles:10 Average:93.00 Median:86.50 stddev:9.08 (10.5%) stddev-sans-first:9.27 02:57:07 INFO - PID 1675 | Values: 100.0 109.0 96.0 83.0 86.0 84.0 90.0 90.0 105.0 87.0 02:57:07 INFO - PID 1675 | 02:57:07 INFO - PID 1675 | [#11] stanford-crypto-ccm Cycles:10 Average:142.00 Median:144.00 stddev:8.33 (5.8%) stddev-sans-first:8.76 02:57:07 INFO - PID 1675 | Values: 145.0 147.0 128.0 136.0 156.0 152.0 143.0 139.0 136.0 138.0 02:57:07 INFO - PID 1675 | 02:57:07 INFO - PID 1675 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:200.80 Median:201.00 stddev:19.22 (9.6%) stddev-sans-first:18.70 02:57:07 INFO - PID 1675 | Values: 179.0 189.0 185.0 185.0 225.0 205.0 226.0 229.0 188.0 197.0 02:57:07 INFO - PID 1675 | 02:57:07 INFO - PID 1675 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.20 Median:72.00 stddev:15.22 (21.1%) stddev-sans-first:16.12 02:57:07 INFO - PID 1675 | Values: 73.0 96.0 71.0 56.0 92.0 56.0 84.0 70.0 56.0 58.0 02:57:07 INFO - PID 1675 | -------- Summary: end -------- 02:57:07 INFO - PID 1675 | 02:57:08 INFO - TEST-INFO | 1675: exit 0 02:57:08 INFO - TEST-OK | kraken | took 90652ms 02:57:08 INFO - SUITE-END | took 1234s 02:57:08 INFO - Completed test suite (00:20:35) 02:57:08 INFO - javascript benchmark 02:57:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8470.744418123171, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3517.630648781878, 3585.8924232273007, 3594.442861356489, 3620.67413932746, 3634.1096431374317, 6135.232666669988, 6144.631322120676, 6247.406496382842, 6254.687265636717, 6256.311805701369, 5279.970405770867, 5797.3043234811885, 6191.81424557264, 6199.163112979746, 6294.0, 5270.1040823060175, 5439.836804895859, 5449.155380915964, 5458.208559758833, 5511.0, 5477.534409575171, 5531.810660707947, 5537.169424586304, 5579.776808927638, 5582.49757521823, 5366.6779993200535, 5428.91856622147, 5439.510444060014, 5471.8084867029465, 5478.506934375885, 5483.067878460672, 5503.532199763025, 5510.338759348863, 5530.170474428827, 5541.113421852484, 5431.049566325917, 5442.727863606804, 5456.208849716769, 5468.6171967962255, 5498.560115190775, 6135.87728245437, 6152.630842149485, 6197.287311959092, 6234.158388617524, 6250.812475625738, 6705.6982435790505, 6732.461403087741, 6745.055692203046, 6746.257911629717, 6749.493787965922, 6833.4191593714595, 6834.077399551046, 6846.657667116624, 6848.041274221613, 6863.588184708883, 6259.593126446766, 6304.306526282106, 6317.3366796486625, 6341.746330146835, 6342.334054924167, 6854.383105520427, 6880.415164710956, 6895.482838787111, 6913.516053876281, 6933.514653974174, 5579.721013949286, 6119.653676191231, 6465.2564955030775, 6509.511786616023, 6593.472522198213, 6781.4574834013165, 6788.694508747019, 6792.490563207681, 6823.112995310578, 6827.351401616838, 5644.8871022579315, 6263.467605253604, 6681.899771503431, 6690.431313338421, 6728.831779205559, 5591.440855914458, 6597.967010164918, 6686.83282917931, 6701.195856497252, 6716.093327400738, 5340.893182136413, 5341.278927344836, 5345.54562862161, 5355.116405793078, 5395.0, 4729.0, 4754.453237877619, 4755.619550435957, 4762.880927976828, 4781.497942716034, 6182.381761823872, 6242.09489624002, 6309.495240380758, 6318.6524741138755, 6361.840953976095, 6675.599464032077, 6695.096162018162, 6700.028495868072, 6702.564333318416, 6703.93296067033, 6246.312905580474, 6312.4634406076, 6315.684215789192, 6336.461400780985, 6337.112804207414, 5785.0454674979, 5853.590248682552, 5862.5896187267335, 5928.051511758098, 5995.070764031495, 5995.820125396245, 6951.582905025714, 6965.47758918073, 6978.162620485574, 7023.438124949889, 6709.228438729609, 6790.388865002174, 6796.626185559841, 6813.523053386413, 6817.829554260985, 6765.2896445872475, 6960.164780226405, 6967.7212911482975, 6976.5465244758925, 6990.126234220723, 5839.970800145972, 6315.336889626523, 6481.5786973848435, 6558.901616475852, 6675.666216689243, 5936.961031819328, 6596.60420374779, 6815.829604259933, 6825.385715285648, 6834.248232694499, 6345.1116843641, 6468.870622587616, 6571.277159512545, 6595.637239951657, 6598.538102332791, 6372.458341041063, 6557.803265902031, 6574.638394888327, 6580.5722628029025, 6606.966965165144, 6276.968615156895, 6318.0, 6353.777617783356, 6410.8076757697345, 6412.711427985659, 6575.704093315717, 6577.868442631217, 6582.17722784652, 6592.17597800275, 6606.669666516751, 6396.168498095218, 6414.03789431589, 6415.903761443676, 6424.486041116793, 6446.774362897276], "value": 6110.7508240304205, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [721.6865305144636, 788.6293442082219, 805.8670319397299, 1233.8642749297576, 1290.2903403128278, 37903.62096379039, 38784.418233726516, 38823.22353552931, 38836.0, 38939.41590876139, 32117.518237226403, 34340.96977090685, 38915.80542097292, 39477.60522394775, 39499.80250098753, 7165.85668286634, 7407.444441666869, 8364.539950302731, 8372.246497815195, 8389.748307550783, 8221.218984196492, 8261.884645572862, 8332.87506186663, 8333.041700204494, 8359.289460395874, 7442.255774422569, 8299.0, 8357.289630381425, 8372.28835548976, 8386.412951093425, 8315.376346773986, 8328.958355208246, 8335.54154521501, 8352.24829765321, 8378.874316885252, 8134.349252059821, 8150.429469937107, 8216.753497395088, 8220.712275070404, 8225.876611850827, 29248.56127158073, 36796.81601591975, 36845.447318290244, 36921.44617830735, 36986.630133698585, 3113.237256872058, 3537.7346698997676, 3576.481410195533, 3580.785152890809, 3589.9820500897326, 23744.76255237443, 29318.853405733047, 30001.249968750737, 30274.697253027407, 30323.93866214694, 2746.5056289867816, 3040.75673946086, 3068.5550595163577, 3095.8142511449387, 3117.0492999635103, 222362.0, 232544.0, 280268.0, 291793.0, 292771.0, 39761.602383976366, 48542.0, 48804.7559762206, 48921.02157956821, 49098.0, 51380.0, 51441.74279128655, 51446.742766285926, 55120.44879551233, 55791.721041394536, 14489.855101448851, 14524.49164279245, 14688.0, 14689.77965330521, 14715.117092974457, 11745.941270293593, 11772.470238839272, 11804.763904721858, 11839.408029598486, 11861.169718119649, 14042.578722638336, 14070.507532236323, 14146.009779315555, 14148.504802331869, 14295.856331493664, 5162.354705661793, 5179.98990196908, 6132.141500189977, 6211.65835879031, 6239.251289845157, 978.9167920726818, 1002.6039714312806, 1016.786474840277, 1050.7320633238476, 1050.9001644843727, 23030.884845576, 23256.0, 23435.179768708014, 24122.03511859506, 24225.27324180242, 897.7351681254046, 1091.143452389886, 1133.9546418143182, 1141.7088642396302, 1145.0438883532177, 11844.88155118455, 11941.64175074749, 11988.580399685969, 12371.6907077322, 12408.317542535247, 320776.0, 322002.0, 322553.0, 322695.0, 322774.0, 54876.72561637166, 55156.72421637866, 55212.0, 55230.0, 55240.61898452571, 1479.52655150353, 1487.21177775779, 1512.8260250071162, 1526.0996012352766, 1530.4260902161689, 22211.0, 22773.202937897095, 22892.885535572215, 23855.0, 24345.51308973846, 36349.27301453903, 38350.80824595859, 38375.61624383832, 38429.231415372094, 38501.61498385092, 271605.0, 271729.0, 271972.0, 272154.0, 272305.0, 36259.818700906326, 36482.27035459223, 36524.81737591295, 36542.086447839014, 36700.81649591735, 43273.13453730845, 43470.565294347914, 44018.559814402724, 45594.54405455903, 45848.0, 29185.708142918298, 29682.40635187241, 30087.548686769278, 30603.939606039217, 30727.846360768053, 26470.470590587694, 29987.0, 30022.84988575043, 31068.533971989986, 31642.10147391164, 29788.0, 36806.44790328201, 36970.89087327384, 36989.445158323186, 37221.813890930374, 21479.14083436645, 28094.76077549292, 28859.42281154323, 29471.82112715553, 29681.0, 24125.155619553232, 24384.878075609508, 24576.6313505294, 24660.753392465846, 24788.25635230946], "value": 17281.153789782453, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [479.1590758219327, 491.7148054128606, 499.11905486815755, 511.5856156513225, 516.1947362115103, 19430.805691943075, 20879.791202087974, 21543.6768448473, 22067.779322206792, 22972.8851355743, 25345.366365840862, 25915.0, 26013.869930650366, 26104.47791044178, 26145.084922027734, 7129.322714342129, 7255.818604534876, 7286.635668216594, 7293.234210407911, 7294.8905766413545, 6881.380675739182, 7106.964465177667, 7111.964440177792, 7129.108861392326, 7191.604461754601, 7096.077509923703, 7099.787006389816, 7101.538400004009, 7107.111611048619, 7108.253633368474, 7062.82342941428, 7070.8939365909555, 7079.07971963644, 7085.822854428629, 7101.538400004009, 6925.480588955848, 6929.099217101795, 6947.652617369111, 6952.304769523058, 6960.712268230393, 30379.69620303813, 31099.0, 31202.843985780153, 31815.88644397435, 31921.563529641222, 2260.1976298413992, 2320.2111282164146, 2323.0, 2329.3827135809024, 2335.1476711000364, 20550.38348849522, 20657.793422065737, 20910.163593456244, 20976.895115524323, 20977.475563110893, 2305.319930620471, 2307.9653805192934, 2368.230325144335, 2377.227401094651, 2410.4455975125643, 169501.15249423796, 170541.44187837193, 178753.0, 195476.0, 207570.9621451898, 22232.777672223394, 25338.493230135293, 25953.74046259551, 26406.867965660047, 27550.311242219104, 40027.19877605515, 41846.58153418427, 44847.0, 44975.32537011948, 45004.549954500035, 11138.554457821758, 11168.274062186094, 11291.854547635923, 11349.205555611194, 11419.257748246337, 8864.0692727264, 9000.414973026733, 9003.324750643727, 9059.773505662411, 9079.364444488823, 11015.953484418967, 11564.653060408202, 12384.690382740504, 12391.93804030974, 12555.811662825065, 4252.787360632018, 4821.710697358169, 4824.903501929942, 4925.605951523869, 4946.975265123651, 948.5020364308793, 953.5102743903975, 969.709087273815, 1013.1084645511902, 1013.8225810483283, 21223.575528489342, 21989.890050549966, 22214.55570888573, 22428.551428971656, 22499.775002250095, 993.3344659078434, 1013.1135256650431, 1031.4739482863642, 1043.8277684182176, 1047.8323468245044, 10366.91147429524, 10569.429772985555, 10892.61875834342, 11470.54117835294, 11743.11926605508, 491788.0, 492080.0, 493859.0, 498124.0, 548110.0, 147923.0, 149533.0, 150561.0, 150825.24587376992, 151794.0, 942.8403064231035, 1002.318423472046, 1142.828575713633, 1180.3744015671537, 1224.0330139190119, 21915.561688766455, 22979.6553051701, 23806.761932380454, 23865.522689545764, 23913.521729564964, 58560.707196465446, 68491.0, 69908.95136573057, 70077.9488307686, 73461.26538734544, 75812.0, 78519.60740196262, 93612.59581106153, 93693.0, 94565.5271723637, 16097.356105756107, 16312.429064982669, 16462.588435289214, 16565.42021029258, 16591.41930032443, 16638.417655382003, 18212.635747285247, 18414.631707366047, 18584.5353866149, 18666.0, 14709.55871323862, 14835.85164148388, 15205.923970380078, 15236.466723665062, 15258.618534536281, 12806.67983300425, 12832.871671283168, 13579.388927498088, 13647.249401282628, 13745.862541374458, 15181.848181518486, 15243.085414875142, 15359.462418815288, 15445.613859653598, 15541.533753987398, 16094.873358865236, 16227.837721623106, 16415.91792041032, 16467.0, 16603.252853621376, 12825.615231543068, 14840.925795370955, 14913.850861491246, 14927.776083358976, 14978.400863965318], "value": 13001.87651636312, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1386.9306534673267, 1443.6751730860565, 1472.5287907869479, 1473.2633683158424, 1486.8774075572942, 13231.603051908434, 13300.866991330084, 13774.862251377483, 13795.862041379583, 13858.0, 13296.002799790007, 13525.391357388917, 13538.864611353883, 13556.18662880228, 13567.796483052738, 2678.6116013178125, 2720.319920019995, 2742.3418379588857, 2743.876525556355, 2748.7938404619736, 2667.4531720997147, 2719.5376785946437, 2721.7958653100995, 2723.536998710214, 2728.358835673615, 2662.227953893378, 2682.557378032622, 2698.0961377938415, 2698.406350602874, 2704.445588654321, 2648.589468632365, 2665.760081592656, 2669.159214847317, 2672.5590277604174, 2683.18162960297, 2241.921532746346, 2321.721393432782, 2614.75159859813, 2633.2363614551755, 2644.5239856825765, 10587.311824731369, 13427.32863356838, 13434.723701248366, 13435.26106064166, 13462.865371346259, 6451.903221451682, 7309.853802923912, 7581.962090189514, 7591.962040189819, 7619.733309334147, 4520.977395113036, 4717.811287548494, 4779.402574678165, 4783.234682450826, 4803.6397270204525, 1343.3619030960313, 1377.3044612470737, 1390.7079513302117, 1395.7696979998288, 1395.9720805583934, 19563.21747130113, 19602.313919012908, 19695.507612309662, 19710.0, 19754.308599199103, 5451.073317536028, 5516.724163791794, 6399.380512963135, 6434.678266086677, 6473.611583305017, 13932.164070155823, 13978.231197284247, 13979.720405592034, 13987.510437134855, 13993.580192594238, 7097.787066388017, 7351.742689005966, 7385.81535461607, 7395.926040739631, 7397.519161254501, 6626.966865165643, 6723.125993620798, 6761.256261811197, 6767.120274364301, 6874.6562671867205, 4869.975650121727, 5046.47012063728, 5069.403138011515, 5093.9490605094215, 5107.514786095315, 1457.6574504991318, 1467.6404280951022, 1469.7942288079678, 1483.6439254579038, 1490.3368001239344, 765.6133652505449, 815.388458656008, 817.3134566963779, 825.5748289630845, 843.1905370843936, 7643.4267429942975, 7814.101378341564, 7894.802629934183, 8064.193580641889, 8280.8343833123, 849.995750021246, 952.7284723853807, 969.9030096990385, 974.8050389922043, 1010.242318261304, 4380.759058251827, 4430.291153415438, 4633.907321853544, 5458.208559758813, 5473.480019398151, 12471.93764031174, 18805.90597047006, 18814.811851881306, 18818.435446936615, 18832.435026949213, 17511.299548018193, 18138.909305453653, 18276.177572009292, 18313.267469301078, 18335.35826246075, 9176.95411522938, 9375.48434836077, 9381.108794664497, 9396.624135034659, 9401.717948461557, 6738.7304507819135, 7493.363064139391, 8052.959735201286, 8143.348532117535, 8189.812477190655, 16161.353545858034, 16294.0, 16364.509064728078, 16457.753133702765, 16689.248983795515, 15928.0, 16371.181440927669, 16689.666206675556, 16945.830541694424, 17020.319187232868, 10720.142388608736, 10975.0, 10988.725281868017, 10989.340639561651, 10998.34009959405, 10374.740631484274, 12422.751544968869, 12455.128141030404, 12556.87443125557, 12629.11596188258, 10200.948995254976, 10210.285280030326, 10294.0, 10313.27807053499, 10349.534270957976, 7935.642896069806, 9925.858526269283, 10150.23873209527, 10163.085322321027, 10226.181905447398, 8129.878051829109, 8884.866726998971, 9163.26693864476, 9226.815463690555, 9273.814523709352, 6781.42357899584, 6785.389314961677, 6945.861082778417, 6976.988336691253, 7064.540804847668, 6923.238443771081, 7066.752663656747, 7084.61034643079, 7338.192798792022, 7428.777136685908], "value": 6055.9387243761985, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [392.7486408698432, 436.66813221951327, 448.3230322213458, 449.92126377883864, 467.86309268477606, 6597.680463907213, 6598.58130501942, 6600.702968366423, 6601.9766936124915, 6623.86752264955, 6146.815595532141, 6407.48740100792, 6418.229812422516, 6442.0, 6449.322821103787, 4747.0268594938125, 4779.689320194185, 4800.4719480857075, 4811.711297322172, 4817.638677099214, 4778.78495467703, 4783.378160839086, 4784.282357646346, 4807.03859228154, 4821.252705830602, 4155.106652069804, 4544.341070544769, 4747.501512341206, 4773.57037866592, 4774.427068751755, 4230.725002874803, 4766.380370551823, 4767.856964291077, 4789.880252993703, 4823.421189457253, 3423.7945723256685, 4126.133511962492, 4154.210699967027, 4210.200061988213, 4222.050038741289, 5447.346318441812, 5857.619254748471, 6320.810375688737, 6336.3663633636725, 6349.111124442582, 4742.739149346785, 5699.00267453194, 5701.4298570142655, 5709.000924838137, 5752.568557358173, 5721.283614915509, 6770.52606317558, 6922.5154239203275, 6933.826654333682, 6933.965330173317, 3665.4685070664605, 4468.8882777930485, 4761.571458568746, 4775.403074615673, 4833.540813622701, 5981.910271345933, 6027.547933904974, 7186.604736739425, 7295.343419092307, 7343.375813055896, 4063.167050754588, 4065.9796701016307, 4797.808087676523, 4875.7074575525585, 4892.3884514435695, 4591.8392856249875, 4940.258961155855, 4975.303457515951, 5015.17249653803, 5063.506095885931, 4915.606751459874, 5390.353157621033, 5466.562674985991, 5553.583481238923, 5695.914561281584, 6906.758263460755, 6960.895586566105, 6964.547304425196, 6984.650767461708, 7019.824504387329, 1433.269032793283, 1449.1884544655027, 1691.4164613208422, 1694.6610677864476, 1695.6015336395938, 927.248928368024, 1025.8204814157493, 1088.3633074651418, 1153.140910022039, 1155.5608868630036, 5277.577793776489, 5750.424957504216, 5810.4189581041555, 6246.000639897595, 6622.867542649216, 7158.425146467873, 7175.605341706152, 7207.315305046012, 7211.495195336276, 7211.927880721126, 6529.934700652932, 6551.639659818756, 6565.063306324646, 6567.44176744982, 6570.835729106715, 3484.564429446319, 3485.721142308609, 3759.962400376016, 3769.905752356213, 3775.5469343678933, 7062.435005199674, 7228.710851565878, 7297.89053164203, 7447.515911465737, 7452.627368631547, 5778.3643799182055, 6477.287498375175, 7221.638918054181, 7357.41140708742, 7408.407327413794, 6119.785807496805, 6520.576162549419, 6869.003994420881, 7077.363037326665, 7441.590712510756, 6278.4349408551625, 7123.786286411416, 7208.89186662211, 7220.350168484862, 7226.313500217471, 6526.4452521536205, 7089.291070892869, 7117.857642847218, 7122.857542849218, 7123.7506687265695, 6195.566310358412, 6505.2844187138435, 7354.8896766549615, 7431.331180193592, 7455.515391499535, 4852.636052296021, 4854.514548545118, 4883.89992303313, 4900.852974410774, 4908.582770464551, 6077.969610152098, 7198.604076775828, 7207.459440541876, 7227.747028853965, 7237.670671491173, 5580.441955804549, 6100.755969761338, 6586.868262634816, 6944.687489062903, 6980.197277313174, 6186.412290832363, 6909.0, 6916.0, 6932.514723969273, 6950.8609827804175, 6741.764038258833, 6748.156480439945, 6768.323167683192, 6782.525223234326, 6804.489663275175, 6427.0, 6915.101036865175, 6917.481188910751, 6938.479614029069, 7034.261402552863, 6708.6310252936555, 6716.429103526059, 6733.898991515033, 6748.0, 6751.696173672295], "value": 5043.479230300721, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [818.5252553518952, 824.9298809601183, 841.5119230846113, 856.8518185631253, 862.4523427623459, 16417.671646567076, 16611.91694041528, 16813.243404046818, 16829.07440090796, 16874.915625421872, 13926.930365348184, 14957.878159138081, 15140.621484462894, 15377.92311038446, 16204.918975405137, 963.1716723617683, 984.5913945712521, 985.9309848310585, 986.4919566423298, 990.5641517732175, 990.2869933647762, 1011.9139873110795, 1014.6093753904763, 1014.9898501015006, 1015.5988384588084, 889.3552174673375, 1045.8483519889612, 1059.5443959097586, 1061.4851796878509, 1061.9362838229692, 1050.0864248104199, 1050.3015494696017, 1050.6007717067541, 1051.8842927277992, 1054.4095306628312, 771.726037256777, 774.2837874965691, 774.5631852912821, 775.2209029924945, 779.9532028078333, 17015.808893377467, 17025.318987240495, 17050.06224657643, 17214.569635759082, 17240.0, 16699.08155051472, 16997.83002169975, 17395.04327262, 17424.390146344816, 17434.302627894867, 14095.78856317156, 14367.425302987867, 14433.0, 14447.927760361132, 14452.277386130756, 3566.946495802565, 3570.410882204433, 3580.785152890835, 3590.784552926833, 3591.407417776064, 105725.41411878828, 126414.73585264213, 127410.0, 129173.0, 129373.0, 13362.599122026355, 13910.304484775721, 13912.51306204278, 13920.930395347958, 14102.0, 26322.47355052888, 26672.0, 26720.465590688076, 26793.732062679515, 26878.5968210477, 7448.366888814403, 7480.438967077382, 7483.513571617937, 7486.6256687166515, 7502.6623801929045, 3859.7877116758286, 3936.4488971544006, 3940.566537680853, 3945.743526670815, 3951.4863067801, 4290.313549832074, 4346.21768081742, 4412.845550405785, 4419.049904270597, 4422.181896349186, 941.6892425499568, 947.9477779664567, 965.1169180200038, 965.6764983730388, 969.1665167955553, 645.1548471502347, 658.0754040573003, 662.1292999705372, 685.5132855672429, 687.6286805125177, 9279.72160835176, 11441.485133169028, 11585.0, 12304.938475307745, 12453.377331133406, 637.5951270942985, 638.8786130635165, 641.2401304454237, 651.8859199640139, 661.9602823830586, 5705.657660540381, 5864.648121112747, 5901.232839730807, 5940.82177534666, 5989.55078369124, 145231.0, 146357.53642463652, 147054.0, 147547.83582574557, 148684.51315486923, 122648.38675806743, 124156.0, 125648.37175814062, 126500.36749816193, 127791.0, 81078.18921810706, 101076.49461752645, 103478.0, 106003.93996060095, 108150.91849081409, 9896.45569493656, 11723.296602203895, 12199.817002745143, 12227.69430764238, 12297.631071067883, 43752.90617734582, 45326.32010519779, 46423.30365044459, 47070.0, 47074.52925470839, 60931.08603370864, 79341.0, 82031.0, 83802.0, 85707.57146214478, 17877.553061173574, 18190.90904545469, 18227.270909163486, 18272.908635456737, 18278.543036424195, 17491.56271093233, 17940.192691329183, 18629.44111676652, 19149.808501914802, 19417.2233110674, 17512.0, 17947.551311217325, 18072.90963545174, 18453.16960736797, 18518.907405462887, 17343.219555120304, 17823.821761782216, 18241.270349186416, 18349.72475412843, 18385.540361490534, 14533.78199327032, 17497.82502174962, 17560.73658895092, 17843.732344014592, 18024.729629055837, 11917.16579839403, 11937.82093268619, 12143.0, 12544.059195560552, 12591.496340146292, 12565.74868502643, 13112.934435327763, 13292.534761283308, 13706.0, 14414.639634009234], "value": 8808.770790567043, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1562.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 117.0, 112.0, 120.0, 118.0, 117.0, 118.0, 128.0, 168.0, 160.0], "value": 127.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 116.0, 125.0, 157.0, 109.0, 128.0, 112.0, 148.0, 160.0, 133.0], "value": 132.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 197.0, 199.0, 212.0, 218.0, 255.0, 278.0, 204.0, 197.0, 246.0], "value": 220.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 79.0, 75.0, 148.0, 57.0, 69.0, 57.0, 58.0, 122.0, 73.0], "value": 80.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 73.0, 111.0, 72.0, 69.0, 77.0, 113.0, 70.0, 71.0, 137.0], "value": 86.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 92.0, 90.0, 90.0, 91.0, 90.0, 91.0, 90.0, 90.0, 89.0], "value": 90.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 128.0, 144.0, 99.0, 99.0, 98.0, 98.0, 98.0, 100.0, 100.0], "value": 106.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 91.0, 92.0, 91.0, 95.0, 91.0, 122.0, 132.0, 92.0, 94.0], "value": 99.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 52.0, 52.0, 52.0, 51.0, 53.0, 53.0, 53.0, 54.0, 53.0], "value": 52.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 57.0, 60.0, 61.0, 69.0, 77.0, 60.0, 55.0, 59.0, 56.0], "value": 60.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 109.0, 96.0, 83.0, 86.0, 84.0, 90.0, 90.0, 105.0, 87.0], "value": 93.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 147.0, 128.0, 136.0, 156.0, 152.0, 143.0, 139.0, 136.0, 138.0], "value": 142.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 189.0, 185.0, 185.0, 225.0, 205.0, 226.0, 229.0, 188.0, 197.0], "value": 200.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 96.0, 71.0, 56.0, 92.0, 56.0, 84.0, 70.0, 56.0, 58.0], "value": 71.2, "unit": "score"}]}]} 02:57:08 INFO - Return code: 0 02:57:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:57:08 INFO - # TBPL SUCCESS # 02:57:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:57:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:57:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:57:08 INFO - 2017-06-11 02:57:08.995 screenresolution[1683:15659] starting screenresolution argv=screenresolution get 02:57:08 INFO - 2017-06-11 02:57:08.997 screenresolution[1683:15659] Display 0: 1600x1200x32@60 02:57:09 INFO - 2017-06-11 02:57:09.004 screenresolution[1684:15662] starting screenresolution argv=screenresolution list 02:57:09 INFO - Available Modes on Display 0 02:57:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:57:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:57:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:57:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:57:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:57:09 INFO - Intel Iris: 02:57:09 INFO - Chipset Model: Intel Iris 02:57:09 INFO - Type: GPU 02:57:09 INFO - Bus: Built-In 02:57:09 INFO - VRAM (Dynamic, Max): 1536 MB 02:57:09 INFO - Vendor: Intel (0x8086) 02:57:09 INFO - Device ID: 0x0a2e 02:57:09 INFO - Revision ID: 0x0009 02:57:09 INFO - Displays: 02:57:09 INFO - Display: 02:57:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:57:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:57:09 INFO - Display Serial Number: 1600x1200 60 02:57:09 INFO - Main Display: Yes 02:57:09 INFO - Mirror: Off 02:57:09 INFO - Online: Yes 02:57:09 INFO - Rotation: Supported 02:57:09 INFO - Return code: 0 02:57:09 INFO - Running post-action listener: _resource_record_post_action 02:57:09 INFO - [mozharness: 2017-06-11 09:57:09.247303Z] Finished run-tests step (success) 02:57:09 INFO - Running post-run listener: _resource_record_post_run 02:57:09 INFO - Total resource usage - Wall time: 1258s; CPU: 53.0%; Read bytes: 34504192; Write bytes: 578228224; Read time: 235; Write time: 1801 02:57:09 INFO - TinderboxPrint: CPU usage
53.4% 02:57:09 INFO - TinderboxPrint: I/O read bytes / time
34,504,192 / 235 02:57:09 INFO - TinderboxPrint: I/O write bytes / time
578,228,224 / 1,801 02:57:09 INFO - TinderboxPrint: CPU idle
2,345.9 (46.6%) 02:57:09 INFO - TinderboxPrint: CPU system
1,026.0 (20.4%) 02:57:09 INFO - TinderboxPrint: CPU user
1,661.8 (33.0%) 02:57:09 INFO - TinderboxPrint: Swap in / out
282,701,824 / 0 02:57:09 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 241082880; Write bytes: 236244480; Read time: 17475; Write time: 517 02:57:09 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 02:57:09 INFO - run-tests - Wall time: 1236s; CPU: 53.0%; Read bytes: 32607744; Write bytes: 322667008; Read time: 214; Write time: 1232 02:57:09 INFO - Running post-run listener: _upload_blobber_files 02:57:09 INFO - Blob upload gear active. 02:57:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:57:09 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 02:57:09 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'] 02:57:09 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 02:57:10 INFO - (blobuploader) - INFO - Open directory for files ... 02:57:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:57:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:57:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:57:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:57:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:57:11 INFO - (blobuploader) - INFO - Done attempting. 02:57:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:57:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:57:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:57:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:57:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:57:12 INFO - (blobuploader) - INFO - Done attempting. 02:57:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 02:57:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:57:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:57:13 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 02:57:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:57:13 INFO - (blobuploader) - INFO - Done attempting. 02:57:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:57:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:57:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:57:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:57:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:57:15 INFO - (blobuploader) - INFO - Done attempting. 02:57:15 INFO - (blobuploader) - INFO - Iteration through files over. 02:57:15 INFO - Return code: 0 02:57:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:57:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:57:15 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1c5e60620a99ae2e570e6767e1204e085baa54be8eb3f540fb2ed954f51002f52e2056116cc521551aa4ccefe4fbb9d914006150077b763947241f91815afa47", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca900e422bae1c09c47981071cc6641bd1e588c094c160db7e7800b28974981ee519fc0d3a369f1a895871dce6dc5aa487f6708750ac2449951f2d418bab870b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ed38be48db9891ef454ae43b46114bf892a3441eb75c520a50931d6c3c5127d13956c8d860968acbb12aff080085f893006d965dee719b7902f2c1e1c958e5c3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8bd36f61fb9541714e1a8c317f73cae93f174e92225822d3728c000385679f7d224a158824a457c168a279151e40c75afc5ccaaeefa78d27c1c809326b46f255"} 02:57:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:57:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:57:15 INFO - Contents: 02:57:15 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1c5e60620a99ae2e570e6767e1204e085baa54be8eb3f540fb2ed954f51002f52e2056116cc521551aa4ccefe4fbb9d914006150077b763947241f91815afa47", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca900e422bae1c09c47981071cc6641bd1e588c094c160db7e7800b28974981ee519fc0d3a369f1a895871dce6dc5aa487f6708750ac2449951f2d418bab870b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ed38be48db9891ef454ae43b46114bf892a3441eb75c520a50931d6c3c5127d13956c8d860968acbb12aff080085f893006d965dee719b7902f2c1e1c958e5c3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8bd36f61fb9541714e1a8c317f73cae93f174e92225822d3728c000385679f7d224a158824a457c168a279151e40c75afc5ccaaeefa78d27c1c809326b46f255"} 02:57:15 INFO - Running post-run listener: copy_logs_to_upload_dir 02:57:15 INFO - Copying logs to upload dir... 02:57:15 INFO - mkdir: /builds/slave/test/build/upload/logs 02:57:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1339.166223 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-06-11 02:57:15.243443) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-11 02:57:15.246566) ========= 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.lcJIUdkDHT/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.4QtbeIopIn/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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/1c5e60620a99ae2e570e6767e1204e085baa54be8eb3f540fb2ed954f51002f52e2056116cc521551aa4ccefe4fbb9d914006150077b763947241f91815afa47", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca900e422bae1c09c47981071cc6641bd1e588c094c160db7e7800b28974981ee519fc0d3a369f1a895871dce6dc5aa487f6708750ac2449951f2d418bab870b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ed38be48db9891ef454ae43b46114bf892a3441eb75c520a50931d6c3c5127d13956c8d860968acbb12aff080085f893006d965dee719b7902f2c1e1c958e5c3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8bd36f61fb9541714e1a8c317f73cae93f174e92225822d3728c000385679f7d224a158824a457c168a279151e40c75afc5ccaaeefa78d27c1c809326b46f255"} build_url:https://queue.taskcluster.net/v1/task/VDZSI2FlSu2sxA6eEWR86A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010630 build_url: 'https://queue.taskcluster.net/v1/task/VDZSI2FlSu2sxA6eEWR86A/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/1c5e60620a99ae2e570e6767e1204e085baa54be8eb3f540fb2ed954f51002f52e2056116cc521551aa4ccefe4fbb9d914006150077b763947241f91815afa47", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca900e422bae1c09c47981071cc6641bd1e588c094c160db7e7800b28974981ee519fc0d3a369f1a895871dce6dc5aa487f6708750ac2449951f2d418bab870b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ed38be48db9891ef454ae43b46114bf892a3441eb75c520a50931d6c3c5127d13956c8d860968acbb12aff080085f893006d965dee719b7902f2c1e1c958e5c3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8bd36f61fb9541714e1a8c317f73cae93f174e92225822d3728c000385679f7d224a158824a457c168a279151e40c75afc5ccaaeefa78d27c1c809326b46f255"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-11 02:57:15.279292) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-11 02:57:15.279642) ========= 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.lcJIUdkDHT/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.4QtbeIopIn/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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.005079 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-11 02:57:15.327955) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-11 02:57:15.331297) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-11 02:57:15.331639) ========= ========= Total master_lag: 0.17 =========