builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0068 starttime: 1495115228.43 results: success (0) revision: cf4e37d17683e0cbe134debc1d3685fb3f84e3d1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-18 06:47:08.437861) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-18 06:47:08.438646) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-18 06:47:08.439091) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-18 06:47:08.466917) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-18 06:47:08.467481) ========= 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.gUjgQSZ7tG/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.iRUwogP6Gf/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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-05-18 06:47:08-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.9M=0.001s 2017-05-18 06:47:08 (14.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.115149 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-18 06:47:08.606197) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-18 06:47:08.606512) ========= 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.gUjgQSZ7tG/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.iRUwogP6Gf/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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.030141 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-18 06:47:08.658406) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-18 06:47:08.658748) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev cf4e37d17683e0cbe134debc1d3685fb3f84e3d1 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev cf4e37d17683e0cbe134debc1d3685fb3f84e3d1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.gUjgQSZ7tG/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.iRUwogP6Gf/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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-05-18 06:47:08,749 truncating revision to first 12 chars 2017-05-18 06:47:08,750 Setting DEBUG logging. 2017-05-18 06:47:08,750 attempt 1/10 2017-05-18 06:47:08,750 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/cf4e37d17683?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-18 06:47:09,506 unpacking tar archive at: mozilla-central-cf4e37d17683/testing/mozharness/ program finished with exit code 0 elapsedTime=1.024440 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-18 06:47:09.704437) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-18 06:47:09.704741) ========= script_repo_revision: cf4e37d17683e0cbe134debc1d3685fb3f84e3d1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-18 06:47:09.705111) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-18 06:47:09.705383) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-18 06:47:09.723888) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2017-05-18 06:47:09.724200) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.gUjgQSZ7tG/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.iRUwogP6Gf/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 06:47:09 INFO - MultiFileLogger online at 20170518 06:47:09 in /builds/slave/test 06:47:09 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 06:47:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:47:09 INFO - {'append_to_log': False, 06:47:09 INFO - 'base_work_dir': '/builds/slave/test', 06:47:09 INFO - 'blob_upload_branch': 'Firefox', 06:47:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:47:09 INFO - 'branch': 'Firefox', 06:47:09 INFO - 'buildbot_json_path': 'buildprops.json', 06:47:09 INFO - 'config_files': ('talos/mac_config.py',), 06:47:09 INFO - 'default_actions': ('clobber', 06:47:09 INFO - 'read-buildbot-config', 06:47:09 INFO - 'download-and-extract', 06:47:09 INFO - 'populate-webroot', 06:47:09 INFO - 'create-virtualenv', 06:47:09 INFO - 'install', 06:47:09 INFO - 'run-tests'), 06:47:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:47:09 INFO - 'download_minidump_stackwalk': True, 06:47:09 INFO - 'download_symbols': 'ondemand', 06:47:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:47:09 INFO - 'tooltool.py': '/tools/tooltool.py', 06:47:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:47:09 INFO - '/tools/misc-python/virtualenv.py')}, 06:47:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:47:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:47:09 INFO - 'gecko_profile': False, 06:47:09 INFO - 'gecko_profile_interval': 0, 06:47:09 INFO - 'installer_path': 'installer.exe', 06:47:09 INFO - 'log_level': 'info', 06:47:09 INFO - 'log_name': 'talos', 06:47:09 INFO - 'log_to_console': True, 06:47:09 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:47:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:47:09 INFO - 'opt_config_files': (), 06:47:09 INFO - 'pip_index': False, 06:47:09 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:47:09 INFO - 'cmd': ('bash', 06:47:09 INFO - '-c', 06:47:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:47:09 INFO - 'enabled': True, 06:47:09 INFO - 'halt_on_failure': False, 06:47:09 INFO - 'name': 'check_screen_resolution'},), 06:47:09 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:47:09 INFO - 'cmd': ('bash', 06:47:09 INFO - '-c', 06:47:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:47:09 INFO - 'enabled': True, 06:47:09 INFO - 'halt_on_failure': False, 06:47:09 INFO - 'name': 'check_screen_resolution'},), 06:47:09 INFO - 'run_cmd_checks_enabled': True, 06:47:09 INFO - 'suite': 'dromaeojs-e10s', 06:47:09 INFO - 'system_bits': '32', 06:47:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:47:09 INFO - 'title': 't-yosemite-r7-0068', 06:47:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:47:09 INFO - 'use_talos_json': True, 06:47:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:47:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:47:09 INFO - 'work_dir': 'build'} 06:47:09 INFO - [mozharness: 2017-05-18 13:47:09.932442Z] Running clobber step. 06:47:09 INFO - Running pre-action listener: _resource_record_pre_action 06:47:09 INFO - Running main action method: clobber 06:47:09 INFO - rmtree: /builds/slave/test/build 06:47:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:47:12 INFO - Running post-action listener: _resource_record_post_action 06:47:12 INFO - [mozharness: 2017-05-18 13:47:12.387575Z] Finished clobber step (success) 06:47:12 INFO - [mozharness: 2017-05-18 13:47:12.387727Z] Running read-buildbot-config step. 06:47:12 INFO - Running pre-action listener: _resource_record_pre_action 06:47:12 INFO - Running main action method: read_buildbot_config 06:47:12 INFO - Using buildbot properties: 06:47:12 INFO - { 06:47:12 INFO - "project": "", 06:47:12 INFO - "product": "firefox", 06:47:12 INFO - "who": "cbook@mozilla.com", 06:47:12 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 06:47:12 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 06:47:12 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 06:47:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 06:47:12 INFO - "stage_platform": "macosx64", 06:47:12 INFO - "basedir": "/builds/slave/test", 06:47:12 INFO - "buildnumber": 70, 06:47:12 INFO - "platform": "yosemite_r7", 06:47:12 INFO - "parent_task_id": "IMhxRChoQx-Seg1mLagG-Q", 06:47:12 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 06:47:12 INFO - "slavebuilddir": "test", 06:47:12 INFO - "taskId": "YeSlSN0XTlCiyju8x8Jbaw", 06:47:12 INFO - "branch": "mozilla-central", 06:47:12 INFO - "script_repo_revision": "production", 06:47:12 INFO - "revision": "cf4e37d17683e0cbe134debc1d3685fb3f84e3d1", 06:47:12 INFO - "slavename": "t-yosemite-r7-0068", 06:47:12 INFO - "repo_path": "mozilla-central" 06:47:12 INFO - } 06:47:12 INFO - Finding installer, test and symbols from parent task. 06:47:12 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YeSlSN0XTlCiyju8x8Jbaw'}, attempt #1 06:47:12 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YeSlSN0XTlCiyju8x8Jbaw'}, attempt #1 06:47:13 INFO - Task dependencies: V4JNTEZ-SfSYGtNWMly0Tw IMhxRChoQx-Seg1mLagG-Q 06:47:13 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IMhxRChoQx-Seg1mLagG-Q'}, attempt #1 06:47:13 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/IMhxRChoQx-Seg1mLagG-Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 06:47:13 INFO - Set test_url: https://queue.taskcluster.net/v1/task/IMhxRChoQx-Seg1mLagG-Q/artifacts/public/build/test_packages.json 06:47:13 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/IMhxRChoQx-Seg1mLagG-Q/artifacts/public/build/target.crashreporter-symbols.zip 06:47:13 INFO - Running post-action listener: _resource_record_post_action 06:47:13 INFO - [mozharness: 2017-05-18 13:47:13.961868Z] Finished read-buildbot-config step (success) 06:47:13 INFO - [mozharness: 2017-05-18 13:47:13.961991Z] Running download-and-extract step. 06:47:13 INFO - Running pre-action listener: _resource_record_pre_action 06:47:13 INFO - Running main action method: download_and_extract 06:47:13 INFO - mkdir: /builds/slave/test/build/tests 06:47:13 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')]} 06:47:13 INFO - trying https://queue.taskcluster.net/v1/task/IMhxRChoQx-Seg1mLagG-Q/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 06:47:13 INFO - Downloading https://queue.taskcluster.net/v1/task/IMhxRChoQx-Seg1mLagG-Q/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 06:47:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IMhxRChoQx-Seg1mLagG-Q/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 06:47:14 INFO - Downloaded 1325 bytes. 06:47:14 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 06:47:14 INFO - Using the following test package requirements: 06:47:14 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:47:14 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 06:47:14 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 06:47:14 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:47:14 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 06:47:14 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:47:14 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 06:47:14 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:47:14 INFO - u'jsshell-mac.zip'], 06:47:14 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:47:14 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 06:47:14 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 06:47:14 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:47:14 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 06:47:14 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:47:14 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 06:47:14 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:47:14 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 06:47:14 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:47:14 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 06:47:14 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 06:47:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IMhxRChoQx-Seg1mLagG-Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 06:47:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IMhxRChoQx-Seg1mLagG-Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:47:14 INFO - Fetch https://queue.taskcluster.net/v1/task/IMhxRChoQx-Seg1mLagG-Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 06:47:16 INFO - Content-Length response header: 56567871 06:47:16 INFO - Bytes received: 56567871 06:47:34 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 06:47:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IMhxRChoQx-Seg1mLagG-Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 06:47:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IMhxRChoQx-Seg1mLagG-Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:47:34 INFO - Fetch https://queue.taskcluster.net/v1/task/IMhxRChoQx-Seg1mLagG-Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 06:47:37 INFO - Content-Length response header: 56567871 06:47:37 INFO - Bytes received: 56567871 06:47:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IMhxRChoQx-Seg1mLagG-Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 06:47:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IMhxRChoQx-Seg1mLagG-Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 06:47:53 INFO - Fetch https://queue.taskcluster.net/v1/task/IMhxRChoQx-Seg1mLagG-Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 06:47:54 INFO - Content-Length response header: 14410001 06:47:54 INFO - Bytes received: 14410001 06:47:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:47:55 INFO - trying https://queue.taskcluster.net/v1/task/IMhxRChoQx-Seg1mLagG-Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 06:47:55 INFO - Downloading https://queue.taskcluster.net/v1/task/IMhxRChoQx-Seg1mLagG-Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 06:47:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IMhxRChoQx-Seg1mLagG-Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 06:47:57 INFO - Downloaded 71655797 bytes. 06:47:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IMhxRChoQx-Seg1mLagG-Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 06:47:57 INFO - mkdir: /builds/slave/test/properties 06:47:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:47:57 INFO - Writing to file /builds/slave/test/properties/build_url 06:47:57 INFO - Contents: 06:47:57 INFO - build_url:https://queue.taskcluster.net/v1/task/IMhxRChoQx-Seg1mLagG-Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 06:47:57 INFO - Running post-action listener: _resource_record_post_action 06:47:57 INFO - Running post-action listener: set_extra_try_arguments 06:47:57 INFO - [mozharness: 2017-05-18 13:47:57.254972Z] Finished download-and-extract step (success) 06:47:57 INFO - [mozharness: 2017-05-18 13:47:57.255117Z] Running populate-webroot step. 06:47:57 INFO - Running pre-action listener: _resource_record_pre_action 06:47:57 INFO - Running main action method: populate_webroot 06:47:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:47:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:47:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:47:57 INFO - '--tppagecycles', 06:47:57 INFO - '7'], 06:47:57 INFO - 'tests': ['tsvgm']}}, 06:47:57 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:47:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:47:57 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:47:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:47:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:47:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:47:57 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 06:47:57 INFO - 'talos_options': ['--disable-e10s'], 06:47:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:47:57 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 06:47:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:47:57 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 06:47:57 INFO - 'talos_options': ['--disable-e10s'], 06:47:57 INFO - 'tests': ['damp', 'tps']}, 06:47:57 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 06:47:57 INFO - 'tests': ['damp', 'tps']}, 06:47:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:47:57 INFO - 'tests': ['dromaeo_dom']}, 06:47:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:47:57 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:47:57 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 06:47:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 06:47:57 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 06:47:57 INFO - 'tests': ['ts_paint', 'tp5o']}, 06:47:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:47:57 INFO - 'tests': ['a11yr', 06:47:57 INFO - 'ts_paint', 06:47:57 INFO - 'tpaint', 06:47:57 INFO - 'sessionrestore', 06:47:57 INFO - 'sessionrestore_no_auto_restore', 06:47:57 INFO - 'tabpaint']}, 06:47:57 INFO - 'other-e10s': {'tests': ['a11yr', 06:47:57 INFO - 'ts_paint', 06:47:57 INFO - 'tpaint', 06:47:57 INFO - 'sessionrestore', 06:47:57 INFO - 'sessionrestore_no_auto_restore', 06:47:57 INFO - 'tabpaint']}, 06:47:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:47:57 INFO - 'ts_paint', 06:47:57 INFO - 'tpaint', 06:47:57 INFO - 'sessionrestore', 06:47:57 INFO - 'sessionrestore_no_auto_restore', 06:47:57 INFO - 'tabpaint']}, 06:47:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:47:57 INFO - 'ts_paint', 06:47:57 INFO - 'tpaint', 06:47:57 INFO - 'sessionrestore', 06:47:57 INFO - 'sessionrestore_no_auto_restore', 06:47:57 INFO - 'tabpaint']}, 06:47:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:47:57 INFO - 'tests': ['a11yr', 06:47:57 INFO - 'ts_paint', 06:47:57 INFO - 'tpaint', 06:47:57 INFO - 'sessionrestore', 06:47:57 INFO - 'sessionrestore_no_auto_restore', 06:47:57 INFO - 'tabpaint']}, 06:47:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:47:57 INFO - 'tests': ['a11yr', 06:47:57 INFO - 'ts_paint', 06:47:57 INFO - 'tpaint', 06:47:57 INFO - 'sessionrestore', 06:47:57 INFO - 'sessionrestore_no_auto_restore', 06:47:57 INFO - 'tabpaint']}, 06:47:57 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 06:47:57 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 06:47:57 INFO - 'bloom_basic_ref']}, 06:47:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:47:57 INFO - 'tests': ['tsvgx', 06:47:57 INFO - 'tsvgr_opacity', 06:47:57 INFO - 'tart', 06:47:57 INFO - 'tscrollx', 06:47:57 INFO - 'cart', 06:47:57 INFO - 'tsvg_static']}, 06:47:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:47:57 INFO - 'tsvgr_opacity', 06:47:57 INFO - 'tart', 06:47:57 INFO - 'tscrollx', 06:47:57 INFO - 'cart', 06:47:57 INFO - 'tsvg_static']}, 06:47:57 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 06:47:57 INFO - 'talos_options': ['--disable-e10s'], 06:47:57 INFO - 'tests': ['tp5o']}, 06:47:57 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 06:47:57 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 06:47:57 INFO - 'talos_options': ['--disable-e10s', 06:47:57 INFO - '--xperf_path', 06:47:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:47:57 INFO - 'tests': ['tp5n']}, 06:47:57 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 06:47:57 INFO - 'talos_options': ['--xperf_path', 06:47:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:47:57 INFO - 'tests': ['tp5n']}}, 06:47:57 INFO - 'talos.zip': {'path': '', 06:47:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:47:57 INFO - Running post-action listener: _resource_record_post_action 06:47:57 INFO - [mozharness: 2017-05-18 13:47:57.276708Z] Finished populate-webroot step (success) 06:47:57 INFO - [mozharness: 2017-05-18 13:47:57.276852Z] Running create-virtualenv step. 06:47:57 INFO - Running pre-action listener: _resource_record_pre_action 06:47:57 INFO - Running main action method: create_virtualenv 06:47:57 INFO - Creating virtualenv /builds/slave/test/build/venv 06:47:57 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 06:47:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:47:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:47:57 INFO - Using real prefix '/tools/python27' 06:47:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:47:58 INFO - Installing distribute.............................................................................................................................................................................................done. 06:48:01 INFO - Installing pip.................done. 06:48:01 INFO - Return code: 0 06:48:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 06:48:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 06:48:01 INFO - Reading from file tmpfile_stdout 06:48:01 INFO - Output received: 06:48:01 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) 06:48:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:48:01 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')]} 06:48:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:48: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')]} 06:48:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:48: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103f63b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f67768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1c0477f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c73d30>, '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 0x103c27db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103c68be8>, '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.gUjgQSZ7tG/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.iRUwogP6Gf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 06:48: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', 'pip>=1.5'] in /builds/slave/test/build 06:48: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 pip>=1.5 06:48:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gUjgQSZ7tG/Render', 06:48:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:48:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:48:02 INFO - 'HOME': '/Users/cltbld', 06:48:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:48:02 INFO - 'LOGNAME': 'cltbld', 06:48:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:48:02 INFO - 'MOZ_NO_REMOTE': '1', 06:48:02 INFO - 'NO_EM_RESTART': '1', 06:48:02 INFO - 'PAGER': '/bin/cat', 06:48:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:48:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:48:02 INFO - 'PWD': '/builds/slave/test', 06:48:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:48:02 INFO - 'SHELL': '/bin/bash', 06:48:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iRUwogP6Gf/Listeners', 06:48:02 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 06:48:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:48:02 INFO - 'USER': 'cltbld', 06:48:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:48:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:48:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:48:02 INFO - 'XPC_FLAGS': '0x0', 06:48:02 INFO - 'XPC_SERVICE_NAME': '0', 06:48:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:48:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:48:02 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 06:48:02 INFO - Cleaning up... 06:48:02 INFO - Return code: 0 06:48:02 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:48: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')]} 06:48:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:48: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')]} 06:48:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:48:03 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 0x103f63b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f67768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1c0477f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c73d30>, '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 0x103c27db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103c68be8>, '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.gUjgQSZ7tG/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.iRUwogP6Gf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 06:48:03 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 06:48:03 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 06:48:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gUjgQSZ7tG/Render', 06:48:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:48:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:48:03 INFO - 'HOME': '/Users/cltbld', 06:48:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:48:03 INFO - 'LOGNAME': 'cltbld', 06:48:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:48:03 INFO - 'MOZ_NO_REMOTE': '1', 06:48:03 INFO - 'NO_EM_RESTART': '1', 06:48:03 INFO - 'PAGER': '/bin/cat', 06:48:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:48:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:48:03 INFO - 'PWD': '/builds/slave/test', 06:48:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:48:03 INFO - 'SHELL': '/bin/bash', 06:48:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iRUwogP6Gf/Listeners', 06:48:03 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 06:48:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:48:03 INFO - 'USER': 'cltbld', 06:48:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:48:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:48:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:48:03 INFO - 'XPC_FLAGS': '0x0', 06:48:03 INFO - 'XPC_SERVICE_NAME': '0', 06:48:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:48:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:48:03 INFO - Downloading/unpacking psutil>=3.1.1 06:48:03 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 06:48:03 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 06:48:03 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 06:48:03 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 06:48:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:48:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:48:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:48:05 INFO - Installing collected packages: psutil 06:48:05 INFO - Running setup.py install for psutil 06:48:05 INFO - building 'psutil._psutil_osx' extension 06:48:05 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 06:48:06 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 06:48:06 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 06:48:06 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 06:48:06 INFO - building 'psutil._psutil_posix' extension 06:48:06 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 06:48:06 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:48:06 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:48:06 INFO - ^ 06:48:06 INFO - 1 warning generated. 06:48:06 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 06:48:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:48:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:48:06 INFO - Successfully installed psutil 06:48:06 INFO - Cleaning up... 06:48:06 INFO - Return code: 0 06:48:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:48:06 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')]} 06:48:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:48:06 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')]} 06:48:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:48:06 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 0x103f63b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f67768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1c0477f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c73d30>, '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 0x103c27db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103c68be8>, '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.gUjgQSZ7tG/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.iRUwogP6Gf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 06:48:06 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 06:48:06 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 06:48:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gUjgQSZ7tG/Render', 06:48:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:48:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:48:06 INFO - 'HOME': '/Users/cltbld', 06:48:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:48:06 INFO - 'LOGNAME': 'cltbld', 06:48:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:48:06 INFO - 'MOZ_NO_REMOTE': '1', 06:48:06 INFO - 'NO_EM_RESTART': '1', 06:48:06 INFO - 'PAGER': '/bin/cat', 06:48:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:48:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:48:06 INFO - 'PWD': '/builds/slave/test', 06:48:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:48:06 INFO - 'SHELL': '/bin/bash', 06:48:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iRUwogP6Gf/Listeners', 06:48:06 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 06:48:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:48:06 INFO - 'USER': 'cltbld', 06:48:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:48:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:48:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:48:06 INFO - 'XPC_FLAGS': '0x0', 06:48:06 INFO - 'XPC_SERVICE_NAME': '0', 06:48:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:48:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:48:07 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:48:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:08 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:48:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:48:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:48:08 INFO - Installing collected packages: mozsystemmonitor 06:48:08 INFO - Running setup.py install for mozsystemmonitor 06:48:08 INFO - Successfully installed mozsystemmonitor 06:48:08 INFO - Cleaning up... 06:48:08 INFO - Return code: 0 06:48:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:48:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:48:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:48:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:48:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:48:08 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 0x103f63b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f67768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1c0477f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c73d30>, '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 0x103c27db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103c68be8>, '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.gUjgQSZ7tG/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.iRUwogP6Gf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 06:48:08 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 06:48:08 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 06:48:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gUjgQSZ7tG/Render', 06:48:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:48:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:48:08 INFO - 'HOME': '/Users/cltbld', 06:48:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:48:08 INFO - 'LOGNAME': 'cltbld', 06:48:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:48:08 INFO - 'MOZ_NO_REMOTE': '1', 06:48:08 INFO - 'NO_EM_RESTART': '1', 06:48:08 INFO - 'PAGER': '/bin/cat', 06:48:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:48:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:48:08 INFO - 'PWD': '/builds/slave/test', 06:48:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:48:08 INFO - 'SHELL': '/bin/bash', 06:48:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iRUwogP6Gf/Listeners', 06:48:08 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 06:48:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:48:08 INFO - 'USER': 'cltbld', 06:48:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:48:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:48:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:48:08 INFO - 'XPC_FLAGS': '0x0', 06:48:08 INFO - 'XPC_SERVICE_NAME': '0', 06:48:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:48:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:48:09 INFO - Downloading/unpacking jsonschema==2.5.1 06:48:09 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 06:48:09 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 06:48:09 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 06:48:09 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 06:48:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:48:10 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:48:10 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 06:48:10 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 06:48:10 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 06:48:10 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 06:48:10 INFO - Downloading functools32-3.2.3-2.tar.gz 06:48:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:48:10 INFO - warning: no files found matching '*.txt' 06:48:10 INFO - no previously-included directories found matching 'build' 06:48:10 INFO - no previously-included directories found matching 'dist' 06:48:10 INFO - no previously-included directories found matching '.git*' 06:48:10 INFO - Installing collected packages: jsonschema, functools32 06:48:10 INFO - Running setup.py install for functools32 06:48:11 INFO - warning: no files found matching '*.txt' 06:48:11 INFO - no previously-included directories found matching 'build' 06:48:11 INFO - no previously-included directories found matching 'dist' 06:48:11 INFO - no previously-included directories found matching '.git*' 06:48:11 INFO - Successfully installed jsonschema functools32 06:48:11 INFO - Cleaning up... 06:48:11 INFO - Return code: 0 06:48:11 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:48:11 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')]} 06:48:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:48:11 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')]} 06:48:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:48:11 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 0x103f63b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f67768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1c0477f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c73d30>, '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 0x103c27db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103c68be8>, '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.gUjgQSZ7tG/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.iRUwogP6Gf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 06:48:11 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 06:48:11 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 06:48:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gUjgQSZ7tG/Render', 06:48:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:48:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:48:11 INFO - 'HOME': '/Users/cltbld', 06:48:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:48:11 INFO - 'LOGNAME': 'cltbld', 06:48:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:48:11 INFO - 'MOZ_NO_REMOTE': '1', 06:48:11 INFO - 'NO_EM_RESTART': '1', 06:48:11 INFO - 'PAGER': '/bin/cat', 06:48:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:48:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:48:11 INFO - 'PWD': '/builds/slave/test', 06:48:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:48:11 INFO - 'SHELL': '/bin/bash', 06:48:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iRUwogP6Gf/Listeners', 06:48:11 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 06:48:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:48:11 INFO - 'USER': 'cltbld', 06:48:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:48:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:48:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:48:11 INFO - 'XPC_FLAGS': '0x0', 06:48:11 INFO - 'XPC_SERVICE_NAME': '0', 06:48:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:48:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:48:11 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:48:11 INFO - Cleaning up... 06:48:11 INFO - Return code: 0 06:48:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:48:11 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')]} 06:48:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:48:11 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')]} 06:48:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:48:11 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 0x103f63b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f67768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1c0477f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c73d30>, '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 0x103c27db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103c68be8>, '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.gUjgQSZ7tG/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.iRUwogP6Gf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 06:48:11 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 06:48:11 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 06:48:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gUjgQSZ7tG/Render', 06:48:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:48:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:48:11 INFO - 'HOME': '/Users/cltbld', 06:48:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:48:11 INFO - 'LOGNAME': 'cltbld', 06:48:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:48:11 INFO - 'MOZ_NO_REMOTE': '1', 06:48:11 INFO - 'NO_EM_RESTART': '1', 06:48:11 INFO - 'PAGER': '/bin/cat', 06:48:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:48:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:48:11 INFO - 'PWD': '/builds/slave/test', 06:48:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:48:11 INFO - 'SHELL': '/bin/bash', 06:48:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iRUwogP6Gf/Listeners', 06:48:11 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 06:48:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:48:11 INFO - 'USER': 'cltbld', 06:48:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:48:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:48:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:48:11 INFO - 'XPC_FLAGS': '0x0', 06:48:11 INFO - 'XPC_SERVICE_NAME': '0', 06:48:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:48:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:48:11 INFO - Downloading/unpacking blobuploader==1.2.4 06:48:11 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 06:48:11 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 06:48:11 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 06:48:11 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 06:48:13 INFO - Downloading blobuploader-1.2.4.tar.gz 06:48:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:48:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:48:13 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 06:48:13 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 06:48:13 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 06:48:13 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 06:48:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:48:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:48:13 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 06:48:13 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 06:48:13 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 06:48:13 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 06:48:13 INFO - Downloading docopt-0.6.1.tar.gz 06:48:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:48:14 INFO - Installing collected packages: blobuploader, requests, docopt 06:48:14 INFO - Running setup.py install for blobuploader 06:48:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:48:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:48:14 INFO - Running setup.py install for requests 06:48:14 INFO - Running setup.py install for docopt 06:48:14 INFO - Successfully installed blobuploader requests docopt 06:48:14 INFO - Cleaning up... 06:48:14 INFO - Return code: 0 06:48:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:48:14 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')]} 06:48:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:48:14 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')]} 06:48:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:48:14 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 0x103f63b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f67768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1c0477f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c73d30>, '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 0x103c27db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103c68be8>, '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.gUjgQSZ7tG/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.iRUwogP6Gf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 06:48:14 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 06:48:14 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 06:48:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gUjgQSZ7tG/Render', 06:48:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:48:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:48:14 INFO - 'HOME': '/Users/cltbld', 06:48:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:48:14 INFO - 'LOGNAME': 'cltbld', 06:48:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:48:14 INFO - 'MOZ_NO_REMOTE': '1', 06:48:14 INFO - 'NO_EM_RESTART': '1', 06:48:14 INFO - 'PAGER': '/bin/cat', 06:48:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:48:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:48:14 INFO - 'PWD': '/builds/slave/test', 06:48:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:48:14 INFO - 'SHELL': '/bin/bash', 06:48:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iRUwogP6Gf/Listeners', 06:48:14 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 06:48:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:48:14 INFO - 'USER': 'cltbld', 06:48:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:48:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:48:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:48:14 INFO - 'XPC_FLAGS': '0x0', 06:48:14 INFO - 'XPC_SERVICE_NAME': '0', 06:48:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:48:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:48:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:48:15 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-OD7fRP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:48:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:48:15 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-R52MA0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:48:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:48:15 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-YkBWEh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:48:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:48:15 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-ogjMDZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:48:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:48:15 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-sqb3H5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:48:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:48:15 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-0EH8Ai-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:48:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:48:15 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-gfK4mF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:48:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:48:15 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-981RJ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:48:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:48:15 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-Jbd85G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:48:16 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-Zc9gEz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:48:16 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-fCmyHk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:48:16 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-v3i9yV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:48:16 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-yKKagV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:48:16 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-vh6uHU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:48:16 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-Hyl_J_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:48:16 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-N3V4bb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:48:16 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-aWR6KL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:48:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:48:16 INFO - Running setup.py install for manifestparser 06:48:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:48:17 INFO - Running setup.py install for mozcrash 06:48:17 INFO - Running setup.py install for mozdebug 06:48:17 INFO - Running setup.py install for mozdevice 06:48:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:48:17 INFO - Running setup.py install for mozfile 06:48:17 INFO - Running setup.py install for mozhttpd 06:48:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:48:17 INFO - Running setup.py install for mozinfo 06:48:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:48:17 INFO - Running setup.py install for mozInstall 06:48:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:48:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:48:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:48:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:48:18 INFO - Running setup.py install for mozleak 06:48:18 INFO - Running setup.py install for mozlog 06:48:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:48:18 INFO - Running setup.py install for moznetwork 06:48:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:48:18 INFO - Running setup.py install for mozprocess 06:48:18 INFO - Running setup.py install for mozprofile 06:48:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:48:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:48:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:48:18 INFO - Running setup.py install for mozrunner 06:48:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:48:19 INFO - Running setup.py install for mozscreenshot 06:48:19 INFO - Running setup.py install for moztest 06:48:19 INFO - Running setup.py install for mozversion 06:48:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:48:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:48:19 INFO - Cleaning up... 06:48:19 INFO - Return code: 0 06:48:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:48:19 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')]} 06:48:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:48:19 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')]} 06:48:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:48:19 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 0x103f63b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f67768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1c0477f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c73d30>, '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 0x103c27db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103c68be8>, '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.gUjgQSZ7tG/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.iRUwogP6Gf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 06:48:19 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 06:48:19 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 06:48:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gUjgQSZ7tG/Render', 06:48:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:48:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:48:19 INFO - 'HOME': '/Users/cltbld', 06:48:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:48:19 INFO - 'LOGNAME': 'cltbld', 06:48:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:48:19 INFO - 'MOZ_NO_REMOTE': '1', 06:48:19 INFO - 'NO_EM_RESTART': '1', 06:48:19 INFO - 'PAGER': '/bin/cat', 06:48:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:48:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:48:19 INFO - 'PWD': '/builds/slave/test', 06:48:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:48:19 INFO - 'SHELL': '/bin/bash', 06:48:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iRUwogP6Gf/Listeners', 06:48:19 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 06:48:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:48:19 INFO - 'USER': 'cltbld', 06:48:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:48:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:48:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:48:19 INFO - 'XPC_FLAGS': '0x0', 06:48:19 INFO - 'XPC_SERVICE_NAME': '0', 06:48:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:48:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:48:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:48:19 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-UJSW6U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:48:19 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)) 06:48:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:48:19 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-aTBJUB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:48:20 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)) 06:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:48:20 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-B0dM3O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:48:20 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)) 06:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:48:20 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-MoNtYF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:48:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 06:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:48:20 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-iOeXJy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:48:20 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)) 06:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:48:20 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-Vf0MlX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:48:20 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)) 06:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:48:20 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-HXsplJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:48:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 06:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:48:20 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-eTcUXb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:48:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 06:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:48:20 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-Vopsn4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:48:20 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)) 06:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:48:20 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-RJo4rx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:48:20 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)) 06:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:48:20 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-qb2f4R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:48:20 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)) 06:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:48:20 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-IzLkt0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:48:21 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)) 06:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:48:21 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-oRnK6X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:48:21 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)) 06:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:48:21 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-b24mWm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:48:21 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)) 06:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:48:21 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-3Ex0ou-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:48:21 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)) 06:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:48:21 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-DgsEjT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:48:21 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)) 06:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:48:21 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-577m68-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:48:21 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)) 06:48:21 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)) 06:48:21 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)) 06:48:21 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)) 06:48:21 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:48:21 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:48:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:48:21 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 06:48:21 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 06:48:21 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 06:48:21 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 06:48:22 INFO - Downloading blessings-1.6.tar.gz 06:48:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:48:23 INFO - Installing collected packages: blessings 06:48:23 INFO - Running setup.py install for blessings 06:48:23 INFO - Successfully installed blessings 06:48:23 INFO - Cleaning up... 06:48:23 INFO - Return code: 0 06:48:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:48:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:48:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:48:23 INFO - Reading from file tmpfile_stdout 06:48:23 INFO - Current package versions: 06:48:23 INFO - blessings == 1.6 06:48:23 INFO - blobuploader == 1.2.4 06:48:23 INFO - docopt == 0.6.1 06:48:23 INFO - functools32 == 3.2.3-2 06:48:23 INFO - jsonschema == 2.5.1 06:48:23 INFO - manifestparser == 1.1 06:48:23 INFO - mozInstall == 1.12 06:48:23 INFO - mozcrash == 1.0 06:48:23 INFO - mozdebug == 0.1 06:48:23 INFO - mozdevice == 0.50 06:48:23 INFO - mozfile == 1.2 06:48:23 INFO - mozhttpd == 0.7 06:48:23 INFO - mozinfo == 0.9 06:48:23 INFO - mozleak == 0.1 06:48:23 INFO - mozlog == 3.4 06:48:23 INFO - moznetwork == 0.27 06:48:23 INFO - mozprocess == 0.25 06:48:23 INFO - mozprofile == 0.28 06:48:23 INFO - mozrunner == 6.13 06:48:23 INFO - mozscreenshot == 0.1 06:48:23 INFO - mozsystemmonitor == 0.3 06:48:23 INFO - moztest == 0.8 06:48:23 INFO - mozversion == 1.4 06:48:23 INFO - psutil == 3.1.1 06:48:23 INFO - requests == 1.2.3 06:48:23 INFO - wsgiref == 0.1.2 06:48:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:48:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:48:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:48:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:48:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:48:23 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 0x103f63b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f67768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1c0477f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c73d30>, '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 0x103c27db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103c68be8>, '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.gUjgQSZ7tG/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.iRUwogP6Gf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 06:48:23 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 06:48:23 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 06:48:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gUjgQSZ7tG/Render', 06:48:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:48:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:48:23 INFO - 'HOME': '/Users/cltbld', 06:48:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:48:23 INFO - 'LOGNAME': 'cltbld', 06:48:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:48:23 INFO - 'MOZ_NO_REMOTE': '1', 06:48:23 INFO - 'NO_EM_RESTART': '1', 06:48:23 INFO - 'PAGER': '/bin/cat', 06:48:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:48:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:48:23 INFO - 'PWD': '/builds/slave/test', 06:48:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:48:23 INFO - 'SHELL': '/bin/bash', 06:48:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iRUwogP6Gf/Listeners', 06:48:23 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 06:48:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:48:23 INFO - 'USER': 'cltbld', 06:48:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:48:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:48:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:48:23 INFO - 'XPC_FLAGS': '0x0', 06:48:23 INFO - 'XPC_SERVICE_NAME': '0', 06:48:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:48:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:48:23 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)) 06:48:23 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)) 06:48:23 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)) 06:48:23 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)) 06:48:23 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)) 06:48:23 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)) 06:48:23 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)) 06:48:23 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)) 06:48:23 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)) 06:48:23 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)) 06:48:23 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)) 06:48:23 INFO - Cleaning up... 06:48:23 INFO - Return code: 0 06:48:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:48:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:48:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:48:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:48:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:48:23 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 0x103f63b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f67768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1c0477f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c73d30>, '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 0x103c27db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103c68be8>, '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.gUjgQSZ7tG/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.iRUwogP6Gf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 06:48:23 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 06:48:23 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 06:48:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gUjgQSZ7tG/Render', 06:48:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:48:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:48:23 INFO - 'HOME': '/Users/cltbld', 06:48:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:48:23 INFO - 'LOGNAME': 'cltbld', 06:48:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:48:23 INFO - 'MOZ_NO_REMOTE': '1', 06:48:23 INFO - 'NO_EM_RESTART': '1', 06:48:23 INFO - 'PAGER': '/bin/cat', 06:48:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:48:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:48:23 INFO - 'PWD': '/builds/slave/test', 06:48:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:48:23 INFO - 'SHELL': '/bin/bash', 06:48:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iRUwogP6Gf/Listeners', 06:48:23 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 06:48:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:48:23 INFO - 'USER': 'cltbld', 06:48:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:48:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:48:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:48:23 INFO - 'XPC_FLAGS': '0x0', 06:48:23 INFO - 'XPC_SERVICE_NAME': '0', 06:48:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:48:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:48:24 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:48:24 INFO - Cleaning up... 06:48:24 INFO - Return code: 0 06:48:24 INFO - Running post-action listener: _resource_record_post_action 06:48:24 INFO - Running post-action listener: _start_resource_monitoring 06:48:24 INFO - Starting resource monitoring. 06:48:24 INFO - [mozharness: 2017-05-18 13:48:24.178054Z] Finished create-virtualenv step (success) 06:48:24 INFO - [mozharness: 2017-05-18 13:48:24.179071Z] Running install step. 06:48:24 INFO - Running pre-action listener: _resource_record_pre_action 06:48:24 INFO - Running main action method: install 06:48:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:48:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:48:24 INFO - Reading from file tmpfile_stdout 06:48:24 INFO - Detecting whether we're running mozinstall >=1.0... 06:48:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:48:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:48:24 INFO - Reading from file tmpfile_stdout 06:48:24 INFO - Output received: 06:48:24 INFO - Usage: mozinstall [options] installer 06:48:24 INFO - Options: 06:48:24 INFO - -h, --help show this help message and exit 06:48:24 INFO - -d DEST, --destination=DEST 06:48:24 INFO - Directory to install application into. [default: 06:48:24 INFO - "/builds/slave/test"] 06:48:24 INFO - --app=APP Application being installed. [default: firefox] 06:48:24 INFO - mkdir: /builds/slave/test/build/application 06:48:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 06:48:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 06:48:46 INFO - Reading from file tmpfile_stdout 06:48:46 INFO - Output received: 06:48:46 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 06:48:46 INFO - Running post-action listener: _resource_record_post_action 06:48:46 INFO - [mozharness: 2017-05-18 13:48:46.933240Z] Finished install step (success) 06:48:46 INFO - [mozharness: 2017-05-18 13:48:46.933386Z] Running run-tests step. 06:48:46 INFO - Running pre-action listener: _resource_record_pre_action 06:48:46 INFO - Running main action method: run_tests 06:48:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:48:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:48:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:48:46 INFO - 2017-05-18 06:48:46.948 screenresolution[1625:7777] starting screenresolution argv=screenresolution get 06:48:46 INFO - 2017-05-18 06:48:46.950 screenresolution[1625:7777] Display 0: 1600x1200x32@60 06:48:46 INFO - 2017-05-18 06:48:46.956 screenresolution[1626:7779] starting screenresolution argv=screenresolution list 06:48:46 INFO - Available Modes on Display 0 06:48:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:48:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:48:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:48:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:48:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:48:47 INFO - Intel Iris: 06:48:47 INFO - Chipset Model: Intel Iris 06:48:47 INFO - Type: GPU 06:48:47 INFO - Bus: Built-In 06:48:47 INFO - VRAM (Dynamic, Max): 1536 MB 06:48:47 INFO - Vendor: Intel (0x8086) 06:48:47 INFO - Device ID: 0x0a2e 06:48:47 INFO - Revision ID: 0x0009 06:48:47 INFO - Displays: 06:48:47 INFO - Display: 06:48:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:48:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:48:47 INFO - Display Serial Number: 1600x1200 60 06:48:47 INFO - Main Display: Yes 06:48:47 INFO - Mirror: Off 06:48:47 INFO - Online: Yes 06:48:47 INFO - Rotation: Supported 06:48:47 INFO - Return code: 0 06:48:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:48:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:48:47 INFO - Python 2.7.3 06:48:47 INFO - Return code: 0 06:48:47 INFO - grabbing minidump binary from tooltool 06:48:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:48:47 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 0x103c73d30>, '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 0x103c27db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103c68be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:48:47 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 06:48:47 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 06:48:47 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 06:48:47 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:48:47 INFO - Return code: 0 06:48:47 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:48:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:48:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:48:47 INFO - ENV: RUST_BACKTRACE is now 1 06:48:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:48:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:48:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IMhxRChoQx-Seg1mLagG-Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0068', '--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 06:48:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/IMhxRChoQx-Seg1mLagG-Q/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0068 --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 06:48:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gUjgQSZ7tG/Render', 06:48:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:48:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:48:47 INFO - 'HOME': '/Users/cltbld', 06:48:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:48:47 INFO - 'LOGNAME': 'cltbld', 06:48:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:48:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:48:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:48:47 INFO - 'MOZ_NO_REMOTE': '1', 06:48:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:48:47 INFO - 'NO_EM_RESTART': '1', 06:48:47 INFO - 'PAGER': '/bin/cat', 06:48:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:48:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:48:47 INFO - 'PWD': '/builds/slave/test', 06:48:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:48:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:48:47 INFO - 'RUST_BACKTRACE': '1', 06:48:47 INFO - 'SHELL': '/bin/bash', 06:48:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iRUwogP6Gf/Listeners', 06:48:47 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 06:48:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:48:47 INFO - 'USER': 'cltbld', 06:48:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:48:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:48:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:48:47 INFO - 'XPC_FLAGS': '0x0', 06:48:47 INFO - 'XPC_SERVICE_NAME': '0', 06:48:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:48:47 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IMhxRChoQx-Seg1mLagG-Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0068', '--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 06:48:47 INFO - mozversion application_buildid: 20170518052032 06:48:47 INFO - mozversion application_changeset: cf4e37d17683e0cbe134debc1d3685fb3f84e3d1 06:48:47 INFO - mozversion application_display_name: Nightly 06:48:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:48:47 INFO - mozversion application_name: Firefox 06:48:47 INFO - mozversion application_remotingname: firefox 06:48:47 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 06:48:47 INFO - mozversion application_vendor: Mozilla 06:48:47 INFO - mozversion application_version: 55.0a1 06:48:47 INFO - mozversion platform_buildid: 20170518052032 06:48:47 INFO - mozversion platform_changeset: cf4e37d17683e0cbe134debc1d3685fb3f84e3d1 06:48:47 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 06:48:47 INFO - mozversion platform_version: 55.0a1 06:48:47 INFO - using testdate: 1495115327 06:48:47 INFO - actual date: 1495115327 06:48:47 INFO - starting webserver on 'localhost:49226' 06:48:47 INFO - SUITE-START | Running 2 tests 06:48:47 INFO - TEST-START | dromaeo_css 06:48:47 INFO - operating with platform_type : mac_ 06:48:47 INFO - Initialising browser for dromaeo_css test... 06:48:47 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/tmp85RGAG/profile http://localhost:49226/getInfo.html) 06:48:48 INFO - PID 1636 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2941: String.localeCompare is deprecated; use String.prototype.localeCompare instead 06:48:50 INFO - PID 1636 | 2017-05-18 06:48:50.103 plugin-container[1638:7939] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c47, name = 'com.apple.tsm.portname' 06:48:50 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:48:50 INFO - PID 1636 | 2017-05-18 06:48:50.107 plugin-container[1638:7939] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0xa30b, name = 'com.apple.CFPasteboardClient' 06:48:50 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:48:50 INFO - PID 1636 | 2017-05-18 06:48:50.107 plugin-container[1638:7939] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:48:50 INFO - PID 1636 | __metrics Screen width/height:1600/1200 06:48:50 INFO - PID 1636 | colorDepth:24 06:48:50 INFO - PID 1636 | Browser inner width/height: 1024/691 06:48:50 INFO - PID 1636 | __metrics 06:48:51 INFO - TEST-INFO | 1636: exit 0 06:48:51 INFO - Browser initialized. 06:48:51 INFO - Running cycle 1/1 for dromaeo_css test... 06:48:51 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/tmp85RGAG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:48:52 INFO - PID 1640 | 2017-05-18 06:48:52.865 plugin-container[1641:8088] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a3b, name = 'com.apple.tsm.portname' 06:48:52 INFO - PID 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:48:52 INFO - PID 1640 | 2017-05-18 06:48:52.866 plugin-container[1641:8088] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a23, name = 'com.apple.CFPasteboardClient' 06:48:52 INFO - PID 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:48:52 INFO - PID 1640 | 2017-05-18 06:48:52.866 plugin-container[1641:8088] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:48:53 INFO - PID 1640 | 2017-05-18 06:48:53.557 plugin-container[1642:8134] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 06:48:53 INFO - PID 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:48:53 INFO - PID 1640 | 2017-05-18 06:48:53.558 plugin-container[1642:8134] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b0b, name = 'com.apple.CFPasteboardClient' 06:48:53 INFO - PID 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:48:53 INFO - PID 1640 | 2017-05-18 06:48:53.558 plugin-container[1642:8134] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:49:02 INFO - PID 1640 | RSS: Main: 237793280 06:49:02 INFO - PID 1640 | 06:52:05 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49226/tests/dromaeo/cssquery-ext.html) 06:52:07 INFO - PID 1640 | RSS: Main: 261140480 06:52:07 INFO - PID 1640 | 06:55:09 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-ext.html (next: http://localhost:49226/tests/dromaeo/cssquery-jquery.html) 06:55:09 INFO - PID 1640 | RSS: Main: 268976128 06:55:09 INFO - PID 1640 | 06:58:11 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49226/tests/dromaeo/cssquery-mootools.html) 06:58:12 INFO - PID 1640 | RSS: Main: 273203200 06:58:12 INFO - PID 1640 | 07:01:13 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49226/tests/dromaeo/cssquery-prototype.html) 07:01:14 INFO - PID 1640 | RSS: Main: 271118336 07:01:14 INFO - PID 1640 | 07:04:17 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49226/tests/dromaeo/cssquery-yui.html) 07:04:17 INFO - PID 1640 | RSS: Main: 272355328 07:04:17 INFO - PID 1640 | 07:07:19 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-yui.html (next: http://localhost:49226/tests/dromaeo/cssquery-dojo.html) 07:07:19 INFO - PID 1640 | RSS: Main: 273510400 07:07:19 INFO - PID 1640 | 07:07:19 INFO - PID 1640 | __start_tp_report 07:07:19 INFO - PID 1640 | _x_x_mozilla_page_load 07:07:19 INFO - PID 1640 | _x_x_mozilla_page_load_details 07:07:19 INFO - PID 1640 | |i|pagename|runs| 07:07:19 INFO - PID 1640 | |0;dojo.html;3615.8192090395473;3619.384704600218;3651.634836516347;3657.7073834093276;3661.3775658138115;5728.341240757309;6193.876122477559;6301.968490157555;6316.084167795672;6348.619082855026;6241.063840423939;6243.594166379182;6259.906101408483;6298;6335.904961425582;4473.507914129443;5453.727313634303;5493.368262649787;5520.641158324696;5526.392096869341;5583.888322233553;5654;5669.433056694339;5700.429957004287;5731.14032895067;5464.4262352452815;5591.4688104629995;5607.551395888319;5665.433456654343;5673.602847800656;5513.807016754435;5540.473655002768;5575.553955683536;5650.943490565083;5656.4060773618585;5533.584981126432;5533.584981126432;5539.030669632798;5551.195076713894;5555.2222688823595;6068.180795592582;6311.7159727811895;6328.588641738318;6330.39861213184;6339.556231063827;6556;6631.867362652768;6661.766838160641;6822.147231596051;6823.590584564892;6901.930980690179;6954.547954383;6956.165260168812;6969.6166710830885;6971.058907047585;5590.720463976785;6181.134641150243;6410.942194537895;6422.518311126684;6431.196100487439;5679.602427830012;6753.223379311344;6922.757703480454;6927.549709268881;7032.226455089936;6515.02274658805;6609;6647.933520664828;6686.297938716365;6721.966390168018;6841.965790171017;6846.383825455733;6855.691493882788;6860.3139686031;6864.039034535169;5234.345706786652;5468.316460442445;6508.9023664645065;6517.902231466532;6820.795376138723;6543.9345606544275;6800.455963522906;6814.114165158498;6815.591064536144;6849.520533562602;5327.094393953037;5341.65279256847;5344.973275133677;5388.137897936312;5441.646292990943;4803.567678908915;4831.613470922318;4838.491958344393;4840.419149701988;4840.983393487337;6366.7771627993725;6375.203099612549;6384.361563843579;6390.520710946697;6416.262129855033;5793.768249270066;6665.800025999228;6739.258681545026;6826.010228516937;6831.658417079127;6369.681515924185;6371.9681401593625;6383.968080159662;6385.936140638627;6398.072279519443;6005.069214271794;6009.06859436788;6026.397360263939;6037.0642550404755;6082.878342433127;6810.31896810315;6919;6985.3713165815325;7063.152421709222;7069.929300706927;6923.238443771282;6939.653017349011;6946.2011868635755;6953.582785032914;6957.860842783217;6985.7205711772995;7099.112610923635;7100.680469378787;7103.325184107502;7115.181754098345;6556.803295901131;6623.536352455475;6674.966625166843;6751.594904305757;6798.694058767466;6892.586444813327;6922.411595014279;6927.653617319214;6940.993555934462;6962.443004559522;6582.111414958919;6587.802365928838;6643.136392268974;6646.770347485537;6652.234992975869;5464;5626.690532020614;6426.003969384753;6537.8692426150255;6606.8017959461295;6349.111124442489;6353.8729225416155;6488.221413430419;6492.805215843532;6508.544401891822;6615.966920165369;6647.235567909559;6647.4682025438815;6650.90023649636;6651.767188148391;5524.254225679482;6267.2165979252595;6319.936800631935;6356.364363563606;6483.643399613066 07:07:19 INFO - PID 1640 | |1;ext.html;656.3075954867614;777.172311488265;830.9542975136369;1206.5354838387218;1259.5969289827246;35353.646463535355;35450.29099418016;37169.44245836311;37404.43893341602;37586.8723938282;29413.85293073537;30855.537166942457;37828.62171378286;38105.428418573676;38228.22147231596;7040.190378106532;7759.146493885668;7942.682292708285;8100.797480062986;8330.166983301651;8242.505449673008;8348.916510834875;8358.0806111328;8360.038595561497;8376.41365104443;7329.853402931938;8254.752357429286;8311.293540049102;8314.667413303461;8333.916660833374;8321.542315172663;8345.499270043816;8359.414840961135;8359.456635318746;8443.451175673561;8072.071711753106;8077.35381169505;8092;8115.066767321775;8152.062512810984;34694.306113877836;34960;34962.65037349645;35016.299674006375;35023.6497635023;3464.84408201629;3476.5654293213347;3480.6867381935494;3490.930181396358;3491.930161396783;28629.427411451656;28839.423211535654;28998.565021524693;29001.419971600662;29036.12891613255;3038.756899448039;3082.0137555982096;3084.290613158986;3087.799293045945;3094.6441159266747;287456;290251;290930;292269.99999999785;295984;44355.55644443579;44566.77716611396;44571.777141114086;44697.65907022794;44793.55206447959;45604.315935260995;46206.76896615563;51941;53117;53261.7336913313;13963.371648275852;13976.790348144787;14100.365483553265;14129.505467308594;14141.44444111147;11288.266262693063;11319.547218111184;11322.547098116147;11332.6033588824;11396.658100257006;13466.326683665777;13602.387892544859;13604.93197534006;13613.727725445435;13745.38145783442;5153.805449335575;5994.550408719363;6054.152418661391;6091.817245482643;6097;993.6174572789383;1038.771470276538;1051.3376572759112;1058.5077938758513;1070.4968664727567;17668;20228;22723.545529089657;23181.652275215885;24194.758052419602;998.6255154317131;1112.6272698645882;1138.3682056458579;1153.648137318126;1179.622520793355;11369.374684392438;14027.929860350838;15393.845461590425;15743.68512629741;16038.679226415406;270617;286974;306546;308375;321925;46807.76596116998;49064.77338066577;52796;52797;52893.7355313221;1511.8110236220473;1533.9539813805604;1537.4618883390947;1537.8539038791298;1549.6668216333542;26952;28311.15066548007;28670.856645716638;28969.130926072252;28994.13017609475;36985.89042328627;37011.81494092512;37014;37025;37098.8145059273;288956.55521722254;289683;289801.5509922437;290030.5498472494;290704;26102.86948565245;31682.89109881143;32664.183395415304;32724.34551309008;32737.017889463354;40699.79650101731;40700;40732.59267407288;40870.59129408668;40883;27512;28662.713372866838;28755.71244287614;28863.42273154483;29470;21430.357089287347;28739.994100206393;29338.559921600765;29423.55864661989;29695.25761855971;34581.48127778035;34585.30829383449;34590;34622.65377346194;34701.82649086738;27753.722462775113;28360.432791343646;28914.855425722737;29514;29688;19216.519587010436;22336.77663223347;23109.191178309353;23499.53000940006;23538.76461235434 07:07:19 INFO - PID 1640 | |2;jquery.html;347.9773814702044;445.2853170661087;446.90285349467064;447.52785810969436;460.4957571459252;20476.89761551192;20886.373408797743;21244.89377553112;22337.883105844725;22472.662910056362;19330.22679092838;20200.69698954517;22170.224042158527;22763.544729105408;23591.17430889915;6028.939710602893;6218;6242.438180563749;6255.4995600352095;6281.497480201596;5321.4412486688925;6085.969570152143;6124.938750612504;6175.938240617604;6228.096925945735;6126.417990290937;6183.536234782387;6197.194364732579;6199.070139479091;6221.937780622181;6061.787837425691;6113.205283313186;6114.2357205349335;6120.357362476965;6162.96918515409;5199.324087868591;5369.812056578001;5495;5507.339119305669;5526.143447765603;27370.315742106406;27421;27483.17550473489;27713.722862771316;27840.443191136266;2164.599549083425;2216.833737469696;2220.944476388087;2232.821374290053;2233.062113912161;17504.21231044606;17677.38129165473;17776.733348999776;17950.102494875206;18017.819821801877;1851.7870444898874;2140.4327853118934;2181.2256648889575;2183.1267493002765;2189.3322536626324;155939;156492;159134;159225.2038739799;160959.99999999884;10915.454227288605;11936;12935.611931642066;12980.935095324463;13062.020348473903;14575;16667.749983750255;16696.58258543522;16709.498715038568;16723.414680486123;6509.772157974543;8505.872411913826;8757;8816.338774591932;8855.114488551222;7106.822329441702;8166.510009399455;8284.8343033139;8323.750287491384;8329.583520824055;7116.146062472537;7593.620318984029;7943.364530837519;8039.392121575708;8060.959695201486;1729.8270172982602;1773.875828692005;2171.956560868774;2181.5418762060144;2373.7863592276776;881.8280435315052;946.7349142240183;970.9465979371203;992.1071036067597;1007.9748006299901;20207.49481262951;21649.567008660055;25499.617505737428;26976.73023269742;27327.590086148724;999.8052327468605;1006.3861044762689;1008.1884083313004;1018.9032041956002;1019.0675531888387;10576.471176441148;10589.629362972259;11184.720381990515;11208.27146235492;11238.38188899602;465327;550599;562400.5719456645;566816;568941;108947.45526272476;112101;131704.68295317117;134611;135324;1197.5748609243592;1219.6280134559045;1244.9066320026034;1246.7880460321585;1248.581725122076;20352.18591256333;22526.76102814361;22810.65784013208;22882.542349153257;22977.195798146986;55523.72238138784;55666;56136.719316403156;56578.7171064142;56662.30013099613;76330.23669763231;76519.61740191486;77056.61471692605;77473;78480;8953.328500362368;8975.281977441658;9051.230645394951;9099.008077721137;9304.906950930404;13733.313334333043;13929.442822286996;14353.92823035878;14392.856071439151;14534.491292804702;10895.891041089804;11956.641300760992;11981.64055078349;11983.28100313984;12169.90470857627;11152.609658661908;11401.429928503376;11523.250988685708;11576.36330001858;11702.590409335633;12123.090768192596;12210.938945305217;12398.938005309916;12469.5012199511;12582.622521324374;9069.727908162766;9109.544522773967;9121.543922803967;9160.908390916005;9186.356955013085;9887.110160085627;11872.990795782454;12009.099317551394;12065.336406497376;12282.263064216175 07:07:19 INFO - PID 1640 | |3;mootools.html;1196.8204769284607;1279.0215485153858;1456.271864067966;1493.3653197391106;1495.4018392642945;9234.63061477541;11673;12092.637220883376;12119.575814846483;12361.011119110472;11893;12092.818607720868;12105.273683578991;12110.0311975042;12148.574799882006;2570.6915170179605;2627.8029147813895;2632.7104018557943;2633.591793272046;2640.1683469707077;2228.186711850179;2536.8964500442275;2547.0957809977467;2598.883050262743;2600.6749156355454;2570.473053024135;2590.3912580543556;2616.032068134793;2626.2909014566058;2629.4609605030923;2537.1500547316673;2563.141347648531;2563.6539067225967;2583.4833033393397;2591.403977085281;2522.823402361835;2539.9111031114007;2570.2546261584116;2616.7121616622157;2622;11849.289042657469;11986.940065299617;11988.460519276654;11993.28040317575;11996.70008249792;6692.899606505857;6924.896126558106;6927.861442771166;6938.965305173492;6952.165740111169;3610.007248006793;3947.940780888289;4381.846635367779;4428.756418396987;4535.886602834922;1222.3093951917137;1245.1844042152404;1287.6008437384442;1289.7936330187124;1292.5088466382715;16441.6711665766;16442.83557164425;16517.008979461272;16533.58666033347;17000.14999250045;5589.972050139723;5675.971620141873;5835.44563266489;5838.416158384128;5860.765569377178;11620.825687614693;11662.76674466506;11817.409129543661;11838.467268972745;11838.704032399086;6597.0764093026055;6662.600243985376;6703.463722902156;6712.731490740413;6721.563098398687;6118.755249789959;6182.6599537024995;6309.652969086745;6329.715162817684;6334.746610135543;4334.4798624165305;4472.530384309665;4476.239039363316;4481.775911204427;4572.245579479349;1059.6820953713855;1089.84742136101;1291.4963164365904;1303.6349822049842;1303.7327347893658;685.807973767346;728.2644529025722;812.4556547113448;814.2915663372903;834.6828205281954;6003.919294527027;6078.452939235402;6243.219597550306;6243.594166379171;6425.100485931974;465.2834634662582;566.8659914970066;611.5230120506012;620.0482259731291;620.8137558732362;3938.7242892997215;3952.8023598819946;3986.142979259473;4004.139110091286;4042.919141617151;14540;15286.464973725866;15339.539813805604;15365.692686146214;15381.846181538265;12403.193792403645;15058.924705376403;15098.849011509965;15119.622009449851;15286.159261240517;8247.711330103537;8481;8528.763329317339;8653.567321633893;8757.868631970525;6026.156338112755;7083.610401427971;7112.964435177998;7152.320529549685;7303.817404564716;10908.890911090788;11782.52869885195;12797.680057998625;13298.734025319633;13732.038757286895;13830.86169138336;13853.584392468241;13884.583462495737;14145.646358841112;14359.712805743618;9130.269578433843;9398.436093834393;9414.2468602513;9521.904780952102;9553.522323883917;10775.51510182028;10822.783544329226;10844.783104338027;10853.620123295646;10890.509927053143;8909.643614255358;9092.363534552518;9136.908630913606;9151.313651476034;9200.21798147165;8894.822103558023;8955.776105597412;8984.371094023356;9004.63981440735;9071.59177837012;8043.718469853527;8202.548859812767;8219.178082191733;8230.794230144294;8232.711855085283;6025.969870150621;6068.969655151873;6087.299960504599;6184.783532576338;6302.590331628429;5379.4082650907585;5927.229460170151;6083.361247068994;6220.844478887883;6391.169148010729 07:07:19 INFO - PID 1640 | |4;prototype.html;351.9190586165182;387.40339876590053;394.1820722001846;414.15099046953736;414.2253985047961;6371.776987805416;6407.967960160193;6415.807525774228;6419.454346380558;6525.608463492182;6217.222847144107;6271.529635277361;6289.245290565127;6338.668879535303;6355.173827402431;4524.502304746476;4587.174308624446;4591.517890621487;4601.608863246628;4619.86140415788;4510.571495707919;4562.520935301795;4566.1780879441685;4607.89410541471;4619.653525985547;3754.755940863849;3954.8813535593836;4513.7066090704;4523.457185137771;4548.749818759967;3764.623537646241;4358.7166834156;4507.323901414781;4552.521985191557;4581.152486789922;2940.558916162571;3474.1314671332166;3808.638179372955;3903.863364782219;3912.6283003114945;6124.877502449971;6155.6614386209185;6183.845403864894;6185.226846644169;6195.659238741868;5120.9758048390095;5418.024755544;5444.836654900319;5511.53151982082;5543.556515478752;5814.738336774855;6673.9666301668185;6724.865502689919;6761.030642477775;6812.591244525295;3493.388656985043;4396.758178300192;4403.339499075132;4406.603405693471;4426.601605855456;5073;6734;6929.99515070317;6939.757108501179;7076.7169313227405;4083.2650122977557;4408.801603927831;4995.775190116381;5060.2156665716875;5101.387833460009;4982.377202849644;5221.6083793715625;5244.973775131176;5265.447128051576;5277.868053298698;5130.4356520782685;5512.724363781875;5592.972035139798;5805.303363596396;5846.6784326862435;5662.546996240291;5803.564732645069;6515.902261466082;6733.057371967929;6879.415249703831;1754.5964428181335;1762.5899280575488;1769.716845304771;1776.413783451458;1784.946451606428;1036.0778906772978;1210.2315029956042;1238.8451443569554;1240;1242.4781591731494;6354.523410744213;6357.268914074848;6365.61806291624;6376.776812811529;6402.343027643569;6365.331640177715;6975.441964642816;6976.337247961435;7008.123984501937;7053.329933656294;5347.786088556492;5791.986402379567;5838.620489668158;6337.04944258365;6387.105805187278;3213.823239721791;3426.760126791101;3581.928361432757;3597.370460169512;3664.4136938089778;5959.437441900024;6918.477934854324;7047.260037696071;7175.461840361993;7206.351428371367;6370.77702280418;7169.856602867913;7180.605066721276;7190.568565886064;7217.0459371019115;5822.737976791054;6171.96914015418;6176.69116544162;7136.536125151849;7158.1746654603785;5962.4633782959745;6250.312465628686;6900.205946454059;7051.342934410512;7096.8935465967015;6669.966650166718;6789.93210067893;6847.835867902369;6899.896501552581;6992.61540615271;5748.597598168088;7191.712331506681;7201.927980720126;7217.350438460564;7220.916862470462;4346.521882592975;4529.84145554904;4605.562471565196;5163.4578369270685;5178.860650656849;6208.379162083756;7095.077639906779;7115.146182458138;7146.464015198777;7149.998000559852;6406.487481001415;6618.801435956929;6681.632510211985;6711.697973591103;6775.815366777607;5967.6121052131475;6760.985852122221;6788.66056697173;6800.455963523005;6823.28355522663;5612.915806262991;6478.28738838718;6645.966770166118;6669.266380698023;6678.83302917431;5434.673919564839;6742.69657865407;6753.966230168818;6809.072098997479;6859.5541289816;5457;6298.401651843067;6414.262359828493;6612.570182938093;6618.87479128559 07:07:19 INFO - PID 1640 | |5;yui.html;795.1174196641726;833.791552111972;861.5476874640813;875.6558682422478;877.1886005444964;16127.274272657729;16442.09568473735;16443.2600532976;17380.21789019494;17779.288828446846;17573.209205585747;17867.91066044668;18142.63714725708;18171;18526.444206673823;878.108719649553;961.1109723505764;995.4723996281982;1001.484235618657;1003.9247056470757;945.0266225787416;992.8064027514638;1002.503760638485;1003.4581326083905;1006;1011.9494025298742;1028.9485525723685;1045.7751583409565;1046.6232156423682;1049.4123290957089;1011.4386515483903;1039.698487438642;1040.9427481488515;1044.1542350695922;1048.9370637761758;753.8756105242628;760.1562265884863;776.6427443376053;778.8286576953061;778.9883151752729;16236.43172488969;16445.753313700305;16559.751603725952;16980.49058528234;17132.486025419257;17064.914675426666;17160.485185444457;17456.30174793007;17460.476185714448;17559.82440175595;14250.643733906736;14254.501092461818;14338.139711617336;14457.421703131862;14549.12705237689;3603.5315408996926;3631.346357655621;3639.1084184375;3644.835982380799;3654.780713157219;117658.82341176564;118970;118990.21514677287;120177.59644807142;121731.78268217292;11775.470103845348;11919.284842909454;12152.756944861054;12353.38233088342;12392.75214495705;25676.3580910475;25882.482350352886;25917.870410648204;26405.33986650349;26407.075752348577;7368.705251789976;7400.703971841066;7422.665980030912;7429.2570742926355;7434.628268586549;3676.3382591133854;3725.4784330193793;3789.1285004448578;3793.468914351993;3793.715471339661;4344.652427805768;4427.601515863587;4453.933191002137;4453.955460445354;4460.063386688832;764.1861417590248;874.1127755328347;893.647009431271;894.4007514964859;897.6678628907215;644.7195469970578;656.1732217406103;661.5798967655574;668.044696084594;679.1069743287561;11257.324560526231;11517.193796434169;11857.88142118585;12032.699182520331;12048.939755301168;531.7503865915069;548.44333002003;655.5574986883835;676.6075676107846;686.2074304178682;4343.630791382705;5012.997400519911;5575.60970732053;5823.126531020381;5823.88352232953;102571;103935;105949.47025264979;106629;106952.46523767487;60312.396876031555;71044.64477677683;71360.64319678368;76101.21183130016;76738;70936;71495.64252178706;71858;71914;72769;9434.575444105169;11585.536578536765;11723.296602203895;12261.325627090595;12385.133040687426;25877;28484.857575711987;28769.712302876702;35391.646083538835;35970;47894;47994;48043.519564805305;48107;48160.75919620379;15751.44869929547;15920.363185472452;16059.116748578941;16237.106959116889;16444.67110657804;15250.313735882133;17734.645307094044;18124.72812907834;18146.45560633183;18436;16249.025058496056;16578.253978570752;16733.832661673227;16831.495055148363;17078.2314795832;16128.596785080466;16243.350265989227;16412.507624771275;16601.50195494137;16609;12632.494700211888;15787.447439340027;15936.521904342888;15983.760243596587;16221;10965.56137754481;11149.609763658233;11200.327980321206;11311.660650180507;11393.658190254306;12600.810987835373;12710.555130570385;12716.745665086832;12718.618441446772;12833.486660533474 07:07:19 INFO - PID 1640 | __end_tp_report 07:07:19 INFO - PID 1640 | __start_cc_report 07:07:19 INFO - PID 1640 | _x_x_mozilla_cycle_collect,344 07:07:19 INFO - PID 1640 | __end_cc_report 07:07:19 INFO - PID 1640 | __startTimestamp1495116439879__endTimestamp 07:07:19 INFO - PID 1640 | 07:07:19 INFO - PID 1640 | ------- Summary: start ------- 07:07:19 INFO - PID 1640 | Number of tests: 6 07:07:19 INFO - PID 1640 | 07:07:19 INFO - PID 1640 | [#0] dojo.html Cycles:165 Average:6216.65 Median:6398.07 stddev:746.55 (11.7%) stddev-sans-first:720.42 07:07:19 INFO - PID 1640 | Values: 3615.8 3619.4 3651.6 3657.7 3661.4 5728.3 6193.9 6302.0 6316.1 6348.6 6241.1 6243.6 6259.9 6298.0 6335.9 4473.5 5453.7 5493.4 5520.6 5526.4 5583.9 5654.0 5669.4 5700.4 5731.1 5464.4 5591.5 5607.6 5665.4 5673.6 5513.8 5540.5 5575.6 5650.9 5656.4 5533.6 5533.6 5539.0 5551.2 5555.2 6068.2 6311.7 6328.6 6330.4 6339.6 6556.0 6631.9 6661.8 6822.1 6823.6 6901.9 6954.5 6956.2 6969.6 6971.1 5590.7 6181.1 6410.9 6422.5 6431.2 5679.6 6753.2 6922.8 6927.5 7032.2 6515.0 6609.0 6647.9 6686.3 6722.0 6842.0 6846.4 6855.7 6860.3 6864.0 5234.3 5468.3 6508.9 6517.9 6820.8 6543.9 6800.5 6814.1 6815.6 6849.5 5327.1 5341.7 5345.0 5388.1 5441.6 4803.6 4831.6 4838.5 4840.4 4841.0 6366.8 6375.2 6384.4 6390.5 6416.3 5793.8 6665.8 6739.3 6826.0 6831.7 6369.7 6372.0 6384.0 6385.9 6398.1 6005.1 6009.1 6026.4 6037.1 6082.9 6810.3 6919.0 6985.4 7063.2 7069.9 6923.2 6939.7 6946.2 6953.6 6957.9 6985.7 7099.1 7100.7 7103.3 7115.2 6556.8 6623.5 6675.0 6751.6 6798.7 6892.6 6922.4 6927.7 6941.0 6962.4 6582.1 6587.8 6643.1 6646.8 6652.2 5464.0 5626.7 6426.0 6537.9 6606.8 6349.1 6353.9 6488.2 6492.8 6508.5 6616.0 6647.2 6647.5 6650.9 6651.8 5524.3 6267.2 6319.9 6356.4 6483.6 07:07:19 INFO - PID 1640 | 07:07:19 INFO - PID 1640 | [#1] ext.html Cycles:180 Average:43705.37 Median:3091.22 stddev:76966.57 (2489.8%) stddev-sans-first:77114.61 07:07:19 INFO - PID 1640 | Values: 656.3 777.2 831.0 1206.5 1259.6 35353.6 35450.3 37169.4 37404.4 37586.9 29413.9 30855.5 37828.6 38105.4 38228.2 7040.2 7759.1 7942.7 8100.8 8330.2 8242.5 8348.9 8358.1 8360.0 8376.4 7329.9 8254.8 8311.3 8314.7 8333.9 8321.5 8345.5 8359.4 8359.5 8443.5 8072.1 8077.4 8092.0 8115.1 8152.1 34694.3 34960.0 34962.7 35016.3 35023.6 3464.8 3476.6 3480.7 3490.9 3491.9 28629.4 28839.4 28998.6 29001.4 29036.1 3038.8 3082.0 3084.3 3087.8 3094.6 287456.0 290251.0 290930.0 292270.0 295984.0 44355.6 44566.8 44571.8 44697.7 44793.6 45604.3 46206.8 51941.0 53117.0 53261.7 13963.4 13976.8 14100.4 14129.5 14141.4 11288.3 11319.5 11322.5 11332.6 11396.7 13466.3 13602.4 13604.9 13613.7 13745.4 5153.8 5994.6 6054.2 6091.8 6097.0 993.6 1038.8 1051.3 1058.5 1070.5 17668.0 20228.0 22723.5 23181.7 24194.8 998.6 1112.6 1138.4 1153.6 1179.6 11369.4 14027.9 15393.8 15743.7 16038.7 270617.0 286974.0 306546.0 308375.0 321925.0 46807.8 49064.8 52796.0 52797.0 52893.7 1511.8 1534.0 1537.5 1537.9 1549.7 26952.0 28311.2 28670.9 28969.1 28994.1 36985.9 37011.8 37014.0 37025.0 37098.8 288956.6 289683.0 289801.6 290030.5 290704.0 26102.9 31682.9 32664.2 32724.3 32737.0 40699.8 40700.0 40732.6 40870.6 40883.0 27512.0 28662.7 28755.7 28863.4 29470.0 21430.4 28740.0 29338.6 29423.6 29695.3 34581.5 34585.3 34590.0 34622.7 34701.8 27753.7 28360.4 28914.9 29514.0 29688.0 19216.5 22336.8 23109.2 23499.5 23538.8 07:07:19 INFO - PID 1640 | 07:07:19 INFO - PID 1640 | [#2] jquery.html Cycles:180 Average:35481.02 Median:22787.10 stddev:92401.52 (405.5%) stddev-sans-first:92623.08 07:07:19 INFO - PID 1640 | Values: 348.0 445.3 446.9 447.5 460.5 20476.9 20886.4 21244.9 22337.9 22472.7 19330.2 20200.7 22170.2 22763.5 23591.2 6028.9 6218.0 6242.4 6255.5 6281.5 5321.4 6086.0 6124.9 6175.9 6228.1 6126.4 6183.5 6197.2 6199.1 6221.9 6061.8 6113.2 6114.2 6120.4 6163.0 5199.3 5369.8 5495.0 5507.3 5526.1 27370.3 27421.0 27483.2 27713.7 27840.4 2164.6 2216.8 2220.9 2232.8 2233.1 17504.2 17677.4 17776.7 17950.1 18017.8 1851.8 2140.4 2181.2 2183.1 2189.3 155939.0 156492.0 159134.0 159225.2 160960.0 10915.5 11936.0 12935.6 12980.9 13062.0 14575.0 16667.7 16696.6 16709.5 16723.4 6509.8 8505.9 8757.0 8816.3 8855.1 7106.8 8166.5 8284.8 8323.8 8329.6 7116.1 7593.6 7943.4 8039.4 8061.0 1729.8 1773.9 2172.0 2181.5 2373.8 881.8 946.7 970.9 992.1 1008.0 20207.5 21649.6 25499.6 26976.7 27327.6 999.8 1006.4 1008.2 1018.9 1019.1 10576.5 10589.6 11184.7 11208.3 11238.4 465327.0 550599.0 562400.6 566816.0 568941.0 108947.5 112101.0 131704.7 134611.0 135324.0 1197.6 1219.6 1244.9 1246.8 1248.6 20352.2 22526.8 22810.7 22882.5 22977.2 55523.7 55666.0 56136.7 56578.7 56662.3 76330.2 76519.6 77056.6 77473.0 78480.0 8953.3 8975.3 9051.2 9099.0 9304.9 13733.3 13929.4 14353.9 14392.9 14534.5 10895.9 11956.6 11981.6 11983.3 12169.9 11152.6 11401.4 11523.3 11576.4 11702.6 12123.1 12210.9 12398.9 12469.5 12582.6 9069.7 9109.5 9121.5 9160.9 9186.4 9887.1 11873.0 12009.1 12065.3 12282.3 07:07:19 INFO - PID 1640 | 07:07:19 INFO - PID 1640 | [#3] mootools.html Cycles:180 Average:7132.70 Median:3943.33 stddev:4514.21 (114.5%) stddev-sans-first:4504.84 07:07:19 INFO - PID 1640 | Values: 1196.8 1279.0 1456.3 1493.4 1495.4 9234.6 11673.0 12092.6 12119.6 12361.0 11893.0 12092.8 12105.3 12110.0 12148.6 2570.7 2627.8 2632.7 2633.6 2640.2 2228.2 2536.9 2547.1 2598.9 2600.7 2570.5 2590.4 2616.0 2626.3 2629.5 2537.2 2563.1 2563.7 2583.5 2591.4 2522.8 2539.9 2570.3 2616.7 2622.0 11849.3 11986.9 11988.5 11993.3 11996.7 6692.9 6924.9 6927.9 6939.0 6952.2 3610.0 3947.9 4381.8 4428.8 4535.9 1222.3 1245.2 1287.6 1289.8 1292.5 16441.7 16442.8 16517.0 16533.6 17000.1 5590.0 5676.0 5835.4 5838.4 5860.8 11620.8 11662.8 11817.4 11838.5 11838.7 6597.1 6662.6 6703.5 6712.7 6721.6 6118.8 6182.7 6309.7 6329.7 6334.7 4334.5 4472.5 4476.2 4481.8 4572.2 1059.7 1089.8 1291.5 1303.6 1303.7 685.8 728.3 812.5 814.3 834.7 6003.9 6078.5 6243.2 6243.6 6425.1 465.3 566.9 611.5 620.0 620.8 3938.7 3952.8 3986.1 4004.1 4042.9 14540.0 15286.5 15339.5 15365.7 15381.8 12403.2 15058.9 15098.8 15119.6 15286.2 8247.7 8481.0 8528.8 8653.6 8757.9 6026.2 7083.6 7113.0 7152.3 7303.8 10908.9 11782.5 12797.7 13298.7 13732.0 13830.9 13853.6 13884.6 14145.6 14359.7 9130.3 9398.4 9414.2 9521.9 9553.5 10775.5 10822.8 10844.8 10853.6 10890.5 8909.6 9092.4 9136.9 9151.3 9200.2 8894.8 8955.8 8984.4 9004.6 9071.6 8043.7 8202.5 8219.2 8230.8 8232.7 6026.0 6069.0 6087.3 6184.8 6302.6 5379.4 5927.2 6083.4 6220.8 6391.2 07:07:19 INFO - PID 1640 | 07:07:19 INFO - PID 1640 | [#4] prototype.html Cycles:180 Average:5440.47 Median:6140.27 stddev:1693.74 (27.6%) stddev-sans-first:1654.87 07:07:19 INFO - PID 1640 | Values: 351.9 387.4 394.2 414.2 414.2 6371.8 6408.0 6415.8 6419.5 6525.6 6217.2 6271.5 6289.2 6338.7 6355.2 4524.5 4587.2 4591.5 4601.6 4619.9 4510.6 4562.5 4566.2 4607.9 4619.7 3754.8 3954.9 4513.7 4523.5 4548.7 3764.6 4358.7 4507.3 4552.5 4581.2 2940.6 3474.1 3808.6 3903.9 3912.6 6124.9 6155.7 6183.8 6185.2 6195.7 5121.0 5418.0 5444.8 5511.5 5543.6 5814.7 6674.0 6724.9 6761.0 6812.6 3493.4 4396.8 4403.3 4406.6 4426.6 5073.0 6734.0 6930.0 6939.8 7076.7 4083.3 4408.8 4995.8 5060.2 5101.4 4982.4 5221.6 5245.0 5265.4 5277.9 5130.4 5512.7 5593.0 5805.3 5846.7 5662.5 5803.6 6515.9 6733.1 6879.4 1754.6 1762.6 1769.7 1776.4 1784.9 1036.1 1210.2 1238.8 1240.0 1242.5 6354.5 6357.3 6365.6 6376.8 6402.3 6365.3 6975.4 6976.3 7008.1 7053.3 5347.8 5792.0 5838.6 6337.0 6387.1 3213.8 3426.8 3581.9 3597.4 3664.4 5959.4 6918.5 7047.3 7175.5 7206.4 6370.8 7169.9 7180.6 7190.6 7217.0 5822.7 6172.0 6176.7 7136.5 7158.2 5962.5 6250.3 6900.2 7051.3 7096.9 6670.0 6789.9 6847.8 6899.9 6992.6 5748.6 7191.7 7201.9 7217.4 7220.9 4346.5 4529.8 4605.6 5163.5 5178.9 6208.4 7095.1 7115.1 7146.5 7150.0 6406.5 6618.8 6681.6 6711.7 6775.8 5967.6 6761.0 6788.7 6800.5 6823.3 5612.9 6478.3 6646.0 6669.3 6678.8 5434.7 6742.7 6754.0 6809.1 6859.6 5457.0 6298.4 6414.3 6612.6 6618.9 07:07:19 INFO - PID 1640 | 07:07:19 INFO - PID 1640 | [#5] yui.html Cycles:180 Average:20264.31 Median:17756.97 stddev:28148.33 (158.5%) stddev-sans-first:28189.33 07:07:19 INFO - PID 1640 | Values: 795.1 833.8 861.5 875.7 877.2 16127.3 16442.1 16443.3 17380.2 17779.3 17573.2 17867.9 18142.6 18171.0 18526.4 878.1 961.1 995.5 1001.5 1003.9 945.0 992.8 1002.5 1003.5 1006.0 1011.9 1028.9 1045.8 1046.6 1049.4 1011.4 1039.7 1040.9 1044.2 1048.9 753.9 760.2 776.6 778.8 779.0 16236.4 16445.8 16559.8 16980.5 17132.5 17064.9 17160.5 17456.3 17460.5 17559.8 14250.6 14254.5 14338.1 14457.4 14549.1 3603.5 3631.3 3639.1 3644.8 3654.8 117658.8 118970.0 118990.2 120177.6 121731.8 11775.5 11919.3 12152.8 12353.4 12392.8 25676.4 25882.5 25917.9 26405.3 26407.1 7368.7 7400.7 7422.7 7429.3 7434.6 3676.3 3725.5 3789.1 3793.5 3793.7 4344.7 4427.6 4453.9 4454.0 4460.1 764.2 874.1 893.6 894.4 897.7 644.7 656.2 661.6 668.0 679.1 11257.3 11517.2 11857.9 12032.7 12048.9 531.8 548.4 655.6 676.6 686.2 4343.6 5013.0 5575.6 5823.1 5823.9 102571.0 103935.0 105949.5 106629.0 106952.5 60312.4 71044.6 71360.6 76101.2 76738.0 70936.0 71495.6 71858.0 71914.0 72769.0 9434.6 11585.5 11723.3 12261.3 12385.1 25877.0 28484.9 28769.7 35391.6 35970.0 47894.0 47994.0 48043.5 48107.0 48160.8 15751.4 15920.4 16059.1 16237.1 16444.7 15250.3 17734.6 18124.7 18146.5 18436.0 16249.0 16578.3 16733.8 16831.5 17078.2 16128.6 16243.4 16412.5 16601.5 16609.0 12632.5 15787.4 15936.5 15983.8 16221.0 10965.6 11149.6 11200.3 11311.7 11393.7 12600.8 12710.6 12716.7 12718.6 12833.5 07:07:19 INFO - PID 1640 | -------- Summary: end -------- 07:07:19 INFO - PID 1640 | 07:07:20 INFO - TEST-INFO | 1640: exit 0 07:07:20 INFO - TEST-OK | dromaeo_css | took 1112989ms 07:07:20 INFO - TEST-START | kraken 07:07:20 INFO - operating with platform_type : mac_ 07:07:20 INFO - Initialising browser for kraken test... 07:07:20 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/tmpaPAYBt/profile http://localhost:49226/getInfo.html) 07:07:21 INFO - PID 1650 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2941: String.localeCompare is deprecated; use String.prototype.localeCompare instead 07:07:22 INFO - PID 1650 | 2017-05-18 07:07:22.926 plugin-container[1651:10323] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x974b, name = 'com.apple.tsm.portname' 07:07:22 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:07:22 INFO - PID 1650 | 2017-05-18 07:07:22.930 plugin-container[1651:10323] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0f, name = 'com.apple.CFPasteboardClient' 07:07:22 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:07:22 INFO - PID 1650 | 2017-05-18 07:07:22.930 plugin-container[1651:10323] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:07:23 INFO - PID 1650 | __metrics Screen width/height:1600/1200 07:07:23 INFO - PID 1650 | colorDepth:24 07:07:23 INFO - PID 1650 | Browser inner width/height: 1024/691 07:07:23 INFO - PID 1650 | __metrics 07:07:23 INFO - PID 1650 | 07:07:23 INFO - PID 1650 | ###!!! [Child][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost 07:07:23 INFO - PID 1650 | 07:07:24 INFO - TEST-INFO | 1650: exit 0 07:07:24 INFO - Browser initialized. 07:07:24 INFO - Running cycle 1/1 for kraken test... 07:07:24 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/tmpaPAYBt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:07:26 INFO - PID 1653 | 2017-05-18 07:07:26.282 plugin-container[1654:10464] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963b, name = 'com.apple.tsm.portname' 07:07:26 INFO - PID 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:07:26 INFO - PID 1653 | 2017-05-18 07:07:26.282 plugin-container[1654:10464] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963f, name = 'com.apple.CFPasteboardClient' 07:07:26 INFO - PID 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:07:26 INFO - PID 1653 | 2017-05-18 07:07:26.282 plugin-container[1654:10464] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:07:26 INFO - PID 1653 | 2017-05-18 07:07:26.908 plugin-container[1655:10511] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4543, name = 'com.apple.tsm.portname' 07:07:26 INFO - PID 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:07:26 INFO - PID 1653 | 2017-05-18 07:07:26.908 plugin-container[1655:10511] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d0b, name = 'com.apple.CFPasteboardClient' 07:07:26 INFO - PID 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:07:26 INFO - PID 1653 | 2017-05-18 07:07:26.908 plugin-container[1655:10511] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:07:36 INFO - PID 1653 | RSS: Main: 238899200 07:07:36 INFO - PID 1653 | 07:07:39 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-beat-detection) 07:07:40 INFO - PID 1653 | RSS: Main: 251572224 07:07:40 INFO - PID 1653 | 07:07:42 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-dft) 07:07:43 INFO - PID 1653 | RSS: Main: 260689920 07:07:43 INFO - PID 1653 | 07:07:46 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-fft) 07:07:47 INFO - PID 1653 | RSS: Main: 263028736 07:07:47 INFO - PID 1653 | 07:07:49 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-oscillator) 07:07:49 INFO - PID 1653 | RSS: Main: 264802304 07:07:49 INFO - PID 1653 | 07:07:51 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:07:52 INFO - PID 1653 | RSS: Main: 265601024 07:07:52 INFO - PID 1653 | 07:07:55 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-darkroom) 07:07:55 INFO - PID 1653 | RSS: Main: 271081472 07:07:55 INFO - PID 1653 | 07:07:59 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-desaturate) 07:07:59 INFO - PID 1653 | RSS: Main: 268521472 07:07:59 INFO - PID 1653 | 07:08:02 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49226/tests/kraken/driver.html?testName=json-parse-financial) 07:08:03 INFO - PID 1653 | RSS: Main: 268832768 07:08:03 INFO - PID 1653 | 07:08:04 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49226/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:08:05 INFO - PID 1653 | RSS: Main: 269152256 07:08:05 INFO - PID 1653 | 07:08:07 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:08:07 INFO - PID 1653 | RSS: Main: 269086720 07:08:07 INFO - PID 1653 | 07:08:10 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:08:10 INFO - PID 1653 | RSS: Main: 268713984 07:08:10 INFO - PID 1653 | 07:08:13 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:08:14 INFO - PID 1653 | RSS: Main: 268910592 07:08:14 INFO - PID 1653 | 07:08:17 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:08:18 INFO - PID 1653 | RSS: Main: 269078528 07:08:18 INFO - PID 1653 | 07:08:19 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49226/tests/kraken/driver.html?testName=ai-astar) 07:08:20 INFO - PID 1653 | RSS: Main: 270565376 07:08:20 INFO - PID 1653 | 07:08:20 INFO - PID 1653 | __start_tp_report 07:08:20 INFO - PID 1653 | _x_x_mozilla_page_load 07:08:20 INFO - PID 1653 | _x_x_mozilla_page_load_details 07:08:20 INFO - PID 1653 | |i|pagename|runs| 07:08:20 INFO - PID 1653 | |0;ai-astar;102;114;115;133;150;114;168;168;154;115 07:08:20 INFO - PID 1653 | |1;audio-beat-detection;130;110;115;145;114;109;113;119;146;111 07:08:20 INFO - PID 1653 | |2;audio-dft;246;253;195;202;228;201;196;242;248;197 07:08:20 INFO - PID 1653 | |3;audio-fft;81;59;60;82;59;58;64;62;81;55 07:08:20 INFO - PID 1653 | |4;audio-oscillator;83;70;83;71;69;70;88;71;71;88 07:08:20 INFO - PID 1653 | |5;imaging-gaussian-blur;89;91;88;88;89;88;88;89;98;89 07:08:20 INFO - PID 1653 | |6;imaging-darkroom;93;154;157;107;114;128;106;133;138;112 07:08:20 INFO - PID 1653 | |7;imaging-desaturate;95;89;98;92;92;92;91;98;73;126 07:08:20 INFO - PID 1653 | |8;json-parse-financial;52;50;52;51;50;57;69;48;49;53 07:08:20 INFO - PID 1653 | |9;json-stringify-tinderbox;57;62;65;91;85;65;59;56;65;81 07:08:20 INFO - PID 1653 | |10;stanford-crypto-aes;84;83;85;83;84;83;82;84;86;83 07:08:20 INFO - PID 1653 | |11;stanford-crypto-ccm;148;120;125;173;166;152;133;140;225;136 07:08:20 INFO - PID 1653 | |12;stanford-crypto-pbkdf2;223;172;171;184;188;186;189;183;186;186 07:08:20 INFO - PID 1653 | |13;stanford-crypto-sha256-iterative;70;67;76;93;98;100;101;66;87;99 07:08:20 INFO - PID 1653 | __end_tp_report 07:08:20 INFO - PID 1653 | __start_cc_report 07:08:20 INFO - PID 1653 | _x_x_mozilla_cycle_collect,923 07:08:20 INFO - PID 1653 | __end_cc_report 07:08:20 INFO - PID 1653 | __startTimestamp1495116500150__endTimestamp 07:08:20 INFO - PID 1653 | 07:08:20 INFO - PID 1653 | ------- Summary: start ------- 07:08:20 INFO - PID 1653 | Number of tests: 14 07:08:20 INFO - PID 1653 | 07:08:20 INFO - PID 1653 | [#0] ai-astar Cycles:10 Average:133.30 Median:141.50 stddev:24.74 (17.5%) stddev-sans-first:23.51 07:08:20 INFO - PID 1653 | Values: 102.0 114.0 115.0 133.0 150.0 114.0 168.0 168.0 154.0 115.0 07:08:20 INFO - PID 1653 | 07:08:20 INFO - PID 1653 | [#1] audio-beat-detection Cycles:10 Average:121.20 Median:117.00 stddev:14.14 (12.1%) stddev-sans-first:14.64 07:08:20 INFO - PID 1653 | Values: 130.0 110.0 115.0 145.0 114.0 109.0 113.0 119.0 146.0 111.0 07:08:20 INFO - PID 1653 | 07:08:20 INFO - PID 1653 | [#2] audio-dft Cycles:10 Average:220.80 Median:235.00 stddev:24.73 (10.5%) stddev-sans-first:24.49 07:08:20 INFO - PID 1653 | Values: 246.0 253.0 195.0 202.0 228.0 201.0 196.0 242.0 248.0 197.0 07:08:20 INFO - PID 1653 | 07:08:20 INFO - PID 1653 | [#3] audio-fft Cycles:10 Average:66.10 Median:63.00 stddev:10.77 (17.1%) stddev-sans-first:9.99 07:08:20 INFO - PID 1653 | Values: 81.0 59.0 60.0 82.0 59.0 58.0 64.0 62.0 81.0 55.0 07:08:20 INFO - PID 1653 | 07:08:20 INFO - PID 1653 | [#4] audio-oscillator Cycles:10 Average:76.40 Median:77.00 stddev:8.03 (10.4%) stddev-sans-first:8.15 07:08:20 INFO - PID 1653 | Values: 83.0 70.0 83.0 71.0 69.0 70.0 88.0 71.0 71.0 88.0 07:08:20 INFO - PID 1653 | 07:08:20 INFO - PID 1653 | [#5] imaging-gaussian-blur Cycles:10 Average:89.70 Median:89.00 stddev:3.06 (3.4%) stddev-sans-first:3.23 07:08:20 INFO - PID 1653 | Values: 89.0 91.0 88.0 88.0 89.0 88.0 88.0 89.0 98.0 89.0 07:08:20 INFO - PID 1653 | 07:08:20 INFO - PID 1653 | [#6] imaging-darkroom Cycles:10 Average:124.20 Median:135.50 stddev:21.34 (15.8%) stddev-sans-first:19.42 07:08:20 INFO - PID 1653 | Values: 93.0 154.0 157.0 107.0 114.0 128.0 106.0 133.0 138.0 112.0 07:08:20 INFO - PID 1653 | 07:08:20 INFO - PID 1653 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:92.00 stddev:13.08 (14.2%) stddev-sans-first:13.88 07:08:20 INFO - PID 1653 | Values: 95.0 89.0 98.0 92.0 92.0 92.0 91.0 98.0 73.0 126.0 07:08:20 INFO - PID 1653 | 07:08:20 INFO - PID 1653 | [#8] json-parse-financial Cycles:10 Average:53.10 Median:52.00 stddev:6.12 (11.8%) stddev-sans-first:6.48 07:08:20 INFO - PID 1653 | Values: 52.0 50.0 52.0 51.0 50.0 57.0 69.0 48.0 49.0 53.0 07:08:20 INFO - PID 1653 | 07:08:20 INFO - PID 1653 | [#9] json-stringify-tinderbox Cycles:10 Average:68.60 Median:65.00 stddev:12.44 (19.1%) stddev-sans-first:12.46 07:08:20 INFO - PID 1653 | Values: 57.0 62.0 65.0 91.0 85.0 65.0 59.0 56.0 65.0 81.0 07:08:20 INFO - PID 1653 | 07:08:20 INFO - PID 1653 | [#10] stanford-crypto-aes Cycles:10 Average:83.70 Median:84.00 stddev:1.16 (1.4%) stddev-sans-first:1.22 07:08:20 INFO - PID 1653 | Values: 84.0 83.0 85.0 83.0 84.0 83.0 82.0 84.0 86.0 83.0 07:08:20 INFO - PID 1653 | 07:08:20 INFO - PID 1653 | [#11] stanford-crypto-ccm Cycles:10 Average:151.80 Median:150.00 stddev:30.72 (20.5%) stddev-sans-first:32.56 07:08:20 INFO - PID 1653 | Values: 148.0 120.0 125.0 173.0 166.0 152.0 133.0 140.0 225.0 136.0 07:08:20 INFO - PID 1653 | 07:08:20 INFO - PID 1653 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.80 Median:186.00 stddev:14.18 (7.6%) stddev-sans-first:6.65 07:08:20 INFO - PID 1653 | Values: 223.0 172.0 171.0 184.0 188.0 186.0 189.0 183.0 186.0 186.0 07:08:20 INFO - PID 1653 | 07:08:20 INFO - PID 1653 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:85.70 Median:81.50 stddev:14.53 (17.8%) stddev-sans-first:14.26 07:08:20 INFO - PID 1653 | Values: 70.0 67.0 76.0 93.0 98.0 100.0 101.0 66.0 87.0 99.0 07:08:20 INFO - PID 1653 | -------- Summary: end -------- 07:08:20 INFO - PID 1653 | 07:08:20 INFO - TEST-INFO | 1653: exit 0 07:08:20 INFO - TEST-OK | kraken | took 60431ms 07:08:20 INFO - SUITE-END | took 1173s 07:08:21 INFO - Completed test suite (00:19:33) 07:08:21 INFO - javascript benchmark 07:08:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7856.002941818688, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3615.8192090395473, 3619.384704600218, 3651.634836516347, 3657.7073834093276, 3661.3775658138115, 5728.341240757309, 6193.876122477559, 6301.968490157555, 6316.084167795672, 6348.619082855026, 6241.063840423939, 6243.594166379182, 6259.906101408483, 6298.0, 6335.904961425582, 4473.507914129443, 5453.727313634303, 5493.368262649787, 5520.641158324696, 5526.392096869341, 5583.888322233553, 5654.0, 5669.433056694339, 5700.429957004287, 5731.14032895067, 5464.4262352452815, 5591.4688104629995, 5607.551395888319, 5665.433456654343, 5673.602847800656, 5513.807016754435, 5540.473655002768, 5575.553955683536, 5650.943490565083, 5656.4060773618585, 5533.584981126432, 5533.584981126432, 5539.030669632798, 5551.195076713894, 5555.2222688823595, 6068.180795592582, 6311.7159727811895, 6328.588641738318, 6330.39861213184, 6339.556231063827, 6556.0, 6631.867362652768, 6661.766838160641, 6822.147231596051, 6823.590584564892, 6901.930980690179, 6954.547954383, 6956.165260168812, 6969.6166710830885, 6971.058907047585, 5590.720463976785, 6181.134641150243, 6410.942194537895, 6422.518311126684, 6431.196100487439, 5679.602427830012, 6753.223379311344, 6922.757703480454, 6927.549709268881, 7032.226455089936, 6515.02274658805, 6609.0, 6647.933520664828, 6686.297938716365, 6721.966390168018, 6841.965790171017, 6846.383825455733, 6855.691493882788, 6860.3139686031, 6864.039034535169, 5234.345706786652, 5468.316460442445, 6508.9023664645065, 6517.902231466532, 6820.795376138723, 6543.9345606544275, 6800.455963522906, 6814.114165158498, 6815.591064536144, 6849.520533562602, 5327.094393953037, 5341.65279256847, 5344.973275133677, 5388.137897936312, 5441.646292990943, 4803.567678908915, 4831.613470922318, 4838.491958344393, 4840.419149701988, 4840.983393487337, 6366.7771627993725, 6375.203099612549, 6384.361563843579, 6390.520710946697, 6416.262129855033, 5793.768249270066, 6665.800025999228, 6739.258681545026, 6826.010228516937, 6831.658417079127, 6369.681515924185, 6371.9681401593625, 6383.968080159662, 6385.936140638627, 6398.072279519443, 6005.069214271794, 6009.06859436788, 6026.397360263939, 6037.0642550404755, 6082.878342433127, 6810.31896810315, 6919.0, 6985.3713165815325, 7063.152421709222, 7069.929300706927, 6923.238443771282, 6939.653017349011, 6946.2011868635755, 6953.582785032914, 6957.860842783217, 6985.7205711772995, 7099.112610923635, 7100.680469378787, 7103.325184107502, 7115.181754098345, 6556.803295901131, 6623.536352455475, 6674.966625166843, 6751.594904305757, 6798.694058767466, 6892.586444813327, 6922.411595014279, 6927.653617319214, 6940.993555934462, 6962.443004559522, 6582.111414958919, 6587.802365928838, 6643.136392268974, 6646.770347485537, 6652.234992975869, 5464.0, 5626.690532020614, 6426.003969384753, 6537.8692426150255, 6606.8017959461295, 6349.111124442489, 6353.8729225416155, 6488.221413430419, 6492.805215843532, 6508.544401891822, 6615.966920165369, 6647.235567909559, 6647.4682025438815, 6650.90023649636, 6651.767188148391, 5524.254225679482, 6267.2165979252595, 6319.936800631935, 6356.364363563606, 6483.643399613066], "value": 6168.80515638761, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [656.3075954867614, 777.172311488265, 830.9542975136369, 1206.5354838387218, 1259.5969289827246, 35353.646463535355, 35450.29099418016, 37169.44245836311, 37404.43893341602, 37586.8723938282, 29413.85293073537, 30855.537166942457, 37828.62171378286, 38105.428418573676, 38228.22147231596, 7040.190378106532, 7759.146493885668, 7942.682292708285, 8100.797480062986, 8330.166983301651, 8242.505449673008, 8348.916510834875, 8358.0806111328, 8360.038595561497, 8376.41365104443, 7329.853402931938, 8254.752357429286, 8311.293540049102, 8314.667413303461, 8333.916660833374, 8321.542315172663, 8345.499270043816, 8359.414840961135, 8359.456635318746, 8443.451175673561, 8072.071711753106, 8077.35381169505, 8092.0, 8115.066767321775, 8152.062512810984, 34694.306113877836, 34960.0, 34962.65037349645, 35016.299674006375, 35023.6497635023, 3464.84408201629, 3476.5654293213347, 3480.6867381935494, 3490.930181396358, 3491.930161396783, 28629.427411451656, 28839.423211535654, 28998.565021524693, 29001.419971600662, 29036.12891613255, 3038.756899448039, 3082.0137555982096, 3084.290613158986, 3087.799293045945, 3094.6441159266747, 287456.0, 290251.0, 290930.0, 292269.99999999785, 295984.0, 44355.55644443579, 44566.77716611396, 44571.777141114086, 44697.65907022794, 44793.55206447959, 45604.315935260995, 46206.76896615563, 51941.0, 53117.0, 53261.7336913313, 13963.371648275852, 13976.790348144787, 14100.365483553265, 14129.505467308594, 14141.44444111147, 11288.266262693063, 11319.547218111184, 11322.547098116147, 11332.6033588824, 11396.658100257006, 13466.326683665777, 13602.387892544859, 13604.93197534006, 13613.727725445435, 13745.38145783442, 5153.805449335575, 5994.550408719363, 6054.152418661391, 6091.817245482643, 6097.0, 993.6174572789383, 1038.771470276538, 1051.3376572759112, 1058.5077938758513, 1070.4968664727567, 17668.0, 20228.0, 22723.545529089657, 23181.652275215885, 24194.758052419602, 998.6255154317131, 1112.6272698645882, 1138.3682056458579, 1153.648137318126, 1179.622520793355, 11369.374684392438, 14027.929860350838, 15393.845461590425, 15743.68512629741, 16038.679226415406, 270617.0, 286974.0, 306546.0, 308375.0, 321925.0, 46807.76596116998, 49064.77338066577, 52796.0, 52797.0, 52893.7355313221, 1511.8110236220473, 1533.9539813805604, 1537.4618883390947, 1537.8539038791298, 1549.6668216333542, 26952.0, 28311.15066548007, 28670.856645716638, 28969.130926072252, 28994.13017609475, 36985.89042328627, 37011.81494092512, 37014.0, 37025.0, 37098.8145059273, 288956.55521722254, 289683.0, 289801.5509922437, 290030.5498472494, 290704.0, 26102.86948565245, 31682.89109881143, 32664.183395415304, 32724.34551309008, 32737.017889463354, 40699.79650101731, 40700.0, 40732.59267407288, 40870.59129408668, 40883.0, 27512.0, 28662.713372866838, 28755.71244287614, 28863.42273154483, 29470.0, 21430.357089287347, 28739.994100206393, 29338.559921600765, 29423.55864661989, 29695.25761855971, 34581.48127778035, 34585.30829383449, 34590.0, 34622.65377346194, 34701.82649086738, 27753.722462775113, 28360.432791343646, 28914.855425722737, 29514.0, 29688.0, 19216.519587010436, 22336.77663223347, 23109.191178309353, 23499.53000940006, 23538.76461235434], "value": 17066.95179606487, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.9773814702044, 445.2853170661087, 446.90285349467064, 447.52785810969436, 460.4957571459252, 20476.89761551192, 20886.373408797743, 21244.89377553112, 22337.883105844725, 22472.662910056362, 19330.22679092838, 20200.69698954517, 22170.224042158527, 22763.544729105408, 23591.17430889915, 6028.939710602893, 6218.0, 6242.438180563749, 6255.4995600352095, 6281.497480201596, 5321.4412486688925, 6085.969570152143, 6124.938750612504, 6175.938240617604, 6228.096925945735, 6126.417990290937, 6183.536234782387, 6197.194364732579, 6199.070139479091, 6221.937780622181, 6061.787837425691, 6113.205283313186, 6114.2357205349335, 6120.357362476965, 6162.96918515409, 5199.324087868591, 5369.812056578001, 5495.0, 5507.339119305669, 5526.143447765603, 27370.315742106406, 27421.0, 27483.17550473489, 27713.722862771316, 27840.443191136266, 2164.599549083425, 2216.833737469696, 2220.944476388087, 2232.821374290053, 2233.062113912161, 17504.21231044606, 17677.38129165473, 17776.733348999776, 17950.102494875206, 18017.819821801877, 1851.7870444898874, 2140.4327853118934, 2181.2256648889575, 2183.1267493002765, 2189.3322536626324, 155939.0, 156492.0, 159134.0, 159225.2038739799, 160959.99999999884, 10915.454227288605, 11936.0, 12935.611931642066, 12980.935095324463, 13062.020348473903, 14575.0, 16667.749983750255, 16696.58258543522, 16709.498715038568, 16723.414680486123, 6509.772157974543, 8505.872411913826, 8757.0, 8816.338774591932, 8855.114488551222, 7106.822329441702, 8166.510009399455, 8284.8343033139, 8323.750287491384, 8329.583520824055, 7116.146062472537, 7593.620318984029, 7943.364530837519, 8039.392121575708, 8060.959695201486, 1729.8270172982602, 1773.875828692005, 2171.956560868774, 2181.5418762060144, 2373.7863592276776, 881.8280435315052, 946.7349142240183, 970.9465979371203, 992.1071036067597, 1007.9748006299901, 20207.49481262951, 21649.567008660055, 25499.617505737428, 26976.73023269742, 27327.590086148724, 999.8052327468605, 1006.3861044762689, 1008.1884083313004, 1018.9032041956002, 1019.0675531888387, 10576.471176441148, 10589.629362972259, 11184.720381990515, 11208.27146235492, 11238.38188899602, 465327.0, 550599.0, 562400.5719456645, 566816.0, 568941.0, 108947.45526272476, 112101.0, 131704.68295317117, 134611.0, 135324.0, 1197.5748609243592, 1219.6280134559045, 1244.9066320026034, 1246.7880460321585, 1248.581725122076, 20352.18591256333, 22526.76102814361, 22810.65784013208, 22882.542349153257, 22977.195798146986, 55523.72238138784, 55666.0, 56136.719316403156, 56578.7171064142, 56662.30013099613, 76330.23669763231, 76519.61740191486, 77056.61471692605, 77473.0, 78480.0, 8953.328500362368, 8975.281977441658, 9051.230645394951, 9099.008077721137, 9304.906950930404, 13733.313334333043, 13929.442822286996, 14353.92823035878, 14392.856071439151, 14534.491292804702, 10895.891041089804, 11956.641300760992, 11981.64055078349, 11983.28100313984, 12169.90470857627, 11152.609658661908, 11401.429928503376, 11523.250988685708, 11576.36330001858, 11702.590409335633, 12123.090768192596, 12210.938945305217, 12398.938005309916, 12469.5012199511, 12582.622521324374, 9069.727908162766, 9109.544522773967, 9121.543922803967, 9160.908390916005, 9186.356955013085, 9887.110160085627, 11872.990795782454, 12009.099317551394, 12065.336406497376, 12282.263064216175], "value": 10490.240852267878, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1196.8204769284607, 1279.0215485153858, 1456.271864067966, 1493.3653197391106, 1495.4018392642945, 9234.63061477541, 11673.0, 12092.637220883376, 12119.575814846483, 12361.011119110472, 11893.0, 12092.818607720868, 12105.273683578991, 12110.0311975042, 12148.574799882006, 2570.6915170179605, 2627.8029147813895, 2632.7104018557943, 2633.591793272046, 2640.1683469707077, 2228.186711850179, 2536.8964500442275, 2547.0957809977467, 2598.883050262743, 2600.6749156355454, 2570.473053024135, 2590.3912580543556, 2616.032068134793, 2626.2909014566058, 2629.4609605030923, 2537.1500547316673, 2563.141347648531, 2563.6539067225967, 2583.4833033393397, 2591.403977085281, 2522.823402361835, 2539.9111031114007, 2570.2546261584116, 2616.7121616622157, 2622.0, 11849.289042657469, 11986.940065299617, 11988.460519276654, 11993.28040317575, 11996.70008249792, 6692.899606505857, 6924.896126558106, 6927.861442771166, 6938.965305173492, 6952.165740111169, 3610.007248006793, 3947.940780888289, 4381.846635367779, 4428.756418396987, 4535.886602834922, 1222.3093951917137, 1245.1844042152404, 1287.6008437384442, 1289.7936330187124, 1292.5088466382715, 16441.6711665766, 16442.83557164425, 16517.008979461272, 16533.58666033347, 17000.14999250045, 5589.972050139723, 5675.971620141873, 5835.44563266489, 5838.416158384128, 5860.765569377178, 11620.825687614693, 11662.76674466506, 11817.409129543661, 11838.467268972745, 11838.704032399086, 6597.0764093026055, 6662.600243985376, 6703.463722902156, 6712.731490740413, 6721.563098398687, 6118.755249789959, 6182.6599537024995, 6309.652969086745, 6329.715162817684, 6334.746610135543, 4334.4798624165305, 4472.530384309665, 4476.239039363316, 4481.775911204427, 4572.245579479349, 1059.6820953713855, 1089.84742136101, 1291.4963164365904, 1303.6349822049842, 1303.7327347893658, 685.807973767346, 728.2644529025722, 812.4556547113448, 814.2915663372903, 834.6828205281954, 6003.919294527027, 6078.452939235402, 6243.219597550306, 6243.594166379171, 6425.100485931974, 465.2834634662582, 566.8659914970066, 611.5230120506012, 620.0482259731291, 620.8137558732362, 3938.7242892997215, 3952.8023598819946, 3986.142979259473, 4004.139110091286, 4042.919141617151, 14540.0, 15286.464973725866, 15339.539813805604, 15365.692686146214, 15381.846181538265, 12403.193792403645, 15058.924705376403, 15098.849011509965, 15119.622009449851, 15286.159261240517, 8247.711330103537, 8481.0, 8528.763329317339, 8653.567321633893, 8757.868631970525, 6026.156338112755, 7083.610401427971, 7112.964435177998, 7152.320529549685, 7303.817404564716, 10908.890911090788, 11782.52869885195, 12797.680057998625, 13298.734025319633, 13732.038757286895, 13830.86169138336, 13853.584392468241, 13884.583462495737, 14145.646358841112, 14359.712805743618, 9130.269578433843, 9398.436093834393, 9414.2468602513, 9521.904780952102, 9553.522323883917, 10775.51510182028, 10822.783544329226, 10844.783104338027, 10853.620123295646, 10890.509927053143, 8909.643614255358, 9092.363534552518, 9136.908630913606, 9151.313651476034, 9200.21798147165, 8894.822103558023, 8955.776105597412, 8984.371094023356, 9004.63981440735, 9071.59177837012, 8043.718469853527, 8202.548859812767, 8219.178082191733, 8230.794230144294, 8232.711855085283, 6025.969870150621, 6068.969655151873, 6087.299960504599, 6184.783532576338, 6302.590331628429, 5379.4082650907585, 5927.229460170151, 6083.361247068994, 6220.844478887883, 6391.169148010729], "value": 5338.331800708288, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.9190586165182, 387.40339876590053, 394.1820722001846, 414.15099046953736, 414.2253985047961, 6371.776987805416, 6407.967960160193, 6415.807525774228, 6419.454346380558, 6525.608463492182, 6217.222847144107, 6271.529635277361, 6289.245290565127, 6338.668879535303, 6355.173827402431, 4524.502304746476, 4587.174308624446, 4591.517890621487, 4601.608863246628, 4619.86140415788, 4510.571495707919, 4562.520935301795, 4566.1780879441685, 4607.89410541471, 4619.653525985547, 3754.755940863849, 3954.8813535593836, 4513.7066090704, 4523.457185137771, 4548.749818759967, 3764.623537646241, 4358.7166834156, 4507.323901414781, 4552.521985191557, 4581.152486789922, 2940.558916162571, 3474.1314671332166, 3808.638179372955, 3903.863364782219, 3912.6283003114945, 6124.877502449971, 6155.6614386209185, 6183.845403864894, 6185.226846644169, 6195.659238741868, 5120.9758048390095, 5418.024755544, 5444.836654900319, 5511.53151982082, 5543.556515478752, 5814.738336774855, 6673.9666301668185, 6724.865502689919, 6761.030642477775, 6812.591244525295, 3493.388656985043, 4396.758178300192, 4403.339499075132, 4406.603405693471, 4426.601605855456, 5073.0, 6734.0, 6929.99515070317, 6939.757108501179, 7076.7169313227405, 4083.2650122977557, 4408.801603927831, 4995.775190116381, 5060.2156665716875, 5101.387833460009, 4982.377202849644, 5221.6083793715625, 5244.973775131176, 5265.447128051576, 5277.868053298698, 5130.4356520782685, 5512.724363781875, 5592.972035139798, 5805.303363596396, 5846.6784326862435, 5662.546996240291, 5803.564732645069, 6515.902261466082, 6733.057371967929, 6879.415249703831, 1754.5964428181335, 1762.5899280575488, 1769.716845304771, 1776.413783451458, 1784.946451606428, 1036.0778906772978, 1210.2315029956042, 1238.8451443569554, 1240.0, 1242.4781591731494, 6354.523410744213, 6357.268914074848, 6365.61806291624, 6376.776812811529, 6402.343027643569, 6365.331640177715, 6975.441964642816, 6976.337247961435, 7008.123984501937, 7053.329933656294, 5347.786088556492, 5791.986402379567, 5838.620489668158, 6337.04944258365, 6387.105805187278, 3213.823239721791, 3426.760126791101, 3581.928361432757, 3597.370460169512, 3664.4136938089778, 5959.437441900024, 6918.477934854324, 7047.260037696071, 7175.461840361993, 7206.351428371367, 6370.77702280418, 7169.856602867913, 7180.605066721276, 7190.568565886064, 7217.0459371019115, 5822.737976791054, 6171.96914015418, 6176.69116544162, 7136.536125151849, 7158.1746654603785, 5962.4633782959745, 6250.312465628686, 6900.205946454059, 7051.342934410512, 7096.8935465967015, 6669.966650166718, 6789.93210067893, 6847.835867902369, 6899.896501552581, 6992.61540615271, 5748.597598168088, 7191.712331506681, 7201.927980720126, 7217.350438460564, 7220.916862470462, 4346.521882592975, 4529.84145554904, 4605.562471565196, 5163.4578369270685, 5178.860650656849, 6208.379162083756, 7095.077639906779, 7115.146182458138, 7146.464015198777, 7149.998000559852, 6406.487481001415, 6618.801435956929, 6681.632510211985, 6711.697973591103, 6775.815366777607, 5967.6121052131475, 6760.985852122221, 6788.66056697173, 6800.455963523005, 6823.28355522663, 5612.915806262991, 6478.28738838718, 6645.966770166118, 6669.266380698023, 6678.83302917431, 5434.673919564839, 6742.69657865407, 6753.966230168818, 6809.072098997479, 6859.5541289816, 5457.0, 6298.401651843067, 6414.262359828493, 6612.570182938093, 6618.87479128559], "value": 4925.612063772861, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [795.1174196641726, 833.791552111972, 861.5476874640813, 875.6558682422478, 877.1886005444964, 16127.274272657729, 16442.09568473735, 16443.2600532976, 17380.21789019494, 17779.288828446846, 17573.209205585747, 17867.91066044668, 18142.63714725708, 18171.0, 18526.444206673823, 878.108719649553, 961.1109723505764, 995.4723996281982, 1001.484235618657, 1003.9247056470757, 945.0266225787416, 992.8064027514638, 1002.503760638485, 1003.4581326083905, 1006.0, 1011.9494025298742, 1028.9485525723685, 1045.7751583409565, 1046.6232156423682, 1049.4123290957089, 1011.4386515483903, 1039.698487438642, 1040.9427481488515, 1044.1542350695922, 1048.9370637761758, 753.8756105242628, 760.1562265884863, 776.6427443376053, 778.8286576953061, 778.9883151752729, 16236.43172488969, 16445.753313700305, 16559.751603725952, 16980.49058528234, 17132.486025419257, 17064.914675426666, 17160.485185444457, 17456.30174793007, 17460.476185714448, 17559.82440175595, 14250.643733906736, 14254.501092461818, 14338.139711617336, 14457.421703131862, 14549.12705237689, 3603.5315408996926, 3631.346357655621, 3639.1084184375, 3644.835982380799, 3654.780713157219, 117658.82341176564, 118970.0, 118990.21514677287, 120177.59644807142, 121731.78268217292, 11775.470103845348, 11919.284842909454, 12152.756944861054, 12353.38233088342, 12392.75214495705, 25676.3580910475, 25882.482350352886, 25917.870410648204, 26405.33986650349, 26407.075752348577, 7368.705251789976, 7400.703971841066, 7422.665980030912, 7429.2570742926355, 7434.628268586549, 3676.3382591133854, 3725.4784330193793, 3789.1285004448578, 3793.468914351993, 3793.715471339661, 4344.652427805768, 4427.601515863587, 4453.933191002137, 4453.955460445354, 4460.063386688832, 764.1861417590248, 874.1127755328347, 893.647009431271, 894.4007514964859, 897.6678628907215, 644.7195469970578, 656.1732217406103, 661.5798967655574, 668.044696084594, 679.1069743287561, 11257.324560526231, 11517.193796434169, 11857.88142118585, 12032.699182520331, 12048.939755301168, 531.7503865915069, 548.44333002003, 655.5574986883835, 676.6075676107846, 686.2074304178682, 4343.630791382705, 5012.997400519911, 5575.60970732053, 5823.126531020381, 5823.88352232953, 102571.0, 103935.0, 105949.47025264979, 106629.0, 106952.46523767487, 60312.396876031555, 71044.64477677683, 71360.64319678368, 76101.21183130016, 76738.0, 70936.0, 71495.64252178706, 71858.0, 71914.0, 72769.0, 9434.575444105169, 11585.536578536765, 11723.296602203895, 12261.325627090595, 12385.133040687426, 25877.0, 28484.857575711987, 28769.712302876702, 35391.646083538835, 35970.0, 47894.0, 47994.0, 48043.519564805305, 48107.0, 48160.75919620379, 15751.44869929547, 15920.363185472452, 16059.116748578941, 16237.106959116889, 16444.67110657804, 15250.313735882133, 17734.645307094044, 18124.72812907834, 18146.45560633183, 18436.0, 16249.025058496056, 16578.253978570752, 16733.832661673227, 16831.495055148363, 17078.2314795832, 16128.596785080466, 16243.350265989227, 16412.507624771275, 16601.50195494137, 16609.0, 12632.494700211888, 15787.447439340027, 15936.521904342888, 15983.760243596587, 16221.0, 10965.56137754481, 11149.609763658233, 11200.327980321206, 11311.660650180507, 11393.658190254306, 12600.810987835373, 12710.555130570385, 12716.745665086832, 12718.618441446772, 12833.486660533474], "value": 8094.179896447119, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1556.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 114.0, 115.0, 133.0, 150.0, 114.0, 168.0, 168.0, 154.0, 115.0], "value": 133.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 110.0, 115.0, 145.0, 114.0, 109.0, 113.0, 119.0, 146.0, 111.0], "value": 121.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [246.0, 253.0, 195.0, 202.0, 228.0, 201.0, 196.0, 242.0, 248.0, 197.0], "value": 220.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 60.0, 82.0, 59.0, 58.0, 64.0, 62.0, 81.0, 55.0], "value": 66.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 70.0, 83.0, 71.0, 69.0, 70.0, 88.0, 71.0, 71.0, 88.0], "value": 76.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 91.0, 88.0, 88.0, 89.0, 88.0, 88.0, 89.0, 98.0, 89.0], "value": 89.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 154.0, 157.0, 107.0, 114.0, 128.0, 106.0, 133.0, 138.0, 112.0], "value": 124.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 89.0, 98.0, 92.0, 92.0, 92.0, 91.0, 98.0, 73.0, 126.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 50.0, 52.0, 51.0, 50.0, 57.0, 69.0, 48.0, 49.0, 53.0], "value": 53.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 62.0, 65.0, 91.0, 85.0, 65.0, 59.0, 56.0, 65.0, 81.0], "value": 68.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 85.0, 83.0, 84.0, 83.0, 82.0, 84.0, 86.0, 83.0], "value": 83.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 120.0, 125.0, 173.0, 166.0, 152.0, 133.0, 140.0, 225.0, 136.0], "value": 151.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [223.0, 172.0, 171.0, 184.0, 188.0, 186.0, 189.0, 183.0, 186.0, 186.0], "value": 186.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 67.0, 76.0, 93.0, 98.0, 100.0, 101.0, 66.0, 87.0, 99.0], "value": 85.7, "unit": "score"}]}]} 07:08:21 INFO - Return code: 0 07:08:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:08:21 INFO - # TBPL SUCCESS # 07:08:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:08:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:08:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:08:21 INFO - 2017-05-18 07:08:21.454 screenresolution[1660:10893] starting screenresolution argv=screenresolution get 07:08:21 INFO - 2017-05-18 07:08:21.457 screenresolution[1660:10893] Display 0: 1600x1200x32@60 07:08:21 INFO - 2017-05-18 07:08:21.464 screenresolution[1661:10896] starting screenresolution argv=screenresolution list 07:08:21 INFO - Available Modes on Display 0 07:08:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:08:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:08:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:08:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:08:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:08:21 INFO - Intel Iris: 07:08:21 INFO - Chipset Model: Intel Iris 07:08:21 INFO - Type: GPU 07:08:21 INFO - Bus: Built-In 07:08:21 INFO - VRAM (Dynamic, Max): 1536 MB 07:08:21 INFO - Vendor: Intel (0x8086) 07:08:21 INFO - Device ID: 0x0a2e 07:08:21 INFO - Revision ID: 0x0009 07:08:21 INFO - Displays: 07:08:21 INFO - Display: 07:08:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:08:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:08:21 INFO - Display Serial Number: 1600x1200 60 07:08:21 INFO - Main Display: Yes 07:08:21 INFO - Mirror: Off 07:08:21 INFO - Online: Yes 07:08:21 INFO - Rotation: Supported 07:08:21 INFO - Return code: 0 07:08:21 INFO - Running post-action listener: _resource_record_post_action 07:08:21 INFO - [mozharness: 2017-05-18 14:08:21.700966Z] Finished run-tests step (success) 07:08:21 INFO - Running post-run listener: _resource_record_post_run 07:08:21 INFO - Total resource usage - Wall time: 1197s; CPU: 54.0%; Read bytes: 40094720; Write bytes: 575691776; Read time: 393; Write time: 1797 07:08:21 INFO - TinderboxPrint: CPU usage
54.1% 07:08:21 INFO - TinderboxPrint: I/O read bytes / time
40,094,720 / 393 07:08:21 INFO - TinderboxPrint: I/O write bytes / time
575,691,776 / 1,797 07:08:21 INFO - TinderboxPrint: CPU idle
2,198.1 (45.9%) 07:08:21 INFO - TinderboxPrint: CPU system
963.5 (20.1%) 07:08:21 INFO - TinderboxPrint: CPU user
1,628.0 (34.0%) 07:08:21 INFO - TinderboxPrint: Swap in / out
299,315,200 / 0 07:08:21 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 248398336; Write bytes: 238868480; Read time: 17461; Write time: 536 07:08:21 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 33940480; Write bytes: 311755776; Read time: 309; Write time: 1216 07:08:22 INFO - Running post-run listener: _upload_blobber_files 07:08:22 INFO - Blob upload gear active. 07:08:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:08:22 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 07:08:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:08:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:08:22 INFO - (blobuploader) - INFO - Open directory for files ... 07:08:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 07:08:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:08:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:08:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 07:08:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:08:23 INFO - (blobuploader) - INFO - Done attempting. 07:08:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 07:08:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:08:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:08:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 07:08:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:08:26 INFO - (blobuploader) - INFO - Done attempting. 07:08:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 07:08:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:08:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:08:26 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 07:08:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:08:26 INFO - (blobuploader) - INFO - Done attempting. 07:08:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:08:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:08:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:08:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:08:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:08:29 INFO - (blobuploader) - INFO - Done attempting. 07:08:29 INFO - (blobuploader) - INFO - Iteration through files over. 07:08:29 INFO - Return code: 0 07:08:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:08:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:08:29 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fca92bf9f30d26baf9f5f8b0a343e3bf26988c93a60340feff39c2e7a505e4cc0b2a79ad6d3520fbf918f078e34f17451f07a26f621a3fb4b578c759b06f2974", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e8a93211550ddc47e737705f293b2970e5062fae30f44ce1c99056bd582479d6de03fc3836b6797e2d9cfc991e001aa283323e2805f2e0eb84f5eaa378029f3d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bd5e1ab8b608834fce0fadedf71bbbd593ea427c087d561fd9fd954e1b70b0234fc2051f8f8c388f417f9134a47ad840f3765b331ddcca1ccea218018ce57306", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3372a34cdffb3a7d36d3d0d9fa75e4428635213f1594b74636047fe2c09def8a207ef16c04786d04836722c8cfcf7e37b835959a73202d870a2311bcfb090785"} 07:08:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:08:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:08:29 INFO - Contents: 07:08:29 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fca92bf9f30d26baf9f5f8b0a343e3bf26988c93a60340feff39c2e7a505e4cc0b2a79ad6d3520fbf918f078e34f17451f07a26f621a3fb4b578c759b06f2974", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e8a93211550ddc47e737705f293b2970e5062fae30f44ce1c99056bd582479d6de03fc3836b6797e2d9cfc991e001aa283323e2805f2e0eb84f5eaa378029f3d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bd5e1ab8b608834fce0fadedf71bbbd593ea427c087d561fd9fd954e1b70b0234fc2051f8f8c388f417f9134a47ad840f3765b331ddcca1ccea218018ce57306", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3372a34cdffb3a7d36d3d0d9fa75e4428635213f1594b74636047fe2c09def8a207ef16c04786d04836722c8cfcf7e37b835959a73202d870a2311bcfb090785"} 07:08:29 INFO - Running post-run listener: copy_logs_to_upload_dir 07:08:29 INFO - Copying logs to upload dir... 07:08:29 INFO - mkdir: /builds/slave/test/build/upload/logs 07:08:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1279.575121 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2017-05-18 07:08:29.344327) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-18 07:08:29.347644) ========= 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.gUjgQSZ7tG/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.iRUwogP6Gf/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fca92bf9f30d26baf9f5f8b0a343e3bf26988c93a60340feff39c2e7a505e4cc0b2a79ad6d3520fbf918f078e34f17451f07a26f621a3fb4b578c759b06f2974", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e8a93211550ddc47e737705f293b2970e5062fae30f44ce1c99056bd582479d6de03fc3836b6797e2d9cfc991e001aa283323e2805f2e0eb84f5eaa378029f3d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bd5e1ab8b608834fce0fadedf71bbbd593ea427c087d561fd9fd954e1b70b0234fc2051f8f8c388f417f9134a47ad840f3765b331ddcca1ccea218018ce57306", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3372a34cdffb3a7d36d3d0d9fa75e4428635213f1594b74636047fe2c09def8a207ef16c04786d04836722c8cfcf7e37b835959a73202d870a2311bcfb090785"} build_url:https://queue.taskcluster.net/v1/task/IMhxRChoQx-Seg1mLagG-Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011033 build_url: 'https://queue.taskcluster.net/v1/task/IMhxRChoQx-Seg1mLagG-Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fca92bf9f30d26baf9f5f8b0a343e3bf26988c93a60340feff39c2e7a505e4cc0b2a79ad6d3520fbf918f078e34f17451f07a26f621a3fb4b578c759b06f2974", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e8a93211550ddc47e737705f293b2970e5062fae30f44ce1c99056bd582479d6de03fc3836b6797e2d9cfc991e001aa283323e2805f2e0eb84f5eaa378029f3d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bd5e1ab8b608834fce0fadedf71bbbd593ea427c087d561fd9fd954e1b70b0234fc2051f8f8c388f417f9134a47ad840f3765b331ddcca1ccea218018ce57306", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3372a34cdffb3a7d36d3d0d9fa75e4428635213f1594b74636047fe2c09def8a207ef16c04786d04836722c8cfcf7e37b835959a73202d870a2311bcfb090785"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-18 07:08:29.381237) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-18 07:08:29.381603) ========= 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.gUjgQSZ7tG/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.iRUwogP6Gf/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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.005140 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-18 07:08:29.429999) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-18 07:08:29.430368) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-18 07:08:29.430723) ========= ========= Total master_lag: 0.18 =========