builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0199 starttime: 1496227414.1 results: success (0) revision: 7b8937970f9ca85db88cb2496f2112175fd847c8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-31 03:43:34.099500) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-31 03:43:34.099983) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-31 03:43:34.100268) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-31 03:43:34.153844) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 03:43:34.154208) ========= 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.ez7XG78zRw/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.Yw2BtCHPrx/Listeners TMPDIR=/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/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-31 03:43:34-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 153M=0s 2017-05-31 03:43:34 (153 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.178326 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 03:43:34.356756) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 03:43:34.357075) ========= 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.ez7XG78zRw/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.Yw2BtCHPrx/Listeners TMPDIR=/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/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.021358 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 03:43:34.406410) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-31 03:43:34.406872) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 7b8937970f9ca85db88cb2496f2112175fd847c8 --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 7b8937970f9ca85db88cb2496f2112175fd847c8 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ez7XG78zRw/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.Yw2BtCHPrx/Listeners TMPDIR=/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/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-31 03:43:34,400 truncating revision to first 12 chars 2017-05-31 03:43:34,400 Setting DEBUG logging. 2017-05-31 03:43:34,400 attempt 1/10 2017-05-31 03:43:34,400 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/7b8937970f9c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-31 03:43:36,319 unpacking tar archive at: mozilla-central-7b8937970f9c/testing/mozharness/ program finished with exit code 0 elapsedTime=2.179727 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-31 03:43:36.609572) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-31 03:43:36.610260) ========= script_repo_revision: 7b8937970f9ca85db88cb2496f2112175fd847c8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-31 03:43:36.611019) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-31 03:43:36.611530) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-31 03:43:36.636024) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 17 secs) (at 2017-05-31 03:43:36.636302) ========= /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.ez7XG78zRw/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.Yw2BtCHPrx/Listeners TMPDIR=/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/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 03:43:36 INFO - MultiFileLogger online at 20170531 03:43:36 in /builds/slave/test 03:43:36 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 03:43:36 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:43:36 INFO - {'append_to_log': False, 03:43:36 INFO - 'base_work_dir': '/builds/slave/test', 03:43:36 INFO - 'blob_upload_branch': 'Firefox', 03:43:36 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:43:36 INFO - 'branch': 'Firefox', 03:43:36 INFO - 'buildbot_json_path': 'buildprops.json', 03:43:36 INFO - 'config_files': ('talos/mac_config.py',), 03:43:36 INFO - 'default_actions': ('clobber', 03:43:36 INFO - 'read-buildbot-config', 03:43:36 INFO - 'download-and-extract', 03:43:36 INFO - 'populate-webroot', 03:43:36 INFO - 'create-virtualenv', 03:43:36 INFO - 'install', 03:43:36 INFO - 'setup-mitmproxy', 03:43:36 INFO - 'run-tests'), 03:43:36 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:43:36 INFO - 'download_minidump_stackwalk': True, 03:43:36 INFO - 'download_symbols': 'ondemand', 03:43:36 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:43:36 INFO - 'tooltool.py': '/tools/tooltool.py', 03:43:36 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:43:36 INFO - '/tools/misc-python/virtualenv.py')}, 03:43:36 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:43:36 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:43:36 INFO - 'gecko_profile': False, 03:43:36 INFO - 'gecko_profile_interval': 0, 03:43:36 INFO - 'installer_path': 'installer.exe', 03:43:36 INFO - 'log_level': 'info', 03:43:36 INFO - 'log_name': 'talos', 03:43:36 INFO - 'log_to_console': True, 03:43:36 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:43:36 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:43:36 INFO - 'opt_config_files': (), 03:43:36 INFO - 'pip_index': False, 03:43:36 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:43:36 INFO - 'cmd': ('bash', 03:43:36 INFO - '-c', 03:43:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:43:36 INFO - 'enabled': True, 03:43:36 INFO - 'halt_on_failure': False, 03:43:36 INFO - 'name': 'check_screen_resolution'},), 03:43:36 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:43:36 INFO - 'cmd': ('bash', 03:43:36 INFO - '-c', 03:43:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:43:36 INFO - 'enabled': True, 03:43:36 INFO - 'halt_on_failure': False, 03:43:36 INFO - 'name': 'check_screen_resolution'},), 03:43:36 INFO - 'run_cmd_checks_enabled': True, 03:43:36 INFO - 'suite': 'dromaeojs-e10s', 03:43:36 INFO - 'system_bits': '32', 03:43:36 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:43:36 INFO - 'title': 't-yosemite-r7-0199', 03:43:36 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:43:36 INFO - 'use_talos_json': True, 03:43:36 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:43:36 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:43:36 INFO - 'work_dir': 'build'} 03:43:36 INFO - [mozharness: 2017-05-31 10:43:36.765378Z] Running clobber step. 03:43:36 INFO - Running pre-action listener: _resource_record_pre_action 03:43:36 INFO - Running main action method: clobber 03:43:36 INFO - rmtree: /builds/slave/test/build 03:43:36 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:43:38 INFO - Running post-action listener: _resource_record_post_action 03:43:38 INFO - [mozharness: 2017-05-31 10:43:38.675485Z] Finished clobber step (success) 03:43:38 INFO - [mozharness: 2017-05-31 10:43:38.675639Z] Running read-buildbot-config step. 03:43:38 INFO - Running pre-action listener: _resource_record_pre_action 03:43:38 INFO - Running main action method: read_buildbot_config 03:43:38 INFO - Using buildbot properties: 03:43:38 INFO - { 03:43:38 INFO - "project": "", 03:43:38 INFO - "product": "firefox", 03:43:38 INFO - "who": "cbook@mozilla.com", 03:43:38 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 03:43:38 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 03:43:38 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 03:43:38 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 03:43:38 INFO - "stage_platform": "macosx64", 03:43:38 INFO - "basedir": "/builds/slave/test", 03:43:38 INFO - "buildnumber": 171, 03:43:38 INFO - "platform": "yosemite_r7", 03:43:38 INFO - "parent_task_id": "LBkgda2FSVqBrn9Ey14C8g", 03:43:38 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 03:43:38 INFO - "slavebuilddir": "test", 03:43:38 INFO - "taskId": "BRvZpAHQQeCImpw2H_fLUQ", 03:43:38 INFO - "branch": "mozilla-central", 03:43:38 INFO - "script_repo_revision": "production", 03:43:38 INFO - "revision": "7b8937970f9ca85db88cb2496f2112175fd847c8", 03:43:38 INFO - "slavename": "t-yosemite-r7-0199", 03:43:38 INFO - "repo_path": "mozilla-central" 03:43:38 INFO - } 03:43:38 INFO - Finding installer, test and symbols from parent task. 03:43:38 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BRvZpAHQQeCImpw2H_fLUQ'}, attempt #1 03:43:39 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BRvZpAHQQeCImpw2H_fLUQ'}, attempt #1 03:43:39 INFO - Task dependencies: PiBD4grXRd-LmLUAiXLumA LBkgda2FSVqBrn9Ey14C8g 03:43:39 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LBkgda2FSVqBrn9Ey14C8g'}, attempt #1 03:43:40 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/LBkgda2FSVqBrn9Ey14C8g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 03:43:40 INFO - Set test_url: https://queue.taskcluster.net/v1/task/LBkgda2FSVqBrn9Ey14C8g/artifacts/public/build/test_packages.json 03:43:40 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/LBkgda2FSVqBrn9Ey14C8g/artifacts/public/build/target.crashreporter-symbols.zip 03:43:40 INFO - Running post-action listener: _resource_record_post_action 03:43:40 INFO - [mozharness: 2017-05-31 10:43:40.036459Z] Finished read-buildbot-config step (success) 03:43:40 INFO - [mozharness: 2017-05-31 10:43:40.036588Z] Running download-and-extract step. 03:43:40 INFO - Running pre-action listener: _resource_record_pre_action 03:43:40 INFO - Running main action method: download_and_extract 03:43:40 INFO - mkdir: /builds/slave/test/build/tests 03:43:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:43:40 INFO - trying https://queue.taskcluster.net/v1/task/LBkgda2FSVqBrn9Ey14C8g/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 03:43:40 INFO - Downloading https://queue.taskcluster.net/v1/task/LBkgda2FSVqBrn9Ey14C8g/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 03:43:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LBkgda2FSVqBrn9Ey14C8g/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 03:43:40 INFO - Downloaded 1325 bytes. 03:43:40 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 03:43:40 INFO - Using the following test package requirements: 03:43:40 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:43:40 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 03:43:40 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 03:43:40 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:43:40 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 03:43:40 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:43:40 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 03:43:40 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:43:40 INFO - u'jsshell-mac.zip'], 03:43:40 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:43:40 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 03:43:40 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 03:43:40 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:43:40 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 03:43:40 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:43:40 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 03:43:40 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:43:40 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 03:43:40 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:43:40 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 03:43:40 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 03:43:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LBkgda2FSVqBrn9Ey14C8g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 03:43:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LBkgda2FSVqBrn9Ey14C8g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:43:40 INFO - Fetch https://queue.taskcluster.net/v1/task/LBkgda2FSVqBrn9Ey14C8g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 03:43:42 INFO - Content-Length response header: 57633443 03:43:42 INFO - Bytes received: 57633443 03:44:01 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 03:44:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LBkgda2FSVqBrn9Ey14C8g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 03:44:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LBkgda2FSVqBrn9Ey14C8g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:44:01 INFO - Fetch https://queue.taskcluster.net/v1/task/LBkgda2FSVqBrn9Ey14C8g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 03:44:03 INFO - Content-Length response header: 57633443 03:44:03 INFO - Bytes received: 57633443 03:44:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LBkgda2FSVqBrn9Ey14C8g/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 03:44:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LBkgda2FSVqBrn9Ey14C8g/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 03:44:20 INFO - Fetch https://queue.taskcluster.net/v1/task/LBkgda2FSVqBrn9Ey14C8g/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 03:44:21 INFO - Content-Length response header: 14447425 03:44:21 INFO - Bytes received: 14447425 03:44:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:44:22 INFO - trying https://queue.taskcluster.net/v1/task/LBkgda2FSVqBrn9Ey14C8g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 03:44:22 INFO - Downloading https://queue.taskcluster.net/v1/task/LBkgda2FSVqBrn9Ey14C8g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 03:44:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LBkgda2FSVqBrn9Ey14C8g/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 03:44:24 INFO - Downloaded 67812855 bytes. 03:44:24 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LBkgda2FSVqBrn9Ey14C8g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 03:44:24 INFO - mkdir: /builds/slave/test/properties 03:44:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:44:24 INFO - Writing to file /builds/slave/test/properties/build_url 03:44:24 INFO - Contents: 03:44:24 INFO - build_url:https://queue.taskcluster.net/v1/task/LBkgda2FSVqBrn9Ey14C8g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 03:44:24 INFO - Running post-action listener: _resource_record_post_action 03:44:24 INFO - Running post-action listener: set_extra_try_arguments 03:44:24 INFO - [mozharness: 2017-05-31 10:44:24.202578Z] Finished download-and-extract step (success) 03:44:24 INFO - [mozharness: 2017-05-31 10:44:24.202729Z] Running populate-webroot step. 03:44:24 INFO - Running pre-action listener: _resource_record_pre_action 03:44:24 INFO - Running main action method: populate_webroot 03:44:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:44:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:44:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:44:24 INFO - '--tppagecycles', 03:44:24 INFO - '7'], 03:44:24 INFO - 'tests': ['tsvgm']}}, 03:44:24 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:44:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:44:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:44:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:44:24 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 03:44:24 INFO - 'talos_options': ['--disable-e10s'], 03:44:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:44:24 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:44:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:44:24 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 03:44:24 INFO - 'talos_options': ['--disable-e10s'], 03:44:24 INFO - 'tests': ['damp', 'tps']}, 03:44:24 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:44:24 INFO - 'tests': ['damp', 'tps']}, 03:44:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:44:24 INFO - 'tests': ['dromaeo_dom']}, 03:44:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:44:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:44:24 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 03:44:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 03:44:24 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 03:44:24 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:44:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:44:24 INFO - 'tests': ['a11yr', 03:44:24 INFO - 'ts_paint', 03:44:24 INFO - 'tpaint', 03:44:24 INFO - 'sessionrestore', 03:44:24 INFO - 'sessionrestore_no_auto_restore', 03:44:24 INFO - 'tabpaint']}, 03:44:24 INFO - 'other-e10s': {'tests': ['a11yr', 03:44:24 INFO - 'ts_paint', 03:44:24 INFO - 'tpaint', 03:44:24 INFO - 'sessionrestore', 03:44:24 INFO - 'sessionrestore_no_auto_restore', 03:44:24 INFO - 'tabpaint']}, 03:44:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:44:24 INFO - 'ts_paint', 03:44:24 INFO - 'tpaint', 03:44:24 INFO - 'sessionrestore', 03:44:24 INFO - 'sessionrestore_no_auto_restore', 03:44:24 INFO - 'tabpaint']}, 03:44:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:44:24 INFO - 'ts_paint', 03:44:24 INFO - 'tpaint', 03:44:24 INFO - 'sessionrestore', 03:44:24 INFO - 'sessionrestore_no_auto_restore', 03:44:24 INFO - 'tabpaint']}, 03:44:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:44:24 INFO - 'tests': ['a11yr', 03:44:24 INFO - 'ts_paint', 03:44:24 INFO - 'tpaint', 03:44:24 INFO - 'sessionrestore', 03:44:24 INFO - 'sessionrestore_no_auto_restore', 03:44:24 INFO - 'tabpaint']}, 03:44:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:44:24 INFO - 'tests': ['a11yr', 03:44:24 INFO - 'ts_paint', 03:44:24 INFO - 'tpaint', 03:44:24 INFO - 'sessionrestore', 03:44:24 INFO - 'sessionrestore_no_auto_restore', 03:44:24 INFO - 'tabpaint']}, 03:44:24 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 03:44:24 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 03:44:24 INFO - 'bloom_basic_ref']}, 03:44:24 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 03:44:24 INFO - 'talos_options': ['--mitmproxy', 03:44:24 INFO - 'mitmproxy-recording-1.mp', 03:44:24 INFO - '--firstNonBlankPaint'], 03:44:24 INFO - 'tests': ['Quantum_1']}, 03:44:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:44:24 INFO - 'tests': ['tsvgx', 03:44:24 INFO - 'tsvgr_opacity', 03:44:24 INFO - 'tart', 03:44:24 INFO - 'tscrollx', 03:44:24 INFO - 'cart', 03:44:24 INFO - 'tsvg_static']}, 03:44:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:44:24 INFO - 'tsvgr_opacity', 03:44:24 INFO - 'tart', 03:44:24 INFO - 'tscrollx', 03:44:24 INFO - 'cart', 03:44:24 INFO - 'tsvg_static']}, 03:44:24 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 03:44:24 INFO - 'talos_options': ['--disable-e10s'], 03:44:24 INFO - 'tests': ['tp5o']}, 03:44:24 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:44:24 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 03:44:24 INFO - 'talos_options': ['--disable-e10s', 03:44:24 INFO - '--xperf_path', 03:44:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:44:24 INFO - 'tests': ['tp5n']}, 03:44:24 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:44:24 INFO - 'talos_options': ['--xperf_path', 03:44:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:44:24 INFO - 'tests': ['tp5n']}}, 03:44:24 INFO - 'talos.zip': {'path': '', 03:44:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:44:24 INFO - Running post-action listener: _resource_record_post_action 03:44:24 INFO - [mozharness: 2017-05-31 10:44:24.224423Z] Finished populate-webroot step (success) 03:44:24 INFO - [mozharness: 2017-05-31 10:44:24.224559Z] Running create-virtualenv step. 03:44:24 INFO - Running pre-action listener: _resource_record_pre_action 03:44:24 INFO - Running main action method: create_virtualenv 03:44:24 INFO - Creating virtualenv /builds/slave/test/build/venv 03:44:24 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 03:44:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:44:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:44:24 INFO - Using real prefix '/tools/python27' 03:44:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:44:25 INFO - Installing distribute.............................................................................................................................................................................................done. 03:44:27 INFO - Installing pip.................done. 03:44:28 INFO - Return code: 0 03:44:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:44:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:44:28 INFO - Reading from file tmpfile_stdout 03:44:28 INFO - Output received: 03:44:28 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) 03:44:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:44:28 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')]} 03:44:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:44:28 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')]} 03:44:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:44:28 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 0x108590c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108594768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8648d77e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108519c60>, '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 0x1082aecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082d7be8>, '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.ez7XG78zRw/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.Yw2BtCHPrx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/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 03:44:28 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 03:44:28 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 03:44:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ez7XG78zRw/Render', 03:44:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:44:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:44:28 INFO - 'HOME': '/Users/cltbld', 03:44:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:44:28 INFO - 'LOGNAME': 'cltbld', 03:44:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:44:28 INFO - 'MOZ_NO_REMOTE': '1', 03:44:28 INFO - 'NO_EM_RESTART': '1', 03:44:28 INFO - 'PAGER': '/bin/cat', 03:44:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:44:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:44:28 INFO - 'PWD': '/builds/slave/test', 03:44:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:44:28 INFO - 'SHELL': '/bin/bash', 03:44:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Yw2BtCHPrx/Listeners', 03:44:28 INFO - 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', 03:44:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:44:28 INFO - 'USER': 'cltbld', 03:44:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:44:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:44:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:44:28 INFO - 'XPC_FLAGS': '0x0', 03:44:28 INFO - 'XPC_SERVICE_NAME': '0', 03:44:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:44:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:44:28 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 03:44:28 INFO - Cleaning up... 03:44:28 INFO - Return code: 0 03:44:28 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:44:28 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')]} 03:44:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:44:28 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')]} 03:44:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:44:28 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 0x108590c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108594768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8648d77e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108519c60>, '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 0x1082aecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082d7be8>, '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.ez7XG78zRw/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.Yw2BtCHPrx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/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 03:44:28 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 03:44:28 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 03:44:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ez7XG78zRw/Render', 03:44:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:44:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:44:28 INFO - 'HOME': '/Users/cltbld', 03:44:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:44:28 INFO - 'LOGNAME': 'cltbld', 03:44:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:44:28 INFO - 'MOZ_NO_REMOTE': '1', 03:44:28 INFO - 'NO_EM_RESTART': '1', 03:44:28 INFO - 'PAGER': '/bin/cat', 03:44:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:44:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:44:28 INFO - 'PWD': '/builds/slave/test', 03:44:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:44:28 INFO - 'SHELL': '/bin/bash', 03:44:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Yw2BtCHPrx/Listeners', 03:44:28 INFO - 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', 03:44:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:44:28 INFO - 'USER': 'cltbld', 03:44:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:44:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:44:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:44:28 INFO - 'XPC_FLAGS': '0x0', 03:44:28 INFO - 'XPC_SERVICE_NAME': '0', 03:44:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:44:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:44:28 INFO - Downloading/unpacking psutil>=3.1.1 03:44:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:44:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:44:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:44:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:44:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:44:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:44:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:44:30 INFO - Installing collected packages: psutil 03:44:30 INFO - Running setup.py install for psutil 03:44:31 INFO - building 'psutil._psutil_osx' extension 03:44:31 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 03:44:31 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 03:44:31 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 03:44:31 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 03:44:31 INFO - building 'psutil._psutil_posix' extension 03:44:31 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 03:44:31 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:44:31 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:44:31 INFO - ^ 03:44:31 INFO - 1 warning generated. 03:44:31 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 03:44:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:44:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:44:31 INFO - Successfully installed psutil 03:44:31 INFO - Cleaning up... 03:44:31 INFO - Return code: 0 03:44:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:44:31 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')]} 03:44:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:44:31 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')]} 03:44:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:44:31 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 0x108590c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108594768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8648d77e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108519c60>, '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 0x1082aecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082d7be8>, '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.ez7XG78zRw/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.Yw2BtCHPrx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/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 03:44:31 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 03:44:31 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 03:44:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ez7XG78zRw/Render', 03:44:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:44:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:44:31 INFO - 'HOME': '/Users/cltbld', 03:44:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:44:31 INFO - 'LOGNAME': 'cltbld', 03:44:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:44:31 INFO - 'MOZ_NO_REMOTE': '1', 03:44:31 INFO - 'NO_EM_RESTART': '1', 03:44:31 INFO - 'PAGER': '/bin/cat', 03:44:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:44:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:44:31 INFO - 'PWD': '/builds/slave/test', 03:44:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:44:31 INFO - 'SHELL': '/bin/bash', 03:44:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Yw2BtCHPrx/Listeners', 03:44:31 INFO - 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', 03:44:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:44:31 INFO - 'USER': 'cltbld', 03:44:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:44:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:44:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:44:31 INFO - 'XPC_FLAGS': '0x0', 03:44:31 INFO - 'XPC_SERVICE_NAME': '0', 03:44:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:44:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:44:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:44:32 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 03:44:32 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 03:44:32 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 03:44:32 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 03:44:33 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:44:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:44:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:44:33 INFO - Installing collected packages: mozsystemmonitor 03:44:33 INFO - Running setup.py install for mozsystemmonitor 03:44:33 INFO - Successfully installed mozsystemmonitor 03:44:33 INFO - Cleaning up... 03:44:33 INFO - Return code: 0 03:44:33 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:44:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:44:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:44:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:44:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:44:33 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 0x108590c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108594768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8648d77e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108519c60>, '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 0x1082aecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082d7be8>, '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.ez7XG78zRw/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.Yw2BtCHPrx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/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 03:44:33 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 03:44:33 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 03:44:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ez7XG78zRw/Render', 03:44:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:44:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:44:33 INFO - 'HOME': '/Users/cltbld', 03:44:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:44:33 INFO - 'LOGNAME': 'cltbld', 03:44:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:44:33 INFO - 'MOZ_NO_REMOTE': '1', 03:44:33 INFO - 'NO_EM_RESTART': '1', 03:44:33 INFO - 'PAGER': '/bin/cat', 03:44:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:44:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:44:33 INFO - 'PWD': '/builds/slave/test', 03:44:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:44:33 INFO - 'SHELL': '/bin/bash', 03:44:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Yw2BtCHPrx/Listeners', 03:44:33 INFO - 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', 03:44:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:44:33 INFO - 'USER': 'cltbld', 03:44:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:44:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:44:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:44:33 INFO - 'XPC_FLAGS': '0x0', 03:44:33 INFO - 'XPC_SERVICE_NAME': '0', 03:44:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:44:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:44:34 INFO - Downloading/unpacking jsonschema==2.5.1 03:44:34 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 03:44:34 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 03:44:34 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 03:44:34 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 03:44:35 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:44:35 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:44:35 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 03:44:35 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 03:44:35 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 03:44:35 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 03:44:35 INFO - Downloading functools32-3.2.3-2.tar.gz 03:44:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:44:35 INFO - warning: no files found matching '*.txt' 03:44:35 INFO - no previously-included directories found matching 'build' 03:44:35 INFO - no previously-included directories found matching 'dist' 03:44:35 INFO - no previously-included directories found matching '.git*' 03:44:35 INFO - Installing collected packages: jsonschema, functools32 03:44:35 INFO - Running setup.py install for functools32 03:44:36 INFO - warning: no files found matching '*.txt' 03:44:36 INFO - no previously-included directories found matching 'build' 03:44:36 INFO - no previously-included directories found matching 'dist' 03:44:36 INFO - no previously-included directories found matching '.git*' 03:44:36 INFO - Successfully installed jsonschema functools32 03:44:36 INFO - Cleaning up... 03:44:36 INFO - Return code: 0 03:44:36 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:44:36 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')]} 03:44:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:44:36 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')]} 03:44:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:44:36 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 0x108590c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108594768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8648d77e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108519c60>, '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 0x1082aecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082d7be8>, '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.ez7XG78zRw/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.Yw2BtCHPrx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/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 03:44:36 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 03:44:36 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 03:44:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ez7XG78zRw/Render', 03:44:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:44:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:44:36 INFO - 'HOME': '/Users/cltbld', 03:44:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:44:36 INFO - 'LOGNAME': 'cltbld', 03:44:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:44:36 INFO - 'MOZ_NO_REMOTE': '1', 03:44:36 INFO - 'NO_EM_RESTART': '1', 03:44:36 INFO - 'PAGER': '/bin/cat', 03:44:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:44:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:44:36 INFO - 'PWD': '/builds/slave/test', 03:44:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:44:36 INFO - 'SHELL': '/bin/bash', 03:44:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Yw2BtCHPrx/Listeners', 03:44:36 INFO - 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', 03:44:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:44:36 INFO - 'USER': 'cltbld', 03:44:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:44:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:44:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:44:36 INFO - 'XPC_FLAGS': '0x0', 03:44:36 INFO - 'XPC_SERVICE_NAME': '0', 03:44:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:44:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:44:36 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:44:36 INFO - Cleaning up... 03:44:36 INFO - Return code: 0 03:44:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:44:36 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')]} 03:44:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:44:36 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')]} 03:44:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:44:36 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 0x108590c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108594768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8648d77e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108519c60>, '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 0x1082aecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082d7be8>, '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.ez7XG78zRw/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.Yw2BtCHPrx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/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 03:44:36 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 03:44:36 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 03:44:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ez7XG78zRw/Render', 03:44:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:44:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:44:36 INFO - 'HOME': '/Users/cltbld', 03:44:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:44:36 INFO - 'LOGNAME': 'cltbld', 03:44:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:44:36 INFO - 'MOZ_NO_REMOTE': '1', 03:44:36 INFO - 'NO_EM_RESTART': '1', 03:44:36 INFO - 'PAGER': '/bin/cat', 03:44:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:44:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:44:36 INFO - 'PWD': '/builds/slave/test', 03:44:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:44:36 INFO - 'SHELL': '/bin/bash', 03:44:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Yw2BtCHPrx/Listeners', 03:44:36 INFO - 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', 03:44:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:44:36 INFO - 'USER': 'cltbld', 03:44:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:44:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:44:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:44:36 INFO - 'XPC_FLAGS': '0x0', 03:44:36 INFO - 'XPC_SERVICE_NAME': '0', 03:44:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:44:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:44:36 INFO - Downloading/unpacking blobuploader==1.2.4 03:44:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:44:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:44:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:44:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:44:37 INFO - Downloading blobuploader-1.2.4.tar.gz 03:44:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:44:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:44:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:44:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:44:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:44:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:44:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:44:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:44:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:44:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:44:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:44:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:44:38 INFO - Downloading docopt-0.6.1.tar.gz 03:44:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:44:38 INFO - Installing collected packages: blobuploader, requests, docopt 03:44:38 INFO - Running setup.py install for blobuploader 03:44:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:44:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:44:39 INFO - Running setup.py install for requests 03:44:39 INFO - Running setup.py install for docopt 03:44:39 INFO - Successfully installed blobuploader requests docopt 03:44:39 INFO - Cleaning up... 03:44:39 INFO - Return code: 0 03:44:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:44:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:44:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:44:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:44:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:44:39 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 0x108590c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108594768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8648d77e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108519c60>, '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 0x1082aecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082d7be8>, '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.ez7XG78zRw/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.Yw2BtCHPrx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/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 03:44:39 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 03:44:39 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 03:44:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ez7XG78zRw/Render', 03:44:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:44:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:44:39 INFO - 'HOME': '/Users/cltbld', 03:44:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:44:39 INFO - 'LOGNAME': 'cltbld', 03:44:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:44:39 INFO - 'MOZ_NO_REMOTE': '1', 03:44:39 INFO - 'NO_EM_RESTART': '1', 03:44:39 INFO - 'PAGER': '/bin/cat', 03:44:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:44:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:44:39 INFO - 'PWD': '/builds/slave/test', 03:44:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:44:39 INFO - 'SHELL': '/bin/bash', 03:44:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Yw2BtCHPrx/Listeners', 03:44:39 INFO - 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', 03:44:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:44:39 INFO - 'USER': 'cltbld', 03:44:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:44:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:44:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:44:39 INFO - 'XPC_FLAGS': '0x0', 03:44:39 INFO - 'XPC_SERVICE_NAME': '0', 03:44:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:44:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:44:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:44:40 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-BNmxda-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:44:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:44:40 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-p5c6Rf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:44:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:44:40 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-nj924v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:44:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:44:40 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-LVCZE8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:44:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:44:40 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-ILg2Fi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:44:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:44:40 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-ZrYwuX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:44:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:44:40 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-7K7x14-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:44:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:44:40 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-AfItr7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:44:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:44:40 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-o1090t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:44:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:44:41 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-l3p1In-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:44:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:44:41 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-tJc51z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:44:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:44:41 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-EvYGmQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:44:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:44:41 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-7vwJaQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:44:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:44:41 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-THh9EL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:44:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:44:41 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-uJlNVz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:44:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:44:41 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-9x5GWj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:44:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:44:41 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-K8bqRa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:44:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:44:41 INFO - Running setup.py install for manifestparser 03:44:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:44:42 INFO - Running setup.py install for mozcrash 03:44:42 INFO - Running setup.py install for mozdebug 03:44:42 INFO - Running setup.py install for mozdevice 03:44:42 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:44:42 INFO - Running setup.py install for mozfile 03:44:42 INFO - Running setup.py install for mozhttpd 03:44:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:44:42 INFO - Running setup.py install for mozinfo 03:44:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:44:42 INFO - Running setup.py install for mozInstall 03:44:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:44:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:44:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:44:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:44:43 INFO - Running setup.py install for mozleak 03:44:43 INFO - Running setup.py install for mozlog 03:44:43 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:44:43 INFO - Running setup.py install for moznetwork 03:44:43 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:44:43 INFO - Running setup.py install for mozprocess 03:44:43 INFO - Running setup.py install for mozprofile 03:44:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:44:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:44:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:44:43 INFO - Running setup.py install for mozrunner 03:44:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:44:44 INFO - Running setup.py install for mozscreenshot 03:44:44 INFO - Running setup.py install for moztest 03:44:44 INFO - Running setup.py install for mozversion 03:44:44 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:44:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:44:44 INFO - Cleaning up... 03:44:44 INFO - Return code: 0 03:44:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:44:44 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')]} 03:44:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:44:44 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')]} 03:44:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:44:44 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 0x108590c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108594768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8648d77e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108519c60>, '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 0x1082aecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082d7be8>, '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.ez7XG78zRw/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.Yw2BtCHPrx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/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 03:44:44 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 03:44:44 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 03:44:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ez7XG78zRw/Render', 03:44:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:44:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:44:44 INFO - 'HOME': '/Users/cltbld', 03:44:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:44:44 INFO - 'LOGNAME': 'cltbld', 03:44:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:44:44 INFO - 'MOZ_NO_REMOTE': '1', 03:44:44 INFO - 'NO_EM_RESTART': '1', 03:44:44 INFO - 'PAGER': '/bin/cat', 03:44:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:44:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:44:44 INFO - 'PWD': '/builds/slave/test', 03:44:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:44:44 INFO - 'SHELL': '/bin/bash', 03:44:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Yw2BtCHPrx/Listeners', 03:44:44 INFO - 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', 03:44:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:44:44 INFO - 'USER': 'cltbld', 03:44:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:44:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:44:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:44:44 INFO - 'XPC_FLAGS': '0x0', 03:44:44 INFO - 'XPC_SERVICE_NAME': '0', 03:44:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:44:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:44:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:44:44 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-LcoGEK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:44:44 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)) 03:44:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:44:44 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-jLqjgk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:44:44 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)) 03:44:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:44:44 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-lUVTBX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:44:45 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)) 03:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:44:45 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-RrX4EB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:44:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:44:45 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-wU6XvZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:44:45 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)) 03:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:44:45 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-1fYqoY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:44:45 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)) 03:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:44:45 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-3u9JKe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:44:45 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)) 03:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:44:45 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-ZCOUp1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:44:45 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)) 03:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:44:45 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-xXa0ya-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:44:45 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)) 03:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:44:45 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-VNmhEZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:44:45 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)) 03:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:44:45 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-TXanGV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:44:45 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)) 03:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:44:45 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-2pBm_f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:44:45 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)) 03:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:44:46 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-rgSSiX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:44:46 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)) 03:44:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:44:46 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-7DVuWk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:44:46 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)) 03:44:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:44:46 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-Irw745-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:44:46 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)) 03:44:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:44:46 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-qaoYo6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:44:46 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)) 03:44:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:44:46 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-AZ9Mk3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:44:46 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)) 03:44:46 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)) 03:44:46 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)) 03:44:46 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)) 03:44:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:44:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:44:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:44:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:44:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:44:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:44:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:44:47 INFO - Downloading blessings-1.6.tar.gz 03:44:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:44:48 INFO - Installing collected packages: blessings 03:44:48 INFO - Running setup.py install for blessings 03:44:48 INFO - Successfully installed blessings 03:44:48 INFO - Cleaning up... 03:44:48 INFO - Return code: 0 03:44:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:44:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:44:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:44:48 INFO - Reading from file tmpfile_stdout 03:44:48 INFO - Current package versions: 03:44:48 INFO - blessings == 1.6 03:44:48 INFO - blobuploader == 1.2.4 03:44:48 INFO - docopt == 0.6.1 03:44:48 INFO - functools32 == 3.2.3-2 03:44:48 INFO - jsonschema == 2.5.1 03:44:48 INFO - manifestparser == 1.1 03:44:48 INFO - mozInstall == 1.12 03:44:48 INFO - mozcrash == 1.0 03:44:48 INFO - mozdebug == 0.1 03:44:48 INFO - mozdevice == 0.51 03:44:48 INFO - mozfile == 1.2 03:44:48 INFO - mozhttpd == 0.7 03:44:48 INFO - mozinfo == 0.9 03:44:48 INFO - mozleak == 0.1 03:44:48 INFO - mozlog == 3.4 03:44:48 INFO - moznetwork == 0.27 03:44:48 INFO - mozprocess == 0.25 03:44:48 INFO - mozprofile == 0.28 03:44:48 INFO - mozrunner == 6.13 03:44:48 INFO - mozscreenshot == 0.1 03:44:48 INFO - mozsystemmonitor == 0.3 03:44:48 INFO - moztest == 0.8 03:44:48 INFO - mozversion == 1.4 03:44:48 INFO - psutil == 3.1.1 03:44:48 INFO - requests == 1.2.3 03:44:48 INFO - wsgiref == 0.1.2 03:44:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:44:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:44:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:44:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:44:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:44:48 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 0x108590c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108594768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8648d77e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108519c60>, '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 0x1082aecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082d7be8>, '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.ez7XG78zRw/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.Yw2BtCHPrx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/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 03:44:48 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 03:44:48 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 03:44:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ez7XG78zRw/Render', 03:44:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:44:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:44:48 INFO - 'HOME': '/Users/cltbld', 03:44:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:44:48 INFO - 'LOGNAME': 'cltbld', 03:44:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:44:48 INFO - 'MOZ_NO_REMOTE': '1', 03:44:48 INFO - 'NO_EM_RESTART': '1', 03:44:48 INFO - 'PAGER': '/bin/cat', 03:44:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:44:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:44:48 INFO - 'PWD': '/builds/slave/test', 03:44:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:44:48 INFO - 'SHELL': '/bin/bash', 03:44:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Yw2BtCHPrx/Listeners', 03:44:48 INFO - 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', 03:44:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:44:48 INFO - 'USER': 'cltbld', 03:44:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:44:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:44:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:44:48 INFO - 'XPC_FLAGS': '0x0', 03:44:48 INFO - 'XPC_SERVICE_NAME': '0', 03:44:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:44:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:44:48 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)) 03:44:48 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)) 03:44:48 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)) 03:44:48 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)) 03:44:48 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)) 03:44:48 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)) 03:44:48 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)) 03:44:48 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)) 03:44:48 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)) 03:44:48 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:44:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:44:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:44:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:44:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:44:50 INFO - Downloading marionette_driver-1.4.0.tar.gz 03:44:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 03:44:50 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)) 03:44:50 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)) 03:44:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:44:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:44:50 INFO - Installing collected packages: marionette-driver 03:44:50 INFO - Running setup.py install for marionette-driver 03:44:50 INFO - Successfully installed marionette-driver 03:44:50 INFO - Cleaning up... 03:44:50 INFO - Return code: 0 03:44:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:44:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:44:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:44:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:44:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:44:50 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 0x108590c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108594768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8648d77e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108519c60>, '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 0x1082aecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082d7be8>, '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.ez7XG78zRw/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.Yw2BtCHPrx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/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 03:44:50 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 03:44:50 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 03:44:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ez7XG78zRw/Render', 03:44:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:44:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:44:50 INFO - 'HOME': '/Users/cltbld', 03:44:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:44:50 INFO - 'LOGNAME': 'cltbld', 03:44:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:44:50 INFO - 'MOZ_NO_REMOTE': '1', 03:44:50 INFO - 'NO_EM_RESTART': '1', 03:44:50 INFO - 'PAGER': '/bin/cat', 03:44:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:44:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:44:50 INFO - 'PWD': '/builds/slave/test', 03:44:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:44:50 INFO - 'SHELL': '/bin/bash', 03:44:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Yw2BtCHPrx/Listeners', 03:44:50 INFO - 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', 03:44:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:44:50 INFO - 'USER': 'cltbld', 03:44:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:44:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:44:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:44:50 INFO - 'XPC_FLAGS': '0x0', 03:44:50 INFO - 'XPC_SERVICE_NAME': '0', 03:44:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:44:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:44:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:44:50 INFO - Cleaning up... 03:44:50 INFO - Return code: 0 03:44:50 INFO - Running post-action listener: _resource_record_post_action 03:44:50 INFO - Running post-action listener: _start_resource_monitoring 03:44:50 INFO - Starting resource monitoring. 03:44:50 INFO - [mozharness: 2017-05-31 10:44:50.852940Z] Finished create-virtualenv step (success) 03:44:50 INFO - [mozharness: 2017-05-31 10:44:50.854030Z] Running install step. 03:44:50 INFO - Running pre-action listener: _resource_record_pre_action 03:44:50 INFO - Running main action method: install 03:44:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:44:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:44:51 INFO - Reading from file tmpfile_stdout 03:44:51 INFO - Detecting whether we're running mozinstall >=1.0... 03:44:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:44:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:44:51 INFO - Reading from file tmpfile_stdout 03:44:51 INFO - Output received: 03:44:51 INFO - Usage: mozinstall [options] installer 03:44:51 INFO - Options: 03:44:51 INFO - -h, --help show this help message and exit 03:44:51 INFO - -d DEST, --destination=DEST 03:44:51 INFO - Directory to install application into. [default: 03:44:51 INFO - "/builds/slave/test"] 03:44:51 INFO - --app=APP Application being installed. [default: firefox] 03:44:51 INFO - mkdir: /builds/slave/test/build/application 03:44:51 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'] 03:44:51 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 03:45:13 INFO - Reading from file tmpfile_stdout 03:45:13 INFO - Output received: 03:45:13 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 03:45:13 INFO - Running post-action listener: _resource_record_post_action 03:45:13 INFO - [mozharness: 2017-05-31 10:45:13.553725Z] Finished install step (success) 03:45:13 INFO - [mozharness: 2017-05-31 10:45:13.553864Z] Running setup-mitmproxy step. 03:45:13 INFO - Running pre-action listener: _resource_record_pre_action 03:45:13 INFO - Running main action method: setup_mitmproxy 03:45:13 INFO - Skipping: mitmproxy is not required 03:45:13 INFO - Running post-action listener: _resource_record_post_action 03:45:13 INFO - [mozharness: 2017-05-31 10:45:13.554562Z] Finished setup-mitmproxy step (success) 03:45:13 INFO - [mozharness: 2017-05-31 10:45:13.554678Z] Running run-tests step. 03:45:13 INFO - Running pre-action listener: _resource_record_pre_action 03:45:13 INFO - Running main action method: run_tests 03:45:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:45:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:45:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:45:13 INFO - 2017-05-31 03:45:13.569 screenresolution[2412:32843] starting screenresolution argv=screenresolution get 03:45:13 INFO - 2017-05-31 03:45:13.571 screenresolution[2412:32843] Display 0: 1600x1200x32@60 03:45:13 INFO - 2017-05-31 03:45:13.577 screenresolution[2413:32845] starting screenresolution argv=screenresolution list 03:45:13 INFO - Available Modes on Display 0 03:45:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:45:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:45:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:45:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:45:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:45:13 INFO - Intel Iris: 03:45:13 INFO - Chipset Model: Intel Iris 03:45:13 INFO - Type: GPU 03:45:13 INFO - Bus: Built-In 03:45:13 INFO - VRAM (Dynamic, Max): 1536 MB 03:45:13 INFO - Vendor: Intel (0x8086) 03:45:13 INFO - Device ID: 0x0a2e 03:45:13 INFO - Revision ID: 0x0009 03:45:13 INFO - Displays: 03:45:13 INFO - Display: 03:45:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:45:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:45:13 INFO - Display Serial Number: 1600x1200 60 03:45:13 INFO - Main Display: Yes 03:45:13 INFO - Mirror: Off 03:45:13 INFO - Online: Yes 03:45:13 INFO - Rotation: Supported 03:45:13 INFO - Return code: 0 03:45:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:45:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:45:13 INFO - Python 2.7.3 03:45:13 INFO - Return code: 0 03:45:13 INFO - grabbing minidump binary from tooltool 03:45: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')]} 03:45:13 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 0x108519c60>, '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 0x1082aecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082d7be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:45:13 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 03:45:13 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 03:45:13 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 03:45:13 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:45:13 INFO - Return code: 0 03:45:13 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:45:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:45:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:45:13 INFO - ENV: RUST_BACKTRACE is now 1 03:45:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:45:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:45:13 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/LBkgda2FSVqBrn9Ey14C8g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0199', '--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 03:45:13 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/LBkgda2FSVqBrn9Ey14C8g/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0199 --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 03:45:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ez7XG78zRw/Render', 03:45:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:45:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:45:13 INFO - 'HOME': '/Users/cltbld', 03:45:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:45:13 INFO - 'LOGNAME': 'cltbld', 03:45:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:45:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:45:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:45:13 INFO - 'MOZ_NO_REMOTE': '1', 03:45:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:45:13 INFO - 'NO_EM_RESTART': '1', 03:45:13 INFO - 'PAGER': '/bin/cat', 03:45:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:45:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:45:13 INFO - 'PWD': '/builds/slave/test', 03:45:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:45:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:45:13 INFO - 'RUST_BACKTRACE': '1', 03:45:13 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:45:13 INFO - 'SHELL': '/bin/bash', 03:45:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Yw2BtCHPrx/Listeners', 03:45:13 INFO - 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', 03:45:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:45:13 INFO - 'USER': 'cltbld', 03:45:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:45:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:45:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:45:13 INFO - 'XPC_FLAGS': '0x0', 03:45:13 INFO - 'XPC_SERVICE_NAME': '0', 03:45:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:45:13 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/LBkgda2FSVqBrn9Ey14C8g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0199', '--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 03:45:14 INFO - mozversion application_buildid: 20170531023144 03:45:14 INFO - mozversion application_changeset: 7b8937970f9ca85db88cb2496f2112175fd847c8 03:45:14 INFO - mozversion application_display_name: Nightly 03:45:14 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:45:14 INFO - mozversion application_name: Firefox 03:45:14 INFO - mozversion application_remotingname: firefox 03:45:14 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:45:14 INFO - mozversion application_vendor: Mozilla 03:45:14 INFO - mozversion application_version: 55.0a1 03:45:14 INFO - mozversion platform_buildid: 20170531023144 03:45:14 INFO - mozversion platform_changeset: 7b8937970f9ca85db88cb2496f2112175fd847c8 03:45:14 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:45:14 INFO - mozversion platform_version: 55.0a1 03:45:14 INFO - using testdate: 1496227514 03:45:14 INFO - actual date: 1496227514 03:45:14 INFO - starting webserver on 'localhost:56916' 03:45:14 INFO - SUITE-START | Running 2 tests 03:45:14 INFO - TEST-START | dromaeo_css 03:45:14 INFO - operating with platform_type : mac_ 03:45:14 INFO - Initialising browser for dromaeo_css test... 03:45:14 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:56916/getInfo.html -foreground -profile /var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/tmpKqhyWT/profile 03:45:14 INFO - TEST-INFO | started process 2423 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:56916/getInfo.html -foreground) 03:45:48 INFO - TEST-INFO | 2423: killed by SIGTERM 03:45:48 INFO - Browser initialized. 03:45:48 INFO - Running cycle 1/1 for dromaeo_css test... 03:45:48 INFO - TEST-INFO | started process 2430 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/tmpKqhyWT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:45:50 INFO - PID 2430 | 2017-05-31 03:45:50.076 plugin-container[2431:33467] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x973f, name = 'com.apple.tsm.portname' 03:45:50 INFO - PID 2430 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:45:50 INFO - PID 2430 | 2017-05-31 03:45:50.076 plugin-container[2431:33467] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4523, name = 'com.apple.CFPasteboardClient' 03:45:50 INFO - PID 2430 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:45:50 INFO - PID 2430 | 2017-05-31 03:45:50.076 plugin-container[2431:33467] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:45:50 INFO - PID 2430 | 2017-05-31 03:45:50.783 plugin-container[2432:33515] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a4f, name = 'com.apple.tsm.portname' 03:45:50 INFO - PID 2430 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:45:50 INFO - PID 2430 | 2017-05-31 03:45:50.783 plugin-container[2432:33515] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x461b, name = 'com.apple.CFPasteboardClient' 03:45:50 INFO - PID 2430 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:45:50 INFO - PID 2430 | 2017-05-31 03:45:50.783 plugin-container[2432:33515] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:46:00 INFO - PID 2430 | RSS: Main: 260665344 03:46:00 INFO - PID 2430 | 03:49:02 INFO - PID 2430 | Cycle 1(1): loaded http://localhost:56916/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56916/tests/dromaeo/cssquery-ext.html) 03:49:03 INFO - PID 2430 | RSS: Main: 270671872 03:49:03 INFO - PID 2430 | 03:52:05 INFO - PID 2430 | Cycle 1(1): loaded http://localhost:56916/tests/dromaeo/cssquery-ext.html (next: http://localhost:56916/tests/dromaeo/cssquery-jquery.html) 03:52:06 INFO - PID 2430 | RSS: Main: 283095040 03:52:06 INFO - PID 2430 | 03:55:08 INFO - PID 2430 | Cycle 1(1): loaded http://localhost:56916/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56916/tests/dromaeo/cssquery-mootools.html) 03:55:08 INFO - PID 2430 | RSS: Main: 282554368 03:55:08 INFO - PID 2430 | 03:58:10 INFO - PID 2430 | Cycle 1(1): loaded http://localhost:56916/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56916/tests/dromaeo/cssquery-prototype.html) 03:58:11 INFO - PID 2430 | RSS: Main: 286306304 03:58:11 INFO - PID 2430 | 04:01:14 INFO - PID 2430 | Cycle 1(1): loaded http://localhost:56916/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56916/tests/dromaeo/cssquery-yui.html) 04:01:14 INFO - PID 2430 | RSS: Main: 288755712 04:01:14 INFO - PID 2430 | 04:04:16 INFO - PID 2430 | Cycle 1(1): loaded http://localhost:56916/tests/dromaeo/cssquery-yui.html (next: http://localhost:56916/tests/dromaeo/cssquery-dojo.html) 04:04:16 INFO - PID 2430 | RSS: Main: 288833536 04:04:16 INFO - PID 2430 | 04:04:16 INFO - PID 2430 | __start_tp_report 04:04:16 INFO - PID 2430 | _x_x_mozilla_page_load 04:04:16 INFO - PID 2430 | _x_x_mozilla_page_load_details 04:04:16 INFO - PID 2430 | |i|pagename|runs| 04:04:16 INFO - PID 2430 | |0;dojo.html;3132.263917979275;3556.875509357173;3570.7857528548275;3586.4082426399655;3599.6580324869146;6124.625058545677;6130.969345153274;6183.041628547577;6197.59715618485;6223.751049958008;6112.929053818841;6156.846078848031;6159.846003849906;6242.1261023456755;6251.6874156292115;5253.921191182136;5390.8921821563545;5425.097572798553;5429.619926605139;5468.425815289397;5495.120780675093;5544.085225937715;5553.416891226424;5556.305461817273;5568.86077848053;5341.519263266286;5378.139497680352;5437.700926449043;5443.537299329542;5525.778968841241;5471.042567550702;5485.369182544018;5498.835034948958;5510.9448905511235;5524.06090964533;4662.557057079572;5402.675839449606;5460.989716902386;5487.396386397493;5508.118701007859;6105.634327431686;6115.362036198632;6147.600405973598;6196.907046394263;6250.843728906768;5544.251526044013;6577.835554111138;6672.666366681647;6675;6681.432078273353;5498.862528436781;6740.561863478858;6750.426213771835;6781.627010514419;6790.151231096113;6195.287541932736;6278.874422511615;6288.276848162474;6298.7480500780375;6308.116863639094;5678.943210567842;6746.966265168643;6833.111695479556;6868.626274745071;6938.306169383122;5226.686398816008;6383.489320854321;6494.967525162344;6503.8699226015215;6597.274299827015;6699.095622090956;6743.831404214934;6787.490938179656;6802.761903333359;6805.455563554904;6698.83252918681;6702.530822842355;6713.5636183648885;6722.058911752359;6750.223724271772;6454.064160696673;6553.279139294674;6590;6701.932980670228;6733.124693789776;5325.307709997753;5365.409804921455;5371.785128594891;5376.1935709643085;5427.728613569385;4786.138495070921;4813.109574728686;4816.566509014206;4831.758412079382;4839.516048395132;6219.253689557282;6220.937790622036;6267.091271765568;6350.872982540416;6362.427381535592;6759.966200168968;6821.283765204681;6859.382655561023;6870.62211578358;6883.414909732729;6201.782937597162;6317.1156038154695;6317.399847014526;6320.525960552977;6350.587211831216;5814.273215848019;6004.309504407049;6012.849678757979;6019.879602408015;6033.849153771103;6843.110395648534;6948.444124470131;6951.5481493703755;6968.895466568107;7011.404030657452;5684.63049901755;6725.327467253235;6801.455883529206;6827.146606674166;6844.486663500357;6648.700808463727;6941.409980151541;6984.266652001466;7007.789766307019;7018.613976231356;5245.580353571781;6439.7424103035355;6609.603423794588;6674.365935236145;6738.056672066013;6848.315168483112;6870.519063665495;6871.381575658214;6891.793246202421;6908.965455172692;6472.967635161794;6559.4752419807255;6612.107365505595;6666.766663166766;6714.160729908762;5410.864728381822;6581;6657.034729964032;6682.732690692513;6695.899561506484;6374.235091789015;6486.05952136947;6512.023196520559;6529.771457998947;6534.673266336759;6406.167198264196;6582.868342633024;6601.141851559267;6607.2071351438135;6617;6349.904751428825;6369.649669268235;6434.8391290218115;6486.05952136928;6494.805155845333 04:04:16 INFO - PID 2430 | |1;ext.html;773.5049568276304;818.0388044048244;844.0715213265408;1240.441801189465;1307.0785096506963;29912.551311730287;37697.24605507892;39197.41203881944;39199.412008819854;39279.80360098199;39145.41281880767;39474.8026259869;39485.40771888417;39485.40771888424;39523.20953580927;7862.567558784264;8046.356291496695;8207.876881846778;8395.790105247386;8416.705415310465;8318.41771076025;8390.790230244262;8398.370122240825;8409.411341206118;8413.032501262373;8360.665573377057;8382.287505562035;8433.746987590383;8437.156284371544;8441.155884411572;8363.790905227357;8392.622331995075;8404.8319033619;8407.915920840775;8409.032961209477;8259.545724985124;8289.668413263462;8295.585220739;8320.958395208047;8346.290565301957;36809.631903680885;37143.4428483573;37185.44221836675;37201.51193952239;37246;3514.1741690702665;3515.8769443069627;3562.2341196642847;3565.7682250653884;3573.338932297507;30175;30230.395392092254;30234.395312093635;30250;30294.394112117854;3052.9847350763325;3095.860686269123;3104.8758049677986;3111.642161151451;3112.797668151563;255179;258499;275573.999999998;298387.62092842907;298813;46675.5332446678;46930;46982.060358793315;47050;47215.76392118017;48785;48904;49043;49076;49716.757081073265;14430.855691443161;14568.271586420637;14588.270586470846;14635.707285854436;14667.119972801665;11679.474423650956;11698;11741.412929353499;11751.294922304689;11752.88247117535;13775.517856874962;13811.930940345233;13814.792778108336;13829.654258643614;13908.721825563433;6168.444839964424;6184.5052395808225;6218.5647004709235;6219.440250377488;6220.502359811204;954.346272803132;954.8376775948105;973.3429934793986;991.2119864707574;997.4713401897006;22644.773552264265;23595;23824.7617523826;24008.249846809937;24227.51544969091;897.5915958238986;1050.6270274052754;1084.4794498640697;1115.9609413670482;1119.7648493816228;10625.149988000941;11341.262817916973;11966.589521986367;12179.330136842382;12254;340973.29513352277;341770;341959;342566;342766;52479.73760131175;52685.73657131766;52817;52854.67863303371;52890.206646900326;1490.5230326295482;1504.247876061969;1532.6245069957931;1539.4227164813194;1548.233624355951;19970.90014549918;23116.19093331725;23379.415514612272;23478.530429390976;23530.647040294753;36097.09757255985;36110.63889361178;36129.45805812862;36182.27635447329;36208.63791362053;274716;276239;277027.2297277082;277150.6142469275;277408;35069.47395789116;35279.76520821759;35357;35361.469577956865;35429.937101886986;42698;42737.358939616555;43034.56965430431;44725.10549789051;44769.32846007247;30085.3982920336;30597;30688.232794179432;30840.38319233558;31102.844485777427;30091.849540752155;30470.695293046785;30522;30643.560425598924;30912.69087309098;35985.74049908241;36223;36583.26833463369;36622;36964;30101.69898300989;30320.938767143045;30728.846355768077;30929.845350774;30960.53559196659;22823;23161;24030.63954040656;24034;24280.392990175387 04:04:16 INFO - PID 2430 | |2;jquery.html;405.19770853709656;405.83969332113816;422.1872894677746;422.2947677378777;447.1905850410757;22031.33905982821;22829.4292642684;22878.741669208186;22896.427589310275;22898.542029159406;20385.796142038576;20815.895920520376;23781.643275350885;24794;24813;5395.81114660985;5931.110333449996;6167.629942203482;6198.1632479615355;6201.782937597185;6090.634561926277;6090.817275481742;6130.724117414727;6143.569950103494;6154.84612884677;6090.116933044706;6099.054646529795;6103.206583144207;6120.846978825521;6135.846603834895;4735.195016847127;5773.942260577403;5791.710414479259;5819.272590926134;5827.970860145714;5211.7133557654315;5212.921806172911;5219.399769026534;5224.268602395668;5231.267622532849;28732.712672873422;28817.991370301937;29160.270993225127;29173.708262917524;29205.561916571267;1984.345166095185;2230.408941630469;2266.5353602511445;2269.807066399358;2271.693321401606;17808.643827123513;18644.533886652807;18822.62354752911;18828;18874.52813679669;2145.5279838435517;2185.191479152724;2206.3270702435752;2215.213599172303;2227.899744511501;148402.51597483995;154544;172467;173977;177497.22502775065;12693.301868397142;13008.479660813651;13059.28173950423;13062.216267024009;13222.338883055809;16609.501714948572;16639.41762038323;16845.747313790303;16847.83152168487;16880;8872.8669069964;9004.189622933967;9010.684626038057;9063.365564410427;9073.682421115229;8490.235878770816;8508.957455212685;8531.488110713377;8546.957265213634;8611.095834937241;7984.920150798418;8141.307988821017;8231.341612124554;8286.917130828735;8295.129011453831;2108.9894550527456;2182.3125715399597;2198.3404978506383;2205.2722601541454;2205.9889700551394;955.8518429643416;961.7018724195382;966.9226461883032;990.64336838738;1011.8026984738052;18108.00405977684;21067.68398474024;21918.45203869884;22216.913283462465;22284.77715222827;1014.4572653630272;1020.4795554267378;1039.5166247694708;1040.7398150462384;1048.6172547020283;10337.74155646115;10416.32293900894;10536.157107431387;11449.885501144881;11545.422728863523;533494;537003;540175;541497;544705;101837;137573;149869;151396;151611.24194379177;999.8751404669747;1018.8130827585927;1162.627959053112;1184.7452797648548;1196.4257156564897;22542.4364390885;22756.772432275466;23381;23410.829458526663;23456.882715586315;63999;65992;66145;66572;66679.33320666732;87172.56413717891;87252.56373718091;87659;88319;88385.55807220923;13096.476140954255;15273.847261527242;16503.91748041252;16578.50264492067;16636.334546618;15282.541523754306;16558.66882662364;18040.548986274924;18137.36519221821;18159.09204539794;14862.777058343918;14868.702625947204;15046.54860354191;15055.623609409853;15167.089974601558;13400.262985535888;13402.798958015443;13551.72896542044;13790.65523361924;14045.227512486912;15069.47256846005;15387.461438849587;15466.690666186389;15570.221488925281;15650.45223417175;16223;16557.006579604782;16656.583585410463;16805.663886722443;16806.243719032427;14585.343659535134;14741.410343586136;14824.481143159937;14824.703505930036;14885.851141488447 04:04:16 INFO - PID 2430 | |3;mootools.html;1293.4567481657703;1389.3608939887652;1436.9223082687977;1451.1728314860534;1473.697891932154;11770.646880593586;11966.940165299162;12146.757064858697;12205.633830985074;12335.198212116205;11966.820497692519;12173.45219465124;12175.20861144025;12178.81731774024;12192.268463892173;2507.247825652302;2542.1229675761924;2545.440003199293;2553.374423266293;2560.1807421625085;2480.131953816168;2526.216872769438;2529.140092368594;2529.53203657324;2536.264483299841;2494.914712100233;2505.799536037113;2509.77412032917;2521.710003349611;2521.9117330893423;2467.087177744237;2483.0688491815567;2486.0552989863736;2490.2404766546197;2496.4757400945823;1991.382671371865;2078.262216912989;2406.0856874387796;2465.93964595224;2511.045802595002;11535.788742182118;11635.011024062966;11652.941735291353;11665.24175928562;11695.35675537845;6449.806505804833;6625.138731964862;6670.666466676646;6671.099401580773;6676.732930682767;3959.208158368309;4060.3503439449546;4308.547602501723;4314.158739045887;4337.110892267109;1252.4364036183754;1255.7237407770276;1256.654420034498;1279.0918447902002;1291.373683763374;15353.07881527112;15773.763393549221;16397.42609008691;16549.25528351215;16565.42021029258;5588.944110558924;5636.718164091779;5813.244278243886;5872.794452194153;5890.4698577128265;12085.818712719316;12310;12375.566855160027;12380.380980950917;12401.503939842305;6513.772017979348;6568.737250510022;6583.934160658332;6597.571157874723;6606.504512161511;5986.281646202484;6138.324784273726;6205.720742566596;6207.255129384503;6223.253209614784;4375.868723938287;4440.289553671397;4473.194824931544;4528.343390208402;4547.226971414867;1125.499152876962;1209.9455524501418;1233.80259158536;1291.7222797098668;1322.5569434239444;710.4316546762569;788.7633709887011;789.8025493626593;813.6989313953875;815.4780940198278;6244.229795598254;6285.937140628626;6378.330275321025;6440.61356318613;6469.805905822833;587.7061469265367;591.1782622155208;592.6769910398843;602.0848310567912;602.8726281852876;2986.7140653162346;3803.923921521554;4389.1660584488845;4455.554444555583;4550.158220729176;11972.341521216242;15040.473583424528;15275.701565367068;15298.464553740565;15364.615884602974;15052.698946021237;15205.163715995726;15277.694446111016;15280.388784448498;15287.61780955485;8460.957695211484;8552.657893684183;8555.486670799772;8578.18507241811;8734.650613975497;6262.530310226843;6735.191776986793;6812.761553345609;7226.349628533457;7295.8540829184185;12934.482620695067;13442.93278533601;13445.731085378433;13557.728845422838;13609.727805444034;13760.724785504433;13861.4455421782;13873.653158671113;14025.649358765704;14054.086484378482;8605.483670979762;8631;8638.222559969632;8702.303815694602;8733.30133589324;10638.946805265925;10678.359298442118;10694.304870183412;10763.246572739834;10777.784444310913;9089.909100908906;9149.817003659757;9234.815303694022;9245.02927192635;9457.763555911157;8885.06706795777;9057.09429057089;9265.305102117503;9361.204297634506;9370.297227707813;8048.839023219386;8063.435559510778;8104.918950810416;8193.09875913661;8201.13888041747;5862.706864656835;6040.818775436744;6099.634021958696;6152.6000809949;6214.25428948529;6073.665948372882;6125.326214116532;6135.601185923079;6200.534959877937;6222.222222222223 04:04:16 INFO - PID 2430 | |4;prototype.html;393.9369700847864;395.81594558530276;401.85734064407114;403.36671425861374;403.5328908977618;5351;5803.825885223439;6093.177421048157;6420.293767685556;6478.4817214622835;5820;6050.334463209044;6223.408776166269;6268.247810262775;6298.842528936779;4603.217453032992;4611.7924693388795;4618.5381461853885;4645.140648979949;4663.743494107823;3894.9805250973845;4607.746573938432;4617;4624.491305956342;4650.7442090685;3787.791671458069;4580.541945805409;4581.40441742573;4588.954110458886;4591.334256532801;3818.694504439638;4409.809351475116;4587.954120458786;4589.534901149644;4608.585227329523;3334.9666503334897;3739.6821270192067;4003.459532963042;4004;4022.73852199609;5386.892262154735;5897.523040323074;6221.3156552779155;6230.781922632731;6243.157173781527;4675.602573781232;5512;5541.307336582927;5542.5565954723525;5546.55627549795;5924.289085309747;6849.383555480031;6854.280300568418;6873.278305777922;6887.862242755167;4544.931826022612;4546.954530454686;4563.612092972101;4583.679142460029;4595.126925884071;5868.09044598088;6604.834879128012;6779.186497620317;7129.928700712979;7233.240509746453;4478.081993191388;4529.682922195415;4544.227481328182;4588;4600.815967361268;3741.4387841823946;4452.732836029849;4653.139169253631;4694.2723877799;4699.412573428322;5158.252053452228;5172.793088276502;5295.947040529545;5443.836684899459;5453.972730136324;5629.774809007594;6764.526483146231;6774.627395493295;6799.354061364163;6816.897746533806;1592.3948899418147;1604.181867247712;1604.3101466369574;1606.377558665756;1610.1788088075166;1027.3476342522551;1046.7697106636529;1063.4204358624568;1063.8936106389453;1068.5832525315134;6339.4611458025665;6351.936480635227;6371.39471750183;6377.64922929234;6394.360563943662;5501.724913754297;5859.941400586024;6154.630722156685;6947;7041.718331266795;6281.618044030306;6286.214223222098;6307.653079080603;6308.653024083719;6331.176946996861;3631.9818400908357;3663.8900832975055;3672.889813305605;3684.7604905681583;3689.94465083024;6200.906986395207;6226.315105338409;7135.286471352823;7156.141263048467;7159.67781449836;7107.39587135089;7179.210286868441;7195.316444937723;7195.640217989079;7215.278472152848;6135.693215339215;6244.469220116341;7196.280371962762;7223.927760722326;7252.601106939274;6813.18241810966;7007.789766307019;7025.227225005143;7134.785956421316;7136.250693677297;5208.479152084761;5220.9738951305;6806.719748883981;6907.447404207752;6940.618265995418;6682.766103186365;6759.695813688298;6873.621950792554;7085.645717713991;7097.716091356495;3614.3855544556964;4108.787907567215;4605.815767369268;4618.6536009800075;4658.37111989877;6678.265390806911;7002.859942801014;7021.648917554204;7071.605629297863;7092.645367731696;6806.897896531655;6819.5908245505425;6821.965890170517;6849.623270720158;6861.656917154222;6785.185777706707;6804.380313579209;6808.365992161945;6810.489213309121;6815.556988795713;5649.85875353103;6616.503762217949;6661.233958094881;6793.184817821893;6893.93106068953;6749.797506074629;6798.388145066968;6816.556923799937;6822.283660215606;6826.726930922708;6609.636469994197;6617;6632.933670663231;6635.23694775107;6675 04:04:16 INFO - PID 2430 | |5;yui.html;728.763151975608;795.1571334385552;813.6013353456808;850.1625898489987;860.2601762484262;16406;16937.237824297907;16938.491845244647;16971.236294366754;16974;12686.492540298377;15061.77407338888;15472.690546189098;15977.520674379788;16421.42525011625;964.218982624075;984.9113579777819;986.9703908882709;988.1650005745134;990.7374545745383;852.4629483425464;968.6561270748888;997.615917871621;999.9250056245811;1000.2348203624233;1057;1069.4759567811755;1069.5133714160077;1069.6737495064003;1070.6520380876207;1058.6929790360787;1064.9520771565299;1065.6802959112274;1067.092970974673;1067.6690226029955;777.7005852746677;785.7014334552833;786.9488483248571;787.0594639405855;787.2049230277404;17168.91415542927;17687.73468397975;17695.911520442445;17770.466885993443;17861.553461163447;17801.02094384808;17828.643427131516;18030.63938721231;18118.365857194935;18167.909160454114;14358;14842.109473431628;14849.183294918776;14908.776368354484;14956.850431495655;3566.91082722934;3602;3632.56409230894;3634;3683.2633473305445;142889.8566521503;143298;144634.27682861625;145790;146837.26581367024;13593.184408935298;13689.247091410069;13704.45182192721;13812.171269723849;13902.304884755924;26082.608760868603;26355.736442635713;26705;26742.465150696877;26795;7874.960625196837;7883.2905038546805;7941.682332706742;7953.363730901514;7980.600969951595;3535.1869070114017;3611.5485564304463;3611.7832930024283;3615.8192090395373;3620.2940426616556;4352.82588696455;4420.115976804587;4425.159219748238;4446.199684056836;4471.329300604935;859.7721603775004;885.198895000026;886.4548302793864;890.6615486115235;891.153404265937;650.1580453312971;660.8347913021745;670.9261981182066;672.653583404547;681.111149949311;10511.421871797125;10807.729806754736;11788.764224715458;12278.693032674255;12355.567555135527;621.6448143048199;653.3923451190439;665.5008743442418;684.7569112964925;693.8646963842103;4342.240107981153;5106.770195341219;5387.703676297841;5398.541124004425;5939.049752039653;129382;129506.70493295135;131933;132123.33938330246;133197;67517;117057;117575;118261;120272.39863800626;67093;72153.55692886113;82013;89165.55417222873;108469.45765271124;10650.946745266534;12438.751224975269;12447.253164809777;12621.558245461365;12705.317856929436;42769;43122.78438607787;43147;43266.56733432625;43650.69047928567;65374;78594;81026.18973810186;83766;85465;16995.915020424818;17001.574960626083;17268.395606153725;17307.740383894;17323.913380433016;17903.10484475797;18154.636907261516;18515.444536663384;18693.252269909597;18697.71953420727;16439.67120657604;17719.29122835072;17767.02281374485;17792.46622601324;17876.463706088838;14357.497487587885;16754.4135955241;17822.287108515513;17827.821721783133;17894;13600;16702.248398822325;16928.83071169322;17267;17409.129543523028;9601.903980960102;11773.411329433666;12161.69595760113;12191.390430478617;12244;12392.938035309766;13723.725525489634;13758.862411376158;13860.168389896638;13973.510927117502 04:04:16 INFO - PID 2430 | __end_tp_report 04:04:16 INFO - PID 2430 | __start_cc_report 04:04:16 INFO - PID 2430 | _x_x_mozilla_cycle_collect,459 04:04:16 INFO - PID 2430 | __end_cc_report 04:04:16 INFO - PID 2430 | __startTimestamp1496228656787__endTimestamp 04:04:16 INFO - PID 2430 | 04:04:16 INFO - PID 2430 | ------- Summary: start ------- 04:04:16 INFO - PID 2430 | Number of tests: 6 04:04:16 INFO - PID 2430 | 04:04:16 INFO - PID 2430 | [#0] dojo.html Cycles:165 Average:6153.87 Median:6362.43 stddev:751.09 (11.8%) stddev-sans-first:715.01 04:04:16 INFO - PID 2430 | Values: 3132.3 3556.9 3570.8 3586.4 3599.7 6124.6 6131.0 6183.0 6197.6 6223.8 6112.9 6156.8 6159.8 6242.1 6251.7 5253.9 5390.9 5425.1 5429.6 5468.4 5495.1 5544.1 5553.4 5556.3 5568.9 5341.5 5378.1 5437.7 5443.5 5525.8 5471.0 5485.4 5498.8 5510.9 5524.1 4662.6 5402.7 5461.0 5487.4 5508.1 6105.6 6115.4 6147.6 6196.9 6250.8 5544.3 6577.8 6672.7 6675.0 6681.4 5498.9 6740.6 6750.4 6781.6 6790.2 6195.3 6278.9 6288.3 6298.7 6308.1 5678.9 6747.0 6833.1 6868.6 6938.3 5226.7 6383.5 6495.0 6503.9 6597.3 6699.1 6743.8 6787.5 6802.8 6805.5 6698.8 6702.5 6713.6 6722.1 6750.2 6454.1 6553.3 6590.0 6701.9 6733.1 5325.3 5365.4 5371.8 5376.2 5427.7 4786.1 4813.1 4816.6 4831.8 4839.5 6219.3 6220.9 6267.1 6350.9 6362.4 6760.0 6821.3 6859.4 6870.6 6883.4 6201.8 6317.1 6317.4 6320.5 6350.6 5814.3 6004.3 6012.8 6019.9 6033.8 6843.1 6948.4 6951.5 6968.9 7011.4 5684.6 6725.3 6801.5 6827.1 6844.5 6648.7 6941.4 6984.3 7007.8 7018.6 5245.6 6439.7 6609.6 6674.4 6738.1 6848.3 6870.5 6871.4 6891.8 6909.0 6473.0 6559.5 6612.1 6666.8 6714.2 5410.9 6581.0 6657.0 6682.7 6695.9 6374.2 6486.1 6512.0 6529.8 6534.7 6406.2 6582.9 6601.1 6607.2 6617.0 6349.9 6369.6 6434.8 6486.1 6494.8 04:04:16 INFO - PID 2430 | 04:04:16 INFO - PID 2430 | [#1] ext.html Cycles:180 Average:44796.43 Median:35318.38 stddev:78814.63 (223.2%) stddev-sans-first:78966.42 04:04:16 INFO - PID 2430 | Values: 773.5 818.0 844.1 1240.4 1307.1 29912.6 37697.2 39197.4 39199.4 39279.8 39145.4 39474.8 39485.4 39485.4 39523.2 7862.6 8046.4 8207.9 8395.8 8416.7 8318.4 8390.8 8398.4 8409.4 8413.0 8360.7 8382.3 8433.7 8437.2 8441.2 8363.8 8392.6 8404.8 8407.9 8409.0 8259.5 8289.7 8295.6 8321.0 8346.3 36809.6 37143.4 37185.4 37201.5 37246.0 3514.2 3515.9 3562.2 3565.8 3573.3 30175.0 30230.4 30234.4 30250.0 30294.4 3053.0 3095.9 3104.9 3111.6 3112.8 255179.0 258499.0 275574.0 298387.6 298813.0 46675.5 46930.0 46982.1 47050.0 47215.8 48785.0 48904.0 49043.0 49076.0 49716.8 14430.9 14568.3 14588.3 14635.7 14667.1 11679.5 11698.0 11741.4 11751.3 11752.9 13775.5 13811.9 13814.8 13829.7 13908.7 6168.4 6184.5 6218.6 6219.4 6220.5 954.3 954.8 973.3 991.2 997.5 22644.8 23595.0 23824.8 24008.2 24227.5 897.6 1050.6 1084.5 1116.0 1119.8 10625.1 11341.3 11966.6 12179.3 12254.0 340973.3 341770.0 341959.0 342566.0 342766.0 52479.7 52685.7 52817.0 52854.7 52890.2 1490.5 1504.2 1532.6 1539.4 1548.2 19970.9 23116.2 23379.4 23478.5 23530.6 36097.1 36110.6 36129.5 36182.3 36208.6 274716.0 276239.0 277027.2 277150.6 277408.0 35069.5 35279.8 35357.0 35361.5 35429.9 42698.0 42737.4 43034.6 44725.1 44769.3 30085.4 30597.0 30688.2 30840.4 31102.8 30091.8 30470.7 30522.0 30643.6 30912.7 35985.7 36223.0 36583.3 36622.0 36964.0 30101.7 30320.9 30728.8 30929.8 30960.5 22823.0 23161.0 24030.6 24034.0 24280.4 04:04:16 INFO - PID 2430 | 04:04:16 INFO - PID 2430 | [#2] jquery.html Cycles:180 Average:37447.79 Median:12216.06 stddev:92361.31 (756.1%) stddev-sans-first:92578.53 04:04:16 INFO - PID 2430 | Values: 405.2 405.8 422.2 422.3 447.2 22031.3 22829.4 22878.7 22896.4 22898.5 20385.8 20815.9 23781.6 24794.0 24813.0 5395.8 5931.1 6167.6 6198.2 6201.8 6090.6 6090.8 6130.7 6143.6 6154.8 6090.1 6099.1 6103.2 6120.8 6135.8 4735.2 5773.9 5791.7 5819.3 5828.0 5211.7 5212.9 5219.4 5224.3 5231.3 28732.7 28818.0 29160.3 29173.7 29205.6 1984.3 2230.4 2266.5 2269.8 2271.7 17808.6 18644.5 18822.6 18828.0 18874.5 2145.5 2185.2 2206.3 2215.2 2227.9 148402.5 154544.0 172467.0 173977.0 177497.2 12693.3 13008.5 13059.3 13062.2 13222.3 16609.5 16639.4 16845.7 16847.8 16880.0 8872.9 9004.2 9010.7 9063.4 9073.7 8490.2 8509.0 8531.5 8547.0 8611.1 7984.9 8141.3 8231.3 8286.9 8295.1 2109.0 2182.3 2198.3 2205.3 2206.0 955.9 961.7 966.9 990.6 1011.8 18108.0 21067.7 21918.5 22216.9 22284.8 1014.5 1020.5 1039.5 1040.7 1048.6 10337.7 10416.3 10536.2 11449.9 11545.4 533494.0 537003.0 540175.0 541497.0 544705.0 101837.0 137573.0 149869.0 151396.0 151611.2 999.9 1018.8 1162.6 1184.7 1196.4 22542.4 22756.8 23381.0 23410.8 23456.9 63999.0 65992.0 66145.0 66572.0 66679.3 87172.6 87252.6 87659.0 88319.0 88385.6 13096.5 15273.8 16503.9 16578.5 16636.3 15282.5 16558.7 18040.5 18137.4 18159.1 14862.8 14868.7 15046.5 15055.6 15167.1 13400.3 13402.8 13551.7 13790.7 14045.2 15069.5 15387.5 15466.7 15570.2 15650.5 16223.0 16557.0 16656.6 16805.7 16806.2 14585.3 14741.4 14824.5 14824.7 14885.9 04:04:16 INFO - PID 2430 | 04:04:16 INFO - PID 2430 | [#3] mootools.html Cycles:180 Average:7133.98 Median:3395.32 stddev:4542.49 (133.8%) stddev-sans-first:4534.03 04:04:16 INFO - PID 2430 | Values: 1293.5 1389.4 1436.9 1451.2 1473.7 11770.6 11966.9 12146.8 12205.6 12335.2 11966.8 12173.5 12175.2 12178.8 12192.3 2507.2 2542.1 2545.4 2553.4 2560.2 2480.1 2526.2 2529.1 2529.5 2536.3 2494.9 2505.8 2509.8 2521.7 2521.9 2467.1 2483.1 2486.1 2490.2 2496.5 1991.4 2078.3 2406.1 2465.9 2511.0 11535.8 11635.0 11652.9 11665.2 11695.4 6449.8 6625.1 6670.7 6671.1 6676.7 3959.2 4060.4 4308.5 4314.2 4337.1 1252.4 1255.7 1256.7 1279.1 1291.4 15353.1 15773.8 16397.4 16549.3 16565.4 5588.9 5636.7 5813.2 5872.8 5890.5 12085.8 12310.0 12375.6 12380.4 12401.5 6513.8 6568.7 6583.9 6597.6 6606.5 5986.3 6138.3 6205.7 6207.3 6223.3 4375.9 4440.3 4473.2 4528.3 4547.2 1125.5 1209.9 1233.8 1291.7 1322.6 710.4 788.8 789.8 813.7 815.5 6244.2 6285.9 6378.3 6440.6 6469.8 587.7 591.2 592.7 602.1 602.9 2986.7 3803.9 4389.2 4455.6 4550.2 11972.3 15040.5 15275.7 15298.5 15364.6 15052.7 15205.2 15277.7 15280.4 15287.6 8461.0 8552.7 8555.5 8578.2 8734.7 6262.5 6735.2 6812.8 7226.3 7295.9 12934.5 13442.9 13445.7 13557.7 13609.7 13760.7 13861.4 13873.7 14025.6 14054.1 8605.5 8631.0 8638.2 8702.3 8733.3 10638.9 10678.4 10694.3 10763.2 10777.8 9089.9 9149.8 9234.8 9245.0 9457.8 8885.1 9057.1 9265.3 9361.2 9370.3 8048.8 8063.4 8104.9 8193.1 8201.1 5862.7 6040.8 6099.6 6152.6 6214.3 6073.7 6125.3 6135.6 6200.5 6222.2 04:04:16 INFO - PID 2430 | 04:04:16 INFO - PID 2430 | [#4] prototype.html Cycles:180 Average:5433.26 Median:6145.16 stddev:1725.00 (28.1%) stddev-sans-first:1687.86 04:04:16 INFO - PID 2430 | Values: 393.9 395.8 401.9 403.4 403.5 5351.0 5803.8 6093.2 6420.3 6478.5 5820.0 6050.3 6223.4 6268.2 6298.8 4603.2 4611.8 4618.5 4645.1 4663.7 3895.0 4607.7 4617.0 4624.5 4650.7 3787.8 4580.5 4581.4 4589.0 4591.3 3818.7 4409.8 4588.0 4589.5 4608.6 3335.0 3739.7 4003.5 4004.0 4022.7 5386.9 5897.5 6221.3 6230.8 6243.2 4675.6 5512.0 5541.3 5542.6 5546.6 5924.3 6849.4 6854.3 6873.3 6887.9 4544.9 4547.0 4563.6 4583.7 4595.1 5868.1 6604.8 6779.2 7129.9 7233.2 4478.1 4529.7 4544.2 4588.0 4600.8 3741.4 4452.7 4653.1 4694.3 4699.4 5158.3 5172.8 5295.9 5443.8 5454.0 5629.8 6764.5 6774.6 6799.4 6816.9 1592.4 1604.2 1604.3 1606.4 1610.2 1027.3 1046.8 1063.4 1063.9 1068.6 6339.5 6351.9 6371.4 6377.6 6394.4 5501.7 5859.9 6154.6 6947.0 7041.7 6281.6 6286.2 6307.7 6308.7 6331.2 3632.0 3663.9 3672.9 3684.8 3689.9 6200.9 6226.3 7135.3 7156.1 7159.7 7107.4 7179.2 7195.3 7195.6 7215.3 6135.7 6244.5 7196.3 7223.9 7252.6 6813.2 7007.8 7025.2 7134.8 7136.3 5208.5 5221.0 6806.7 6907.4 6940.6 6682.8 6759.7 6873.6 7085.6 7097.7 3614.4 4108.8 4605.8 4618.7 4658.4 6678.3 7002.9 7021.6 7071.6 7092.6 6806.9 6819.6 6822.0 6849.6 6861.7 6785.2 6804.4 6808.4 6810.5 6815.6 5649.9 6616.5 6661.2 6793.2 6893.9 6749.8 6798.4 6816.6 6822.3 6826.7 6609.6 6617.0 6632.9 6635.2 6675.0 04:04:16 INFO - PID 2430 | 04:04:16 INFO - PID 2430 | [#5] yui.html Cycles:180 Average:24457.80 Median:17885.23 stddev:36845.39 (206.0%) stddev-sans-first:36905.67 04:04:16 INFO - PID 2430 | Values: 728.8 795.2 813.6 850.2 860.3 16406.0 16937.2 16938.5 16971.2 16974.0 12686.5 15061.8 15472.7 15977.5 16421.4 964.2 984.9 987.0 988.2 990.7 852.5 968.7 997.6 999.9 1000.2 1057.0 1069.5 1069.5 1069.7 1070.7 1058.7 1065.0 1065.7 1067.1 1067.7 777.7 785.7 786.9 787.1 787.2 17168.9 17687.7 17695.9 17770.5 17861.6 17801.0 17828.6 18030.6 18118.4 18167.9 14358.0 14842.1 14849.2 14908.8 14956.9 3566.9 3602.0 3632.6 3634.0 3683.3 142889.9 143298.0 144634.3 145790.0 146837.3 13593.2 13689.2 13704.5 13812.2 13902.3 26082.6 26355.7 26705.0 26742.5 26795.0 7875.0 7883.3 7941.7 7953.4 7980.6 3535.2 3611.5 3611.8 3615.8 3620.3 4352.8 4420.1 4425.2 4446.2 4471.3 859.8 885.2 886.5 890.7 891.2 650.2 660.8 670.9 672.7 681.1 10511.4 10807.7 11788.8 12278.7 12355.6 621.6 653.4 665.5 684.8 693.9 4342.2 5106.8 5387.7 5398.5 5939.0 129382.0 129506.7 131933.0 132123.3 133197.0 67517.0 117057.0 117575.0 118261.0 120272.4 67093.0 72153.6 82013.0 89165.6 108469.5 10650.9 12438.8 12447.3 12621.6 12705.3 42769.0 43122.8 43147.0 43266.6 43650.7 65374.0 78594.0 81026.2 83766.0 85465.0 16995.9 17001.6 17268.4 17307.7 17323.9 17903.1 18154.6 18515.4 18693.3 18697.7 16439.7 17719.3 17767.0 17792.5 17876.5 14357.5 16754.4 17822.3 17827.8 17894.0 13600.0 16702.2 16928.8 17267.0 17409.1 9601.9 11773.4 12161.7 12191.4 12244.0 12392.9 13723.7 13758.9 13860.2 13973.5 04:04:16 INFO - PID 2430 | -------- Summary: end -------- 04:04:16 INFO - PID 2430 | 04:04:17 INFO - TEST-INFO | 2430: exit 0 04:04:17 INFO - TEST-OK | dromaeo_css | took 1143247ms 04:04:17 INFO - TEST-START | kraken 04:04:17 INFO - operating with platform_type : mac_ 04:04:17 INFO - Initialising browser for kraken test... 04:04:17 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:56916/getInfo.html -foreground -profile /var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/tmpbk9QUG/profile 04:04:17 INFO - TEST-INFO | started process 2440 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:56916/getInfo.html -foreground) 04:04:51 INFO - TEST-INFO | 2440: killed by SIGTERM 04:04:51 INFO - Browser initialized. 04:04:51 INFO - Running cycle 1/1 for kraken test... 04:04:51 INFO - TEST-INFO | started process 2444 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/tmpbk9QUG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:04:53 INFO - PID 2444 | 2017-05-31 04:04:53.221 plugin-container[2445:36092] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x983f, name = 'com.apple.tsm.portname' 04:04:53 INFO - PID 2444 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:04:53 INFO - PID 2444 | 2017-05-31 04:04:53.222 plugin-container[2445:36092] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4923, name = 'com.apple.CFPasteboardClient' 04:04:53 INFO - PID 2444 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:04:53 INFO - PID 2444 | 2017-05-31 04:04:53.222 plugin-container[2445:36092] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:04:53 INFO - PID 2444 | 2017-05-31 04:04:53.739 plugin-container[2446:36137] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 04:04:53 INFO - PID 2444 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:04:53 INFO - PID 2444 | 2017-05-31 04:04:53.740 plugin-container[2446:36137] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x490f, name = 'com.apple.CFPasteboardClient' 04:04:53 INFO - PID 2444 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:04:53 INFO - PID 2444 | 2017-05-31 04:04:53.740 plugin-container[2446:36137] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:05:03 INFO - PID 2444 | RSS: Main: 269107200 04:05:03 INFO - PID 2444 | 04:05:05 INFO - PID 2444 | Cycle 1(1): loaded http://localhost:56916/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56916/tests/kraken/driver.html?testName=audio-beat-detection) 04:05:06 INFO - PID 2444 | RSS: Main: 276537344 04:05:06 INFO - PID 2444 | 04:05:09 INFO - PID 2444 | Cycle 1(1): loaded http://localhost:56916/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56916/tests/kraken/driver.html?testName=audio-dft) 04:05:09 INFO - PID 2444 | RSS: Main: 284200960 04:05:09 INFO - PID 2444 | 04:05:13 INFO - PID 2444 | Cycle 1(1): loaded http://localhost:56916/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56916/tests/kraken/driver.html?testName=audio-fft) 04:05:13 INFO - PID 2444 | RSS: Main: 282148864 04:05:13 INFO - PID 2444 | 04:05:15 INFO - PID 2444 | Cycle 1(1): loaded http://localhost:56916/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56916/tests/kraken/driver.html?testName=audio-oscillator) 04:05:16 INFO - PID 2444 | RSS: Main: 282984448 04:05:16 INFO - PID 2444 | 04:05:18 INFO - PID 2444 | Cycle 1(1): loaded http://localhost:56916/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56916/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:05:19 INFO - PID 2444 | RSS: Main: 283537408 04:05:19 INFO - PID 2444 | 04:05:21 INFO - PID 2444 | Cycle 1(1): loaded http://localhost:56916/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56916/tests/kraken/driver.html?testName=imaging-darkroom) 04:05:22 INFO - PID 2444 | RSS: Main: 287703040 04:05:22 INFO - PID 2444 | 04:05:25 INFO - PID 2444 | Cycle 1(1): loaded http://localhost:56916/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56916/tests/kraken/driver.html?testName=imaging-desaturate) 04:05:26 INFO - PID 2444 | RSS: Main: 292020224 04:05:26 INFO - PID 2444 | 04:05:29 INFO - PID 2444 | Cycle 1(1): loaded http://localhost:56916/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56916/tests/kraken/driver.html?testName=json-parse-financial) 04:05:29 INFO - PID 2444 | RSS: Main: 291102720 04:05:29 INFO - PID 2444 | 04:05:31 INFO - PID 2444 | Cycle 1(1): loaded http://localhost:56916/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56916/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:05:32 INFO - PID 2444 | RSS: Main: 290754560 04:05:32 INFO - PID 2444 | 04:05:33 INFO - PID 2444 | Cycle 1(1): loaded http://localhost:56916/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56916/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:05:34 INFO - PID 2444 | RSS: Main: 290775040 04:05:34 INFO - PID 2444 | 04:05:36 INFO - PID 2444 | Cycle 1(1): loaded http://localhost:56916/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56916/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:05:37 INFO - PID 2444 | RSS: Main: 290623488 04:05:37 INFO - PID 2444 | 04:05:40 INFO - PID 2444 | Cycle 1(1): loaded http://localhost:56916/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56916/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:05:41 INFO - PID 2444 | RSS: Main: 290205696 04:05:41 INFO - PID 2444 | 04:05:44 INFO - PID 2444 | Cycle 1(1): loaded http://localhost:56916/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56916/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:05:44 INFO - PID 2444 | RSS: Main: 290406400 04:05:44 INFO - PID 2444 | 04:05:46 INFO - PID 2444 | Cycle 1(1): loaded http://localhost:56916/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56916/tests/kraken/driver.html?testName=ai-astar) 04:05:46 INFO - PID 2444 | RSS: Main: 292290560 04:05:46 INFO - PID 2444 | 04:05:46 INFO - PID 2444 | __start_tp_report 04:05:46 INFO - PID 2444 | _x_x_mozilla_page_load 04:05:46 INFO - PID 2444 | _x_x_mozilla_page_load_details 04:05:46 INFO - PID 2444 | |i|pagename|runs| 04:05:46 INFO - PID 2444 | |0;ai-astar;101;116;116;115;115;115;115;115;114;106 04:05:46 INFO - PID 2444 | |1;audio-beat-detection;142;106;115;147;110;129;133;144;157;138 04:05:46 INFO - PID 2444 | |2;audio-dft;199;201;200;208;213;217;235;199;202;200 04:05:46 INFO - PID 2444 | |3;audio-fft;83;60;92;97;81;81;87;65;78;59 04:05:46 INFO - PID 2444 | |4;audio-oscillator;81;69;82;71;70;73;88;70;69;90 04:05:46 INFO - PID 2444 | |5;imaging-gaussian-blur;92;93;92;92;94;93;93;93;101;92 04:05:46 INFO - PID 2444 | |6;imaging-darkroom;150;129;98;99;99;100;99;99;99;102 04:05:46 INFO - PID 2444 | |7;imaging-desaturate;89;94;90;92;96;90;90;95;91;90 04:05:46 INFO - PID 2444 | |8;json-parse-financial;51;48;50;49;52;53;53;52;53;52 04:05:46 INFO - PID 2444 | |9;json-stringify-tinderbox;56;60;59;66;65;76;79;57;58;57 04:05:46 INFO - PID 2444 | |10;stanford-crypto-aes;99;89;83;81;103;82;90;89;122;88 04:05:46 INFO - PID 2444 | |11;stanford-crypto-ccm;140;129;132;136;156;154;167;150;149;174 04:05:46 INFO - PID 2444 | |12;stanford-crypto-pbkdf2;181;189;185;189;191;195;187;213;240;238 04:05:46 INFO - PID 2444 | |13;stanford-crypto-sha256-iterative;68;69;64;65;67;65;65;68;65;76 04:05:46 INFO - PID 2444 | __end_tp_report 04:05:46 INFO - PID 2444 | __start_cc_report 04:05:46 INFO - PID 2444 | _x_x_mozilla_cycle_collect,1199 04:05:46 INFO - PID 2444 | __end_cc_report 04:05:46 INFO - PID 2444 | __startTimestamp1496228746775__endTimestamp 04:05:46 INFO - PID 2444 | 04:05:46 INFO - PID 2444 | ------- Summary: start ------- 04:05:46 INFO - PID 2444 | Number of tests: 14 04:05:46 INFO - PID 2444 | 04:05:46 INFO - PID 2444 | [#0] ai-astar Cycles:10 Average:112.80 Median:115.00 stddev:5.07 (4.4%) stddev-sans-first:3.10 04:05:46 INFO - PID 2444 | Values: 101.0 116.0 116.0 115.0 115.0 115.0 115.0 115.0 114.0 106.0 04:05:46 INFO - PID 2444 | 04:05:46 INFO - PID 2444 | [#1] audio-beat-detection Cycles:10 Average:132.10 Median:140.00 stddev:16.96 (12.1%) stddev-sans-first:17.61 04:05:46 INFO - PID 2444 | Values: 142.0 106.0 115.0 147.0 110.0 129.0 133.0 144.0 157.0 138.0 04:05:46 INFO - PID 2444 | 04:05:46 INFO - PID 2444 | [#2] audio-dft Cycles:10 Average:207.40 Median:205.00 stddev:11.58 (5.6%) stddev-sans-first:11.87 04:05:46 INFO - PID 2444 | Values: 199.0 201.0 200.0 208.0 213.0 217.0 235.0 199.0 202.0 200.0 04:05:46 INFO - PID 2444 | 04:05:46 INFO - PID 2444 | [#3] audio-fft Cycles:10 Average:78.30 Median:82.00 stddev:13.06 (15.9%) stddev-sans-first:13.74 04:05:46 INFO - PID 2444 | Values: 83.0 60.0 92.0 97.0 81.0 81.0 87.0 65.0 78.0 59.0 04:05:46 INFO - PID 2444 | 04:05:46 INFO - PID 2444 | [#4] audio-oscillator Cycles:10 Average:76.30 Median:77.00 stddev:8.19 (10.6%) stddev-sans-first:8.51 04:05:46 INFO - PID 2444 | Values: 81.0 69.0 82.0 71.0 70.0 73.0 88.0 70.0 69.0 90.0 04:05:46 INFO - PID 2444 | 04:05:46 INFO - PID 2444 | [#5] imaging-gaussian-blur Cycles:10 Average:93.50 Median:93.00 stddev:2.72 (2.9%) stddev-sans-first:2.83 04:05:46 INFO - PID 2444 | Values: 92.0 93.0 92.0 92.0 94.0 93.0 93.0 93.0 101.0 92.0 04:05:46 INFO - PID 2444 | 04:05:46 INFO - PID 2444 | [#6] imaging-darkroom Cycles:10 Average:107.40 Median:99.00 stddev:17.66 (17.8%) stddev-sans-first:9.94 04:05:46 INFO - PID 2444 | Values: 150.0 129.0 98.0 99.0 99.0 100.0 99.0 99.0 99.0 102.0 04:05:46 INFO - PID 2444 | 04:05:46 INFO - PID 2444 | [#7] imaging-desaturate Cycles:10 Average:91.70 Median:91.50 stddev:2.45 (2.7%) stddev-sans-first:2.40 04:05:46 INFO - PID 2444 | Values: 89.0 94.0 90.0 92.0 96.0 90.0 90.0 95.0 91.0 90.0 04:05:46 INFO - PID 2444 | 04:05:46 INFO - PID 2444 | [#8] json-parse-financial Cycles:10 Average:51.30 Median:52.00 stddev:1.77 (3.4%) stddev-sans-first:1.87 04:05:46 INFO - PID 2444 | Values: 51.0 48.0 50.0 49.0 52.0 53.0 53.0 52.0 53.0 52.0 04:05:46 INFO - PID 2444 | 04:05:46 INFO - PID 2444 | [#9] json-stringify-tinderbox Cycles:10 Average:63.30 Median:62.50 stddev:8.22 (13.2%) stddev-sans-first:8.28 04:05:46 INFO - PID 2444 | Values: 56.0 60.0 59.0 66.0 65.0 76.0 79.0 57.0 58.0 57.0 04:05:46 INFO - PID 2444 | 04:05:46 INFO - PID 2444 | [#10] stanford-crypto-aes Cycles:10 Average:92.60 Median:88.50 stddev:12.50 (14.1%) stddev-sans-first:13.04 04:05:46 INFO - PID 2444 | Values: 99.0 89.0 83.0 81.0 103.0 82.0 90.0 89.0 122.0 88.0 04:05:46 INFO - PID 2444 | 04:05:46 INFO - PID 2444 | [#11] stanford-crypto-ccm Cycles:10 Average:148.70 Median:152.00 stddev:14.77 (9.7%) stddev-sans-first:15.32 04:05:46 INFO - PID 2444 | Values: 140.0 129.0 132.0 136.0 156.0 154.0 167.0 150.0 149.0 174.0 04:05:46 INFO - PID 2444 | 04:05:46 INFO - PID 2444 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:200.80 Median:193.00 stddev:21.88 (11.3%) stddev-sans-first:22.01 04:05:46 INFO - PID 2444 | Values: 181.0 189.0 185.0 189.0 191.0 195.0 187.0 213.0 240.0 238.0 04:05:46 INFO - PID 2444 | 04:05:46 INFO - PID 2444 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.20 Median:67.50 stddev:3.52 (5.2%) stddev-sans-first:3.72 04:05:46 INFO - PID 2444 | Values: 68.0 69.0 64.0 65.0 67.0 65.0 65.0 68.0 65.0 76.0 04:05:46 INFO - PID 2444 | -------- Summary: end -------- 04:05:46 INFO - PID 2444 | 04:05:47 INFO - TEST-INFO | 2444: exit 0 04:05:47 INFO - TEST-OK | kraken | took 90149ms 04:05:47 INFO - SUITE-END | took 1233s 04:05:47 INFO - Completed test suite (00:20:33) 04:05:47 INFO - javascript benchmark 04:05:47 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8051.4013409420195, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3132.263917979275, 3556.875509357173, 3570.7857528548275, 3586.4082426399655, 3599.6580324869146, 6124.625058545677, 6130.969345153274, 6183.041628547577, 6197.59715618485, 6223.751049958008, 6112.929053818841, 6156.846078848031, 6159.846003849906, 6242.1261023456755, 6251.6874156292115, 5253.921191182136, 5390.8921821563545, 5425.097572798553, 5429.619926605139, 5468.425815289397, 5495.120780675093, 5544.085225937715, 5553.416891226424, 5556.305461817273, 5568.86077848053, 5341.519263266286, 5378.139497680352, 5437.700926449043, 5443.537299329542, 5525.778968841241, 5471.042567550702, 5485.369182544018, 5498.835034948958, 5510.9448905511235, 5524.06090964533, 4662.557057079572, 5402.675839449606, 5460.989716902386, 5487.396386397493, 5508.118701007859, 6105.634327431686, 6115.362036198632, 6147.600405973598, 6196.907046394263, 6250.843728906768, 5544.251526044013, 6577.835554111138, 6672.666366681647, 6675.0, 6681.432078273353, 5498.862528436781, 6740.561863478858, 6750.426213771835, 6781.627010514419, 6790.151231096113, 6195.287541932736, 6278.874422511615, 6288.276848162474, 6298.7480500780375, 6308.116863639094, 5678.943210567842, 6746.966265168643, 6833.111695479556, 6868.626274745071, 6938.306169383122, 5226.686398816008, 6383.489320854321, 6494.967525162344, 6503.8699226015215, 6597.274299827015, 6699.095622090956, 6743.831404214934, 6787.490938179656, 6802.761903333359, 6805.455563554904, 6698.83252918681, 6702.530822842355, 6713.5636183648885, 6722.058911752359, 6750.223724271772, 6454.064160696673, 6553.279139294674, 6590.0, 6701.932980670228, 6733.124693789776, 5325.307709997753, 5365.409804921455, 5371.785128594891, 5376.1935709643085, 5427.728613569385, 4786.138495070921, 4813.109574728686, 4816.566509014206, 4831.758412079382, 4839.516048395132, 6219.253689557282, 6220.937790622036, 6267.091271765568, 6350.872982540416, 6362.427381535592, 6759.966200168968, 6821.283765204681, 6859.382655561023, 6870.62211578358, 6883.414909732729, 6201.782937597162, 6317.1156038154695, 6317.399847014526, 6320.525960552977, 6350.587211831216, 5814.273215848019, 6004.309504407049, 6012.849678757979, 6019.879602408015, 6033.849153771103, 6843.110395648534, 6948.444124470131, 6951.5481493703755, 6968.895466568107, 7011.404030657452, 5684.63049901755, 6725.327467253235, 6801.455883529206, 6827.146606674166, 6844.486663500357, 6648.700808463727, 6941.409980151541, 6984.266652001466, 7007.789766307019, 7018.613976231356, 5245.580353571781, 6439.7424103035355, 6609.603423794588, 6674.365935236145, 6738.056672066013, 6848.315168483112, 6870.519063665495, 6871.381575658214, 6891.793246202421, 6908.965455172692, 6472.967635161794, 6559.4752419807255, 6612.107365505595, 6666.766663166766, 6714.160729908762, 5410.864728381822, 6581.0, 6657.034729964032, 6682.732690692513, 6695.899561506484, 6374.235091789015, 6486.05952136947, 6512.023196520559, 6529.771457998947, 6534.673266336759, 6406.167198264196, 6582.868342633024, 6601.141851559267, 6607.2071351438135, 6617.0, 6349.904751428825, 6369.649669268235, 6434.8391290218115, 6486.05952136928, 6494.805155845333], "value": 6104.857002087716, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [773.5049568276304, 818.0388044048244, 844.0715213265408, 1240.441801189465, 1307.0785096506963, 29912.551311730287, 37697.24605507892, 39197.41203881944, 39199.412008819854, 39279.80360098199, 39145.41281880767, 39474.8026259869, 39485.40771888417, 39485.40771888424, 39523.20953580927, 7862.567558784264, 8046.356291496695, 8207.876881846778, 8395.790105247386, 8416.705415310465, 8318.41771076025, 8390.790230244262, 8398.370122240825, 8409.411341206118, 8413.032501262373, 8360.665573377057, 8382.287505562035, 8433.746987590383, 8437.156284371544, 8441.155884411572, 8363.790905227357, 8392.622331995075, 8404.8319033619, 8407.915920840775, 8409.032961209477, 8259.545724985124, 8289.668413263462, 8295.585220739, 8320.958395208047, 8346.290565301957, 36809.631903680885, 37143.4428483573, 37185.44221836675, 37201.51193952239, 37246.0, 3514.1741690702665, 3515.8769443069627, 3562.2341196642847, 3565.7682250653884, 3573.338932297507, 30175.0, 30230.395392092254, 30234.395312093635, 30250.0, 30294.394112117854, 3052.9847350763325, 3095.860686269123, 3104.8758049677986, 3111.642161151451, 3112.797668151563, 255179.0, 258499.0, 275573.999999998, 298387.62092842907, 298813.0, 46675.5332446678, 46930.0, 46982.060358793315, 47050.0, 47215.76392118017, 48785.0, 48904.0, 49043.0, 49076.0, 49716.757081073265, 14430.855691443161, 14568.271586420637, 14588.270586470846, 14635.707285854436, 14667.119972801665, 11679.474423650956, 11698.0, 11741.412929353499, 11751.294922304689, 11752.88247117535, 13775.517856874962, 13811.930940345233, 13814.792778108336, 13829.654258643614, 13908.721825563433, 6168.444839964424, 6184.5052395808225, 6218.5647004709235, 6219.440250377488, 6220.502359811204, 954.346272803132, 954.8376775948105, 973.3429934793986, 991.2119864707574, 997.4713401897006, 22644.773552264265, 23595.0, 23824.7617523826, 24008.249846809937, 24227.51544969091, 897.5915958238986, 1050.6270274052754, 1084.4794498640697, 1115.9609413670482, 1119.7648493816228, 10625.149988000941, 11341.262817916973, 11966.589521986367, 12179.330136842382, 12254.0, 340973.29513352277, 341770.0, 341959.0, 342566.0, 342766.0, 52479.73760131175, 52685.73657131766, 52817.0, 52854.67863303371, 52890.206646900326, 1490.5230326295482, 1504.247876061969, 1532.6245069957931, 1539.4227164813194, 1548.233624355951, 19970.90014549918, 23116.19093331725, 23379.415514612272, 23478.530429390976, 23530.647040294753, 36097.09757255985, 36110.63889361178, 36129.45805812862, 36182.27635447329, 36208.63791362053, 274716.0, 276239.0, 277027.2297277082, 277150.6142469275, 277408.0, 35069.47395789116, 35279.76520821759, 35357.0, 35361.469577956865, 35429.937101886986, 42698.0, 42737.358939616555, 43034.56965430431, 44725.10549789051, 44769.32846007247, 30085.3982920336, 30597.0, 30688.232794179432, 30840.38319233558, 31102.844485777427, 30091.849540752155, 30470.695293046785, 30522.0, 30643.560425598924, 30912.69087309098, 35985.74049908241, 36223.0, 36583.26833463369, 36622.0, 36964.0, 30101.69898300989, 30320.938767143045, 30728.846355768077, 30929.845350774, 30960.53559196659, 22823.0, 23161.0, 24030.63954040656, 24034.0, 24280.392990175387], "value": 17400.042877121945, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [405.19770853709656, 405.83969332113816, 422.1872894677746, 422.2947677378777, 447.1905850410757, 22031.33905982821, 22829.4292642684, 22878.741669208186, 22896.427589310275, 22898.542029159406, 20385.796142038576, 20815.895920520376, 23781.643275350885, 24794.0, 24813.0, 5395.81114660985, 5931.110333449996, 6167.629942203482, 6198.1632479615355, 6201.782937597185, 6090.634561926277, 6090.817275481742, 6130.724117414727, 6143.569950103494, 6154.84612884677, 6090.116933044706, 6099.054646529795, 6103.206583144207, 6120.846978825521, 6135.846603834895, 4735.195016847127, 5773.942260577403, 5791.710414479259, 5819.272590926134, 5827.970860145714, 5211.7133557654315, 5212.921806172911, 5219.399769026534, 5224.268602395668, 5231.267622532849, 28732.712672873422, 28817.991370301937, 29160.270993225127, 29173.708262917524, 29205.561916571267, 1984.345166095185, 2230.408941630469, 2266.5353602511445, 2269.807066399358, 2271.693321401606, 17808.643827123513, 18644.533886652807, 18822.62354752911, 18828.0, 18874.52813679669, 2145.5279838435517, 2185.191479152724, 2206.3270702435752, 2215.213599172303, 2227.899744511501, 148402.51597483995, 154544.0, 172467.0, 173977.0, 177497.22502775065, 12693.301868397142, 13008.479660813651, 13059.28173950423, 13062.216267024009, 13222.338883055809, 16609.501714948572, 16639.41762038323, 16845.747313790303, 16847.83152168487, 16880.0, 8872.8669069964, 9004.189622933967, 9010.684626038057, 9063.365564410427, 9073.682421115229, 8490.235878770816, 8508.957455212685, 8531.488110713377, 8546.957265213634, 8611.095834937241, 7984.920150798418, 8141.307988821017, 8231.341612124554, 8286.917130828735, 8295.129011453831, 2108.9894550527456, 2182.3125715399597, 2198.3404978506383, 2205.2722601541454, 2205.9889700551394, 955.8518429643416, 961.7018724195382, 966.9226461883032, 990.64336838738, 1011.8026984738052, 18108.00405977684, 21067.68398474024, 21918.45203869884, 22216.913283462465, 22284.77715222827, 1014.4572653630272, 1020.4795554267378, 1039.5166247694708, 1040.7398150462384, 1048.6172547020283, 10337.74155646115, 10416.32293900894, 10536.157107431387, 11449.885501144881, 11545.422728863523, 533494.0, 537003.0, 540175.0, 541497.0, 544705.0, 101837.0, 137573.0, 149869.0, 151396.0, 151611.24194379177, 999.8751404669747, 1018.8130827585927, 1162.627959053112, 1184.7452797648548, 1196.4257156564897, 22542.4364390885, 22756.772432275466, 23381.0, 23410.829458526663, 23456.882715586315, 63999.0, 65992.0, 66145.0, 66572.0, 66679.33320666732, 87172.56413717891, 87252.56373718091, 87659.0, 88319.0, 88385.55807220923, 13096.476140954255, 15273.847261527242, 16503.91748041252, 16578.50264492067, 16636.334546618, 15282.541523754306, 16558.66882662364, 18040.548986274924, 18137.36519221821, 18159.09204539794, 14862.777058343918, 14868.702625947204, 15046.54860354191, 15055.623609409853, 15167.089974601558, 13400.262985535888, 13402.798958015443, 13551.72896542044, 13790.65523361924, 14045.227512486912, 15069.47256846005, 15387.461438849587, 15466.690666186389, 15570.221488925281, 15650.45223417175, 16223.0, 16557.006579604782, 16656.583585410463, 16805.663886722443, 16806.243719032427, 14585.343659535134, 14741.410343586136, 14824.481143159937, 14824.703505930036, 14885.851141488447], "value": 11362.943621943406, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1293.4567481657703, 1389.3608939887652, 1436.9223082687977, 1451.1728314860534, 1473.697891932154, 11770.646880593586, 11966.940165299162, 12146.757064858697, 12205.633830985074, 12335.198212116205, 11966.820497692519, 12173.45219465124, 12175.20861144025, 12178.81731774024, 12192.268463892173, 2507.247825652302, 2542.1229675761924, 2545.440003199293, 2553.374423266293, 2560.1807421625085, 2480.131953816168, 2526.216872769438, 2529.140092368594, 2529.53203657324, 2536.264483299841, 2494.914712100233, 2505.799536037113, 2509.77412032917, 2521.710003349611, 2521.9117330893423, 2467.087177744237, 2483.0688491815567, 2486.0552989863736, 2490.2404766546197, 2496.4757400945823, 1991.382671371865, 2078.262216912989, 2406.0856874387796, 2465.93964595224, 2511.045802595002, 11535.788742182118, 11635.011024062966, 11652.941735291353, 11665.24175928562, 11695.35675537845, 6449.806505804833, 6625.138731964862, 6670.666466676646, 6671.099401580773, 6676.732930682767, 3959.208158368309, 4060.3503439449546, 4308.547602501723, 4314.158739045887, 4337.110892267109, 1252.4364036183754, 1255.7237407770276, 1256.654420034498, 1279.0918447902002, 1291.373683763374, 15353.07881527112, 15773.763393549221, 16397.42609008691, 16549.25528351215, 16565.42021029258, 5588.944110558924, 5636.718164091779, 5813.244278243886, 5872.794452194153, 5890.4698577128265, 12085.818712719316, 12310.0, 12375.566855160027, 12380.380980950917, 12401.503939842305, 6513.772017979348, 6568.737250510022, 6583.934160658332, 6597.571157874723, 6606.504512161511, 5986.281646202484, 6138.324784273726, 6205.720742566596, 6207.255129384503, 6223.253209614784, 4375.868723938287, 4440.289553671397, 4473.194824931544, 4528.343390208402, 4547.226971414867, 1125.499152876962, 1209.9455524501418, 1233.80259158536, 1291.7222797098668, 1322.5569434239444, 710.4316546762569, 788.7633709887011, 789.8025493626593, 813.6989313953875, 815.4780940198278, 6244.229795598254, 6285.937140628626, 6378.330275321025, 6440.61356318613, 6469.805905822833, 587.7061469265367, 591.1782622155208, 592.6769910398843, 602.0848310567912, 602.8726281852876, 2986.7140653162346, 3803.923921521554, 4389.1660584488845, 4455.554444555583, 4550.158220729176, 11972.341521216242, 15040.473583424528, 15275.701565367068, 15298.464553740565, 15364.615884602974, 15052.698946021237, 15205.163715995726, 15277.694446111016, 15280.388784448498, 15287.61780955485, 8460.957695211484, 8552.657893684183, 8555.486670799772, 8578.18507241811, 8734.650613975497, 6262.530310226843, 6735.191776986793, 6812.761553345609, 7226.349628533457, 7295.8540829184185, 12934.482620695067, 13442.93278533601, 13445.731085378433, 13557.728845422838, 13609.727805444034, 13760.724785504433, 13861.4455421782, 13873.653158671113, 14025.649358765704, 14054.086484378482, 8605.483670979762, 8631.0, 8638.222559969632, 8702.303815694602, 8733.30133589324, 10638.946805265925, 10678.359298442118, 10694.304870183412, 10763.246572739834, 10777.784444310913, 9089.909100908906, 9149.817003659757, 9234.815303694022, 9245.02927192635, 9457.763555911157, 8885.06706795777, 9057.09429057089, 9265.305102117503, 9361.204297634506, 9370.297227707813, 8048.839023219386, 8063.435559510778, 8104.918950810416, 8193.09875913661, 8201.13888041747, 5862.706864656835, 6040.818775436744, 6099.634021958696, 6152.6000809949, 6214.25428948529, 6073.665948372882, 6125.326214116532, 6135.601185923079, 6200.534959877937, 6222.222222222223], "value": 5323.367060225338, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [393.9369700847864, 395.81594558530276, 401.85734064407114, 403.36671425861374, 403.5328908977618, 5351.0, 5803.825885223439, 6093.177421048157, 6420.293767685556, 6478.4817214622835, 5820.0, 6050.334463209044, 6223.408776166269, 6268.247810262775, 6298.842528936779, 4603.217453032992, 4611.7924693388795, 4618.5381461853885, 4645.140648979949, 4663.743494107823, 3894.9805250973845, 4607.746573938432, 4617.0, 4624.491305956342, 4650.7442090685, 3787.791671458069, 4580.541945805409, 4581.40441742573, 4588.954110458886, 4591.334256532801, 3818.694504439638, 4409.809351475116, 4587.954120458786, 4589.534901149644, 4608.585227329523, 3334.9666503334897, 3739.6821270192067, 4003.459532963042, 4004.0, 4022.73852199609, 5386.892262154735, 5897.523040323074, 6221.3156552779155, 6230.781922632731, 6243.157173781527, 4675.602573781232, 5512.0, 5541.307336582927, 5542.5565954723525, 5546.55627549795, 5924.289085309747, 6849.383555480031, 6854.280300568418, 6873.278305777922, 6887.862242755167, 4544.931826022612, 4546.954530454686, 4563.612092972101, 4583.679142460029, 4595.126925884071, 5868.09044598088, 6604.834879128012, 6779.186497620317, 7129.928700712979, 7233.240509746453, 4478.081993191388, 4529.682922195415, 4544.227481328182, 4588.0, 4600.815967361268, 3741.4387841823946, 4452.732836029849, 4653.139169253631, 4694.2723877799, 4699.412573428322, 5158.252053452228, 5172.793088276502, 5295.947040529545, 5443.836684899459, 5453.972730136324, 5629.774809007594, 6764.526483146231, 6774.627395493295, 6799.354061364163, 6816.897746533806, 1592.3948899418147, 1604.181867247712, 1604.3101466369574, 1606.377558665756, 1610.1788088075166, 1027.3476342522551, 1046.7697106636529, 1063.4204358624568, 1063.8936106389453, 1068.5832525315134, 6339.4611458025665, 6351.936480635227, 6371.39471750183, 6377.64922929234, 6394.360563943662, 5501.724913754297, 5859.941400586024, 6154.630722156685, 6947.0, 7041.718331266795, 6281.618044030306, 6286.214223222098, 6307.653079080603, 6308.653024083719, 6331.176946996861, 3631.9818400908357, 3663.8900832975055, 3672.889813305605, 3684.7604905681583, 3689.94465083024, 6200.906986395207, 6226.315105338409, 7135.286471352823, 7156.141263048467, 7159.67781449836, 7107.39587135089, 7179.210286868441, 7195.316444937723, 7195.640217989079, 7215.278472152848, 6135.693215339215, 6244.469220116341, 7196.280371962762, 7223.927760722326, 7252.601106939274, 6813.18241810966, 7007.789766307019, 7025.227225005143, 7134.785956421316, 7136.250693677297, 5208.479152084761, 5220.9738951305, 6806.719748883981, 6907.447404207752, 6940.618265995418, 6682.766103186365, 6759.695813688298, 6873.621950792554, 7085.645717713991, 7097.716091356495, 3614.3855544556964, 4108.787907567215, 4605.815767369268, 4618.6536009800075, 4658.37111989877, 6678.265390806911, 7002.859942801014, 7021.648917554204, 7071.605629297863, 7092.645367731696, 6806.897896531655, 6819.5908245505425, 6821.965890170517, 6849.623270720158, 6861.656917154222, 6785.185777706707, 6804.380313579209, 6808.365992161945, 6810.489213309121, 6815.556988795713, 5649.85875353103, 6616.503762217949, 6661.233958094881, 6793.184817821893, 6893.93106068953, 6749.797506074629, 6798.388145066968, 6816.556923799937, 6822.283660215606, 6826.726930922708, 6609.636469994197, 6617.0, 6632.933670663231, 6635.23694775107, 6675.0], "value": 4896.410285337021, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [728.763151975608, 795.1571334385552, 813.6013353456808, 850.1625898489987, 860.2601762484262, 16406.0, 16937.237824297907, 16938.491845244647, 16971.236294366754, 16974.0, 12686.492540298377, 15061.77407338888, 15472.690546189098, 15977.520674379788, 16421.42525011625, 964.218982624075, 984.9113579777819, 986.9703908882709, 988.1650005745134, 990.7374545745383, 852.4629483425464, 968.6561270748888, 997.615917871621, 999.9250056245811, 1000.2348203624233, 1057.0, 1069.4759567811755, 1069.5133714160077, 1069.6737495064003, 1070.6520380876207, 1058.6929790360787, 1064.9520771565299, 1065.6802959112274, 1067.092970974673, 1067.6690226029955, 777.7005852746677, 785.7014334552833, 786.9488483248571, 787.0594639405855, 787.2049230277404, 17168.91415542927, 17687.73468397975, 17695.911520442445, 17770.466885993443, 17861.553461163447, 17801.02094384808, 17828.643427131516, 18030.63938721231, 18118.365857194935, 18167.909160454114, 14358.0, 14842.109473431628, 14849.183294918776, 14908.776368354484, 14956.850431495655, 3566.91082722934, 3602.0, 3632.56409230894, 3634.0, 3683.2633473305445, 142889.8566521503, 143298.0, 144634.27682861625, 145790.0, 146837.26581367024, 13593.184408935298, 13689.247091410069, 13704.45182192721, 13812.171269723849, 13902.304884755924, 26082.608760868603, 26355.736442635713, 26705.0, 26742.465150696877, 26795.0, 7874.960625196837, 7883.2905038546805, 7941.682332706742, 7953.363730901514, 7980.600969951595, 3535.1869070114017, 3611.5485564304463, 3611.7832930024283, 3615.8192090395373, 3620.2940426616556, 4352.82588696455, 4420.115976804587, 4425.159219748238, 4446.199684056836, 4471.329300604935, 859.7721603775004, 885.198895000026, 886.4548302793864, 890.6615486115235, 891.153404265937, 650.1580453312971, 660.8347913021745, 670.9261981182066, 672.653583404547, 681.111149949311, 10511.421871797125, 10807.729806754736, 11788.764224715458, 12278.693032674255, 12355.567555135527, 621.6448143048199, 653.3923451190439, 665.5008743442418, 684.7569112964925, 693.8646963842103, 4342.240107981153, 5106.770195341219, 5387.703676297841, 5398.541124004425, 5939.049752039653, 129382.0, 129506.70493295135, 131933.0, 132123.33938330246, 133197.0, 67517.0, 117057.0, 117575.0, 118261.0, 120272.39863800626, 67093.0, 72153.55692886113, 82013.0, 89165.55417222873, 108469.45765271124, 10650.946745266534, 12438.751224975269, 12447.253164809777, 12621.558245461365, 12705.317856929436, 42769.0, 43122.78438607787, 43147.0, 43266.56733432625, 43650.69047928567, 65374.0, 78594.0, 81026.18973810186, 83766.0, 85465.0, 16995.915020424818, 17001.574960626083, 17268.395606153725, 17307.740383894, 17323.913380433016, 17903.10484475797, 18154.636907261516, 18515.444536663384, 18693.252269909597, 18697.71953420727, 16439.67120657604, 17719.29122835072, 17767.02281374485, 17792.46622601324, 17876.463706088838, 14357.497487587885, 16754.4135955241, 17822.287108515513, 17827.821721783133, 17894.0, 13600.0, 16702.248398822325, 16928.83071169322, 17267.0, 17409.129543523028, 9601.903980960102, 11773.411329433666, 12161.69595760113, 12191.390430478617, 12244.0, 12392.938035309766, 13723.725525489634, 13758.862411376158, 13860.168389896638, 13973.510927117502], "value": 8657.985679660012, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1523.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 116.0, 116.0, 115.0, 115.0, 115.0, 115.0, 115.0, 114.0, 106.0], "value": 112.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 106.0, 115.0, 147.0, 110.0, 129.0, 133.0, 144.0, 157.0, 138.0], "value": 132.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 201.0, 200.0, 208.0, 213.0, 217.0, 235.0, 199.0, 202.0, 200.0], "value": 207.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 60.0, 92.0, 97.0, 81.0, 81.0, 87.0, 65.0, 78.0, 59.0], "value": 78.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 69.0, 82.0, 71.0, 70.0, 73.0, 88.0, 70.0, 69.0, 90.0], "value": 76.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 92.0, 92.0, 94.0, 93.0, 93.0, 93.0, 101.0, 92.0], "value": 93.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 129.0, 98.0, 99.0, 99.0, 100.0, 99.0, 99.0, 99.0, 102.0], "value": 107.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 94.0, 90.0, 92.0, 96.0, 90.0, 90.0, 95.0, 91.0, 90.0], "value": 91.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 48.0, 50.0, 49.0, 52.0, 53.0, 53.0, 52.0, 53.0, 52.0], "value": 51.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 60.0, 59.0, 66.0, 65.0, 76.0, 79.0, 57.0, 58.0, 57.0], "value": 63.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 89.0, 83.0, 81.0, 103.0, 82.0, 90.0, 89.0, 122.0, 88.0], "value": 92.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 129.0, 132.0, 136.0, 156.0, 154.0, 167.0, 150.0, 149.0, 174.0], "value": 148.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 189.0, 185.0, 189.0, 191.0, 195.0, 187.0, 213.0, 240.0, 238.0], "value": 200.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 64.0, 65.0, 67.0, 65.0, 65.0, 68.0, 65.0, 76.0], "value": 67.2, "unit": "score"}]}]} 04:05:48 INFO - Return code: 0 04:05:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:05:48 INFO - # TBPL SUCCESS # 04:05:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:05:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:05:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:05:48 INFO - 2017-05-31 04:05:48.095 screenresolution[2452:36667] starting screenresolution argv=screenresolution get 04:05:48 INFO - 2017-05-31 04:05:48.097 screenresolution[2452:36667] Display 0: 1600x1200x32@60 04:05:48 INFO - 2017-05-31 04:05:48.103 screenresolution[2453:36670] starting screenresolution argv=screenresolution list 04:05:48 INFO - Available Modes on Display 0 04:05:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:05:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:05:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:05:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:05:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:05:48 INFO - Intel Iris: 04:05:48 INFO - Chipset Model: Intel Iris 04:05:48 INFO - Type: GPU 04:05:48 INFO - Bus: Built-In 04:05:48 INFO - VRAM (Dynamic, Max): 1536 MB 04:05:48 INFO - Vendor: Intel (0x8086) 04:05:48 INFO - Device ID: 0x0a2e 04:05:48 INFO - Revision ID: 0x0009 04:05:48 INFO - Displays: 04:05:48 INFO - Display: 04:05:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:05:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:05:48 INFO - Display Serial Number: 1600x1200 60 04:05:48 INFO - Main Display: Yes 04:05:48 INFO - Mirror: Off 04:05:48 INFO - Online: Yes 04:05:48 INFO - Rotation: Supported 04:05:48 INFO - Return code: 0 04:05:48 INFO - Running post-action listener: _resource_record_post_action 04:05:48 INFO - [mozharness: 2017-05-31 11:05:48.338135Z] Finished run-tests step (success) 04:05:48 INFO - Running post-run listener: _resource_record_post_run 04:05:48 INFO - Total resource usage - Wall time: 1257s; CPU: 53.0%; Read bytes: 1868288; Write bytes: 568034816; Read time: 18; Write time: 2640 04:05:48 INFO - TinderboxPrint: CPU usage
53.2% 04:05:48 INFO - TinderboxPrint: I/O read bytes / time
1,868,288 / 18 04:05:48 INFO - TinderboxPrint: I/O write bytes / time
568,034,816 / 2,640 04:05:48 INFO - TinderboxPrint: CPU idle
2,354.9 (46.8%) 04:05:48 INFO - TinderboxPrint: CPU system
1,016.5 (20.2%) 04:05:48 INFO - TinderboxPrint: CPU user
1,658.3 (33.0%) 04:05:48 INFO - TinderboxPrint: Swap in / out
247,529,472 / 0 04:05:48 INFO - install - Wall time: 23s; CPU: 55.0%; Read bytes: 230715904; Write bytes: 229799936; Read time: 17662; Write time: 422 04:05:48 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 04:05:48 INFO - run-tests - Wall time: 1235s; CPU: 53.0%; Read bytes: 1868288; Write bytes: 319770112; Read time: 18; Write time: 2115 04:05:48 INFO - Running post-run listener: _upload_blobber_files 04:05:48 INFO - Blob upload gear active. 04:05:48 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:05:48 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 04:05:48 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'] 04:05:48 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 04:05:49 INFO - (blobuploader) - INFO - Open directory for files ... 04:05:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:05:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:05:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:05:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:05:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:05:49 INFO - (blobuploader) - INFO - Done attempting. 04:05:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:05:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:05:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:05:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:05:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:05:50 INFO - (blobuploader) - INFO - Done attempting. 04:05:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:05:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:05:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:05:51 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:05:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:05:51 INFO - (blobuploader) - INFO - Done attempting. 04:05:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:05:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:05:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:05:53 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:05:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:05:53 INFO - (blobuploader) - INFO - Done attempting. 04:05:53 INFO - (blobuploader) - INFO - Iteration through files over. 04:05:53 INFO - Return code: 0 04:05:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:05:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:05:53 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2da376663fea8c5bb8f56530bfdc3d330d6f3f7d3fc5b3577f0ab6e63fb13f316f1ddb9bd020e02a5c8da4a940474d8108fa70d89e078dd43ee250759dc7d345", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f9cc4f49b8f10f1c710167c85c17b2cc7bad4f61a08fc59ce7619ae860f3f6e9083392a751f86fe1e454bed4e1bad5742fe1b25c168f5f860f847d154930e52f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5c4c974f888260ab30a31b8192275c3048034abeb59f8ee16d1fb125a18986511a6f0e9bad43131d8b1e00697e4f0f0edf272fc7d5ec0feba3d901bca853a591", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ea1571f9db0cd0e3c5d1bed11d58a7a3700fc805494a491942222b649d56b69805ef6df1f555cf7b8ba7aa0d1715c3046f8d41719ab35a77b256ea95f5aa3c63"} 04:05:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:05:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:05:53 INFO - Contents: 04:05:53 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2da376663fea8c5bb8f56530bfdc3d330d6f3f7d3fc5b3577f0ab6e63fb13f316f1ddb9bd020e02a5c8da4a940474d8108fa70d89e078dd43ee250759dc7d345", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f9cc4f49b8f10f1c710167c85c17b2cc7bad4f61a08fc59ce7619ae860f3f6e9083392a751f86fe1e454bed4e1bad5742fe1b25c168f5f860f847d154930e52f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5c4c974f888260ab30a31b8192275c3048034abeb59f8ee16d1fb125a18986511a6f0e9bad43131d8b1e00697e4f0f0edf272fc7d5ec0feba3d901bca853a591", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ea1571f9db0cd0e3c5d1bed11d58a7a3700fc805494a491942222b649d56b69805ef6df1f555cf7b8ba7aa0d1715c3046f8d41719ab35a77b256ea95f5aa3c63"} 04:05:53 INFO - Running post-run listener: copy_logs_to_upload_dir 04:05:53 INFO - Copying logs to upload dir... 04:05:53 INFO - mkdir: /builds/slave/test/build/upload/logs 04:05:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1337.386135 ========= master_lag: 0.26 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 17 secs) (at 2017-05-31 04:05:54.283002) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-31 04:05:54.286119) ========= 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.ez7XG78zRw/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.Yw2BtCHPrx/Listeners TMPDIR=/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/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/2da376663fea8c5bb8f56530bfdc3d330d6f3f7d3fc5b3577f0ab6e63fb13f316f1ddb9bd020e02a5c8da4a940474d8108fa70d89e078dd43ee250759dc7d345", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f9cc4f49b8f10f1c710167c85c17b2cc7bad4f61a08fc59ce7619ae860f3f6e9083392a751f86fe1e454bed4e1bad5742fe1b25c168f5f860f847d154930e52f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5c4c974f888260ab30a31b8192275c3048034abeb59f8ee16d1fb125a18986511a6f0e9bad43131d8b1e00697e4f0f0edf272fc7d5ec0feba3d901bca853a591", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ea1571f9db0cd0e3c5d1bed11d58a7a3700fc805494a491942222b649d56b69805ef6df1f555cf7b8ba7aa0d1715c3046f8d41719ab35a77b256ea95f5aa3c63"} build_url:https://queue.taskcluster.net/v1/task/LBkgda2FSVqBrn9Ey14C8g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010970 build_url: 'https://queue.taskcluster.net/v1/task/LBkgda2FSVqBrn9Ey14C8g/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/2da376663fea8c5bb8f56530bfdc3d330d6f3f7d3fc5b3577f0ab6e63fb13f316f1ddb9bd020e02a5c8da4a940474d8108fa70d89e078dd43ee250759dc7d345", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f9cc4f49b8f10f1c710167c85c17b2cc7bad4f61a08fc59ce7619ae860f3f6e9083392a751f86fe1e454bed4e1bad5742fe1b25c168f5f860f847d154930e52f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5c4c974f888260ab30a31b8192275c3048034abeb59f8ee16d1fb125a18986511a6f0e9bad43131d8b1e00697e4f0f0edf272fc7d5ec0feba3d901bca853a591", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ea1571f9db0cd0e3c5d1bed11d58a7a3700fc805494a491942222b649d56b69805ef6df1f555cf7b8ba7aa0d1715c3046f8d41719ab35a77b256ea95f5aa3c63"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-31 04:05:54.320293) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 04:05:54.320628) ========= 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.ez7XG78zRw/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.Yw2BtCHPrx/Listeners TMPDIR=/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/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.005514 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 04:05:54.371585) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-31 04:05:54.372110) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-31 04:05:54.372785) ========= ========= Total master_lag: 0.40 =========