builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s slave: t-yosemite-r7-0023 starttime: 1497900947.06 results: success (0) buildid: 20170619105559 builduid: 3b1e343e008d4d0ab685cf5886337798 revision: e9004c186de84e98ac082df097348bfa279ee498 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-19 12:35:47.060234) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-19 12:35:47.060783) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-19 12:35:47.061108) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-19 12:35:47.092436) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-19 12:35:47.092878) ========= 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.osN7GbXiBw/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.1ix1Bfa072/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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-19 12:35:47-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.00M=0.001s 2017-06-19 12:35:47 (8.00 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.092829 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-19 12:35:47.207726) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-19 12:35:47.208090) ========= 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.osN7GbXiBw/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.1ix1Bfa072/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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.021643 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-19 12:35:47.257632) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-19 12:35:47.258034) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev e9004c186de84e98ac082df097348bfa279ee498 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev e9004c186de84e98ac082df097348bfa279ee498 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.osN7GbXiBw/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.1ix1Bfa072/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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-19 12:35:47,322 truncating revision to first 12 chars 2017-06-19 12:35:47,322 Setting DEBUG logging. 2017-06-19 12:35:47,322 attempt 1/10 2017-06-19 12:35:47,322 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/e9004c186de8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-19 12:35:48,258 unpacking tar archive at: mozilla-beta-e9004c186de8/testing/mozharness/ program finished with exit code 0 elapsedTime=1.199646 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-19 12:35:48.480260) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-19 12:35:48.480647) ========= script_repo_revision: e9004c186de84e98ac082df097348bfa279ee498 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-19 12:35:48.481091) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-19 12:35:48.481411) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-19 12:35:48.499407) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 17 secs) (at 2017-06-19 12:35:48.499844) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.osN7GbXiBw/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.1ix1Bfa072/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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 12:35:48 INFO - MultiFileLogger online at 20170619 12:35:48 in /builds/slave/test 12:35:48 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 12:35:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:35:48 INFO - {'append_to_log': False, 12:35:48 INFO - 'base_work_dir': '/builds/slave/test', 12:35:48 INFO - 'blob_upload_branch': 'Mozilla-Beta', 12:35:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:35:48 INFO - 'branch': 'Mozilla-Beta', 12:35:48 INFO - 'buildbot_json_path': 'buildprops.json', 12:35:48 INFO - 'config_files': ('talos/mac_config.py',), 12:35:48 INFO - 'default_actions': ('clobber', 12:35:48 INFO - 'read-buildbot-config', 12:35:48 INFO - 'download-and-extract', 12:35:48 INFO - 'populate-webroot', 12:35:48 INFO - 'create-virtualenv', 12:35:48 INFO - 'install', 12:35:48 INFO - 'setup-mitmproxy', 12:35:48 INFO - 'run-tests'), 12:35:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:35:48 INFO - 'download_minidump_stackwalk': True, 12:35:48 INFO - 'download_symbols': 'ondemand', 12:35:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:35:48 INFO - 'tooltool.py': '/tools/tooltool.py', 12:35:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:35:48 INFO - '/tools/misc-python/virtualenv.py')}, 12:35:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:35:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:35:48 INFO - 'gecko_profile': False, 12:35:48 INFO - 'gecko_profile_interval': 0, 12:35:48 INFO - 'installer_path': 'installer.exe', 12:35:48 INFO - 'log_level': 'info', 12:35:48 INFO - 'log_name': 'talos', 12:35:48 INFO - 'log_to_console': True, 12:35:48 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:35:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:35:48 INFO - 'opt_config_files': (), 12:35:48 INFO - 'pip_index': False, 12:35:48 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:35:48 INFO - 'cmd': ('bash', 12:35:48 INFO - '-c', 12:35:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:35:48 INFO - 'enabled': True, 12:35:48 INFO - 'halt_on_failure': False, 12:35:48 INFO - 'name': 'check_screen_resolution'},), 12:35:48 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:35:48 INFO - 'cmd': ('bash', 12:35:48 INFO - '-c', 12:35:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:35:48 INFO - 'enabled': True, 12:35:48 INFO - 'halt_on_failure': False, 12:35:48 INFO - 'name': 'check_screen_resolution'},), 12:35:48 INFO - 'run_cmd_checks_enabled': True, 12:35:48 INFO - 'suite': 'dromaeojs-e10s', 12:35:48 INFO - 'system_bits': '32', 12:35:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:35:48 INFO - 'title': 't-yosemite-r7-0023', 12:35:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:35:48 INFO - 'use_talos_json': True, 12:35:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:35:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:35:48 INFO - 'work_dir': 'build'} 12:35:48 INFO - [mozharness: 2017-06-19 19:35:48.695922Z] Running clobber step. 12:35:48 INFO - Running pre-action listener: _resource_record_pre_action 12:35:48 INFO - Running main action method: clobber 12:35:48 INFO - rmtree: /builds/slave/test/build 12:35:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:35:50 INFO - Running post-action listener: _resource_record_post_action 12:35:50 INFO - [mozharness: 2017-06-19 19:35:50.453103Z] Finished clobber step (success) 12:35:50 INFO - [mozharness: 2017-06-19 19:35:50.453260Z] Running read-buildbot-config step. 12:35:50 INFO - Running pre-action listener: _resource_record_pre_action 12:35:50 INFO - Running main action method: read_buildbot_config 12:35:50 INFO - Using buildbot properties: 12:35:50 INFO - { 12:35:50 INFO - "project": "", 12:35:50 INFO - "product": "firefox", 12:35:50 INFO - "script_repo_revision": "production", 12:35:50 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s", 12:35:50 INFO - "repository": "", 12:35:50 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs-e10s", 12:35:50 INFO - "buildid": "20170619105559", 12:35:50 INFO - "pgo_build": "False", 12:35:50 INFO - "basedir": "/builds/slave/test", 12:35:50 INFO - "buildnumber": 2, 12:35:50 INFO - "slavename": "t-yosemite-r7-0023", 12:35:50 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 12:35:50 INFO - "platform": "yosemite_r7_devedition", 12:35:50 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 12:35:50 INFO - "branch": "mozilla-beta", 12:35:50 INFO - "repo_path": "releases/mozilla-beta", 12:35:50 INFO - "revision": "e9004c186de84e98ac082df097348bfa279ee498", 12:35:50 INFO - "stage_platform": "macosx64-devedition", 12:35:50 INFO - "builduid": "3b1e343e008d4d0ab685cf5886337798", 12:35:50 INFO - "slavebuilddir": "test" 12:35:50 INFO - } 12:35:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/JjnqEl7tQoC9uC932cL50g/artifacts/public/build/firefox-55.0.en-US.mac.dmg. 12:35:50 INFO - Running post-action listener: _resource_record_post_action 12:35:50 INFO - [mozharness: 2017-06-19 19:35:50.456678Z] Finished read-buildbot-config step (success) 12:35:50 INFO - [mozharness: 2017-06-19 19:35:50.456814Z] Running download-and-extract step. 12:35:50 INFO - Running pre-action listener: _resource_record_pre_action 12:35:50 INFO - Running main action method: download_and_extract 12:35:50 INFO - mkdir: /builds/slave/test/build/tests 12:35:50 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')]} 12:35:50 INFO - trying https://queue.taskcluster.net/v1/task/JjnqEl7tQoC9uC932cL50g/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json 12:35:50 INFO - Downloading https://queue.taskcluster.net/v1/task/JjnqEl7tQoC9uC932cL50g/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 12:35:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JjnqEl7tQoC9uC932cL50g/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json'}, attempt #1 12:35:51 INFO - Downloaded 1290 bytes. 12:35:51 INFO - Reading from file /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 12:35:51 INFO - Using the following test package requirements: 12:35:51 INFO - {u'awsy': [u'firefox-55.0.en-US.mac.common.tests.zip', 12:35:51 INFO - u'firefox-55.0.en-US.mac.awsy.tests.zip'], 12:35:51 INFO - u'common': [u'firefox-55.0.en-US.mac.common.tests.zip'], 12:35:51 INFO - u'cppunittest': [u'firefox-55.0.en-US.mac.common.tests.zip', 12:35:51 INFO - u'firefox-55.0.en-US.mac.cppunittest.tests.zip'], 12:35:51 INFO - u'gtest': [u'firefox-55.0.en-US.mac.common.tests.zip', 12:35:51 INFO - u'firefox-55.0.en-US.mac.gtest.tests.zip'], 12:35:51 INFO - u'jittest': [u'firefox-55.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 12:35:51 INFO - u'mochitest': [u'firefox-55.0.en-US.mac.common.tests.zip', 12:35:51 INFO - u'firefox-55.0.en-US.mac.mochitest.tests.zip'], 12:35:51 INFO - u'mozbase': [u'firefox-55.0.en-US.mac.common.tests.zip'], 12:35:51 INFO - u'reftest': [u'firefox-55.0.en-US.mac.common.tests.zip', 12:35:51 INFO - u'firefox-55.0.en-US.mac.reftest.tests.zip'], 12:35:51 INFO - u'talos': [u'firefox-55.0.en-US.mac.common.tests.zip', 12:35:51 INFO - u'firefox-55.0.en-US.mac.talos.tests.zip'], 12:35:51 INFO - u'web-platform': [u'firefox-55.0.en-US.mac.common.tests.zip', 12:35:51 INFO - u'firefox-55.0.en-US.mac.web-platform.tests.tar.gz'], 12:35:51 INFO - u'xpcshell': [u'firefox-55.0.en-US.mac.common.tests.zip', 12:35:51 INFO - u'firefox-55.0.en-US.mac.xpcshell.tests.zip']} 12:35:51 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip'] for test suite category: common 12:35:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JjnqEl7tQoC9uC932cL50g/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 12:35:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JjnqEl7tQoC9uC932cL50g/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 12:35:51 INFO - Fetch https://queue.taskcluster.net/v1/task/JjnqEl7tQoC9uC932cL50g/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 12:35:53 INFO - Content-Length response header: 59545028 12:35:53 INFO - Bytes received: 59545028 12:36:11 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip', u'firefox-55.0.en-US.mac.talos.tests.zip'] for test suite category: talos 12:36:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JjnqEl7tQoC9uC932cL50g/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 12:36:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JjnqEl7tQoC9uC932cL50g/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 12:36:11 INFO - Fetch https://queue.taskcluster.net/v1/task/JjnqEl7tQoC9uC932cL50g/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 12:36:14 INFO - Content-Length response header: 59545028 12:36:14 INFO - Bytes received: 59545028 12:36:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JjnqEl7tQoC9uC932cL50g/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip 12:36:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JjnqEl7tQoC9uC932cL50g/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip'}, attempt #1 12:36:32 INFO - Fetch https://queue.taskcluster.net/v1/task/JjnqEl7tQoC9uC932cL50g/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip into memory 12:36:32 INFO - Content-Length response header: 14454470 12:36:32 INFO - Bytes received: 14454470 12: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')]} 12:36:33 INFO - trying https://queue.taskcluster.net/v1/task/JjnqEl7tQoC9uC932cL50g/artifacts/public/build/firefox-55.0.en-US.mac.dmg 12:36:33 INFO - Downloading https://queue.taskcluster.net/v1/task/JjnqEl7tQoC9uC932cL50g/artifacts/public/build/firefox-55.0.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0.en-US.mac.dmg 12:36:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JjnqEl7tQoC9uC932cL50g/artifacts/public/build/firefox-55.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0.en-US.mac.dmg'}, attempt #1 12:36:35 INFO - Downloaded 66850314 bytes. 12:36:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JjnqEl7tQoC9uC932cL50g/artifacts/public/build/firefox-55.0.en-US.mac.dmg 12:36:35 INFO - mkdir: /builds/slave/test/properties 12:36:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:36:35 INFO - Writing to file /builds/slave/test/properties/build_url 12:36:35 INFO - Contents: 12:36:35 INFO - build_url:https://queue.taskcluster.net/v1/task/JjnqEl7tQoC9uC932cL50g/artifacts/public/build/firefox-55.0.en-US.mac.dmg 12:36:35 INFO - Running post-action listener: _resource_record_post_action 12:36:35 INFO - Running post-action listener: set_extra_try_arguments 12:36:35 INFO - [mozharness: 2017-06-19 19:36:35.807229Z] Finished download-and-extract step (success) 12:36:35 INFO - [mozharness: 2017-06-19 19:36:35.807401Z] Running populate-webroot step. 12:36:35 INFO - Running pre-action listener: _resource_record_pre_action 12:36:35 INFO - Running main action method: populate_webroot 12:36:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:36:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:36:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:36:35 INFO - '--tppagecycles', 12:36:35 INFO - '7'], 12:36:35 INFO - 'tests': ['tsvgm']}}, 12:36:35 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:36:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:36:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:36:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:36:35 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:36:35 INFO - 'talos_options': ['--disable-e10s'], 12:36:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:36:35 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:36:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:36:35 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:36:35 INFO - 'talos_options': ['--disable-e10s'], 12:36:35 INFO - 'tests': ['damp', 'tps']}, 12:36:35 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:36:35 INFO - 'tests': ['damp', 'tps']}, 12:36:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:36:35 INFO - 'tests': ['dromaeo_dom']}, 12:36:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:36:35 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:36:35 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 12:36:35 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 12:36:35 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 12:36:35 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 12:36:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:36:35 INFO - 'tests': ['a11yr', 12:36:35 INFO - 'ts_paint', 12:36:35 INFO - 'tpaint', 12:36:35 INFO - 'sessionrestore', 12:36:35 INFO - 'sessionrestore_no_auto_restore', 12:36:35 INFO - 'tabpaint']}, 12:36:35 INFO - 'other-e10s': {'tests': ['a11yr', 12:36:35 INFO - 'ts_paint', 12:36:35 INFO - 'tpaint', 12:36:35 INFO - 'sessionrestore', 12:36:35 INFO - 'sessionrestore_no_auto_restore', 12:36:35 INFO - 'tabpaint']}, 12:36:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:36:35 INFO - 'ts_paint', 12:36:35 INFO - 'tpaint', 12:36:35 INFO - 'sessionrestore', 12:36:35 INFO - 'sessionrestore_no_auto_restore', 12:36:35 INFO - 'tabpaint']}, 12:36:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:36:35 INFO - 'ts_paint', 12:36:35 INFO - 'tpaint', 12:36:35 INFO - 'sessionrestore', 12:36:35 INFO - 'sessionrestore_no_auto_restore', 12:36:35 INFO - 'tabpaint']}, 12:36:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:36:35 INFO - 'tests': ['a11yr', 12:36:35 INFO - 'ts_paint', 12:36:35 INFO - 'tpaint', 12:36:35 INFO - 'sessionrestore', 12:36:35 INFO - 'sessionrestore_no_auto_restore', 12:36:35 INFO - 'tabpaint']}, 12:36:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:36:35 INFO - 'tests': ['a11yr', 12:36:35 INFO - 'ts_paint', 12:36:35 INFO - 'tpaint', 12:36:35 INFO - 'sessionrestore', 12:36:35 INFO - 'sessionrestore_no_auto_restore', 12:36:35 INFO - 'tabpaint']}, 12:36:35 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 12:36:35 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 12:36:35 INFO - 'bloom_basic_ref']}, 12:36:35 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:36:35 INFO - 'talos_options': ['--mitmproxy', 12:36:35 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:36:35 INFO - '--firstNonBlankPaint'], 12:36:35 INFO - 'tests': ['quantum_pageload_google', 12:36:35 INFO - 'quantum_pageload_youtube', 12:36:35 INFO - 'quantum_pageload_amazon', 12:36:35 INFO - 'quantum_pageload_facebook']}, 12:36:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:36:35 INFO - 'tests': ['tsvgx', 12:36:35 INFO - 'tsvgr_opacity', 12:36:35 INFO - 'tart', 12:36:35 INFO - 'tscrollx', 12:36:35 INFO - 'cart', 12:36:35 INFO - 'tsvg_static']}, 12:36:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:36:35 INFO - 'tsvgr_opacity', 12:36:35 INFO - 'tart', 12:36:35 INFO - 'tscrollx', 12:36:35 INFO - 'cart', 12:36:35 INFO - 'tsvg_static']}, 12:36:35 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:36:35 INFO - 'talos_options': ['--disable-e10s'], 12:36:35 INFO - 'tests': ['tp5o']}, 12:36:35 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:36:35 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:36:35 INFO - 'talos_options': ['--disable-e10s', 12:36:35 INFO - '--xperf_path', 12:36:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:36:35 INFO - 'tests': ['tp5n']}, 12:36:35 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:36:35 INFO - 'talos_options': ['--xperf_path', 12:36:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:36:35 INFO - 'tests': ['tp5n']}}, 12:36:35 INFO - 'talos.zip': {'path': '', 12:36:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:36:35 INFO - Running post-action listener: _resource_record_post_action 12:36:35 INFO - [mozharness: 2017-06-19 19:36:35.828618Z] Finished populate-webroot step (success) 12:36:35 INFO - [mozharness: 2017-06-19 19:36:35.828729Z] Running create-virtualenv step. 12:36:35 INFO - Running pre-action listener: _resource_record_pre_action 12:36:35 INFO - Running main action method: create_virtualenv 12:36:35 INFO - Creating virtualenv /builds/slave/test/build/venv 12:36:35 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 12:36:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:36:35 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 12:36:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:36:35 INFO - Using real prefix '/tools/python27' 12:36:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:36:36 INFO - Installing distribute.............................................................................................................................................................................................done. 12:36:39 INFO - Installing pip.................done. 12:36:39 INFO - Return code: 0 12:36:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:36:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:36:39 INFO - Reading from file tmpfile_stdout 12:36:39 INFO - Output received: 12:36:39 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 12:36:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:36:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:36:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:36:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:36:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:36:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1073dfc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073e3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd97ad4f160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107135d30>, '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 0x1070ffcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107126be8>, '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.osN7GbXiBw/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.1ix1Bfa072/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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 12:36:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:36:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:36:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.osN7GbXiBw/Render', 12:36:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:36:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:36:39 INFO - 'HOME': '/Users/cltbld', 12:36:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:36:39 INFO - 'LOGNAME': 'cltbld', 12:36:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:36:39 INFO - 'MOZ_NO_REMOTE': '1', 12:36:39 INFO - 'NO_EM_RESTART': '1', 12:36:39 INFO - 'PAGER': '/bin/cat', 12:36:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:36:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:36:39 INFO - 'PWD': '/builds/slave/test', 12:36:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:36:39 INFO - 'SHELL': '/bin/bash', 12:36:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1ix1Bfa072/Listeners', 12:36:39 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 12:36:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:36:39 INFO - 'USER': 'cltbld', 12:36:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:36:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:36:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:36:39 INFO - 'XPC_FLAGS': '0x0', 12:36:39 INFO - 'XPC_SERVICE_NAME': '0', 12:36:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:36:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:36:40 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 12:36:40 INFO - Cleaning up... 12:36:40 INFO - Return code: 0 12:36:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:36:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:36:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:36:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:36:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:36:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1073dfc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073e3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd97ad4f160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107135d30>, '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 0x1070ffcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107126be8>, '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.osN7GbXiBw/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.1ix1Bfa072/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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 12:36:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:36:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:36:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.osN7GbXiBw/Render', 12:36:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:36:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:36:40 INFO - 'HOME': '/Users/cltbld', 12:36:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:36:40 INFO - 'LOGNAME': 'cltbld', 12:36:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:36:40 INFO - 'MOZ_NO_REMOTE': '1', 12:36:40 INFO - 'NO_EM_RESTART': '1', 12:36:40 INFO - 'PAGER': '/bin/cat', 12:36:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:36:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:36:40 INFO - 'PWD': '/builds/slave/test', 12:36:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:36:40 INFO - 'SHELL': '/bin/bash', 12:36:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1ix1Bfa072/Listeners', 12:36:40 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 12:36:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:36:40 INFO - 'USER': 'cltbld', 12:36:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:36:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:36:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:36:40 INFO - 'XPC_FLAGS': '0x0', 12:36:40 INFO - 'XPC_SERVICE_NAME': '0', 12:36:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:36:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:36:40 INFO - Downloading/unpacking psutil>=3.1.1 12:36:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:36:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:36:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:36:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:36:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:36:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:36:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:36:42 INFO - Installing collected packages: psutil 12:36:42 INFO - Running setup.py install for psutil 12:36:42 INFO - building 'psutil._psutil_osx' extension 12:36:42 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 12:36:42 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 12:36:42 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 12:36:42 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 12:36:43 INFO - building 'psutil._psutil_posix' extension 12:36:43 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 12:36:43 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:36:43 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:36:43 INFO - ^ 12:36:43 INFO - 1 warning generated. 12:36:43 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 12:36:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:36:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:36:43 INFO - Successfully installed psutil 12:36:43 INFO - Cleaning up... 12:36:43 INFO - Return code: 0 12:36:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:36:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:36:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:36:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:36:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:36:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1073dfc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073e3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd97ad4f160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107135d30>, '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 0x1070ffcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107126be8>, '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.osN7GbXiBw/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.1ix1Bfa072/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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 12:36:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:36:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 12:36:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.osN7GbXiBw/Render', 12:36:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:36:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:36:43 INFO - 'HOME': '/Users/cltbld', 12:36:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:36:43 INFO - 'LOGNAME': 'cltbld', 12:36:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:36:43 INFO - 'MOZ_NO_REMOTE': '1', 12:36:43 INFO - 'NO_EM_RESTART': '1', 12:36:43 INFO - 'PAGER': '/bin/cat', 12:36:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:36:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:36:43 INFO - 'PWD': '/builds/slave/test', 12:36:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:36:43 INFO - 'SHELL': '/bin/bash', 12:36:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1ix1Bfa072/Listeners', 12:36:43 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 12:36:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:36:43 INFO - 'USER': 'cltbld', 12:36:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:36:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:36:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:36:43 INFO - 'XPC_FLAGS': '0x0', 12:36:43 INFO - 'XPC_SERVICE_NAME': '0', 12:36:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:36:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:36:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:36:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:36:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:36:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:36:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:36:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:36:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:36:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:36:45 INFO - Installing collected packages: mozsystemmonitor 12:36:45 INFO - Running setup.py install for mozsystemmonitor 12:36:45 INFO - Successfully installed mozsystemmonitor 12:36:45 INFO - Cleaning up... 12:36:45 INFO - Return code: 0 12:36:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:36:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:36:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:36:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:36:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:36:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1073dfc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073e3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd97ad4f160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107135d30>, '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 0x1070ffcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107126be8>, '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.osN7GbXiBw/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.1ix1Bfa072/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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 12:36:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:36:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 12:36:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.osN7GbXiBw/Render', 12:36:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:36:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:36:45 INFO - 'HOME': '/Users/cltbld', 12:36:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:36:45 INFO - 'LOGNAME': 'cltbld', 12:36:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:36:45 INFO - 'MOZ_NO_REMOTE': '1', 12:36:45 INFO - 'NO_EM_RESTART': '1', 12:36:45 INFO - 'PAGER': '/bin/cat', 12:36:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:36:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:36:45 INFO - 'PWD': '/builds/slave/test', 12:36:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:36:45 INFO - 'SHELL': '/bin/bash', 12:36:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1ix1Bfa072/Listeners', 12:36:45 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 12:36:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:36:45 INFO - 'USER': 'cltbld', 12:36:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:36:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:36:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:36:45 INFO - 'XPC_FLAGS': '0x0', 12:36:45 INFO - 'XPC_SERVICE_NAME': '0', 12:36:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:36:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:36:46 INFO - Downloading/unpacking jsonschema==2.5.1 12:36:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:36:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:36:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:36:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:36:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:36:47 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:36: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 12:36: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 12:36: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 12:36: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 12:36:47 INFO - Downloading functools32-3.2.3-2.tar.gz 12:36:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:36:47 INFO - warning: no files found matching '*.txt' 12:36:47 INFO - no previously-included directories found matching 'build' 12:36:47 INFO - no previously-included directories found matching 'dist' 12:36:47 INFO - no previously-included directories found matching '.git*' 12:36:47 INFO - Installing collected packages: jsonschema, functools32 12:36:47 INFO - Running setup.py install for functools32 12:36:48 INFO - warning: no files found matching '*.txt' 12:36:48 INFO - no previously-included directories found matching 'build' 12:36:48 INFO - no previously-included directories found matching 'dist' 12:36:48 INFO - no previously-included directories found matching '.git*' 12:36:48 INFO - Successfully installed jsonschema functools32 12:36:48 INFO - Cleaning up... 12:36:48 INFO - Return code: 0 12:36:48 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:36:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:36:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:36:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:36:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:36:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1073dfc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073e3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd97ad4f160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107135d30>, '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 0x1070ffcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107126be8>, '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.osN7GbXiBw/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.1ix1Bfa072/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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 12:36:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:36:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 12:36:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.osN7GbXiBw/Render', 12:36:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:36:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:36:48 INFO - 'HOME': '/Users/cltbld', 12:36:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:36:48 INFO - 'LOGNAME': 'cltbld', 12:36:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:36:48 INFO - 'MOZ_NO_REMOTE': '1', 12:36:48 INFO - 'NO_EM_RESTART': '1', 12:36:48 INFO - 'PAGER': '/bin/cat', 12:36:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:36:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:36:48 INFO - 'PWD': '/builds/slave/test', 12:36:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:36:48 INFO - 'SHELL': '/bin/bash', 12:36:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1ix1Bfa072/Listeners', 12:36:48 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 12:36:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:36:48 INFO - 'USER': 'cltbld', 12:36:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:36:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:36:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:36:48 INFO - 'XPC_FLAGS': '0x0', 12:36:48 INFO - 'XPC_SERVICE_NAME': '0', 12:36:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:36:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:36:48 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:36:48 INFO - Cleaning up... 12:36:48 INFO - Return code: 0 12:36:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:36:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:36:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:36:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:36:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:36:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1073dfc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073e3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd97ad4f160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107135d30>, '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 0x1070ffcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107126be8>, '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.osN7GbXiBw/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.1ix1Bfa072/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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 12:36:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:36:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:36:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.osN7GbXiBw/Render', 12:36:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:36:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:36:48 INFO - 'HOME': '/Users/cltbld', 12:36:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:36:48 INFO - 'LOGNAME': 'cltbld', 12:36:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:36:48 INFO - 'MOZ_NO_REMOTE': '1', 12:36:48 INFO - 'NO_EM_RESTART': '1', 12:36:48 INFO - 'PAGER': '/bin/cat', 12:36:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:36:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:36:48 INFO - 'PWD': '/builds/slave/test', 12:36:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:36:48 INFO - 'SHELL': '/bin/bash', 12:36:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1ix1Bfa072/Listeners', 12:36:48 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 12:36:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:36:48 INFO - 'USER': 'cltbld', 12:36:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:36:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:36:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:36:48 INFO - 'XPC_FLAGS': '0x0', 12:36:48 INFO - 'XPC_SERVICE_NAME': '0', 12:36:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:36:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:36:48 INFO - Downloading/unpacking blobuploader==1.2.4 12:36:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:36:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:36:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:36:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:36:49 INFO - Downloading blobuploader-1.2.4.tar.gz 12:36:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:36:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:36:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:36:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:36:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:36:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:36:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:36:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:36:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:36:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:36:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:36:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:36:50 INFO - Downloading docopt-0.6.1.tar.gz 12:36:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:36:50 INFO - Installing collected packages: blobuploader, requests, docopt 12:36:50 INFO - Running setup.py install for blobuploader 12:36:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:36:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:36:51 INFO - Running setup.py install for requests 12:36:51 INFO - Running setup.py install for docopt 12:36:51 INFO - Successfully installed blobuploader requests docopt 12:36:51 INFO - Cleaning up... 12:36:51 INFO - Return code: 0 12:36:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:36: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')]} 12:36:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:36: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')]} 12:36:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:36:51 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 0x1073dfc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073e3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd97ad4f160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107135d30>, '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 0x1070ffcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107126be8>, '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.osN7GbXiBw/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.1ix1Bfa072/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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 12:36:51 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 12:36:51 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 12:36:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.osN7GbXiBw/Render', 12:36:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:36:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:36:51 INFO - 'HOME': '/Users/cltbld', 12:36:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:36:51 INFO - 'LOGNAME': 'cltbld', 12:36:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:36:51 INFO - 'MOZ_NO_REMOTE': '1', 12:36:51 INFO - 'NO_EM_RESTART': '1', 12:36:51 INFO - 'PAGER': '/bin/cat', 12:36:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:36:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:36:51 INFO - 'PWD': '/builds/slave/test', 12:36:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:36:51 INFO - 'SHELL': '/bin/bash', 12:36:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1ix1Bfa072/Listeners', 12:36:51 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 12:36:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:36:51 INFO - 'USER': 'cltbld', 12:36:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:36:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:36:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:36:51 INFO - 'XPC_FLAGS': '0x0', 12:36:51 INFO - 'XPC_SERVICE_NAME': '0', 12:36:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:36:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:36:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:36:52 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-8fWpBO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:36:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:36:52 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-D6szBl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:36:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:36:52 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-LjkKuZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:36:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:36:52 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-qJsGHX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:36:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:36:52 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-Ha7CVG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:36:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:36:52 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-z8IeRR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:36:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:36:52 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-BCYW0m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:36:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:36:52 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-_YuphD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:36:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:36:52 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-wIm124-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:36:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:36:53 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-BqWDvO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:36:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:36:53 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-tmYXm9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:36:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:36:53 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-iypBot-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:36:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:36:53 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-4IjuDj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:36:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:36:53 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-9uzgc_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:36:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:36:53 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-o7gZ8N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:36:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:36:53 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-3fKJ9d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:36:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:36:53 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-4ZGk40-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:36:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:36:53 INFO - Running setup.py install for manifestparser 12:36:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:36:54 INFO - Running setup.py install for mozcrash 12:36:54 INFO - Running setup.py install for mozdebug 12:36:54 INFO - Running setup.py install for mozdevice 12:36:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:36:54 INFO - Running setup.py install for mozfile 12:36:54 INFO - Running setup.py install for mozhttpd 12:36:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:36:54 INFO - Running setup.py install for mozinfo 12:36:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:36:54 INFO - Running setup.py install for mozInstall 12:36:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:36:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:36:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:36:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:36:55 INFO - Running setup.py install for mozleak 12:36:55 INFO - Running setup.py install for mozlog 12:36:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:36:55 INFO - Running setup.py install for moznetwork 12:36:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:36:55 INFO - Running setup.py install for mozprocess 12:36:55 INFO - Running setup.py install for mozprofile 12:36:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:36:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:36:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:36:55 INFO - Running setup.py install for mozrunner 12:36:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:36:56 INFO - Running setup.py install for mozscreenshot 12:36:56 INFO - Running setup.py install for moztest 12:36:56 INFO - Running setup.py install for mozversion 12:36:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:36:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:36:56 INFO - Cleaning up... 12:36:56 INFO - Return code: 0 12:36:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:36: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')]} 12:36:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:36: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')]} 12:36:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:36:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1073dfc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073e3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd97ad4f160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107135d30>, '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 0x1070ffcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107126be8>, '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.osN7GbXiBw/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.1ix1Bfa072/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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 12:36:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:36:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:36:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.osN7GbXiBw/Render', 12:36:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:36:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:36:56 INFO - 'HOME': '/Users/cltbld', 12:36:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:36:56 INFO - 'LOGNAME': 'cltbld', 12:36:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:36:56 INFO - 'MOZ_NO_REMOTE': '1', 12:36:56 INFO - 'NO_EM_RESTART': '1', 12:36:56 INFO - 'PAGER': '/bin/cat', 12:36:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:36:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:36:56 INFO - 'PWD': '/builds/slave/test', 12:36:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:36:56 INFO - 'SHELL': '/bin/bash', 12:36:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1ix1Bfa072/Listeners', 12:36:56 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 12:36:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:36:56 INFO - 'USER': 'cltbld', 12:36:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:36:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:36:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:36:56 INFO - 'XPC_FLAGS': '0x0', 12:36:56 INFO - 'XPC_SERVICE_NAME': '0', 12:36:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:36:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:36:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:36:56 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-Y6LDaO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:36:56 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 12:36:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:36:56 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-zanX9X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:36:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:36:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:36:57 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-waZSov-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:36:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:36:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:36:57 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-oSOOev-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:36:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:36:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:36:57 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-9M3GoD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:36:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 12:36:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:36:57 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-QzblR9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:36:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 12:36:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:36:57 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-qhDYJm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:36:57 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)) 12:36:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:36:57 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-mdzop2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:36:57 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)) 12:36:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:36:57 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-UFXu6k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:36:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 12:36:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:36:57 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-9M0i5h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:36:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:36:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:36:57 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-INdF5y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:36:57 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 12:36:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:36:57 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-Afpe6t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:36:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 12:36:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:36:58 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-TRYZIB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:36:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 12:36:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:36:58 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-3Lvh6Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:36:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 12:36:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:36:58 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-cI8TZc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:36:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 12:36:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:36:58 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-dwFSdN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:36:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 12:36:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:36:58 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-QtLzZB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:36:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 12:36:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:36:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:36:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:36:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:36:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:36:58 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)) 12:36:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:36: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 12:36: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 12:36: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 12:36: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 12:36:59 INFO - Downloading blessings-1.6.tar.gz 12:36:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:37:00 INFO - Installing collected packages: blessings 12:37:00 INFO - Running setup.py install for blessings 12:37:00 INFO - Successfully installed blessings 12:37:00 INFO - Cleaning up... 12:37:00 INFO - Return code: 0 12:37:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:37:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:37:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:37:00 INFO - Reading from file tmpfile_stdout 12:37:00 INFO - Current package versions: 12:37:00 INFO - blessings == 1.6 12:37:00 INFO - blobuploader == 1.2.4 12:37:00 INFO - docopt == 0.6.1 12:37:00 INFO - functools32 == 3.2.3-2 12:37:00 INFO - jsonschema == 2.5.1 12:37:00 INFO - manifestparser == 1.1 12:37:00 INFO - mozInstall == 1.13 12:37:00 INFO - mozcrash == 1.0 12:37:00 INFO - mozdebug == 0.1 12:37:00 INFO - mozdevice == 0.51 12:37:00 INFO - mozfile == 1.2 12:37:00 INFO - mozhttpd == 0.7 12:37:00 INFO - mozinfo == 0.10 12:37:00 INFO - mozleak == 0.1 12:37:00 INFO - mozlog == 3.4 12:37:00 INFO - moznetwork == 0.27 12:37:00 INFO - mozprocess == 0.25 12:37:00 INFO - mozprofile == 0.28 12:37:00 INFO - mozrunner == 6.13 12:37:00 INFO - mozscreenshot == 0.1 12:37:00 INFO - mozsystemmonitor == 0.3 12:37:00 INFO - moztest == 0.8 12:37:00 INFO - mozversion == 1.4 12:37:00 INFO - psutil == 3.1.1 12:37:00 INFO - requests == 1.2.3 12:37:00 INFO - wsgiref == 0.1.2 12:37:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:37: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')]} 12:37:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:37: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')]} 12:37:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:37:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1073dfc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073e3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd97ad4f160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107135d30>, '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 0x1070ffcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107126be8>, '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.osN7GbXiBw/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.1ix1Bfa072/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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 12:37:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 12:37:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:37:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.osN7GbXiBw/Render', 12:37:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:37:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:37:00 INFO - 'HOME': '/Users/cltbld', 12:37:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:37:00 INFO - 'LOGNAME': 'cltbld', 12:37:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:37:00 INFO - 'MOZ_NO_REMOTE': '1', 12:37:00 INFO - 'NO_EM_RESTART': '1', 12:37:00 INFO - 'PAGER': '/bin/cat', 12:37:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:37:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:37:00 INFO - 'PWD': '/builds/slave/test', 12:37:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:37:00 INFO - 'SHELL': '/bin/bash', 12:37:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1ix1Bfa072/Listeners', 12:37:00 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 12:37:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:37:00 INFO - 'USER': 'cltbld', 12:37:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:37:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:37:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:37:00 INFO - 'XPC_FLAGS': '0x0', 12:37:00 INFO - 'XPC_SERVICE_NAME': '0', 12:37:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:37:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:37:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:37:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 12:37:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 12:37:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:37:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 12:37:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 12:37:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 12:37:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 12:37:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 12:37:00 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 12:37:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:37:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:37:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:37:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:37:02 INFO - Downloading marionette_driver-1.4.0.tar.gz 12:37:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 12:37:02 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:37:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:37:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 12:37:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 12:37:02 INFO - Installing collected packages: marionette-driver 12:37:02 INFO - Running setup.py install for marionette-driver 12:37:02 INFO - Successfully installed marionette-driver 12:37:02 INFO - Cleaning up... 12:37:02 INFO - Return code: 0 12:37:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:37:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:37:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:37:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:37:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:37:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1073dfc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073e3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd97ad4f160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107135d30>, '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 0x1070ffcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107126be8>, '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.osN7GbXiBw/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.1ix1Bfa072/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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 12:37:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:37:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:37:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.osN7GbXiBw/Render', 12:37:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:37:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:37:02 INFO - 'HOME': '/Users/cltbld', 12:37:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:37:02 INFO - 'LOGNAME': 'cltbld', 12:37:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:37:02 INFO - 'MOZ_NO_REMOTE': '1', 12:37:02 INFO - 'NO_EM_RESTART': '1', 12:37:02 INFO - 'PAGER': '/bin/cat', 12:37:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:37:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:37:02 INFO - 'PWD': '/builds/slave/test', 12:37:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:37:02 INFO - 'SHELL': '/bin/bash', 12:37:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1ix1Bfa072/Listeners', 12:37:02 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 12:37:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:37:02 INFO - 'USER': 'cltbld', 12:37:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:37:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:37:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:37:02 INFO - 'XPC_FLAGS': '0x0', 12:37:02 INFO - 'XPC_SERVICE_NAME': '0', 12:37:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:37:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:37:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:37:02 INFO - Cleaning up... 12:37:02 INFO - Return code: 0 12:37:02 INFO - Running post-action listener: _resource_record_post_action 12:37:02 INFO - Running post-action listener: _start_resource_monitoring 12:37:02 INFO - Starting resource monitoring. 12:37:02 INFO - [mozharness: 2017-06-19 19:37:02.930273Z] Finished create-virtualenv step (success) 12:37:02 INFO - [mozharness: 2017-06-19 19:37:02.931251Z] Running install step. 12:37:02 INFO - Running pre-action listener: _resource_record_pre_action 12:37:02 INFO - Running main action method: install 12:37:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:37:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:37:03 INFO - Reading from file tmpfile_stdout 12:37:03 INFO - Detecting whether we're running mozinstall >=1.0... 12:37:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:37:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:37:03 INFO - Reading from file tmpfile_stdout 12:37:03 INFO - Output received: 12:37:03 INFO - Usage: mozinstall [options] installer 12:37:03 INFO - Options: 12:37:03 INFO - -h, --help show this help message and exit 12:37:03 INFO - -d DEST, --destination=DEST 12:37:03 INFO - Directory to install application into. [default: 12:37:03 INFO - "/builds/slave/test"] 12:37:03 INFO - --app=APP Application being installed. [default: firefox] 12:37:03 INFO - mkdir: /builds/slave/test/build/application 12:37:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:37:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0.en-US.mac.dmg --destination /builds/slave/test/build/application 12:37:24 INFO - Reading from file tmpfile_stdout 12:37:24 INFO - Output received: 12:37:24 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 12:37:24 INFO - Running post-action listener: _resource_record_post_action 12:37:24 INFO - [mozharness: 2017-06-19 19:37:24.470571Z] Finished install step (success) 12:37:24 INFO - [mozharness: 2017-06-19 19:37:24.470755Z] Running setup-mitmproxy step. 12:37:24 INFO - Running pre-action listener: _resource_record_pre_action 12:37:24 INFO - Running main action method: setup_mitmproxy 12:37:24 INFO - Skipping: mitmproxy is not required 12:37:24 INFO - Running post-action listener: _resource_record_post_action 12:37:24 INFO - [mozharness: 2017-06-19 19:37:24.471418Z] Finished setup-mitmproxy step (success) 12:37:24 INFO - [mozharness: 2017-06-19 19:37:24.471550Z] Running run-tests step. 12:37:24 INFO - Running pre-action listener: _resource_record_pre_action 12:37:24 INFO - Running main action method: run_tests 12:37:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:37:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:37:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:37:24 INFO - 2017-06-19 12:37:24.486 screenresolution[2544:33106] starting screenresolution argv=screenresolution get 12:37:24 INFO - 2017-06-19 12:37:24.489 screenresolution[2544:33106] Display 0: 1600x1200x32@60 12:37:24 INFO - 2017-06-19 12:37:24.494 screenresolution[2545:33108] starting screenresolution argv=screenresolution list 12:37:24 INFO - Available Modes on Display 0 12:37:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:37:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:37:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:37:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:37:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:37:24 INFO - Intel Iris: 12:37:24 INFO - Chipset Model: Intel Iris 12:37:24 INFO - Type: GPU 12:37:24 INFO - Bus: Built-In 12:37:24 INFO - VRAM (Dynamic, Max): 1536 MB 12:37:24 INFO - Vendor: Intel (0x8086) 12:37:24 INFO - Device ID: 0x0a2e 12:37:24 INFO - Revision ID: 0x0009 12:37:24 INFO - Displays: 12:37:24 INFO - Display: 12:37:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:37:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:37:24 INFO - Display Serial Number: 1600x1200 60 12:37:24 INFO - Main Display: Yes 12:37:24 INFO - Mirror: Off 12:37:24 INFO - Online: Yes 12:37:24 INFO - Rotation: Supported 12:37:24 INFO - Return code: 0 12:37:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:37:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:37:24 INFO - Python 2.7.3 12:37:24 INFO - Return code: 0 12:37:24 INFO - grabbing minidump binary from tooltool 12:37:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:37:24 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 0x107135d30>, '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 0x1070ffcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107126be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:37:24 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 12:37:24 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 12:37:24 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 12:37:24 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:37:24 INFO - Return code: 0 12:37:24 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:37:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:37:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:37:24 INFO - ENV: RUST_BACKTRACE is now 1 12:37:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:37:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:37:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JjnqEl7tQoC9uC932cL50g/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0023', '--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 12:37:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JjnqEl7tQoC9uC932cL50g/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0023 --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 12:37:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.osN7GbXiBw/Render', 12:37:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:37:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:37:24 INFO - 'HOME': '/Users/cltbld', 12:37:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:37:24 INFO - 'LOGNAME': 'cltbld', 12:37:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:37:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:37:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:37:24 INFO - 'MOZ_NO_REMOTE': '1', 12:37:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:37:24 INFO - 'NO_EM_RESTART': '1', 12:37:24 INFO - 'PAGER': '/bin/cat', 12:37:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:37:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:37:24 INFO - 'PWD': '/builds/slave/test', 12:37:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:37:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:37:24 INFO - 'RUST_BACKTRACE': '1', 12:37:24 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:37:24 INFO - 'SHELL': '/bin/bash', 12:37:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1ix1Bfa072/Listeners', 12:37:24 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 12:37:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:37:24 INFO - 'USER': 'cltbld', 12:37:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:37:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:37:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:37:24 INFO - 'XPC_FLAGS': '0x0', 12:37:24 INFO - 'XPC_SERVICE_NAME': '0', 12:37:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:37:24 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JjnqEl7tQoC9uC932cL50g/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0023', '--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 12:37:25 INFO - mozversion application_buildid: 20170619105559 12:37:25 INFO - mozversion application_changeset: e9004c186de84e98ac082df097348bfa279ee498 12:37:25 INFO - mozversion application_display_name: FirefoxDeveloperEdition 12:37:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:37:25 INFO - mozversion application_name: Firefox 12:37:25 INFO - mozversion application_remotingname: firefox-dev 12:37:25 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 12:37:25 INFO - mozversion application_vendor: Mozilla 12:37:25 INFO - mozversion application_version: 55.0 12:37:25 INFO - mozversion platform_buildid: 20170619105559 12:37:25 INFO - mozversion platform_changeset: e9004c186de84e98ac082df097348bfa279ee498 12:37:25 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 12:37:25 INFO - mozversion platform_version: 55.0 12:37:25 INFO - using testdate: 1497901045 12:37:25 INFO - actual date: 1497901045 12:37:25 INFO - starting webserver on 'localhost:57985' 12:37:25 INFO - SUITE-START | Running 2 tests 12:37:25 INFO - TEST-START | dromaeo_css 12:37:25 INFO - operating with platform_type : mac_ 12:37:25 INFO - Initialising browser for dromaeo_css test... 12:37:25 INFO - Application command: /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:57985/getInfo.html -foreground -profile /var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/tmpiorggS/profile 12:37:25 INFO - TEST-INFO | started process 2555 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:57985/getInfo.html -foreground) 12:37:58 INFO - TEST-INFO | 2555: killed by SIGTERM 12:37:58 INFO - Browser initialized. 12:37:58 INFO - Running cycle 1/1 for dromaeo_css test... 12:37:58 INFO - TEST-INFO | started process 2562 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/tmpiorggS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:38:00 INFO - PID 2562 | 2017-06-19 12:38:00.122 plugin-container[2563:33583] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x484f, name = 'com.apple.tsm.portname' 12:38:00 INFO - PID 2562 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:38:00 INFO - PID 2562 | 2017-06-19 12:38:00.123 plugin-container[2563:33583] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 12:38:00 INFO - PID 2562 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:38:00 INFO - PID 2562 | 2017-06-19 12:38:00.123 plugin-container[2563:33583] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:38:00 INFO - PID 2562 | 2017-06-19 12:38:00.823 plugin-container[2564:33629] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4443, name = 'com.apple.tsm.portname' 12:38:00 INFO - PID 2562 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:38:00 INFO - PID 2562 | 2017-06-19 12:38:00.823 plugin-container[2564:33629] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a03, name = 'com.apple.CFPasteboardClient' 12:38:00 INFO - PID 2562 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:38:00 INFO - PID 2562 | 2017-06-19 12:38:00.823 plugin-container[2564:33629] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:38:10 INFO - PID 2562 | RSS: Main: 214802432 12:38:10 INFO - PID 2562 | 12:41:14 INFO - PID 2562 | Cycle 1(1): loaded http://localhost:57985/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57985/tests/dromaeo/cssquery-ext.html) 12:41:17 INFO - PID 2562 | RSS: Main: 229355520 12:41:17 INFO - PID 2562 | 12:44:19 INFO - PID 2562 | Cycle 1(1): loaded http://localhost:57985/tests/dromaeo/cssquery-ext.html (next: http://localhost:57985/tests/dromaeo/cssquery-jquery.html) 12:44:20 INFO - PID 2562 | RSS: Main: 238469120 12:44:20 INFO - PID 2562 | 12:47:21 INFO - PID 2562 | Cycle 1(1): loaded http://localhost:57985/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57985/tests/dromaeo/cssquery-mootools.html) 12:47:22 INFO - PID 2562 | RSS: Main: 244072448 12:47:22 INFO - PID 2562 | 12:50:23 INFO - PID 2562 | Cycle 1(1): loaded http://localhost:57985/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57985/tests/dromaeo/cssquery-prototype.html) 12:50:24 INFO - PID 2562 | RSS: Main: 245837824 12:50:24 INFO - PID 2562 | 12:53:27 INFO - PID 2562 | Cycle 1(1): loaded http://localhost:57985/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57985/tests/dromaeo/cssquery-yui.html) 12:53:28 INFO - PID 2562 | RSS: Main: 246370304 12:53:28 INFO - PID 2562 | 12:56:29 INFO - PID 2562 | Cycle 1(1): loaded http://localhost:57985/tests/dromaeo/cssquery-yui.html (next: http://localhost:57985/tests/dromaeo/cssquery-dojo.html) 12:56:30 INFO - PID 2562 | RSS: Main: 243908608 12:56:30 INFO - PID 2562 | 12:56:30 INFO - PID 2562 | __start_tp_report 12:56:30 INFO - PID 2562 | _x_x_mozilla_page_load 12:56:30 INFO - PID 2562 | _x_x_mozilla_page_load_details 12:56:30 INFO - PID 2562 | |i|pagename|runs| 12:56:30 INFO - PID 2562 | |0;dojo.html;2710.308871237834;3523.5067090607304;3540.451230059341;3574.1243395368124;3642;6200.193974783278;6206.937930620692;6251;6269.15366425533;6274.84312892178;5257.369115706111;6285.182926219585;6358.586691865025;6367.968160159205;6381.553291269613;5551.195076713864;5586.35756887957;5601.215829783833;5625.971870140644;5677.914831277534;5593.8321850344355;5596.552275817945;5686.31764188298;5686.687232202227;5690.971545142268;5449.536789372908;5483.2049352844015;5509.311336082989;5552.944470555283;5580.832575022756;5518.668879867221;5549.139883318092;5561.416051314634;5603.495685388335;5611.971940140314;5509.917351239735;5515.7242137892945;5553.861153471155;5562.916556251659;5608.383077861432;5194.428612852583;6237.719302631347;6296.338884417162;6341.904871426932;6372.617642941438;6627.801165965029;6855.828604284883;6867.3476019778045;6905.861882762367;6906.171259448848;6819.897701534481;6834.931650683479;6874.312568743136;6909.619970901598;6989.161300643905;5998.820035398945;6458.483321334282;6464.806055818333;6473.805785826433;6569.277379488252;6869.484788640872;7200.423966082701;7217.097862767154;7232.0959880015;7240.637968101574;6616.503762217853;6635.535512514078;6648.069270302161;6666.0667506549125;6675.232348279913;6869.656517174121;6882;6951.304869513109;6967.616781076988;6968.616726080114;6839.931600684029;6876.896846547205;6887.139107611549;6892.517523773287;6893.724251030003;6832.316768323128;6841.794746157623;6881.277465866012;6887.070245516891;6891.138607674041;5393;5412.377576578744;5439.5104440600535;5466.371367292732;5499.340079190522;4791.1075125326;4854.8786280342565;4968.652194346361;4992.475790042066;5016.1221786188;6383.585066970632;6479.254885688206;6482.189726284215;6508.967455162694;6519.511036672268;7023.754168604074;7027.472939529555;7035.648217589202;7069.399101076466;7074.257202993715;6473;6477.740890364333;6486.2216534016225;6493.415592596689;6519.217693876671;5197.714125723046;5749.482546570829;5936.7031648417405;6083.11794789753;6104.023356262976;6971.337722916416;6991.440684745106;7015.719371225094;7147.428205743632;7172;6944.236134025355;6966.268541803038;7041.049458323061;7041.964790176016;7069.858602827811;6297.4017468340435;6927.406696459741;7173.390261827803;7182.569045857265;7206.171290301682;5952.672603006703;6538.5615164663695;6860.897086543606;6874.346937040973;6881.690323935335;6122.97760420812;6804.523683342119;6859.314068593101;7052.435805135474;7052.823679408056;6231.813045608639;6635.701393437211;6686.264510903894;6782.966085169543;6799.048133261446;6653.800385988428;6730.629815360006;6735.595864248161;6763.560368576027;6773.661316934232;6382.946813775675;6484.221893372637;6542.247641521286;6644.468442524683;6662.333766623299;6592.43964263043;6594.175728033996;6608.4052435281055;6616.2391324996315;6630.403263706289;6510.381513756375;6563.901541476977;6594.406503414716;6595.901061483985;6632.038354438483 12:56:30 INFO - PID 2562 | |1;ext.html;733.7541923455642;749.3555542233678;831.7629475599454;844.3245403677058;846.4413487098516;33100.17249568762;36782.89651310461;37805.99999999996;37825.621743782554;37965;34216.82891585539;37128;37331.25337493249;37665;37677.623223767754;6980.89528657068;7399.852002959924;7507.361874240696;7509.586972716498;7520.323170914617;7481.989931359279;7537.962310188469;7592;7596.202398748134;7616.352610028127;7507.249275072503;7519.774406767806;7546.396288296923;7562.773116806505;7567.243275672444;7529.774106776778;7568.735094271701;8101.797455063641;8372.748817535483;8379.078301386842;6934.237233904267;8132.024157101186;8154.8776768348525;8208.876866847002;8209.466384684976;35604.28791424183;35856.820715896516;35881.10297242564;35945;35971.64028359709;3491.5810102787573;3495.8426870790877;3510.2628448025944;3537.1510837399096;3541.628129046439;29334;29379.41241175186;29410.264743381373;29508.262293442836;29600.25999350012;3042.604461420023;3118.844057797101;3132.1230055584247;3138.168385377877;3154.5741324921073;254968;258324.9999999981;258478;312620;315745;38205.044873878374;46431;47426.05147897023;47470.287945680844;48177.7955551114;48211;48696;48722.512774872506;48811.755941220064;48827;15369.615759606098;15442.45951391696;15553.066815991077;15572.610684732746;15586.454474093352;12536.686582835502;12616.684582885318;12746;12749.681257968625;12778.97768178562;13153.539626113232;13326.800097998537;13370.665733356549;13442.663933401547;13443.260620665957;5873.2658417697785;5892.175095486636;5915.733791979371;5921.822345329647;5938.554608404387;938.2634631813993;962.4033099478369;962.9807403852024;966.9419834809793;1023.2530252915265;23041.30876073687;23053;23146.305610831703;23865.880670596536;26357.286776359477;893.5576889439769;982.7641366072091;1074.435921141407;1105.0441368216434;1135.903448206895;9483.573239204114;10182.490875456198;11093.944530277297;11783.705407364885;11846.289222646668;350566;355851.441485587;358220;358465;358820;52081.47918520766;52383.21425178625;52676.73661631667;52786;52933.7353313231;1528.648410865485;1578.0295118502038;1587.1429428108693;1588.1424031023348;1593.561770513095;22320;22534.887325563268;22895;23217;24261;38444.2311153781;38715;38763.03092422712;38789.41815872708;38797.41803872888;257551;304376.47811761685;307410;307949;308029;34194.658053419145;34754.82622586871;34829.78095766636;34892.47661285134;35145;42952.92617684583;44176.33735494035;44708.32937505875;44742.55257447514;44962.55037449714;28915.85542072276;28993.565096523147;29878.25304367408;29912.401751964404;31159.221019474695;26215.95136194531;28143;29980.70019299866;30492.695073048984;30724.539131912592;31236.843815780776;35928.46107308445;36915.63084369122;36962.44556331711;37022;26809.98185739183;28997.420051598427;29506.96725614593;30499.695003049685;31648.683513164575;19253.614927701088;19712.21151153995;24762.009519619012;25286;25725.485490290463 12:56:30 INFO - PID 2562 | |2;jquery.html;512.9333186685731;516.3983958688128;517.1920257334566;520.1158031346711;540.227474711163;22460.775392246072;23342.183023594178;23351.182708605204;23357.76642233577;23488;23521.294361169148;23877.999999999956;26009.739902601017;26026;26034.698265086765;6552.541322107454;6695.799126026227;6716.932830671704;6720.823855825235;6723.125993620823;6668.466522678198;6722.226943901441;6723.09238252837;6726.798196054102;6733.528652994268;5783.768649254025;6230.127782110508;6395.168628078343;6620;6683.331666833302;5459.972700136514;6239.875202495948;6333.55665103443;6354.396332348442;6373.553851230416;5858.091995740667;5861.0915308127305;5868.178455016258;5876.3242227144;5877;29466;30712.692873071206;31141.37717245665;31216;31235.687643123507;2215.29110684581;2235.16181431963;2236.9217077402377;2264.388615073921;2265.920692775745;19694.409167724993;19984.400467985983;20312.49218769528;20334.593308133753;20334.694979575317;2166.6858305545607;2215.8892055397328;2228.554289142162;2234.251525738863;2239.1603148819195;210604;211016;213876.93061534592;221320.78679213324;226167;15392.768578513693;15454.22276438224;15478.690426191639;15769.211539422982;16090;20810;20881.95590220483;21156.942152892294;21240;21251.8937405312;8830.470171789713;10428.426436546062;10530.36817790935;10666.78666426667;10761.192910531741;7994.560299183485;9996.900030999597;10055.59777608902;10085.647002355028;10175.7456063599;9242.260619150586;9363.765905852271;9459.432434053979;9578.078686000837;9585.329026968186;2070.9171633134806;2594.3384436968454;2632.8420294782372;2674.625552422662;2786.84672343019;854.7563944275852;944.527736131934;987.5384431042098;1001.1190152665753;1004.251832384878;21142.682859757115;21398.572028559654;21548.56902861934;22317.553648927256;22614.98232579538;996.8903420623726;1000.8949060348705;1013.4628646817199;1046.131710680149;1050.579768092769;11037.944810276058;11298.830517542243;11346.432678366049;11569.611646602461;11986.280823150639;490061;597549;618890;623121;625726;120392;139360.72876720573;139787;148134.51865481422;149204;1092.836074588818;1098.6484325016079;1194.6475789642077;1221.3709939381226;1227.4108427954632;19263.458923286384;22723.772762272165;22953.311400658007;24769.50460990806;24807.25578232656;72862.63568682123;73151;75275.36536221424;75668;77977.44045118953;96615.0338496606;97666.51166744121;101113;101921.49039254757;102594;11882.930536251433;11914.821277681016;12037.157398982337;12082.939585302018;12101.273923564615;18225.99757013377;18371.173297201924;18378.81621183771;18716.502679785313;18759.062046897874;12572.874271257171;14442.133471991294;15511.069335839886;15537.533873983799;15567.143807090719;11904.285742855456;13596.93201533986;13766.587002389944;13797.724045519235;13810.37853296584;15629;15995.200239988188;15998.440054598033;16010.599735006717;16089.678206435572;12028;12122.212056216318;12274.202176858476;12340.753184936431;12352.135350525377;15428.614284642525;15440.150791706115;15461.5361539154;15469.767953480481;15488.612784680474 12:56:30 INFO - PID 2562 | |3;mootools.html;1340.323136815908;1414.889311890166;1480.9037412568175;1487.9032862863912;1500.5873384819174;13593.048486605941;14256.358463869125;14259.07316024459;14260.215688137174;14270.143791372499;13621.863781362183;14046.578602641937;14047.578572642811;14055.226962517057;14096.64758381041;2787.999999999995;2817.168935164121;2848.5299925512268;2849.9002534911283;2851.5599622190766;2700.5004074246326;2807.1578526442086;2814.2120206342156;2820.182147177316;2820.4077143799827;2737.7809775218034;2737.904173353933;2755.9940621673145;2770.030489328729;2774.1677496750995;2742.163640089772;2752.8210666306827;2769.0862015534926;2771.3764403009263;2776.2226576558596;2726.495598314318;2746.1624204617583;2750.7386798254333;2756.738109879558;2769.6537932758406;13257.535986240531;13527.188368697909;13574.321283935862;13583;13591.660208494768;6918.308169183041;7572.356349710281;7775.377969762406;7824.843503129962;7838.608069596555;4517.576963256563;4723.7874295656775;4936.753162341868;5037.697738135724;5061.873453163664;1128.9613555528933;1385.8336999560117;1397.9720405591831;1416.3272445588264;1422.7296813605383;19014.80985190158;19377.903110484356;20725.792742072535;20757.27349542774;20893.8955305224;6023.186869772612;6682.599044057372;6916.757913473105;6955.756548520879;7001.684924178322;14467.85532144665;14491.710165796625;14509.492167774077;14522.128672279698;14565.198914059829;7541.849163016709;7606.771796846104;7665.501742386727;7692.269234422721;7806.0632724073475;6987.231404545496;7177.5693458391615;7183.964080179566;7239.095113110861;7241.348278654947;4247.702660813713;5247.921281180786;5252.579793616502;5313.149896016619;5326.334208223972;1547.5280039587808;1549.4964136655633;1560.2766306781477;1560.8985415947927;1565.3738504598252;801.9358451323997;819.9057108432487;823.4359463767339;826.2481142160605;854.372036553133;7024.578525288499;7157.069580954547;7209.783706488814;7215.494915355875;7264.600446975362;652.5921299188008;681.8090934538337;705.1573369823114;719.4244604316526;722.0288711682779;4186.497620285585;4558;4729.006908549174;4784.258439941815;4884.413870335511;18403.90798046001;18812.341568045053;19038.61922761537;19129.521761955784;19146.138423770964;18533;18720.719189212174;18884;19025.42923712291;19064.71402928957;8148.307393871587;9111.681091161778;9593.616255349847;9729.173020293354;9775.266854985905;7404.666789994355;7445.143808462096;7490.850182996419;7724.227577242231;7849.686012559662;14123.576292711236;17242.913785431494;17384.652306953536;17408.39070632522;17574.560635984202;16694.916525417782;16971.91514042422;17451.47645570635;17877;17969.460916172535;10911.83632245501;11063.50214240345;11111.333320000826;11118.666440006813;11131.719852216775;12216.450259738156;12546.059045571801;12581;12672.429740661877;12698.428570714155;9908.653197138066;10067.942865998964;10109.595616175271;10310.896891031294;10350.53422596;9809.754756131153;10111.241656875616;10135.594576216869;10467.371957682564;10472.633457828939;9137.908620913971;9256.72229833106;9296.6281348748;9336.859947100935;9410;6488.221413430419;6534.771283005072;6739.730410783513;6767.898481522682;6852.040714300102;6671.366220209073;6922.550034247959;6935.665209244223;6965.094537710268;7014.929850701427 12:56:30 INFO - PID 2562 | |4;prototype.html;425.37682295437196;429.23381763552067;437.37237064811995;456.42718388422537;461.9953800461995;5076.416212135601;6307.905381419282;6407.807765767028;6488.967555162223;6563.573367731106;6265.874682506347;6347.428731414172;6350.7459701612;6361.332060133689;6373.426391624753;3846.230753849234;4089.7546147231114;4577.718238893099;4730.834420795273;4774.379330687006;4143.958560414388;4485.5962963333295;4695.483496815347;4767.499412561666;4781.517729503865;4548.226801443746;4722.268048452478;4740.71555706657;4741.312509686093;4755.690880092783;4465.24090904547;4732.881677958061;4756.7859446324655;4766.833160839354;4805.615550755966;3470.288590838872;3642.2351306225723;4158.438610787535;4191.517975432834;4208.116295577933;5815.9709201453725;6202.255729312466;6218.22272215973;6233.1273621693;6316.557840951135;5546.611737178399;5606.523445507137;5629.268195134648;5633.8309850704545;5637.408072152406;5789.971050144764;6634.834129146761;6784.389404953528;6835.231731148892;6906.447484201302;4306.698531102824;4705.411823522059;4791.921817591049;4793.712377257376;4819.6867203631655;7146.142462904485;7175.533590316612;7180.973318676999;7191.856162876713;7246.275372462765;4500.594946454835;4863.416390033219;4867.245576935581;4889.119958407449;4892.902141957141;4546.568076032771;4946.392422462714;4980.950190498049;4982.875428114254;4987.526185012418;4687.69529980557;4933.827316043993;5603.327600687943;5638.2670252866865;5671.773129074873;5586.329640443173;5872.911906321408;6802.217744959294;6816.113905192294;6926.341997510229;1568.6627375114406;1684.7388654758531;1696.9733311346704;1713.2119225156318;1723.5432610358266;897.6858099665195;1135.8636963564425;1147.6958605969426;1153.8096214124575;1156.8958793708607;5874.853128671732;6044.304904935901;6155.6614386209185;6265.906011409833;6361.872762544724;6812.182538095461;7091.2554181810165;7112.217656057829;7141.750038748619;7167.4266058715175;5122.5400760782995;6369.426751592287;6375.936240637627;6394.26465956421;6525.738970441129;3369.3598216339306;3452.843297495367;3688.5020522229693;3689.114612493036;3689.317476266899;7134.678939447737;7249.420046396275;7259.310365515267;7265.745698900619;7274.418046556263;5942.465178133989;6356.887544679663;6812.3187681232475;7219.711211551479;7253.56478611285;6043.939560604338;6265.028920517145;6808.863822723617;7265.527740696838;7284.562926224444;7018.298170182941;7021.227664956748;7034.859302813813;7067.363937245673;7109.786706398816;6812.182538095461;6882.5870447773295;6896.000079988474;6970.581765094111;6975;6371.617702937839;6935.757248496278;7193.352598266181;7231.710731570678;7260.237675044044;4450.443694538183;4450.554944505524;4779.139754844162;4785.258284965836;4864.513548645107;5727.656340619576;7007.264237255014;7013.894791578233;7015.579065256101;7022.613756243456;5195.7142357170715;6711.261761206361;6736.393724564813;6774.220964589135;6788.524803263922;6356.650384228912;6722.563033402813;6725.730970761115;6736.932630673827;6765.342491089517;6272.560920735687;6585.275619681927;6595.934040659724;6604.3725846044545;6645.667716614246;5327.934413117469;6739.898901516383;6744.22441419243;6752.831179220362;6782.355676210752;5760.423957604206;5983.132445795422;6518.413342799171;6612.173478315211;6617.2721000688925 12:56:30 INFO - PID 2562 | |5;yui.html;766.3524321947954;816.714150047483;839.3830534557096;854.0562678240544;854.1117238072405;14324.28378581072;16215.351385944563;16482.917585412088;16716;16976.915115424435;17139;18844.81155188448;18962.71555926662;18964.810351896478;19011.429657110275;945.5508633399132;949.981000379992;964.2767924056957;974.1816873825985;976.9936964926102;992.1814503034989;995.6714284286204;995.9103680668738;998.3660375661435;999.0558921818885;906.8957069936943;1016.0550687860286;1016.3292227130096;1025.0569476082023;1037.5279247942206;1003.1022235099545;1014.0467960117503;1014.0518615094873;1016.4815943868606;1017.262484698595;731.9085114360705;743.9888401673979;746.3954197100367;747.1743723185856;747.9439042071812;14915.627109322246;16307.429239976544;17325.7401138983;17503;17510.474685759447;17784.733229001446;17901.462956111336;18122.45632631023;18243.72634410485;18249.635007299912;14639.048461850052;14726.969112162153;14770.852291477055;14836.925815370962;14986.400543978336;3527.400341941876;3598.4706499737767;3617.8191090445634;3619.4027985382645;3635.618260082706;111170;144735;148672;152047.47952520443;152135.71796423063;13408.999999999805;13638.386272617756;13650.385732642055;13682.247476388695;13706.109102908278;26644.60033099505;27181.456370872867;27287;27344.726552734617;27391.589126163122;7906.407019473562;7933.127355990837;7969;8000.279974802179;8003.439759216915;4030.1133750574827;4061.472008638917;4099.405586190045;4113.691473139466;4119.114390406017;4617.7691115444095;4645.349651048856;4681.50844161365;4743.739094349775;4746.311784791186;822.1572887790063;858.356232825381;859.8366310401129;864.2135656552507;952.4904176265665;629.169496264935;641.7080228496023;661.8047675935611;678.09812948778;692.3734020711264;11068.28056176346;12011.399430028463;14071.507497237548;14279.143251404741;14307.713845723236;632.9810105696837;648.3386945315751;662.7746566167526;663.2670898657011;676.0400231671039;5368.461882153102;5434.510894019478;6040.184575082396;6239.188905442355;6684.96657516719;148687;150195.24902375418;151094;151223;152746;105031.47484262529;108723;124669.37665311615;124911.50176996409;125403;98596;99069.00930990741;114217;116555;120168.7983120175;10511.316764410289;11372.374519401512;11831.349275789915;12058.51765929353;12446.937765311115;37478;40345.92181887896;44394;45460.77269613631;47959.80100497376;60103;60509.78980420455;77432.61283693771;80043;83883;12159.574414895436;15992.960457570221;16635.833641663914;16959.491215263562;17016.914915425343;18726;18990.905045474683;19033.90483047576;19405;19573.608527829078;17550.648987020442;17600.559985999942;18034;18317.358892439235;18594.163262653485;17342.30630774755;17975.820241797413;18245.81754182494;18290.908545457187;18303;16189;17337.219825107648;17580.296788128842;17638.38265660696;17872.642547149244;11210;11658.41707914584;12021.879781202426;12388.442520086797;12510.812337814757;13151.408186631434;13306.268155251553;14317.928410357881;14610.561683149523;14901.701965960838 12:56:30 INFO - PID 2562 | __end_tp_report 12:56:30 INFO - PID 2562 | __start_cc_report 12:56:30 INFO - PID 2562 | _x_x_mozilla_cycle_collect,401 12:56:30 INFO - PID 2562 | __end_cc_report 12:56:30 INFO - PID 2562 | __startTimestamp1497902190092__endTimestamp 12:56:30 INFO - PID 2562 | 12:56:30 INFO - PID 2562 | ------- Summary: start ------- 12:56:30 INFO - PID 2562 | Number of tests: 6 12:56:30 INFO - PID 2562 | 12:56:30 INFO - PID 2562 | [#0] dojo.html Cycles:165 Average:6304.42 Median:6542.25 stddev:793.64 (12.1%) stddev-sans-first:744.30 12:56:30 INFO - PID 2562 | Values: 2710.3 3523.5 3540.5 3574.1 3642.0 6200.2 6206.9 6251.0 6269.2 6274.8 5257.4 6285.2 6358.6 6368.0 6381.6 5551.2 5586.4 5601.2 5626.0 5677.9 5593.8 5596.6 5686.3 5686.7 5691.0 5449.5 5483.2 5509.3 5552.9 5580.8 5518.7 5549.1 5561.4 5603.5 5612.0 5509.9 5515.7 5553.9 5562.9 5608.4 5194.4 6237.7 6296.3 6341.9 6372.6 6627.8 6855.8 6867.3 6905.9 6906.2 6819.9 6834.9 6874.3 6909.6 6989.2 5998.8 6458.5 6464.8 6473.8 6569.3 6869.5 7200.4 7217.1 7232.1 7240.6 6616.5 6635.5 6648.1 6666.1 6675.2 6869.7 6882.0 6951.3 6967.6 6968.6 6839.9 6876.9 6887.1 6892.5 6893.7 6832.3 6841.8 6881.3 6887.1 6891.1 5393.0 5412.4 5439.5 5466.4 5499.3 4791.1 4854.9 4968.7 4992.5 5016.1 6383.6 6479.3 6482.2 6509.0 6519.5 7023.8 7027.5 7035.6 7069.4 7074.3 6473.0 6477.7 6486.2 6493.4 6519.2 5197.7 5749.5 5936.7 6083.1 6104.0 6971.3 6991.4 7015.7 7147.4 7172.0 6944.2 6966.3 7041.0 7042.0 7069.9 6297.4 6927.4 7173.4 7182.6 7206.2 5952.7 6538.6 6860.9 6874.3 6881.7 6123.0 6804.5 6859.3 7052.4 7052.8 6231.8 6635.7 6686.3 6783.0 6799.0 6653.8 6730.6 6735.6 6763.6 6773.7 6382.9 6484.2 6542.2 6644.5 6662.3 6592.4 6594.2 6608.4 6616.2 6630.4 6510.4 6563.9 6594.4 6595.9 6632.0 12:56:30 INFO - PID 2562 | 12:56:30 INFO - PID 2562 | [#1] ext.html Cycles:180 Average:45512.19 Median:3539.39 stddev:82491.06 (2330.7%) stddev-sans-first:82653.95 12:56:30 INFO - PID 2562 | Values: 733.8 749.4 831.8 844.3 846.4 33100.2 36782.9 37806.0 37825.6 37965.0 34216.8 37128.0 37331.3 37665.0 37677.6 6980.9 7399.9 7507.4 7509.6 7520.3 7482.0 7538.0 7592.0 7596.2 7616.4 7507.2 7519.8 7546.4 7562.8 7567.2 7529.8 7568.7 8101.8 8372.7 8379.1 6934.2 8132.0 8154.9 8208.9 8209.5 35604.3 35856.8 35881.1 35945.0 35971.6 3491.6 3495.8 3510.3 3537.2 3541.6 29334.0 29379.4 29410.3 29508.3 29600.3 3042.6 3118.8 3132.1 3138.2 3154.6 254968.0 258325.0 258478.0 312620.0 315745.0 38205.0 46431.0 47426.1 47470.3 48177.8 48211.0 48696.0 48722.5 48811.8 48827.0 15369.6 15442.5 15553.1 15572.6 15586.5 12536.7 12616.7 12746.0 12749.7 12779.0 13153.5 13326.8 13370.7 13442.7 13443.3 5873.3 5892.2 5915.7 5921.8 5938.6 938.3 962.4 963.0 966.9 1023.3 23041.3 23053.0 23146.3 23865.9 26357.3 893.6 982.8 1074.4 1105.0 1135.9 9483.6 10182.5 11093.9 11783.7 11846.3 350566.0 355851.4 358220.0 358465.0 358820.0 52081.5 52383.2 52676.7 52786.0 52933.7 1528.6 1578.0 1587.1 1588.1 1593.6 22320.0 22534.9 22895.0 23217.0 24261.0 38444.2 38715.0 38763.0 38789.4 38797.4 257551.0 304376.5 307410.0 307949.0 308029.0 34194.7 34754.8 34829.8 34892.5 35145.0 42952.9 44176.3 44708.3 44742.6 44962.6 28915.9 28993.6 29878.3 29912.4 31159.2 26216.0 28143.0 29980.7 30492.7 30724.5 31236.8 35928.5 36915.6 36962.4 37022.0 26810.0 28997.4 29507.0 30499.7 31648.7 19253.6 19712.2 24762.0 25286.0 25725.5 12:56:30 INFO - PID 2562 | 12:56:30 INFO - PID 2562 | [#2] jquery.html Cycles:180 Average:41335.13 Median:111768.04 stddev:102890.99 (92.1%) stddev-sans-first:103133.97 12:56:30 INFO - PID 2562 | Values: 512.9 516.4 517.2 520.1 540.2 22460.8 23342.2 23351.2 23357.8 23488.0 23521.3 23878.0 26009.7 26026.0 26034.7 6552.5 6695.8 6716.9 6720.8 6723.1 6668.5 6722.2 6723.1 6726.8 6733.5 5783.8 6230.1 6395.2 6620.0 6683.3 5460.0 6239.9 6333.6 6354.4 6373.6 5858.1 5861.1 5868.2 5876.3 5877.0 29466.0 30712.7 31141.4 31216.0 31235.7 2215.3 2235.2 2236.9 2264.4 2265.9 19694.4 19984.4 20312.5 20334.6 20334.7 2166.7 2215.9 2228.6 2234.3 2239.2 210604.0 211016.0 213876.9 221320.8 226167.0 15392.8 15454.2 15478.7 15769.2 16090.0 20810.0 20882.0 21156.9 21240.0 21251.9 8830.5 10428.4 10530.4 10666.8 10761.2 7994.6 9996.9 10055.6 10085.6 10175.7 9242.3 9363.8 9459.4 9578.1 9585.3 2070.9 2594.3 2632.8 2674.6 2786.8 854.8 944.5 987.5 1001.1 1004.3 21142.7 21398.6 21548.6 22317.6 22615.0 996.9 1000.9 1013.5 1046.1 1050.6 11037.9 11298.8 11346.4 11569.6 11986.3 490061.0 597549.0 618890.0 623121.0 625726.0 120392.0 139360.7 139787.0 148134.5 149204.0 1092.8 1098.6 1194.6 1221.4 1227.4 19263.5 22723.8 22953.3 24769.5 24807.3 72862.6 73151.0 75275.4 75668.0 77977.4 96615.0 97666.5 101113.0 101921.5 102594.0 11882.9 11914.8 12037.2 12082.9 12101.3 18226.0 18371.2 18378.8 18716.5 18759.1 12572.9 14442.1 15511.1 15537.5 15567.1 11904.3 13596.9 13766.6 13797.7 13810.4 15629.0 15995.2 15998.4 16010.6 16089.7 12028.0 12122.2 12274.2 12340.8 12352.1 15428.6 15440.2 15461.5 15469.8 15488.6 12:56:30 INFO - PID 2562 | 12:56:30 INFO - PID 2562 | [#3] mootools.html Cycles:180 Average:8402.08 Median:2770.70 stddev:5648.02 (203.8%) stddev-sans-first:5638.93 12:56:30 INFO - PID 2562 | Values: 1340.3 1414.9 1480.9 1487.9 1500.6 13593.0 14256.4 14259.1 14260.2 14270.1 13621.9 14046.6 14047.6 14055.2 14096.6 2788.0 2817.2 2848.5 2849.9 2851.6 2700.5 2807.2 2814.2 2820.2 2820.4 2737.8 2737.9 2756.0 2770.0 2774.2 2742.2 2752.8 2769.1 2771.4 2776.2 2726.5 2746.2 2750.7 2756.7 2769.7 13257.5 13527.2 13574.3 13583.0 13591.7 6918.3 7572.4 7775.4 7824.8 7838.6 4517.6 4723.8 4936.8 5037.7 5061.9 1129.0 1385.8 1398.0 1416.3 1422.7 19014.8 19377.9 20725.8 20757.3 20893.9 6023.2 6682.6 6916.8 6955.8 7001.7 14467.9 14491.7 14509.5 14522.1 14565.2 7541.8 7606.8 7665.5 7692.3 7806.1 6987.2 7177.6 7184.0 7239.1 7241.3 4247.7 5247.9 5252.6 5313.1 5326.3 1547.5 1549.5 1560.3 1560.9 1565.4 801.9 819.9 823.4 826.2 854.4 7024.6 7157.1 7209.8 7215.5 7264.6 652.6 681.8 705.2 719.4 722.0 4186.5 4558.0 4729.0 4784.3 4884.4 18403.9 18812.3 19038.6 19129.5 19146.1 18533.0 18720.7 18884.0 19025.4 19064.7 8148.3 9111.7 9593.6 9729.2 9775.3 7404.7 7445.1 7490.9 7724.2 7849.7 14123.6 17242.9 17384.7 17408.4 17574.6 16694.9 16971.9 17451.5 17877.0 17969.5 10911.8 11063.5 11111.3 11118.7 11131.7 12216.5 12546.1 12581.0 12672.4 12698.4 9908.7 10067.9 10109.6 10310.9 10350.5 9809.8 10111.2 10135.6 10467.4 10472.6 9137.9 9256.7 9296.6 9336.9 9410.0 6488.2 6534.8 6739.7 6767.9 6852.0 6671.4 6922.6 6935.7 6965.1 7014.9 12:56:30 INFO - PID 2562 | 12:56:30 INFO - PID 2562 | [#4] prototype.html Cycles:180 Average:5502.62 Median:6210.24 stddev:1702.11 (27.4%) stddev-sans-first:1663.68 12:56:30 INFO - PID 2562 | Values: 425.4 429.2 437.4 456.4 462.0 5076.4 6307.9 6407.8 6489.0 6563.6 6265.9 6347.4 6350.7 6361.3 6373.4 3846.2 4089.8 4577.7 4730.8 4774.4 4144.0 4485.6 4695.5 4767.5 4781.5 4548.2 4722.3 4740.7 4741.3 4755.7 4465.2 4732.9 4756.8 4766.8 4805.6 3470.3 3642.2 4158.4 4191.5 4208.1 5816.0 6202.3 6218.2 6233.1 6316.6 5546.6 5606.5 5629.3 5633.8 5637.4 5790.0 6634.8 6784.4 6835.2 6906.4 4306.7 4705.4 4791.9 4793.7 4819.7 7146.1 7175.5 7181.0 7191.9 7246.3 4500.6 4863.4 4867.2 4889.1 4892.9 4546.6 4946.4 4981.0 4982.9 4987.5 4687.7 4933.8 5603.3 5638.3 5671.8 5586.3 5872.9 6802.2 6816.1 6926.3 1568.7 1684.7 1697.0 1713.2 1723.5 897.7 1135.9 1147.7 1153.8 1156.9 5874.9 6044.3 6155.7 6265.9 6361.9 6812.2 7091.3 7112.2 7141.8 7167.4 5122.5 6369.4 6375.9 6394.3 6525.7 3369.4 3452.8 3688.5 3689.1 3689.3 7134.7 7249.4 7259.3 7265.7 7274.4 5942.5 6356.9 6812.3 7219.7 7253.6 6043.9 6265.0 6808.9 7265.5 7284.6 7018.3 7021.2 7034.9 7067.4 7109.8 6812.2 6882.6 6896.0 6970.6 6975.0 6371.6 6935.8 7193.4 7231.7 7260.2 4450.4 4450.6 4779.1 4785.3 4864.5 5727.7 7007.3 7013.9 7015.6 7022.6 5195.7 6711.3 6736.4 6774.2 6788.5 6356.7 6722.6 6725.7 6736.9 6765.3 6272.6 6585.3 6595.9 6604.4 6645.7 5327.9 6739.9 6744.2 6752.8 6782.4 5760.4 5983.1 6518.4 6612.2 6617.3 12:56:30 INFO - PID 2562 | 12:56:30 INFO - PID 2562 | [#5] yui.html Cycles:180 Average:25936.37 Median:18310.18 stddev:39858.11 (217.7%) stddev-sans-first:39925.12 12:56:30 INFO - PID 2562 | Values: 766.4 816.7 839.4 854.1 854.1 14324.3 16215.4 16482.9 16716.0 16976.9 17139.0 18844.8 18962.7 18964.8 19011.4 945.6 950.0 964.3 974.2 977.0 992.2 995.7 995.9 998.4 999.1 906.9 1016.1 1016.3 1025.1 1037.5 1003.1 1014.0 1014.1 1016.5 1017.3 731.9 744.0 746.4 747.2 747.9 14915.6 16307.4 17325.7 17503.0 17510.5 17784.7 17901.5 18122.5 18243.7 18249.6 14639.0 14727.0 14770.9 14836.9 14986.4 3527.4 3598.5 3617.8 3619.4 3635.6 111170.0 144735.0 148672.0 152047.5 152135.7 13409.0 13638.4 13650.4 13682.2 13706.1 26644.6 27181.5 27287.0 27344.7 27391.6 7906.4 7933.1 7969.0 8000.3 8003.4 4030.1 4061.5 4099.4 4113.7 4119.1 4617.8 4645.3 4681.5 4743.7 4746.3 822.2 858.4 859.8 864.2 952.5 629.2 641.7 661.8 678.1 692.4 11068.3 12011.4 14071.5 14279.1 14307.7 633.0 648.3 662.8 663.3 676.0 5368.5 5434.5 6040.2 6239.2 6685.0 148687.0 150195.2 151094.0 151223.0 152746.0 105031.5 108723.0 124669.4 124911.5 125403.0 98596.0 99069.0 114217.0 116555.0 120168.8 10511.3 11372.4 11831.3 12058.5 12446.9 37478.0 40345.9 44394.0 45460.8 47959.8 60103.0 60509.8 77432.6 80043.0 83883.0 12159.6 15993.0 16635.8 16959.5 17016.9 18726.0 18990.9 19033.9 19405.0 19573.6 17550.6 17600.6 18034.0 18317.4 18594.2 17342.3 17975.8 18245.8 18290.9 18303.0 16189.0 17337.2 17580.3 17638.4 17872.6 11210.0 11658.4 12021.9 12388.4 12510.8 13151.4 13306.3 14317.9 14610.6 14901.7 12:56:30 INFO - PID 2562 | -------- Summary: end -------- 12:56:30 INFO - PID 2562 | 12:56:30 INFO - TEST-INFO | 2562: exit 0 12:56:30 INFO - TEST-OK | dromaeo_css | took 1145544ms 12:56:30 INFO - TEST-START | kraken 12:56:30 INFO - operating with platform_type : mac_ 12:56:30 INFO - Initialising browser for kraken test... 12:56:30 INFO - Application command: /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:57985/getInfo.html -foreground -profile /var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/tmpIFupE2/profile 12:56:30 INFO - TEST-INFO | started process 2569 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:57985/getInfo.html -foreground) 12:57:03 INFO - TEST-INFO | 2569: killed by SIGTERM 12:57:03 INFO - Browser initialized. 12:57:03 INFO - Running cycle 1/1 for kraken test... 12:57:03 INFO - TEST-INFO | started process 2573 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/tmpIFupE2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:57:05 INFO - PID 2573 | 2017-06-19 12:57:05.714 plugin-container[2574:35997] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4743, name = 'com.apple.tsm.portname' 12:57:05 INFO - PID 2573 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:57:05 INFO - PID 2573 | 2017-06-19 12:57:05.715 plugin-container[2574:35997] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x991b, name = 'com.apple.CFPasteboardClient' 12:57:05 INFO - PID 2573 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:57:05 INFO - PID 2573 | 2017-06-19 12:57:05.715 plugin-container[2574:35997] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:57:06 INFO - PID 2573 | 2017-06-19 12:57:06.456 plugin-container[2575:36044] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4643, name = 'com.apple.tsm.portname' 12:57:06 INFO - PID 2573 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:57:06 INFO - PID 2573 | 2017-06-19 12:57:06.457 plugin-container[2575:36044] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4817, name = 'com.apple.CFPasteboardClient' 12:57:06 INFO - PID 2573 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:57:06 INFO - PID 2573 | 2017-06-19 12:57:06.457 plugin-container[2575:36044] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:57:15 INFO - PID 2573 | RSS: Main: 214241280 12:57:15 INFO - PID 2573 | 12:57:18 INFO - PID 2573 | Cycle 1(1): loaded http://localhost:57985/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57985/tests/kraken/driver.html?testName=audio-beat-detection) 12:57:19 INFO - PID 2573 | RSS: Main: 228524032 12:57:19 INFO - PID 2573 | 12:57:21 INFO - PID 2573 | Cycle 1(1): loaded http://localhost:57985/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57985/tests/kraken/driver.html?testName=audio-dft) 12:57:22 INFO - PID 2573 | RSS: Main: 237621248 12:57:22 INFO - PID 2573 | 12:57:25 INFO - PID 2573 | Cycle 1(1): loaded http://localhost:57985/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57985/tests/kraken/driver.html?testName=audio-fft) 12:57:26 INFO - PID 2573 | RSS: Main: 241885184 12:57:26 INFO - PID 2573 | 12:57:28 INFO - PID 2573 | Cycle 1(1): loaded http://localhost:57985/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57985/tests/kraken/driver.html?testName=audio-oscillator) 12:57:28 INFO - PID 2573 | RSS: Main: 243322880 12:57:28 INFO - PID 2573 | 12:57:30 INFO - PID 2573 | Cycle 1(1): loaded http://localhost:57985/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57985/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:57:31 INFO - PID 2573 | RSS: Main: 243580928 12:57:31 INFO - PID 2573 | 12:57:34 INFO - PID 2573 | Cycle 1(1): loaded http://localhost:57985/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57985/tests/kraken/driver.html?testName=imaging-darkroom) 12:57:34 INFO - PID 2573 | RSS: Main: 247791616 12:57:34 INFO - PID 2573 | 12:57:38 INFO - PID 2573 | Cycle 1(1): loaded http://localhost:57985/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57985/tests/kraken/driver.html?testName=imaging-desaturate) 12:57:38 INFO - PID 2573 | RSS: Main: 248025088 12:57:38 INFO - PID 2573 | 12:57:41 INFO - PID 2573 | Cycle 1(1): loaded http://localhost:57985/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57985/tests/kraken/driver.html?testName=json-parse-financial) 12:57:42 INFO - PID 2573 | RSS: Main: 248139776 12:57:42 INFO - PID 2573 | 12:57:43 INFO - PID 2573 | Cycle 1(1): loaded http://localhost:57985/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57985/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:57:44 INFO - PID 2573 | RSS: Main: 247787520 12:57:44 INFO - PID 2573 | 12:57:45 INFO - PID 2573 | Cycle 1(1): loaded http://localhost:57985/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57985/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:57:46 INFO - PID 2573 | RSS: Main: 247738368 12:57:46 INFO - PID 2573 | 12:57:48 INFO - PID 2573 | Cycle 1(1): loaded http://localhost:57985/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57985/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:57:49 INFO - PID 2573 | RSS: Main: 247648256 12:57:49 INFO - PID 2573 | 12:57:52 INFO - PID 2573 | Cycle 1(1): loaded http://localhost:57985/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57985/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:57:53 INFO - PID 2573 | RSS: Main: 247635968 12:57:53 INFO - PID 2573 | 12:57:56 INFO - PID 2573 | Cycle 1(1): loaded http://localhost:57985/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57985/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:57:56 INFO - PID 2573 | RSS: Main: 248655872 12:57:56 INFO - PID 2573 | 12:57:58 INFO - PID 2573 | Cycle 1(1): loaded http://localhost:57985/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57985/tests/kraken/driver.html?testName=ai-astar) 12:57:58 INFO - PID 2573 | RSS: Main: 249720832 12:57:58 INFO - PID 2573 | 12:57:58 INFO - PID 2573 | __start_tp_report 12:57:58 INFO - PID 2573 | _x_x_mozilla_page_load 12:57:58 INFO - PID 2573 | _x_x_mozilla_page_load_details 12:57:58 INFO - PID 2573 | |i|pagename|runs| 12:57:58 INFO - PID 2573 | |0;ai-astar;101;116;104;122;118;114;113;106;150;110 12:57:58 INFO - PID 2573 | |1;audio-beat-detection;142;112;152;148;111;131;129;107;156;123 12:57:58 INFO - PID 2573 | |2;audio-dft;196;203;199;202;199;200;194;199;204;197 12:57:58 INFO - PID 2573 | |3;audio-fft;68;84;82;158;57;61;61;62;125;51 12:57:58 INFO - PID 2573 | |4;audio-oscillator;70;77;123;68;73;71;111;69;72;110 12:57:58 INFO - PID 2573 | |5;imaging-gaussian-blur;89;90;90;88;89;89;90;88;89;88 12:57:58 INFO - PID 2573 | |6;imaging-darkroom;96;97;96;96;97;97;96;109;154;115 12:57:58 INFO - PID 2573 | |7;imaging-desaturate;89;92;90;90;94;92;90;90;90;86 12:57:58 INFO - PID 2573 | |8;json-parse-financial;50;50;52;54;52;53;53;52;50;52 12:57:58 INFO - PID 2573 | |9;json-stringify-tinderbox;53;55;55;62;55;62;56;56;64;47 12:57:58 INFO - PID 2573 | |10;stanford-crypto-aes;78;82;76;77;97;65;85;101;100;92 12:57:58 INFO - PID 2573 | |11;stanford-crypto-ccm;154;149;155;130;138;135;146;132;149;182 12:57:58 INFO - PID 2573 | |12;stanford-crypto-pbkdf2;185;181;186;221;182;179;182;196;179;181 12:57:58 INFO - PID 2573 | |13;stanford-crypto-sha256-iterative;67;67;70;65;67;67;64;66;65;64 12:57:58 INFO - PID 2573 | __end_tp_report 12:57:58 INFO - PID 2573 | __start_cc_report 12:57:58 INFO - PID 2573 | _x_x_mozilla_cycle_collect,1036 12:57:58 INFO - PID 2573 | __end_cc_report 12:57:58 INFO - PID 2573 | __startTimestamp1497902278675__endTimestamp 12:57:58 INFO - PID 2573 | 12:57:58 INFO - PID 2573 | ------- Summary: start ------- 12:57:58 INFO - PID 2573 | Number of tests: 14 12:57:58 INFO - PID 2573 | 12:57:58 INFO - PID 2573 | [#0] ai-astar Cycles:10 Average:115.40 Median:115.00 stddev:13.79 (12.0%) stddev-sans-first:13.60 12:57:58 INFO - PID 2573 | Values: 101.0 116.0 104.0 122.0 118.0 114.0 113.0 106.0 150.0 110.0 12:57:58 INFO - PID 2573 | 12:57:58 INFO - PID 2573 | [#1] audio-beat-detection Cycles:10 Average:131.10 Median:136.50 stddev:17.89 (13.1%) stddev-sans-first:18.54 12:57:58 INFO - PID 2573 | Values: 142.0 112.0 152.0 148.0 111.0 131.0 129.0 107.0 156.0 123.0 12:57:58 INFO - PID 2573 | 12:57:58 INFO - PID 2573 | [#2] audio-dft Cycles:10 Average:199.30 Median:199.50 stddev:3.13 (1.6%) stddev-sans-first:3.08 12:57:58 INFO - PID 2573 | Values: 196.0 203.0 199.0 202.0 199.0 200.0 194.0 199.0 204.0 197.0 12:57:58 INFO - PID 2573 | 12:57:58 INFO - PID 2573 | [#3] audio-fft Cycles:10 Average:80.90 Median:61.50 stddev:34.45 (56.0%) stddev-sans-first:36.22 12:57:58 INFO - PID 2573 | Values: 68.0 84.0 82.0 158.0 57.0 61.0 61.0 62.0 125.0 51.0 12:57:58 INFO - PID 2573 | 12:57:58 INFO - PID 2573 | [#4] audio-oscillator Cycles:10 Average:84.40 Median:70.50 stddev:21.30 (30.2%) stddev-sans-first:21.95 12:57:58 INFO - PID 2573 | Values: 70.0 77.0 123.0 68.0 73.0 71.0 111.0 69.0 72.0 110.0 12:57:58 INFO - PID 2573 | 12:57:58 INFO - PID 2573 | [#5] imaging-gaussian-blur Cycles:10 Average:89.00 Median:89.00 stddev:0.82 (0.9%) stddev-sans-first:0.87 12:57:58 INFO - PID 2573 | Values: 89.0 90.0 90.0 88.0 89.0 89.0 90.0 88.0 89.0 88.0 12:57:58 INFO - PID 2573 | 12:57:58 INFO - PID 2573 | [#6] imaging-darkroom Cycles:10 Average:105.30 Median:96.00 stddev:18.35 (19.1%) stddev-sans-first:19.16 12:57:58 INFO - PID 2573 | Values: 96.0 97.0 96.0 96.0 97.0 97.0 96.0 109.0 154.0 115.0 12:57:58 INFO - PID 2573 | 12:57:58 INFO - PID 2573 | [#7] imaging-desaturate Cycles:10 Average:90.30 Median:90.00 stddev:2.11 (2.3%) stddev-sans-first:2.19 12:57:58 INFO - PID 2573 | Values: 89.0 92.0 90.0 90.0 94.0 92.0 90.0 90.0 90.0 86.0 12:57:58 INFO - PID 2573 | 12:57:58 INFO - PID 2573 | [#8] json-parse-financial Cycles:10 Average:51.80 Median:52.00 stddev:1.40 (2.7%) stddev-sans-first:1.32 12:57:58 INFO - PID 2573 | Values: 50.0 50.0 52.0 54.0 52.0 53.0 53.0 52.0 50.0 52.0 12:57:58 INFO - PID 2573 | 12:57:58 INFO - PID 2573 | [#9] json-stringify-tinderbox Cycles:10 Average:56.50 Median:56.00 stddev:5.02 (9.0%) stddev-sans-first:5.16 12:57:58 INFO - PID 2573 | Values: 53.0 55.0 55.0 62.0 55.0 62.0 56.0 56.0 64.0 47.0 12:57:58 INFO - PID 2573 | 12:57:58 INFO - PID 2573 | [#10] stanford-crypto-aes Cycles:10 Average:85.30 Median:80.00 stddev:11.91 (14.9%) stddev-sans-first:12.33 12:57:58 INFO - PID 2573 | Values: 78.0 82.0 76.0 77.0 97.0 65.0 85.0 101.0 100.0 92.0 12:57:58 INFO - PID 2573 | 12:57:58 INFO - PID 2573 | [#11] stanford-crypto-ccm Cycles:10 Average:147.00 Median:149.00 stddev:15.22 (10.2%) stddev-sans-first:15.94 12:57:58 INFO - PID 2573 | Values: 154.0 149.0 155.0 130.0 138.0 135.0 146.0 132.0 149.0 182.0 12:57:58 INFO - PID 2573 | 12:57:58 INFO - PID 2573 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.20 Median:183.50 stddev:12.87 (7.0%) stddev-sans-first:13.63 12:57:58 INFO - PID 2573 | Values: 185.0 181.0 186.0 221.0 182.0 179.0 182.0 196.0 179.0 181.0 12:57:58 INFO - PID 2573 | 12:57:58 INFO - PID 2573 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.20 Median:67.00 stddev:1.81 (2.7%) stddev-sans-first:1.90 12:57:58 INFO - PID 2573 | Values: 67.0 67.0 70.0 65.0 67.0 67.0 64.0 66.0 65.0 64.0 12:57:58 INFO - PID 2573 | -------- Summary: end -------- 12:57:58 INFO - PID 2573 | 12:57:59 INFO - TEST-INFO | 2573: exit 0 12:57:59 INFO - TEST-OK | kraken | took 88974ms 12:57:59 INFO - SUITE-END | took 1234s 12:57:59 INFO - Completed test suite (00:20:34) 12:57:59 INFO - javascript benchmark 12:57:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8390.865520288253, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2710.308871237834, 3523.5067090607304, 3540.451230059341, 3574.1243395368124, 3642.0, 6200.193974783278, 6206.937930620692, 6251.0, 6269.15366425533, 6274.84312892178, 5257.369115706111, 6285.182926219585, 6358.586691865025, 6367.968160159205, 6381.553291269613, 5551.195076713864, 5586.35756887957, 5601.215829783833, 5625.971870140644, 5677.914831277534, 5593.8321850344355, 5596.552275817945, 5686.31764188298, 5686.687232202227, 5690.971545142268, 5449.536789372908, 5483.2049352844015, 5509.311336082989, 5552.944470555283, 5580.832575022756, 5518.668879867221, 5549.139883318092, 5561.416051314634, 5603.495685388335, 5611.971940140314, 5509.917351239735, 5515.7242137892945, 5553.861153471155, 5562.916556251659, 5608.383077861432, 5194.428612852583, 6237.719302631347, 6296.338884417162, 6341.904871426932, 6372.617642941438, 6627.801165965029, 6855.828604284883, 6867.3476019778045, 6905.861882762367, 6906.171259448848, 6819.897701534481, 6834.931650683479, 6874.312568743136, 6909.619970901598, 6989.161300643905, 5998.820035398945, 6458.483321334282, 6464.806055818333, 6473.805785826433, 6569.277379488252, 6869.484788640872, 7200.423966082701, 7217.097862767154, 7232.0959880015, 7240.637968101574, 6616.503762217853, 6635.535512514078, 6648.069270302161, 6666.0667506549125, 6675.232348279913, 6869.656517174121, 6882.0, 6951.304869513109, 6967.616781076988, 6968.616726080114, 6839.931600684029, 6876.896846547205, 6887.139107611549, 6892.517523773287, 6893.724251030003, 6832.316768323128, 6841.794746157623, 6881.277465866012, 6887.070245516891, 6891.138607674041, 5393.0, 5412.377576578744, 5439.5104440600535, 5466.371367292732, 5499.340079190522, 4791.1075125326, 4854.8786280342565, 4968.652194346361, 4992.475790042066, 5016.1221786188, 6383.585066970632, 6479.254885688206, 6482.189726284215, 6508.967455162694, 6519.511036672268, 7023.754168604074, 7027.472939529555, 7035.648217589202, 7069.399101076466, 7074.257202993715, 6473.0, 6477.740890364333, 6486.2216534016225, 6493.415592596689, 6519.217693876671, 5197.714125723046, 5749.482546570829, 5936.7031648417405, 6083.11794789753, 6104.023356262976, 6971.337722916416, 6991.440684745106, 7015.719371225094, 7147.428205743632, 7172.0, 6944.236134025355, 6966.268541803038, 7041.049458323061, 7041.964790176016, 7069.858602827811, 6297.4017468340435, 6927.406696459741, 7173.390261827803, 7182.569045857265, 7206.171290301682, 5952.672603006703, 6538.5615164663695, 6860.897086543606, 6874.346937040973, 6881.690323935335, 6122.97760420812, 6804.523683342119, 6859.314068593101, 7052.435805135474, 7052.823679408056, 6231.813045608639, 6635.701393437211, 6686.264510903894, 6782.966085169543, 6799.048133261446, 6653.800385988428, 6730.629815360006, 6735.595864248161, 6763.560368576027, 6773.661316934232, 6382.946813775675, 6484.221893372637, 6542.247641521286, 6644.468442524683, 6662.333766623299, 6592.43964263043, 6594.175728033996, 6608.4052435281055, 6616.2391324996315, 6630.403263706289, 6510.381513756375, 6563.901541476977, 6594.406503414716, 6595.901061483985, 6632.038354438483], "value": 6247.361675775654, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [733.7541923455642, 749.3555542233678, 831.7629475599454, 844.3245403677058, 846.4413487098516, 33100.17249568762, 36782.89651310461, 37805.99999999996, 37825.621743782554, 37965.0, 34216.82891585539, 37128.0, 37331.25337493249, 37665.0, 37677.623223767754, 6980.89528657068, 7399.852002959924, 7507.361874240696, 7509.586972716498, 7520.323170914617, 7481.989931359279, 7537.962310188469, 7592.0, 7596.202398748134, 7616.352610028127, 7507.249275072503, 7519.774406767806, 7546.396288296923, 7562.773116806505, 7567.243275672444, 7529.774106776778, 7568.735094271701, 8101.797455063641, 8372.748817535483, 8379.078301386842, 6934.237233904267, 8132.024157101186, 8154.8776768348525, 8208.876866847002, 8209.466384684976, 35604.28791424183, 35856.820715896516, 35881.10297242564, 35945.0, 35971.64028359709, 3491.5810102787573, 3495.8426870790877, 3510.2628448025944, 3537.1510837399096, 3541.628129046439, 29334.0, 29379.41241175186, 29410.264743381373, 29508.262293442836, 29600.25999350012, 3042.604461420023, 3118.844057797101, 3132.1230055584247, 3138.168385377877, 3154.5741324921073, 254968.0, 258324.9999999981, 258478.0, 312620.0, 315745.0, 38205.044873878374, 46431.0, 47426.05147897023, 47470.287945680844, 48177.7955551114, 48211.0, 48696.0, 48722.512774872506, 48811.755941220064, 48827.0, 15369.615759606098, 15442.45951391696, 15553.066815991077, 15572.610684732746, 15586.454474093352, 12536.686582835502, 12616.684582885318, 12746.0, 12749.681257968625, 12778.97768178562, 13153.539626113232, 13326.800097998537, 13370.665733356549, 13442.663933401547, 13443.260620665957, 5873.2658417697785, 5892.175095486636, 5915.733791979371, 5921.822345329647, 5938.554608404387, 938.2634631813993, 962.4033099478369, 962.9807403852024, 966.9419834809793, 1023.2530252915265, 23041.30876073687, 23053.0, 23146.305610831703, 23865.880670596536, 26357.286776359477, 893.5576889439769, 982.7641366072091, 1074.435921141407, 1105.0441368216434, 1135.903448206895, 9483.573239204114, 10182.490875456198, 11093.944530277297, 11783.705407364885, 11846.289222646668, 350566.0, 355851.441485587, 358220.0, 358465.0, 358820.0, 52081.47918520766, 52383.21425178625, 52676.73661631667, 52786.0, 52933.7353313231, 1528.648410865485, 1578.0295118502038, 1587.1429428108693, 1588.1424031023348, 1593.561770513095, 22320.0, 22534.887325563268, 22895.0, 23217.0, 24261.0, 38444.2311153781, 38715.0, 38763.03092422712, 38789.41815872708, 38797.41803872888, 257551.0, 304376.47811761685, 307410.0, 307949.0, 308029.0, 34194.658053419145, 34754.82622586871, 34829.78095766636, 34892.47661285134, 35145.0, 42952.92617684583, 44176.33735494035, 44708.32937505875, 44742.55257447514, 44962.55037449714, 28915.85542072276, 28993.565096523147, 29878.25304367408, 29912.401751964404, 31159.221019474695, 26215.95136194531, 28143.0, 29980.70019299866, 30492.695073048984, 30724.539131912592, 31236.843815780776, 35928.46107308445, 36915.63084369122, 36962.44556331711, 37022.0, 26809.98185739183, 28997.420051598427, 29506.96725614593, 30499.695003049685, 31648.683513164575, 19253.614927701088, 19712.21151153995, 24762.009519619012, 25286.0, 25725.485490290463], "value": 17034.41297819255, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [512.9333186685731, 516.3983958688128, 517.1920257334566, 520.1158031346711, 540.227474711163, 22460.775392246072, 23342.183023594178, 23351.182708605204, 23357.76642233577, 23488.0, 23521.294361169148, 23877.999999999956, 26009.739902601017, 26026.0, 26034.698265086765, 6552.541322107454, 6695.799126026227, 6716.932830671704, 6720.823855825235, 6723.125993620823, 6668.466522678198, 6722.226943901441, 6723.09238252837, 6726.798196054102, 6733.528652994268, 5783.768649254025, 6230.127782110508, 6395.168628078343, 6620.0, 6683.331666833302, 5459.972700136514, 6239.875202495948, 6333.55665103443, 6354.396332348442, 6373.553851230416, 5858.091995740667, 5861.0915308127305, 5868.178455016258, 5876.3242227144, 5877.0, 29466.0, 30712.692873071206, 31141.37717245665, 31216.0, 31235.687643123507, 2215.29110684581, 2235.16181431963, 2236.9217077402377, 2264.388615073921, 2265.920692775745, 19694.409167724993, 19984.400467985983, 20312.49218769528, 20334.593308133753, 20334.694979575317, 2166.6858305545607, 2215.8892055397328, 2228.554289142162, 2234.251525738863, 2239.1603148819195, 210604.0, 211016.0, 213876.93061534592, 221320.78679213324, 226167.0, 15392.768578513693, 15454.22276438224, 15478.690426191639, 15769.211539422982, 16090.0, 20810.0, 20881.95590220483, 21156.942152892294, 21240.0, 21251.8937405312, 8830.470171789713, 10428.426436546062, 10530.36817790935, 10666.78666426667, 10761.192910531741, 7994.560299183485, 9996.900030999597, 10055.59777608902, 10085.647002355028, 10175.7456063599, 9242.260619150586, 9363.765905852271, 9459.432434053979, 9578.078686000837, 9585.329026968186, 2070.9171633134806, 2594.3384436968454, 2632.8420294782372, 2674.625552422662, 2786.84672343019, 854.7563944275852, 944.527736131934, 987.5384431042098, 1001.1190152665753, 1004.251832384878, 21142.682859757115, 21398.572028559654, 21548.56902861934, 22317.553648927256, 22614.98232579538, 996.8903420623726, 1000.8949060348705, 1013.4628646817199, 1046.131710680149, 1050.579768092769, 11037.944810276058, 11298.830517542243, 11346.432678366049, 11569.611646602461, 11986.280823150639, 490061.0, 597549.0, 618890.0, 623121.0, 625726.0, 120392.0, 139360.72876720573, 139787.0, 148134.51865481422, 149204.0, 1092.836074588818, 1098.6484325016079, 1194.6475789642077, 1221.3709939381226, 1227.4108427954632, 19263.458923286384, 22723.772762272165, 22953.311400658007, 24769.50460990806, 24807.25578232656, 72862.63568682123, 73151.0, 75275.36536221424, 75668.0, 77977.44045118953, 96615.0338496606, 97666.51166744121, 101113.0, 101921.49039254757, 102594.0, 11882.930536251433, 11914.821277681016, 12037.157398982337, 12082.939585302018, 12101.273923564615, 18225.99757013377, 18371.173297201924, 18378.81621183771, 18716.502679785313, 18759.062046897874, 12572.874271257171, 14442.133471991294, 15511.069335839886, 15537.533873983799, 15567.143807090719, 11904.285742855456, 13596.93201533986, 13766.587002389944, 13797.724045519235, 13810.37853296584, 15629.0, 15995.200239988188, 15998.440054598033, 16010.599735006717, 16089.678206435572, 12028.0, 12122.212056216318, 12274.202176858476, 12340.753184936431, 12352.135350525377, 15428.614284642525, 15440.150791706115, 15461.5361539154, 15469.767953480481, 15488.612784680474], "value": 12069.724354949663, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1340.323136815908, 1414.889311890166, 1480.9037412568175, 1487.9032862863912, 1500.5873384819174, 13593.048486605941, 14256.358463869125, 14259.07316024459, 14260.215688137174, 14270.143791372499, 13621.863781362183, 14046.578602641937, 14047.578572642811, 14055.226962517057, 14096.64758381041, 2787.999999999995, 2817.168935164121, 2848.5299925512268, 2849.9002534911283, 2851.5599622190766, 2700.5004074246326, 2807.1578526442086, 2814.2120206342156, 2820.182147177316, 2820.4077143799827, 2737.7809775218034, 2737.904173353933, 2755.9940621673145, 2770.030489328729, 2774.1677496750995, 2742.163640089772, 2752.8210666306827, 2769.0862015534926, 2771.3764403009263, 2776.2226576558596, 2726.495598314318, 2746.1624204617583, 2750.7386798254333, 2756.738109879558, 2769.6537932758406, 13257.535986240531, 13527.188368697909, 13574.321283935862, 13583.0, 13591.660208494768, 6918.308169183041, 7572.356349710281, 7775.377969762406, 7824.843503129962, 7838.608069596555, 4517.576963256563, 4723.7874295656775, 4936.753162341868, 5037.697738135724, 5061.873453163664, 1128.9613555528933, 1385.8336999560117, 1397.9720405591831, 1416.3272445588264, 1422.7296813605383, 19014.80985190158, 19377.903110484356, 20725.792742072535, 20757.27349542774, 20893.8955305224, 6023.186869772612, 6682.599044057372, 6916.757913473105, 6955.756548520879, 7001.684924178322, 14467.85532144665, 14491.710165796625, 14509.492167774077, 14522.128672279698, 14565.198914059829, 7541.849163016709, 7606.771796846104, 7665.501742386727, 7692.269234422721, 7806.0632724073475, 6987.231404545496, 7177.5693458391615, 7183.964080179566, 7239.095113110861, 7241.348278654947, 4247.702660813713, 5247.921281180786, 5252.579793616502, 5313.149896016619, 5326.334208223972, 1547.5280039587808, 1549.4964136655633, 1560.2766306781477, 1560.8985415947927, 1565.3738504598252, 801.9358451323997, 819.9057108432487, 823.4359463767339, 826.2481142160605, 854.372036553133, 7024.578525288499, 7157.069580954547, 7209.783706488814, 7215.494915355875, 7264.600446975362, 652.5921299188008, 681.8090934538337, 705.1573369823114, 719.4244604316526, 722.0288711682779, 4186.497620285585, 4558.0, 4729.006908549174, 4784.258439941815, 4884.413870335511, 18403.90798046001, 18812.341568045053, 19038.61922761537, 19129.521761955784, 19146.138423770964, 18533.0, 18720.719189212174, 18884.0, 19025.42923712291, 19064.71402928957, 8148.307393871587, 9111.681091161778, 9593.616255349847, 9729.173020293354, 9775.266854985905, 7404.666789994355, 7445.143808462096, 7490.850182996419, 7724.227577242231, 7849.686012559662, 14123.576292711236, 17242.913785431494, 17384.652306953536, 17408.39070632522, 17574.560635984202, 16694.916525417782, 16971.91514042422, 17451.47645570635, 17877.0, 17969.460916172535, 10911.83632245501, 11063.50214240345, 11111.333320000826, 11118.666440006813, 11131.719852216775, 12216.450259738156, 12546.059045571801, 12581.0, 12672.429740661877, 12698.428570714155, 9908.653197138066, 10067.942865998964, 10109.595616175271, 10310.896891031294, 10350.53422596, 9809.754756131153, 10111.241656875616, 10135.594576216869, 10467.371957682564, 10472.633457828939, 9137.908620913971, 9256.72229833106, 9296.6281348748, 9336.859947100935, 9410.0, 6488.221413430419, 6534.771283005072, 6739.730410783513, 6767.898481522682, 6852.040714300102, 6671.366220209073, 6922.550034247959, 6935.665209244223, 6965.094537710268, 7014.929850701427], "value": 6136.780067019848, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [425.37682295437196, 429.23381763552067, 437.37237064811995, 456.42718388422537, 461.9953800461995, 5076.416212135601, 6307.905381419282, 6407.807765767028, 6488.967555162223, 6563.573367731106, 6265.874682506347, 6347.428731414172, 6350.7459701612, 6361.332060133689, 6373.426391624753, 3846.230753849234, 4089.7546147231114, 4577.718238893099, 4730.834420795273, 4774.379330687006, 4143.958560414388, 4485.5962963333295, 4695.483496815347, 4767.499412561666, 4781.517729503865, 4548.226801443746, 4722.268048452478, 4740.71555706657, 4741.312509686093, 4755.690880092783, 4465.24090904547, 4732.881677958061, 4756.7859446324655, 4766.833160839354, 4805.615550755966, 3470.288590838872, 3642.2351306225723, 4158.438610787535, 4191.517975432834, 4208.116295577933, 5815.9709201453725, 6202.255729312466, 6218.22272215973, 6233.1273621693, 6316.557840951135, 5546.611737178399, 5606.523445507137, 5629.268195134648, 5633.8309850704545, 5637.408072152406, 5789.971050144764, 6634.834129146761, 6784.389404953528, 6835.231731148892, 6906.447484201302, 4306.698531102824, 4705.411823522059, 4791.921817591049, 4793.712377257376, 4819.6867203631655, 7146.142462904485, 7175.533590316612, 7180.973318676999, 7191.856162876713, 7246.275372462765, 4500.594946454835, 4863.416390033219, 4867.245576935581, 4889.119958407449, 4892.902141957141, 4546.568076032771, 4946.392422462714, 4980.950190498049, 4982.875428114254, 4987.526185012418, 4687.69529980557, 4933.827316043993, 5603.327600687943, 5638.2670252866865, 5671.773129074873, 5586.329640443173, 5872.911906321408, 6802.217744959294, 6816.113905192294, 6926.341997510229, 1568.6627375114406, 1684.7388654758531, 1696.9733311346704, 1713.2119225156318, 1723.5432610358266, 897.6858099665195, 1135.8636963564425, 1147.6958605969426, 1153.8096214124575, 1156.8958793708607, 5874.853128671732, 6044.304904935901, 6155.6614386209185, 6265.906011409833, 6361.872762544724, 6812.182538095461, 7091.2554181810165, 7112.217656057829, 7141.750038748619, 7167.4266058715175, 5122.5400760782995, 6369.426751592287, 6375.936240637627, 6394.26465956421, 6525.738970441129, 3369.3598216339306, 3452.843297495367, 3688.5020522229693, 3689.114612493036, 3689.317476266899, 7134.678939447737, 7249.420046396275, 7259.310365515267, 7265.745698900619, 7274.418046556263, 5942.465178133989, 6356.887544679663, 6812.3187681232475, 7219.711211551479, 7253.56478611285, 6043.939560604338, 6265.028920517145, 6808.863822723617, 7265.527740696838, 7284.562926224444, 7018.298170182941, 7021.227664956748, 7034.859302813813, 7067.363937245673, 7109.786706398816, 6812.182538095461, 6882.5870447773295, 6896.000079988474, 6970.581765094111, 6975.0, 6371.617702937839, 6935.757248496278, 7193.352598266181, 7231.710731570678, 7260.237675044044, 4450.443694538183, 4450.554944505524, 4779.139754844162, 4785.258284965836, 4864.513548645107, 5727.656340619576, 7007.264237255014, 7013.894791578233, 7015.579065256101, 7022.613756243456, 5195.7142357170715, 6711.261761206361, 6736.393724564813, 6774.220964589135, 6788.524803263922, 6356.650384228912, 6722.563033402813, 6725.730970761115, 6736.932630673827, 6765.342491089517, 6272.560920735687, 6585.275619681927, 6595.934040659724, 6604.3725846044545, 6645.667716614246, 5327.934413117469, 6739.898901516383, 6744.22441419243, 6752.831179220362, 6782.355676210752, 5760.423957604206, 5983.132445795422, 6518.413342799171, 6612.173478315211, 6617.2721000688925], "value": 4986.026140338509, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [766.3524321947954, 816.714150047483, 839.3830534557096, 854.0562678240544, 854.1117238072405, 14324.28378581072, 16215.351385944563, 16482.917585412088, 16716.0, 16976.915115424435, 17139.0, 18844.81155188448, 18962.71555926662, 18964.810351896478, 19011.429657110275, 945.5508633399132, 949.981000379992, 964.2767924056957, 974.1816873825985, 976.9936964926102, 992.1814503034989, 995.6714284286204, 995.9103680668738, 998.3660375661435, 999.0558921818885, 906.8957069936943, 1016.0550687860286, 1016.3292227130096, 1025.0569476082023, 1037.5279247942206, 1003.1022235099545, 1014.0467960117503, 1014.0518615094873, 1016.4815943868606, 1017.262484698595, 731.9085114360705, 743.9888401673979, 746.3954197100367, 747.1743723185856, 747.9439042071812, 14915.627109322246, 16307.429239976544, 17325.7401138983, 17503.0, 17510.474685759447, 17784.733229001446, 17901.462956111336, 18122.45632631023, 18243.72634410485, 18249.635007299912, 14639.048461850052, 14726.969112162153, 14770.852291477055, 14836.925815370962, 14986.400543978336, 3527.400341941876, 3598.4706499737767, 3617.8191090445634, 3619.4027985382645, 3635.618260082706, 111170.0, 144735.0, 148672.0, 152047.47952520443, 152135.71796423063, 13408.999999999805, 13638.386272617756, 13650.385732642055, 13682.247476388695, 13706.109102908278, 26644.60033099505, 27181.456370872867, 27287.0, 27344.726552734617, 27391.589126163122, 7906.407019473562, 7933.127355990837, 7969.0, 8000.279974802179, 8003.439759216915, 4030.1133750574827, 4061.472008638917, 4099.405586190045, 4113.691473139466, 4119.114390406017, 4617.7691115444095, 4645.349651048856, 4681.50844161365, 4743.739094349775, 4746.311784791186, 822.1572887790063, 858.356232825381, 859.8366310401129, 864.2135656552507, 952.4904176265665, 629.169496264935, 641.7080228496023, 661.8047675935611, 678.09812948778, 692.3734020711264, 11068.28056176346, 12011.399430028463, 14071.507497237548, 14279.143251404741, 14307.713845723236, 632.9810105696837, 648.3386945315751, 662.7746566167526, 663.2670898657011, 676.0400231671039, 5368.461882153102, 5434.510894019478, 6040.184575082396, 6239.188905442355, 6684.96657516719, 148687.0, 150195.24902375418, 151094.0, 151223.0, 152746.0, 105031.47484262529, 108723.0, 124669.37665311615, 124911.50176996409, 125403.0, 98596.0, 99069.00930990741, 114217.0, 116555.0, 120168.7983120175, 10511.316764410289, 11372.374519401512, 11831.349275789915, 12058.51765929353, 12446.937765311115, 37478.0, 40345.92181887896, 44394.0, 45460.77269613631, 47959.80100497376, 60103.0, 60509.78980420455, 77432.61283693771, 80043.0, 83883.0, 12159.574414895436, 15992.960457570221, 16635.833641663914, 16959.491215263562, 17016.914915425343, 18726.0, 18990.905045474683, 19033.90483047576, 19405.0, 19573.608527829078, 17550.648987020442, 17600.559985999942, 18034.0, 18317.358892439235, 18594.163262653485, 17342.30630774755, 17975.820241797413, 18245.81754182494, 18290.908545457187, 18303.0, 16189.0, 17337.219825107648, 17580.296788128842, 17638.38265660696, 17872.642547149244, 11210.0, 11658.41707914584, 12021.879781202426, 12388.442520086797, 12510.812337814757, 13151.408186631434, 13306.268155251553, 14317.928410357881, 14610.561683149523, 14901.701965960838], "value": 8879.674820901888, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1489.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 116.0, 104.0, 122.0, 118.0, 114.0, 113.0, 106.0, 150.0, 110.0], "value": 115.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 112.0, 152.0, 148.0, 111.0, 131.0, 129.0, 107.0, 156.0, 123.0], "value": 131.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 203.0, 199.0, 202.0, 199.0, 200.0, 194.0, 199.0, 204.0, 197.0], "value": 199.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 84.0, 82.0, 158.0, 57.0, 61.0, 61.0, 62.0, 125.0, 51.0], "value": 80.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 77.0, 123.0, 68.0, 73.0, 71.0, 111.0, 69.0, 72.0, 110.0], "value": 84.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 90.0, 90.0, 88.0, 89.0, 89.0, 90.0, 88.0, 89.0, 88.0], "value": 89.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 96.0, 96.0, 97.0, 97.0, 96.0, 109.0, 154.0, 115.0], "value": 105.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 92.0, 90.0, 90.0, 94.0, 92.0, 90.0, 90.0, 90.0, 86.0], "value": 90.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 52.0, 54.0, 52.0, 53.0, 53.0, 52.0, 50.0, 52.0], "value": 51.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 55.0, 55.0, 62.0, 55.0, 62.0, 56.0, 56.0, 64.0, 47.0], "value": 56.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 82.0, 76.0, 77.0, 97.0, 65.0, 85.0, 101.0, 100.0, 92.0], "value": 85.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 149.0, 155.0, 130.0, 138.0, 135.0, 146.0, 132.0, 149.0, 182.0], "value": 147.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 181.0, 186.0, 221.0, 182.0, 179.0, 182.0, 196.0, 179.0, 181.0], "value": 187.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 70.0, 65.0, 67.0, 67.0, 64.0, 66.0, 65.0, 64.0], "value": 66.2, "unit": "score"}]}]} 12:57:59 INFO - Return code: 0 12:58:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:58:00 INFO - # TBPL SUCCESS # 12:58:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:58:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:58:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:58:00 INFO - 2017-06-19 12:58:00.056 screenresolution[2581:36424] starting screenresolution argv=screenresolution get 12:58:00 INFO - 2017-06-19 12:58:00.059 screenresolution[2581:36424] Display 0: 1600x1200x32@60 12:58:00 INFO - 2017-06-19 12:58:00.065 screenresolution[2582:36427] starting screenresolution argv=screenresolution list 12:58:00 INFO - Available Modes on Display 0 12:58:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:58:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:58:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:58:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:58:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:58:00 INFO - Intel Iris: 12:58:00 INFO - Chipset Model: Intel Iris 12:58:00 INFO - Type: GPU 12:58:00 INFO - Bus: Built-In 12:58:00 INFO - VRAM (Dynamic, Max): 1536 MB 12:58:00 INFO - Vendor: Intel (0x8086) 12:58:00 INFO - Device ID: 0x0a2e 12:58:00 INFO - Revision ID: 0x0009 12:58:00 INFO - Displays: 12:58:00 INFO - Display: 12:58:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:58:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:58:00 INFO - Display Serial Number: 1600x1200 60 12:58:00 INFO - Main Display: Yes 12:58:00 INFO - Mirror: Off 12:58:00 INFO - Online: Yes 12:58:00 INFO - Rotation: Supported 12:58:00 INFO - Return code: 0 12:58:00 INFO - Running post-action listener: _resource_record_post_action 12:58:00 INFO - [mozharness: 2017-06-19 19:58:00.308819Z] Finished run-tests step (success) 12:58:00 INFO - Running post-run listener: _resource_record_post_run 12:58:00 INFO - Total resource usage - Wall time: 1256s; CPU: 53.0%; Read bytes: 3159552; Write bytes: 557825024; Read time: 25; Write time: 2135 12:58:00 INFO - TinderboxPrint: CPU usage
53.0% 12:58:00 INFO - TinderboxPrint: I/O read bytes / time
3,159,552 / 25 12:58:00 INFO - TinderboxPrint: I/O write bytes / time
557,825,024 / 2,135 12:58:00 INFO - TinderboxPrint: CPU idle
2,361.1 (47.0%) 12:58:00 INFO - TinderboxPrint: CPU system
1,017.0 (20.2%) 12:58:00 INFO - TinderboxPrint: CPU user
1,647.6 (32.8%) 12:58:00 INFO - TinderboxPrint: Swap in / out
246,124,544 / 0 12:58:00 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 231760384; Write bytes: 228623360; Read time: 16859; Write time: 443 12:58:00 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 12:58:00 INFO - run-tests - Wall time: 1236s; CPU: 53.0%; Read bytes: 2295296; Write bytes: 309835776; Read time: 21; Write time: 1616 12:58:00 INFO - Running post-run listener: _upload_blobber_files 12:58:00 INFO - Blob upload gear active. 12:58:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:58:00 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 12:58:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:58:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:58:01 INFO - (blobuploader) - INFO - Open directory for files ... 12:58:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:58:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:58:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:58:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:58:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:58:02 INFO - (blobuploader) - INFO - Done attempting. 12:58:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:58:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:58:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:58:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:58:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:58:02 INFO - (blobuploader) - INFO - Done attempting. 12:58:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:58:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:58:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:58:03 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:58:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:58:03 INFO - (blobuploader) - INFO - Done attempting. 12:58:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:58:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:58:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:58:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:58:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:58:05 INFO - (blobuploader) - INFO - Done attempting. 12:58:05 INFO - (blobuploader) - INFO - Iteration through files over. 12:58:05 INFO - Return code: 0 12:58:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:58:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:58:05 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3a0461ce93498a46cde23e73f860a38f8a1092cbee6338e7ba586dba3de7a6ed619448dc8689599398e817438f1b9eeab2e842e54910f5eb6fede65e564eb8eb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/07f5d71f6ecc9f7ec646d56fb7c710d07b5e239ebfe5c199c9b6e0cc546549531423dc32ac682b2a13202bbeb4f4ed898182f920f82b865f4b19bae4b7b3c964", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a2de605b838faec94467b88e4b1962d9b9391c176e97ad625ed22a6882177815917c211ae73bc45a51dfa802d32e36d81f11e426bd4bf385887f842e43fc0377", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3262eb929e67b3806563b6a4fbf988dfcc6687449d5db0673ec5fd5228d1c21009b7d38a0307cfa59f2e2beb8e431288e377bbd88ca71eb197331da389b81e68"} 12:58:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:58:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:58:05 INFO - Contents: 12:58:05 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3a0461ce93498a46cde23e73f860a38f8a1092cbee6338e7ba586dba3de7a6ed619448dc8689599398e817438f1b9eeab2e842e54910f5eb6fede65e564eb8eb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/07f5d71f6ecc9f7ec646d56fb7c710d07b5e239ebfe5c199c9b6e0cc546549531423dc32ac682b2a13202bbeb4f4ed898182f920f82b865f4b19bae4b7b3c964", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a2de605b838faec94467b88e4b1962d9b9391c176e97ad625ed22a6882177815917c211ae73bc45a51dfa802d32e36d81f11e426bd4bf385887f842e43fc0377", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3262eb929e67b3806563b6a4fbf988dfcc6687449d5db0673ec5fd5228d1c21009b7d38a0307cfa59f2e2beb8e431288e377bbd88ca71eb197331da389b81e68"} 12:58:05 INFO - Running post-run listener: copy_logs_to_upload_dir 12:58:05 INFO - Copying logs to upload dir... 12:58:05 INFO - mkdir: /builds/slave/test/build/upload/logs 12:58:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1337.279630 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 17 secs) (at 2017-06-19 12:58:05.826247) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-19 12:58:05.829862) ========= 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.osN7GbXiBw/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.1ix1Bfa072/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3a0461ce93498a46cde23e73f860a38f8a1092cbee6338e7ba586dba3de7a6ed619448dc8689599398e817438f1b9eeab2e842e54910f5eb6fede65e564eb8eb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/07f5d71f6ecc9f7ec646d56fb7c710d07b5e239ebfe5c199c9b6e0cc546549531423dc32ac682b2a13202bbeb4f4ed898182f920f82b865f4b19bae4b7b3c964", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a2de605b838faec94467b88e4b1962d9b9391c176e97ad625ed22a6882177815917c211ae73bc45a51dfa802d32e36d81f11e426bd4bf385887f842e43fc0377", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3262eb929e67b3806563b6a4fbf988dfcc6687449d5db0673ec5fd5228d1c21009b7d38a0307cfa59f2e2beb8e431288e377bbd88ca71eb197331da389b81e68"} build_url:https://queue.taskcluster.net/v1/task/JjnqEl7tQoC9uC932cL50g/artifacts/public/build/firefox-55.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011297 build_url: 'https://queue.taskcluster.net/v1/task/JjnqEl7tQoC9uC932cL50g/artifacts/public/build/firefox-55.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3a0461ce93498a46cde23e73f860a38f8a1092cbee6338e7ba586dba3de7a6ed619448dc8689599398e817438f1b9eeab2e842e54910f5eb6fede65e564eb8eb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/07f5d71f6ecc9f7ec646d56fb7c710d07b5e239ebfe5c199c9b6e0cc546549531423dc32ac682b2a13202bbeb4f4ed898182f920f82b865f4b19bae4b7b3c964", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a2de605b838faec94467b88e4b1962d9b9391c176e97ad625ed22a6882177815917c211ae73bc45a51dfa802d32e36d81f11e426bd4bf385887f842e43fc0377", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3262eb929e67b3806563b6a4fbf988dfcc6687449d5db0673ec5fd5228d1c21009b7d38a0307cfa59f2e2beb8e431288e377bbd88ca71eb197331da389b81e68"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-19 12:58:05.863832) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-19 12:58:05.864176) ========= 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.osN7GbXiBw/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.1ix1Bfa072/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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.005365 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-19 12:58:05.916987) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-19 12:58:05.917801) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-19 12:58:05.918484) ========= ========= Total master_lag: 0.19 =========