builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0016 starttime: 1496518350.42 results: success (0) revision: 3b1dcffbad51682211afbf018ccf2912bb1c1bea ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-03 12:32:30.419365) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-03 12:32:30.420057) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-03 12:32:30.420340) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-03 12:32:30.547493) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-03 12:32:30.547742) ========= 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.2BkjnOQxUa/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.Z72oo4mg6s/Listeners TMPDIR=/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-06-03 12:32: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.8M=0s 2017-06-03 12:32:31 (66.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.557514 ========= master_lag: 0.07 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-03 12:32:31.176355) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-03 12:32:31.176636) ========= 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.2BkjnOQxUa/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.Z72oo4mg6s/Listeners TMPDIR=/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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.030420 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-03 12:32:31.229362) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-03 12:32:31.229717) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3b1dcffbad51682211afbf018ccf2912bb1c1bea --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 3b1dcffbad51682211afbf018ccf2912bb1c1bea --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.2BkjnOQxUa/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.Z72oo4mg6s/Listeners TMPDIR=/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-06-03 12:32:31,331 truncating revision to first 12 chars 2017-06-03 12:32:31,331 Setting DEBUG logging. 2017-06-03 12:32:31,331 attempt 1/10 2017-06-03 12:32:31,331 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3b1dcffbad51?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-03 12:32:32,464 unpacking tar archive at: mozilla-central-3b1dcffbad51/testing/mozharness/ program finished with exit code 0 elapsedTime=1.412099 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-03 12:32:32.670207) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-03 12:32:32.670523) ========= script_repo_revision: 3b1dcffbad51682211afbf018ccf2912bb1c1bea ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-03 12:32:32.670956) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-03 12:32:32.671221) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-03 12:32:32.693374) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 18 secs) (at 2017-06-03 12:32:32.693666) ========= /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.2BkjnOQxUa/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.Z72oo4mg6s/Listeners TMPDIR=/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 12:32:32 INFO - MultiFileLogger online at 20170603 12:32:32 in /builds/slave/test 12:32:32 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 12:32:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:32:32 INFO - {'append_to_log': False, 12:32:32 INFO - 'base_work_dir': '/builds/slave/test', 12:32:32 INFO - 'blob_upload_branch': 'Firefox', 12:32:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:32:32 INFO - 'branch': 'Firefox', 12:32:32 INFO - 'buildbot_json_path': 'buildprops.json', 12:32:32 INFO - 'config_files': ('talos/mac_config.py',), 12:32:32 INFO - 'default_actions': ('clobber', 12:32:32 INFO - 'read-buildbot-config', 12:32:32 INFO - 'download-and-extract', 12:32:32 INFO - 'populate-webroot', 12:32:32 INFO - 'create-virtualenv', 12:32:32 INFO - 'install', 12:32:32 INFO - 'setup-mitmproxy', 12:32:32 INFO - 'run-tests'), 12:32:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:32:32 INFO - 'download_minidump_stackwalk': True, 12:32:32 INFO - 'download_symbols': 'ondemand', 12:32:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:32:32 INFO - 'tooltool.py': '/tools/tooltool.py', 12:32:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:32:32 INFO - '/tools/misc-python/virtualenv.py')}, 12:32:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:32:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:32:32 INFO - 'gecko_profile': False, 12:32:32 INFO - 'gecko_profile_interval': 0, 12:32:32 INFO - 'installer_path': 'installer.exe', 12:32:32 INFO - 'log_level': 'info', 12:32:32 INFO - 'log_name': 'talos', 12:32:32 INFO - 'log_to_console': True, 12:32:32 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:32:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:32:32 INFO - 'opt_config_files': (), 12:32:32 INFO - 'pip_index': False, 12:32:32 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:32:32 INFO - 'cmd': ('bash', 12:32:32 INFO - '-c', 12:32:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:32:32 INFO - 'enabled': True, 12:32:32 INFO - 'halt_on_failure': False, 12:32:32 INFO - 'name': 'check_screen_resolution'},), 12:32:32 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:32:32 INFO - 'cmd': ('bash', 12:32:32 INFO - '-c', 12:32:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:32:32 INFO - 'enabled': True, 12:32:32 INFO - 'halt_on_failure': False, 12:32:32 INFO - 'name': 'check_screen_resolution'},), 12:32:32 INFO - 'run_cmd_checks_enabled': True, 12:32:32 INFO - 'suite': 'dromaeojs-e10s', 12:32:32 INFO - 'system_bits': '32', 12:32:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:32:32 INFO - 'title': 't-yosemite-r7-0016', 12:32:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:32:32 INFO - 'use_talos_json': True, 12:32:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:32:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:32:32 INFO - 'work_dir': 'build'} 12:32:32 INFO - [mozharness: 2017-06-03 19:32:32.905259Z] Running clobber step. 12:32:32 INFO - Running pre-action listener: _resource_record_pre_action 12:32:32 INFO - Running main action method: clobber 12:32:32 INFO - rmtree: /builds/slave/test/build 12:32:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:32:35 INFO - Running post-action listener: _resource_record_post_action 12:32:35 INFO - [mozharness: 2017-06-03 19:32:35.443846Z] Finished clobber step (success) 12:32:35 INFO - [mozharness: 2017-06-03 19:32:35.443973Z] Running read-buildbot-config step. 12:32:35 INFO - Running pre-action listener: _resource_record_pre_action 12:32:35 INFO - Running main action method: read_buildbot_config 12:32:35 INFO - Using buildbot properties: 12:32:35 INFO - { 12:32:35 INFO - "project": "", 12:32:35 INFO - "product": "firefox", 12:32:35 INFO - "who": "archaeopteryx@coole-files.de", 12:32:35 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 12:32:35 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 12:32:35 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 12:32:35 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 12:32:35 INFO - "stage_platform": "macosx64", 12:32:35 INFO - "basedir": "/builds/slave/test", 12:32:35 INFO - "buildnumber": 85, 12:32:35 INFO - "platform": "yosemite_r7", 12:32:35 INFO - "parent_task_id": "DDkleuz5Qv6WHINlY1eFxQ", 12:32:35 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 12:32:35 INFO - "slavebuilddir": "test", 12:32:35 INFO - "taskId": "ONG_5KXfSjSnGyfLkNcdrA", 12:32:35 INFO - "branch": "mozilla-central", 12:32:35 INFO - "script_repo_revision": "production", 12:32:35 INFO - "revision": "3b1dcffbad51682211afbf018ccf2912bb1c1bea", 12:32:35 INFO - "slavename": "t-yosemite-r7-0016", 12:32:35 INFO - "repo_path": "mozilla-central" 12:32:35 INFO - } 12:32:35 INFO - Finding installer, test and symbols from parent task. 12:32:35 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ONG_5KXfSjSnGyfLkNcdrA'}, attempt #1 12:32:36 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ONG_5KXfSjSnGyfLkNcdrA'}, attempt #1 12:32:36 INFO - Task dependencies: CbjA-M96S0ejW5CdV8lenA DDkleuz5Qv6WHINlY1eFxQ 12:32:36 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DDkleuz5Qv6WHINlY1eFxQ'}, attempt #1 12:32:37 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/DDkleuz5Qv6WHINlY1eFxQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 12:32:37 INFO - Set test_url: https://queue.taskcluster.net/v1/task/DDkleuz5Qv6WHINlY1eFxQ/artifacts/public/build/test_packages.json 12:32:37 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/DDkleuz5Qv6WHINlY1eFxQ/artifacts/public/build/target.crashreporter-symbols.zip 12:32:37 INFO - Running post-action listener: _resource_record_post_action 12:32:37 INFO - [mozharness: 2017-06-03 19:32:37.450799Z] Finished read-buildbot-config step (success) 12:32:37 INFO - [mozharness: 2017-06-03 19:32:37.450933Z] Running download-and-extract step. 12:32:37 INFO - Running pre-action listener: _resource_record_pre_action 12:32:37 INFO - Running main action method: download_and_extract 12:32:37 INFO - mkdir: /builds/slave/test/build/tests 12:32: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')]} 12:32:37 INFO - trying https://queue.taskcluster.net/v1/task/DDkleuz5Qv6WHINlY1eFxQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 12:32:37 INFO - Downloading https://queue.taskcluster.net/v1/task/DDkleuz5Qv6WHINlY1eFxQ/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 12:32:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DDkleuz5Qv6WHINlY1eFxQ/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 12:32:38 INFO - Downloaded 1328 bytes. 12:32:38 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 12:32:38 INFO - Using the following test package requirements: 12:32:38 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:32:38 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 12:32:38 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 12:32:38 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:32:38 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 12:32:38 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:32:38 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 12:32:38 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:32:38 INFO - u'jsshell-mac.zip'], 12:32:38 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:32:38 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 12:32:38 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 12:32:38 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:32:38 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 12:32:38 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:32:38 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 12:32:38 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:32:38 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.tar.gz'], 12:32:38 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:32:38 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 12:32:38 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:32:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DDkleuz5Qv6WHINlY1eFxQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 12:32:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DDkleuz5Qv6WHINlY1eFxQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:32:38 INFO - Fetch https://queue.taskcluster.net/v1/task/DDkleuz5Qv6WHINlY1eFxQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 12:32:40 INFO - Content-Length response header: 57631457 12:32:40 INFO - Bytes received: 57631457 12:32: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 12:32:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DDkleuz5Qv6WHINlY1eFxQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 12:32:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DDkleuz5Qv6WHINlY1eFxQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:32:58 INFO - Fetch https://queue.taskcluster.net/v1/task/DDkleuz5Qv6WHINlY1eFxQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 12:33:01 INFO - Content-Length response header: 57631457 12:33:01 INFO - Bytes received: 57631457 12:33:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DDkleuz5Qv6WHINlY1eFxQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 12:33:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DDkleuz5Qv6WHINlY1eFxQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 12:33:18 INFO - Fetch https://queue.taskcluster.net/v1/task/DDkleuz5Qv6WHINlY1eFxQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 12:33:19 INFO - Content-Length response header: 14447425 12:33:19 INFO - Bytes received: 14447425 12:33:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:33:19 INFO - trying https://queue.taskcluster.net/v1/task/DDkleuz5Qv6WHINlY1eFxQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 12:33:19 INFO - Downloading https://queue.taskcluster.net/v1/task/DDkleuz5Qv6WHINlY1eFxQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 12:33:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DDkleuz5Qv6WHINlY1eFxQ/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 12:33:21 INFO - Downloaded 67772593 bytes. 12:33:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DDkleuz5Qv6WHINlY1eFxQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 12:33:21 INFO - mkdir: /builds/slave/test/properties 12:33:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:33:21 INFO - Writing to file /builds/slave/test/properties/build_url 12:33:21 INFO - Contents: 12:33:21 INFO - build_url:https://queue.taskcluster.net/v1/task/DDkleuz5Qv6WHINlY1eFxQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 12:33:21 INFO - Running post-action listener: _resource_record_post_action 12:33:21 INFO - Running post-action listener: set_extra_try_arguments 12:33:21 INFO - [mozharness: 2017-06-03 19:33:21.729178Z] Finished download-and-extract step (success) 12:33:21 INFO - [mozharness: 2017-06-03 19:33:21.729311Z] Running populate-webroot step. 12:33:21 INFO - Running pre-action listener: _resource_record_pre_action 12:33:21 INFO - Running main action method: populate_webroot 12:33:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:33:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:33:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:33:21 INFO - '--tppagecycles', 12:33:21 INFO - '7'], 12:33:21 INFO - 'tests': ['tsvgm']}}, 12:33:21 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:33:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:33:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:33:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:33:21 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:33:21 INFO - 'talos_options': ['--disable-e10s'], 12:33:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:33:21 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:33:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:33:21 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:33:21 INFO - 'talos_options': ['--disable-e10s'], 12:33:21 INFO - 'tests': ['damp', 'tps']}, 12:33:21 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:33:21 INFO - 'tests': ['damp', 'tps']}, 12:33:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:33:21 INFO - 'tests': ['dromaeo_dom']}, 12:33:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:33:21 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:33:21 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 12:33:21 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 12:33:21 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 12:33:21 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 12:33:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:33:21 INFO - 'tests': ['a11yr', 12:33:21 INFO - 'ts_paint', 12:33:21 INFO - 'tpaint', 12:33:21 INFO - 'sessionrestore', 12:33:21 INFO - 'sessionrestore_no_auto_restore', 12:33:21 INFO - 'tabpaint']}, 12:33:21 INFO - 'other-e10s': {'tests': ['a11yr', 12:33:21 INFO - 'ts_paint', 12:33:21 INFO - 'tpaint', 12:33:21 INFO - 'sessionrestore', 12:33:21 INFO - 'sessionrestore_no_auto_restore', 12:33:21 INFO - 'tabpaint']}, 12:33:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:33:21 INFO - 'ts_paint', 12:33:21 INFO - 'tpaint', 12:33:21 INFO - 'sessionrestore', 12:33:21 INFO - 'sessionrestore_no_auto_restore', 12:33:21 INFO - 'tabpaint']}, 12:33:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:33:21 INFO - 'ts_paint', 12:33:21 INFO - 'tpaint', 12:33:21 INFO - 'sessionrestore', 12:33:21 INFO - 'sessionrestore_no_auto_restore', 12:33:21 INFO - 'tabpaint']}, 12:33:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:33:21 INFO - 'tests': ['a11yr', 12:33:21 INFO - 'ts_paint', 12:33:21 INFO - 'tpaint', 12:33:21 INFO - 'sessionrestore', 12:33:21 INFO - 'sessionrestore_no_auto_restore', 12:33:21 INFO - 'tabpaint']}, 12:33:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:33:21 INFO - 'tests': ['a11yr', 12:33:21 INFO - 'ts_paint', 12:33:21 INFO - 'tpaint', 12:33:21 INFO - 'sessionrestore', 12:33:21 INFO - 'sessionrestore_no_auto_restore', 12:33:21 INFO - 'tabpaint']}, 12:33:21 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 12:33:21 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 12:33:21 INFO - 'bloom_basic_ref']}, 12:33:21 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 12:33:21 INFO - 'talos_options': ['--mitmproxy', 12:33:21 INFO - 'mitmproxy-recording-1.mp', 12:33:21 INFO - '--firstNonBlankPaint'], 12:33:21 INFO - 'tests': ['Quantum_1']}, 12:33:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:33:21 INFO - 'tests': ['tsvgx', 12:33:21 INFO - 'tsvgr_opacity', 12:33:21 INFO - 'tart', 12:33:21 INFO - 'tscrollx', 12:33:21 INFO - 'cart', 12:33:21 INFO - 'tsvg_static']}, 12:33:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:33:21 INFO - 'tsvgr_opacity', 12:33:21 INFO - 'tart', 12:33:21 INFO - 'tscrollx', 12:33:21 INFO - 'cart', 12:33:21 INFO - 'tsvg_static']}, 12:33:21 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:33:21 INFO - 'talos_options': ['--disable-e10s'], 12:33:21 INFO - 'tests': ['tp5o']}, 12:33:21 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:33:21 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:33:21 INFO - 'talos_options': ['--disable-e10s', 12:33:21 INFO - '--xperf_path', 12:33:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:33:21 INFO - 'tests': ['tp5n']}, 12:33:21 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:33:21 INFO - 'talos_options': ['--xperf_path', 12:33:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:33:21 INFO - 'tests': ['tp5n']}}, 12:33:21 INFO - 'talos.zip': {'path': '', 12:33:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:33:21 INFO - Running post-action listener: _resource_record_post_action 12:33:21 INFO - [mozharness: 2017-06-03 19:33:21.751163Z] Finished populate-webroot step (success) 12:33:21 INFO - [mozharness: 2017-06-03 19:33:21.751279Z] Running create-virtualenv step. 12:33:21 INFO - Running pre-action listener: _resource_record_pre_action 12:33:21 INFO - Running main action method: create_virtualenv 12:33:21 INFO - Creating virtualenv /builds/slave/test/build/venv 12:33:21 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 12:33:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:33:21 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 12:33:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:33:21 INFO - Using real prefix '/tools/python27' 12:33:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:33:22 INFO - Installing distribute.............................................................................................................................................................................................done. 12:33:25 INFO - Installing pip.................done. 12:33:25 INFO - Return code: 0 12:33:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:33:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:33:25 INFO - Reading from file tmpfile_stdout 12:33:25 INFO - Output received: 12:33:25 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 12:33:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:33: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')]} 12:33:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:33: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')]} 12:33:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:33: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1085a9c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1085ad768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffaf05de230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108532c60>, '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 0x1082c7cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082f0be8>, '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.2BkjnOQxUa/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.Z72oo4mg6s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:33: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', 'pip>=1.5'] in /builds/slave/test/build 12:33: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 pip>=1.5 12:33:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2BkjnOQxUa/Render', 12:33:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:33:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:33:25 INFO - 'HOME': '/Users/cltbld', 12:33:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:33:25 INFO - 'LOGNAME': 'cltbld', 12:33:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:33:25 INFO - 'MOZ_NO_REMOTE': '1', 12:33:25 INFO - 'NO_EM_RESTART': '1', 12:33:25 INFO - 'PAGER': '/bin/cat', 12:33:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:33:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:33:25 INFO - 'PWD': '/builds/slave/test', 12:33:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:33:25 INFO - 'SHELL': '/bin/bash', 12:33:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z72oo4mg6s/Listeners', 12:33:25 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 12:33:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:33:25 INFO - 'USER': 'cltbld', 12:33:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:33:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:33:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:33:25 INFO - 'XPC_FLAGS': '0x0', 12:33:25 INFO - 'XPC_SERVICE_NAME': '0', 12:33:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:33:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:33:26 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 12:33:26 INFO - Cleaning up... 12:33:26 INFO - Return code: 0 12:33:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:33:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:33:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:33:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:33:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:33:26 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 0x1085a9c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1085ad768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffaf05de230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108532c60>, '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 0x1082c7cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082f0be8>, '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.2BkjnOQxUa/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.Z72oo4mg6s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:33:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:33:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:33:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2BkjnOQxUa/Render', 12:33:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:33:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:33:26 INFO - 'HOME': '/Users/cltbld', 12:33:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:33:26 INFO - 'LOGNAME': 'cltbld', 12:33:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:33:26 INFO - 'MOZ_NO_REMOTE': '1', 12:33:26 INFO - 'NO_EM_RESTART': '1', 12:33:26 INFO - 'PAGER': '/bin/cat', 12:33:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:33:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:33:26 INFO - 'PWD': '/builds/slave/test', 12:33:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:33:26 INFO - 'SHELL': '/bin/bash', 12:33:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z72oo4mg6s/Listeners', 12:33:26 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 12:33:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:33:26 INFO - 'USER': 'cltbld', 12:33:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:33:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:33:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:33:26 INFO - 'XPC_FLAGS': '0x0', 12:33:26 INFO - 'XPC_SERVICE_NAME': '0', 12:33:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:33:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:33:26 INFO - Downloading/unpacking psutil>=3.1.1 12:33:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:33:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:33:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:33:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:33:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:33:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:33:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:33:28 INFO - Installing collected packages: psutil 12:33:28 INFO - Running setup.py install for psutil 12:33:28 INFO - building 'psutil._psutil_osx' extension 12:33:28 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 12:33:29 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 12:33:29 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 12:33:29 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 12:33:29 INFO - building 'psutil._psutil_posix' extension 12:33:29 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 12:33:29 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:33:29 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:33:29 INFO - ^ 12:33:29 INFO - 1 warning generated. 12:33:29 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 12:33:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:33:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:33:29 INFO - Successfully installed psutil 12:33:29 INFO - Cleaning up... 12:33:29 INFO - Return code: 0 12:33:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:33:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:33:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:33:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:33:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:33: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 0x1085a9c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1085ad768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffaf05de230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108532c60>, '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 0x1082c7cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082f0be8>, '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.2BkjnOQxUa/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.Z72oo4mg6s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:33: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 12:33: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 12:33:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2BkjnOQxUa/Render', 12:33:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:33:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:33:29 INFO - 'HOME': '/Users/cltbld', 12:33:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:33:29 INFO - 'LOGNAME': 'cltbld', 12:33:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:33:29 INFO - 'MOZ_NO_REMOTE': '1', 12:33:29 INFO - 'NO_EM_RESTART': '1', 12:33:29 INFO - 'PAGER': '/bin/cat', 12:33:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:33:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:33:29 INFO - 'PWD': '/builds/slave/test', 12:33:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:33:29 INFO - 'SHELL': '/bin/bash', 12:33:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z72oo4mg6s/Listeners', 12:33:29 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 12:33:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:33:29 INFO - 'USER': 'cltbld', 12:33:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:33:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:33:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:33:29 INFO - 'XPC_FLAGS': '0x0', 12:33:29 INFO - 'XPC_SERVICE_NAME': '0', 12:33:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:33:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:33:30 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:33:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:33:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:33:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:33:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:33:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:33:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:33:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:33:31 INFO - Installing collected packages: mozsystemmonitor 12:33:31 INFO - Running setup.py install for mozsystemmonitor 12:33:31 INFO - Successfully installed mozsystemmonitor 12:33:31 INFO - Cleaning up... 12:33:31 INFO - Return code: 0 12:33:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:33: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')]} 12:33:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:33: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')]} 12:33:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:33: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 0x1085a9c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1085ad768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffaf05de230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108532c60>, '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 0x1082c7cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082f0be8>, '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.2BkjnOQxUa/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.Z72oo4mg6s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:33: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 12:33: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 12:33:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2BkjnOQxUa/Render', 12:33:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:33:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:33:31 INFO - 'HOME': '/Users/cltbld', 12:33:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:33:31 INFO - 'LOGNAME': 'cltbld', 12:33:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:33:31 INFO - 'MOZ_NO_REMOTE': '1', 12:33:31 INFO - 'NO_EM_RESTART': '1', 12:33:31 INFO - 'PAGER': '/bin/cat', 12:33:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:33:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:33:31 INFO - 'PWD': '/builds/slave/test', 12:33:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:33:31 INFO - 'SHELL': '/bin/bash', 12:33:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z72oo4mg6s/Listeners', 12:33:31 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 12:33:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:33:31 INFO - 'USER': 'cltbld', 12:33:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:33:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:33:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:33:31 INFO - 'XPC_FLAGS': '0x0', 12:33:31 INFO - 'XPC_SERVICE_NAME': '0', 12:33:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:33:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:33:32 INFO - Downloading/unpacking jsonschema==2.5.1 12:33:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:33:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:33:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:33:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:33:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:33:33 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:33: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 12:33: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 12:33: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 12:33: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 12:33:33 INFO - Downloading functools32-3.2.3-2.tar.gz 12:33:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:33:33 INFO - warning: no files found matching '*.txt' 12:33:33 INFO - no previously-included directories found matching 'build' 12:33:33 INFO - no previously-included directories found matching 'dist' 12:33:33 INFO - no previously-included directories found matching '.git*' 12:33:33 INFO - Installing collected packages: jsonschema, functools32 12:33:33 INFO - Running setup.py install for functools32 12:33:33 INFO - warning: no files found matching '*.txt' 12:33:33 INFO - no previously-included directories found matching 'build' 12:33:33 INFO - no previously-included directories found matching 'dist' 12:33:33 INFO - no previously-included directories found matching '.git*' 12:33:34 INFO - Successfully installed jsonschema functools32 12:33:34 INFO - Cleaning up... 12:33:34 INFO - Return code: 0 12:33:34 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:33: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')]} 12:33:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:33: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')]} 12:33:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:33: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', '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 0x1085a9c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1085ad768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffaf05de230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108532c60>, '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 0x1082c7cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082f0be8>, '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.2BkjnOQxUa/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.Z72oo4mg6s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:33: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:33: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 functools32==3.2.3-2 12:33:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2BkjnOQxUa/Render', 12:33:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:33:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:33:34 INFO - 'HOME': '/Users/cltbld', 12:33:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:33:34 INFO - 'LOGNAME': 'cltbld', 12:33:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:33:34 INFO - 'MOZ_NO_REMOTE': '1', 12:33:34 INFO - 'NO_EM_RESTART': '1', 12:33:34 INFO - 'PAGER': '/bin/cat', 12:33:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:33:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:33:34 INFO - 'PWD': '/builds/slave/test', 12:33:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:33:34 INFO - 'SHELL': '/bin/bash', 12:33:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z72oo4mg6s/Listeners', 12:33:34 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 12:33:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:33:34 INFO - 'USER': 'cltbld', 12:33:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:33:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:33:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:33:34 INFO - 'XPC_FLAGS': '0x0', 12:33:34 INFO - 'XPC_SERVICE_NAME': '0', 12:33:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:33:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:33:34 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:33:34 INFO - Cleaning up... 12:33:34 INFO - Return code: 0 12:33:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:33: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')]} 12:33:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:33: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')]} 12:33:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:33: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 0x1085a9c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1085ad768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffaf05de230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108532c60>, '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 0x1082c7cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082f0be8>, '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.2BkjnOQxUa/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.Z72oo4mg6s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:33: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 12:33: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 12:33:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2BkjnOQxUa/Render', 12:33:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:33:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:33:34 INFO - 'HOME': '/Users/cltbld', 12:33:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:33:34 INFO - 'LOGNAME': 'cltbld', 12:33:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:33:34 INFO - 'MOZ_NO_REMOTE': '1', 12:33:34 INFO - 'NO_EM_RESTART': '1', 12:33:34 INFO - 'PAGER': '/bin/cat', 12:33:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:33:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:33:34 INFO - 'PWD': '/builds/slave/test', 12:33:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:33:34 INFO - 'SHELL': '/bin/bash', 12:33:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z72oo4mg6s/Listeners', 12:33:34 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 12:33:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:33:34 INFO - 'USER': 'cltbld', 12:33:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:33:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:33:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:33:34 INFO - 'XPC_FLAGS': '0x0', 12:33:34 INFO - 'XPC_SERVICE_NAME': '0', 12:33:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:33:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:33:34 INFO - Downloading/unpacking blobuploader==1.2.4 12:33: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 12:33: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 12:33: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 12:33: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 12:33:35 INFO - Downloading blobuploader-1.2.4.tar.gz 12:33:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:33:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:33: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 12:33: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 12:33: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 12:33: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 12:33:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:33:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:33: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 12:33: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 12:33: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 12:33: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 12:33:36 INFO - Downloading docopt-0.6.1.tar.gz 12:33:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:33:36 INFO - Installing collected packages: blobuploader, requests, docopt 12:33:36 INFO - Running setup.py install for blobuploader 12:33:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:33:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:33:37 INFO - Running setup.py install for requests 12:33:37 INFO - Running setup.py install for docopt 12:33:37 INFO - Successfully installed blobuploader requests docopt 12:33:37 INFO - Cleaning up... 12:33:37 INFO - Return code: 0 12:33:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:33: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')]} 12:33:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:33: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')]} 12:33:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:33: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 0x1085a9c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1085ad768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffaf05de230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108532c60>, '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 0x1082c7cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082f0be8>, '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.2BkjnOQxUa/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.Z72oo4mg6s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:33: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 12:33: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 12:33:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2BkjnOQxUa/Render', 12:33:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:33:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:33:37 INFO - 'HOME': '/Users/cltbld', 12:33:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:33:37 INFO - 'LOGNAME': 'cltbld', 12:33:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:33:37 INFO - 'MOZ_NO_REMOTE': '1', 12:33:37 INFO - 'NO_EM_RESTART': '1', 12:33:37 INFO - 'PAGER': '/bin/cat', 12:33:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:33:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:33:37 INFO - 'PWD': '/builds/slave/test', 12:33:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:33:37 INFO - 'SHELL': '/bin/bash', 12:33:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z72oo4mg6s/Listeners', 12:33:37 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 12:33:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:33:37 INFO - 'USER': 'cltbld', 12:33:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:33:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:33:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:33:37 INFO - 'XPC_FLAGS': '0x0', 12:33:37 INFO - 'XPC_SERVICE_NAME': '0', 12:33:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:33:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:33:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:33:37 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-UnbMv2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:33:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:33:38 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-GwQRAn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:33:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:33:38 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-almcqJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:33:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:33:38 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-bPaRDq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:33:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:33:38 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-vvHgzK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:33:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:33:38 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-x0YN5s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:33:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:33:38 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-3LHIsz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:33:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:33:38 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-I7To05-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:33:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:33:38 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-JP2g6K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:33:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:33:38 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-eh1Ws1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:33:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:33:39 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-FLf10E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:33:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:33:39 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-FZDVeU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:33:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:33:39 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-kmb15C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:33:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:33:39 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-K1kwLs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:33:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:33:39 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-vroCC3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:33:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:33:39 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-8nTuoO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:33:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:33:39 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-3CMW7f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:33:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:33:39 INFO - Running setup.py install for manifestparser 12:33:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:33:39 INFO - Running setup.py install for mozcrash 12:33:40 INFO - Running setup.py install for mozdebug 12:33:40 INFO - Running setup.py install for mozdevice 12:33:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:33:40 INFO - Running setup.py install for mozfile 12:33:40 INFO - Running setup.py install for mozhttpd 12:33:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:33:40 INFO - Running setup.py install for mozinfo 12:33:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:33:40 INFO - Running setup.py install for mozInstall 12:33:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:33:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:33:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:33:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:33:40 INFO - Running setup.py install for mozleak 12:33:41 INFO - Running setup.py install for mozlog 12:33:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:33:41 INFO - Running setup.py install for moznetwork 12:33:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:33:41 INFO - Running setup.py install for mozprocess 12:33:41 INFO - Running setup.py install for mozprofile 12:33:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:33:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:33:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:33:41 INFO - Running setup.py install for mozrunner 12:33:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:33:41 INFO - Running setup.py install for mozscreenshot 12:33:42 INFO - Running setup.py install for moztest 12:33:42 INFO - Running setup.py install for mozversion 12:33:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:33:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:33:42 INFO - Cleaning up... 12:33:42 INFO - Return code: 0 12:33:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:33: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')]} 12:33:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:33: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')]} 12:33:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:33: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 0x1085a9c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1085ad768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffaf05de230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108532c60>, '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 0x1082c7cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082f0be8>, '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.2BkjnOQxUa/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.Z72oo4mg6s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:33: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 12:33: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 12:33:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2BkjnOQxUa/Render', 12:33:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:33:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:33:42 INFO - 'HOME': '/Users/cltbld', 12:33:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:33:42 INFO - 'LOGNAME': 'cltbld', 12:33:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:33:42 INFO - 'MOZ_NO_REMOTE': '1', 12:33:42 INFO - 'NO_EM_RESTART': '1', 12:33:42 INFO - 'PAGER': '/bin/cat', 12:33:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:33:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:33:42 INFO - 'PWD': '/builds/slave/test', 12:33:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:33:42 INFO - 'SHELL': '/bin/bash', 12:33:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z72oo4mg6s/Listeners', 12:33:42 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 12:33:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:33:42 INFO - 'USER': 'cltbld', 12:33:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:33:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:33:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:33:42 INFO - 'XPC_FLAGS': '0x0', 12:33:42 INFO - 'XPC_SERVICE_NAME': '0', 12:33:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:33:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:33:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:33:42 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-5PMTpO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:33: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)) 12:33:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:33:42 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-VLhLHN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:33: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)) 12:33:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:33:42 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-oGhi3S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:33: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)) 12:33:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:33:42 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-d6RI4r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:33:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:33:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:33:43 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-s0BbP9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:33:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 12:33:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:33:43 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-klVotl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:33:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 12:33:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:33:43 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-9DVVns-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:33: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)) 12:33:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:33:43 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-fbhTCv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:33: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)) 12:33:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:33:43 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-1BHmlD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:33: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)) 12:33:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:33:43 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-L2h191-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:33: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)) 12:33:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:33:43 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-BJx6oo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:33: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)) 12:33:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:33:43 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-sH2yoF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:33: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)) 12:33:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:33:43 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-V3qYA2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:33:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 12:33:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:33:44 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-yojtca-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:33:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 12:33:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:33:44 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-zJdOSl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:33:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 12:33:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:33:44 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-9NceXk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:33: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)) 12:33:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:33:44 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-sT9be5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:33: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)) 12:33: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)) 12:33: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)) 12:33: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)) 12:33: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)) 12:33: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)) 12:33:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:33: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 12:33: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 12:33: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 12:33: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 12:33:45 INFO - Downloading blessings-1.6.tar.gz 12:33:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:33:45 INFO - Installing collected packages: blessings 12:33:45 INFO - Running setup.py install for blessings 12:33:46 INFO - Successfully installed blessings 12:33:46 INFO - Cleaning up... 12:33:46 INFO - Return code: 0 12:33:46 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:33:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:33:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:33:46 INFO - Reading from file tmpfile_stdout 12:33:46 INFO - Current package versions: 12:33:46 INFO - blessings == 1.6 12:33:46 INFO - blobuploader == 1.2.4 12:33:46 INFO - docopt == 0.6.1 12:33:46 INFO - functools32 == 3.2.3-2 12:33:46 INFO - jsonschema == 2.5.1 12:33:46 INFO - manifestparser == 1.1 12:33:46 INFO - mozInstall == 1.12 12:33:46 INFO - mozcrash == 1.0 12:33:46 INFO - mozdebug == 0.1 12:33:46 INFO - mozdevice == 0.51 12:33:46 INFO - mozfile == 1.2 12:33:46 INFO - mozhttpd == 0.7 12:33:46 INFO - mozinfo == 0.9 12:33:46 INFO - mozleak == 0.1 12:33:46 INFO - mozlog == 3.4 12:33:46 INFO - moznetwork == 0.27 12:33:46 INFO - mozprocess == 0.25 12:33:46 INFO - mozprofile == 0.28 12:33:46 INFO - mozrunner == 6.13 12:33:46 INFO - mozscreenshot == 0.1 12:33:46 INFO - mozsystemmonitor == 0.3 12:33:46 INFO - moztest == 0.8 12:33:46 INFO - mozversion == 1.4 12:33:46 INFO - psutil == 3.1.1 12:33:46 INFO - requests == 1.2.3 12:33:46 INFO - wsgiref == 0.1.2 12:33:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:33: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')]} 12:33:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:33: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')]} 12:33:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:33: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 0x1085a9c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1085ad768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffaf05de230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108532c60>, '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 0x1082c7cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082f0be8>, '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.2BkjnOQxUa/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.Z72oo4mg6s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:33: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 12:33: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 12:33:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2BkjnOQxUa/Render', 12:33:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:33:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:33:46 INFO - 'HOME': '/Users/cltbld', 12:33:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:33:46 INFO - 'LOGNAME': 'cltbld', 12:33:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:33:46 INFO - 'MOZ_NO_REMOTE': '1', 12:33:46 INFO - 'NO_EM_RESTART': '1', 12:33:46 INFO - 'PAGER': '/bin/cat', 12:33:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:33:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:33:46 INFO - 'PWD': '/builds/slave/test', 12:33:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:33:46 INFO - 'SHELL': '/bin/bash', 12:33:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z72oo4mg6s/Listeners', 12:33:46 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 12:33:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:33:46 INFO - 'USER': 'cltbld', 12:33:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:33:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:33:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:33:46 INFO - 'XPC_FLAGS': '0x0', 12:33:46 INFO - 'XPC_SERVICE_NAME': '0', 12:33:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:33:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:33: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)) 12:33: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)) 12:33: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)) 12:33: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)) 12:33: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)) 12:33: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)) 12:33: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)) 12:33: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)) 12:33: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)) 12:33:46 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 12:33:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:33:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:33:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:33:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:33:48 INFO - Downloading marionette_driver-1.4.0.tar.gz 12:33:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 12:33:48 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:33:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:33:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 12:33:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 12:33:48 INFO - Installing collected packages: marionette-driver 12:33:48 INFO - Running setup.py install for marionette-driver 12:33:48 INFO - Successfully installed marionette-driver 12:33:48 INFO - Cleaning up... 12:33:48 INFO - Return code: 0 12:33:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:33:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:33:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:33:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:33:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:33: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 0x1085a9c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1085ad768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffaf05de230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108532c60>, '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 0x1082c7cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082f0be8>, '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.2BkjnOQxUa/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.Z72oo4mg6s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:33: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 12:33: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 12:33:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2BkjnOQxUa/Render', 12:33:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:33:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:33:48 INFO - 'HOME': '/Users/cltbld', 12:33:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:33:48 INFO - 'LOGNAME': 'cltbld', 12:33:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:33:48 INFO - 'MOZ_NO_REMOTE': '1', 12:33:48 INFO - 'NO_EM_RESTART': '1', 12:33:48 INFO - 'PAGER': '/bin/cat', 12:33:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:33:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:33:48 INFO - 'PWD': '/builds/slave/test', 12:33:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:33:48 INFO - 'SHELL': '/bin/bash', 12:33:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z72oo4mg6s/Listeners', 12:33:48 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 12:33:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:33:48 INFO - 'USER': 'cltbld', 12:33:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:33:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:33:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:33:48 INFO - 'XPC_FLAGS': '0x0', 12:33:48 INFO - 'XPC_SERVICE_NAME': '0', 12:33:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:33:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:33:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:33:48 INFO - Cleaning up... 12:33:48 INFO - Return code: 0 12:33:48 INFO - Running post-action listener: _resource_record_post_action 12:33:48 INFO - Running post-action listener: _start_resource_monitoring 12:33:48 INFO - Starting resource monitoring. 12:33:48 INFO - [mozharness: 2017-06-03 19:33:48.755249Z] Finished create-virtualenv step (success) 12:33:48 INFO - [mozharness: 2017-06-03 19:33:48.756291Z] Running install step. 12:33:48 INFO - Running pre-action listener: _resource_record_pre_action 12:33:48 INFO - Running main action method: install 12:33:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:33:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:33:49 INFO - Reading from file tmpfile_stdout 12:33:49 INFO - Detecting whether we're running mozinstall >=1.0... 12:33:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:33:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:33:49 INFO - Reading from file tmpfile_stdout 12:33:49 INFO - Output received: 12:33:49 INFO - Usage: mozinstall [options] installer 12:33:49 INFO - Options: 12:33:49 INFO - -h, --help show this help message and exit 12:33:49 INFO - -d DEST, --destination=DEST 12:33:49 INFO - Directory to install application into. [default: 12:33:49 INFO - "/builds/slave/test"] 12:33:49 INFO - --app=APP Application being installed. [default: firefox] 12:33:49 INFO - mkdir: /builds/slave/test/build/application 12:33:49 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'] 12:33:49 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 12:34:10 INFO - Reading from file tmpfile_stdout 12:34:10 INFO - Output received: 12:34:10 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:34:10 INFO - Running post-action listener: _resource_record_post_action 12:34:10 INFO - [mozharness: 2017-06-03 19:34:10.537896Z] Finished install step (success) 12:34:10 INFO - [mozharness: 2017-06-03 19:34:10.538069Z] Running setup-mitmproxy step. 12:34:10 INFO - Running pre-action listener: _resource_record_pre_action 12:34:10 INFO - Running main action method: setup_mitmproxy 12:34:10 INFO - Skipping: mitmproxy is not required 12:34:10 INFO - Running post-action listener: _resource_record_post_action 12:34:10 INFO - [mozharness: 2017-06-03 19:34:10.538743Z] Finished setup-mitmproxy step (success) 12:34:10 INFO - [mozharness: 2017-06-03 19:34:10.538865Z] Running run-tests step. 12:34:10 INFO - Running pre-action listener: _resource_record_pre_action 12:34:10 INFO - Running main action method: run_tests 12:34:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:34:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:34:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:34:10 INFO - 2017-06-03 12:34:10.553 screenresolution[1629:8892] starting screenresolution argv=screenresolution get 12:34:10 INFO - 2017-06-03 12:34:10.556 screenresolution[1629:8892] Display 0: 1600x1200x32@60 12:34:10 INFO - 2017-06-03 12:34:10.562 screenresolution[1630:8895] starting screenresolution argv=screenresolution list 12:34:10 INFO - Available Modes on Display 0 12:34:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:34:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:34:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:34:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:34:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:34:10 INFO - Intel Iris: 12:34:10 INFO - Chipset Model: Intel Iris 12:34:10 INFO - Type: GPU 12:34:10 INFO - Bus: Built-In 12:34:10 INFO - VRAM (Dynamic, Max): 1536 MB 12:34:10 INFO - Vendor: Intel (0x8086) 12:34:10 INFO - Device ID: 0x0a2e 12:34:10 INFO - Revision ID: 0x0009 12:34:10 INFO - Displays: 12:34:10 INFO - Display: 12:34:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:34:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:34:10 INFO - Display Serial Number: 1600x1200 60 12:34:10 INFO - Main Display: Yes 12:34:10 INFO - Mirror: Off 12:34:10 INFO - Online: Yes 12:34:10 INFO - Rotation: Supported 12:34:10 INFO - Return code: 0 12:34:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:34:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:34:10 INFO - Python 2.7.3 12:34:10 INFO - Return code: 0 12:34:10 INFO - grabbing minidump binary from tooltool 12:34: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')]} 12:34: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 0x108532c60>, '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 0x1082c7cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082f0be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:34: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 12:34: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 12:34: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 12:34:10 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:34:10 INFO - Return code: 0 12:34:10 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:34:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:34:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:34:10 INFO - ENV: RUST_BACKTRACE is now 1 12:34:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:34:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:34: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/DDkleuz5Qv6WHINlY1eFxQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0016', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 12:34: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/DDkleuz5Qv6WHINlY1eFxQ/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0016 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 12:34:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2BkjnOQxUa/Render', 12:34:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:34:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:34:10 INFO - 'HOME': '/Users/cltbld', 12:34:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:34:10 INFO - 'LOGNAME': 'cltbld', 12:34:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:34:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:34:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:34:10 INFO - 'MOZ_NO_REMOTE': '1', 12:34:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:34:10 INFO - 'NO_EM_RESTART': '1', 12:34:10 INFO - 'PAGER': '/bin/cat', 12:34:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:34:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:34:10 INFO - 'PWD': '/builds/slave/test', 12:34:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:34:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:34:10 INFO - 'RUST_BACKTRACE': '1', 12:34:10 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:34:10 INFO - 'SHELL': '/bin/bash', 12:34:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z72oo4mg6s/Listeners', 12:34:10 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 12:34:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:34:10 INFO - 'USER': 'cltbld', 12:34:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:34:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:34:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:34:10 INFO - 'XPC_FLAGS': '0x0', 12:34:10 INFO - 'XPC_SERVICE_NAME': '0', 12:34:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:34: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/DDkleuz5Qv6WHINlY1eFxQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0016', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 12:34:11 INFO - mozversion application_buildid: 20170603111623 12:34:11 INFO - mozversion application_changeset: 3b1dcffbad51682211afbf018ccf2912bb1c1bea 12:34:11 INFO - mozversion application_display_name: Nightly 12:34:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:34:11 INFO - mozversion application_name: Firefox 12:34:11 INFO - mozversion application_remotingname: firefox 12:34:11 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:34:11 INFO - mozversion application_vendor: Mozilla 12:34:11 INFO - mozversion application_version: 55.0a1 12:34:11 INFO - mozversion platform_buildid: 20170603111623 12:34:11 INFO - mozversion platform_changeset: 3b1dcffbad51682211afbf018ccf2912bb1c1bea 12:34:11 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:34:11 INFO - mozversion platform_version: 55.0a1 12:34:11 INFO - using testdate: 1496518451 12:34:11 INFO - actual date: 1496518451 12:34:11 INFO - starting webserver on 'localhost:49226' 12:34:11 INFO - SUITE-START | Running 2 tests 12:34:11 INFO - TEST-START | dromaeo_css 12:34:11 INFO - operating with platform_type : mac_ 12:34:11 INFO - Initialising browser for dromaeo_css test... 12:34:11 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49226/getInfo.html -foreground -profile /var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/tmpnWWcky/profile 12:34:11 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49226/getInfo.html -foreground) 12:34:45 INFO - TEST-INFO | 1640: killed by SIGTERM 12:34:45 INFO - Browser initialized. 12:34:45 INFO - Running cycle 1/1 for dromaeo_css test... 12:34:45 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/tmpnWWcky/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:34:47 INFO - PID 1648 | 2017-06-03 12:34:47.302 plugin-container[1649:9388] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x983b, name = 'com.apple.tsm.portname' 12:34:47 INFO - PID 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:34:47 INFO - PID 1648 | 2017-06-03 12:34:47.303 plugin-container[1649:9388] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d0f, name = 'com.apple.CFPasteboardClient' 12:34:47 INFO - PID 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:34:47 INFO - PID 1648 | 2017-06-03 12:34:47.303 plugin-container[1649:9388] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:34:47 INFO - PID 1648 | 2017-06-03 12:34:47.768 plugin-container[1650:9436] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x464b, name = 'com.apple.tsm.portname' 12:34:47 INFO - PID 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:34:47 INFO - PID 1648 | 2017-06-03 12:34:47.768 plugin-container[1650:9436] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 12:34:47 INFO - PID 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:34:47 INFO - PID 1648 | 2017-06-03 12:34:47.769 plugin-container[1650:9436] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:34:57 INFO - PID 1648 | RSS: Main: 269205504 12:34:57 INFO - PID 1648 | 12:37:58 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49226/tests/dromaeo/cssquery-ext.html) 12:38:00 INFO - PID 1648 | RSS: Main: 269160448 12:38:00 INFO - PID 1648 | 12:41:02 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-ext.html (next: http://localhost:49226/tests/dromaeo/cssquery-jquery.html) 12:41:03 INFO - PID 1648 | RSS: Main: 283021312 12:41:03 INFO - PID 1648 | 12:44:05 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49226/tests/dromaeo/cssquery-mootools.html) 12:44:05 INFO - PID 1648 | RSS: Main: 281907200 12:44:05 INFO - PID 1648 | 12:47:07 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49226/tests/dromaeo/cssquery-prototype.html) 12:47:08 INFO - PID 1648 | RSS: Main: 286191616 12:47:08 INFO - PID 1648 | 12:50:10 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49226/tests/dromaeo/cssquery-yui.html) 12:50:11 INFO - PID 1648 | RSS: Main: 286380032 12:50:11 INFO - PID 1648 | 12:53:13 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-yui.html (next: http://localhost:49226/tests/dromaeo/cssquery-dojo.html) 12:53:13 INFO - PID 1648 | RSS: Main: 289374208 12:53:13 INFO - PID 1648 | 12:53:13 INFO - PID 1648 | __start_tp_report 12:53:13 INFO - PID 1648 | _x_x_mozilla_page_load 12:53:13 INFO - PID 1648 | _x_x_mozilla_page_load_details 12:53:13 INFO - PID 1648 | |i|pagename|runs| 12:53:13 INFO - PID 1648 | |0;dojo.html;3044.2693753499166;3347.1966727985277;3431.7254619630417;3601.6038235794063;3659.7804131752087;6195.876082478348;6211.440970312671;6262.937370626298;6285.497160227188;6298.842528936768;6146.524834039832;6229.781957631484;6259.061140828867;6312.431881130698;6313.242410910686;5083.7712302946275;5285.6300058995885;5485.917711234335;5486.91769623456;5515.448455154493;5269.8155564555245;5531.944680553203;5597.216389705424;5611.2144299798065;5646.2942132233475;5013.473585273567;5037.219231019198;5383.569314454834;5452.700101494416;5502.367227768817;5512.33851937766;5519.365272993617;5540.36285827131;5544.833654990316;5570.108782594765;5426.240326354314;5482.835514934558;5487.0644205350345;5524.309461317335;5524.917126243109;6178.5366097542865;6231.781887633911;6251.249850017982;6292.716827742763;6305.211848518935;6749.3250674932115;6784.321567843225;6788.7623933162595;6804.047433359334;6812.591244525344;6883.552569083044;6883.8967415488805;6893.724251029953;6894.862102757967;6902.827429314257;6261.780837670705;6307.400796924285;6326.49388048955;6357.364263573606;6370.681465926731;6959;7003.54476958996;7008.229094799568;7010.474214433836;7020.122484689414;6374.649394283266;6467.676616169173;6478.935210647927;6570.244421891448;6650.168728908887;6702.966485167641;6819.829504262433;6831.077804496329;6832.350926661959;6836.042953986446;6467.676616169267;6529.869402611921;6722.6302553359055;6727.730890764413;6746.325367463214;6623.602583844985;6635.867282654416;6701.932980670228;6729.629870357079;6732.966335168293;5355.839324820262;5377.381601115843;5382.623216374895;5390.029794637003;5402.216678581583;4710.528947105262;4776.8566942991765;4777.283407488835;4780.95219047805;4816.277558366273;6203.2245969253845;6321.778737744157;6343.175387199634;6357.523185761086;6363.427291543783;6842.486813488908;6850;6854.10896583441;6869.965650171717;6873.656317184221;5240.3973543042275;6364.745410183634;6366.204224471941;6387.552871298964;6390.9360906391275;5808.564357673192;5881.5000724938;5955.464008239279;5998.790042348497;6064.545159113084;7003.3346832050875;7028.156621205488;7037.085178926707;7047.506674532733;7058.682359293742;5951.791687290924;6260.62266301413;6748.898766518408;6895.172579290316;6906.689198985957;6776.525643205126;6855.280195579392;6917.377436030782;6989.30106989297;7061.399781018466;5437.728113594384;6684.398404143456;6694.665266736546;6710.731570737116;6731.932680673131;6152.892479353759;6597.50518711089;6618.271990080991;6853.211880633791;6870.931290687229;5373.382061063028;6534.085228068169;6630.90053649186;6647.335266473314;6681.599104053772;6607.405333520006;6609.4712423005085;6636.369544893228;6639.3692599203;6654.800355989328;6495.447886929664;6516.185476815398;6526.836829079121;6543.5419520633095;6562.5406221565945;6418.486521078209;6487.967560162169;6587.176602924635;6625.105610742488;6667.233268174222;6445.291017987924;6470.514711396759;6505.967470162619;6507.023946408077;6509.511786615928 12:53:13 INFO - PID 1648 | |1;ext.html;659.5152562866293;756.912955010174;827.4332082523472;1243.8445194350707;1310.0698504062116;38413;39459.40810887836;39573.80213098934;39596;39627.80186099066;39468;39827.40258896119;39848.80075599618;39952.80023599878;40025.59974400255;7914.287714105715;8085.514869107843;8437.493750374966;8666.436681615674;8696.130386961286;8525.403221774479;8645;8664;8665.480071195718;8678.739637810875;8625.568721563897;8654.567271636424;8666.696665616704;8675.43609665373;8688.132051608904;7544.62276886159;8394.496330220207;8597.613107410181;8652.005019422753;8662.480251184887;7150.821229469253;8268.83462330756;8282.668693252263;8397.202265784741;8417.821504989306;37707.62292377069;37959.4306085409;37977;38004;38046;3495.5281037059926;3520.559930008749;3536.2043540203504;3540.0972751948348;3542.1144713821545;30722.231944201572;31109.91115310976;31126.533102003486;31138.688613113805;31147.844260778777;3085.9074227773203;3116.548100525411;3120.063980805749;3120.7347375473114;3125.1249650098007;207557.88663170065;239033;242041;298440;299330.50334748405;48364.758176208896;48423;48469.75765121152;48565.02869942652;48642.75678621584;39874.60125398767;50587.98824023499;50676.493235067916;50703.74648126809;50706;13354.866451335361;13501.59495215145;13607.659808504866;14157.71684566303;14257.215853128178;11255.099592032619;11384.089272858151;11404.714882127848;11419.828702569468;11446.484908179153;13732.45070197181;13778.379972901244;13785.379657915419;13789.862101378858;13812.37844297009;5256.842294731164;6210.875782484325;6247.156633854463;6278.215223097113;6312.715927783169;987.8567607696843;999.8550210219478;1000.45975173407;1031.783325501638;1036.828923227659;23063.53872922532;30282;30697.232569185504;31315.84342078275;31400.37199256002;1053.6312290698347;1054.847047178155;1098.6484325016079;1105.8562386889653;1108.1245815805423;10700.013645490208;11520.481578328827;11735.061195104372;11798.52805887755;12536.68658283532;279237;312510;315273;317792.4110379433;319644;54117;54327;54352.3694289172;54448.72775636097;54766;1458.5113986814324;1460.6348412896775;1493.768465887789;1493.7759336099455;1511.4936496273651;18973.71539426882;20065.197392104154;21171.077100549788;23341.949612267148;23791.881040595377;38339.80830095831;41564.1687166261;42071.47571145741;46804.06391872212;47593.762031189624;261786;265897;266882;267041.6647916748;267230.6300090488;37754;38239;38587.421188681634;38670.419943700304;38711.806440967615;48114.03771924612;48173.51826481691;48548;48937;49268.507314927825;31641;31694.841525792224;32345.35309293848;33292.33415331728;33295.83352083224;31565.842170788997;32560.023199304058;32692.83653581812;33225.00324990254;33430.83284583561;37857.81071094737;39178.80410597929;40127.79936100301;40200.798996004836;40347.79826100851;31359.372812543166;32273.51589726203;32758.90513685789;33441.49837752387;34251.486227706104;25178.874105629355;25515.6172657414;25734.356641084123;25773.613395799453;25782.484350312512 12:53:13 INFO - PID 1648 | |2;jquery.html;367.9135403180253;370.95206042928714;399.301222859995;408.95910408959105;456.94973552909175;21706.457338566524;22479.763613001298;22480.10079596818;23175.652365214537;24271.75728242717;23678.408039799015;24173.516529669396;24206;24306.149284775038;24310.635340469864;5623.80316688916;5902.822915312548;6131.049687298465;6271.717772700216;6276.9058464122845;6168.105624684418;6194.256689197303;6208.1308616793685;6249.875002499947;6275.7489700411925;5957.2851257849115;6058.939410605882;6097.725602347883;6101.664408457533;6103.42017508336;6111.938880611204;6132.6013809102715;6136.478399336062;6146.907796383058;6154;5437.483439073281;5458.344998600193;5459.863503412406;5464.672119672832;5480.26016487773;27943.88224471019;29052.27369315784;29184;29261.97583084603;29424;2023;2233.251860626692;2233.6426171812595;2238.7649296823924;2239.5185035217505;18475.2609895604;18658.44024679262;18795.24819007238;18808.341708040152;18810.62378752417;2114.2811444109075;2167.3606286145623;2184.3992901951933;2193.7915897989665;2197.48359135603;157605;165549;176109;179038;180845;13069.477220911247;13269.601911942656;13418.127821691558;13542.729145417234;13699.178049317074;16628.58428539296;16960.067196304073;16995.83004169967;17006.574835628962;17054.062026588657;7716.228377162239;9052.547372631341;9115.088491150831;9344.672936447192;9503.762405939908;8245.257926786619;8309.95845020771;8986.730398088068;9025.909740902638;9058.818823623491;7119.572825630479;8292.12932642076;8338.04112527055;8351.24838764514;8465.095353545526;1951.9512012199525;2006.6287736768745;2274.999000439801;2284.6116160252795;2350.917717879866;825.533573530953;920.3189639666598;922.1055576091181;945.6359301668907;990.2127808392345;18617.720734189;20806.8789681549;20937.5453050245;21742.804145772134;21751.456213594785;911.9498427586547;933.7245512573807;955.6368579939717;985.9507024648739;990.1484723138095;9192.63229470827;10371.94814025925;10431.478426078818;10587.523561439753;11248.831267530994;464785;549563;550585;562179;566377;143920;146270;146576;146853.26573367065;147732;979.9314048016713;1028.1106842581114;1115.0912006714502;1136.9488373023235;1188.8573371195512;21767.782322176576;22506.099756009575;23986.28041158768;24018.15936442216;24080.397990050387;56339;60183;67761.66119169373;68010.65994669995;68188.6590567044;88048.5597572008;88403.55798220968;88654;89138.55430723065;89673;10236.385816851012;10258.487075646039;10290.12533934594;10338.379697218192;10348.172146228435;18120.637587248446;18376.295971848358;18539.35112271118;18579;18666.626667466302;14839.92580037136;14863.702725945204;15147.091174529563;15175;15187.392504299703;13263.867361326264;13546.171266878846;13581.253031083384;13705;14017.649558761113;15096.622584435478;15275.388984440498;15285;15535.223238837787;15573.844261557238;10258.076773090457;10259.384436933808;10330.380177189392;10349.793004139725;10384.948075259575;13819.239941803296;14815.555533334018;14927.402903883723;15009.024413413093;15015.32431040584 12:53:13 INFO - PID 1648 | |3;mootools.html;1261.3819228578;1385.5704731533224;1452.3754785442256;1467.95596132116;1522.535626633877;11613.361265130414;11806.881931180696;12313.938430307859;12319.692007699801;12429.19210251333;12162;12353.382330883465;12373;12418.751624967495;12424.627261182157;2480.9627855582225;2530.2915183748487;2530.291518374858;2532.5441420544294;2540.352210186409;2435.464197876473;2474.4185116497697;2474.6164344526537;2476.158106243877;2484.813638977075;2084.395525297662;2440.9633855492184;2466.6176742604835;2468.7407822178684;2472.765087316702;2411.1560953666253;2425.5755242832433;2436.8903399347073;2440.3411079008656;2442.3405680466176;2411.3730430088126;2418.153646223825;2424.260600516842;2515.496900619865;2519.3071905226025;12033.578824741093;12054;12069.577564785279;12132.878671213262;12156.149069565134;5687.345955215162;6409.102725618371;6529.347065293433;6645.900311495283;6650.168728908887;4076.266272071026;4236.766977816219;4302.010537576343;4303.978480107611;4331.480222373335;1224.4489979509256;1251.5119103549623;1271.68207948013;1271.7774389481804;1277.2272774971195;15652.295646695811;16036.75944860828;16925.23836427364;17073.86576677903;17086.658266834595;5346.465353464622;5426.104692725735;5466.808661696821;5651.350094739076;5691.345495268097;10906.72733181661;12600.306983115834;12614.117011809269;12614.495420183273;12629.052821038274;6376.29860715321;6457.806265812033;6538.444232240313;6539.47684185264;6544.9672751635935;6038.943184942617;6151.6001459904965;6172.166757487771;6187.741474361916;6194.34959329273;4315.395844581761;4414.190182025354;4416.138852923713;4423.579759922803;4427.800748966304;1219.426869371394;1228.9508419663114;1241.6647505173553;1250.849898012226;1250.9374531273581;801.32288216457;810.2626609785067;813.08933993926;837.8575642140729;844.9450785698909;6373.840653983595;6842.213145488333;7590.848183036308;7706.691732330756;7881.684732610746;943.9008904064974;944.2729098594044;953.2612225525135;983.5868935047297;991.5736233419555;4387.956120438818;4688;4744.69159504638;4917.803287868445;4935.407751069894;11932.940335298268;15146.924265378602;15453.381864725285;15460.458883939233;15485.070895746292;14770.187639679922;15162.469313573973;15251.313690883937;15364.231788410758;15432.768508472382;8502.532360720219;8615.6984505542;8667.089955554702;8677.739667809976;8689.13108689126;5521.420250873601;6402.679866006588;7144.535605185646;7396.519226250277;7569.886451703119;12760.553380631633;13032.804507932198;13176.80234796498;13200.359928014244;13264.204147751167;13443;13482.19106853592;13572.93213533926;13636.93181534086;13682;8645.52449615252;9535.618575256913;9675.758106047406;9813.165880900204;9828.11546960777;9124.406913550598;10319;10784.676459706221;10795.784084318113;10921;9212;9225.630974760934;9227.123423274777;9308.394954327947;9323;8950.418222815497;9011.684591039282;9025;9036.141566551167;9082.909170908471;6832.795016149524;7225.5664660120565;7461.141968673672;7571.848563028819;7636.961815190888;6135.509159267159;6221.782237621843;6446.065320528591;6511.120998665119;6617.205935287796;6277.497800176066;6369;6653.866922661617;6681.365270299509;6698.3597580706155 12:53:13 INFO - PID 1648 | |4;prototype.html;334.3463528801193;385.8013123241531;388.64439038279977;396.75599506303615;412.6410023279747;4746.477887432384;6065.938460769365;6151.569390142692;6226.283977342607;6405.167328247321;4471.038726673764;5335.466453354663;6065.968785306497;6196.442320191193;6259.655718935457;4025.75845449274;4471.396361491205;4574.817007319703;4583.610393116581;4592.793324300398;3971.7418367806144;4582.793774280149;4596.402467679214;4604.585587297142;4630.143423466669;3947.723659343847;4529.071540334224;4532.818687252506;4552.795124219418;4553.704009239405;4344.847930322439;4544.500104988449;4572.039871626962;4585.656075794295;4605;3959.5644479107277;3961.3067713150085;3961.504811898513;3964.405339199114;3974.423708562242;6134.263888333428;6150.7390984848;6167.660778657172;6193.504519638418;6247.656378899159;5456.83629491112;5471.37079235889;5489.917651235235;5497.175423686439;5596.3284405871145;6299.307076221612;6708.161479815023;6713.865722685519;6721.295093434615;6726.899096513556;4450.332450132506;4466.7989940452435;4475.239209334421;4479.700886742873;4503.392042074343;5980.372060933626;6009.248843894513;6872;6935.445164386837;7165.89251161233;3972.324704800191;4366.585174408426;4391.67062470316;4538.52345503724;4567.794449249792;4072.0477653701423;4616.76916154191;4621.329907163443;4626.514216007338;4628.39830821991;5341.412444631087;5399.946000539944;5420.647657902224;5442.210879422461;5454.290942177491;5685.374608793029;6588.967055164693;6674.966625166843;6696.263411024784;6708.161479815023;1257.9936051159036;1400.0129908414544;1579.518246934696;1584.8890577659683;1586.9841301586837;1017.9694609161737;1051.4952822645173;1051.500537244818;1053.8155822730992;1059.5708737961138;4931.753412329369;5767.741226207179;6327.588706734047;6336.841578960563;6347.39699728525;6870.45036397097;6983.685734141975;6991.510594258353;7004.754833580801;7008.773464643667;6034.758609655564;6226.1905952226825;6307.463865571386;6323.9051414228825;6335.683215839282;3016.5173572228778;3618.1316484044164;3639.47227651989;3654.926901461956;3657.847777949937;6459.709313080923;6854.794356169323;7156.817170762967;7159.70889315414;7186.964065179641;5823.505002074787;6552.901706474407;7103.431725461951;7184.712611495586;7204.3155900189395;7051.083359163276;7114.181869085018;7135.004397537396;7135.500514963903;7168.820779480555;6580.341965803573;6972.965135174291;6980.162380514371;6991.265917078634;7019.438444924496;6227.941249987422;6348.746050157942;6837.897431538431;6855.4172895304455;6884.690188941409;6483.481321494363;6749.291324410866;7043.647817608997;7117.6085315308155;7135.678894449866;4228.513720922133;4573.307876085736;4583.816647334203;4605.493395726535;4629.1436084324505;6481.5786973848435;6940.479464040318;7029.683664235019;7065.82335441618;7129.180144283267;6630.801075967729;6664.600123992576;6750.9873518972545;6789.642071585604;6822.931770682229;6741.797746067626;6775.120573756232;6789.966050169915;6796.830079248059;6806.876865317167;5537.612367134262;6454.709538070892;6568.441682456853;6572.277049524644;6638.402543770891;5578.748956296895;5640.7590330127305;6357.713902874474;6730.730770769114;6742.25835158142;6490.48076153916;6527.934720652733;6536.934630653633;6545.541812073109;6587.341265873374 12:53:13 INFO - PID 1648 | |5;yui.html;810.9189081091889;813.585071613477;824.212876702749;850.1880703927748;851.0935853316217;13490.59528214154;17297.221625026872;17328.13359332033;17342.653146937053;17377.30490780367;15356.308966096525;15735.134567598778;15753.527394178165;16249.187540622952;16536.83463165368;980.4117529482324;1004.5579944824291;1009.1775203209397;1010.0808264479325;1010.206987514801;980.1032055669054;980.1962390839515;980.289290264556;983.306768728045;986.842105263158;978.3445091788519;1030.5208078243616;1038.48075962019;1039.3971496531992;1041.697907606797;1020.3010937507794;1026.538057873956;1027.3887037212846;1027.866377370937;1031.0823367203195;737.8524295140993;742.0501757750035;758.4008633179782;759.4721668440436;762.8092976755811;17344.739828902577;17410.1294935254;17452.650946981117;17570.38503652366;17633.823661763345;17436.651266974717;17521.912390437967;17702.203400846993;17805.19876605543;17876.642467150585;14886.851131488764;14919.552413427615;14939.850601493954;15140.318685659062;15192.696146077127;3011.2471882029495;3296.3901678189613;3558.2705545363397;3627.7823330599986;3654.6162652921325;117211;140153;140351;142382;143176;13318;13565.711257430528;13918.512852050331;14012;14090.22503762303;26248;26930.730692693214;27160.728392716213;27172.320691982462;27187.72812271852;7821.882671759929;7962.920370796333;7964.323032542299;8089.110197878229;8114.675412983414;3938.0942383252013;3971.8411263549133;3997.7801220932547;4007.358822588372;4014.8394064237104;4410.117976404732;4481.282994720829;4491.842785502492;4496.955030449653;4499.617532509707;893.8346405914926;894.8255090257468;910.1762904571374;911.4349103555838;913.1325241021057;540.8837100023435;674.1943377663645;675.9695813688396;676.8274090106992;685.8868286732733;10532.578696852193;11011.449427528752;11168.274062186094;11271.985521303122;11647.941760291144;634.5780056262533;649.1658219188321;651.0787236060921;670.4904272752646;682.6757290287173;5247.580193584504;5363.651362661414;5550.139728342114;5591.860203494945;5888.116782482662;130951;131154.34422827823;134022;134377.32811336077;135202;74811;115571;115722;115988;117691;97495.1753377271;101885.49057254667;102236.48881755544;105427.40298567152;107352.63236838127;10174;11807.527698891949;11983.400829958293;12285.69285767863;12853.807192892302;46075.539244608466;46312.07375852397;46442.07115857732;47062.35281765259;47071.823204420165;53103.20345194748;56176;61872;62136.378636213056;63321.36678633155;14220.715585688435;16272.267747951608;16630.3347866084;17549.385771498448;17728.55678608045;18263.634727305645;18847.528811779815;19128.426147215607;19279.710804338225;19392.836429814255;17194.968301901925;17880.28478860831;18122.45632631023;18340.816591834446;18340.991245481095;17590.648187036444;17929.37247196394;18292.81707182911;18322.908385457988;18347.52308438397;17069.48791536256;17287.827121729126;17809.020503872413;17919.910400448436;18160.81954672942;11625.76748465009;11789.587364442203;11896.940515297369;12329.260244385365;12389.690257743268;12939.676508087374;13127.146735462164;13532.458701651824;13651;13863.168209907439 12:53:13 INFO - PID 1648 | __end_tp_report 12:53:13 INFO - PID 1648 | __start_cc_report 12:53:13 INFO - PID 1648 | _x_x_mozilla_cycle_collect,451 12:53:13 INFO - PID 1648 | __end_cc_report 12:53:13 INFO - PID 1648 | __startTimestamp1496519593521__endTimestamp 12:53:13 INFO - PID 1648 | 12:53:13 INFO - PID 1648 | ------- Summary: start ------- 12:53:13 INFO - PID 1648 | Number of tests: 6 12:53:13 INFO - PID 1648 | 12:53:13 INFO - PID 1648 | [#0] dojo.html Cycles:165 Average:6199.89 Median:6445.29 stddev:771.53 (12.0%) stddev-sans-first:733.10 12:53:13 INFO - PID 1648 | Values: 3044.3 3347.2 3431.7 3601.6 3659.8 6195.9 6211.4 6262.9 6285.5 6298.8 6146.5 6229.8 6259.1 6312.4 6313.2 5083.8 5285.6 5485.9 5486.9 5515.4 5269.8 5531.9 5597.2 5611.2 5646.3 5013.5 5037.2 5383.6 5452.7 5502.4 5512.3 5519.4 5540.4 5544.8 5570.1 5426.2 5482.8 5487.1 5524.3 5524.9 6178.5 6231.8 6251.2 6292.7 6305.2 6749.3 6784.3 6788.8 6804.0 6812.6 6883.6 6883.9 6893.7 6894.9 6902.8 6261.8 6307.4 6326.5 6357.4 6370.7 6959.0 7003.5 7008.2 7010.5 7020.1 6374.6 6467.7 6478.9 6570.2 6650.2 6703.0 6819.8 6831.1 6832.4 6836.0 6467.7 6529.9 6722.6 6727.7 6746.3 6623.6 6635.9 6701.9 6729.6 6733.0 5355.8 5377.4 5382.6 5390.0 5402.2 4710.5 4776.9 4777.3 4781.0 4816.3 6203.2 6321.8 6343.2 6357.5 6363.4 6842.5 6850.0 6854.1 6870.0 6873.7 5240.4 6364.7 6366.2 6387.6 6390.9 5808.6 5881.5 5955.5 5998.8 6064.5 7003.3 7028.2 7037.1 7047.5 7058.7 5951.8 6260.6 6748.9 6895.2 6906.7 6776.5 6855.3 6917.4 6989.3 7061.4 5437.7 6684.4 6694.7 6710.7 6731.9 6152.9 6597.5 6618.3 6853.2 6870.9 5373.4 6534.1 6630.9 6647.3 6681.6 6607.4 6609.5 6636.4 6639.4 6654.8 6495.4 6516.2 6526.8 6543.5 6562.5 6418.5 6488.0 6587.2 6625.1 6667.2 6445.3 6470.5 6506.0 6507.0 6509.5 12:53:13 INFO - PID 1648 | 12:53:13 INFO - PID 1648 | [#1] ext.html Cycles:180 Average:44046.10 Median:3508.04 stddev:72983.36 (2080.5%) stddev-sans-first:73115.40 12:53:13 INFO - PID 1648 | Values: 659.5 756.9 827.4 1243.8 1310.1 38413.0 39459.4 39573.8 39596.0 39627.8 39468.0 39827.4 39848.8 39952.8 40025.6 7914.3 8085.5 8437.5 8666.4 8696.1 8525.4 8645.0 8664.0 8665.5 8678.7 8625.6 8654.6 8666.7 8675.4 8688.1 7544.6 8394.5 8597.6 8652.0 8662.5 7150.8 8268.8 8282.7 8397.2 8417.8 37707.6 37959.4 37977.0 38004.0 38046.0 3495.5 3520.6 3536.2 3540.1 3542.1 30722.2 31109.9 31126.5 31138.7 31147.8 3085.9 3116.5 3120.1 3120.7 3125.1 207557.9 239033.0 242041.0 298440.0 299330.5 48364.8 48423.0 48469.8 48565.0 48642.8 39874.6 50588.0 50676.5 50703.7 50706.0 13354.9 13501.6 13607.7 14157.7 14257.2 11255.1 11384.1 11404.7 11419.8 11446.5 13732.5 13778.4 13785.4 13789.9 13812.4 5256.8 6210.9 6247.2 6278.2 6312.7 987.9 999.9 1000.5 1031.8 1036.8 23063.5 30282.0 30697.2 31315.8 31400.4 1053.6 1054.8 1098.6 1105.9 1108.1 10700.0 11520.5 11735.1 11798.5 12536.7 279237.0 312510.0 315273.0 317792.4 319644.0 54117.0 54327.0 54352.4 54448.7 54766.0 1458.5 1460.6 1493.8 1493.8 1511.5 18973.7 20065.2 21171.1 23341.9 23791.9 38339.8 41564.2 42071.5 46804.1 47593.8 261786.0 265897.0 266882.0 267041.7 267230.6 37754.0 38239.0 38587.4 38670.4 38711.8 48114.0 48173.5 48548.0 48937.0 49268.5 31641.0 31694.8 32345.4 33292.3 33295.8 31565.8 32560.0 32692.8 33225.0 33430.8 37857.8 39178.8 40127.8 40200.8 40347.8 31359.4 32273.5 32758.9 33441.5 34251.5 25178.9 25515.6 25734.4 25773.6 25782.5 12:53:13 INFO - PID 1648 | 12:53:13 INFO - PID 1648 | [#2] jquery.html Cycles:180 Average:37568.64 Median:2279.81 stddev:92978.25 (4078.3%) stddev-sans-first:93197.12 12:53:13 INFO - PID 1648 | Values: 367.9 371.0 399.3 409.0 456.9 21706.5 22479.8 22480.1 23175.7 24271.8 23678.4 24173.5 24206.0 24306.1 24310.6 5623.8 5902.8 6131.0 6271.7 6276.9 6168.1 6194.3 6208.1 6249.9 6275.7 5957.3 6058.9 6097.7 6101.7 6103.4 6111.9 6132.6 6136.5 6146.9 6154.0 5437.5 5458.3 5459.9 5464.7 5480.3 27943.9 29052.3 29184.0 29262.0 29424.0 2023.0 2233.3 2233.6 2238.8 2239.5 18475.3 18658.4 18795.2 18808.3 18810.6 2114.3 2167.4 2184.4 2193.8 2197.5 157605.0 165549.0 176109.0 179038.0 180845.0 13069.5 13269.6 13418.1 13542.7 13699.2 16628.6 16960.1 16995.8 17006.6 17054.1 7716.2 9052.5 9115.1 9344.7 9503.8 8245.3 8310.0 8986.7 9025.9 9058.8 7119.6 8292.1 8338.0 8351.2 8465.1 1952.0 2006.6 2275.0 2284.6 2350.9 825.5 920.3 922.1 945.6 990.2 18617.7 20806.9 20937.5 21742.8 21751.5 911.9 933.7 955.6 986.0 990.1 9192.6 10371.9 10431.5 10587.5 11248.8 464785.0 549563.0 550585.0 562179.0 566377.0 143920.0 146270.0 146576.0 146853.3 147732.0 979.9 1028.1 1115.1 1136.9 1188.9 21767.8 22506.1 23986.3 24018.2 24080.4 56339.0 60183.0 67761.7 68010.7 68188.7 88048.6 88403.6 88654.0 89138.6 89673.0 10236.4 10258.5 10290.1 10338.4 10348.2 18120.6 18376.3 18539.4 18579.0 18666.6 14839.9 14863.7 15147.1 15175.0 15187.4 13263.9 13546.2 13581.3 13705.0 14017.6 15096.6 15275.4 15285.0 15535.2 15573.8 10258.1 10259.4 10330.4 10349.8 10384.9 13819.2 14815.6 14927.4 15009.0 15015.3 12:53:13 INFO - PID 1648 | 12:53:13 INFO - PID 1648 | [#3] mootools.html Cycles:180 Average:7193.04 Median:4269.39 stddev:4541.42 (106.4%) stddev-sans-first:4532.28 12:53:13 INFO - PID 1648 | Values: 1261.4 1385.6 1452.4 1468.0 1522.5 11613.4 11806.9 12313.9 12319.7 12429.2 12162.0 12353.4 12373.0 12418.8 12424.6 2481.0 2530.3 2530.3 2532.5 2540.4 2435.5 2474.4 2474.6 2476.2 2484.8 2084.4 2441.0 2466.6 2468.7 2472.8 2411.2 2425.6 2436.9 2440.3 2442.3 2411.4 2418.2 2424.3 2515.5 2519.3 12033.6 12054.0 12069.6 12132.9 12156.1 5687.3 6409.1 6529.3 6645.9 6650.2 4076.3 4236.8 4302.0 4304.0 4331.5 1224.4 1251.5 1271.7 1271.8 1277.2 15652.3 16036.8 16925.2 17073.9 17086.7 5346.5 5426.1 5466.8 5651.4 5691.3 10906.7 12600.3 12614.1 12614.5 12629.1 6376.3 6457.8 6538.4 6539.5 6545.0 6038.9 6151.6 6172.2 6187.7 6194.3 4315.4 4414.2 4416.1 4423.6 4427.8 1219.4 1229.0 1241.7 1250.8 1250.9 801.3 810.3 813.1 837.9 844.9 6373.8 6842.2 7590.8 7706.7 7881.7 943.9 944.3 953.3 983.6 991.6 4388.0 4688.0 4744.7 4917.8 4935.4 11932.9 15146.9 15453.4 15460.5 15485.1 14770.2 15162.5 15251.3 15364.2 15432.8 8502.5 8615.7 8667.1 8677.7 8689.1 5521.4 6402.7 7144.5 7396.5 7569.9 12760.6 13032.8 13176.8 13200.4 13264.2 13443.0 13482.2 13572.9 13636.9 13682.0 8645.5 9535.6 9675.8 9813.2 9828.1 9124.4 10319.0 10784.7 10795.8 10921.0 9212.0 9225.6 9227.1 9308.4 9323.0 8950.4 9011.7 9025.0 9036.1 9082.9 6832.8 7225.6 7461.1 7571.8 7637.0 6135.5 6221.8 6446.1 6511.1 6617.2 6277.5 6369.0 6653.9 6681.4 6698.4 12:53:13 INFO - PID 1648 | 12:53:13 INFO - PID 1648 | [#4] prototype.html Cycles:180 Average:5403.83 Median:6142.50 stddev:1718.73 (28.0%) stddev-sans-first:1680.91 12:53:13 INFO - PID 1648 | Values: 334.3 385.8 388.6 396.8 412.6 4746.5 6065.9 6151.6 6226.3 6405.2 4471.0 5335.5 6066.0 6196.4 6259.7 4025.8 4471.4 4574.8 4583.6 4592.8 3971.7 4582.8 4596.4 4604.6 4630.1 3947.7 4529.1 4532.8 4552.8 4553.7 4344.8 4544.5 4572.0 4585.7 4605.0 3959.6 3961.3 3961.5 3964.4 3974.4 6134.3 6150.7 6167.7 6193.5 6247.7 5456.8 5471.4 5489.9 5497.2 5596.3 6299.3 6708.2 6713.9 6721.3 6726.9 4450.3 4466.8 4475.2 4479.7 4503.4 5980.4 6009.2 6872.0 6935.4 7165.9 3972.3 4366.6 4391.7 4538.5 4567.8 4072.0 4616.8 4621.3 4626.5 4628.4 5341.4 5399.9 5420.6 5442.2 5454.3 5685.4 6589.0 6675.0 6696.3 6708.2 1258.0 1400.0 1579.5 1584.9 1587.0 1018.0 1051.5 1051.5 1053.8 1059.6 4931.8 5767.7 6327.6 6336.8 6347.4 6870.5 6983.7 6991.5 7004.8 7008.8 6034.8 6226.2 6307.5 6323.9 6335.7 3016.5 3618.1 3639.5 3654.9 3657.8 6459.7 6854.8 7156.8 7159.7 7187.0 5823.5 6552.9 7103.4 7184.7 7204.3 7051.1 7114.2 7135.0 7135.5 7168.8 6580.3 6973.0 6980.2 6991.3 7019.4 6227.9 6348.7 6837.9 6855.4 6884.7 6483.5 6749.3 7043.6 7117.6 7135.7 4228.5 4573.3 4583.8 4605.5 4629.1 6481.6 6940.5 7029.7 7065.8 7129.2 6630.8 6664.6 6751.0 6789.6 6822.9 6741.8 6775.1 6790.0 6796.8 6806.9 5537.6 6454.7 6568.4 6572.3 6638.4 5578.7 5640.8 6357.7 6730.7 6742.3 6490.5 6527.9 6536.9 6545.5 6587.3 12:53:13 INFO - PID 1648 | 12:53:13 INFO - PID 1648 | [#5] yui.html Cycles:180 Average:24464.76 Median:17878.46 stddev:36543.69 (204.4%) stddev-sans-first:36603.05 12:53:13 INFO - PID 1648 | Values: 810.9 813.6 824.2 850.2 851.1 13490.6 17297.2 17328.1 17342.7 17377.3 15356.3 15735.1 15753.5 16249.2 16536.8 980.4 1004.6 1009.2 1010.1 1010.2 980.1 980.2 980.3 983.3 986.8 978.3 1030.5 1038.5 1039.4 1041.7 1020.3 1026.5 1027.4 1027.9 1031.1 737.9 742.1 758.4 759.5 762.8 17344.7 17410.1 17452.7 17570.4 17633.8 17436.7 17521.9 17702.2 17805.2 17876.6 14886.9 14919.6 14939.9 15140.3 15192.7 3011.2 3296.4 3558.3 3627.8 3654.6 117211.0 140153.0 140351.0 142382.0 143176.0 13318.0 13565.7 13918.5 14012.0 14090.2 26248.0 26930.7 27160.7 27172.3 27187.7 7821.9 7962.9 7964.3 8089.1 8114.7 3938.1 3971.8 3997.8 4007.4 4014.8 4410.1 4481.3 4491.8 4497.0 4499.6 893.8 894.8 910.2 911.4 913.1 540.9 674.2 676.0 676.8 685.9 10532.6 11011.4 11168.3 11272.0 11647.9 634.6 649.2 651.1 670.5 682.7 5247.6 5363.7 5550.1 5591.9 5888.1 130951.0 131154.3 134022.0 134377.3 135202.0 74811.0 115571.0 115722.0 115988.0 117691.0 97495.2 101885.5 102236.5 105427.4 107352.6 10174.0 11807.5 11983.4 12285.7 12853.8 46075.5 46312.1 46442.1 47062.4 47071.8 53103.2 56176.0 61872.0 62136.4 63321.4 14220.7 16272.3 16630.3 17549.4 17728.6 18263.6 18847.5 19128.4 19279.7 19392.8 17195.0 17880.3 18122.5 18340.8 18341.0 17590.6 17929.4 18292.8 18322.9 18347.5 17069.5 17287.8 17809.0 17919.9 18160.8 11625.8 11789.6 11896.9 12329.3 12389.7 12939.7 13127.1 13532.5 13651.0 13863.2 12:53:13 INFO - PID 1648 | -------- Summary: end -------- 12:53:13 INFO - PID 1648 | 12:53:14 INFO - TEST-INFO | 1648: exit 0 12:53:14 INFO - TEST-OK | dromaeo_css | took 1143000ms 12:53:14 INFO - TEST-START | kraken 12:53:14 INFO - operating with platform_type : mac_ 12:53:14 INFO - Initialising browser for kraken test... 12:53:14 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49226/getInfo.html -foreground -profile /var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/tmpyen6pu/profile 12:53:14 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49226/getInfo.html -foreground) 12:53:48 INFO - TEST-INFO | 1657: killed by SIGTERM 12:53:48 INFO - Browser initialized. 12:53:48 INFO - Running cycle 1/1 for kraken test... 12:53:48 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/tmpyen6pu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:53:50 INFO - PID 1661 | 2017-06-03 12:53:50.296 plugin-container[1662:11942] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x983f, name = 'com.apple.tsm.portname' 12:53:50 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:53:50 INFO - PID 1661 | 2017-06-03 12:53:50.297 plugin-container[1662:11942] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d0b, name = 'com.apple.CFPasteboardClient' 12:53:50 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:53:50 INFO - PID 1661 | 2017-06-03 12:53:50.297 plugin-container[1662:11942] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:53:50 INFO - PID 1661 | 2017-06-03 12:53:50.819 plugin-container[1663:11989] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 12:53:50 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:53:50 INFO - PID 1661 | 2017-06-03 12:53:50.820 plugin-container[1663:11989] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a13, name = 'com.apple.CFPasteboardClient' 12:53:50 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:53:50 INFO - PID 1661 | 2017-06-03 12:53:50.820 plugin-container[1663:11989] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:54:00 INFO - PID 1661 | RSS: Main: 269045760 12:54:00 INFO - PID 1661 | 12:54:02 INFO - PID 1661 | 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) 12:54:03 INFO - PID 1661 | RSS: Main: 276594688 12:54:03 INFO - PID 1661 | 12:54:06 INFO - PID 1661 | 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) 12:54:06 INFO - PID 1661 | RSS: Main: 287154176 12:54:06 INFO - PID 1661 | 12:54:10 INFO - PID 1661 | 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) 12:54:10 INFO - PID 1661 | RSS: Main: 285175808 12:54:10 INFO - PID 1661 | 12:54:12 INFO - PID 1661 | 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) 12:54:13 INFO - PID 1661 | RSS: Main: 287944704 12:54:13 INFO - PID 1661 | 12:54:15 INFO - PID 1661 | 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) 12:54:16 INFO - PID 1661 | RSS: Main: 288460800 12:54:16 INFO - PID 1661 | 12:54:18 INFO - PID 1661 | 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) 12:54:19 INFO - PID 1661 | RSS: Main: 293220352 12:54:19 INFO - PID 1661 | 12:54:22 INFO - PID 1661 | 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) 12:54:23 INFO - PID 1661 | RSS: Main: 295841792 12:54:23 INFO - PID 1661 | 12:54:26 INFO - PID 1661 | 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) 12:54:26 INFO - PID 1661 | RSS: Main: 294666240 12:54:26 INFO - PID 1661 | 12:54:28 INFO - PID 1661 | 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) 12:54:28 INFO - PID 1661 | RSS: Main: 294326272 12:54:28 INFO - PID 1661 | 12:54:30 INFO - PID 1661 | 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) 12:54:31 INFO - PID 1661 | RSS: Main: 294100992 12:54:31 INFO - PID 1661 | 12:54:33 INFO - PID 1661 | 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) 12:54:34 INFO - PID 1661 | RSS: Main: 294297600 12:54:34 INFO - PID 1661 | 12:54:37 INFO - PID 1661 | 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) 12:54:37 INFO - PID 1661 | RSS: Main: 294400000 12:54:37 INFO - PID 1661 | 12:54:40 INFO - PID 1661 | 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) 12:54:41 INFO - PID 1661 | RSS: Main: 294699008 12:54:41 INFO - PID 1661 | 12:54:43 INFO - PID 1661 | 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) 12:54:43 INFO - PID 1661 | RSS: Main: 296349696 12:54:43 INFO - PID 1661 | 12:54:43 INFO - PID 1661 | __start_tp_report 12:54:43 INFO - PID 1661 | _x_x_mozilla_page_load 12:54:43 INFO - PID 1661 | _x_x_mozilla_page_load_details 12:54:43 INFO - PID 1661 | |i|pagename|runs| 12:54:43 INFO - PID 1661 | |0;ai-astar;101;118;114;114;114;115;114;113;115;113 12:54:43 INFO - PID 1661 | |1;audio-beat-detection;139;113;139;191;117;111;110;117;143;113 12:54:43 INFO - PID 1661 | |2;audio-dft;199;202;198;211;215;212;225;196;201;199 12:54:43 INFO - PID 1661 | |3;audio-fft;81;59;61;89;74;50;68;83;93;85 12:54:43 INFO - PID 1661 | |4;audio-oscillator;86;69;82;72;85;100;97;70;68;90 12:54:43 INFO - PID 1661 | |5;imaging-gaussian-blur;89;91;89;91;91;89;91;89;91;89 12:54:43 INFO - PID 1661 | |6;imaging-darkroom;100;98;96;96;97;97;96;96;97;97 12:54:43 INFO - PID 1661 | |7;imaging-desaturate;125;92;98;92;89;91;91;90;89;90 12:54:43 INFO - PID 1661 | |8;json-parse-financial;50;47;50;52;52;51;51;50;51;49 12:54:43 INFO - PID 1661 | |9;json-stringify-tinderbox;57;60;63;63;65;60;57;56;59;59 12:54:43 INFO - PID 1661 | |10;stanford-crypto-aes;87;86;84;86;90;98;109;89;122;80 12:54:43 INFO - PID 1661 | |11;stanford-crypto-ccm;134;144;130;132;136;139;153;151;141;138 12:54:43 INFO - PID 1661 | |12;stanford-crypto-pbkdf2;230;222;186;186;187;182;182;199;187;186 12:54:43 INFO - PID 1661 | |13;stanford-crypto-sha256-iterative;67;70;67;67;67;68;60;64;61;69 12:54:43 INFO - PID 1661 | __end_tp_report 12:54:43 INFO - PID 1661 | __start_cc_report 12:54:43 INFO - PID 1661 | _x_x_mozilla_cycle_collect,1213 12:54:43 INFO - PID 1661 | __end_cc_report 12:54:43 INFO - PID 1661 | __startTimestamp1496519683460__endTimestamp 12:54:43 INFO - PID 1661 | 12:54:43 INFO - PID 1661 | ------- Summary: start ------- 12:54:43 INFO - PID 1661 | Number of tests: 14 12:54:43 INFO - PID 1661 | 12:54:43 INFO - PID 1661 | [#0] ai-astar Cycles:10 Average:113.10 Median:114.00 stddev:4.48 (3.9%) stddev-sans-first:1.51 12:54:43 INFO - PID 1661 | Values: 101.0 118.0 114.0 114.0 114.0 115.0 114.0 113.0 115.0 113.0 12:54:43 INFO - PID 1661 | 12:54:43 INFO - PID 1661 | [#1] audio-beat-detection Cycles:10 Average:129.30 Median:128.00 stddev:25.22 (19.7%) stddev-sans-first:26.50 12:54:43 INFO - PID 1661 | Values: 139.0 113.0 139.0 191.0 117.0 111.0 110.0 117.0 143.0 113.0 12:54:43 INFO - PID 1661 | 12:54:43 INFO - PID 1661 | [#2] audio-dft Cycles:10 Average:205.80 Median:206.50 stddev:9.46 (4.6%) stddev-sans-first:9.71 12:54:43 INFO - PID 1661 | Values: 199.0 202.0 198.0 211.0 215.0 212.0 225.0 196.0 201.0 199.0 12:54:43 INFO - PID 1661 | 12:54:43 INFO - PID 1661 | [#3] audio-fft Cycles:10 Average:74.30 Median:82.00 stddev:14.31 (17.4%) stddev-sans-first:14.97 12:54:43 INFO - PID 1661 | Values: 81.0 59.0 61.0 89.0 74.0 50.0 68.0 83.0 93.0 85.0 12:54:43 INFO - PID 1661 | 12:54:43 INFO - PID 1661 | [#4] audio-oscillator Cycles:10 Average:81.90 Median:83.50 stddev:11.77 (14.1%) stddev-sans-first:12.39 12:54:43 INFO - PID 1661 | Values: 86.0 69.0 82.0 72.0 85.0 100.0 97.0 70.0 68.0 90.0 12:54:43 INFO - PID 1661 | 12:54:43 INFO - PID 1661 | [#5] imaging-gaussian-blur Cycles:10 Average:90.00 Median:91.00 stddev:1.05 (1.2%) stddev-sans-first:1.05 12:54:43 INFO - PID 1661 | Values: 89.0 91.0 89.0 91.0 91.0 89.0 91.0 89.0 91.0 89.0 12:54:43 INFO - PID 1661 | 12:54:43 INFO - PID 1661 | [#6] imaging-darkroom Cycles:10 Average:97.00 Median:97.00 stddev:1.25 (1.3%) stddev-sans-first:0.71 12:54:43 INFO - PID 1661 | Values: 100.0 98.0 96.0 96.0 97.0 97.0 96.0 96.0 97.0 97.0 12:54:43 INFO - PID 1661 | 12:54:43 INFO - PID 1661 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:91.00 stddev:10.95 (12.0%) stddev-sans-first:2.74 12:54:43 INFO - PID 1661 | Values: 125.0 92.0 98.0 92.0 89.0 91.0 91.0 90.0 89.0 90.0 12:54:43 INFO - PID 1661 | 12:54:43 INFO - PID 1661 | [#8] json-parse-financial Cycles:10 Average:50.30 Median:51.00 stddev:1.49 (2.9%) stddev-sans-first:1.58 12:54:43 INFO - PID 1661 | Values: 50.0 47.0 50.0 52.0 52.0 51.0 51.0 50.0 51.0 49.0 12:54:43 INFO - PID 1661 | 12:54:43 INFO - PID 1661 | [#9] json-stringify-tinderbox Cycles:10 Average:59.90 Median:60.00 stddev:2.96 (4.9%) stddev-sans-first:2.95 12:54:43 INFO - PID 1661 | Values: 57.0 60.0 63.0 63.0 65.0 60.0 57.0 56.0 59.0 59.0 12:54:43 INFO - PID 1661 | 12:54:43 INFO - PID 1661 | [#10] stanford-crypto-aes Cycles:10 Average:93.10 Median:86.50 stddev:13.04 (15.1%) stddev-sans-first:13.65 12:54:43 INFO - PID 1661 | Values: 87.0 86.0 84.0 86.0 90.0 98.0 109.0 89.0 122.0 80.0 12:54:43 INFO - PID 1661 | 12:54:43 INFO - PID 1661 | [#11] stanford-crypto-ccm Cycles:10 Average:139.80 Median:140.00 stddev:7.66 (5.5%) stddev-sans-first:7.83 12:54:43 INFO - PID 1661 | Values: 134.0 144.0 130.0 132.0 136.0 139.0 153.0 151.0 141.0 138.0 12:54:43 INFO - PID 1661 | 12:54:43 INFO - PID 1661 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:194.70 Median:187.00 stddev:17.25 (9.2%) stddev-sans-first:12.72 12:54:43 INFO - PID 1661 | Values: 230.0 222.0 186.0 186.0 187.0 182.0 182.0 199.0 187.0 186.0 12:54:43 INFO - PID 1661 | 12:54:43 INFO - PID 1661 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.00 Median:67.00 stddev:3.30 (4.9%) stddev-sans-first:3.48 12:54:43 INFO - PID 1661 | Values: 67.0 70.0 67.0 67.0 67.0 68.0 60.0 64.0 61.0 69.0 12:54:43 INFO - PID 1661 | -------- Summary: end -------- 12:54:43 INFO - PID 1661 | 12:54:44 INFO - TEST-INFO | 1661: exit 0 12:54:44 INFO - TEST-OK | kraken | took 90143ms 12:54:44 INFO - SUITE-END | took 1233s 12:54:44 INFO - Completed test suite (00:20:33) 12:54:44 INFO - javascript benchmark 12:54:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8085.9309722650605, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3044.2693753499166, 3347.1966727985277, 3431.7254619630417, 3601.6038235794063, 3659.7804131752087, 6195.876082478348, 6211.440970312671, 6262.937370626298, 6285.497160227188, 6298.842528936768, 6146.524834039832, 6229.781957631484, 6259.061140828867, 6312.431881130698, 6313.242410910686, 5083.7712302946275, 5285.6300058995885, 5485.917711234335, 5486.91769623456, 5515.448455154493, 5269.8155564555245, 5531.944680553203, 5597.216389705424, 5611.2144299798065, 5646.2942132233475, 5013.473585273567, 5037.219231019198, 5383.569314454834, 5452.700101494416, 5502.367227768817, 5512.33851937766, 5519.365272993617, 5540.36285827131, 5544.833654990316, 5570.108782594765, 5426.240326354314, 5482.835514934558, 5487.0644205350345, 5524.309461317335, 5524.917126243109, 6178.5366097542865, 6231.781887633911, 6251.249850017982, 6292.716827742763, 6305.211848518935, 6749.3250674932115, 6784.321567843225, 6788.7623933162595, 6804.047433359334, 6812.591244525344, 6883.552569083044, 6883.8967415488805, 6893.724251029953, 6894.862102757967, 6902.827429314257, 6261.780837670705, 6307.400796924285, 6326.49388048955, 6357.364263573606, 6370.681465926731, 6959.0, 7003.54476958996, 7008.229094799568, 7010.474214433836, 7020.122484689414, 6374.649394283266, 6467.676616169173, 6478.935210647927, 6570.244421891448, 6650.168728908887, 6702.966485167641, 6819.829504262433, 6831.077804496329, 6832.350926661959, 6836.042953986446, 6467.676616169267, 6529.869402611921, 6722.6302553359055, 6727.730890764413, 6746.325367463214, 6623.602583844985, 6635.867282654416, 6701.932980670228, 6729.629870357079, 6732.966335168293, 5355.839324820262, 5377.381601115843, 5382.623216374895, 5390.029794637003, 5402.216678581583, 4710.528947105262, 4776.8566942991765, 4777.283407488835, 4780.95219047805, 4816.277558366273, 6203.2245969253845, 6321.778737744157, 6343.175387199634, 6357.523185761086, 6363.427291543783, 6842.486813488908, 6850.0, 6854.10896583441, 6869.965650171717, 6873.656317184221, 5240.3973543042275, 6364.745410183634, 6366.204224471941, 6387.552871298964, 6390.9360906391275, 5808.564357673192, 5881.5000724938, 5955.464008239279, 5998.790042348497, 6064.545159113084, 7003.3346832050875, 7028.156621205488, 7037.085178926707, 7047.506674532733, 7058.682359293742, 5951.791687290924, 6260.62266301413, 6748.898766518408, 6895.172579290316, 6906.689198985957, 6776.525643205126, 6855.280195579392, 6917.377436030782, 6989.30106989297, 7061.399781018466, 5437.728113594384, 6684.398404143456, 6694.665266736546, 6710.731570737116, 6731.932680673131, 6152.892479353759, 6597.50518711089, 6618.271990080991, 6853.211880633791, 6870.931290687229, 5373.382061063028, 6534.085228068169, 6630.90053649186, 6647.335266473314, 6681.599104053772, 6607.405333520006, 6609.4712423005085, 6636.369544893228, 6639.3692599203, 6654.800355989328, 6495.447886929664, 6516.185476815398, 6526.836829079121, 6543.5419520633095, 6562.5406221565945, 6418.486521078209, 6487.967560162169, 6587.176602924635, 6625.105610742488, 6667.233268174222, 6445.291017987924, 6470.514711396759, 6505.967470162619, 6507.023946408077, 6509.511786615928], "value": 6145.522184337033, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [659.5152562866293, 756.912955010174, 827.4332082523472, 1243.8445194350707, 1310.0698504062116, 38413.0, 39459.40810887836, 39573.80213098934, 39596.0, 39627.80186099066, 39468.0, 39827.40258896119, 39848.80075599618, 39952.80023599878, 40025.59974400255, 7914.287714105715, 8085.514869107843, 8437.493750374966, 8666.436681615674, 8696.130386961286, 8525.403221774479, 8645.0, 8664.0, 8665.480071195718, 8678.739637810875, 8625.568721563897, 8654.567271636424, 8666.696665616704, 8675.43609665373, 8688.132051608904, 7544.62276886159, 8394.496330220207, 8597.613107410181, 8652.005019422753, 8662.480251184887, 7150.821229469253, 8268.83462330756, 8282.668693252263, 8397.202265784741, 8417.821504989306, 37707.62292377069, 37959.4306085409, 37977.0, 38004.0, 38046.0, 3495.5281037059926, 3520.559930008749, 3536.2043540203504, 3540.0972751948348, 3542.1144713821545, 30722.231944201572, 31109.91115310976, 31126.533102003486, 31138.688613113805, 31147.844260778777, 3085.9074227773203, 3116.548100525411, 3120.063980805749, 3120.7347375473114, 3125.1249650098007, 207557.88663170065, 239033.0, 242041.0, 298440.0, 299330.50334748405, 48364.758176208896, 48423.0, 48469.75765121152, 48565.02869942652, 48642.75678621584, 39874.60125398767, 50587.98824023499, 50676.493235067916, 50703.74648126809, 50706.0, 13354.866451335361, 13501.59495215145, 13607.659808504866, 14157.71684566303, 14257.215853128178, 11255.099592032619, 11384.089272858151, 11404.714882127848, 11419.828702569468, 11446.484908179153, 13732.45070197181, 13778.379972901244, 13785.379657915419, 13789.862101378858, 13812.37844297009, 5256.842294731164, 6210.875782484325, 6247.156633854463, 6278.215223097113, 6312.715927783169, 987.8567607696843, 999.8550210219478, 1000.45975173407, 1031.783325501638, 1036.828923227659, 23063.53872922532, 30282.0, 30697.232569185504, 31315.84342078275, 31400.37199256002, 1053.6312290698347, 1054.847047178155, 1098.6484325016079, 1105.8562386889653, 1108.1245815805423, 10700.013645490208, 11520.481578328827, 11735.061195104372, 11798.52805887755, 12536.68658283532, 279237.0, 312510.0, 315273.0, 317792.4110379433, 319644.0, 54117.0, 54327.0, 54352.3694289172, 54448.72775636097, 54766.0, 1458.5113986814324, 1460.6348412896775, 1493.768465887789, 1493.7759336099455, 1511.4936496273651, 18973.71539426882, 20065.197392104154, 21171.077100549788, 23341.949612267148, 23791.881040595377, 38339.80830095831, 41564.1687166261, 42071.47571145741, 46804.06391872212, 47593.762031189624, 261786.0, 265897.0, 266882.0, 267041.6647916748, 267230.6300090488, 37754.0, 38239.0, 38587.421188681634, 38670.419943700304, 38711.806440967615, 48114.03771924612, 48173.51826481691, 48548.0, 48937.0, 49268.507314927825, 31641.0, 31694.841525792224, 32345.35309293848, 33292.33415331728, 33295.83352083224, 31565.842170788997, 32560.023199304058, 32692.83653581812, 33225.00324990254, 33430.83284583561, 37857.81071094737, 39178.80410597929, 40127.79936100301, 40200.798996004836, 40347.79826100851, 31359.372812543166, 32273.51589726203, 32758.90513685789, 33441.49837752387, 34251.486227706104, 25178.874105629355, 25515.6172657414, 25734.356641084123, 25773.613395799453, 25782.484350312512], "value": 17781.249705791084, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [367.9135403180253, 370.95206042928714, 399.301222859995, 408.95910408959105, 456.94973552909175, 21706.457338566524, 22479.763613001298, 22480.10079596818, 23175.652365214537, 24271.75728242717, 23678.408039799015, 24173.516529669396, 24206.0, 24306.149284775038, 24310.635340469864, 5623.80316688916, 5902.822915312548, 6131.049687298465, 6271.717772700216, 6276.9058464122845, 6168.105624684418, 6194.256689197303, 6208.1308616793685, 6249.875002499947, 6275.7489700411925, 5957.2851257849115, 6058.939410605882, 6097.725602347883, 6101.664408457533, 6103.42017508336, 6111.938880611204, 6132.6013809102715, 6136.478399336062, 6146.907796383058, 6154.0, 5437.483439073281, 5458.344998600193, 5459.863503412406, 5464.672119672832, 5480.26016487773, 27943.88224471019, 29052.27369315784, 29184.0, 29261.97583084603, 29424.0, 2023.0, 2233.251860626692, 2233.6426171812595, 2238.7649296823924, 2239.5185035217505, 18475.2609895604, 18658.44024679262, 18795.24819007238, 18808.341708040152, 18810.62378752417, 2114.2811444109075, 2167.3606286145623, 2184.3992901951933, 2193.7915897989665, 2197.48359135603, 157605.0, 165549.0, 176109.0, 179038.0, 180845.0, 13069.477220911247, 13269.601911942656, 13418.127821691558, 13542.729145417234, 13699.178049317074, 16628.58428539296, 16960.067196304073, 16995.83004169967, 17006.574835628962, 17054.062026588657, 7716.228377162239, 9052.547372631341, 9115.088491150831, 9344.672936447192, 9503.762405939908, 8245.257926786619, 8309.95845020771, 8986.730398088068, 9025.909740902638, 9058.818823623491, 7119.572825630479, 8292.12932642076, 8338.04112527055, 8351.24838764514, 8465.095353545526, 1951.9512012199525, 2006.6287736768745, 2274.999000439801, 2284.6116160252795, 2350.917717879866, 825.533573530953, 920.3189639666598, 922.1055576091181, 945.6359301668907, 990.2127808392345, 18617.720734189, 20806.8789681549, 20937.5453050245, 21742.804145772134, 21751.456213594785, 911.9498427586547, 933.7245512573807, 955.6368579939717, 985.9507024648739, 990.1484723138095, 9192.63229470827, 10371.94814025925, 10431.478426078818, 10587.523561439753, 11248.831267530994, 464785.0, 549563.0, 550585.0, 562179.0, 566377.0, 143920.0, 146270.0, 146576.0, 146853.26573367065, 147732.0, 979.9314048016713, 1028.1106842581114, 1115.0912006714502, 1136.9488373023235, 1188.8573371195512, 21767.782322176576, 22506.099756009575, 23986.28041158768, 24018.15936442216, 24080.397990050387, 56339.0, 60183.0, 67761.66119169373, 68010.65994669995, 68188.6590567044, 88048.5597572008, 88403.55798220968, 88654.0, 89138.55430723065, 89673.0, 10236.385816851012, 10258.487075646039, 10290.12533934594, 10338.379697218192, 10348.172146228435, 18120.637587248446, 18376.295971848358, 18539.35112271118, 18579.0, 18666.626667466302, 14839.92580037136, 14863.702725945204, 15147.091174529563, 15175.0, 15187.392504299703, 13263.867361326264, 13546.171266878846, 13581.253031083384, 13705.0, 14017.649558761113, 15096.622584435478, 15275.388984440498, 15285.0, 15535.223238837787, 15573.844261557238, 10258.076773090457, 10259.384436933808, 10330.380177189392, 10349.793004139725, 10384.948075259575, 13819.239941803296, 14815.555533334018, 14927.402903883723, 15009.024413413093, 15015.32431040584], "value": 11121.662405068662, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1261.3819228578, 1385.5704731533224, 1452.3754785442256, 1467.95596132116, 1522.535626633877, 11613.361265130414, 11806.881931180696, 12313.938430307859, 12319.692007699801, 12429.19210251333, 12162.0, 12353.382330883465, 12373.0, 12418.751624967495, 12424.627261182157, 2480.9627855582225, 2530.2915183748487, 2530.291518374858, 2532.5441420544294, 2540.352210186409, 2435.464197876473, 2474.4185116497697, 2474.6164344526537, 2476.158106243877, 2484.813638977075, 2084.395525297662, 2440.9633855492184, 2466.6176742604835, 2468.7407822178684, 2472.765087316702, 2411.1560953666253, 2425.5755242832433, 2436.8903399347073, 2440.3411079008656, 2442.3405680466176, 2411.3730430088126, 2418.153646223825, 2424.260600516842, 2515.496900619865, 2519.3071905226025, 12033.578824741093, 12054.0, 12069.577564785279, 12132.878671213262, 12156.149069565134, 5687.345955215162, 6409.102725618371, 6529.347065293433, 6645.900311495283, 6650.168728908887, 4076.266272071026, 4236.766977816219, 4302.010537576343, 4303.978480107611, 4331.480222373335, 1224.4489979509256, 1251.5119103549623, 1271.68207948013, 1271.7774389481804, 1277.2272774971195, 15652.295646695811, 16036.75944860828, 16925.23836427364, 17073.86576677903, 17086.658266834595, 5346.465353464622, 5426.104692725735, 5466.808661696821, 5651.350094739076, 5691.345495268097, 10906.72733181661, 12600.306983115834, 12614.117011809269, 12614.495420183273, 12629.052821038274, 6376.29860715321, 6457.806265812033, 6538.444232240313, 6539.47684185264, 6544.9672751635935, 6038.943184942617, 6151.6001459904965, 6172.166757487771, 6187.741474361916, 6194.34959329273, 4315.395844581761, 4414.190182025354, 4416.138852923713, 4423.579759922803, 4427.800748966304, 1219.426869371394, 1228.9508419663114, 1241.6647505173553, 1250.849898012226, 1250.9374531273581, 801.32288216457, 810.2626609785067, 813.08933993926, 837.8575642140729, 844.9450785698909, 6373.840653983595, 6842.213145488333, 7590.848183036308, 7706.691732330756, 7881.684732610746, 943.9008904064974, 944.2729098594044, 953.2612225525135, 983.5868935047297, 991.5736233419555, 4387.956120438818, 4688.0, 4744.69159504638, 4917.803287868445, 4935.407751069894, 11932.940335298268, 15146.924265378602, 15453.381864725285, 15460.458883939233, 15485.070895746292, 14770.187639679922, 15162.469313573973, 15251.313690883937, 15364.231788410758, 15432.768508472382, 8502.532360720219, 8615.6984505542, 8667.089955554702, 8677.739667809976, 8689.13108689126, 5521.420250873601, 6402.679866006588, 7144.535605185646, 7396.519226250277, 7569.886451703119, 12760.553380631633, 13032.804507932198, 13176.80234796498, 13200.359928014244, 13264.204147751167, 13443.0, 13482.19106853592, 13572.93213533926, 13636.93181534086, 13682.0, 8645.52449615252, 9535.618575256913, 9675.758106047406, 9813.165880900204, 9828.11546960777, 9124.406913550598, 10319.0, 10784.676459706221, 10795.784084318113, 10921.0, 9212.0, 9225.630974760934, 9227.123423274777, 9308.394954327947, 9323.0, 8950.418222815497, 9011.684591039282, 9025.0, 9036.141566551167, 9082.909170908471, 6832.795016149524, 7225.5664660120565, 7461.141968673672, 7571.848563028819, 7636.961815190888, 6135.509159267159, 6221.782237621843, 6446.065320528591, 6511.120998665119, 6617.205935287796, 6277.497800176066, 6369.0, 6653.866922661617, 6681.365270299509, 6698.3597580706155], "value": 5441.001348702309, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [334.3463528801193, 385.8013123241531, 388.64439038279977, 396.75599506303615, 412.6410023279747, 4746.477887432384, 6065.938460769365, 6151.569390142692, 6226.283977342607, 6405.167328247321, 4471.038726673764, 5335.466453354663, 6065.968785306497, 6196.442320191193, 6259.655718935457, 4025.75845449274, 4471.396361491205, 4574.817007319703, 4583.610393116581, 4592.793324300398, 3971.7418367806144, 4582.793774280149, 4596.402467679214, 4604.585587297142, 4630.143423466669, 3947.723659343847, 4529.071540334224, 4532.818687252506, 4552.795124219418, 4553.704009239405, 4344.847930322439, 4544.500104988449, 4572.039871626962, 4585.656075794295, 4605.0, 3959.5644479107277, 3961.3067713150085, 3961.504811898513, 3964.405339199114, 3974.423708562242, 6134.263888333428, 6150.7390984848, 6167.660778657172, 6193.504519638418, 6247.656378899159, 5456.83629491112, 5471.37079235889, 5489.917651235235, 5497.175423686439, 5596.3284405871145, 6299.307076221612, 6708.161479815023, 6713.865722685519, 6721.295093434615, 6726.899096513556, 4450.332450132506, 4466.7989940452435, 4475.239209334421, 4479.700886742873, 4503.392042074343, 5980.372060933626, 6009.248843894513, 6872.0, 6935.445164386837, 7165.89251161233, 3972.324704800191, 4366.585174408426, 4391.67062470316, 4538.52345503724, 4567.794449249792, 4072.0477653701423, 4616.76916154191, 4621.329907163443, 4626.514216007338, 4628.39830821991, 5341.412444631087, 5399.946000539944, 5420.647657902224, 5442.210879422461, 5454.290942177491, 5685.374608793029, 6588.967055164693, 6674.966625166843, 6696.263411024784, 6708.161479815023, 1257.9936051159036, 1400.0129908414544, 1579.518246934696, 1584.8890577659683, 1586.9841301586837, 1017.9694609161737, 1051.4952822645173, 1051.500537244818, 1053.8155822730992, 1059.5708737961138, 4931.753412329369, 5767.741226207179, 6327.588706734047, 6336.841578960563, 6347.39699728525, 6870.45036397097, 6983.685734141975, 6991.510594258353, 7004.754833580801, 7008.773464643667, 6034.758609655564, 6226.1905952226825, 6307.463865571386, 6323.9051414228825, 6335.683215839282, 3016.5173572228778, 3618.1316484044164, 3639.47227651989, 3654.926901461956, 3657.847777949937, 6459.709313080923, 6854.794356169323, 7156.817170762967, 7159.70889315414, 7186.964065179641, 5823.505002074787, 6552.901706474407, 7103.431725461951, 7184.712611495586, 7204.3155900189395, 7051.083359163276, 7114.181869085018, 7135.004397537396, 7135.500514963903, 7168.820779480555, 6580.341965803573, 6972.965135174291, 6980.162380514371, 6991.265917078634, 7019.438444924496, 6227.941249987422, 6348.746050157942, 6837.897431538431, 6855.4172895304455, 6884.690188941409, 6483.481321494363, 6749.291324410866, 7043.647817608997, 7117.6085315308155, 7135.678894449866, 4228.513720922133, 4573.307876085736, 4583.816647334203, 4605.493395726535, 4629.1436084324505, 6481.5786973848435, 6940.479464040318, 7029.683664235019, 7065.82335441618, 7129.180144283267, 6630.801075967729, 6664.600123992576, 6750.9873518972545, 6789.642071585604, 6822.931770682229, 6741.797746067626, 6775.120573756232, 6789.966050169915, 6796.830079248059, 6806.876865317167, 5537.612367134262, 6454.709538070892, 6568.441682456853, 6572.277049524644, 6638.402543770891, 5578.748956296895, 5640.7590330127305, 6357.713902874474, 6730.730770769114, 6742.25835158142, 6490.48076153916, 6527.934720652733, 6536.934630653633, 6545.541812073109, 6587.341265873374], "value": 4858.952569029444, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [810.9189081091889, 813.585071613477, 824.212876702749, 850.1880703927748, 851.0935853316217, 13490.59528214154, 17297.221625026872, 17328.13359332033, 17342.653146937053, 17377.30490780367, 15356.308966096525, 15735.134567598778, 15753.527394178165, 16249.187540622952, 16536.83463165368, 980.4117529482324, 1004.5579944824291, 1009.1775203209397, 1010.0808264479325, 1010.206987514801, 980.1032055669054, 980.1962390839515, 980.289290264556, 983.306768728045, 986.842105263158, 978.3445091788519, 1030.5208078243616, 1038.48075962019, 1039.3971496531992, 1041.697907606797, 1020.3010937507794, 1026.538057873956, 1027.3887037212846, 1027.866377370937, 1031.0823367203195, 737.8524295140993, 742.0501757750035, 758.4008633179782, 759.4721668440436, 762.8092976755811, 17344.739828902577, 17410.1294935254, 17452.650946981117, 17570.38503652366, 17633.823661763345, 17436.651266974717, 17521.912390437967, 17702.203400846993, 17805.19876605543, 17876.642467150585, 14886.851131488764, 14919.552413427615, 14939.850601493954, 15140.318685659062, 15192.696146077127, 3011.2471882029495, 3296.3901678189613, 3558.2705545363397, 3627.7823330599986, 3654.6162652921325, 117211.0, 140153.0, 140351.0, 142382.0, 143176.0, 13318.0, 13565.711257430528, 13918.512852050331, 14012.0, 14090.22503762303, 26248.0, 26930.730692693214, 27160.728392716213, 27172.320691982462, 27187.72812271852, 7821.882671759929, 7962.920370796333, 7964.323032542299, 8089.110197878229, 8114.675412983414, 3938.0942383252013, 3971.8411263549133, 3997.7801220932547, 4007.358822588372, 4014.8394064237104, 4410.117976404732, 4481.282994720829, 4491.842785502492, 4496.955030449653, 4499.617532509707, 893.8346405914926, 894.8255090257468, 910.1762904571374, 911.4349103555838, 913.1325241021057, 540.8837100023435, 674.1943377663645, 675.9695813688396, 676.8274090106992, 685.8868286732733, 10532.578696852193, 11011.449427528752, 11168.274062186094, 11271.985521303122, 11647.941760291144, 634.5780056262533, 649.1658219188321, 651.0787236060921, 670.4904272752646, 682.6757290287173, 5247.580193584504, 5363.651362661414, 5550.139728342114, 5591.860203494945, 5888.116782482662, 130951.0, 131154.34422827823, 134022.0, 134377.32811336077, 135202.0, 74811.0, 115571.0, 115722.0, 115988.0, 117691.0, 97495.1753377271, 101885.49057254667, 102236.48881755544, 105427.40298567152, 107352.63236838127, 10174.0, 11807.527698891949, 11983.400829958293, 12285.69285767863, 12853.807192892302, 46075.539244608466, 46312.07375852397, 46442.07115857732, 47062.35281765259, 47071.823204420165, 53103.20345194748, 56176.0, 61872.0, 62136.378636213056, 63321.36678633155, 14220.715585688435, 16272.267747951608, 16630.3347866084, 17549.385771498448, 17728.55678608045, 18263.634727305645, 18847.528811779815, 19128.426147215607, 19279.710804338225, 19392.836429814255, 17194.968301901925, 17880.28478860831, 18122.45632631023, 18340.816591834446, 18340.991245481095, 17590.648187036444, 17929.37247196394, 18292.81707182911, 18322.908385457988, 18347.52308438397, 17069.48791536256, 17287.827121729126, 17809.020503872413, 17919.910400448436, 18160.81954672942, 11625.76748465009, 11789.587364442203, 11896.940515297369, 12329.260244385365, 12389.690257743268, 12939.676508087374, 13127.146735462164, 13532.458701651824, 13651.0, 13863.168209907439], "value": 8698.350462014834, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1489.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 118.0, 114.0, 114.0, 114.0, 115.0, 114.0, 113.0, 115.0, 113.0], "value": 113.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 113.0, 139.0, 191.0, 117.0, 111.0, 110.0, 117.0, 143.0, 113.0], "value": 129.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 202.0, 198.0, 211.0, 215.0, 212.0, 225.0, 196.0, 201.0, 199.0], "value": 205.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 61.0, 89.0, 74.0, 50.0, 68.0, 83.0, 93.0, 85.0], "value": 74.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 69.0, 82.0, 72.0, 85.0, 100.0, 97.0, 70.0, 68.0, 90.0], "value": 81.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 91.0, 89.0, 91.0, 91.0, 89.0, 91.0, 89.0, 91.0, 89.0], "value": 90.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 98.0, 96.0, 96.0, 97.0, 97.0, 96.0, 96.0, 97.0, 97.0], "value": 97.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 92.0, 98.0, 92.0, 89.0, 91.0, 91.0, 90.0, 89.0, 90.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 47.0, 50.0, 52.0, 52.0, 51.0, 51.0, 50.0, 51.0, 49.0], "value": 50.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 60.0, 63.0, 63.0, 65.0, 60.0, 57.0, 56.0, 59.0, 59.0], "value": 59.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 84.0, 86.0, 90.0, 98.0, 109.0, 89.0, 122.0, 80.0], "value": 93.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 144.0, 130.0, 132.0, 136.0, 139.0, 153.0, 151.0, 141.0, 138.0], "value": 139.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [230.0, 222.0, 186.0, 186.0, 187.0, 182.0, 182.0, 199.0, 187.0, 186.0], "value": 194.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 70.0, 67.0, 67.0, 67.0, 68.0, 60.0, 64.0, 61.0, 69.0], "value": 66.0, "unit": "score"}]}]} 12:54:44 INFO - Return code: 0 12:54:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:54:44 INFO - # TBPL SUCCESS # 12:54:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:54:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:54:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:54:44 INFO - 2017-06-03 12:54:44.880 screenresolution[1669:12365] starting screenresolution argv=screenresolution get 12:54:44 INFO - 2017-06-03 12:54:44.883 screenresolution[1669:12365] Display 0: 1600x1200x32@60 12:54:44 INFO - 2017-06-03 12:54:44.889 screenresolution[1670:12368] starting screenresolution argv=screenresolution list 12:54:44 INFO - Available Modes on Display 0 12:54:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:54:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:54:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:54:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:54:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:54:45 INFO - Intel Iris: 12:54:45 INFO - Chipset Model: Intel Iris 12:54:45 INFO - Type: GPU 12:54:45 INFO - Bus: Built-In 12:54:45 INFO - VRAM (Dynamic, Max): 1536 MB 12:54:45 INFO - Vendor: Intel (0x8086) 12:54:45 INFO - Device ID: 0x0a2e 12:54:45 INFO - Revision ID: 0x0009 12:54:45 INFO - Displays: 12:54:45 INFO - Display: 12:54:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:54:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:54:45 INFO - Display Serial Number: 1600x1200 60 12:54:45 INFO - Main Display: Yes 12:54:45 INFO - Mirror: Off 12:54:45 INFO - Online: Yes 12:54:45 INFO - Rotation: Supported 12:54:45 INFO - Return code: 0 12:54:45 INFO - Running post-action listener: _resource_record_post_action 12:54:45 INFO - [mozharness: 2017-06-03 19:54:45.125750Z] Finished run-tests step (success) 12:54:45 INFO - Running post-run listener: _resource_record_post_run 12:54:45 INFO - Total resource usage - Wall time: 1255s; CPU: 53.0%; Read bytes: 36433408; Write bytes: 572612096; Read time: 353; Write time: 1876 12:54:45 INFO - TinderboxPrint: CPU usage
53.1% 12:54:45 INFO - TinderboxPrint: I/O read bytes / time
36,433,408 / 353 12:54:45 INFO - TinderboxPrint: I/O write bytes / time
572,612,096 / 1,876 12:54:45 INFO - TinderboxPrint: CPU idle
2,353.9 (46.9%) 12:54:45 INFO - TinderboxPrint: CPU system
1,011.1 (20.1%) 12:54:45 INFO - TinderboxPrint: CPU user
1,656.7 (33.0%) 12:54:45 INFO - TinderboxPrint: Swap in / out
279,040,000 / 0 12:54:45 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 231035392; Write bytes: 230963200; Read time: 16915; Write time: 507 12:54:45 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 12:54:45 INFO - run-tests - Wall time: 1235s; CPU: 53.0%; Read bytes: 35442176; Write bytes: 323188224; Read time: 336; Write time: 1324 12:54:45 INFO - Running post-run listener: _upload_blobber_files 12:54:45 INFO - Blob upload gear active. 12:54:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:54:45 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 12:54:45 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'] 12:54:45 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 12:54:46 INFO - (blobuploader) - INFO - Open directory for files ... 12:54:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:54:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:54:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:54:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:54:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:54:47 INFO - (blobuploader) - INFO - Done attempting. 12:54:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:54:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:54:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:54:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:54:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:54:48 INFO - (blobuploader) - INFO - Done attempting. 12:54:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:54:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:54:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:54:49 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:54:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:54:49 INFO - (blobuploader) - INFO - Done attempting. 12:54:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:54:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:54:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:54:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:54:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:54:51 INFO - (blobuploader) - INFO - Done attempting. 12:54:51 INFO - (blobuploader) - INFO - Iteration through files over. 12:54:51 INFO - Return code: 0 12:54:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:54:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:54:51 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/55063e123e26c3d98ef7429f42749816130e3d926177c3a7a5fe6cbd34860caa8dd37dcc378f7c40fd57d524da00c8c42329db7715935c2ec1f35f905f0061bc", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d27b10bdefeb651c900962327195ba9c25bf15eabcaa7ef4b077ac8c488a63a2c5faece830a7c4c862055fd3a6b062016b3dadc6c9999035e8d19804d480195", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/305a831a1813c183b0dd230d2efadcc8c9f8e0d668aa622a3070876e90d566faca8ba1e792262fdb2212292e12bc31c1fdc2feb6c39f64dc0ff8c1ba77224aab", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/688a8bbf449adc38c46c9c00852d41d825eb2533ef64dba786c6ad5073acd04b4b1285c764d3dfe0427168049276be5d99222bf8069f427031f4fefff7c816d9"} 12:54:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:54:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:54:51 INFO - Contents: 12:54:51 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/55063e123e26c3d98ef7429f42749816130e3d926177c3a7a5fe6cbd34860caa8dd37dcc378f7c40fd57d524da00c8c42329db7715935c2ec1f35f905f0061bc", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d27b10bdefeb651c900962327195ba9c25bf15eabcaa7ef4b077ac8c488a63a2c5faece830a7c4c862055fd3a6b062016b3dadc6c9999035e8d19804d480195", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/305a831a1813c183b0dd230d2efadcc8c9f8e0d668aa622a3070876e90d566faca8ba1e792262fdb2212292e12bc31c1fdc2feb6c39f64dc0ff8c1ba77224aab", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/688a8bbf449adc38c46c9c00852d41d825eb2533ef64dba786c6ad5073acd04b4b1285c764d3dfe0427168049276be5d99222bf8069f427031f4fefff7c816d9"} 12:54:51 INFO - Running post-run listener: copy_logs_to_upload_dir 12:54:51 INFO - Copying logs to upload dir... 12:54:51 INFO - mkdir: /builds/slave/test/build/upload/logs 12:54:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1338.696205 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 18 secs) (at 2017-06-03 12:54:51.434480) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-03 12:54:51.438502) ========= 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.2BkjnOQxUa/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.Z72oo4mg6s/Listeners TMPDIR=/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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/55063e123e26c3d98ef7429f42749816130e3d926177c3a7a5fe6cbd34860caa8dd37dcc378f7c40fd57d524da00c8c42329db7715935c2ec1f35f905f0061bc", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d27b10bdefeb651c900962327195ba9c25bf15eabcaa7ef4b077ac8c488a63a2c5faece830a7c4c862055fd3a6b062016b3dadc6c9999035e8d19804d480195", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/305a831a1813c183b0dd230d2efadcc8c9f8e0d668aa622a3070876e90d566faca8ba1e792262fdb2212292e12bc31c1fdc2feb6c39f64dc0ff8c1ba77224aab", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/688a8bbf449adc38c46c9c00852d41d825eb2533ef64dba786c6ad5073acd04b4b1285c764d3dfe0427168049276be5d99222bf8069f427031f4fefff7c816d9"} build_url:https://queue.taskcluster.net/v1/task/DDkleuz5Qv6WHINlY1eFxQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011096 build_url: 'https://queue.taskcluster.net/v1/task/DDkleuz5Qv6WHINlY1eFxQ/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/55063e123e26c3d98ef7429f42749816130e3d926177c3a7a5fe6cbd34860caa8dd37dcc378f7c40fd57d524da00c8c42329db7715935c2ec1f35f905f0061bc", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d27b10bdefeb651c900962327195ba9c25bf15eabcaa7ef4b077ac8c488a63a2c5faece830a7c4c862055fd3a6b062016b3dadc6c9999035e8d19804d480195", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/305a831a1813c183b0dd230d2efadcc8c9f8e0d668aa622a3070876e90d566faca8ba1e792262fdb2212292e12bc31c1fdc2feb6c39f64dc0ff8c1ba77224aab", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/688a8bbf449adc38c46c9c00852d41d825eb2533ef64dba786c6ad5073acd04b4b1285c764d3dfe0427168049276be5d99222bf8069f427031f4fefff7c816d9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-03 12:54:51.473063) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-03 12:54:51.473389) ========= 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.2BkjnOQxUa/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.Z72oo4mg6s/Listeners TMPDIR=/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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.005186 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-03 12:54:51.522009) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-03 12:54:51.522349) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-03 12:54:51.522699) ========= ========= Total master_lag: 0.23 =========