builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0312 starttime: 1495816950.36 results: success (0) revision: 9104506c0a5eaac89b6c22c804c83ccee7d6e662 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-26 09:42:30.361274) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-26 09:42:30.361873) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-26 09:42:30.362203) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-26 09:42:30.388498) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 09:42:30.388880) ========= 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.kE8ViWXumf/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.08AtFBEVra/Listeners TMPDIR=/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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-26 09:42:30-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 66.0M=0s 2017-05-26 09:42:30 (66.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.140741 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 09:42:30.553100) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 09:42:30.553480) ========= 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.kE8ViWXumf/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.08AtFBEVra/Listeners TMPDIR=/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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.027830 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 09:42:30.603067) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-26 09:42:30.603508) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 9104506c0a5eaac89b6c22c804c83ccee7d6e662 --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 9104506c0a5eaac89b6c22c804c83ccee7d6e662 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.kE8ViWXumf/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.08AtFBEVra/Listeners TMPDIR=/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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-26 09:42:30,700 truncating revision to first 12 chars 2017-05-26 09:42:30,701 Setting DEBUG logging. 2017-05-26 09:42:30,701 attempt 1/10 2017-05-26 09:42:30,701 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/9104506c0a5e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-26 09:42:32,894 unpacking tar archive at: mozilla-central-9104506c0a5e/testing/mozharness/ program finished with exit code 0 elapsedTime=2.468247 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-26 09:42:33.093140) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-26 09:42:33.093476) ========= script_repo_revision: 9104506c0a5eaac89b6c22c804c83ccee7d6e662 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-26 09:42:33.093837) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-26 09:42:33.094109) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-26 09:42:33.113270) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 18 secs) (at 2017-05-26 09:42:33.113767) ========= /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.kE8ViWXumf/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.08AtFBEVra/Listeners TMPDIR=/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 09:42:33 INFO - MultiFileLogger online at 20170526 09:42:33 in /builds/slave/test 09:42:33 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 09:42:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:42:33 INFO - {'append_to_log': False, 09:42:33 INFO - 'base_work_dir': '/builds/slave/test', 09:42:33 INFO - 'blob_upload_branch': 'Firefox', 09:42:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:42:33 INFO - 'branch': 'Firefox', 09:42:33 INFO - 'buildbot_json_path': 'buildprops.json', 09:42:33 INFO - 'config_files': ('talos/mac_config.py',), 09:42:33 INFO - 'default_actions': ('clobber', 09:42:33 INFO - 'read-buildbot-config', 09:42:33 INFO - 'download-and-extract', 09:42:33 INFO - 'populate-webroot', 09:42:33 INFO - 'create-virtualenv', 09:42:33 INFO - 'install', 09:42:33 INFO - 'run-tests'), 09:42:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:42:33 INFO - 'download_minidump_stackwalk': True, 09:42:33 INFO - 'download_symbols': 'ondemand', 09:42:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:42:33 INFO - 'tooltool.py': '/tools/tooltool.py', 09:42:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:42:33 INFO - '/tools/misc-python/virtualenv.py')}, 09:42:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:42:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:42:33 INFO - 'gecko_profile': False, 09:42:33 INFO - 'gecko_profile_interval': 0, 09:42:33 INFO - 'installer_path': 'installer.exe', 09:42:33 INFO - 'log_level': 'info', 09:42:33 INFO - 'log_name': 'talos', 09:42:33 INFO - 'log_to_console': True, 09:42:33 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:42:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:42:33 INFO - 'opt_config_files': (), 09:42:33 INFO - 'pip_index': False, 09:42:33 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:42:33 INFO - 'cmd': ('bash', 09:42:33 INFO - '-c', 09:42:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:42:33 INFO - 'enabled': True, 09:42:33 INFO - 'halt_on_failure': False, 09:42:33 INFO - 'name': 'check_screen_resolution'},), 09:42:33 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:42:33 INFO - 'cmd': ('bash', 09:42:33 INFO - '-c', 09:42:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:42:33 INFO - 'enabled': True, 09:42:33 INFO - 'halt_on_failure': False, 09:42:33 INFO - 'name': 'check_screen_resolution'},), 09:42:33 INFO - 'run_cmd_checks_enabled': True, 09:42:33 INFO - 'suite': 'dromaeojs-e10s', 09:42:33 INFO - 'system_bits': '32', 09:42:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:42:33 INFO - 'title': 't-yosemite-r7-0312', 09:42:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:42:33 INFO - 'use_talos_json': True, 09:42:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:42:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:42:33 INFO - 'work_dir': 'build'} 09:42:33 INFO - [mozharness: 2017-05-26 16:42:33.320210Z] Running clobber step. 09:42:33 INFO - Running pre-action listener: _resource_record_pre_action 09:42:33 INFO - Running main action method: clobber 09:42:33 INFO - rmtree: /builds/slave/test/build 09:42:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:42:35 INFO - Running post-action listener: _resource_record_post_action 09:42:35 INFO - [mozharness: 2017-05-26 16:42:35.788115Z] Finished clobber step (success) 09:42:35 INFO - [mozharness: 2017-05-26 16:42:35.788391Z] Running read-buildbot-config step. 09:42:35 INFO - Running pre-action listener: _resource_record_pre_action 09:42:35 INFO - Running main action method: read_buildbot_config 09:42:35 INFO - Using buildbot properties: 09:42:35 INFO - { 09:42:35 INFO - "project": "", 09:42:35 INFO - "product": "firefox", 09:42:35 INFO - "who": "cbook@mozilla.com", 09:42:35 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 09:42:35 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 09:42:35 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 09:42:35 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 09:42:35 INFO - "stage_platform": "macosx64", 09:42:35 INFO - "basedir": "/builds/slave/test", 09:42:35 INFO - "buildnumber": 87, 09:42:35 INFO - "platform": "yosemite_r7", 09:42:35 INFO - "parent_task_id": "LkjDgFExQje5_3sCOTxpAQ", 09:42:35 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 09:42:35 INFO - "slavebuilddir": "test", 09:42:35 INFO - "taskId": "V8UJrZE8RmmlATqvi5BkmA", 09:42:35 INFO - "branch": "mozilla-central", 09:42:35 INFO - "script_repo_revision": "production", 09:42:35 INFO - "revision": "9104506c0a5eaac89b6c22c804c83ccee7d6e662", 09:42:35 INFO - "slavename": "t-yosemite-r7-0312", 09:42:35 INFO - "repo_path": "mozilla-central" 09:42:35 INFO - } 09:42:35 INFO - Finding installer, test and symbols from parent task. 09:42:35 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/V8UJrZE8RmmlATqvi5BkmA'}, attempt #1 09:42:36 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/V8UJrZE8RmmlATqvi5BkmA'}, attempt #1 09:42:36 INFO - Task dependencies: JhKoAWdxTz2SsDGqquamgw LkjDgFExQje5_3sCOTxpAQ 09:42:36 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LkjDgFExQje5_3sCOTxpAQ'}, attempt #1 09:42:37 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/LkjDgFExQje5_3sCOTxpAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:42:37 INFO - Set test_url: https://queue.taskcluster.net/v1/task/LkjDgFExQje5_3sCOTxpAQ/artifacts/public/build/test_packages.json 09:42:37 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/LkjDgFExQje5_3sCOTxpAQ/artifacts/public/build/target.crashreporter-symbols.zip 09:42:37 INFO - Running post-action listener: _resource_record_post_action 09:42:37 INFO - [mozharness: 2017-05-26 16:42:37.204118Z] Finished read-buildbot-config step (success) 09:42:37 INFO - [mozharness: 2017-05-26 16:42:37.204228Z] Running download-and-extract step. 09:42:37 INFO - Running pre-action listener: _resource_record_pre_action 09:42:37 INFO - Running main action method: download_and_extract 09:42:37 INFO - mkdir: /builds/slave/test/build/tests 09:42:37 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')]} 09:42:37 INFO - trying https://queue.taskcluster.net/v1/task/LkjDgFExQje5_3sCOTxpAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 09:42:37 INFO - Downloading https://queue.taskcluster.net/v1/task/LkjDgFExQje5_3sCOTxpAQ/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 09:42:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LkjDgFExQje5_3sCOTxpAQ/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 09:42:38 INFO - Downloaded 1325 bytes. 09:42:38 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 09:42:38 INFO - Using the following test package requirements: 09:42:38 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:42:38 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 09:42:38 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:42:38 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:42:38 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 09:42:38 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:42:38 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 09:42:38 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:42:38 INFO - u'jsshell-mac.zip'], 09:42:38 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:42:38 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 09:42:38 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:42:38 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:42:38 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 09:42:38 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:42:38 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 09:42:38 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:42:38 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 09:42:38 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:42:38 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 09:42:38 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:42:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LkjDgFExQje5_3sCOTxpAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:42:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LkjDgFExQje5_3sCOTxpAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:42:38 INFO - Fetch https://queue.taskcluster.net/v1/task/LkjDgFExQje5_3sCOTxpAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:42:39 INFO - Content-Length response header: 57626998 09:42:39 INFO - Bytes received: 57626998 09:42:58 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 09:42:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LkjDgFExQje5_3sCOTxpAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:42:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LkjDgFExQje5_3sCOTxpAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:42:58 INFO - Fetch https://queue.taskcluster.net/v1/task/LkjDgFExQje5_3sCOTxpAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:43:00 INFO - Content-Length response header: 57626998 09:43:00 INFO - Bytes received: 57626998 09:43:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LkjDgFExQje5_3sCOTxpAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 09:43:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LkjDgFExQje5_3sCOTxpAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:43:17 INFO - Fetch https://queue.taskcluster.net/v1/task/LkjDgFExQje5_3sCOTxpAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 09:43:18 INFO - Content-Length response header: 14411888 09:43:18 INFO - Bytes received: 14411888 09:43:18 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')]} 09:43:18 INFO - trying https://queue.taskcluster.net/v1/task/LkjDgFExQje5_3sCOTxpAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:43:18 INFO - Downloading https://queue.taskcluster.net/v1/task/LkjDgFExQje5_3sCOTxpAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 09:43:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LkjDgFExQje5_3sCOTxpAQ/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 09:43:20 INFO - Downloaded 67185326 bytes. 09:43:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LkjDgFExQje5_3sCOTxpAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:43:20 INFO - mkdir: /builds/slave/test/properties 09:43:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:43:20 INFO - Writing to file /builds/slave/test/properties/build_url 09:43:20 INFO - Contents: 09:43:20 INFO - build_url:https://queue.taskcluster.net/v1/task/LkjDgFExQje5_3sCOTxpAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:43:20 INFO - Running post-action listener: _resource_record_post_action 09:43:20 INFO - Running post-action listener: set_extra_try_arguments 09:43:20 INFO - [mozharness: 2017-05-26 16:43:20.889750Z] Finished download-and-extract step (success) 09:43:20 INFO - [mozharness: 2017-05-26 16:43:20.889844Z] Running populate-webroot step. 09:43:20 INFO - Running pre-action listener: _resource_record_pre_action 09:43:20 INFO - Running main action method: populate_webroot 09:43:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:43:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:43:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:43:20 INFO - '--tppagecycles', 09:43:20 INFO - '7'], 09:43:20 INFO - 'tests': ['tsvgm']}}, 09:43:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:43:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:43:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:43:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:43:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:43:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:43:20 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:43:20 INFO - 'talos_options': ['--disable-e10s'], 09:43:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:43:20 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:43:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:43:20 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:43:20 INFO - 'talos_options': ['--disable-e10s'], 09:43:20 INFO - 'tests': ['damp', 'tps']}, 09:43:20 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:43:20 INFO - 'tests': ['damp', 'tps']}, 09:43:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:43:20 INFO - 'tests': ['dromaeo_dom']}, 09:43:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:43:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:43:20 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 09:43:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 09:43:20 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 09:43:20 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 09:43:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:43:20 INFO - 'tests': ['a11yr', 09:43:20 INFO - 'ts_paint', 09:43:20 INFO - 'tpaint', 09:43:20 INFO - 'sessionrestore', 09:43:20 INFO - 'sessionrestore_no_auto_restore', 09:43:20 INFO - 'tabpaint']}, 09:43:20 INFO - 'other-e10s': {'tests': ['a11yr', 09:43:20 INFO - 'ts_paint', 09:43:20 INFO - 'tpaint', 09:43:20 INFO - 'sessionrestore', 09:43:20 INFO - 'sessionrestore_no_auto_restore', 09:43:20 INFO - 'tabpaint']}, 09:43:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:43:20 INFO - 'ts_paint', 09:43:20 INFO - 'tpaint', 09:43:20 INFO - 'sessionrestore', 09:43:20 INFO - 'sessionrestore_no_auto_restore', 09:43:20 INFO - 'tabpaint']}, 09:43:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:43:20 INFO - 'ts_paint', 09:43:20 INFO - 'tpaint', 09:43:20 INFO - 'sessionrestore', 09:43:20 INFO - 'sessionrestore_no_auto_restore', 09:43:20 INFO - 'tabpaint']}, 09:43:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:43:20 INFO - 'tests': ['a11yr', 09:43:20 INFO - 'ts_paint', 09:43:20 INFO - 'tpaint', 09:43:20 INFO - 'sessionrestore', 09:43:20 INFO - 'sessionrestore_no_auto_restore', 09:43:20 INFO - 'tabpaint']}, 09:43:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:43:20 INFO - 'tests': ['a11yr', 09:43:20 INFO - 'ts_paint', 09:43:20 INFO - 'tpaint', 09:43:20 INFO - 'sessionrestore', 09:43:20 INFO - 'sessionrestore_no_auto_restore', 09:43:20 INFO - 'tabpaint']}, 09:43:20 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 09:43:20 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 09:43:20 INFO - 'bloom_basic_ref']}, 09:43:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:43:20 INFO - 'tests': ['tsvgx', 09:43:20 INFO - 'tsvgr_opacity', 09:43:20 INFO - 'tart', 09:43:20 INFO - 'tscrollx', 09:43:20 INFO - 'cart', 09:43:20 INFO - 'tsvg_static']}, 09:43:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:43:20 INFO - 'tsvgr_opacity', 09:43:20 INFO - 'tart', 09:43:20 INFO - 'tscrollx', 09:43:20 INFO - 'cart', 09:43:20 INFO - 'tsvg_static']}, 09:43:20 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:43:20 INFO - 'talos_options': ['--disable-e10s'], 09:43:20 INFO - 'tests': ['tp5o']}, 09:43:20 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:43:20 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:43:20 INFO - 'talos_options': ['--disable-e10s', 09:43:20 INFO - '--xperf_path', 09:43:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:43:20 INFO - 'tests': ['tp5n']}, 09:43:20 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:43:20 INFO - 'talos_options': ['--xperf_path', 09:43:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:43:20 INFO - 'tests': ['tp5n']}}, 09:43:20 INFO - 'talos.zip': {'path': '', 09:43:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:43:20 INFO - Running post-action listener: _resource_record_post_action 09:43:20 INFO - [mozharness: 2017-05-26 16:43:20.911383Z] Finished populate-webroot step (success) 09:43:20 INFO - [mozharness: 2017-05-26 16:43:20.911523Z] Running create-virtualenv step. 09:43:20 INFO - Running pre-action listener: _resource_record_pre_action 09:43:20 INFO - Running main action method: create_virtualenv 09:43:20 INFO - Creating virtualenv /builds/slave/test/build/venv 09:43:20 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 09:43:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:43:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:43:21 INFO - Using real prefix '/tools/python27' 09:43:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:43:21 INFO - Installing distribute.............................................................................................................................................................................................done. 09:43:24 INFO - Installing pip.................done. 09:43:24 INFO - Return code: 0 09:43:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:43:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:43:24 INFO - Reading from file tmpfile_stdout 09:43:24 INFO - Output received: 09:43:24 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) 09:43:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:43:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:43:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:43:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:43:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:43:24 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 0x103515b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103519768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc132d7a6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103225d30>, '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 0x1031d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10321abe8>, '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.kE8ViWXumf/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.08AtFBEVra/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 09:43:24 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 09:43:24 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 09:43:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kE8ViWXumf/Render', 09:43:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:43:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:43:24 INFO - 'HOME': '/Users/cltbld', 09:43:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:43:24 INFO - 'LOGNAME': 'cltbld', 09:43:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:43:24 INFO - 'MOZ_NO_REMOTE': '1', 09:43:24 INFO - 'NO_EM_RESTART': '1', 09:43:24 INFO - 'PAGER': '/bin/cat', 09:43:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:43:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:43:24 INFO - 'PWD': '/builds/slave/test', 09:43:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:43:24 INFO - 'SHELL': '/bin/bash', 09:43:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.08AtFBEVra/Listeners', 09:43:24 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 09:43:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:43:24 INFO - 'USER': 'cltbld', 09:43:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:43:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:43:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:43:24 INFO - 'XPC_FLAGS': '0x0', 09:43:24 INFO - 'XPC_SERVICE_NAME': '0', 09:43:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:43:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:43:25 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 09:43:25 INFO - Cleaning up... 09:43:25 INFO - Return code: 0 09:43:25 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:43:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:43:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:43:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:43:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:43:25 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 0x103515b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103519768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc132d7a6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103225d30>, '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 0x1031d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10321abe8>, '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.kE8ViWXumf/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.08AtFBEVra/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 09:43:25 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 09:43:25 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 09:43:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kE8ViWXumf/Render', 09:43:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:43:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:43:25 INFO - 'HOME': '/Users/cltbld', 09:43:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:43:25 INFO - 'LOGNAME': 'cltbld', 09:43:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:43:25 INFO - 'MOZ_NO_REMOTE': '1', 09:43:25 INFO - 'NO_EM_RESTART': '1', 09:43:25 INFO - 'PAGER': '/bin/cat', 09:43:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:43:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:43:25 INFO - 'PWD': '/builds/slave/test', 09:43:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:43:25 INFO - 'SHELL': '/bin/bash', 09:43:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.08AtFBEVra/Listeners', 09:43:25 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 09:43:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:43:25 INFO - 'USER': 'cltbld', 09:43:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:43:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:43:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:43:25 INFO - 'XPC_FLAGS': '0x0', 09:43:25 INFO - 'XPC_SERVICE_NAME': '0', 09:43:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:43:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:43:25 INFO - Downloading/unpacking psutil>=3.1.1 09:43:25 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 09:43:25 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 09:43:25 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 09:43:25 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 09:43:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:43:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:43:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:43:27 INFO - Installing collected packages: psutil 09:43:27 INFO - Running setup.py install for psutil 09:43:27 INFO - building 'psutil._psutil_osx' extension 09:43:27 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 09:43:27 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 09:43:27 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 09:43:28 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 09:43:28 INFO - building 'psutil._psutil_posix' extension 09:43:28 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 09:43:28 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:43:28 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:43:28 INFO - ^ 09:43:28 INFO - 1 warning generated. 09:43:28 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 09:43:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:43:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:43:28 INFO - Successfully installed psutil 09:43:28 INFO - Cleaning up... 09:43:28 INFO - Return code: 0 09:43:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:43: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')]} 09:43:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:43: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')]} 09:43:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:43:29 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 0x103515b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103519768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc132d7a6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103225d30>, '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 0x1031d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10321abe8>, '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.kE8ViWXumf/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.08AtFBEVra/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 09:43:29 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 09:43:29 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 09:43:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kE8ViWXumf/Render', 09:43:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:43:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:43:29 INFO - 'HOME': '/Users/cltbld', 09:43:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:43:29 INFO - 'LOGNAME': 'cltbld', 09:43:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:43:29 INFO - 'MOZ_NO_REMOTE': '1', 09:43:29 INFO - 'NO_EM_RESTART': '1', 09:43:29 INFO - 'PAGER': '/bin/cat', 09:43:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:43:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:43:29 INFO - 'PWD': '/builds/slave/test', 09:43:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:43:29 INFO - 'SHELL': '/bin/bash', 09:43:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.08AtFBEVra/Listeners', 09:43:29 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 09:43:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:43:29 INFO - 'USER': 'cltbld', 09:43:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:43:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:43:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:43:29 INFO - 'XPC_FLAGS': '0x0', 09:43:29 INFO - 'XPC_SERVICE_NAME': '0', 09:43:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:43:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:43:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:43:29 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 09:43:29 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 09:43:29 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 09:43:29 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 09:43:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:43:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:43:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:43:31 INFO - Installing collected packages: mozsystemmonitor 09:43:31 INFO - Running setup.py install for mozsystemmonitor 09:43:31 INFO - Successfully installed mozsystemmonitor 09:43:31 INFO - Cleaning up... 09:43:31 INFO - Return code: 0 09:43:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:43: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')]} 09:43:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:43: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')]} 09:43:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:43: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', '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 0x103515b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103519768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc132d7a6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103225d30>, '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 0x1031d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10321abe8>, '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.kE8ViWXumf/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.08AtFBEVra/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 09:43: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:43: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 jsonschema==2.5.1 09:43:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kE8ViWXumf/Render', 09:43:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:43:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:43:31 INFO - 'HOME': '/Users/cltbld', 09:43:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:43:31 INFO - 'LOGNAME': 'cltbld', 09:43:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:43:31 INFO - 'MOZ_NO_REMOTE': '1', 09:43:31 INFO - 'NO_EM_RESTART': '1', 09:43:31 INFO - 'PAGER': '/bin/cat', 09:43:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:43:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:43:31 INFO - 'PWD': '/builds/slave/test', 09:43:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:43:31 INFO - 'SHELL': '/bin/bash', 09:43:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.08AtFBEVra/Listeners', 09:43:31 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 09:43:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:43:31 INFO - 'USER': 'cltbld', 09:43:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:43:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:43:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:43:31 INFO - 'XPC_FLAGS': '0x0', 09:43:31 INFO - 'XPC_SERVICE_NAME': '0', 09:43:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:43:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:43:31 INFO - Downloading/unpacking jsonschema==2.5.1 09:43:31 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 09:43:31 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 09:43:31 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 09:43:31 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 09:43:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:43:33 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:43:33 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 09:43:33 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 09:43:33 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 09:43:33 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 09:43:33 INFO - Downloading functools32-3.2.3-2.tar.gz 09:43:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:43:33 INFO - warning: no files found matching '*.txt' 09:43:33 INFO - no previously-included directories found matching 'build' 09:43:33 INFO - no previously-included directories found matching 'dist' 09:43:33 INFO - no previously-included directories found matching '.git*' 09:43:33 INFO - Installing collected packages: jsonschema, functools32 09:43:33 INFO - Running setup.py install for functools32 09:43:33 INFO - warning: no files found matching '*.txt' 09:43:33 INFO - no previously-included directories found matching 'build' 09:43:33 INFO - no previously-included directories found matching 'dist' 09:43:33 INFO - no previously-included directories found matching '.git*' 09:43:33 INFO - Successfully installed jsonschema functools32 09:43:33 INFO - Cleaning up... 09:43:33 INFO - Return code: 0 09:43:33 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:43: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')]} 09:43:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:43: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')]} 09:43:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:43: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', '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 0x103515b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103519768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc132d7a6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103225d30>, '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 0x1031d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10321abe8>, '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.kE8ViWXumf/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.08AtFBEVra/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 09:43: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:43: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 functools32==3.2.3-2 09:43:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kE8ViWXumf/Render', 09:43:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:43:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:43:33 INFO - 'HOME': '/Users/cltbld', 09:43:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:43:33 INFO - 'LOGNAME': 'cltbld', 09:43:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:43:33 INFO - 'MOZ_NO_REMOTE': '1', 09:43:33 INFO - 'NO_EM_RESTART': '1', 09:43:33 INFO - 'PAGER': '/bin/cat', 09:43:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:43:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:43:33 INFO - 'PWD': '/builds/slave/test', 09:43:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:43:33 INFO - 'SHELL': '/bin/bash', 09:43:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.08AtFBEVra/Listeners', 09:43:33 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 09:43:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:43:33 INFO - 'USER': 'cltbld', 09:43:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:43:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:43:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:43:33 INFO - 'XPC_FLAGS': '0x0', 09:43:33 INFO - 'XPC_SERVICE_NAME': '0', 09:43:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:43:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:43:34 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:43:34 INFO - Cleaning up... 09:43:34 INFO - Return code: 0 09:43:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:43:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:43:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:43:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:43:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:43:34 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 0x103515b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103519768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc132d7a6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103225d30>, '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 0x1031d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10321abe8>, '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.kE8ViWXumf/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.08AtFBEVra/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 09:43:34 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 09:43:34 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 09:43:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kE8ViWXumf/Render', 09:43:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:43:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:43:34 INFO - 'HOME': '/Users/cltbld', 09:43:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:43:34 INFO - 'LOGNAME': 'cltbld', 09:43:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:43:34 INFO - 'MOZ_NO_REMOTE': '1', 09:43:34 INFO - 'NO_EM_RESTART': '1', 09:43:34 INFO - 'PAGER': '/bin/cat', 09:43:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:43:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:43:34 INFO - 'PWD': '/builds/slave/test', 09:43:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:43:34 INFO - 'SHELL': '/bin/bash', 09:43:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.08AtFBEVra/Listeners', 09:43:34 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 09:43:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:43:34 INFO - 'USER': 'cltbld', 09:43:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:43:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:43:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:43:34 INFO - 'XPC_FLAGS': '0x0', 09:43:34 INFO - 'XPC_SERVICE_NAME': '0', 09:43:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:43:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:43:34 INFO - Downloading/unpacking blobuploader==1.2.4 09:43: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 09:43: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 09:43: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 09:43: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 09:43:35 INFO - Downloading blobuploader-1.2.4.tar.gz 09:43:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:43:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:43: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 09:43: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 09:43: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 09:43: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 09:43:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:43:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:43: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 09:43: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 09:43: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 09:43: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 09:43:36 INFO - Downloading docopt-0.6.1.tar.gz 09:43:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:43:36 INFO - Installing collected packages: blobuploader, requests, docopt 09:43:36 INFO - Running setup.py install for blobuploader 09:43:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:43:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:43:36 INFO - Running setup.py install for requests 09:43:37 INFO - Running setup.py install for docopt 09:43:37 INFO - Successfully installed blobuploader requests docopt 09:43:37 INFO - Cleaning up... 09:43:37 INFO - Return code: 0 09:43:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:43:37 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')]} 09:43:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:43:37 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')]} 09:43:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:43:37 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 0x103515b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103519768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc132d7a6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103225d30>, '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 0x1031d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10321abe8>, '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.kE8ViWXumf/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.08AtFBEVra/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 09:43:37 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 09:43:37 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 09:43:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kE8ViWXumf/Render', 09:43:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:43:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:43:37 INFO - 'HOME': '/Users/cltbld', 09:43:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:43:37 INFO - 'LOGNAME': 'cltbld', 09:43:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:43:37 INFO - 'MOZ_NO_REMOTE': '1', 09:43:37 INFO - 'NO_EM_RESTART': '1', 09:43:37 INFO - 'PAGER': '/bin/cat', 09:43:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:43:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:43:37 INFO - 'PWD': '/builds/slave/test', 09:43:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:43:37 INFO - 'SHELL': '/bin/bash', 09:43:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.08AtFBEVra/Listeners', 09:43:37 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 09:43:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:43:37 INFO - 'USER': 'cltbld', 09:43:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:43:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:43:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:43:37 INFO - 'XPC_FLAGS': '0x0', 09:43:37 INFO - 'XPC_SERVICE_NAME': '0', 09:43:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:43:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:43:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:43:37 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-aeliF9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:43:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:43:37 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-JmWtJt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:43:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:43:37 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-UpDc9I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:43:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:43:38 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-Sep_g2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:43:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:43:38 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-XMEPYF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:43:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:43:38 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-ntYMzB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:43:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:43:38 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-Tyg594-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:43:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:43:38 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-QItAXr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:43:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:43:38 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-ltNOnD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:43:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:43:38 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-DgdWln-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:43:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:43:38 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-beIUWb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:43:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:43:38 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-7F4Pwd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:43:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:43:39 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-eB90Jj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:43:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:43:39 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-4hOgSr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:43:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:43:39 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-HNjtR_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:43:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:43:39 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-PqCCfR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:43:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:43:39 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-Yh3VAc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:43:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:43:39 INFO - Running setup.py install for manifestparser 09:43:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:43:39 INFO - Running setup.py install for mozcrash 09:43:39 INFO - Running setup.py install for mozdebug 09:43:39 INFO - Running setup.py install for mozdevice 09:43:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:43:40 INFO - Running setup.py install for mozfile 09:43:40 INFO - Running setup.py install for mozhttpd 09:43:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:43:40 INFO - Running setup.py install for mozinfo 09:43:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:43:40 INFO - Running setup.py install for mozInstall 09:43:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:43:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:43:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:43:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:43:40 INFO - Running setup.py install for mozleak 09:43:40 INFO - Running setup.py install for mozlog 09:43:40 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:43:40 INFO - Running setup.py install for moznetwork 09:43:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:43:41 INFO - Running setup.py install for mozprocess 09:43:41 INFO - Running setup.py install for mozprofile 09:43:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:43:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:43:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:43:41 INFO - Running setup.py install for mozrunner 09:43:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:43:41 INFO - Running setup.py install for mozscreenshot 09:43:41 INFO - Running setup.py install for moztest 09:43:41 INFO - Running setup.py install for mozversion 09:43:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:43:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:43:42 INFO - Cleaning up... 09:43:42 INFO - Return code: 0 09:43:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:43:42 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')]} 09:43:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:43:42 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')]} 09:43:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:43:42 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 0x103515b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103519768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc132d7a6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103225d30>, '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 0x1031d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10321abe8>, '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.kE8ViWXumf/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.08AtFBEVra/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 09:43:42 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 09:43:42 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 09:43:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kE8ViWXumf/Render', 09:43:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:43:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:43:42 INFO - 'HOME': '/Users/cltbld', 09:43:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:43:42 INFO - 'LOGNAME': 'cltbld', 09:43:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:43:42 INFO - 'MOZ_NO_REMOTE': '1', 09:43:42 INFO - 'NO_EM_RESTART': '1', 09:43:42 INFO - 'PAGER': '/bin/cat', 09:43:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:43:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:43:42 INFO - 'PWD': '/builds/slave/test', 09:43:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:43:42 INFO - 'SHELL': '/bin/bash', 09:43:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.08AtFBEVra/Listeners', 09:43:42 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 09:43:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:43:42 INFO - 'USER': 'cltbld', 09:43:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:43:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:43:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:43:42 INFO - 'XPC_FLAGS': '0x0', 09:43:42 INFO - 'XPC_SERVICE_NAME': '0', 09:43:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:43:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:43:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:43:42 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-wKFQ4K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:43:42 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)) 09:43:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:43:42 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-VhIOL4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:43:42 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)) 09:43:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:43:42 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-l__mlg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:43:42 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)) 09:43:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:43:42 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-hUl4kC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:43:42 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)) 09:43:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:43:42 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-w2k3sN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:43:42 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)) 09:43:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:43:42 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-sKNIYg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:43:42 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)) 09:43:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:43:42 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-nHiZyj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:43:43 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)) 09:43:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:43:43 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-gAIdkA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:43:43 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)) 09:43:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:43:43 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-CUM5Ke-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:43:43 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)) 09:43:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:43:43 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-35EW46-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:43:43 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)) 09:43:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:43:43 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-Db0E3f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:43:43 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)) 09:43:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:43:43 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-zpUspl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:43:43 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)) 09:43:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:43:43 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-QzazsU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:43:43 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)) 09:43:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:43:43 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-fYGzRc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:43:43 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)) 09:43:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:43:43 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-JXvJlw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:43:43 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)) 09:43:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:43:43 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-7rYHJ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:43:44 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)) 09:43:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:43:44 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-Wd_HaF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:43:44 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)) 09:43:44 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)) 09:43:44 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)) 09:43:44 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)) 09:43:44 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)) 09:43:44 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)) 09:43:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:43:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:43:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:43:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:43:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:43:45 INFO - Downloading blessings-1.6.tar.gz 09:43:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:43:45 INFO - Installing collected packages: blessings 09:43:45 INFO - Running setup.py install for blessings 09:43:45 INFO - Successfully installed blessings 09:43:45 INFO - Cleaning up... 09:43:45 INFO - Return code: 0 09:43:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:43:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:43:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:43:46 INFO - Reading from file tmpfile_stdout 09:43:46 INFO - Current package versions: 09:43:46 INFO - blessings == 1.6 09:43:46 INFO - blobuploader == 1.2.4 09:43:46 INFO - docopt == 0.6.1 09:43:46 INFO - functools32 == 3.2.3-2 09:43:46 INFO - jsonschema == 2.5.1 09:43:46 INFO - manifestparser == 1.1 09:43:46 INFO - mozInstall == 1.12 09:43:46 INFO - mozcrash == 1.0 09:43:46 INFO - mozdebug == 0.1 09:43:46 INFO - mozdevice == 0.51 09:43:46 INFO - mozfile == 1.2 09:43:46 INFO - mozhttpd == 0.7 09:43:46 INFO - mozinfo == 0.9 09:43:46 INFO - mozleak == 0.1 09:43:46 INFO - mozlog == 3.4 09:43:46 INFO - moznetwork == 0.27 09:43:46 INFO - mozprocess == 0.25 09:43:46 INFO - mozprofile == 0.28 09:43:46 INFO - mozrunner == 6.13 09:43:46 INFO - mozscreenshot == 0.1 09:43:46 INFO - mozsystemmonitor == 0.3 09:43:46 INFO - moztest == 0.8 09:43:46 INFO - mozversion == 1.4 09:43:46 INFO - psutil == 3.1.1 09:43:46 INFO - requests == 1.2.3 09:43:46 INFO - wsgiref == 0.1.2 09:43:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:43:46 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')]} 09:43:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:43:46 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')]} 09:43:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:43:46 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 0x103515b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103519768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc132d7a6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103225d30>, '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 0x1031d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10321abe8>, '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.kE8ViWXumf/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.08AtFBEVra/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 09:43:46 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 09:43:46 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 09:43:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kE8ViWXumf/Render', 09:43:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:43:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:43:46 INFO - 'HOME': '/Users/cltbld', 09:43:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:43:46 INFO - 'LOGNAME': 'cltbld', 09:43:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:43:46 INFO - 'MOZ_NO_REMOTE': '1', 09:43:46 INFO - 'NO_EM_RESTART': '1', 09:43:46 INFO - 'PAGER': '/bin/cat', 09:43:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:43:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:43:46 INFO - 'PWD': '/builds/slave/test', 09:43:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:43:46 INFO - 'SHELL': '/bin/bash', 09:43:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.08AtFBEVra/Listeners', 09:43:46 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 09:43:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:43:46 INFO - 'USER': 'cltbld', 09:43:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:43:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:43:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:43:46 INFO - 'XPC_FLAGS': '0x0', 09:43:46 INFO - 'XPC_SERVICE_NAME': '0', 09:43:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:43:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:43:46 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)) 09:43:46 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)) 09:43:46 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)) 09:43:46 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)) 09:43:46 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)) 09:43:46 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)) 09:43:46 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)) 09:43:46 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)) 09:43:46 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)) 09:43:46 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 09:43: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 09:43: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 09:43: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 09:43: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 09:43:47 INFO - Downloading marionette_driver-1.4.0.tar.gz 09:43:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 09:43:47 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)) 09:43:47 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)) 09:43:47 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)) 09:43:47 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)) 09:43:47 INFO - Installing collected packages: marionette-driver 09:43:47 INFO - Running setup.py install for marionette-driver 09:43:48 INFO - Successfully installed marionette-driver 09:43:48 INFO - Cleaning up... 09:43:48 INFO - Return code: 0 09:43:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:43: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')]} 09:43:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:43: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')]} 09:43:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:43:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103515b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103519768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc132d7a6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103225d30>, '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 0x1031d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10321abe8>, '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.kE8ViWXumf/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.08AtFBEVra/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 09:43:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:43:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:43:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kE8ViWXumf/Render', 09:43:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:43:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:43:48 INFO - 'HOME': '/Users/cltbld', 09:43:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:43:48 INFO - 'LOGNAME': 'cltbld', 09:43:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:43:48 INFO - 'MOZ_NO_REMOTE': '1', 09:43:48 INFO - 'NO_EM_RESTART': '1', 09:43:48 INFO - 'PAGER': '/bin/cat', 09:43:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:43:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:43:48 INFO - 'PWD': '/builds/slave/test', 09:43:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:43:48 INFO - 'SHELL': '/bin/bash', 09:43:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.08AtFBEVra/Listeners', 09:43:48 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 09:43:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:43:48 INFO - 'USER': 'cltbld', 09:43:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:43:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:43:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:43:48 INFO - 'XPC_FLAGS': '0x0', 09:43:48 INFO - 'XPC_SERVICE_NAME': '0', 09:43:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:43:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:43:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:43:48 INFO - Cleaning up... 09:43:48 INFO - Return code: 0 09:43:48 INFO - Running post-action listener: _resource_record_post_action 09:43:48 INFO - Running post-action listener: _start_resource_monitoring 09:43:48 INFO - Starting resource monitoring. 09:43:48 INFO - [mozharness: 2017-05-26 16:43:48.394001Z] Finished create-virtualenv step (success) 09:43:48 INFO - [mozharness: 2017-05-26 16:43:48.394948Z] Running install step. 09:43:48 INFO - Running pre-action listener: _resource_record_pre_action 09:43:48 INFO - Running main action method: install 09:43:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:43:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:43:48 INFO - Reading from file tmpfile_stdout 09:43:48 INFO - Detecting whether we're running mozinstall >=1.0... 09:43:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:43:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:43:48 INFO - Reading from file tmpfile_stdout 09:43:48 INFO - Output received: 09:43:48 INFO - Usage: mozinstall [options] installer 09:43:48 INFO - Options: 09:43:48 INFO - -h, --help show this help message and exit 09:43:48 INFO - -d DEST, --destination=DEST 09:43:48 INFO - Directory to install application into. [default: 09:43:48 INFO - "/builds/slave/test"] 09:43:48 INFO - --app=APP Application being installed. [default: firefox] 09:43:48 INFO - mkdir: /builds/slave/test/build/application 09:43:48 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'] 09:43:48 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 09:44:09 INFO - Reading from file tmpfile_stdout 09:44:09 INFO - Output received: 09:44:09 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:44:09 INFO - Running post-action listener: _resource_record_post_action 09:44:09 INFO - [mozharness: 2017-05-26 16:44:09.944988Z] Finished install step (success) 09:44:09 INFO - [mozharness: 2017-05-26 16:44:09.945154Z] Running run-tests step. 09:44:09 INFO - Running pre-action listener: _resource_record_pre_action 09:44:09 INFO - Running main action method: run_tests 09:44:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:44:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:44:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:44:09 INFO - 2017-05-26 09:44:09.959 screenresolution[1622:7424] starting screenresolution argv=screenresolution get 09:44:09 INFO - 2017-05-26 09:44:09.962 screenresolution[1622:7424] Display 0: 1600x1200x32@60 09:44:09 INFO - 2017-05-26 09:44:09.967 screenresolution[1623:7427] starting screenresolution argv=screenresolution list 09:44:09 INFO - Available Modes on Display 0 09:44:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:44:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:44:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:44:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:44:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:44:10 INFO - Intel Iris: 09:44:10 INFO - Chipset Model: Intel Iris 09:44:10 INFO - Type: GPU 09:44:10 INFO - Bus: Built-In 09:44:10 INFO - VRAM (Dynamic, Max): 1536 MB 09:44:10 INFO - Vendor: Intel (0x8086) 09:44:10 INFO - Device ID: 0x0a2e 09:44:10 INFO - Revision ID: 0x0009 09:44:10 INFO - Displays: 09:44:10 INFO - Display: 09:44:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:44:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:44:10 INFO - Display Serial Number: 1600x1200 60 09:44:10 INFO - Main Display: Yes 09:44:10 INFO - Mirror: Off 09:44:10 INFO - Online: Yes 09:44:10 INFO - Rotation: Supported 09:44:10 INFO - Return code: 0 09:44:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:44:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:44:10 INFO - Python 2.7.3 09:44:10 INFO - Return code: 0 09:44:10 INFO - grabbing minidump binary from tooltool 09:44:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:44:10 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 0x103225d30>, '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 0x1031d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10321abe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:44:10 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 09:44:10 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 09:44:10 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 09:44:10 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:44:10 INFO - Return code: 0 09:44:10 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:44:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:44:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:44:10 INFO - ENV: RUST_BACKTRACE is now 1 09:44:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:44:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:44:10 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/LkjDgFExQje5_3sCOTxpAQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0312', '--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 09:44:10 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/LkjDgFExQje5_3sCOTxpAQ/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0312 --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 09:44:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kE8ViWXumf/Render', 09:44:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:44:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:44:10 INFO - 'HOME': '/Users/cltbld', 09:44:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:44:10 INFO - 'LOGNAME': 'cltbld', 09:44:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:44:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:44:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:10 INFO - 'MOZ_NO_REMOTE': '1', 09:44:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:44:10 INFO - 'NO_EM_RESTART': '1', 09:44:10 INFO - 'PAGER': '/bin/cat', 09:44:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:44:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:10 INFO - 'PWD': '/builds/slave/test', 09:44:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:44:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:44:10 INFO - 'RUST_BACKTRACE': '1', 09:44:10 INFO - 'SHELL': '/bin/bash', 09:44:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.08AtFBEVra/Listeners', 09:44:10 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 09:44:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:44:10 INFO - 'USER': 'cltbld', 09:44:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:44:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:44:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:10 INFO - 'XPC_FLAGS': '0x0', 09:44:10 INFO - 'XPC_SERVICE_NAME': '0', 09:44:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:44:10 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/LkjDgFExQje5_3sCOTxpAQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0312', '--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 09:44:10 INFO - mozversion application_buildid: 20170526080023 09:44:10 INFO - mozversion application_changeset: 9104506c0a5eaac89b6c22c804c83ccee7d6e662 09:44:10 INFO - mozversion application_display_name: Nightly 09:44:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:44:10 INFO - mozversion application_name: Firefox 09:44:10 INFO - mozversion application_remotingname: firefox 09:44:10 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:44:10 INFO - mozversion application_vendor: Mozilla 09:44:10 INFO - mozversion application_version: 55.0a1 09:44:10 INFO - mozversion platform_buildid: 20170526080023 09:44:10 INFO - mozversion platform_changeset: 9104506c0a5eaac89b6c22c804c83ccee7d6e662 09:44:10 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:44:10 INFO - mozversion platform_version: 55.0a1 09:44:10 INFO - using testdate: 1495817050 09:44:10 INFO - actual date: 1495817050 09:44:10 INFO - starting webserver on 'localhost:49226' 09:44:10 INFO - SUITE-START | Running 2 tests 09:44:10 INFO - TEST-START | dromaeo_css 09:44:10 INFO - operating with platform_type : mac_ 09:44:10 INFO - Initialising browser for dromaeo_css test... 09:44:10 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49226/getInfo.html -foreground -profile /var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/tmpP90ADX/profile 09:44:10 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49226/getInfo.html -foreground) 09:44:44 INFO - TEST-INFO | 1633: killed by SIGTERM 09:44:44 INFO - Browser initialized. 09:44:44 INFO - Running cycle 1/1 for dromaeo_css test... 09:44:44 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/tmpP90ADX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:44:46 INFO - PID 1643 | 2017-05-26 09:44:46.255 plugin-container[1644:7978] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 09:44:46 INFO - PID 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:44:46 INFO - PID 1643 | 2017-05-26 09:44:46.256 plugin-container[1644:7978] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d0b, name = 'com.apple.CFPasteboardClient' 09:44:46 INFO - PID 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:44:46 INFO - PID 1643 | 2017-05-26 09:44:46.256 plugin-container[1644:7978] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:44:46 INFO - PID 1643 | 2017-05-26 09:44:46.954 plugin-container[1645:8023] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x464b, name = 'com.apple.tsm.portname' 09:44:46 INFO - PID 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:44:46 INFO - PID 1643 | 2017-05-26 09:44:46.954 plugin-container[1645:8023] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 09:44:46 INFO - PID 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:44:46 INFO - PID 1643 | 2017-05-26 09:44:46.954 plugin-container[1645:8023] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:44:56 INFO - PID 1643 | RSS: Main: 242708480 09:44:56 INFO - PID 1643 | 09:47:57 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49226/tests/dromaeo/cssquery-ext.html) 09:47:59 INFO - PID 1643 | RSS: Main: 254824448 09:47:59 INFO - PID 1643 | 09:51:01 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-ext.html (next: http://localhost:49226/tests/dromaeo/cssquery-jquery.html) 09:51:02 INFO - PID 1643 | RSS: Main: 272642048 09:51:02 INFO - PID 1643 | 09:54:03 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49226/tests/dromaeo/cssquery-mootools.html) 09:54:04 INFO - PID 1643 | RSS: Main: 274186240 09:54:04 INFO - PID 1643 | 09:57:06 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49226/tests/dromaeo/cssquery-prototype.html) 09:57:06 INFO - PID 1643 | RSS: Main: 277905408 09:57:06 INFO - PID 1643 | 10:00:09 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49226/tests/dromaeo/cssquery-yui.html) 10:00:10 INFO - PID 1643 | RSS: Main: 277995520 10:00:10 INFO - PID 1643 | 10:03:11 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-yui.html (next: http://localhost:49226/tests/dromaeo/cssquery-dojo.html) 10:03:12 INFO - PID 1643 | RSS: Main: 279764992 10:03:12 INFO - PID 1643 | 10:03:12 INFO - PID 1643 | __start_tp_report 10:03:12 INFO - PID 1643 | _x_x_mozilla_page_load 10:03:12 INFO - PID 1643 | _x_x_mozilla_page_load_details 10:03:12 INFO - PID 1643 | |i|pagename|runs| 10:03:12 INFO - PID 1643 | |0;dojo.html;3056.5262384330435;3083.4603944309747;3580.301841140977;3606.999999999999;3630.1832087780267;6125.877482450348;6127.892078906831;6172.938270617293;6205.22434695663;6214.285357183925;6115.422885572139;6147.477464415519;6175.722092505836;6203.162573052637;6248.031555108954;5323.707196104213;5431.728413579325;5441.483059109388;5451.48210919962;5466.262054622634;5515.806946756864;5570.832875013736;5587.692676902769;5589.0219211638005;5614.578906582003;5385.192221166817;5446.673199608036;5460.535854452376;5499.615026948135;5569.777208911639;4687.39063921688;4924.212126059849;5461.15352120422;5494.835154945358;5540.722963851832;4635.026644404679;5099.3370861787735;5411.485908838694;5442.074847275972;5445.700486473243;4854.708717476928;6111.105587097678;6152.87694246117;6212.1303017577575;6224;6539.542232043759;6662.733490660367;6728.798136055877;6749.763758268437;6766.69549870257;6665.600063996176;6866.896996545056;6873.20958089816;6873.965630171867;6874.828129296758;6183.96908015457;6273.749050037993;6303;6306.274778400497;6332.620042797447;6812.386885180258;6843.076184715099;6890.965545172242;6923.273056329113;6978.371946524837;6154.7230374633245;6176.733769577226;6426.710798014101;6461;6697.665116744241;5574.275344205227;6686.465082793462;6824.078749368871;6833.965830170917;6835.931640683629;6689.163854518185;6728.125343705287;6747.898781518281;6750.3924646782025;6791.252962174157;6495.1231583736535;6581.243157036906;6645.568038077509;6687.565308254948;6809.080774095433;5393.78424863009;5393.919091213635;5414.91877621836;5426.077566813572;5443.646162999393;4765.451973023078;4796.160671882407;4797.064572408347;4823.252395878644;4824.155772739757;6105.175801266861;6183.227096612924;6258.311585725566;6260.123582698426;6297.527685423612;6872.518923675295;6887.380135787703;6890.345417185359;6894.551854129466;6895.827604309832;5534.861628459321;6273.7176827042895;6296.716647750771;6312.368763123651;6319.905201421982;5832.679202643895;5912.113183022495;5952.464278214981;6041.456268935729;6046.244219472566;6978.755743548952;7006.229314775371;7014.824629384306;7036.261192574706;7053.118360204974;6841.931580684329;6861.897071543831;6870.416014638811;6888.586684798727;6898.861687821654;6743.8988415172835;6835.555688880207;6869.347411995853;6948.270431604811;7054.541454805421;6620.073189753342;6687.899681504684;6712.160979877515;6717.1267735194115;6726.192856857208;6816.318368163144;6816.62508562014;6826.726930922906;6841.965790171017;6853.485988550979;6414.550981431255;6498.090267362668;6533.640649764118;6588.40704336612;6655.667216639245;6620.238672552526;6628.966855165693;6645.767398141042;6654.733810647714;6669.299723529154;6439;6499.1876015498065;6511.641859697762;6517.3808488193545;6546.639934803441;6398.71205795731;6584.604923704593;6662.83342916431;6679.365460281266;6685.832854178684;4623.560761727765;6299.6535190564955;6344.809655710336;6402.839929001812;6420.133282007057 10:03:12 INFO - PID 1643 | |1;ext.html;712.4870093532656;768.3699366519456;829.0880031964838;985.9359141655788;1026.4611079183433;39307.606923930754;39859;39873.40189897147;39950.600493995014;39994.8001559953;31953.840230798873;39274.41088383677;39567.208655826864;39659.80170099153;39727;7643.082830060386;8418.49489030657;8589.981354381714;8597.57012149393;8600.268977136919;8497.95751021247;8593.398462107654;8624.051354351017;8649;8651.740447786577;8609.913900861005;8654.09132041136;8656.567171641393;8658.696945606906;8660.566971651424;8602.87095693565;8622.65509379624;8630.395872288911;8652.264557512619;8695.130486951317;8357.498550087013;8404;8440.704575339894;8445.366597505212;8462.57687115648;30376.544351834742;37369;37507;37618;37677.811610942044;3163.8892638757534;3658.2866341063504;3676.9816150919073;3682.1346983458866;3689.3728066228805;30731.846340768152;30818.921337753072;30828.691713082808;30835.537466938014;30837.383252335054;3123.890663826778;3166.3825554016976;3179.9046028619177;3197.2486465680545;3199.2481766784786;239217;240896;271613.28386716277;308468;310990;47530.76234618805;47765.5223447768;47776.522234777905;47899.5210047902;48000.03999919982;50597.747011264706;50606.24090638643;50616.49383506118;50630.74684626627;50698;12442.37788110591;14318.928405357907;14351.569452916428;14412.639684007774;14439.92780036093;11732.12009099321;11753.177277590657;11759.823602645967;11787.587434439753;11808.94095529534;13776.724465510633;13786.310684465736;13818.792718109236;13887.027908046543;13986.16083035021;5949.970250148722;5996.490298324604;6021.567706407306;6022.789202377896;6040.486558642477;855.3328403845011;961.5000199896155;981.9444097595114;1071.4428497181423;1095.1348434736628;24377.756222437547;24751;24792.008319667373;24833.751662483144;24953.5009299813;1081.864766904137;1150.5800382860195;1170.104869774623;1178.8054970929873;1186.7389174381622;10941.56233750657;12179.634610961686;13463.663408414672;16595.50213493597;16603.6679266414;351076;353531;355919;355962;357091;58927.705361473774;59151;59279.70360148171;59374.40625593775;59735.701321493114;1486.4500134950138;1511.4029958166682;1549.1170033081262;1550.8294087650575;1554.7434673279008;29922.70077299199;30334.69665303319;30442.695573043988;31062.378752425277;31135.37729245357;39633.009174770865;39771;39904.60095399009;39990.80004599958;40159.1968160641;296539;299252;299577;300178;300473;34188.82905585556;36620.71827486025;37638.2472350546;38118.809405952794;38202.42696359501;47846.28230576475;48512.787180320774;48663.756681216364;49384.76538086576;50265;32245;32989.67010329866;33596.66403336033;33832.66167338295;34116.14709632279;33068.66931330656;33336;33808.830955846046;33816.83091584526;34024;41166.38250426186;41194.794026029675;41269.96825079397;41549.753507394824;41578.58421415868;29293.26766830846;31582.842085789423;33418.83034093795;34016.319673606886;34271.48592771061;23371.88314058419;23878.283651490485;26008.21975340743;26225.4754904897;26413.735862641126 10:03:12 INFO - PID 1643 | |2;jquery.html;406.0741509358663;426.94970372882705;435.9302511598143;439.3848611943279;451.9977400112999;21498.247561335338;21508.677369839446;22061.448463788412;22219.22232721855;22265.109395624175;22976.966036528356;23924.043038278447;23968.80155992202;24042.75957240427;24090.75909240907;6015.969920150393;6304.87390252197;6321.083442900776;6322.525810564203;6341.682915854189;6076.6961651917445;6170.845728856792;6182.598131121463;6187.226596675416;6214.751409943597;6166.321704612489;6182.319944806068;6187.041008643645;6195.2255968004;6205.565610407273;5862.794802181925;6129.693515324216;6160.722767475474;6162.876742465148;6168.1056246843955;5511.476409741068;5513.20058591506;5513.7794488220825;5514.255575497336;5516.613837031409;27544.449111017868;27815.443691126267;28696.42607147866;28817.855910720522;29000.854995725098;2182.90176942038;2187;2240.484688521633;2254.5941730488507;2255.909763609454;16485.752713709186;16920.153992300337;17061.65876682472;17128.657426851518;17135.571610709707;2130.2331160782105;2168.382011126837;2189.0477642225683;2194.3197608741343;2197.230969160781;164312.53531197042;164956;169541;171093;173002.1349893255;12581.433835477426;13103.279319637511;13134.146280491737;13137.474501020044;13142.211467311992;16366.099864507567;16509.33962641505;16551.337946482246;16577.17114144288;16581.83418165827;8878.644854205888;9104;9133;9152.496612686233;9268.629254829732;6481.8703625928165;7290.562566245936;8265.173482651808;8719.04090550039;8756.956215218883;6861.794146175623;7961.641726122339;8100.554469504234;8255.793605159919;8269.21442462965;1916.865819392657;2235.340574530517;2249.144455823952;2259.2657386349174;2435.9512809743705;919.6551293265026;958.2179326472937;962.1100482054072;965.4207475514635;966.3573723473811;18767.530811729815;18926.905365473085;21084;21342.253021144184;21385;976.2531663277598;998.3361064891766;999.3953658036928;1007.503856772692;1022.2789047865774;10901.672949811518;11126.443677816076;11161.888381116247;11183.944080279547;11184.384858832842;509766;516067;525669;548615;575094;135908;138086;138280;141573;142883.42423500007;1073.2969904712427;1119.6193294279983;1189.6193218170276;1199.70607201238;1202.6933132051272;18201.544961376072;18340.174692139153;18489.81510184881;18641.720374194127;18896.905515472336;51524.71188220324;55894.720526398734;57391.139132912205;60098.69950650219;60116.39883601108;63560.68219658872;67417.66291168513;77578;77976.83034754597;78387.82418263616;8764.079771624143;8767.079456656958;9005.864912026456;9073.727788166365;9144.63421463134;13895.37470813836;13945.790813137608;13957;14301.856981430468;14414.207218602658;10929.56281748721;11082.667519974413;11100.833487497855;11474.483648235682;11717.882821172021;10400.27198096126;10949.343039417661;11126.443677815914;11278.436078196222;11455.942720286344;10428.58285668595;11391.772164556829;11688.47401866935;11944.104192185794;12070.879291207328;8780.385373024083;8893.199612034949;9045.819083618158;9070.229030532479;9213.170814626448;12036.458359373673;12197.207181533173;12239.632811015685;12310.569130080405;12331 10:03:12 INFO - PID 1643 | |3;mootools.html;1352.5401363536398;1406.3179358011362;1409.9013069085156;1416.5183837495254;1417.8653027962346;11592.884071159286;12028.278303301808;12165.939170304147;12168.026557875372;12299;11980.880191198085;12133.514659413613;12154.635360939186;12159.088068394862;12162.574309899155;2317.7798109179603;2544.211294498702;2591.935201619956;2601.3366591519134;2615.8430494170316;2527.898884044636;2574.613807928816;2578.8065895057853;2580.0711743772226;2592.44262483566;2526.343150780801;2544.821862469628;2550.987245063772;2558.7057488388796;2561.1164148368784;2383.8569685818734;2460.372604985726;2466.4943686544307;2470.147799009339;2471.480988992314;2107.6100921329603;2192.8903554822195;2485.652008718781;2522.432452698147;2571.100114959768;11893.52425902963;11983.221090629082;11992.580259690956;12010.219335743148;12045.217060891015;6473.385028422339;6656.034874943154;6696.531242813005;6696.631685257309;6734.124563806674;4043.110515686544;4203.600657937491;4284.871453856389;4288.399624052635;4302.763348015857;1227.9324637144953;1245.9937700311532;1271.5676669932175;1273.5478904988688;1277.993610031944;16665.83334166655;16789.58026049346;16866.746998795028;16874.409395671093;16882.915585422117;5455.918161227585;5532.889342213214;5548.4174161713245;5656.5757568181725;5685.971570142206;12037.217580857216;12041.819372709417;12044.638660840188;12051.457684404222;12060.879391206152;6110.908336374958;6315.115883776275;6329.208848893888;6331.05034244867;6334.271558770708;5181.611379146504;5809.506191973645;5921.14143449206;5932.8813423732145;5995.400459953969;4313.180495705806;4352.978235108804;4457.77711114443;4465.486469056035;4504.166729155117;1094.3762055628215;1251.086706704111;1274.8533918599294;1285.832841730569;1287.0990306785288;671.318611609217;771.2210667226142;800.5796956597716;809.114020147937;811.6266517401943;6824.611386014954;6890.138732658418;7001.579905205602;7049.859002820018;7124.430045596444;926.1895841139003;950.3727539823822;964.3779762053437;966.120830044656;1003.7942221844651;3936.6653834423823;4252.128313695685;4391.604755572014;4493.236149854467;4551;13065.804012939814;15425.151616661191;15425.151616661191;15482.148481833383;15531;12766.17019893704;15158.242087895782;15357.462488812838;15416.537503874903;15474.458393956158;8491.448055876348;8533.061363250037;8606.26846718036;8611.6985905493;8635.266002389742;6666.633335166418;6812.250652428328;6901.1718593769065;6945.305469453015;7167.3907717842485;11381.829272561085;13865.376058077612;13982.230977296347;14116.717665646835;14276.357563909442;13579.79630305566;14163.929180354444;14451.92774036123;14471.49349772753;14623.707525849637;9386.06139386056;9444.480553569345;9541.522923853918;9552.092551207625;9592.376495527767;10766.300190487593;10929.890701093205;10944.78110437771;10945.398003109905;10972.561097556008;9078.81842363136;9179.862302065609;9233.538323083685;9376.29677774156;9455.527223638928;8901.866472002795;8956.596953136994;9023.684171053981;9095.454272743656;9184.311176661911;8169.55067471271;8199.344052475906;8246;8280.089190188954;8288.83422331538;5416.21464887597;6116.633002019893;6133.417325354084;6164.4760195382105;6209.751609935733;6127.785527506516;6248.843778905563;6259.561830671809;6340.175777148941;6359.459445947146 10:03:12 INFO - PID 1643 | |4;prototype.html;347.86607156244844;376.1348897535669;384.15294276121153;392.2194832283756;405.7889897253429;5195.830938038939;5272.235525848754;5570.220169176314;6092.817215483531;6262.874742505147;4774.641901857366;5991.89150007249;6120.479759220471;6149.538784591151;6150.600210986283;3853.807309634521;4474.217012022899;4475.082608065339;4479.440069991251;4483.439570053743;4400.669949753766;4438.245498265286;4441.313709621023;4485.573870482299;4500.894382875822;4370.97814510927;4395.450568678915;4427.712198707074;4428.180786554498;4433.379326894237;3675.044488433;4013.819378127991;4092.9386059209137;4199.832006719727;4412.205802955466;3460.8269586520823;3623.673869351771;3744.4196149596855;3813.2945405100145;3839.558450778168;4626.352310676508;4650.511696271877;5955.583109182359;5982.970085149546;6079.513638908877;4235.767032813194;4275.444192254987;4935.703857768545;5335.653182543122;5373.543248823855;6313.179286692701;6382.266039405481;6543.93456065438;6598.043283723833;6619.768308109193;4297.376880352363;4299.333603291464;4312.671157574906;4325.653947684209;4326.610605045529;6097.817065487998;6597.835054123638;6810.182778066614;6928.16861976561;6965.199002114771;3754.267917756066;4155.4182414462;4387.605115539617;4390.407295015132;4403.8018289176425;4460.7323560586465;4463.732176069446;4464.098336081573;4466.191192567016;4491.8203271869415;4642.814287428465;5217.58259339252;5307.8142265021315;5324.281222035053;5362.410134885159;5585.497305242548;6614.933850661528;6660.666966651745;6715;6739.393454589012;1370.8012338211104;1492.7387707151206;1496.5360738171203;1514.833368329483;1538.96152596186;996.2528103922059;1000.8748906386702;1014.7158795537262;1015.451436559253;1021.7701017271055;6164.445199932027;6172.938270617326;6201.728645627635;6204.751809927552;6217.067439884054;5850.824475265749;6600.405963463311;6876.449884009267;6881.8967715484305;6912.896306555405;5229.607779416559;5993.7712768882275;6164.815055548341;6205.720742566596;6209.410106039919;3465.913352166216;3579.3378225028455;3617;3617.385044542434;3647.2403513415247;6999.860002800017;7092.680829362691;7108.3957863581145;7132.928670713226;7187.460940429489;5574.609777315549;6900.137482814548;7094.92905070953;7168.17565979919;7226.313500217471;6631.966840165768;6968.003758383842;7082.433405327562;7095.254998225215;7097.325754053357;6868.038474613658;6897.931020689729;6913.654317284217;6924.930750692429;6958.756443524452;5481.862953426196;6407.750488654668;6560.671966401565;6716.731330746716;6823.215330237086;5704.657720536781;5756.827295181151;6992.685329160098;7087.62564540864;7104.218535960937;3477.3566890125408;4041.949093235721;4196.832126714898;4211.705180637326;4392.648588112879;6172.567920245539;6184.824883272164;6261.896554308224;6359.332270111571;6674.399304062657;5645.4072322405555;6129.9387006131155;6529.902051469327;6630.7679231226675;6683.825183038018;6470.708818103102;6567.274581049002;6610.3720146586;6635.900461493178;6661.267260601427;6238.220222472191;6489.448396886318;6517.4460170886005;6522.086907833002;6579.605223686594;5232.502912223333;6388.233411990405;6569.835754106186;6602.60384376939;6654.600723956578;5692.373838877637;6018.469960093348;6462.612243265419;6466.935330646634;6477.805665830033 10:03:12 INFO - PID 1643 | |5;yui.html;782.4992005116725;824.1758241758242;838.3419016072385;861.8448679237739;862.2196911794828;14054.578362649137;14476.059056161343;16568.75146872798;16623.501294961156;17012.23444944979;16299.511014669579;18096.81903180968;18130.637387252245;18194.09029548524;18208;967.641972470187;987.1855719031792;994.7264502261863;1021.1320377678987;1029.1149611334247;1033.013472134114;1044.3420644993644;1046.638909576195;1046.973825654361;1049.574922156524;1071.6892101290655;1081.6376513867863;1083.6478144603036;1088.9564417423294;1090.095220966596;1075.1936047964027;1077.6120596585224;1078.5847448732216;1079.8974097460728;1080.8811030786608;788.6805843633338;803.1286054630674;803.955782431966;806.1334065879204;807.64059993303;15615.453459128876;16242.106684132368;17461.912690436468;17527.824721752746;17579;17456.563585910328;17497.300107995667;17526.123693815258;17909.641807163785;17919.910400448043;14469.131852088803;14641.414343426357;14731.410743570244;14763;14793.186374749384;3462.4460086386307;3554.537910071674;3558.1460449492192;3585;3634.60019397866;122798.15802763055;131008.34495827556;136533.6346636531;138973;142451;13160.276184809736;13491.797623035662;13550.457981680622;13654.385552650154;13670.316484175752;22816.65775013376;25538.872305638353;26084.087056952914;26891.193264202106;27042;7281.599512026889;7779.844403112019;7800.180980997027;7864.134945156028;8052.959735201286;3986.242613903349;4094.61101195386;4099.8975025624595;4101.446304748881;4106.609872062149;4240.1731662326165;4308.310670292738;4310.116426132698;4314.913701725948;4332.653387728974;931.5621657820949;959.3620242538773;961.927855410833;961.9519024048769;985.1724551376877;661.2263651527724;671.3554987212233;674.922383925845;679.6635665345685;685.4413652872893;11435.256708313933;11614.896584824428;11980.221285616408;12643.620691379274;12864.48542058326;631.4340435719441;643.719981807915;657.3557913245047;679.8674258519541;687.6802286936598;5565.471280228372;5598.356189038232;5777.19119323295;5805.216295800097;5897.997340452133;147577;150426;151457;151639;154175;110164;110452.4477377608;111412;113329;113767;88059;98394.50802745941;100072.49891251637;101705;102805;11110.833337499782;11812.940935295268;11904.642860714192;12045.75908481843;12964.027697922882;44502.44241451534;45290.773546132055;45563;45749;46504.7674761624;85278;86597;87200;87918;89007;15719.528414147593;17222;17497.675441666044;17655.38206162778;17659.763816533232;18658.81341186625;18673.346432874783;18948.810511894706;18989.90505047466;19141.712874306617;17407.12964351752;17774.111294435486;17935.37226197077;17982.460526184237;18029.54926126805;17284.271183393797;17547.56131096733;17692;17948.910255448638;18134.093295335446;14850.925745371203;17212.311507539558;17285.481435556954;17353.21910514055;17561.824381756018;10747.946260268962;11828.881711182778;12012.159148859497;12275.324857132944;12312.938435307766;11098.722531936766;12250.387480626112;12823.679408014501;12922.741545168856;13900.816872715588 10:03:12 INFO - PID 1643 | __end_tp_report 10:03:12 INFO - PID 1643 | __start_cc_report 10:03:12 INFO - PID 1643 | _x_x_mozilla_cycle_collect,395 10:03:12 INFO - PID 1643 | __end_cc_report 10:03:12 INFO - PID 1643 | __startTimestamp1495818192139__endTimestamp 10:03:12 INFO - PID 1643 | 10:03:12 INFO - PID 1643 | ------- Summary: start ------- 10:03:12 INFO - PID 1643 | Number of tests: 6 10:03:12 INFO - PID 1643 | 10:03:12 INFO - PID 1643 | [#0] dojo.html Cycles:165 Average:6169.60 Median:6402.84 stddev:793.34 (12.4%) stddev-sans-first:757.25 10:03:12 INFO - PID 1643 | Values: 3056.5 3083.5 3580.3 3607.0 3630.2 6125.9 6127.9 6172.9 6205.2 6214.3 6115.4 6147.5 6175.7 6203.2 6248.0 5323.7 5431.7 5441.5 5451.5 5466.3 5515.8 5570.8 5587.7 5589.0 5614.6 5385.2 5446.7 5460.5 5499.6 5569.8 4687.4 4924.2 5461.2 5494.8 5540.7 4635.0 5099.3 5411.5 5442.1 5445.7 4854.7 6111.1 6152.9 6212.1 6224.0 6539.5 6662.7 6728.8 6749.8 6766.7 6665.6 6866.9 6873.2 6874.0 6874.8 6184.0 6273.7 6303.0 6306.3 6332.6 6812.4 6843.1 6891.0 6923.3 6978.4 6154.7 6176.7 6426.7 6461.0 6697.7 5574.3 6686.5 6824.1 6834.0 6835.9 6689.2 6728.1 6747.9 6750.4 6791.3 6495.1 6581.2 6645.6 6687.6 6809.1 5393.8 5393.9 5414.9 5426.1 5443.6 4765.5 4796.2 4797.1 4823.3 4824.2 6105.2 6183.2 6258.3 6260.1 6297.5 6872.5 6887.4 6890.3 6894.6 6895.8 5534.9 6273.7 6296.7 6312.4 6319.9 5832.7 5912.1 5952.5 6041.5 6046.2 6978.8 7006.2 7014.8 7036.3 7053.1 6841.9 6861.9 6870.4 6888.6 6898.9 6743.9 6835.6 6869.3 6948.3 7054.5 6620.1 6687.9 6712.2 6717.1 6726.2 6816.3 6816.6 6826.7 6842.0 6853.5 6414.6 6498.1 6533.6 6588.4 6655.7 6620.2 6629.0 6645.8 6654.7 6669.3 6439.0 6499.2 6511.6 6517.4 6546.6 6398.7 6584.6 6662.8 6679.4 6685.8 4623.6 6299.7 6344.8 6402.8 6420.1 10:03:12 INFO - PID 1643 | 10:03:12 INFO - PID 1643 | [#1] ext.html Cycles:180 Average:46980.35 Median:356526.50 stddev:81782.98 (22.9%) stddev-sans-first:81938.62 10:03:12 INFO - PID 1643 | Values: 712.5 768.4 829.1 985.9 1026.5 39307.6 39859.0 39873.4 39950.6 39994.8 31953.8 39274.4 39567.2 39659.8 39727.0 7643.1 8418.5 8590.0 8597.6 8600.3 8498.0 8593.4 8624.1 8649.0 8651.7 8609.9 8654.1 8656.6 8658.7 8660.6 8602.9 8622.7 8630.4 8652.3 8695.1 8357.5 8404.0 8440.7 8445.4 8462.6 30376.5 37369.0 37507.0 37618.0 37677.8 3163.9 3658.3 3677.0 3682.1 3689.4 30731.8 30818.9 30828.7 30835.5 30837.4 3123.9 3166.4 3179.9 3197.2 3199.2 239217.0 240896.0 271613.3 308468.0 310990.0 47530.8 47765.5 47776.5 47899.5 48000.0 50597.7 50606.2 50616.5 50630.7 50698.0 12442.4 14318.9 14351.6 14412.6 14439.9 11732.1 11753.2 11759.8 11787.6 11808.9 13776.7 13786.3 13818.8 13887.0 13986.2 5950.0 5996.5 6021.6 6022.8 6040.5 855.3 961.5 981.9 1071.4 1095.1 24377.8 24751.0 24792.0 24833.8 24953.5 1081.9 1150.6 1170.1 1178.8 1186.7 10941.6 12179.6 13463.7 16595.5 16603.7 351076.0 353531.0 355919.0 355962.0 357091.0 58927.7 59151.0 59279.7 59374.4 59735.7 1486.5 1511.4 1549.1 1550.8 1554.7 29922.7 30334.7 30442.7 31062.4 31135.4 39633.0 39771.0 39904.6 39990.8 40159.2 296539.0 299252.0 299577.0 300178.0 300473.0 34188.8 36620.7 37638.2 38118.8 38202.4 47846.3 48512.8 48663.8 49384.8 50265.0 32245.0 32989.7 33596.7 33832.7 34116.1 33068.7 33336.0 33808.8 33816.8 34024.0 41166.4 41194.8 41270.0 41549.8 41578.6 29293.3 31582.8 33418.8 34016.3 34271.5 23371.9 23878.3 26008.2 26225.5 26413.7 10:03:12 INFO - PID 1643 | 10:03:12 INFO - PID 1643 | [#2] jquery.html Cycles:180 Average:35745.26 Median:12618.12 stddev:91860.10 (728.0%) stddev-sans-first:92079.47 10:03:12 INFO - PID 1643 | Values: 406.1 426.9 435.9 439.4 452.0 21498.2 21508.7 22061.4 22219.2 22265.1 22977.0 23924.0 23968.8 24042.8 24090.8 6016.0 6304.9 6321.1 6322.5 6341.7 6076.7 6170.8 6182.6 6187.2 6214.8 6166.3 6182.3 6187.0 6195.2 6205.6 5862.8 6129.7 6160.7 6162.9 6168.1 5511.5 5513.2 5513.8 5514.3 5516.6 27544.4 27815.4 28696.4 28817.9 29000.9 2182.9 2187.0 2240.5 2254.6 2255.9 16485.8 16920.2 17061.7 17128.7 17135.6 2130.2 2168.4 2189.0 2194.3 2197.2 164312.5 164956.0 169541.0 171093.0 173002.1 12581.4 13103.3 13134.1 13137.5 13142.2 16366.1 16509.3 16551.3 16577.2 16581.8 8878.6 9104.0 9133.0 9152.5 9268.6 6481.9 7290.6 8265.2 8719.0 8757.0 6861.8 7961.6 8100.6 8255.8 8269.2 1916.9 2235.3 2249.1 2259.3 2436.0 919.7 958.2 962.1 965.4 966.4 18767.5 18926.9 21084.0 21342.3 21385.0 976.3 998.3 999.4 1007.5 1022.3 10901.7 11126.4 11161.9 11183.9 11184.4 509766.0 516067.0 525669.0 548615.0 575094.0 135908.0 138086.0 138280.0 141573.0 142883.4 1073.3 1119.6 1189.6 1199.7 1202.7 18201.5 18340.2 18489.8 18641.7 18896.9 51524.7 55894.7 57391.1 60098.7 60116.4 63560.7 67417.7 77578.0 77976.8 78387.8 8764.1 8767.1 9005.9 9073.7 9144.6 13895.4 13945.8 13957.0 14301.9 14414.2 10929.6 11082.7 11100.8 11474.5 11717.9 10400.3 10949.3 11126.4 11278.4 11455.9 10428.6 11391.8 11688.5 11944.1 12070.9 8780.4 8893.2 9045.8 9070.2 9213.2 12036.5 12197.2 12239.6 12310.6 12331.0 10:03:12 INFO - PID 1643 | 10:03:12 INFO - PID 1643 | [#3] mootools.html Cycles:180 Average:7212.72 Median:3276.25 stddev:4599.06 (140.4%) stddev-sans-first:4590.88 10:03:12 INFO - PID 1643 | Values: 1352.5 1406.3 1409.9 1416.5 1417.9 11592.9 12028.3 12165.9 12168.0 12299.0 11980.9 12133.5 12154.6 12159.1 12162.6 2317.8 2544.2 2591.9 2601.3 2615.8 2527.9 2574.6 2578.8 2580.1 2592.4 2526.3 2544.8 2551.0 2558.7 2561.1 2383.9 2460.4 2466.5 2470.1 2471.5 2107.6 2192.9 2485.7 2522.4 2571.1 11893.5 11983.2 11992.6 12010.2 12045.2 6473.4 6656.0 6696.5 6696.6 6734.1 4043.1 4203.6 4284.9 4288.4 4302.8 1227.9 1246.0 1271.6 1273.5 1278.0 16665.8 16789.6 16866.7 16874.4 16882.9 5455.9 5532.9 5548.4 5656.6 5686.0 12037.2 12041.8 12044.6 12051.5 12060.9 6110.9 6315.1 6329.2 6331.1 6334.3 5181.6 5809.5 5921.1 5932.9 5995.4 4313.2 4353.0 4457.8 4465.5 4504.2 1094.4 1251.1 1274.9 1285.8 1287.1 671.3 771.2 800.6 809.1 811.6 6824.6 6890.1 7001.6 7049.9 7124.4 926.2 950.4 964.4 966.1 1003.8 3936.7 4252.1 4391.6 4493.2 4551.0 13065.8 15425.2 15425.2 15482.1 15531.0 12766.2 15158.2 15357.5 15416.5 15474.5 8491.4 8533.1 8606.3 8611.7 8635.3 6666.6 6812.3 6901.2 6945.3 7167.4 11381.8 13865.4 13982.2 14116.7 14276.4 13579.8 14163.9 14451.9 14471.5 14623.7 9386.1 9444.5 9541.5 9552.1 9592.4 10766.3 10929.9 10944.8 10945.4 10972.6 9078.8 9179.9 9233.5 9376.3 9455.5 8901.9 8956.6 9023.7 9095.5 9184.3 8169.6 8199.3 8246.0 8280.1 8288.8 5416.2 6116.6 6133.4 6164.5 6209.8 6127.8 6248.8 6259.6 6340.2 6359.5 10:03:12 INFO - PID 1643 | 10:03:12 INFO - PID 1643 | [#4] prototype.html Cycles:180 Average:5245.65 Median:5969.28 stddev:1701.15 (28.5%) stddev-sans-first:1665.73 10:03:12 INFO - PID 1643 | Values: 347.9 376.1 384.2 392.2 405.8 5195.8 5272.2 5570.2 6092.8 6262.9 4774.6 5991.9 6120.5 6149.5 6150.6 3853.8 4474.2 4475.1 4479.4 4483.4 4400.7 4438.2 4441.3 4485.6 4500.9 4371.0 4395.5 4427.7 4428.2 4433.4 3675.0 4013.8 4092.9 4199.8 4412.2 3460.8 3623.7 3744.4 3813.3 3839.6 4626.4 4650.5 5955.6 5983.0 6079.5 4235.8 4275.4 4935.7 5335.7 5373.5 6313.2 6382.3 6543.9 6598.0 6619.8 4297.4 4299.3 4312.7 4325.7 4326.6 6097.8 6597.8 6810.2 6928.2 6965.2 3754.3 4155.4 4387.6 4390.4 4403.8 4460.7 4463.7 4464.1 4466.2 4491.8 4642.8 5217.6 5307.8 5324.3 5362.4 5585.5 6614.9 6660.7 6715.0 6739.4 1370.8 1492.7 1496.5 1514.8 1539.0 996.3 1000.9 1014.7 1015.5 1021.8 6164.4 6172.9 6201.7 6204.8 6217.1 5850.8 6600.4 6876.4 6881.9 6912.9 5229.6 5993.8 6164.8 6205.7 6209.4 3465.9 3579.3 3617.0 3617.4 3647.2 6999.9 7092.7 7108.4 7132.9 7187.5 5574.6 6900.1 7094.9 7168.2 7226.3 6632.0 6968.0 7082.4 7095.3 7097.3 6868.0 6897.9 6913.7 6924.9 6958.8 5481.9 6407.8 6560.7 6716.7 6823.2 5704.7 5756.8 6992.7 7087.6 7104.2 3477.4 4041.9 4196.8 4211.7 4392.6 6172.6 6184.8 6261.9 6359.3 6674.4 5645.4 6129.9 6529.9 6630.8 6683.8 6470.7 6567.3 6610.4 6635.9 6661.3 6238.2 6489.4 6517.4 6522.1 6579.6 5232.5 6388.2 6569.8 6602.6 6654.6 5692.4 6018.5 6462.6 6466.9 6477.8 10:03:12 INFO - PID 1643 | 10:03:12 INFO - PID 1643 | [#5] yui.html Cycles:180 Average:25572.31 Median:17657.57 stddev:38753.82 (219.5%) stddev-sans-first:38817.83 10:03:12 INFO - PID 1643 | Values: 782.5 824.2 838.3 861.8 862.2 14054.6 14476.1 16568.8 16623.5 17012.2 16299.5 18096.8 18130.6 18194.1 18208.0 967.6 987.2 994.7 1021.1 1029.1 1033.0 1044.3 1046.6 1047.0 1049.6 1071.7 1081.6 1083.6 1089.0 1090.1 1075.2 1077.6 1078.6 1079.9 1080.9 788.7 803.1 804.0 806.1 807.6 15615.5 16242.1 17461.9 17527.8 17579.0 17456.6 17497.3 17526.1 17909.6 17919.9 14469.1 14641.4 14731.4 14763.0 14793.2 3462.4 3554.5 3558.1 3585.0 3634.6 122798.2 131008.3 136533.6 138973.0 142451.0 13160.3 13491.8 13550.5 13654.4 13670.3 22816.7 25538.9 26084.1 26891.2 27042.0 7281.6 7779.8 7800.2 7864.1 8053.0 3986.2 4094.6 4099.9 4101.4 4106.6 4240.2 4308.3 4310.1 4314.9 4332.7 931.6 959.4 961.9 962.0 985.2 661.2 671.4 674.9 679.7 685.4 11435.3 11614.9 11980.2 12643.6 12864.5 631.4 643.7 657.4 679.9 687.7 5565.5 5598.4 5777.2 5805.2 5898.0 147577.0 150426.0 151457.0 151639.0 154175.0 110164.0 110452.4 111412.0 113329.0 113767.0 88059.0 98394.5 100072.5 101705.0 102805.0 11110.8 11812.9 11904.6 12045.8 12964.0 44502.4 45290.8 45563.0 45749.0 46504.8 85278.0 86597.0 87200.0 87918.0 89007.0 15719.5 17222.0 17497.7 17655.4 17659.8 18658.8 18673.3 18948.8 18989.9 19141.7 17407.1 17774.1 17935.4 17982.5 18029.5 17284.3 17547.6 17692.0 17948.9 18134.1 14850.9 17212.3 17285.5 17353.2 17561.8 10747.9 11828.9 12012.2 12275.3 12312.9 11098.7 12250.4 12823.7 12922.7 13900.8 10:03:12 INFO - PID 1643 | -------- Summary: end -------- 10:03:12 INFO - PID 1643 | 10:03:12 INFO - TEST-INFO | 1643: exit 0 10:03:12 INFO - TEST-OK | dromaeo_css | took 1142060ms 10:03:12 INFO - TEST-START | kraken 10:03:12 INFO - operating with platform_type : mac_ 10:03:12 INFO - Initialising browser for kraken test... 10:03:12 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49226/getInfo.html -foreground -profile /var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/tmpz4ZIj6/profile 10:03:12 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49226/getInfo.html -foreground) 10:03:45 INFO - TEST-INFO | 1652: killed by SIGTERM 10:03:45 INFO - Browser initialized. 10:03:45 INFO - Running cycle 1/1 for kraken test... 10:03:45 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/tmpz4ZIj6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:03:48 INFO - PID 1656 | 2017-05-26 10:03:48.215 plugin-container[1657:10618] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4847, name = 'com.apple.tsm.portname' 10:03:48 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:03:48 INFO - PID 1656 | 2017-05-26 10:03:48.215 plugin-container[1657:10618] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 10:03:48 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:03:48 INFO - PID 1656 | 2017-05-26 10:03:48.215 plugin-container[1657:10618] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:03:48 INFO - PID 1656 | 2017-05-26 10:03:48.713 plugin-container[1658:10663] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x464b, name = 'com.apple.tsm.portname' 10:03:48 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:03:48 INFO - PID 1656 | 2017-05-26 10:03:48.713 plugin-container[1658:10663] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 10:03:48 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:03:48 INFO - PID 1656 | 2017-05-26 10:03:48.713 plugin-container[1658:10663] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:03:58 INFO - PID 1656 | RSS: Main: 243802112 10:03:58 INFO - PID 1656 | 10:04:00 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-beat-detection) 10:04:01 INFO - PID 1656 | RSS: Main: 257568768 10:04:01 INFO - PID 1656 | 10:04:04 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-dft) 10:04:04 INFO - PID 1656 | RSS: Main: 266362880 10:04:04 INFO - PID 1656 | 10:04:08 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-fft) 10:04:09 INFO - PID 1656 | RSS: Main: 269680640 10:04:09 INFO - PID 1656 | 10:04:11 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-oscillator) 10:04:11 INFO - PID 1656 | RSS: Main: 270446592 10:04:11 INFO - PID 1656 | 10:04:13 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:04:14 INFO - PID 1656 | RSS: Main: 270950400 10:04:14 INFO - PID 1656 | 10:04:17 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-darkroom) 10:04:18 INFO - PID 1656 | RSS: Main: 278274048 10:04:18 INFO - PID 1656 | 10:04:20 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-desaturate) 10:04:21 INFO - PID 1656 | RSS: Main: 281346048 10:04:21 INFO - PID 1656 | 10:04:24 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49226/tests/kraken/driver.html?testName=json-parse-financial) 10:04:25 INFO - PID 1656 | RSS: Main: 279646208 10:04:25 INFO - PID 1656 | 10:04:26 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49226/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:04:27 INFO - PID 1656 | RSS: Main: 279261184 10:04:27 INFO - PID 1656 | 10:04:28 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:04:29 INFO - PID 1656 | RSS: Main: 279191552 10:04:29 INFO - PID 1656 | 10:04:31 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:04:32 INFO - PID 1656 | RSS: Main: 279166976 10:04:32 INFO - PID 1656 | 10:04:35 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:04:36 INFO - PID 1656 | RSS: Main: 278806528 10:04:36 INFO - PID 1656 | 10:04:39 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:04:39 INFO - PID 1656 | RSS: Main: 278966272 10:04:39 INFO - PID 1656 | 10:04:41 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49226/tests/kraken/driver.html?testName=ai-astar) 10:04:41 INFO - PID 1656 | RSS: Main: 280838144 10:04:41 INFO - PID 1656 | 10:04:41 INFO - PID 1656 | __start_tp_report 10:04:41 INFO - PID 1656 | _x_x_mozilla_page_load 10:04:41 INFO - PID 1656 | _x_x_mozilla_page_load_details 10:04:41 INFO - PID 1656 | |i|pagename|runs| 10:04:41 INFO - PID 1656 | |0;ai-astar;104;116;117;114;115;116;115;116;116;113 10:04:41 INFO - PID 1656 | |1;audio-beat-detection;158;140;141;152;109;112;130;129;134;107 10:04:41 INFO - PID 1656 | |2;audio-dft;259;228;237;230;269;271;224;203;193;199 10:04:41 INFO - PID 1656 | |3;audio-fft;79;60;79;77;61;87;54;75;88;84 10:04:41 INFO - PID 1656 | |4;audio-oscillator;85;68;85;73;67;80;104;57;76;112 10:04:41 INFO - PID 1656 | |5;imaging-gaussian-blur;88;88;89;88;89;88;98;130;88;96 10:04:41 INFO - PID 1656 | |6;imaging-darkroom;96;97;101;96;95;98;97;96;95;96 10:04:41 INFO - PID 1656 | |7;imaging-desaturate;100;94;122;94;90;92;90;90;91;92 10:04:41 INFO - PID 1656 | |8;json-parse-financial;51;50;47;52;52;50;52;53;52;50 10:04:41 INFO - PID 1656 | |9;json-stringify-tinderbox;56;73;85;91;61;65;57;55;56;57 10:04:41 INFO - PID 1656 | |10;stanford-crypto-aes;85;85;88;86;85;88;90;90;132;109 10:04:41 INFO - PID 1656 | |11;stanford-crypto-ccm;137;139;148;149;143;141;171;150;175;151 10:04:41 INFO - PID 1656 | |12;stanford-crypto-pbkdf2;180;189;187;187;186;184;185;186;186;185 10:04:41 INFO - PID 1656 | |13;stanford-crypto-sha256-iterative;68;67;66;67;68;68;89;90;96;67 10:04:41 INFO - PID 1656 | __end_tp_report 10:04:41 INFO - PID 1656 | __start_cc_report 10:04:41 INFO - PID 1656 | _x_x_mozilla_cycle_collect,1042 10:04:41 INFO - PID 1656 | __end_cc_report 10:04:41 INFO - PID 1656 | __startTimestamp1495818281732__endTimestamp 10:04:41 INFO - PID 1656 | 10:04:41 INFO - PID 1656 | ------- Summary: start ------- 10:04:41 INFO - PID 1656 | Number of tests: 14 10:04:41 INFO - PID 1656 | 10:04:41 INFO - PID 1656 | [#0] ai-astar Cycles:10 Average:114.20 Median:116.00 stddev:3.77 (3.2%) stddev-sans-first:1.22 10:04:41 INFO - PID 1656 | Values: 104.0 116.0 117.0 114.0 115.0 116.0 115.0 116.0 116.0 113.0 10:04:41 INFO - PID 1656 | 10:04:41 INFO - PID 1656 | [#1] audio-beat-detection Cycles:10 Average:131.20 Median:137.00 stddev:17.59 (12.8%) stddev-sans-first:15.76 10:04:41 INFO - PID 1656 | Values: 158.0 140.0 141.0 152.0 109.0 112.0 130.0 129.0 134.0 107.0 10:04:41 INFO - PID 1656 | 10:04:41 INFO - PID 1656 | [#2] audio-dft Cycles:10 Average:231.30 Median:233.50 stddev:28.23 (12.1%) stddev-sans-first:28.11 10:04:41 INFO - PID 1656 | Values: 259.0 228.0 237.0 230.0 269.0 271.0 224.0 203.0 193.0 199.0 10:04:41 INFO - PID 1656 | 10:04:41 INFO - PID 1656 | [#3] audio-fft Cycles:10 Average:74.40 Median:79.00 stddev:11.96 (15.1%) stddev-sans-first:12.57 10:04:41 INFO - PID 1656 | Values: 79.0 60.0 79.0 77.0 61.0 87.0 54.0 75.0 88.0 84.0 10:04:41 INFO - PID 1656 | 10:04:41 INFO - PID 1656 | [#4] audio-oscillator Cycles:10 Average:80.70 Median:74.50 stddev:16.84 (22.6%) stddev-sans-first:17.79 10:04:41 INFO - PID 1656 | Values: 85.0 68.0 85.0 73.0 67.0 80.0 104.0 57.0 76.0 112.0 10:04:41 INFO - PID 1656 | 10:04:41 INFO - PID 1656 | [#5] imaging-gaussian-blur Cycles:10 Average:94.20 Median:88.50 stddev:13.10 (14.8%) stddev-sans-first:13.71 10:04:41 INFO - PID 1656 | Values: 88.0 88.0 89.0 88.0 89.0 88.0 98.0 130.0 88.0 96.0 10:04:41 INFO - PID 1656 | 10:04:41 INFO - PID 1656 | [#6] imaging-darkroom Cycles:10 Average:96.70 Median:96.00 stddev:1.77 (1.8%) stddev-sans-first:1.86 10:04:41 INFO - PID 1656 | Values: 96.0 97.0 101.0 96.0 95.0 98.0 97.0 96.0 95.0 96.0 10:04:41 INFO - PID 1656 | 10:04:41 INFO - PID 1656 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:91.50 stddev:9.79 (10.7%) stddev-sans-first:10.25 10:04:41 INFO - PID 1656 | Values: 100.0 94.0 122.0 94.0 90.0 92.0 90.0 90.0 91.0 92.0 10:04:41 INFO - PID 1656 | 10:04:41 INFO - PID 1656 | [#8] json-parse-financial Cycles:10 Average:50.90 Median:52.00 stddev:1.73 (3.3%) stddev-sans-first:1.83 10:04:41 INFO - PID 1656 | Values: 51.0 50.0 47.0 52.0 52.0 50.0 52.0 53.0 52.0 50.0 10:04:41 INFO - PID 1656 | 10:04:41 INFO - PID 1656 | [#9] json-stringify-tinderbox Cycles:10 Average:65.60 Median:63.00 stddev:13.09 (20.8%) stddev-sans-first:13.42 10:04:41 INFO - PID 1656 | Values: 56.0 73.0 85.0 91.0 61.0 65.0 57.0 55.0 56.0 57.0 10:04:41 INFO - PID 1656 | 10:04:41 INFO - PID 1656 | [#10] stanford-crypto-aes Cycles:10 Average:93.80 Median:87.00 stddev:15.20 (17.5%) stddev-sans-first:15.79 10:04:41 INFO - PID 1656 | Values: 85.0 85.0 88.0 86.0 85.0 88.0 90.0 90.0 132.0 109.0 10:04:41 INFO - PID 1656 | 10:04:41 INFO - PID 1656 | [#11] stanford-crypto-ccm Cycles:10 Average:150.40 Median:149.50 stddev:12.87 (8.6%) stddev-sans-first:12.70 10:04:41 INFO - PID 1656 | Values: 137.0 139.0 148.0 149.0 143.0 141.0 171.0 150.0 175.0 151.0 10:04:41 INFO - PID 1656 | 10:04:41 INFO - PID 1656 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.50 Median:186.00 stddev:2.37 (1.3%) stddev-sans-first:1.45 10:04:41 INFO - PID 1656 | Values: 180.0 189.0 187.0 187.0 186.0 184.0 185.0 186.0 186.0 185.0 10:04:41 INFO - PID 1656 | 10:04:41 INFO - PID 1656 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.60 Median:68.00 stddev:11.93 (17.5%) stddev-sans-first:12.41 10:04:41 INFO - PID 1656 | Values: 68.0 67.0 66.0 67.0 68.0 68.0 89.0 90.0 96.0 67.0 10:04:41 INFO - PID 1656 | -------- Summary: end -------- 10:04:41 INFO - PID 1656 | 10:04:42 INFO - TEST-INFO | 1656: exit 0 10:04:42 INFO - TEST-OK | kraken | took 89817ms 10:04:42 INFO - SUITE-END | took 1231s 10:04:42 INFO - Completed test suite (00:20:32) 10:04:42 INFO - javascript benchmark 10:04:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8013.612503531264, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3056.5262384330435, 3083.4603944309747, 3580.301841140977, 3606.999999999999, 3630.1832087780267, 6125.877482450348, 6127.892078906831, 6172.938270617293, 6205.22434695663, 6214.285357183925, 6115.422885572139, 6147.477464415519, 6175.722092505836, 6203.162573052637, 6248.031555108954, 5323.707196104213, 5431.728413579325, 5441.483059109388, 5451.48210919962, 5466.262054622634, 5515.806946756864, 5570.832875013736, 5587.692676902769, 5589.0219211638005, 5614.578906582003, 5385.192221166817, 5446.673199608036, 5460.535854452376, 5499.615026948135, 5569.777208911639, 4687.39063921688, 4924.212126059849, 5461.15352120422, 5494.835154945358, 5540.722963851832, 4635.026644404679, 5099.3370861787735, 5411.485908838694, 5442.074847275972, 5445.700486473243, 4854.708717476928, 6111.105587097678, 6152.87694246117, 6212.1303017577575, 6224.0, 6539.542232043759, 6662.733490660367, 6728.798136055877, 6749.763758268437, 6766.69549870257, 6665.600063996176, 6866.896996545056, 6873.20958089816, 6873.965630171867, 6874.828129296758, 6183.96908015457, 6273.749050037993, 6303.0, 6306.274778400497, 6332.620042797447, 6812.386885180258, 6843.076184715099, 6890.965545172242, 6923.273056329113, 6978.371946524837, 6154.7230374633245, 6176.733769577226, 6426.710798014101, 6461.0, 6697.665116744241, 5574.275344205227, 6686.465082793462, 6824.078749368871, 6833.965830170917, 6835.931640683629, 6689.163854518185, 6728.125343705287, 6747.898781518281, 6750.3924646782025, 6791.252962174157, 6495.1231583736535, 6581.243157036906, 6645.568038077509, 6687.565308254948, 6809.080774095433, 5393.78424863009, 5393.919091213635, 5414.91877621836, 5426.077566813572, 5443.646162999393, 4765.451973023078, 4796.160671882407, 4797.064572408347, 4823.252395878644, 4824.155772739757, 6105.175801266861, 6183.227096612924, 6258.311585725566, 6260.123582698426, 6297.527685423612, 6872.518923675295, 6887.380135787703, 6890.345417185359, 6894.551854129466, 6895.827604309832, 5534.861628459321, 6273.7176827042895, 6296.716647750771, 6312.368763123651, 6319.905201421982, 5832.679202643895, 5912.113183022495, 5952.464278214981, 6041.456268935729, 6046.244219472566, 6978.755743548952, 7006.229314775371, 7014.824629384306, 7036.261192574706, 7053.118360204974, 6841.931580684329, 6861.897071543831, 6870.416014638811, 6888.586684798727, 6898.861687821654, 6743.8988415172835, 6835.555688880207, 6869.347411995853, 6948.270431604811, 7054.541454805421, 6620.073189753342, 6687.899681504684, 6712.160979877515, 6717.1267735194115, 6726.192856857208, 6816.318368163144, 6816.62508562014, 6826.726930922906, 6841.965790171017, 6853.485988550979, 6414.550981431255, 6498.090267362668, 6533.640649764118, 6588.40704336612, 6655.667216639245, 6620.238672552526, 6628.966855165693, 6645.767398141042, 6654.733810647714, 6669.299723529154, 6439.0, 6499.1876015498065, 6511.641859697762, 6517.3808488193545, 6546.639934803441, 6398.71205795731, 6584.604923704593, 6662.83342916431, 6679.365460281266, 6685.832854178684, 4623.560761727765, 6299.6535190564955, 6344.809655710336, 6402.839929001812, 6420.133282007057], "value": 6113.302724118728, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [712.4870093532656, 768.3699366519456, 829.0880031964838, 985.9359141655788, 1026.4611079183433, 39307.606923930754, 39859.0, 39873.40189897147, 39950.600493995014, 39994.8001559953, 31953.840230798873, 39274.41088383677, 39567.208655826864, 39659.80170099153, 39727.0, 7643.082830060386, 8418.49489030657, 8589.981354381714, 8597.57012149393, 8600.268977136919, 8497.95751021247, 8593.398462107654, 8624.051354351017, 8649.0, 8651.740447786577, 8609.913900861005, 8654.09132041136, 8656.567171641393, 8658.696945606906, 8660.566971651424, 8602.87095693565, 8622.65509379624, 8630.395872288911, 8652.264557512619, 8695.130486951317, 8357.498550087013, 8404.0, 8440.704575339894, 8445.366597505212, 8462.57687115648, 30376.544351834742, 37369.0, 37507.0, 37618.0, 37677.811610942044, 3163.8892638757534, 3658.2866341063504, 3676.9816150919073, 3682.1346983458866, 3689.3728066228805, 30731.846340768152, 30818.921337753072, 30828.691713082808, 30835.537466938014, 30837.383252335054, 3123.890663826778, 3166.3825554016976, 3179.9046028619177, 3197.2486465680545, 3199.2481766784786, 239217.0, 240896.0, 271613.28386716277, 308468.0, 310990.0, 47530.76234618805, 47765.5223447768, 47776.522234777905, 47899.5210047902, 48000.03999919982, 50597.747011264706, 50606.24090638643, 50616.49383506118, 50630.74684626627, 50698.0, 12442.37788110591, 14318.928405357907, 14351.569452916428, 14412.639684007774, 14439.92780036093, 11732.12009099321, 11753.177277590657, 11759.823602645967, 11787.587434439753, 11808.94095529534, 13776.724465510633, 13786.310684465736, 13818.792718109236, 13887.027908046543, 13986.16083035021, 5949.970250148722, 5996.490298324604, 6021.567706407306, 6022.789202377896, 6040.486558642477, 855.3328403845011, 961.5000199896155, 981.9444097595114, 1071.4428497181423, 1095.1348434736628, 24377.756222437547, 24751.0, 24792.008319667373, 24833.751662483144, 24953.5009299813, 1081.864766904137, 1150.5800382860195, 1170.104869774623, 1178.8054970929873, 1186.7389174381622, 10941.56233750657, 12179.634610961686, 13463.663408414672, 16595.50213493597, 16603.6679266414, 351076.0, 353531.0, 355919.0, 355962.0, 357091.0, 58927.705361473774, 59151.0, 59279.70360148171, 59374.40625593775, 59735.701321493114, 1486.4500134950138, 1511.4029958166682, 1549.1170033081262, 1550.8294087650575, 1554.7434673279008, 29922.70077299199, 30334.69665303319, 30442.695573043988, 31062.378752425277, 31135.37729245357, 39633.009174770865, 39771.0, 39904.60095399009, 39990.80004599958, 40159.1968160641, 296539.0, 299252.0, 299577.0, 300178.0, 300473.0, 34188.82905585556, 36620.71827486025, 37638.2472350546, 38118.809405952794, 38202.42696359501, 47846.28230576475, 48512.787180320774, 48663.756681216364, 49384.76538086576, 50265.0, 32245.0, 32989.67010329866, 33596.66403336033, 33832.66167338295, 34116.14709632279, 33068.66931330656, 33336.0, 33808.830955846046, 33816.83091584526, 34024.0, 41166.38250426186, 41194.794026029675, 41269.96825079397, 41549.753507394824, 41578.58421415868, 29293.26766830846, 31582.842085789423, 33418.83034093795, 34016.319673606886, 34271.48592771061, 23371.88314058419, 23878.283651490485, 26008.21975340743, 26225.4754904897, 26413.735862641126], "value": 18171.644348030495, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [406.0741509358663, 426.94970372882705, 435.9302511598143, 439.3848611943279, 451.9977400112999, 21498.247561335338, 21508.677369839446, 22061.448463788412, 22219.22232721855, 22265.109395624175, 22976.966036528356, 23924.043038278447, 23968.80155992202, 24042.75957240427, 24090.75909240907, 6015.969920150393, 6304.87390252197, 6321.083442900776, 6322.525810564203, 6341.682915854189, 6076.6961651917445, 6170.845728856792, 6182.598131121463, 6187.226596675416, 6214.751409943597, 6166.321704612489, 6182.319944806068, 6187.041008643645, 6195.2255968004, 6205.565610407273, 5862.794802181925, 6129.693515324216, 6160.722767475474, 6162.876742465148, 6168.1056246843955, 5511.476409741068, 5513.20058591506, 5513.7794488220825, 5514.255575497336, 5516.613837031409, 27544.449111017868, 27815.443691126267, 28696.42607147866, 28817.855910720522, 29000.854995725098, 2182.90176942038, 2187.0, 2240.484688521633, 2254.5941730488507, 2255.909763609454, 16485.752713709186, 16920.153992300337, 17061.65876682472, 17128.657426851518, 17135.571610709707, 2130.2331160782105, 2168.382011126837, 2189.0477642225683, 2194.3197608741343, 2197.230969160781, 164312.53531197042, 164956.0, 169541.0, 171093.0, 173002.1349893255, 12581.433835477426, 13103.279319637511, 13134.146280491737, 13137.474501020044, 13142.211467311992, 16366.099864507567, 16509.33962641505, 16551.337946482246, 16577.17114144288, 16581.83418165827, 8878.644854205888, 9104.0, 9133.0, 9152.496612686233, 9268.629254829732, 6481.8703625928165, 7290.562566245936, 8265.173482651808, 8719.04090550039, 8756.956215218883, 6861.794146175623, 7961.641726122339, 8100.554469504234, 8255.793605159919, 8269.21442462965, 1916.865819392657, 2235.340574530517, 2249.144455823952, 2259.2657386349174, 2435.9512809743705, 919.6551293265026, 958.2179326472937, 962.1100482054072, 965.4207475514635, 966.3573723473811, 18767.530811729815, 18926.905365473085, 21084.0, 21342.253021144184, 21385.0, 976.2531663277598, 998.3361064891766, 999.3953658036928, 1007.503856772692, 1022.2789047865774, 10901.672949811518, 11126.443677816076, 11161.888381116247, 11183.944080279547, 11184.384858832842, 509766.0, 516067.0, 525669.0, 548615.0, 575094.0, 135908.0, 138086.0, 138280.0, 141573.0, 142883.42423500007, 1073.2969904712427, 1119.6193294279983, 1189.6193218170276, 1199.70607201238, 1202.6933132051272, 18201.544961376072, 18340.174692139153, 18489.81510184881, 18641.720374194127, 18896.905515472336, 51524.71188220324, 55894.720526398734, 57391.139132912205, 60098.69950650219, 60116.39883601108, 63560.68219658872, 67417.66291168513, 77578.0, 77976.83034754597, 78387.82418263616, 8764.079771624143, 8767.079456656958, 9005.864912026456, 9073.727788166365, 9144.63421463134, 13895.37470813836, 13945.790813137608, 13957.0, 14301.856981430468, 14414.207218602658, 10929.56281748721, 11082.667519974413, 11100.833487497855, 11474.483648235682, 11717.882821172021, 10400.27198096126, 10949.343039417661, 11126.443677815914, 11278.436078196222, 11455.942720286344, 10428.58285668595, 11391.772164556829, 11688.47401866935, 11944.104192185794, 12070.879291207328, 8780.385373024083, 8893.199612034949, 9045.819083618158, 9070.229030532479, 9213.170814626448, 12036.458359373673, 12197.207181533173, 12239.632811015685, 12310.569130080405, 12331.0], "value": 10485.01790170744, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1352.5401363536398, 1406.3179358011362, 1409.9013069085156, 1416.5183837495254, 1417.8653027962346, 11592.884071159286, 12028.278303301808, 12165.939170304147, 12168.026557875372, 12299.0, 11980.880191198085, 12133.514659413613, 12154.635360939186, 12159.088068394862, 12162.574309899155, 2317.7798109179603, 2544.211294498702, 2591.935201619956, 2601.3366591519134, 2615.8430494170316, 2527.898884044636, 2574.613807928816, 2578.8065895057853, 2580.0711743772226, 2592.44262483566, 2526.343150780801, 2544.821862469628, 2550.987245063772, 2558.7057488388796, 2561.1164148368784, 2383.8569685818734, 2460.372604985726, 2466.4943686544307, 2470.147799009339, 2471.480988992314, 2107.6100921329603, 2192.8903554822195, 2485.652008718781, 2522.432452698147, 2571.100114959768, 11893.52425902963, 11983.221090629082, 11992.580259690956, 12010.219335743148, 12045.217060891015, 6473.385028422339, 6656.034874943154, 6696.531242813005, 6696.631685257309, 6734.124563806674, 4043.110515686544, 4203.600657937491, 4284.871453856389, 4288.399624052635, 4302.763348015857, 1227.9324637144953, 1245.9937700311532, 1271.5676669932175, 1273.5478904988688, 1277.993610031944, 16665.83334166655, 16789.58026049346, 16866.746998795028, 16874.409395671093, 16882.915585422117, 5455.918161227585, 5532.889342213214, 5548.4174161713245, 5656.5757568181725, 5685.971570142206, 12037.217580857216, 12041.819372709417, 12044.638660840188, 12051.457684404222, 12060.879391206152, 6110.908336374958, 6315.115883776275, 6329.208848893888, 6331.05034244867, 6334.271558770708, 5181.611379146504, 5809.506191973645, 5921.14143449206, 5932.8813423732145, 5995.400459953969, 4313.180495705806, 4352.978235108804, 4457.77711114443, 4465.486469056035, 4504.166729155117, 1094.3762055628215, 1251.086706704111, 1274.8533918599294, 1285.832841730569, 1287.0990306785288, 671.318611609217, 771.2210667226142, 800.5796956597716, 809.114020147937, 811.6266517401943, 6824.611386014954, 6890.138732658418, 7001.579905205602, 7049.859002820018, 7124.430045596444, 926.1895841139003, 950.3727539823822, 964.3779762053437, 966.120830044656, 1003.7942221844651, 3936.6653834423823, 4252.128313695685, 4391.604755572014, 4493.236149854467, 4551.0, 13065.804012939814, 15425.151616661191, 15425.151616661191, 15482.148481833383, 15531.0, 12766.17019893704, 15158.242087895782, 15357.462488812838, 15416.537503874903, 15474.458393956158, 8491.448055876348, 8533.061363250037, 8606.26846718036, 8611.6985905493, 8635.266002389742, 6666.633335166418, 6812.250652428328, 6901.1718593769065, 6945.305469453015, 7167.3907717842485, 11381.829272561085, 13865.376058077612, 13982.230977296347, 14116.717665646835, 14276.357563909442, 13579.79630305566, 14163.929180354444, 14451.92774036123, 14471.49349772753, 14623.707525849637, 9386.06139386056, 9444.480553569345, 9541.522923853918, 9552.092551207625, 9592.376495527767, 10766.300190487593, 10929.890701093205, 10944.78110437771, 10945.398003109905, 10972.561097556008, 9078.81842363136, 9179.862302065609, 9233.538323083685, 9376.29677774156, 9455.527223638928, 8901.866472002795, 8956.596953136994, 9023.684171053981, 9095.454272743656, 9184.311176661911, 8169.55067471271, 8199.344052475906, 8246.0, 8280.089190188954, 8288.83422331538, 5416.21464887597, 6116.633002019893, 6133.417325354084, 6164.4760195382105, 6209.751609935733, 6127.785527506516, 6248.843778905563, 6259.561830671809, 6340.175777148941, 6359.459445947146], "value": 5432.701878619549, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.86607156244844, 376.1348897535669, 384.15294276121153, 392.2194832283756, 405.7889897253429, 5195.830938038939, 5272.235525848754, 5570.220169176314, 6092.817215483531, 6262.874742505147, 4774.641901857366, 5991.89150007249, 6120.479759220471, 6149.538784591151, 6150.600210986283, 3853.807309634521, 4474.217012022899, 4475.082608065339, 4479.440069991251, 4483.439570053743, 4400.669949753766, 4438.245498265286, 4441.313709621023, 4485.573870482299, 4500.894382875822, 4370.97814510927, 4395.450568678915, 4427.712198707074, 4428.180786554498, 4433.379326894237, 3675.044488433, 4013.819378127991, 4092.9386059209137, 4199.832006719727, 4412.205802955466, 3460.8269586520823, 3623.673869351771, 3744.4196149596855, 3813.2945405100145, 3839.558450778168, 4626.352310676508, 4650.511696271877, 5955.583109182359, 5982.970085149546, 6079.513638908877, 4235.767032813194, 4275.444192254987, 4935.703857768545, 5335.653182543122, 5373.543248823855, 6313.179286692701, 6382.266039405481, 6543.93456065438, 6598.043283723833, 6619.768308109193, 4297.376880352363, 4299.333603291464, 4312.671157574906, 4325.653947684209, 4326.610605045529, 6097.817065487998, 6597.835054123638, 6810.182778066614, 6928.16861976561, 6965.199002114771, 3754.267917756066, 4155.4182414462, 4387.605115539617, 4390.407295015132, 4403.8018289176425, 4460.7323560586465, 4463.732176069446, 4464.098336081573, 4466.191192567016, 4491.8203271869415, 4642.814287428465, 5217.58259339252, 5307.8142265021315, 5324.281222035053, 5362.410134885159, 5585.497305242548, 6614.933850661528, 6660.666966651745, 6715.0, 6739.393454589012, 1370.8012338211104, 1492.7387707151206, 1496.5360738171203, 1514.833368329483, 1538.96152596186, 996.2528103922059, 1000.8748906386702, 1014.7158795537262, 1015.451436559253, 1021.7701017271055, 6164.445199932027, 6172.938270617326, 6201.728645627635, 6204.751809927552, 6217.067439884054, 5850.824475265749, 6600.405963463311, 6876.449884009267, 6881.8967715484305, 6912.896306555405, 5229.607779416559, 5993.7712768882275, 6164.815055548341, 6205.720742566596, 6209.410106039919, 3465.913352166216, 3579.3378225028455, 3617.0, 3617.385044542434, 3647.2403513415247, 6999.860002800017, 7092.680829362691, 7108.3957863581145, 7132.928670713226, 7187.460940429489, 5574.609777315549, 6900.137482814548, 7094.92905070953, 7168.17565979919, 7226.313500217471, 6631.966840165768, 6968.003758383842, 7082.433405327562, 7095.254998225215, 7097.325754053357, 6868.038474613658, 6897.931020689729, 6913.654317284217, 6924.930750692429, 6958.756443524452, 5481.862953426196, 6407.750488654668, 6560.671966401565, 6716.731330746716, 6823.215330237086, 5704.657720536781, 5756.827295181151, 6992.685329160098, 7087.62564540864, 7104.218535960937, 3477.3566890125408, 4041.949093235721, 4196.832126714898, 4211.705180637326, 4392.648588112879, 6172.567920245539, 6184.824883272164, 6261.896554308224, 6359.332270111571, 6674.399304062657, 5645.4072322405555, 6129.9387006131155, 6529.902051469327, 6630.7679231226675, 6683.825183038018, 6470.708818103102, 6567.274581049002, 6610.3720146586, 6635.900461493178, 6661.267260601427, 6238.220222472191, 6489.448396886318, 6517.4460170886005, 6522.086907833002, 6579.605223686594, 5232.502912223333, 6388.233411990405, 6569.835754106186, 6602.60384376939, 6654.600723956578, 5692.373838877637, 6018.469960093348, 6462.612243265419, 6466.935330646634, 6477.805665830033], "value": 4714.943754604994, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [782.4992005116725, 824.1758241758242, 838.3419016072385, 861.8448679237739, 862.2196911794828, 14054.578362649137, 14476.059056161343, 16568.75146872798, 16623.501294961156, 17012.23444944979, 16299.511014669579, 18096.81903180968, 18130.637387252245, 18194.09029548524, 18208.0, 967.641972470187, 987.1855719031792, 994.7264502261863, 1021.1320377678987, 1029.1149611334247, 1033.013472134114, 1044.3420644993644, 1046.638909576195, 1046.973825654361, 1049.574922156524, 1071.6892101290655, 1081.6376513867863, 1083.6478144603036, 1088.9564417423294, 1090.095220966596, 1075.1936047964027, 1077.6120596585224, 1078.5847448732216, 1079.8974097460728, 1080.8811030786608, 788.6805843633338, 803.1286054630674, 803.955782431966, 806.1334065879204, 807.64059993303, 15615.453459128876, 16242.106684132368, 17461.912690436468, 17527.824721752746, 17579.0, 17456.563585910328, 17497.300107995667, 17526.123693815258, 17909.641807163785, 17919.910400448043, 14469.131852088803, 14641.414343426357, 14731.410743570244, 14763.0, 14793.186374749384, 3462.4460086386307, 3554.537910071674, 3558.1460449492192, 3585.0, 3634.60019397866, 122798.15802763055, 131008.34495827556, 136533.6346636531, 138973.0, 142451.0, 13160.276184809736, 13491.797623035662, 13550.457981680622, 13654.385552650154, 13670.316484175752, 22816.65775013376, 25538.872305638353, 26084.087056952914, 26891.193264202106, 27042.0, 7281.599512026889, 7779.844403112019, 7800.180980997027, 7864.134945156028, 8052.959735201286, 3986.242613903349, 4094.61101195386, 4099.8975025624595, 4101.446304748881, 4106.609872062149, 4240.1731662326165, 4308.310670292738, 4310.116426132698, 4314.913701725948, 4332.653387728974, 931.5621657820949, 959.3620242538773, 961.927855410833, 961.9519024048769, 985.1724551376877, 661.2263651527724, 671.3554987212233, 674.922383925845, 679.6635665345685, 685.4413652872893, 11435.256708313933, 11614.896584824428, 11980.221285616408, 12643.620691379274, 12864.48542058326, 631.4340435719441, 643.719981807915, 657.3557913245047, 679.8674258519541, 687.6802286936598, 5565.471280228372, 5598.356189038232, 5777.19119323295, 5805.216295800097, 5897.997340452133, 147577.0, 150426.0, 151457.0, 151639.0, 154175.0, 110164.0, 110452.4477377608, 111412.0, 113329.0, 113767.0, 88059.0, 98394.50802745941, 100072.49891251637, 101705.0, 102805.0, 11110.833337499782, 11812.940935295268, 11904.642860714192, 12045.75908481843, 12964.027697922882, 44502.44241451534, 45290.773546132055, 45563.0, 45749.0, 46504.7674761624, 85278.0, 86597.0, 87200.0, 87918.0, 89007.0, 15719.528414147593, 17222.0, 17497.675441666044, 17655.38206162778, 17659.763816533232, 18658.81341186625, 18673.346432874783, 18948.810511894706, 18989.90505047466, 19141.712874306617, 17407.12964351752, 17774.111294435486, 17935.37226197077, 17982.460526184237, 18029.54926126805, 17284.271183393797, 17547.56131096733, 17692.0, 17948.910255448638, 18134.093295335446, 14850.925745371203, 17212.311507539558, 17285.481435556954, 17353.21910514055, 17561.824381756018, 10747.946260268962, 11828.881711182778, 12012.159148859497, 12275.324857132944, 12312.938435307766, 11098.722531936766, 12250.387480626112, 12823.679408014501, 12922.741545168856, 13900.816872715588], "value": 8875.772601101977, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1539.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 116.0, 117.0, 114.0, 115.0, 116.0, 115.0, 116.0, 116.0, 113.0], "value": 114.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 140.0, 141.0, 152.0, 109.0, 112.0, 130.0, 129.0, 134.0, 107.0], "value": 131.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [259.0, 228.0, 237.0, 230.0, 269.0, 271.0, 224.0, 203.0, 193.0, 199.0], "value": 231.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 60.0, 79.0, 77.0, 61.0, 87.0, 54.0, 75.0, 88.0, 84.0], "value": 74.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 68.0, 85.0, 73.0, 67.0, 80.0, 104.0, 57.0, 76.0, 112.0], "value": 80.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 89.0, 88.0, 89.0, 88.0, 98.0, 130.0, 88.0, 96.0], "value": 94.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 101.0, 96.0, 95.0, 98.0, 97.0, 96.0, 95.0, 96.0], "value": 96.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 94.0, 122.0, 94.0, 90.0, 92.0, 90.0, 90.0, 91.0, 92.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 47.0, 52.0, 52.0, 50.0, 52.0, 53.0, 52.0, 50.0], "value": 50.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 73.0, 85.0, 91.0, 61.0, 65.0, 57.0, 55.0, 56.0, 57.0], "value": 65.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 88.0, 86.0, 85.0, 88.0, 90.0, 90.0, 132.0, 109.0], "value": 93.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 139.0, 148.0, 149.0, 143.0, 141.0, 171.0, 150.0, 175.0, 151.0], "value": 150.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 189.0, 187.0, 187.0, 186.0, 184.0, 185.0, 186.0, 186.0, 185.0], "value": 185.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 66.0, 67.0, 68.0, 68.0, 89.0, 90.0, 96.0, 67.0], "value": 74.6, "unit": "score"}]}]} 10:04:42 INFO - Return code: 0 10:04:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:04:43 INFO - # TBPL SUCCESS # 10:04:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:04:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:04:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:04:43 INFO - 2017-05-26 10:04:43.028 screenresolution[1663:11043] starting screenresolution argv=screenresolution get 10:04:43 INFO - 2017-05-26 10:04:43.031 screenresolution[1663:11043] Display 0: 1600x1200x32@60 10:04:43 INFO - 2017-05-26 10:04:43.037 screenresolution[1664:11046] starting screenresolution argv=screenresolution list 10:04:43 INFO - Available Modes on Display 0 10:04:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:04:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:04:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:04:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:04:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:04:43 INFO - Intel Iris: 10:04:43 INFO - Chipset Model: Intel Iris 10:04:43 INFO - Type: GPU 10:04:43 INFO - Bus: Built-In 10:04:43 INFO - VRAM (Dynamic, Max): 1536 MB 10:04:43 INFO - Vendor: Intel (0x8086) 10:04:43 INFO - Device ID: 0x0a2e 10:04:43 INFO - Revision ID: 0x0009 10:04:43 INFO - Displays: 10:04:43 INFO - Display: 10:04:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:04:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:04:43 INFO - Display Serial Number: 1600x1200 60 10:04:43 INFO - Main Display: Yes 10:04:43 INFO - Mirror: Off 10:04:43 INFO - Online: Yes 10:04:43 INFO - Rotation: Supported 10:04:43 INFO - Return code: 0 10:04:43 INFO - Running post-action listener: _resource_record_post_action 10:04:43 INFO - [mozharness: 2017-05-26 17:04:43.279028Z] Finished run-tests step (success) 10:04:43 INFO - Running post-run listener: _resource_record_post_run 10:04:43 INFO - Total resource usage - Wall time: 1254s; CPU: 54.0%; Read bytes: 40830976; Write bytes: 564733952; Read time: 270; Write time: 1735 10:04:43 INFO - TinderboxPrint: CPU usage
53.6% 10:04:43 INFO - TinderboxPrint: I/O read bytes / time
40,830,976 / 270 10:04:43 INFO - TinderboxPrint: I/O write bytes / time
564,733,952 / 1,735 10:04:43 INFO - TinderboxPrint: CPU idle
2,326.4 (46.4%) 10:04:43 INFO - TinderboxPrint: CPU system
1,022.2 (20.4%) 10:04:43 INFO - TinderboxPrint: CPU user
1,669.1 (33.3%) 10:04:43 INFO - TinderboxPrint: Swap in / out
284,405,760 / 0 10:04:43 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 233124352; Write bytes: 228268032; Read time: 16740; Write time: 467 10:04:43 INFO - run-tests - Wall time: 1233s; CPU: 54.0%; Read bytes: 38860800; Write bytes: 317140992; Read time: 252; Write time: 1215 10:04:43 INFO - Running post-run listener: _upload_blobber_files 10:04:43 INFO - Blob upload gear active. 10:04:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:04:43 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 10:04:43 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'] 10:04:43 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 10:04:44 INFO - (blobuploader) - INFO - Open directory for files ... 10:04:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:04:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:04:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:04:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:04:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:04:45 INFO - (blobuploader) - INFO - Done attempting. 10:04:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:04:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:04:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:04:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:04:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:04:47 INFO - (blobuploader) - INFO - Done attempting. 10:04:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:04:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:04:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:04:48 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:04:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:04:48 INFO - (blobuploader) - INFO - Done attempting. 10:04:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:04:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:04:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:04:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:04:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:04:51 INFO - (blobuploader) - INFO - Done attempting. 10:04:51 INFO - (blobuploader) - INFO - Iteration through files over. 10:04:51 INFO - Return code: 0 10:04:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:04:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:04:51 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/420b29af51aee5282a701df5d7777a09025b881eb4bc1025743b2e30eae090b054432159afa5a8256b7afcf042fcf991ba55238bf5d6b95d1f547bd178c6b93f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e821f9c9917c5f0a812580000d423f6478750d2d31b75864211a7289653be1fccc700e341437423fbb2c95c3c9c20a2133f5a1810aa9891420178e99f9f9f3f0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/938557fc200630f8df68392c87b34db9641566c42f5ecf63c7f2218a983fde1b70d3328990c5b20082d28d914b0d86a269dea30dda3786ec8e705276f9b21c37", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1e90d6787ce19c3a34d8cfcc2199e2b1fb6b50559bb28369dbfa1ca2495f8397dcb74efdfd8f13c1d1e040a6bf3a949e59b8fae0b73d2553f2be3d2c4f6639cd"} 10:04:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:04:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:04:51 INFO - Contents: 10:04:51 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/420b29af51aee5282a701df5d7777a09025b881eb4bc1025743b2e30eae090b054432159afa5a8256b7afcf042fcf991ba55238bf5d6b95d1f547bd178c6b93f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e821f9c9917c5f0a812580000d423f6478750d2d31b75864211a7289653be1fccc700e341437423fbb2c95c3c9c20a2133f5a1810aa9891420178e99f9f9f3f0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/938557fc200630f8df68392c87b34db9641566c42f5ecf63c7f2218a983fde1b70d3328990c5b20082d28d914b0d86a269dea30dda3786ec8e705276f9b21c37", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1e90d6787ce19c3a34d8cfcc2199e2b1fb6b50559bb28369dbfa1ca2495f8397dcb74efdfd8f13c1d1e040a6bf3a949e59b8fae0b73d2553f2be3d2c4f6639cd"} 10:04:51 INFO - Running post-run listener: copy_logs_to_upload_dir 10:04:51 INFO - Copying logs to upload dir... 10:04:51 INFO - mkdir: /builds/slave/test/build/upload/logs 10:04:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1338.007794 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 18 secs) (at 2017-05-26 10:04:51.161032) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-26 10:04:51.164863) ========= 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.kE8ViWXumf/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.08AtFBEVra/Listeners TMPDIR=/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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/420b29af51aee5282a701df5d7777a09025b881eb4bc1025743b2e30eae090b054432159afa5a8256b7afcf042fcf991ba55238bf5d6b95d1f547bd178c6b93f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e821f9c9917c5f0a812580000d423f6478750d2d31b75864211a7289653be1fccc700e341437423fbb2c95c3c9c20a2133f5a1810aa9891420178e99f9f9f3f0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/938557fc200630f8df68392c87b34db9641566c42f5ecf63c7f2218a983fde1b70d3328990c5b20082d28d914b0d86a269dea30dda3786ec8e705276f9b21c37", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1e90d6787ce19c3a34d8cfcc2199e2b1fb6b50559bb28369dbfa1ca2495f8397dcb74efdfd8f13c1d1e040a6bf3a949e59b8fae0b73d2553f2be3d2c4f6639cd"} build_url:https://queue.taskcluster.net/v1/task/LkjDgFExQje5_3sCOTxpAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011177 build_url: 'https://queue.taskcluster.net/v1/task/LkjDgFExQje5_3sCOTxpAQ/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/420b29af51aee5282a701df5d7777a09025b881eb4bc1025743b2e30eae090b054432159afa5a8256b7afcf042fcf991ba55238bf5d6b95d1f547bd178c6b93f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e821f9c9917c5f0a812580000d423f6478750d2d31b75864211a7289653be1fccc700e341437423fbb2c95c3c9c20a2133f5a1810aa9891420178e99f9f9f3f0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/938557fc200630f8df68392c87b34db9641566c42f5ecf63c7f2218a983fde1b70d3328990c5b20082d28d914b0d86a269dea30dda3786ec8e705276f9b21c37", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1e90d6787ce19c3a34d8cfcc2199e2b1fb6b50559bb28369dbfa1ca2495f8397dcb74efdfd8f13c1d1e040a6bf3a949e59b8fae0b73d2553f2be3d2c4f6639cd"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-26 10:04:51.199046) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 10:04:51.199472) ========= 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.kE8ViWXumf/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.08AtFBEVra/Listeners TMPDIR=/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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.005118 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 10:04:51.249511) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-26 10:04:51.249915) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-26 10:04:51.250365) ========= ========= Total master_lag: 0.17 =========