builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0104 starttime: 1496215501.41 results: success (0) revision: 925230851743b9a969a3142f00aea5014a33cb02 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-31 00:25:01.411446) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-31 00:25:01.411993) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-31 00:25:01.415374) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-31 00:25:01.443885) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 00:25:01.444282) ========= 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.pJ9hemjSLp/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.HFvv0RidAn/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-31 00:25:01-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 164M=0s 2017-05-31 00:25:01 (164 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.177120 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 00:25:01.647824) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 00:25:01.648342) ========= 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.pJ9hemjSLp/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.HFvv0RidAn/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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.036399 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 00:25:01.717718) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-05-31 00:25:01.718109) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 925230851743b9a969a3142f00aea5014a33cb02 --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 925230851743b9a969a3142f00aea5014a33cb02 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.pJ9hemjSLp/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.HFvv0RidAn/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-31 00:25:01,811 truncating revision to first 12 chars 2017-05-31 00:25:01,812 Setting DEBUG logging. 2017-05-31 00:25:01,812 attempt 1/10 2017-05-31 00:25:01,812 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/925230851743?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-31 00:25:02,502 unpacking tar archive at: mozilla-central-925230851743/testing/mozharness/ program finished with exit code 0 elapsedTime=0.976892 ========= master_lag: 2.47 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-05-31 00:25:05.165874) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-31 00:25:05.166323) ========= script_repo_revision: 925230851743b9a969a3142f00aea5014a33cb02 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-31 00:25:05.166751) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-31 00:25:05.171110) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-31 00:25:06.089569) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 21 secs) (at 2017-05-31 00:25:06.089888) ========= /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.pJ9hemjSLp/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.HFvv0RidAn/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 00:25:06 INFO - MultiFileLogger online at 20170531 00:25:06 in /builds/slave/test 00:25:06 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 00:25:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 00:25:06 INFO - {'append_to_log': False, 00:25:06 INFO - 'base_work_dir': '/builds/slave/test', 00:25:06 INFO - 'blob_upload_branch': 'Firefox', 00:25:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 00:25:06 INFO - 'branch': 'Firefox', 00:25:06 INFO - 'buildbot_json_path': 'buildprops.json', 00:25:06 INFO - 'config_files': ('talos/mac_config.py',), 00:25:06 INFO - 'default_actions': ('clobber', 00:25:06 INFO - 'read-buildbot-config', 00:25:06 INFO - 'download-and-extract', 00:25:06 INFO - 'populate-webroot', 00:25:06 INFO - 'create-virtualenv', 00:25:06 INFO - 'install', 00:25:06 INFO - 'setup-mitmproxy', 00:25:06 INFO - 'run-tests'), 00:25:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 00:25:06 INFO - 'download_minidump_stackwalk': True, 00:25:06 INFO - 'download_symbols': 'ondemand', 00:25:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 00:25:06 INFO - 'tooltool.py': '/tools/tooltool.py', 00:25:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 00:25:06 INFO - '/tools/misc-python/virtualenv.py')}, 00:25:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 00:25:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 00:25:06 INFO - 'gecko_profile': False, 00:25:06 INFO - 'gecko_profile_interval': 0, 00:25:06 INFO - 'installer_path': 'installer.exe', 00:25:06 INFO - 'log_level': 'info', 00:25:06 INFO - 'log_name': 'talos', 00:25:06 INFO - 'log_to_console': True, 00:25:06 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 00:25:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 00:25:06 INFO - 'opt_config_files': (), 00:25:06 INFO - 'pip_index': False, 00:25:06 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 00:25:06 INFO - 'cmd': ('bash', 00:25:06 INFO - '-c', 00:25:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 00:25:06 INFO - 'enabled': True, 00:25:06 INFO - 'halt_on_failure': False, 00:25:06 INFO - 'name': 'check_screen_resolution'},), 00:25:06 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 00:25:06 INFO - 'cmd': ('bash', 00:25:06 INFO - '-c', 00:25:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 00:25:06 INFO - 'enabled': True, 00:25:06 INFO - 'halt_on_failure': False, 00:25:06 INFO - 'name': 'check_screen_resolution'},), 00:25:06 INFO - 'run_cmd_checks_enabled': True, 00:25:06 INFO - 'suite': 'dromaeojs-e10s', 00:25:06 INFO - 'system_bits': '32', 00:25:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 00:25:06 INFO - 'title': 't-yosemite-r7-0104', 00:25:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 00:25:06 INFO - 'use_talos_json': True, 00:25:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 00:25:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 00:25:06 INFO - 'work_dir': 'build'} 00:25:06 INFO - [mozharness: 2017-05-31 07:25:06.347602Z] Running clobber step. 00:25:06 INFO - Running pre-action listener: _resource_record_pre_action 00:25:06 INFO - Running main action method: clobber 00:25:06 INFO - rmtree: /builds/slave/test/build 00:25:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 00:25:08 INFO - Running post-action listener: _resource_record_post_action 00:25:08 INFO - [mozharness: 2017-05-31 07:25:08.974310Z] Finished clobber step (success) 00:25:08 INFO - [mozharness: 2017-05-31 07:25:08.974533Z] Running read-buildbot-config step. 00:25:08 INFO - Running pre-action listener: _resource_record_pre_action 00:25:08 INFO - Running main action method: read_buildbot_config 00:25:08 INFO - Using buildbot properties: 00:25:08 INFO - { 00:25:08 INFO - "project": "", 00:25:08 INFO - "product": "firefox", 00:25:08 INFO - "who": "philringnalda@gmail.com", 00:25:08 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 00:25:08 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 00:25:08 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 00:25:08 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 00:25:08 INFO - "stage_platform": "macosx64", 00:25:08 INFO - "basedir": "/builds/slave/test", 00:25:08 INFO - "buildnumber": 69, 00:25:08 INFO - "platform": "yosemite_r7", 00:25:08 INFO - "parent_task_id": "IkpI-_XtS1i8ZvYndlIGNw", 00:25:08 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 00:25:08 INFO - "slavebuilddir": "test", 00:25:08 INFO - "taskId": "YshwzRdJRYqq8Nj_6WE6uA", 00:25:08 INFO - "branch": "mozilla-central", 00:25:08 INFO - "script_repo_revision": "production", 00:25:08 INFO - "revision": "925230851743b9a969a3142f00aea5014a33cb02", 00:25:08 INFO - "slavename": "t-yosemite-r7-0104", 00:25:08 INFO - "repo_path": "mozilla-central" 00:25:08 INFO - } 00:25:08 INFO - Finding installer, test and symbols from parent task. 00:25:08 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YshwzRdJRYqq8Nj_6WE6uA'}, attempt #1 00:25:09 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YshwzRdJRYqq8Nj_6WE6uA'}, attempt #1 00:25:10 INFO - Task dependencies: RZE5K2ltQVa-0Fcae86Y6A IkpI-_XtS1i8ZvYndlIGNw 00:25:10 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IkpI-_XtS1i8ZvYndlIGNw'}, attempt #1 00:25:10 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/IkpI-_XtS1i8ZvYndlIGNw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 00:25:10 INFO - Set test_url: https://queue.taskcluster.net/v1/task/IkpI-_XtS1i8ZvYndlIGNw/artifacts/public/build/test_packages.json 00:25:10 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/IkpI-_XtS1i8ZvYndlIGNw/artifacts/public/build/target.crashreporter-symbols.zip 00:25:10 INFO - Running post-action listener: _resource_record_post_action 00:25:10 INFO - [mozharness: 2017-05-31 07:25:10.664606Z] Finished read-buildbot-config step (success) 00:25:10 INFO - [mozharness: 2017-05-31 07:25:10.664729Z] Running download-and-extract step. 00:25:10 INFO - Running pre-action listener: _resource_record_pre_action 00:25:10 INFO - Running main action method: download_and_extract 00:25:10 INFO - mkdir: /builds/slave/test/build/tests 00:25: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')]} 00:25:10 INFO - trying https://queue.taskcluster.net/v1/task/IkpI-_XtS1i8ZvYndlIGNw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 00:25:10 INFO - Downloading https://queue.taskcluster.net/v1/task/IkpI-_XtS1i8ZvYndlIGNw/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 00:25:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IkpI-_XtS1i8ZvYndlIGNw/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 00:25:11 INFO - Downloaded 1325 bytes. 00:25:11 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 00:25:11 INFO - Using the following test package requirements: 00:25:11 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 00:25:11 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 00:25:11 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 00:25:11 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 00:25:11 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 00:25:11 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 00:25:11 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 00:25:11 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 00:25:11 INFO - u'jsshell-mac.zip'], 00:25:11 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 00:25:11 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 00:25:11 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 00:25:11 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 00:25:11 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 00:25:11 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 00:25:11 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 00:25:11 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 00:25:11 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 00:25:11 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 00:25:11 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 00:25:11 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 00:25:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IkpI-_XtS1i8ZvYndlIGNw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 00:25:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IkpI-_XtS1i8ZvYndlIGNw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 00:25:11 INFO - Fetch https://queue.taskcluster.net/v1/task/IkpI-_XtS1i8ZvYndlIGNw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 00:25:13 INFO - Content-Length response header: 57630940 00:25:13 INFO - Bytes received: 57630940 00:25:31 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 00:25:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IkpI-_XtS1i8ZvYndlIGNw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 00:25:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IkpI-_XtS1i8ZvYndlIGNw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 00:25:31 INFO - Fetch https://queue.taskcluster.net/v1/task/IkpI-_XtS1i8ZvYndlIGNw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 00:25:33 INFO - Content-Length response header: 57630940 00:25:33 INFO - Bytes received: 57630940 00:25:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IkpI-_XtS1i8ZvYndlIGNw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 00:25:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IkpI-_XtS1i8ZvYndlIGNw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 00:25:51 INFO - Fetch https://queue.taskcluster.net/v1/task/IkpI-_XtS1i8ZvYndlIGNw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 00:25:52 INFO - Content-Length response header: 14447425 00:25:52 INFO - Bytes received: 14447425 00:25:53 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')]} 00:25:53 INFO - trying https://queue.taskcluster.net/v1/task/IkpI-_XtS1i8ZvYndlIGNw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 00:25:53 INFO - Downloading https://queue.taskcluster.net/v1/task/IkpI-_XtS1i8ZvYndlIGNw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 00:25:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IkpI-_XtS1i8ZvYndlIGNw/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 00:25:55 INFO - Downloaded 67882368 bytes. 00:25:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IkpI-_XtS1i8ZvYndlIGNw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 00:25:55 INFO - mkdir: /builds/slave/test/properties 00:25:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 00:25:55 INFO - Writing to file /builds/slave/test/properties/build_url 00:25:55 INFO - Contents: 00:25:55 INFO - build_url:https://queue.taskcluster.net/v1/task/IkpI-_XtS1i8ZvYndlIGNw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 00:25:55 INFO - Running post-action listener: _resource_record_post_action 00:25:55 INFO - Running post-action listener: set_extra_try_arguments 00:25:55 INFO - [mozharness: 2017-05-31 07:25:55.739211Z] Finished download-and-extract step (success) 00:25:55 INFO - [mozharness: 2017-05-31 07:25:55.739389Z] Running populate-webroot step. 00:25:55 INFO - Running pre-action listener: _resource_record_pre_action 00:25:55 INFO - Running main action method: populate_webroot 00:25:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 00:25:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 00:25:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 00:25:55 INFO - '--tppagecycles', 00:25:55 INFO - '7'], 00:25:55 INFO - 'tests': ['tsvgm']}}, 00:25:55 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 00:25:55 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 00:25:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 00:25:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 00:25:55 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 00:25:55 INFO - 'talos_options': ['--disable-e10s'], 00:25:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 00:25:55 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 00:25:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 00:25:55 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 00:25:55 INFO - 'talos_options': ['--disable-e10s'], 00:25:55 INFO - 'tests': ['damp', 'tps']}, 00:25:55 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 00:25:55 INFO - 'tests': ['damp', 'tps']}, 00:25:55 INFO - 'g3': {'talos_options': ['--disable-e10s'], 00:25:55 INFO - 'tests': ['dromaeo_dom']}, 00:25:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 00:25:55 INFO - 'g4': {'talos_options': ['--disable-e10s'], 00:25:55 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 00:25:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 00:25:55 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 00:25:55 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 00:25:55 INFO - 'other': {'talos_options': ['--disable-e10s'], 00:25:55 INFO - 'tests': ['a11yr', 00:25:55 INFO - 'ts_paint', 00:25:55 INFO - 'tpaint', 00:25:55 INFO - 'sessionrestore', 00:25:55 INFO - 'sessionrestore_no_auto_restore', 00:25:55 INFO - 'tabpaint']}, 00:25:55 INFO - 'other-e10s': {'tests': ['a11yr', 00:25:55 INFO - 'ts_paint', 00:25:55 INFO - 'tpaint', 00:25:55 INFO - 'sessionrestore', 00:25:55 INFO - 'sessionrestore_no_auto_restore', 00:25:55 INFO - 'tabpaint']}, 00:25:55 INFO - 'other-e10s_l64': {'tests': ['a11yr', 00:25:55 INFO - 'ts_paint', 00:25:55 INFO - 'tpaint', 00:25:55 INFO - 'sessionrestore', 00:25:55 INFO - 'sessionrestore_no_auto_restore', 00:25:55 INFO - 'tabpaint']}, 00:25:55 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 00:25:55 INFO - 'ts_paint', 00:25:55 INFO - 'tpaint', 00:25:55 INFO - 'sessionrestore', 00:25:55 INFO - 'sessionrestore_no_auto_restore', 00:25:55 INFO - 'tabpaint']}, 00:25:55 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 00:25:55 INFO - 'tests': ['a11yr', 00:25:55 INFO - 'ts_paint', 00:25:55 INFO - 'tpaint', 00:25:55 INFO - 'sessionrestore', 00:25:55 INFO - 'sessionrestore_no_auto_restore', 00:25:55 INFO - 'tabpaint']}, 00:25:55 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 00:25:55 INFO - 'tests': ['a11yr', 00:25:55 INFO - 'ts_paint', 00:25:55 INFO - 'tpaint', 00:25:55 INFO - 'sessionrestore', 00:25:55 INFO - 'sessionrestore_no_auto_restore', 00:25:55 INFO - 'tabpaint']}, 00:25:55 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 00:25:55 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 00:25:55 INFO - 'bloom_basic_ref']}, 00:25:55 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 00:25:55 INFO - 'talos_options': ['--mitmproxy', 00:25:55 INFO - 'mitmproxy-recording-1.mp', 00:25:55 INFO - '--firstNonBlankPaint'], 00:25:55 INFO - 'tests': ['Quantum_1']}, 00:25:55 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 00:25:55 INFO - 'tests': ['tsvgx', 00:25:55 INFO - 'tsvgr_opacity', 00:25:55 INFO - 'tart', 00:25:55 INFO - 'tscrollx', 00:25:55 INFO - 'cart', 00:25:55 INFO - 'tsvg_static']}, 00:25:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 00:25:55 INFO - 'tsvgr_opacity', 00:25:55 INFO - 'tart', 00:25:55 INFO - 'tscrollx', 00:25:55 INFO - 'cart', 00:25:55 INFO - 'tsvg_static']}, 00:25:55 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 00:25:55 INFO - 'talos_options': ['--disable-e10s'], 00:25:55 INFO - 'tests': ['tp5o']}, 00:25:55 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 00:25:55 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 00:25:55 INFO - 'talos_options': ['--disable-e10s', 00:25:55 INFO - '--xperf_path', 00:25:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 00:25:55 INFO - 'tests': ['tp5n']}, 00:25:55 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 00:25:55 INFO - 'talos_options': ['--xperf_path', 00:25:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 00:25:55 INFO - 'tests': ['tp5n']}}, 00:25:55 INFO - 'talos.zip': {'path': '', 00:25:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 00:25:55 INFO - Running post-action listener: _resource_record_post_action 00:25:55 INFO - [mozharness: 2017-05-31 07:25:55.761056Z] Finished populate-webroot step (success) 00:25:55 INFO - [mozharness: 2017-05-31 07:25:55.761171Z] Running create-virtualenv step. 00:25:55 INFO - Running pre-action listener: _resource_record_pre_action 00:25:55 INFO - Running main action method: create_virtualenv 00:25:55 INFO - Creating virtualenv /builds/slave/test/build/venv 00:25:55 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 00:25:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 00:25:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 00:25:55 INFO - Using real prefix '/tools/python27' 00:25:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 00:25:56 INFO - Installing distribute.............................................................................................................................................................................................done. 00:25:59 INFO - Installing pip.................done. 00:25:59 INFO - Return code: 0 00:25:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 00:25:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 00:25:59 INFO - Reading from file tmpfile_stdout 00:25:59 INFO - Output received: 00:25:59 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) 00:25:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 00:25:59 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')]} 00:25:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:25:59 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')]} 00:25:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:25:59 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 0x10a328c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a32c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7394c1b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2b1c60>, '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 0x10a046cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a06fbe8>, '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.pJ9hemjSLp/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.HFvv0RidAn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 00:25:59 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 00:25:59 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 00:25:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pJ9hemjSLp/Render', 00:25:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:25:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:25:59 INFO - 'HOME': '/Users/cltbld', 00:25:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:25:59 INFO - 'LOGNAME': 'cltbld', 00:25:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:25:59 INFO - 'MOZ_NO_REMOTE': '1', 00:25:59 INFO - 'NO_EM_RESTART': '1', 00:25:59 INFO - 'PAGER': '/bin/cat', 00:25:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:25:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:25:59 INFO - 'PWD': '/builds/slave/test', 00:25:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:25:59 INFO - 'SHELL': '/bin/bash', 00:25:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HFvv0RidAn/Listeners', 00:25:59 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 00:25:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:25:59 INFO - 'USER': 'cltbld', 00:25:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:25:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:25:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:25:59 INFO - 'XPC_FLAGS': '0x0', 00:25:59 INFO - 'XPC_SERVICE_NAME': '0', 00:25:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:26:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:26:00 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 00:26:00 INFO - Cleaning up... 00:26:00 INFO - Return code: 0 00:26:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 00:26:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:26:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:26:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:26:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:26:00 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 0x10a328c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a32c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7394c1b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2b1c60>, '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 0x10a046cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a06fbe8>, '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.pJ9hemjSLp/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.HFvv0RidAn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 00:26:00 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 00:26:00 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 00:26:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pJ9hemjSLp/Render', 00:26:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:26:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:26:00 INFO - 'HOME': '/Users/cltbld', 00:26:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:26:00 INFO - 'LOGNAME': 'cltbld', 00:26:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:26:00 INFO - 'MOZ_NO_REMOTE': '1', 00:26:00 INFO - 'NO_EM_RESTART': '1', 00:26:00 INFO - 'PAGER': '/bin/cat', 00:26:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:26:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:26:00 INFO - 'PWD': '/builds/slave/test', 00:26:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:26:00 INFO - 'SHELL': '/bin/bash', 00:26:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HFvv0RidAn/Listeners', 00:26:00 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 00:26:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:26:00 INFO - 'USER': 'cltbld', 00:26:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:26:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:26:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:26:00 INFO - 'XPC_FLAGS': '0x0', 00:26:00 INFO - 'XPC_SERVICE_NAME': '0', 00:26:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:26:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:26:00 INFO - Downloading/unpacking psutil>=3.1.1 00:26:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:26:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:26:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:26:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:26:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 00:26:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 00:26:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 00:26:02 INFO - Installing collected packages: psutil 00:26:02 INFO - Running setup.py install for psutil 00:26:02 INFO - building 'psutil._psutil_osx' extension 00:26:02 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 00:26:03 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 00:26:03 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 00:26:03 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 00:26:03 INFO - building 'psutil._psutil_posix' extension 00:26:03 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 00:26:03 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 00:26:03 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 00:26:03 INFO - ^ 00:26:03 INFO - 1 warning generated. 00:26:03 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 00:26:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 00:26:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 00:26:03 INFO - Successfully installed psutil 00:26:03 INFO - Cleaning up... 00:26:03 INFO - Return code: 0 00:26:03 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 00:26:03 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')]} 00:26:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:26:03 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')]} 00:26:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:26:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a328c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a32c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7394c1b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2b1c60>, '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 0x10a046cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a06fbe8>, '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.pJ9hemjSLp/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.HFvv0RidAn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 00:26:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 00:26:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 00:26:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pJ9hemjSLp/Render', 00:26:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:26:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:26:03 INFO - 'HOME': '/Users/cltbld', 00:26:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:26:03 INFO - 'LOGNAME': 'cltbld', 00:26:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:26:03 INFO - 'MOZ_NO_REMOTE': '1', 00:26:03 INFO - 'NO_EM_RESTART': '1', 00:26:03 INFO - 'PAGER': '/bin/cat', 00:26:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:26:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:26:03 INFO - 'PWD': '/builds/slave/test', 00:26:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:26:03 INFO - 'SHELL': '/bin/bash', 00:26:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HFvv0RidAn/Listeners', 00:26:03 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 00:26:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:26:03 INFO - 'USER': 'cltbld', 00:26:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:26:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:26:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:26:03 INFO - 'XPC_FLAGS': '0x0', 00:26:03 INFO - 'XPC_SERVICE_NAME': '0', 00:26:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:26:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:26:04 INFO - Downloading/unpacking mozsystemmonitor==0.3 00:26:04 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 00:26:04 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 00:26:04 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 00:26:04 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 00:26:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 00:26:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 00:26:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 00:26:05 INFO - Installing collected packages: mozsystemmonitor 00:26:05 INFO - Running setup.py install for mozsystemmonitor 00:26:05 INFO - Successfully installed mozsystemmonitor 00:26:05 INFO - Cleaning up... 00:26:05 INFO - Return code: 0 00:26:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 00:26:05 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')]} 00:26:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:26:05 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')]} 00:26:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:26:05 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 0x10a328c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a32c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7394c1b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2b1c60>, '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 0x10a046cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a06fbe8>, '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.pJ9hemjSLp/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.HFvv0RidAn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 00:26:05 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 00:26:05 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 00:26:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pJ9hemjSLp/Render', 00:26:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:26:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:26:05 INFO - 'HOME': '/Users/cltbld', 00:26:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:26:05 INFO - 'LOGNAME': 'cltbld', 00:26:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:26:05 INFO - 'MOZ_NO_REMOTE': '1', 00:26:05 INFO - 'NO_EM_RESTART': '1', 00:26:05 INFO - 'PAGER': '/bin/cat', 00:26:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:26:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:26:05 INFO - 'PWD': '/builds/slave/test', 00:26:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:26:05 INFO - 'SHELL': '/bin/bash', 00:26:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HFvv0RidAn/Listeners', 00:26:05 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 00:26:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:26:05 INFO - 'USER': 'cltbld', 00:26:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:26:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:26:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:26:05 INFO - 'XPC_FLAGS': '0x0', 00:26:05 INFO - 'XPC_SERVICE_NAME': '0', 00:26:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:26:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:26:06 INFO - Downloading/unpacking jsonschema==2.5.1 00:26:06 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 00:26:06 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 00:26:06 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 00:26:06 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 00:26:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 00:26:07 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 00:26:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:26:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:26:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:26:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:26:07 INFO - Downloading functools32-3.2.3-2.tar.gz 00:26:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 00:26:07 INFO - warning: no files found matching '*.txt' 00:26:07 INFO - no previously-included directories found matching 'build' 00:26:07 INFO - no previously-included directories found matching 'dist' 00:26:07 INFO - no previously-included directories found matching '.git*' 00:26:07 INFO - Installing collected packages: jsonschema, functools32 00:26:07 INFO - Running setup.py install for functools32 00:26:08 INFO - warning: no files found matching '*.txt' 00:26:08 INFO - no previously-included directories found matching 'build' 00:26:08 INFO - no previously-included directories found matching 'dist' 00:26:08 INFO - no previously-included directories found matching '.git*' 00:26:08 INFO - Successfully installed jsonschema functools32 00:26:08 INFO - Cleaning up... 00:26:08 INFO - Return code: 0 00:26:08 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 00:26:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:26:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:26:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:26:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:26:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a328c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a32c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7394c1b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2b1c60>, '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 0x10a046cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a06fbe8>, '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.pJ9hemjSLp/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.HFvv0RidAn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 00:26:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 00:26:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 00:26:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pJ9hemjSLp/Render', 00:26:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:26:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:26:08 INFO - 'HOME': '/Users/cltbld', 00:26:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:26:08 INFO - 'LOGNAME': 'cltbld', 00:26:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:26:08 INFO - 'MOZ_NO_REMOTE': '1', 00:26:08 INFO - 'NO_EM_RESTART': '1', 00:26:08 INFO - 'PAGER': '/bin/cat', 00:26:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:26:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:26:08 INFO - 'PWD': '/builds/slave/test', 00:26:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:26:08 INFO - 'SHELL': '/bin/bash', 00:26:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HFvv0RidAn/Listeners', 00:26:08 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 00:26:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:26:08 INFO - 'USER': 'cltbld', 00:26:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:26:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:26:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:26:08 INFO - 'XPC_FLAGS': '0x0', 00:26:08 INFO - 'XPC_SERVICE_NAME': '0', 00:26:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:26:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:26:08 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 00:26:08 INFO - Cleaning up... 00:26:08 INFO - Return code: 0 00:26:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 00:26:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:26:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:26:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:26:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:26:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a328c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a32c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7394c1b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2b1c60>, '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 0x10a046cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a06fbe8>, '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.pJ9hemjSLp/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.HFvv0RidAn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 00:26:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 00:26:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 00:26:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pJ9hemjSLp/Render', 00:26:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:26:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:26:08 INFO - 'HOME': '/Users/cltbld', 00:26:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:26:08 INFO - 'LOGNAME': 'cltbld', 00:26:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:26:08 INFO - 'MOZ_NO_REMOTE': '1', 00:26:08 INFO - 'NO_EM_RESTART': '1', 00:26:08 INFO - 'PAGER': '/bin/cat', 00:26:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:26:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:26:08 INFO - 'PWD': '/builds/slave/test', 00:26:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:26:08 INFO - 'SHELL': '/bin/bash', 00:26:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HFvv0RidAn/Listeners', 00:26:08 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 00:26:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:26:08 INFO - 'USER': 'cltbld', 00:26:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:26:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:26:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:26:08 INFO - 'XPC_FLAGS': '0x0', 00:26:08 INFO - 'XPC_SERVICE_NAME': '0', 00:26:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:26:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:26:08 INFO - Downloading/unpacking blobuploader==1.2.4 00:26:08 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 00:26:08 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 00:26:08 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 00:26:08 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 00:26:10 INFO - Downloading blobuploader-1.2.4.tar.gz 00:26:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 00:26:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 00:26:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:26:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:26:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:26:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:26:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 00:26:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 00:26:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:26:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:26:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:26:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:26:11 INFO - Downloading docopt-0.6.1.tar.gz 00:26:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 00:26:11 INFO - Installing collected packages: blobuploader, requests, docopt 00:26:11 INFO - Running setup.py install for blobuploader 00:26:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 00:26:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 00:26:11 INFO - Running setup.py install for requests 00:26:11 INFO - Running setup.py install for docopt 00:26:11 INFO - Successfully installed blobuploader requests docopt 00:26:11 INFO - Cleaning up... 00:26:11 INFO - Return code: 0 00:26:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:26:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:26:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:26:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:26:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:26:11 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 0x10a328c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a32c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7394c1b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2b1c60>, '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 0x10a046cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a06fbe8>, '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.pJ9hemjSLp/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.HFvv0RidAn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 00:26:11 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 00:26:11 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 00:26:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pJ9hemjSLp/Render', 00:26:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:26:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:26:11 INFO - 'HOME': '/Users/cltbld', 00:26:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:26:11 INFO - 'LOGNAME': 'cltbld', 00:26:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:26:11 INFO - 'MOZ_NO_REMOTE': '1', 00:26:11 INFO - 'NO_EM_RESTART': '1', 00:26:11 INFO - 'PAGER': '/bin/cat', 00:26:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:26:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:26:11 INFO - 'PWD': '/builds/slave/test', 00:26:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:26:11 INFO - 'SHELL': '/bin/bash', 00:26:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HFvv0RidAn/Listeners', 00:26:11 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 00:26:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:26:11 INFO - 'USER': 'cltbld', 00:26:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:26:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:26:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:26:11 INFO - 'XPC_FLAGS': '0x0', 00:26:11 INFO - 'XPC_SERVICE_NAME': '0', 00:26:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:26:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:26:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 00:26:12 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-mkiiz5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 00:26:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 00:26:12 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-Tjvny0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 00:26:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 00:26:12 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-u5mVXA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 00:26:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 00:26:12 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-lkH9ZZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 00:26:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 00:26:12 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-OA9ji4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 00:26:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 00:26:12 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-7uRnuU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 00:26:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 00:26:12 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-W4h6gS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 00:26:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 00:26:12 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-z7osMY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 00:26:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 00:26:13 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-5_WCca-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 00:26:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 00:26:13 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-djhhHb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 00:26:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 00:26:13 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-2LMCFn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 00:26:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 00:26:13 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-H6DTmw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 00:26:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 00:26:13 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-0XBh0U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 00:26:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 00:26:13 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-xwqtiJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 00:26:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 00:26:13 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-CYl8fq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 00:26:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 00:26:13 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-nViXhM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 00:26:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 00:26:13 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-cO4dlI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 00:26:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 00:26:13 INFO - Running setup.py install for manifestparser 00:26:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 00:26:14 INFO - Running setup.py install for mozcrash 00:26:14 INFO - Running setup.py install for mozdebug 00:26:14 INFO - Running setup.py install for mozdevice 00:26:14 INFO - Installing dm script to /builds/slave/test/build/venv/bin 00:26:14 INFO - Running setup.py install for mozfile 00:26:14 INFO - Running setup.py install for mozhttpd 00:26:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 00:26:14 INFO - Running setup.py install for mozinfo 00:26:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 00:26:14 INFO - Running setup.py install for mozInstall 00:26:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 00:26:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 00:26:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 00:26:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 00:26:15 INFO - Running setup.py install for mozleak 00:26:15 INFO - Running setup.py install for mozlog 00:26:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 00:26:15 INFO - Running setup.py install for moznetwork 00:26:15 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 00:26:15 INFO - Running setup.py install for mozprocess 00:26:15 INFO - Running setup.py install for mozprofile 00:26:15 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 00:26:15 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 00:26:15 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 00:26:15 INFO - Running setup.py install for mozrunner 00:26:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 00:26:16 INFO - Running setup.py install for mozscreenshot 00:26:16 INFO - Running setup.py install for moztest 00:26:16 INFO - Running setup.py install for mozversion 00:26:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 00:26:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 00:26:16 INFO - Cleaning up... 00:26:16 INFO - Return code: 0 00:26:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:26:16 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')]} 00:26:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:26:16 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')]} 00:26:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:26:16 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 0x10a328c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a32c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7394c1b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2b1c60>, '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 0x10a046cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a06fbe8>, '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.pJ9hemjSLp/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.HFvv0RidAn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 00:26:16 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 00:26:16 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 00:26:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pJ9hemjSLp/Render', 00:26:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:26:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:26:16 INFO - 'HOME': '/Users/cltbld', 00:26:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:26:16 INFO - 'LOGNAME': 'cltbld', 00:26:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:26:16 INFO - 'MOZ_NO_REMOTE': '1', 00:26:16 INFO - 'NO_EM_RESTART': '1', 00:26:16 INFO - 'PAGER': '/bin/cat', 00:26:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:26:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:26:16 INFO - 'PWD': '/builds/slave/test', 00:26:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:26:16 INFO - 'SHELL': '/bin/bash', 00:26:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HFvv0RidAn/Listeners', 00:26:16 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 00:26:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:26:16 INFO - 'USER': 'cltbld', 00:26:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:26:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:26:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:26:16 INFO - 'XPC_FLAGS': '0x0', 00:26:16 INFO - 'XPC_SERVICE_NAME': '0', 00:26:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:26:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:26:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 00:26:16 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-rcTHPH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 00:26:16 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)) 00:26:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 00:26:16 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-5GXv8p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 00:26:17 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)) 00:26:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 00:26:17 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-IQNO_x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 00:26:17 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)) 00:26:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 00:26:17 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-0ElC5r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 00:26:17 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)) 00:26:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 00:26:17 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-0rkMoj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 00:26:17 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)) 00:26:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 00:26:17 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-i8mTJs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 00:26:17 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)) 00:26:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 00:26:17 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-QauSxY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 00:26:17 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)) 00:26:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 00:26:17 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-ic7qXf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 00:26:17 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)) 00:26:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 00:26:17 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-gU5zPy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 00:26:17 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)) 00:26:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 00:26:17 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-_i0WhZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 00:26:17 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)) 00:26:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 00:26:17 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-DkCPnJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 00:26:17 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)) 00:26:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 00:26:17 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-UFCaBX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 00:26:18 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)) 00:26:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 00:26:18 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-UrkIC4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 00:26:18 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)) 00:26:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 00:26:18 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-9wz69y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 00:26:18 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)) 00:26:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 00:26:18 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-4j4Thv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 00:26:18 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)) 00:26:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 00:26:18 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-0VXTMe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 00:26:18 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)) 00:26:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 00:26:18 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-6WTx74-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 00:26:18 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)) 00:26:18 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)) 00:26:18 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)) 00:26:18 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)) 00:26:18 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)) 00:26:18 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)) 00:26:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 00:26:18 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 00:26:18 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 00:26:18 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 00:26:18 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 00:26:19 INFO - Downloading blessings-1.6.tar.gz 00:26:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 00:26:20 INFO - Installing collected packages: blessings 00:26:20 INFO - Running setup.py install for blessings 00:26:20 INFO - Successfully installed blessings 00:26:20 INFO - Cleaning up... 00:26:20 INFO - Return code: 0 00:26:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 00:26:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 00:26:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 00:26:20 INFO - Reading from file tmpfile_stdout 00:26:20 INFO - Current package versions: 00:26:20 INFO - blessings == 1.6 00:26:20 INFO - blobuploader == 1.2.4 00:26:20 INFO - docopt == 0.6.1 00:26:20 INFO - functools32 == 3.2.3-2 00:26:20 INFO - jsonschema == 2.5.1 00:26:20 INFO - manifestparser == 1.1 00:26:20 INFO - mozInstall == 1.12 00:26:20 INFO - mozcrash == 1.0 00:26:20 INFO - mozdebug == 0.1 00:26:20 INFO - mozdevice == 0.51 00:26:20 INFO - mozfile == 1.2 00:26:20 INFO - mozhttpd == 0.7 00:26:20 INFO - mozinfo == 0.9 00:26:20 INFO - mozleak == 0.1 00:26:20 INFO - mozlog == 3.4 00:26:20 INFO - moznetwork == 0.27 00:26:20 INFO - mozprocess == 0.25 00:26:20 INFO - mozprofile == 0.28 00:26:20 INFO - mozrunner == 6.13 00:26:20 INFO - mozscreenshot == 0.1 00:26:20 INFO - mozsystemmonitor == 0.3 00:26:20 INFO - moztest == 0.8 00:26:20 INFO - mozversion == 1.4 00:26:20 INFO - psutil == 3.1.1 00:26:20 INFO - requests == 1.2.3 00:26:20 INFO - wsgiref == 0.1.2 00:26:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:26:20 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')]} 00:26:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:26:20 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')]} 00:26:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:26:20 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 0x10a328c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a32c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7394c1b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2b1c60>, '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 0x10a046cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a06fbe8>, '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.pJ9hemjSLp/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.HFvv0RidAn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 00:26:20 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 00:26:20 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 00:26:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pJ9hemjSLp/Render', 00:26:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:26:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:26:20 INFO - 'HOME': '/Users/cltbld', 00:26:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:26:20 INFO - 'LOGNAME': 'cltbld', 00:26:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:26:20 INFO - 'MOZ_NO_REMOTE': '1', 00:26:20 INFO - 'NO_EM_RESTART': '1', 00:26:20 INFO - 'PAGER': '/bin/cat', 00:26:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:26:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:26:20 INFO - 'PWD': '/builds/slave/test', 00:26:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:26:20 INFO - 'SHELL': '/bin/bash', 00:26:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HFvv0RidAn/Listeners', 00:26:20 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 00:26:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:26:20 INFO - 'USER': 'cltbld', 00:26:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:26:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:26:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:26:20 INFO - 'XPC_FLAGS': '0x0', 00:26:20 INFO - 'XPC_SERVICE_NAME': '0', 00:26:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:26:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:26:20 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)) 00:26:20 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)) 00:26:20 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)) 00:26:20 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)) 00:26:20 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)) 00:26:20 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)) 00:26:20 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)) 00:26:20 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)) 00:26:20 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)) 00:26:20 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 00:26:20 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 00:26:20 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 00:26:20 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 00:26:20 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 00:26:22 INFO - Downloading marionette_driver-1.4.0.tar.gz 00:26:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 00:26:22 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)) 00:26:22 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)) 00:26:22 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)) 00:26:22 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)) 00:26:22 INFO - Installing collected packages: marionette-driver 00:26:22 INFO - Running setup.py install for marionette-driver 00:26:22 INFO - Successfully installed marionette-driver 00:26:22 INFO - Cleaning up... 00:26:22 INFO - Return code: 0 00:26:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 00:26:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:26:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:26:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:26:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:26:22 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 0x10a328c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a32c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7394c1b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2b1c60>, '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 0x10a046cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a06fbe8>, '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.pJ9hemjSLp/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.HFvv0RidAn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 00:26:22 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 00:26:22 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 00:26:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pJ9hemjSLp/Render', 00:26:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:26:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:26:22 INFO - 'HOME': '/Users/cltbld', 00:26:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:26:22 INFO - 'LOGNAME': 'cltbld', 00:26:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:26:22 INFO - 'MOZ_NO_REMOTE': '1', 00:26:22 INFO - 'NO_EM_RESTART': '1', 00:26:22 INFO - 'PAGER': '/bin/cat', 00:26:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:26:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:26:22 INFO - 'PWD': '/builds/slave/test', 00:26:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:26:22 INFO - 'SHELL': '/bin/bash', 00:26:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HFvv0RidAn/Listeners', 00:26:22 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 00:26:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:26:22 INFO - 'USER': 'cltbld', 00:26:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:26:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:26:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:26:22 INFO - 'XPC_FLAGS': '0x0', 00:26:22 INFO - 'XPC_SERVICE_NAME': '0', 00:26:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:26:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:26:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 00:26:22 INFO - Cleaning up... 00:26:22 INFO - Return code: 0 00:26:22 INFO - Running post-action listener: _resource_record_post_action 00:26:22 INFO - Running post-action listener: _start_resource_monitoring 00:26:22 INFO - Starting resource monitoring. 00:26:22 INFO - [mozharness: 2017-05-31 07:26:22.953000Z] Finished create-virtualenv step (success) 00:26:22 INFO - [mozharness: 2017-05-31 07:26:22.954149Z] Running install step. 00:26:22 INFO - Running pre-action listener: _resource_record_pre_action 00:26:22 INFO - Running main action method: install 00:26:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 00:26:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 00:26:23 INFO - Reading from file tmpfile_stdout 00:26:23 INFO - Detecting whether we're running mozinstall >=1.0... 00:26:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 00:26:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 00:26:23 INFO - Reading from file tmpfile_stdout 00:26:23 INFO - Output received: 00:26:23 INFO - Usage: mozinstall [options] installer 00:26:23 INFO - Options: 00:26:23 INFO - -h, --help show this help message and exit 00:26:23 INFO - -d DEST, --destination=DEST 00:26:23 INFO - Directory to install application into. [default: 00:26:23 INFO - "/builds/slave/test"] 00:26:23 INFO - --app=APP Application being installed. [default: firefox] 00:26:23 INFO - mkdir: /builds/slave/test/build/application 00:26:23 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'] 00:26:23 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 00:26:45 INFO - Reading from file tmpfile_stdout 00:26:45 INFO - Output received: 00:26:45 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 00:26:45 INFO - Running post-action listener: _resource_record_post_action 00:26:45 INFO - [mozharness: 2017-05-31 07:26:45.054588Z] Finished install step (success) 00:26:45 INFO - [mozharness: 2017-05-31 07:26:45.054738Z] Running setup-mitmproxy step. 00:26:45 INFO - Running pre-action listener: _resource_record_pre_action 00:26:45 INFO - Running main action method: setup_mitmproxy 00:26:45 INFO - Skipping: mitmproxy is not required 00:26:45 INFO - Running post-action listener: _resource_record_post_action 00:26:45 INFO - [mozharness: 2017-05-31 07:26:45.055392Z] Finished setup-mitmproxy step (success) 00:26:45 INFO - [mozharness: 2017-05-31 07:26:45.055493Z] Running run-tests step. 00:26:45 INFO - Running pre-action listener: _resource_record_pre_action 00:26:45 INFO - Running main action method: run_tests 00:26:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 00:26:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 00:26:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 00:26:45 INFO - 2017-05-31 00:26:45.069 screenresolution[1632:8410] starting screenresolution argv=screenresolution get 00:26:45 INFO - 2017-05-31 00:26:45.072 screenresolution[1632:8410] Display 0: 1600x1200x32@60 00:26:45 INFO - 2017-05-31 00:26:45.078 screenresolution[1633:8413] starting screenresolution argv=screenresolution list 00:26:45 INFO - Available Modes on Display 0 00:26:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 00:26:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 00:26:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 00:26:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 00:26:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 00:26:45 INFO - Intel Iris: 00:26:45 INFO - Chipset Model: Intel Iris 00:26:45 INFO - Type: GPU 00:26:45 INFO - Bus: Built-In 00:26:45 INFO - VRAM (Dynamic, Max): 1536 MB 00:26:45 INFO - Vendor: Intel (0x8086) 00:26:45 INFO - Device ID: 0x0a2e 00:26:45 INFO - Revision ID: 0x0009 00:26:45 INFO - Displays: 00:26:45 INFO - Display: 00:26:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 00:26:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 00:26:45 INFO - Display Serial Number: 1600x1200 60 00:26:45 INFO - Main Display: Yes 00:26:45 INFO - Mirror: Off 00:26:45 INFO - Online: Yes 00:26:45 INFO - Rotation: Supported 00:26:45 INFO - Return code: 0 00:26:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 00:26:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 00:26:45 INFO - Python 2.7.3 00:26:45 INFO - Return code: 0 00:26:45 INFO - grabbing minidump binary from tooltool 00:26:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:26:45 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 0x10a2b1c60>, '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 0x10a046cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a06fbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 00:26:45 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 00:26:45 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 00:26:45 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 00:26:45 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 00:26:45 INFO - Return code: 0 00:26:45 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 00:26:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 00:26:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 00:26:45 INFO - ENV: RUST_BACKTRACE is now 1 00:26:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 00:26:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 00:26:45 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/IkpI-_XtS1i8ZvYndlIGNw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0104', '--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 00:26:45 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/IkpI-_XtS1i8ZvYndlIGNw/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0104 --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 00:26:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pJ9hemjSLp/Render', 00:26:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:26:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:26:45 INFO - 'HOME': '/Users/cltbld', 00:26:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:26:45 INFO - 'LOGNAME': 'cltbld', 00:26:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 00:26:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 00:26:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:26:45 INFO - 'MOZ_NO_REMOTE': '1', 00:26:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 00:26:45 INFO - 'NO_EM_RESTART': '1', 00:26:45 INFO - 'PAGER': '/bin/cat', 00:26:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:26:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:26:45 INFO - 'PWD': '/builds/slave/test', 00:26:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 00:26:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:26:45 INFO - 'RUST_BACKTRACE': '1', 00:26:45 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 00:26:45 INFO - 'SHELL': '/bin/bash', 00:26:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HFvv0RidAn/Listeners', 00:26:45 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 00:26:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:26:45 INFO - 'USER': 'cltbld', 00:26:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:26:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:26:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:26:45 INFO - 'XPC_FLAGS': '0x0', 00:26:45 INFO - 'XPC_SERVICE_NAME': '0', 00:26:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:26:45 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/IkpI-_XtS1i8ZvYndlIGNw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0104', '--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 00:26:45 INFO - mozversion application_buildid: 20170530231323 00:26:45 INFO - mozversion application_changeset: 925230851743b9a969a3142f00aea5014a33cb02 00:26:45 INFO - mozversion application_display_name: Nightly 00:26:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 00:26:45 INFO - mozversion application_name: Firefox 00:26:45 INFO - mozversion application_remotingname: firefox 00:26:45 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 00:26:45 INFO - mozversion application_vendor: Mozilla 00:26:45 INFO - mozversion application_version: 55.0a1 00:26:45 INFO - mozversion platform_buildid: 20170530231323 00:26:45 INFO - mozversion platform_changeset: 925230851743b9a969a3142f00aea5014a33cb02 00:26:45 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 00:26:45 INFO - mozversion platform_version: 55.0a1 00:26:45 INFO - using testdate: 1496215605 00:26:45 INFO - actual date: 1496215605 00:26:45 INFO - starting webserver on 'localhost:49228' 00:26:45 INFO - SUITE-START | Running 2 tests 00:26:45 INFO - TEST-START | dromaeo_css 00:26:45 INFO - operating with platform_type : mac_ 00:26:45 INFO - Initialising browser for dromaeo_css test... 00:26:45 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground -profile /var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/tmpv9ZoI4/profile 00:26:45 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground) 00:27:19 INFO - TEST-INFO | 1643: killed by SIGTERM 00:27:19 INFO - Browser initialized. 00:27:19 INFO - Running cycle 1/1 for dromaeo_css test... 00:27:19 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/tmpv9ZoI4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:27:21 INFO - PID 1651 | 2017-05-31 00:27:21.716 plugin-container[1652:9002] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9743, name = 'com.apple.tsm.portname' 00:27:21 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 00:27:21 INFO - PID 1651 | 2017-05-31 00:27:21.717 plugin-container[1652:9002] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 00:27:21 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 00:27:21 INFO - PID 1651 | 2017-05-31 00:27:21.717 plugin-container[1652:9002] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 00:27:22 INFO - PID 1651 | 2017-05-31 00:27:22.129 plugin-container[1653:9048] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x943b, name = 'com.apple.tsm.portname' 00:27:22 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 00:27:22 INFO - PID 1651 | 2017-05-31 00:27:22.129 plugin-container[1653:9048] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x990b, name = 'com.apple.CFPasteboardClient' 00:27:22 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 00:27:22 INFO - PID 1651 | 2017-05-31 00:27:22.129 plugin-container[1653:9048] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 00:27:31 INFO - PID 1651 | RSS: Main: 269336576 00:27:31 INFO - PID 1651 | 00:30:34 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49228/tests/dromaeo/cssquery-ext.html) 00:30:35 INFO - PID 1651 | RSS: Main: 269352960 00:30:35 INFO - PID 1651 | 00:33:37 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-ext.html (next: http://localhost:49228/tests/dromaeo/cssquery-jquery.html) 00:33:38 INFO - PID 1651 | RSS: Main: 281341952 00:33:38 INFO - PID 1651 | 00:36:40 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49228/tests/dromaeo/cssquery-mootools.html) 00:36:40 INFO - PID 1651 | RSS: Main: 283672576 00:36:40 INFO - PID 1651 | 00:39:42 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49228/tests/dromaeo/cssquery-prototype.html) 00:39:43 INFO - PID 1651 | RSS: Main: 287490048 00:39:43 INFO - PID 1651 | 00:42:46 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49228/tests/dromaeo/cssquery-yui.html) 00:42:46 INFO - PID 1651 | RSS: Main: 287911936 00:42:46 INFO - PID 1651 | 00:45:48 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-yui.html (next: http://localhost:49228/tests/dromaeo/cssquery-dojo.html) 00:45:48 INFO - PID 1651 | RSS: Main: 294092800 00:45:48 INFO - PID 1651 | 00:45:48 INFO - PID 1651 | __start_tp_report 00:45:48 INFO - PID 1651 | _x_x_mozilla_page_load 00:45:48 INFO - PID 1651 | _x_x_mozilla_page_load_details 00:45:48 INFO - PID 1651 | |i|pagename|runs| 00:45:48 INFO - PID 1651 | |0;dojo.html;3618.2582570573027;3640.3629364861144;3661.157933675256;3663.9816800916;3677.52192215012;5315.946840531594;6122.5811271884995;6226.564140510166;6272.811815645532;6295.276043255027;6175.66033868137;6232.906506402396;6236.532260080489;6301.527385446098;6320;5139.997700448414;5193.246979188016;5475.945240547603;5476.507114359707;5499.340079190482;4845.733484658343;5622.072358060915;5629.690367029812;5633.295838020247;5651.40660230676;5382.1926710993275;5436.021516127095;5488.4785945335125;5502.532284755801;5554.8611284718;5511;5521.723913804334;5541.0580201365865;5541.33503979521;5556;5457.699826509541;5476.7535460903955;5482;5516.558675305966;5545.750441230114;6188.752449901998;6218.28489723683;6299.8740025199695;6306.306306306303;6308.621482711052;6540.640264785435;6670.866582668368;6738.663066846638;6742.123523941906;6766.25571187174;6878.105846240007;6893.931060689379;6912.688928998157;6915.585064896122;6921.377076063179;6382.106505089291;6388.904166437507;6389.393007664265;6395.840103997437;6397.0724244984685;6714.664266786739;6783.694733736994;6907.240203577602;6967.8606427871155;6998.755043573552;6320.051992201114;6452.93526568111;6574.474042076622;6574.868502629921;6648.800535983928;5733.684647344353;6600.603963762093;6755.831104222434;6804.353586409283;6804.863902721918;6695.46436285096;6699.363560461748;6700.128983232149;6715.966420167868;6743.831404214836;6497.090407342976;6586.506012049115;6647.235567909655;6687.832804179837;6731.057651928734;5393.13709806437;5394.78420863161;5413.350397952192;5416.404195538487;5439;4811.446683631357;4826.420829500483;4832.613390928717;4834.685745426536;4837.22604383304;6301.747930082837;6324.810255692337;6331.68341582919;6342.429181373606;6369.108324834527;6704.329567043256;6739.359760822714;6745.730170793114;6755.1556055493065;6759.594424334556;5318.973405133027;6359.777407790705;6394.808155755335;6399.872002559922;6415.390537898892;4906.31311616374;5357.732113394315;5858.06270996647;5886.381929897385;5890.14592884038;6259.718312675849;6471.255805582418;6918.308169183142;6998.580085194904;7030.648467576703;6776.534190964362;6849.48628852848;6864.141982252218;6868.141482314711;6871.759488417882;6763.222229443677;6936.173823630596;7009.404200643003;7012.509124361246;7037.612931288828;6547.770828020996;6574.934250657432;6602.735890564516;6665.533412661067;6680.966595166993;6216.937830621636;6412.839679008062;6855.931440685728;6873.243943166148;6884.65576721172;6369.20384951881;6410.4871610272;6525.543211975116;6547.312532184243;6613.008048792527;6457.031445283245;6498.74005039812;6505.772297969548;6511.674416279262;6516.837079073061;6268.780592679234;6389.744410223539;6390.392912673288;6401.423871851555;6415.262244841903;5108.66793658411;5196.844094677166;5421.078416669096;6374.426301632875;6422.967885160544;6363.904541431974;6366.267879193952;6407.519436042408;6414.61512309263;6439.903401448888 00:45:48 INFO - PID 1651 | |1;ext.html;776.7126163319573;813.7314686153569;838.3209600223819;1253.8119282107689;1316.7300703355813;38293.04267393317;38883.805580972126;38972.610273897255;39025.02437439062;39068.80465597672;35748;37221;39386.21227575447;39884.60115398845;39903.800480997554;7816.609169541514;7982.0820605630515;8213.794655133639;8383.496990180578;8409.159084091603;8352.665893364257;8435.95782021089;8461.53461559614;8463;8472.19514146155;8414.116517765608;8483.830323393528;8485.745427637181;8496.107908669594;8510.617022233984;7448.9627551861895;8416.957915210416;8444.957775211085;8449.45078569894;8469.830603387898;7121;8215.876761848576;8248.092709801916;8261;8262.752117436487;36420;36706.816465917764;36724.81637591821;36751.4487282691;36772;3556.324298383299;3566.5363502744476;3582.158192824684;3587.910302242439;3600.009997250751;30102.69897301043;30226.24434389158;30377.848110759303;30545.389092218033;30554.694453055406;2764.958525622097;2814.5918841768034;3046.0709483607493;3083.290843106076;3091.4280858041334;237848;243253;265063.6746816273;272427;272805;47122.293165601855;47340.05319893583;47379.526204737514;47432.814179645786;47489.05021899612;49107;49185.75407123013;49205.5714985653;54547.45452545503;55329.446705533235;14592.562223133324;14720.337584808709;14768.261586920611;14784.11295322284;14806.185659788815;11716.297022178696;11800.822987655192;11804.46879890407;11806;11808.76382472363;14013.229272389912;14101.929490352482;14175.149491030365;14207.147571145557;14319.642008949651;6069;6101.694915254308;6130.417610327012;6141;6143.784967526115;909.2952961454926;993.4883534979491;1009.2128140050772;1024.1243736605095;1033.7984093101923;24559.508809823703;24766.38084047913;24872.875635621705;24891.62662560063;24913;1133.1218305812897;1134.9148813838995;1165.7726743285148;1168.4216312924987;1168.4975460552043;12246.632601021984;12742.36288185587;12754.23474591509;12790.360481975864;12797.936010320074;327108;328014;329003;329540.352298237;329699;54215;54488.72755636196;54582.90834183294;54597.45402546003;54652.726736366065;1371.4719832864419;1409.6194027612487;1508.7284288827898;1522.7487464568221;1553.425232663899;24223.878880605484;24267.757322427256;24457.75542244555;24483.020679172634;24493;37612.811935940146;37747.81126094462;37891;37914.8104259477;37921.62078379181;218257;237725;245638.0872382578;263677;264733;28094.85952570224;35293.764718234735;35643.64356356403;36241.818790905876;36300;43686.56313436952;44307.8923026914;45822;45889.54110458853;45908.770456147504;28491.28771780639;30452.934147305623;30921;31259;31823.840880795447;29305.560416594195;30396;30508.389832203677;31214.21964450907;31375.215619608778;37554.812225938695;37575.060623484635;37663.62336376711;37792.811035944644;37819.24361512809;29301;29392;30999.69000309968;31416.057518274487;31915.202119947186;20373.898130509253;21720.67418988685;24426.90078946416;24445.51108977846;24906.501869962136 00:45:48 INFO - PID 1651 | |2;jquery.html;362.87843572403244;412.1077866419204;420.495405513384;433.3781024230231;451.6612540594554;20994.58010839784;21692.13231470741;21750.02124904379;22895.19866804662;23882.164124255654;25286.2414127576;25555;25561.97752089919;25596.976120955187;25636.10273640423;5983.700814959257;7201.783946481614;7242.818929526751;7268.672909719049;7268.781936541912;6829.112215412014;7100.751473698421;7138.75014374497;7179.3538581527655;7202.8559428811395;7166.104236970379;7169.103862017248;7178.102737157856;7196.388306993912;7196.64016799158;7104.857902841914;7159.534630249043;7169.390601798827;7174.461915356369;7176.712931482734;6945;7010.579365238051;7024.719011239545;7032.542884712529;7046.6476676165985;30222.54666180009;30406.087817365515;30611.928582499728;31582.36835263305;31821.363572728646;2275.3174047785596;2339.6139636959883;2344.788968992799;2345.0854166874933;2350.952980940389;20126.49683757903;20432.182712691476;20444.488887777923;20454.693179602316;20462.386128416172;2247.8089362404216;2256.9505180091173;2292.816574674022;2305.965410518844;2316.6756654068445;154161.45838541584;167883;173444.13277933656;181262;186023;25915;26046.479070418485;26421.867890660425;26729.732702673115;27960;44387.77806111013;44423.555764442586;44428.55571444244;44440.55559444364;44462.33306500405;11443.539638977494;11491.54033838637;11576.131790115774;11648.767024659459;11652.41737913101;9168.083191680911;9185.540722963959;9193.770155746026;9200.401973871676;9222.76943076415;12314.938425307817;12400;12446.81329780054;12471.189372690927;12472.750544989049;4785.832495862628;4895.755212239445;4914.754262286871;4938.75306234694;4943.604511639061;957.7701351675687;978.5841017567506;979.9265055120894;998.7503124218945;1007.5012868630075;20850.791492084885;21264.681029784566;21311.041003154896;21426.464338391354;21561.814100224325;953.4422362917771;1005.5676059294427;1018.1549314069305;1037.906588407047;1042.9791404172026;9882.308238423386;10640.308379955433;10935.070519005814;11024.614138505114;11027.503762330714;451282;510352;511352;521642;521739;151512;154242.22878885758;154439;155118.22440887723;155822;999.7000899730052;1134.7957367673898;1165.6794381545144;1204.084895479442;1211.3821950805152;22662.773372266067;22988.965496553028;23288.41778955472;23347.9493422793;23701.881490592437;50139.74930125326;68189;68511.65744171248;69571;69836.60326793334;96394;96750;96863;96937.51531242298;98002.01997979928;14027.859721402656;15364.462243821412;15877.52367428979;16125;16180.838191617933;14776.852231477978;17923.73114403259;18173.182206800462;18259.08704564793;18273.99493027896;14737.263136842901;14842.332095055965;15060.472883449027;15126.773098403313;15178.696426071638;12700.36498175106;13195.67010824737;13244.20534767917;13246.072774905664;13420.188274582806;14672.853271467575;15050.849491504945;15126.941114121906;15296.082235065931;15313.846861531241;15713.371465141267;15882.761758573399;15919.363225471314;15926.68146637084;15956.760648590513;14102.294885255902;14857.034292770935;14869.330880110205;14950;14976.101433914 00:45:48 INFO - PID 1651 | |3;mootools.html;1281.3465132782285;1298.5844529750477;1464.61187785237;1471.735087684217;1489.6871656952044;11399.829002564968;11570;11841.7631647367;11905.642830715082;12319.384030798468;11815.113866460008;11883.22759020663;11938.343391113485;12049.277043377382;12062.2762634242;2148.215901196068;2508.448141408892;2543.36415896026;2545.770880620753;2555.2589748972778;2481.081999660128;2495.800335973118;2503.5869081601513;2504.887280072401;2505.3736565858535;2452.1172377943926;2482.640017197505;2494.837835540693;2495.4884248728968;2495.663085483464;2148.732247973703;2435.683361163055;2462.495188486356;2466.2847774145475;2469.3085935937966;2418.8427752196053;2457.8156638252026;2461.8276720629565;2530.569803133453;2545.8217924745277;11700.882991170065;11773.587924422689;11796.705082372924;11808;11817.94091029548;6431.517636177306;6605.306442823482;6669.833254168636;6675.599464032173;6692.364225398579;4042.312806822847;4194.412782210493;4255.340422234559;4274.316109422509;4285.35719642053;1168.2581560708927;1207.5471698113208;1208.6011616166643;1220.530095913071;1234.9938250308692;14943.252837358197;15385.999999999887;15908.76136857948;15982.00224971867;15995.600109997227;5448.727563621803;5450.645708029045;5579.358373786985;5605.719714014364;5623.100303951348;11718.531258749554;11747.412629368497;11756.1182911282;11767.352795596324;11769.941150294193;5780.971095144581;6089.512838972872;6313.558050936482;6356.523260755462;6361.713722882481;5393.7033463159905;5906.232189815297;5963.016102343152;6015.939840601625;6057.666828324484;3661;4150.8339666413;4187.748735075906;4228.048689045002;4292.806823692878;1236.9319687417278;1238.120934136755;1239.3555351217317;1250.0437165568167;1256.7423678145958;767.3822572828926;782.6673663692908;802.6187560908522;803.1005274093053;808.9878651820227;6543.345665433419;6634.137562116894;6800.965995169992;6899.862002760017;6912.066870972455;788.286600626434;807.8868958345836;889.768660148366;955.044955044955;983.6704703924265;3739.6073412291316;3788.204477059793;3843.6348546888003;3860.2279544091293;3870.4000879863665;13909.095908765898;14711.485098021518;14727.779083313759;15009.699806003819;15152.31814568347;14792.778108328384;14931.25343732809;14992.925035374754;15173;15280.083195008336;8587.742367728979;8629.568521573896;8655.697050603325;8748.737537873874;8771.473711577326;5903.970480147572;6357.8728425432155;6956.2000369958105;7010.298970102949;7018.543794653331;11106.777864442503;12843.871561284268;13351.99999999961;13525.458981640624;13554;13724.107932984323;13840.4463821446;13889.513867014606;13918.930405347908;13979.790303145257;8357.874631880404;8510.361722870686;8520.829583408173;8522.36082293818;8534.487930724177;10459.84310235332;10509.2118091145;10597.841032384365;10625.840612390666;10668.98644628759;9069.138431848964;9091.499967501852;9138.954305228697;9148.542572871464;9270.304727145356;8880.378373513791;8900.821983560163;8953.418027828171;8955.328350373618;9002.234810040958;8068.435209535276;8233.176682331958;8236.341092712688;8252.381071419548;8258.83482330338;5190.844274671766;5697.23087383198;5942.524598032061;6069.787557435468;6149.7847575334645;5628.212050312877;5655.981923253854;6060.545459090674;6202.875942481035;6265.8120256392385 00:45:48 INFO - PID 1651 | |4;prototype.html;390.4923599320882;391.23318296139564;392.90570263136846;393.78735482839267;404.1996846244435;5594.83215503535;6327.462165715908;6401.903971440426;6424.2290925089;6556;5771.278590176228;6012.8496787580225;6238.251409830816;6418.454431373327;6425.453836423898;4451.933221001679;4480.305552639346;4675.4155730533685;4677.766111694419;4683.742394168336;3819.618038196186;3928.466139340124;4655.4878963314;4669.276262179351;4676.859694209179;4561.703489273204;4605.4933957264675;4612.815487380501;4618.838340658078;4621.907561848761;4036.394540818886;4623.445186577632;4637.304404339342;4644.256918892977;4681.46163191234;3743.4838890249493;3962.0689138052535;3964.5242570891387;3965.405189221611;3966.4446977423186;5554.361248456439;5957.106434034886;6178.258608966953;6239.625622462667;6361.236651601791;5528.226048353234;5530.087535556629;5548.944510554883;5549.225387306346;5557.416471270499;5669.404712505168;5693.459121383504;6897.862042759167;6899.068260886909;6947.826304342381;3740.6259374062374;4464.955350446487;4479.76011994003;4485.9102817943785;4526;5178.203769964177;6133.987891997815;6205.5345849061505;6786;7087.2990482284395;3636.5272514572935;4218.599233072853;4477.19410506112;4534.523874993079;4570.154521413549;3569.375359312162;4250.66868936881;4608.308753686973;4661.627069834473;4714.858554243378;4932.358793356841;5093.898122037613;5337;5450.4549545045975;5459.945400546023;5343.412224655285;5822.912656310159;6779.423748981293;6858.348456896687;6886.449084073262;1479.8594133557294;1498.7077519883735;1563.140272849928;1578.2380833541304;1581.343742346936;990.7126933189294;1007.6422869881234;1024.912882404989;1026.343140390151;1034.1984961654778;6196.907046394308;6365.331640177715;6477.449416799531;6484.773032943825;6611.371919667624;6085.292045039915;6749.156355455568;6876.693428248616;7141.642917854087;7181.353678168885;6417.582857114273;6422.807315780534;6431.13179720741;6432.324605916311;6488.15653964991;3273.3289675616443;3284.4088064148204;3617.7467577269863;3703.185299234164;3735.9252814943547;6468.353164683588;6745;7019.5788252705;7307.159676637149;7328.816779580447;6700.463962882957;7127.75052873147;7294.70821167158;7321.853562928818;7351.522151060164;5980.940190598125;7363.729037158435;7399.260073992557;7402.666879990414;7405.185429602631;6200.968995154995;6581.769638062645;7105.46708996817;7120.113170009848;7219.530730502501;6134.723937422916;6616.966915165393;7037.683304251424;7054.682539285847;7074.221835598183;5690.94717477268;5963.671998040149;7134.821629459305;7259.419246460376;7312.451566132455;4492.168948744493;4529.614982726505;4543.818607162228;4560.931586026146;4568.246239370466;6151.446369826737;6270.811875643738;7160.534565253241;7163.677634506564;7186.137663480206;6279.874402512016;6638.933610663831;6684;6831.69257383409;6869.484788640972;6803.897941530782;6904.102466679299;6910.20532638753;6912.861742765016;6920.653967301715;5593.860153496195;6266.310705822447;6534.738610455529;6707.262201157966;6738.629375384205;6841.828954276183;6849.020676005954;6882.793516194522;6911.75808846688;6961;5943.554233432596;6178.66017369049;6611.966940165268;6691.197056353269;6700.497462690415 00:45:48 INFO - PID 1651 | |5;yui.html;812.8577498937684;833.1377024779354;839.2907992746132;848.2620120495168;860.9612567434465;14588.562343129723;15090.924545377286;16851.915740421297;17283.395081172162;17284.39504617337;17636.82363176368;17853.732194017066;18256.08719564023;18299.17653705583;18305.90847045763;950.1496160935958;975.517119026083;981.0777868803337;981.2101258486915;981.249344251648;980.0787259975635;994.4679596415918;995.9004099590055;996.3772642098688;996.7707427291728;887.6493784954971;1036.1451802263164;1043.6138628707351;1044.1177205261542;1045.1534257251612;1032.9018743219383;1034.4414016431117;1035.1615191694752;1035.6996471023356;1036.471399586209;754.2985023927745;755.5882044285877;758.0410780362845;759.7530802489213;760.8896709977022;17582.560435989075;17768.200430980636;17808.46574602764;17900.10499475034;17921.37275195375;17482;17693;18063.367782127694;18081.54796130094;18103.72844407335;14721.263936803118;14873.405063797436;15014.24928753558;15036.17301048442;15042.398304067825;3543.7696549724187;3599.0822340303316;3614.0603443104983;3622.981885090584;3669.321175582526;114811;144678.2766086163;152294;153438.23280883525;153791;13536.05247632676;13623.250721210428;13775;13972;13977.3710183042;26302.86848565745;26761.37243442748;26767.732322676526;26911.05811296595;27047.188584342108;6737.730490780314;6932.930670693228;7622.58075805836;7699.730509432143;7988.081370642334;3923.6664883484655;4010.438538604598;4016.8995775105855;4019.4774679291504;4022.6781857451333;4144.896377590524;4196.475440569929;4240.1519696060295;4242.5757424257945;4310.008697999478;818.8116733151409;911.7948461596088;926.4395041000232;933.883264591926;935.8268720286769;616.3897741236144;616.5529990756755;646.4795839349368;656.7832615237056;674.6458109492457;10111.949440252898;11408.714782130348;11413.029892459068;12472.501099956082;12540.686482838002;580.9331926828385;638.2628064585411;646.6443456099126;656.1896058367912;676.3777324861139;5597.972010139923;5646.096624540054;5804.158397032406;5865.501432378211;5901.262342207224;125541.37229313795;129590.70409295787;147095;147492.26253868663;149857.25071374574;123748;123984;124933;126601.36699316444;128271.58642068045;112864;113652;114644;116783;118017;11118.777624447628;11571.942140289244;11727.296362218294;11739.178257522233;11843.940780296043;41204.58795412127;41384.793076034424;42098.57901421069;42420;42499.787501062296;45500.54499454963;55452.44547554473;56248.43751562432;56379.71810141087;57194;14595.416183352972;14739.705205896036;15011.32449039774;15062.774058389352;15063.47277845314;15987.200639967723;18292.542686432946;18920;18935.147918343984;18966.336178233694;16685.165741712623;16847.747283791;17396.565085872953;17585.472435826945;17706.734398984285;16920.915395422944;17005.829941700424;17152.399666011628;17355.913220433817;17383.956962582284;16836.831631683526;17059.48821535356;17142.82857171462;17208.827911721222;17309.65380692404;8738.737837864874;12047.51809927593;12110.152289340014;12339.93830030844;12375.071869609636;13323.13399629021;13341.066125371131;13348.332583370986;13351.732965340832;13386.598402047954 00:45:48 INFO - PID 1651 | __end_tp_report 00:45:48 INFO - PID 1651 | __start_cc_report 00:45:48 INFO - PID 1651 | _x_x_mozilla_cycle_collect,449 00:45:48 INFO - PID 1651 | __end_cc_report 00:45:48 INFO - PID 1651 | __startTimestamp1496216748939__endTimestamp 00:45:48 INFO - PID 1651 | 00:45:48 INFO - PID 1651 | ------- Summary: start ------- 00:45:48 INFO - PID 1651 | Number of tests: 6 00:45:48 INFO - PID 1651 | 00:45:48 INFO - PID 1651 | [#0] dojo.html Cycles:165 Average:6155.91 Median:6389.74 stddev:735.25 (11.5%) stddev-sans-first:710.04 00:45:48 INFO - PID 1651 | Values: 3618.3 3640.4 3661.2 3664.0 3677.5 5315.9 6122.6 6226.6 6272.8 6295.3 6175.7 6232.9 6236.5 6301.5 6320.0 5140.0 5193.2 5475.9 5476.5 5499.3 4845.7 5622.1 5629.7 5633.3 5651.4 5382.2 5436.0 5488.5 5502.5 5554.9 5511.0 5521.7 5541.1 5541.3 5556.0 5457.7 5476.8 5482.0 5516.6 5545.8 6188.8 6218.3 6299.9 6306.3 6308.6 6540.6 6670.9 6738.7 6742.1 6766.3 6878.1 6893.9 6912.7 6915.6 6921.4 6382.1 6388.9 6389.4 6395.8 6397.1 6714.7 6783.7 6907.2 6967.9 6998.8 6320.1 6452.9 6574.5 6574.9 6648.8 5733.7 6600.6 6755.8 6804.4 6804.9 6695.5 6699.4 6700.1 6716.0 6743.8 6497.1 6586.5 6647.2 6687.8 6731.1 5393.1 5394.8 5413.4 5416.4 5439.0 4811.4 4826.4 4832.6 4834.7 4837.2 6301.7 6324.8 6331.7 6342.4 6369.1 6704.3 6739.4 6745.7 6755.2 6759.6 5319.0 6359.8 6394.8 6399.9 6415.4 4906.3 5357.7 5858.1 5886.4 5890.1 6259.7 6471.3 6918.3 6998.6 7030.6 6776.5 6849.5 6864.1 6868.1 6871.8 6763.2 6936.2 7009.4 7012.5 7037.6 6547.8 6574.9 6602.7 6665.5 6681.0 6216.9 6412.8 6855.9 6873.2 6884.7 6369.2 6410.5 6525.5 6547.3 6613.0 6457.0 6498.7 6505.8 6511.7 6516.8 6268.8 6389.7 6390.4 6401.4 6415.3 5108.7 5196.8 5421.1 6374.4 6423.0 6363.9 6366.3 6407.5 6414.6 6439.9 00:45:48 INFO - PID 1651 | 00:45:48 INFO - PID 1651 | [#1] ext.html Cycles:180 Average:43378.63 Median:182496.38 stddev:73408.18 (40.2%) stddev-sans-first:73544.42 00:45:48 INFO - PID 1651 | Values: 776.7 813.7 838.3 1253.8 1316.7 38293.0 38883.8 38972.6 39025.0 39068.8 35748.0 37221.0 39386.2 39884.6 39903.8 7816.6 7982.1 8213.8 8383.5 8409.2 8352.7 8436.0 8461.5 8463.0 8472.2 8414.1 8483.8 8485.7 8496.1 8510.6 7449.0 8417.0 8445.0 8449.5 8469.8 7121.0 8215.9 8248.1 8261.0 8262.8 36420.0 36706.8 36724.8 36751.4 36772.0 3556.3 3566.5 3582.2 3587.9 3600.0 30102.7 30226.2 30377.8 30545.4 30554.7 2765.0 2814.6 3046.1 3083.3 3091.4 237848.0 243253.0 265063.7 272427.0 272805.0 47122.3 47340.1 47379.5 47432.8 47489.1 49107.0 49185.8 49205.6 54547.5 55329.4 14592.6 14720.3 14768.3 14784.1 14806.2 11716.3 11800.8 11804.5 11806.0 11808.8 14013.2 14101.9 14175.1 14207.1 14319.6 6069.0 6101.7 6130.4 6141.0 6143.8 909.3 993.5 1009.2 1024.1 1033.8 24559.5 24766.4 24872.9 24891.6 24913.0 1133.1 1134.9 1165.8 1168.4 1168.5 12246.6 12742.4 12754.2 12790.4 12797.9 327108.0 328014.0 329003.0 329540.4 329699.0 54215.0 54488.7 54582.9 54597.5 54652.7 1371.5 1409.6 1508.7 1522.7 1553.4 24223.9 24267.8 24457.8 24483.0 24493.0 37612.8 37747.8 37891.0 37914.8 37921.6 218257.0 237725.0 245638.1 263677.0 264733.0 28094.9 35293.8 35643.6 36241.8 36300.0 43686.6 44307.9 45822.0 45889.5 45908.8 28491.3 30452.9 30921.0 31259.0 31823.8 29305.6 30396.0 30508.4 31214.2 31375.2 37554.8 37575.1 37663.6 37792.8 37819.2 29301.0 29392.0 30999.7 31416.1 31915.2 20373.9 21720.7 24426.9 24445.5 24906.5 00:45:48 INFO - PID 1651 | 00:45:48 INFO - PID 1651 | [#2] jquery.html Cycles:180 Average:39059.72 Median:23318.18 stddev:87657.90 (375.9%) stddev-sans-first:87855.66 00:45:48 INFO - PID 1651 | Values: 362.9 412.1 420.5 433.4 451.7 20994.6 21692.1 21750.0 22895.2 23882.2 25286.2 25555.0 25562.0 25597.0 25636.1 5983.7 7201.8 7242.8 7268.7 7268.8 6829.1 7100.8 7138.8 7179.4 7202.9 7166.1 7169.1 7178.1 7196.4 7196.6 7104.9 7159.5 7169.4 7174.5 7176.7 6945.0 7010.6 7024.7 7032.5 7046.6 30222.5 30406.1 30611.9 31582.4 31821.4 2275.3 2339.6 2344.8 2345.1 2351.0 20126.5 20432.2 20444.5 20454.7 20462.4 2247.8 2257.0 2292.8 2306.0 2316.7 154161.5 167883.0 173444.1 181262.0 186023.0 25915.0 26046.5 26421.9 26729.7 27960.0 44387.8 44423.6 44428.6 44440.6 44462.3 11443.5 11491.5 11576.1 11648.8 11652.4 9168.1 9185.5 9193.8 9200.4 9222.8 12314.9 12400.0 12446.8 12471.2 12472.8 4785.8 4895.8 4914.8 4938.8 4943.6 957.8 978.6 979.9 998.8 1007.5 20850.8 21264.7 21311.0 21426.5 21561.8 953.4 1005.6 1018.2 1037.9 1043.0 9882.3 10640.3 10935.1 11024.6 11027.5 451282.0 510352.0 511352.0 521642.0 521739.0 151512.0 154242.2 154439.0 155118.2 155822.0 999.7 1134.8 1165.7 1204.1 1211.4 22662.8 22989.0 23288.4 23347.9 23701.9 50139.7 68189.0 68511.7 69571.0 69836.6 96394.0 96750.0 96863.0 96937.5 98002.0 14027.9 15364.5 15877.5 16125.0 16180.8 14776.9 17923.7 18173.2 18259.1 18274.0 14737.3 14842.3 15060.5 15126.8 15178.7 12700.4 13195.7 13244.2 13246.1 13420.2 14672.9 15050.8 15126.9 15296.1 15313.8 15713.4 15882.8 15919.4 15926.7 15956.8 14102.3 14857.0 14869.3 14950.0 14976.1 00:45:48 INFO - PID 1651 | 00:45:48 INFO - PID 1651 | [#3] mootools.html Cycles:180 Average:7022.75 Median:3700.30 stddev:4462.65 (120.6%) stddev-sans-first:4454.31 00:45:48 INFO - PID 1651 | Values: 1281.3 1298.6 1464.6 1471.7 1489.7 11399.8 11570.0 11841.8 11905.6 12319.4 11815.1 11883.2 11938.3 12049.3 12062.3 2148.2 2508.4 2543.4 2545.8 2555.3 2481.1 2495.8 2503.6 2504.9 2505.4 2452.1 2482.6 2494.8 2495.5 2495.7 2148.7 2435.7 2462.5 2466.3 2469.3 2418.8 2457.8 2461.8 2530.6 2545.8 11700.9 11773.6 11796.7 11808.0 11817.9 6431.5 6605.3 6669.8 6675.6 6692.4 4042.3 4194.4 4255.3 4274.3 4285.4 1168.3 1207.5 1208.6 1220.5 1235.0 14943.3 15386.0 15908.8 15982.0 15995.6 5448.7 5450.6 5579.4 5605.7 5623.1 11718.5 11747.4 11756.1 11767.4 11769.9 5781.0 6089.5 6313.6 6356.5 6361.7 5393.7 5906.2 5963.0 6015.9 6057.7 3661.0 4150.8 4187.7 4228.0 4292.8 1236.9 1238.1 1239.4 1250.0 1256.7 767.4 782.7 802.6 803.1 809.0 6543.3 6634.1 6801.0 6899.9 6912.1 788.3 807.9 889.8 955.0 983.7 3739.6 3788.2 3843.6 3860.2 3870.4 13909.1 14711.5 14727.8 15009.7 15152.3 14792.8 14931.3 14992.9 15173.0 15280.1 8587.7 8629.6 8655.7 8748.7 8771.5 5904.0 6357.9 6956.2 7010.3 7018.5 11106.8 12843.9 13352.0 13525.5 13554.0 13724.1 13840.4 13889.5 13918.9 13979.8 8357.9 8510.4 8520.8 8522.4 8534.5 10459.8 10509.2 10597.8 10625.8 10669.0 9069.1 9091.5 9139.0 9148.5 9270.3 8880.4 8900.8 8953.4 8955.3 9002.2 8068.4 8233.2 8236.3 8252.4 8258.8 5190.8 5697.2 5942.5 6069.8 6149.8 5628.2 5656.0 6060.5 6202.9 6265.8 00:45:48 INFO - PID 1651 | 00:45:48 INFO - PID 1651 | [#4] prototype.html Cycles:180 Average:5432.20 Median:6134.36 stddev:1744.99 (28.4%) stddev-sans-first:1708.36 00:45:48 INFO - PID 1651 | Values: 390.5 391.2 392.9 393.8 404.2 5594.8 6327.5 6401.9 6424.2 6556.0 5771.3 6012.8 6238.3 6418.5 6425.5 4451.9 4480.3 4675.4 4677.8 4683.7 3819.6 3928.5 4655.5 4669.3 4676.9 4561.7 4605.5 4612.8 4618.8 4621.9 4036.4 4623.4 4637.3 4644.3 4681.5 3743.5 3962.1 3964.5 3965.4 3966.4 5554.4 5957.1 6178.3 6239.6 6361.2 5528.2 5530.1 5548.9 5549.2 5557.4 5669.4 5693.5 6897.9 6899.1 6947.8 3740.6 4465.0 4479.8 4485.9 4526.0 5178.2 6134.0 6205.5 6786.0 7087.3 3636.5 4218.6 4477.2 4534.5 4570.2 3569.4 4250.7 4608.3 4661.6 4714.9 4932.4 5093.9 5337.0 5450.5 5459.9 5343.4 5822.9 6779.4 6858.3 6886.4 1479.9 1498.7 1563.1 1578.2 1581.3 990.7 1007.6 1024.9 1026.3 1034.2 6196.9 6365.3 6477.4 6484.8 6611.4 6085.3 6749.2 6876.7 7141.6 7181.4 6417.6 6422.8 6431.1 6432.3 6488.2 3273.3 3284.4 3617.7 3703.2 3735.9 6468.4 6745.0 7019.6 7307.2 7328.8 6700.5 7127.8 7294.7 7321.9 7351.5 5980.9 7363.7 7399.3 7402.7 7405.2 6201.0 6581.8 7105.5 7120.1 7219.5 6134.7 6617.0 7037.7 7054.7 7074.2 5690.9 5963.7 7134.8 7259.4 7312.5 4492.2 4529.6 4543.8 4560.9 4568.2 6151.4 6270.8 7160.5 7163.7 7186.1 6279.9 6638.9 6684.0 6831.7 6869.5 6803.9 6904.1 6910.2 6912.9 6920.7 5593.9 6266.3 6534.7 6707.3 6738.6 6841.8 6849.0 6882.8 6911.8 6961.0 5943.6 6178.7 6612.0 6691.2 6700.5 00:45:48 INFO - PID 1651 | 00:45:48 INFO - PID 1651 | [#5] yui.html Cycles:180 Average:25317.36 Median:17876.92 stddev:39467.48 (220.8%) stddev-sans-first:39535.31 00:45:48 INFO - PID 1651 | Values: 812.9 833.1 839.3 848.3 861.0 14588.6 15090.9 16851.9 17283.4 17284.4 17636.8 17853.7 18256.1 18299.2 18305.9 950.1 975.5 981.1 981.2 981.2 980.1 994.5 995.9 996.4 996.8 887.6 1036.1 1043.6 1044.1 1045.2 1032.9 1034.4 1035.2 1035.7 1036.5 754.3 755.6 758.0 759.8 760.9 17582.6 17768.2 17808.5 17900.1 17921.4 17482.0 17693.0 18063.4 18081.5 18103.7 14721.3 14873.4 15014.2 15036.2 15042.4 3543.8 3599.1 3614.1 3623.0 3669.3 114811.0 144678.3 152294.0 153438.2 153791.0 13536.1 13623.3 13775.0 13972.0 13977.4 26302.9 26761.4 26767.7 26911.1 27047.2 6737.7 6932.9 7622.6 7699.7 7988.1 3923.7 4010.4 4016.9 4019.5 4022.7 4144.9 4196.5 4240.2 4242.6 4310.0 818.8 911.8 926.4 933.9 935.8 616.4 616.6 646.5 656.8 674.6 10111.9 11408.7 11413.0 12472.5 12540.7 580.9 638.3 646.6 656.2 676.4 5598.0 5646.1 5804.2 5865.5 5901.3 125541.4 129590.7 147095.0 147492.3 149857.3 123748.0 123984.0 124933.0 126601.4 128271.6 112864.0 113652.0 114644.0 116783.0 118017.0 11118.8 11571.9 11727.3 11739.2 11843.9 41204.6 41384.8 42098.6 42420.0 42499.8 45500.5 55452.4 56248.4 56379.7 57194.0 14595.4 14739.7 15011.3 15062.8 15063.5 15987.2 18292.5 18920.0 18935.1 18966.3 16685.2 16847.7 17396.6 17585.5 17706.7 16920.9 17005.8 17152.4 17355.9 17384.0 16836.8 17059.5 17142.8 17208.8 17309.7 8738.7 12047.5 12110.2 12339.9 12375.1 13323.1 13341.1 13348.3 13351.7 13386.6 00:45:48 INFO - PID 1651 | -------- Summary: end -------- 00:45:48 INFO - PID 1651 | 00:45:49 INFO - TEST-INFO | 1651: exit 0 00:45:49 INFO - TEST-OK | dromaeo_css | took 1143816ms 00:45:49 INFO - TEST-START | kraken 00:45:49 INFO - operating with platform_type : mac_ 00:45:49 INFO - Initialising browser for kraken test... 00:45:49 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground -profile /var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/tmp9GD0uF/profile 00:45:49 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground) 00:46:23 INFO - TEST-INFO | 1661: killed by SIGTERM 00:46:23 INFO - Browser initialized. 00:46:23 INFO - Running cycle 1/1 for kraken test... 00:46:23 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/tmp9GD0uF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:46:25 INFO - PID 1665 | 2017-05-31 00:46:25.572 plugin-container[1666:11493] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963f, name = 'com.apple.tsm.portname' 00:46:25 INFO - PID 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 00:46:25 INFO - PID 1665 | 2017-05-31 00:46:25.573 plugin-container[1666:11493] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 00:46:25 INFO - PID 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 00:46:25 INFO - PID 1665 | 2017-05-31 00:46:25.573 plugin-container[1666:11493] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 00:46:26 INFO - PID 1665 | 2017-05-31 00:46:26.278 plugin-container[1667:11543] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 00:46:26 INFO - PID 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 00:46:26 INFO - PID 1665 | 2017-05-31 00:46:26.279 plugin-container[1667:11543] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x480b, name = 'com.apple.CFPasteboardClient' 00:46:26 INFO - PID 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 00:46:26 INFO - PID 1665 | 2017-05-31 00:46:26.279 plugin-container[1667:11543] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 00:46:35 INFO - PID 1665 | RSS: Main: 261599232 00:46:35 INFO - PID 1665 | 00:46:38 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection) 00:46:39 INFO - PID 1665 | RSS: Main: 271380480 00:46:39 INFO - PID 1665 | 00:46:42 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-dft) 00:46:42 INFO - PID 1665 | RSS: Main: 280379392 00:46:42 INFO - PID 1665 | 00:46:46 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-fft) 00:46:46 INFO - PID 1665 | RSS: Main: 282091520 00:46:46 INFO - PID 1665 | 00:46:48 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator) 00:46:49 INFO - PID 1665 | RSS: Main: 283295744 00:46:49 INFO - PID 1665 | 00:46:51 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur) 00:46:52 INFO - PID 1665 | RSS: Main: 283783168 00:46:52 INFO - PID 1665 | 00:46:54 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom) 00:46:55 INFO - PID 1665 | RSS: Main: 289951744 00:46:55 INFO - PID 1665 | 00:46:58 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate) 00:46:59 INFO - PID 1665 | RSS: Main: 292634624 00:46:59 INFO - PID 1665 | 00:47:02 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial) 00:47:02 INFO - PID 1665 | RSS: Main: 292102144 00:47:02 INFO - PID 1665 | 00:47:04 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox) 00:47:05 INFO - PID 1665 | RSS: Main: 291459072 00:47:05 INFO - PID 1665 | 00:47:06 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes) 00:47:07 INFO - PID 1665 | RSS: Main: 291291136 00:47:07 INFO - PID 1665 | 00:47:09 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm) 00:47:10 INFO - PID 1665 | RSS: Main: 291729408 00:47:10 INFO - PID 1665 | 00:47:13 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 00:47:13 INFO - PID 1665 | RSS: Main: 291074048 00:47:13 INFO - PID 1665 | 00:47:17 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 00:47:17 INFO - PID 1665 | RSS: Main: 291090432 00:47:17 INFO - PID 1665 | 00:47:19 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49228/tests/kraken/driver.html?testName=ai-astar) 00:47:19 INFO - PID 1665 | RSS: Main: 292909056 00:47:19 INFO - PID 1665 | 00:47:19 INFO - PID 1665 | __start_tp_report 00:47:19 INFO - PID 1665 | _x_x_mozilla_page_load 00:47:19 INFO - PID 1665 | _x_x_mozilla_page_load_details 00:47:19 INFO - PID 1665 | |i|pagename|runs| 00:47:19 INFO - PID 1665 | |0;ai-astar;101;116;119;156;114;116;131;170;161;115 00:47:19 INFO - PID 1665 | |1;audio-beat-detection;168;147;147;168;111;109;96;154;128;131 00:47:19 INFO - PID 1665 | |2;audio-dft;197;214;209;211;217;261;201;199;197;197 00:47:19 INFO - PID 1665 | |3;audio-fft;79;61;62;88;97;87;77;60;76;59 00:47:19 INFO - PID 1665 | |4;audio-oscillator;90;77;117;101;101;68;87;72;71;95 00:47:19 INFO - PID 1665 | |5;imaging-gaussian-blur;91;91;91;91;91;92;91;91;90;92 00:47:19 INFO - PID 1665 | |6;imaging-darkroom;105;107;98;99;98;98;99;98;97;99 00:47:19 INFO - PID 1665 | |7;imaging-desaturate;102;96;93;91;92;91;94;91;90;91 00:47:19 INFO - PID 1665 | |8;json-parse-financial;51;51;55;66;79;77;79;78;77;64 00:47:19 INFO - PID 1665 | |9;json-stringify-tinderbox;56;64;57;57;60;62;56;57;60;61 00:47:19 INFO - PID 1665 | |10;stanford-crypto-aes;88;88;86;91;88;87;88;97;123;113 00:47:19 INFO - PID 1665 | |11;stanford-crypto-ccm;138;138;140;133;138;146;139;135;139;157 00:47:19 INFO - PID 1665 | |12;stanford-crypto-pbkdf2;232;198;186;188;184;186;188;184;194;164 00:47:19 INFO - PID 1665 | |13;stanford-crypto-sha256-iterative;68;71;64;69;65;69;67;71;94;90 00:47:19 INFO - PID 1665 | __end_tp_report 00:47:19 INFO - PID 1665 | __start_cc_report 00:47:19 INFO - PID 1665 | _x_x_mozilla_cycle_collect,1195 00:47:19 INFO - PID 1665 | __end_cc_report 00:47:19 INFO - PID 1665 | __startTimestamp1496216839693__endTimestamp 00:47:19 INFO - PID 1665 | 00:47:19 INFO - PID 1665 | ------- Summary: start ------- 00:47:19 INFO - PID 1665 | Number of tests: 14 00:47:19 INFO - PID 1665 | 00:47:19 INFO - PID 1665 | [#0] ai-astar Cycles:10 Average:129.90 Median:125.00 stddev:23.74 (19.0%) stddev-sans-first:22.76 00:47:19 INFO - PID 1665 | Values: 101.0 116.0 119.0 156.0 114.0 116.0 131.0 170.0 161.0 115.0 00:47:19 INFO - PID 1665 | 00:47:19 INFO - PID 1665 | [#1] audio-beat-detection Cycles:10 Average:135.90 Median:150.50 stddev:25.07 (16.7%) stddev-sans-first:23.75 00:47:19 INFO - PID 1665 | Values: 168.0 147.0 147.0 168.0 111.0 109.0 96.0 154.0 128.0 131.0 00:47:19 INFO - PID 1665 | 00:47:19 INFO - PID 1665 | [#2] audio-dft Cycles:10 Average:210.30 Median:210.00 stddev:19.37 (9.2%) stddev-sans-first:19.94 00:47:19 INFO - PID 1665 | Values: 197.0 214.0 209.0 211.0 217.0 261.0 201.0 199.0 197.0 197.0 00:47:19 INFO - PID 1665 | 00:47:19 INFO - PID 1665 | [#3] audio-fft Cycles:10 Average:74.60 Median:78.00 stddev:13.59 (17.4%) stddev-sans-first:14.32 00:47:19 INFO - PID 1665 | Values: 79.0 61.0 62.0 88.0 97.0 87.0 77.0 60.0 76.0 59.0 00:47:19 INFO - PID 1665 | 00:47:19 INFO - PID 1665 | [#4] audio-oscillator Cycles:10 Average:87.90 Median:74.50 stddev:15.98 (21.5%) stddev-sans-first:16.93 00:47:19 INFO - PID 1665 | Values: 90.0 77.0 117.0 101.0 101.0 68.0 87.0 72.0 71.0 95.0 00:47:19 INFO - PID 1665 | 00:47:19 INFO - PID 1665 | [#5] imaging-gaussian-blur Cycles:10 Average:91.10 Median:91.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 00:47:19 INFO - PID 1665 | Values: 91.0 91.0 91.0 91.0 91.0 92.0 91.0 91.0 90.0 92.0 00:47:19 INFO - PID 1665 | 00:47:19 INFO - PID 1665 | [#6] imaging-darkroom Cycles:10 Average:99.80 Median:98.00 stddev:3.36 (3.4%) stddev-sans-first:2.99 00:47:19 INFO - PID 1665 | Values: 105.0 107.0 98.0 99.0 98.0 98.0 99.0 98.0 97.0 99.0 00:47:19 INFO - PID 1665 | 00:47:19 INFO - PID 1665 | [#7] imaging-desaturate Cycles:10 Average:93.10 Median:91.50 stddev:3.60 (3.9%) stddev-sans-first:1.90 00:47:19 INFO - PID 1665 | Values: 102.0 96.0 93.0 91.0 92.0 91.0 94.0 91.0 90.0 91.0 00:47:19 INFO - PID 1665 | 00:47:19 INFO - PID 1665 | [#8] json-parse-financial Cycles:10 Average:67.70 Median:77.00 stddev:11.88 (15.4%) stddev-sans-first:10.96 00:47:19 INFO - PID 1665 | Values: 51.0 51.0 55.0 66.0 79.0 77.0 79.0 78.0 77.0 64.0 00:47:19 INFO - PID 1665 | 00:47:19 INFO - PID 1665 | [#9] json-stringify-tinderbox Cycles:10 Average:59.00 Median:60.00 stddev:2.79 (4.6%) stddev-sans-first:2.74 00:47:19 INFO - PID 1665 | Values: 56.0 64.0 57.0 57.0 60.0 62.0 56.0 57.0 60.0 61.0 00:47:19 INFO - PID 1665 | 00:47:19 INFO - PID 1665 | [#10] stanford-crypto-aes Cycles:10 Average:94.90 Median:88.00 stddev:12.78 (14.5%) stddev-sans-first:13.30 00:47:19 INFO - PID 1665 | Values: 88.0 88.0 86.0 91.0 88.0 87.0 88.0 97.0 123.0 113.0 00:47:19 INFO - PID 1665 | 00:47:19 INFO - PID 1665 | [#11] stanford-crypto-ccm Cycles:10 Average:140.30 Median:139.00 stddev:6.77 (4.9%) stddev-sans-first:7.13 00:47:19 INFO - PID 1665 | Values: 138.0 138.0 140.0 133.0 138.0 146.0 139.0 135.0 139.0 157.0 00:47:19 INFO - PID 1665 | 00:47:19 INFO - PID 1665 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:190.40 Median:188.00 stddev:17.10 (9.1%) stddev-sans-first:9.40 00:47:19 INFO - PID 1665 | Values: 232.0 198.0 186.0 188.0 184.0 186.0 188.0 184.0 194.0 164.0 00:47:19 INFO - PID 1665 | 00:47:19 INFO - PID 1665 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.80 Median:70.00 stddev:10.41 (14.9%) stddev-sans-first:10.90 00:47:19 INFO - PID 1665 | Values: 68.0 71.0 64.0 69.0 65.0 69.0 67.0 71.0 94.0 90.0 00:47:19 INFO - PID 1665 | -------- Summary: end -------- 00:47:19 INFO - PID 1665 | 00:47:20 INFO - TEST-INFO | 1665: exit 0 00:47:20 INFO - TEST-OK | kraken | took 91037ms 00:47:20 INFO - SUITE-END | took 1234s 00:47:20 INFO - Completed test suite (00:20:35) 00:47:20 INFO - javascript benchmark 00:47:20 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8226.765475386113, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3618.2582570573027, 3640.3629364861144, 3661.157933675256, 3663.9816800916, 3677.52192215012, 5315.946840531594, 6122.5811271884995, 6226.564140510166, 6272.811815645532, 6295.276043255027, 6175.66033868137, 6232.906506402396, 6236.532260080489, 6301.527385446098, 6320.0, 5139.997700448414, 5193.246979188016, 5475.945240547603, 5476.507114359707, 5499.340079190482, 4845.733484658343, 5622.072358060915, 5629.690367029812, 5633.295838020247, 5651.40660230676, 5382.1926710993275, 5436.021516127095, 5488.4785945335125, 5502.532284755801, 5554.8611284718, 5511.0, 5521.723913804334, 5541.0580201365865, 5541.33503979521, 5556.0, 5457.699826509541, 5476.7535460903955, 5482.0, 5516.558675305966, 5545.750441230114, 6188.752449901998, 6218.28489723683, 6299.8740025199695, 6306.306306306303, 6308.621482711052, 6540.640264785435, 6670.866582668368, 6738.663066846638, 6742.123523941906, 6766.25571187174, 6878.105846240007, 6893.931060689379, 6912.688928998157, 6915.585064896122, 6921.377076063179, 6382.106505089291, 6388.904166437507, 6389.393007664265, 6395.840103997437, 6397.0724244984685, 6714.664266786739, 6783.694733736994, 6907.240203577602, 6967.8606427871155, 6998.755043573552, 6320.051992201114, 6452.93526568111, 6574.474042076622, 6574.868502629921, 6648.800535983928, 5733.684647344353, 6600.603963762093, 6755.831104222434, 6804.353586409283, 6804.863902721918, 6695.46436285096, 6699.363560461748, 6700.128983232149, 6715.966420167868, 6743.831404214836, 6497.090407342976, 6586.506012049115, 6647.235567909655, 6687.832804179837, 6731.057651928734, 5393.13709806437, 5394.78420863161, 5413.350397952192, 5416.404195538487, 5439.0, 4811.446683631357, 4826.420829500483, 4832.613390928717, 4834.685745426536, 4837.22604383304, 6301.747930082837, 6324.810255692337, 6331.68341582919, 6342.429181373606, 6369.108324834527, 6704.329567043256, 6739.359760822714, 6745.730170793114, 6755.1556055493065, 6759.594424334556, 5318.973405133027, 6359.777407790705, 6394.808155755335, 6399.872002559922, 6415.390537898892, 4906.31311616374, 5357.732113394315, 5858.06270996647, 5886.381929897385, 5890.14592884038, 6259.718312675849, 6471.255805582418, 6918.308169183142, 6998.580085194904, 7030.648467576703, 6776.534190964362, 6849.48628852848, 6864.141982252218, 6868.141482314711, 6871.759488417882, 6763.222229443677, 6936.173823630596, 7009.404200643003, 7012.509124361246, 7037.612931288828, 6547.770828020996, 6574.934250657432, 6602.735890564516, 6665.533412661067, 6680.966595166993, 6216.937830621636, 6412.839679008062, 6855.931440685728, 6873.243943166148, 6884.65576721172, 6369.20384951881, 6410.4871610272, 6525.543211975116, 6547.312532184243, 6613.008048792527, 6457.031445283245, 6498.74005039812, 6505.772297969548, 6511.674416279262, 6516.837079073061, 6268.780592679234, 6389.744410223539, 6390.392912673288, 6401.423871851555, 6415.262244841903, 5108.66793658411, 5196.844094677166, 5421.078416669096, 6374.426301632875, 6422.967885160544, 6363.904541431974, 6366.267879193952, 6407.519436042408, 6414.61512309263, 6439.903401448888], "value": 6108.977266822851, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [776.7126163319573, 813.7314686153569, 838.3209600223819, 1253.8119282107689, 1316.7300703355813, 38293.04267393317, 38883.805580972126, 38972.610273897255, 39025.02437439062, 39068.80465597672, 35748.0, 37221.0, 39386.21227575447, 39884.60115398845, 39903.800480997554, 7816.609169541514, 7982.0820605630515, 8213.794655133639, 8383.496990180578, 8409.159084091603, 8352.665893364257, 8435.95782021089, 8461.53461559614, 8463.0, 8472.19514146155, 8414.116517765608, 8483.830323393528, 8485.745427637181, 8496.107908669594, 8510.617022233984, 7448.9627551861895, 8416.957915210416, 8444.957775211085, 8449.45078569894, 8469.830603387898, 7121.0, 8215.876761848576, 8248.092709801916, 8261.0, 8262.752117436487, 36420.0, 36706.816465917764, 36724.81637591821, 36751.4487282691, 36772.0, 3556.324298383299, 3566.5363502744476, 3582.158192824684, 3587.910302242439, 3600.009997250751, 30102.69897301043, 30226.24434389158, 30377.848110759303, 30545.389092218033, 30554.694453055406, 2764.958525622097, 2814.5918841768034, 3046.0709483607493, 3083.290843106076, 3091.4280858041334, 237848.0, 243253.0, 265063.6746816273, 272427.0, 272805.0, 47122.293165601855, 47340.05319893583, 47379.526204737514, 47432.814179645786, 47489.05021899612, 49107.0, 49185.75407123013, 49205.5714985653, 54547.45452545503, 55329.446705533235, 14592.562223133324, 14720.337584808709, 14768.261586920611, 14784.11295322284, 14806.185659788815, 11716.297022178696, 11800.822987655192, 11804.46879890407, 11806.0, 11808.76382472363, 14013.229272389912, 14101.929490352482, 14175.149491030365, 14207.147571145557, 14319.642008949651, 6069.0, 6101.694915254308, 6130.417610327012, 6141.0, 6143.784967526115, 909.2952961454926, 993.4883534979491, 1009.2128140050772, 1024.1243736605095, 1033.7984093101923, 24559.508809823703, 24766.38084047913, 24872.875635621705, 24891.62662560063, 24913.0, 1133.1218305812897, 1134.9148813838995, 1165.7726743285148, 1168.4216312924987, 1168.4975460552043, 12246.632601021984, 12742.36288185587, 12754.23474591509, 12790.360481975864, 12797.936010320074, 327108.0, 328014.0, 329003.0, 329540.352298237, 329699.0, 54215.0, 54488.72755636196, 54582.90834183294, 54597.45402546003, 54652.726736366065, 1371.4719832864419, 1409.6194027612487, 1508.7284288827898, 1522.7487464568221, 1553.425232663899, 24223.878880605484, 24267.757322427256, 24457.75542244555, 24483.020679172634, 24493.0, 37612.811935940146, 37747.81126094462, 37891.0, 37914.8104259477, 37921.62078379181, 218257.0, 237725.0, 245638.0872382578, 263677.0, 264733.0, 28094.85952570224, 35293.764718234735, 35643.64356356403, 36241.818790905876, 36300.0, 43686.56313436952, 44307.8923026914, 45822.0, 45889.54110458853, 45908.770456147504, 28491.28771780639, 30452.934147305623, 30921.0, 31259.0, 31823.840880795447, 29305.560416594195, 30396.0, 30508.389832203677, 31214.21964450907, 31375.215619608778, 37554.812225938695, 37575.060623484635, 37663.62336376711, 37792.811035944644, 37819.24361512809, 29301.0, 29392.0, 30999.69000309968, 31416.057518274487, 31915.202119947186, 20373.898130509253, 21720.67418988685, 24426.90078946416, 24445.51108977846, 24906.501869962136], "value": 17480.623974545033, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [362.87843572403244, 412.1077866419204, 420.495405513384, 433.3781024230231, 451.6612540594554, 20994.58010839784, 21692.13231470741, 21750.02124904379, 22895.19866804662, 23882.164124255654, 25286.2414127576, 25555.0, 25561.97752089919, 25596.976120955187, 25636.10273640423, 5983.700814959257, 7201.783946481614, 7242.818929526751, 7268.672909719049, 7268.781936541912, 6829.112215412014, 7100.751473698421, 7138.75014374497, 7179.3538581527655, 7202.8559428811395, 7166.104236970379, 7169.103862017248, 7178.102737157856, 7196.388306993912, 7196.64016799158, 7104.857902841914, 7159.534630249043, 7169.390601798827, 7174.461915356369, 7176.712931482734, 6945.0, 7010.579365238051, 7024.719011239545, 7032.542884712529, 7046.6476676165985, 30222.54666180009, 30406.087817365515, 30611.928582499728, 31582.36835263305, 31821.363572728646, 2275.3174047785596, 2339.6139636959883, 2344.788968992799, 2345.0854166874933, 2350.952980940389, 20126.49683757903, 20432.182712691476, 20444.488887777923, 20454.693179602316, 20462.386128416172, 2247.8089362404216, 2256.9505180091173, 2292.816574674022, 2305.965410518844, 2316.6756654068445, 154161.45838541584, 167883.0, 173444.13277933656, 181262.0, 186023.0, 25915.0, 26046.479070418485, 26421.867890660425, 26729.732702673115, 27960.0, 44387.77806111013, 44423.555764442586, 44428.55571444244, 44440.55559444364, 44462.33306500405, 11443.539638977494, 11491.54033838637, 11576.131790115774, 11648.767024659459, 11652.41737913101, 9168.083191680911, 9185.540722963959, 9193.770155746026, 9200.401973871676, 9222.76943076415, 12314.938425307817, 12400.0, 12446.81329780054, 12471.189372690927, 12472.750544989049, 4785.832495862628, 4895.755212239445, 4914.754262286871, 4938.75306234694, 4943.604511639061, 957.7701351675687, 978.5841017567506, 979.9265055120894, 998.7503124218945, 1007.5012868630075, 20850.791492084885, 21264.681029784566, 21311.041003154896, 21426.464338391354, 21561.814100224325, 953.4422362917771, 1005.5676059294427, 1018.1549314069305, 1037.906588407047, 1042.9791404172026, 9882.308238423386, 10640.308379955433, 10935.070519005814, 11024.614138505114, 11027.503762330714, 451282.0, 510352.0, 511352.0, 521642.0, 521739.0, 151512.0, 154242.22878885758, 154439.0, 155118.22440887723, 155822.0, 999.7000899730052, 1134.7957367673898, 1165.6794381545144, 1204.084895479442, 1211.3821950805152, 22662.773372266067, 22988.965496553028, 23288.41778955472, 23347.9493422793, 23701.881490592437, 50139.74930125326, 68189.0, 68511.65744171248, 69571.0, 69836.60326793334, 96394.0, 96750.0, 96863.0, 96937.51531242298, 98002.01997979928, 14027.859721402656, 15364.462243821412, 15877.52367428979, 16125.0, 16180.838191617933, 14776.852231477978, 17923.73114403259, 18173.182206800462, 18259.08704564793, 18273.99493027896, 14737.263136842901, 14842.332095055965, 15060.472883449027, 15126.773098403313, 15178.696426071638, 12700.36498175106, 13195.67010824737, 13244.20534767917, 13246.072774905664, 13420.188274582806, 14672.853271467575, 15050.849491504945, 15126.941114121906, 15296.082235065931, 15313.846861531241, 15713.371465141267, 15882.761758573399, 15919.363225471314, 15926.68146637084, 15956.760648590513, 14102.294885255902, 14857.034292770935, 14869.330880110205, 14950.0, 14976.101433914], "value": 12933.18697024536, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1281.3465132782285, 1298.5844529750477, 1464.61187785237, 1471.735087684217, 1489.6871656952044, 11399.829002564968, 11570.0, 11841.7631647367, 11905.642830715082, 12319.384030798468, 11815.113866460008, 11883.22759020663, 11938.343391113485, 12049.277043377382, 12062.2762634242, 2148.215901196068, 2508.448141408892, 2543.36415896026, 2545.770880620753, 2555.2589748972778, 2481.081999660128, 2495.800335973118, 2503.5869081601513, 2504.887280072401, 2505.3736565858535, 2452.1172377943926, 2482.640017197505, 2494.837835540693, 2495.4884248728968, 2495.663085483464, 2148.732247973703, 2435.683361163055, 2462.495188486356, 2466.2847774145475, 2469.3085935937966, 2418.8427752196053, 2457.8156638252026, 2461.8276720629565, 2530.569803133453, 2545.8217924745277, 11700.882991170065, 11773.587924422689, 11796.705082372924, 11808.0, 11817.94091029548, 6431.517636177306, 6605.306442823482, 6669.833254168636, 6675.599464032173, 6692.364225398579, 4042.312806822847, 4194.412782210493, 4255.340422234559, 4274.316109422509, 4285.35719642053, 1168.2581560708927, 1207.5471698113208, 1208.6011616166643, 1220.530095913071, 1234.9938250308692, 14943.252837358197, 15385.999999999887, 15908.76136857948, 15982.00224971867, 15995.600109997227, 5448.727563621803, 5450.645708029045, 5579.358373786985, 5605.719714014364, 5623.100303951348, 11718.531258749554, 11747.412629368497, 11756.1182911282, 11767.352795596324, 11769.941150294193, 5780.971095144581, 6089.512838972872, 6313.558050936482, 6356.523260755462, 6361.713722882481, 5393.7033463159905, 5906.232189815297, 5963.016102343152, 6015.939840601625, 6057.666828324484, 3661.0, 4150.8339666413, 4187.748735075906, 4228.048689045002, 4292.806823692878, 1236.9319687417278, 1238.120934136755, 1239.3555351217317, 1250.0437165568167, 1256.7423678145958, 767.3822572828926, 782.6673663692908, 802.6187560908522, 803.1005274093053, 808.9878651820227, 6543.345665433419, 6634.137562116894, 6800.965995169992, 6899.862002760017, 6912.066870972455, 788.286600626434, 807.8868958345836, 889.768660148366, 955.044955044955, 983.6704703924265, 3739.6073412291316, 3788.204477059793, 3843.6348546888003, 3860.2279544091293, 3870.4000879863665, 13909.095908765898, 14711.485098021518, 14727.779083313759, 15009.699806003819, 15152.31814568347, 14792.778108328384, 14931.25343732809, 14992.925035374754, 15173.0, 15280.083195008336, 8587.742367728979, 8629.568521573896, 8655.697050603325, 8748.737537873874, 8771.473711577326, 5903.970480147572, 6357.8728425432155, 6956.2000369958105, 7010.298970102949, 7018.543794653331, 11106.777864442503, 12843.871561284268, 13351.99999999961, 13525.458981640624, 13554.0, 13724.107932984323, 13840.4463821446, 13889.513867014606, 13918.930405347908, 13979.790303145257, 8357.874631880404, 8510.361722870686, 8520.829583408173, 8522.36082293818, 8534.487930724177, 10459.84310235332, 10509.2118091145, 10597.841032384365, 10625.840612390666, 10668.98644628759, 9069.138431848964, 9091.499967501852, 9138.954305228697, 9148.542572871464, 9270.304727145356, 8880.378373513791, 8900.821983560163, 8953.418027828171, 8955.328350373618, 9002.234810040958, 8068.435209535276, 8233.176682331958, 8236.341092712688, 8252.381071419548, 8258.83482330338, 5190.844274671766, 5697.23087383198, 5942.524598032061, 6069.787557435468, 6149.7847575334645, 5628.212050312877, 5655.981923253854, 6060.545459090674, 6202.875942481035, 6265.8120256392385], "value": 5296.801620227843, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.4923599320882, 391.23318296139564, 392.90570263136846, 393.78735482839267, 404.1996846244435, 5594.83215503535, 6327.462165715908, 6401.903971440426, 6424.2290925089, 6556.0, 5771.278590176228, 6012.8496787580225, 6238.251409830816, 6418.454431373327, 6425.453836423898, 4451.933221001679, 4480.305552639346, 4675.4155730533685, 4677.766111694419, 4683.742394168336, 3819.618038196186, 3928.466139340124, 4655.4878963314, 4669.276262179351, 4676.859694209179, 4561.703489273204, 4605.4933957264675, 4612.815487380501, 4618.838340658078, 4621.907561848761, 4036.394540818886, 4623.445186577632, 4637.304404339342, 4644.256918892977, 4681.46163191234, 3743.4838890249493, 3962.0689138052535, 3964.5242570891387, 3965.405189221611, 3966.4446977423186, 5554.361248456439, 5957.106434034886, 6178.258608966953, 6239.625622462667, 6361.236651601791, 5528.226048353234, 5530.087535556629, 5548.944510554883, 5549.225387306346, 5557.416471270499, 5669.404712505168, 5693.459121383504, 6897.862042759167, 6899.068260886909, 6947.826304342381, 3740.6259374062374, 4464.955350446487, 4479.76011994003, 4485.9102817943785, 4526.0, 5178.203769964177, 6133.987891997815, 6205.5345849061505, 6786.0, 7087.2990482284395, 3636.5272514572935, 4218.599233072853, 4477.19410506112, 4534.523874993079, 4570.154521413549, 3569.375359312162, 4250.66868936881, 4608.308753686973, 4661.627069834473, 4714.858554243378, 4932.358793356841, 5093.898122037613, 5337.0, 5450.4549545045975, 5459.945400546023, 5343.412224655285, 5822.912656310159, 6779.423748981293, 6858.348456896687, 6886.449084073262, 1479.8594133557294, 1498.7077519883735, 1563.140272849928, 1578.2380833541304, 1581.343742346936, 990.7126933189294, 1007.6422869881234, 1024.912882404989, 1026.343140390151, 1034.1984961654778, 6196.907046394308, 6365.331640177715, 6477.449416799531, 6484.773032943825, 6611.371919667624, 6085.292045039915, 6749.156355455568, 6876.693428248616, 7141.642917854087, 7181.353678168885, 6417.582857114273, 6422.807315780534, 6431.13179720741, 6432.324605916311, 6488.15653964991, 3273.3289675616443, 3284.4088064148204, 3617.7467577269863, 3703.185299234164, 3735.9252814943547, 6468.353164683588, 6745.0, 7019.5788252705, 7307.159676637149, 7328.816779580447, 6700.463962882957, 7127.75052873147, 7294.70821167158, 7321.853562928818, 7351.522151060164, 5980.940190598125, 7363.729037158435, 7399.260073992557, 7402.666879990414, 7405.185429602631, 6200.968995154995, 6581.769638062645, 7105.46708996817, 7120.113170009848, 7219.530730502501, 6134.723937422916, 6616.966915165393, 7037.683304251424, 7054.682539285847, 7074.221835598183, 5690.94717477268, 5963.671998040149, 7134.821629459305, 7259.419246460376, 7312.451566132455, 4492.168948744493, 4529.614982726505, 4543.818607162228, 4560.931586026146, 4568.246239370466, 6151.446369826737, 6270.811875643738, 7160.534565253241, 7163.677634506564, 7186.137663480206, 6279.874402512016, 6638.933610663831, 6684.0, 6831.69257383409, 6869.484788640972, 6803.897941530782, 6904.102466679299, 6910.20532638753, 6912.861742765016, 6920.653967301715, 5593.860153496195, 6266.310705822447, 6534.738610455529, 6707.262201157966, 6738.629375384205, 6841.828954276183, 6849.020676005954, 6882.793516194522, 6911.75808846688, 6961.0, 5943.554233432596, 6178.66017369049, 6611.966940165268, 6691.197056353269, 6700.497462690415], "value": 4883.28666365586, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [812.8577498937684, 833.1377024779354, 839.2907992746132, 848.2620120495168, 860.9612567434465, 14588.562343129723, 15090.924545377286, 16851.915740421297, 17283.395081172162, 17284.39504617337, 17636.82363176368, 17853.732194017066, 18256.08719564023, 18299.17653705583, 18305.90847045763, 950.1496160935958, 975.517119026083, 981.0777868803337, 981.2101258486915, 981.249344251648, 980.0787259975635, 994.4679596415918, 995.9004099590055, 996.3772642098688, 996.7707427291728, 887.6493784954971, 1036.1451802263164, 1043.6138628707351, 1044.1177205261542, 1045.1534257251612, 1032.9018743219383, 1034.4414016431117, 1035.1615191694752, 1035.6996471023356, 1036.471399586209, 754.2985023927745, 755.5882044285877, 758.0410780362845, 759.7530802489213, 760.8896709977022, 17582.560435989075, 17768.200430980636, 17808.46574602764, 17900.10499475034, 17921.37275195375, 17482.0, 17693.0, 18063.367782127694, 18081.54796130094, 18103.72844407335, 14721.263936803118, 14873.405063797436, 15014.24928753558, 15036.17301048442, 15042.398304067825, 3543.7696549724187, 3599.0822340303316, 3614.0603443104983, 3622.981885090584, 3669.321175582526, 114811.0, 144678.2766086163, 152294.0, 153438.23280883525, 153791.0, 13536.05247632676, 13623.250721210428, 13775.0, 13972.0, 13977.3710183042, 26302.86848565745, 26761.37243442748, 26767.732322676526, 26911.05811296595, 27047.188584342108, 6737.730490780314, 6932.930670693228, 7622.58075805836, 7699.730509432143, 7988.081370642334, 3923.6664883484655, 4010.438538604598, 4016.8995775105855, 4019.4774679291504, 4022.6781857451333, 4144.896377590524, 4196.475440569929, 4240.1519696060295, 4242.5757424257945, 4310.008697999478, 818.8116733151409, 911.7948461596088, 926.4395041000232, 933.883264591926, 935.8268720286769, 616.3897741236144, 616.5529990756755, 646.4795839349368, 656.7832615237056, 674.6458109492457, 10111.949440252898, 11408.714782130348, 11413.029892459068, 12472.501099956082, 12540.686482838002, 580.9331926828385, 638.2628064585411, 646.6443456099126, 656.1896058367912, 676.3777324861139, 5597.972010139923, 5646.096624540054, 5804.158397032406, 5865.501432378211, 5901.262342207224, 125541.37229313795, 129590.70409295787, 147095.0, 147492.26253868663, 149857.25071374574, 123748.0, 123984.0, 124933.0, 126601.36699316444, 128271.58642068045, 112864.0, 113652.0, 114644.0, 116783.0, 118017.0, 11118.777624447628, 11571.942140289244, 11727.296362218294, 11739.178257522233, 11843.940780296043, 41204.58795412127, 41384.793076034424, 42098.57901421069, 42420.0, 42499.787501062296, 45500.54499454963, 55452.44547554473, 56248.43751562432, 56379.71810141087, 57194.0, 14595.416183352972, 14739.705205896036, 15011.32449039774, 15062.774058389352, 15063.47277845314, 15987.200639967723, 18292.542686432946, 18920.0, 18935.147918343984, 18966.336178233694, 16685.165741712623, 16847.747283791, 17396.565085872953, 17585.472435826945, 17706.734398984285, 16920.915395422944, 17005.829941700424, 17152.399666011628, 17355.913220433817, 17383.956962582284, 16836.831631683526, 17059.48821535356, 17142.82857171462, 17208.827911721222, 17309.65380692404, 8738.737837864874, 12047.51809927593, 12110.152289340014, 12339.93830030844, 12375.071869609636, 13323.13399629021, 13341.066125371131, 13348.332583370986, 13351.732965340832, 13386.598402047954], "value": 8677.288503907734, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1547.7000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 116.0, 119.0, 156.0, 114.0, 116.0, 131.0, 170.0, 161.0, 115.0], "value": 129.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 147.0, 147.0, 168.0, 111.0, 109.0, 96.0, 154.0, 128.0, 131.0], "value": 135.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 214.0, 209.0, 211.0, 217.0, 261.0, 201.0, 199.0, 197.0, 197.0], "value": 210.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 61.0, 62.0, 88.0, 97.0, 87.0, 77.0, 60.0, 76.0, 59.0], "value": 74.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 77.0, 117.0, 101.0, 101.0, 68.0, 87.0, 72.0, 71.0, 95.0], "value": 87.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 91.0, 91.0, 91.0, 91.0, 92.0, 91.0, 91.0, 90.0, 92.0], "value": 91.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 107.0, 98.0, 99.0, 98.0, 98.0, 99.0, 98.0, 97.0, 99.0], "value": 99.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 96.0, 93.0, 91.0, 92.0, 91.0, 94.0, 91.0, 90.0, 91.0], "value": 93.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 51.0, 55.0, 66.0, 79.0, 77.0, 79.0, 78.0, 77.0, 64.0], "value": 67.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 64.0, 57.0, 57.0, 60.0, 62.0, 56.0, 57.0, 60.0, 61.0], "value": 59.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 86.0, 91.0, 88.0, 87.0, 88.0, 97.0, 123.0, 113.0], "value": 94.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 138.0, 140.0, 133.0, 138.0, 146.0, 139.0, 135.0, 139.0, 157.0], "value": 140.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [232.0, 198.0, 186.0, 188.0, 184.0, 186.0, 188.0, 184.0, 194.0, 164.0], "value": 190.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 71.0, 64.0, 69.0, 65.0, 69.0, 67.0, 71.0, 94.0, 90.0], "value": 72.8, "unit": "score"}]}]} 00:47:20 INFO - Return code: 0 00:47:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 00:47:20 INFO - # TBPL SUCCESS # 00:47:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 00:47:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 00:47:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 00:47:21 INFO - 2017-05-31 00:47:21.008 screenresolution[1673:11928] starting screenresolution argv=screenresolution get 00:47:21 INFO - 2017-05-31 00:47:21.011 screenresolution[1673:11928] Display 0: 1600x1200x32@60 00:47:21 INFO - 2017-05-31 00:47:21.017 screenresolution[1674:11931] starting screenresolution argv=screenresolution list 00:47:21 INFO - Available Modes on Display 0 00:47:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 00:47:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 00:47:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 00:47:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 00:47:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 00:47:21 INFO - Intel Iris: 00:47:21 INFO - Chipset Model: Intel Iris 00:47:21 INFO - Type: GPU 00:47:21 INFO - Bus: Built-In 00:47:21 INFO - VRAM (Dynamic, Max): 1536 MB 00:47:21 INFO - Vendor: Intel (0x8086) 00:47:21 INFO - Device ID: 0x0a2e 00:47:21 INFO - Revision ID: 0x0009 00:47:21 INFO - Displays: 00:47:21 INFO - Display: 00:47:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 00:47:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 00:47:21 INFO - Display Serial Number: 1600x1200 60 00:47:21 INFO - Main Display: Yes 00:47:21 INFO - Mirror: Off 00:47:21 INFO - Online: Yes 00:47:21 INFO - Rotation: Supported 00:47:21 INFO - Return code: 0 00:47:21 INFO - Running post-action listener: _resource_record_post_action 00:47:21 INFO - [mozharness: 2017-05-31 07:47:21.259156Z] Finished run-tests step (success) 00:47:21 INFO - Running post-run listener: _resource_record_post_run 00:47:21 INFO - Total resource usage - Wall time: 1257s; CPU: 53.0%; Read bytes: 35951616; Write bytes: 571948544; Read time: 344; Write time: 2079 00:47:21 INFO - TinderboxPrint: CPU usage
53.3% 00:47:21 INFO - TinderboxPrint: I/O read bytes / time
35,951,616 / 344 00:47:21 INFO - TinderboxPrint: I/O write bytes / time
571,948,544 / 2,079 00:47:21 INFO - TinderboxPrint: CPU idle
2,350.4 (46.7%) 00:47:21 INFO - TinderboxPrint: CPU system
1,014.7 (20.2%) 00:47:21 INFO - TinderboxPrint: CPU user
1,664.7 (33.1%) 00:47:21 INFO - TinderboxPrint: Swap in / out
278,278,144 / 0 00:47:21 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 247216640; Write bytes: 249370624; Read time: 18080; Write time: 524 00:47:21 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 00:47:21 INFO - run-tests - Wall time: 1236s; CPU: 53.0%; Read bytes: 30802944; Write bytes: 321222144; Read time: 306; Write time: 1550 00:47:21 INFO - Running post-run listener: _upload_blobber_files 00:47:21 INFO - Blob upload gear active. 00:47:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 00:47:21 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 00:47:21 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'] 00:47:21 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 00:47:22 INFO - (blobuploader) - INFO - Open directory for files ... 00:47:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 00:47:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:47:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:47:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 00:47:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:47:22 INFO - (blobuploader) - INFO - Done attempting. 00:47:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 00:47:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:47:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:47:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 00:47:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:47:23 INFO - (blobuploader) - INFO - Done attempting. 00:47:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 00:47:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:47:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:47:24 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 00:47:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:47:24 INFO - (blobuploader) - INFO - Done attempting. 00:47:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 00:47:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:47:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:47:27 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 00:47:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:47:27 INFO - (blobuploader) - INFO - Done attempting. 00:47:27 INFO - (blobuploader) - INFO - Iteration through files over. 00:47:27 INFO - Return code: 0 00:47:27 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 00:47:27 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 00:47:27 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c1c2f6839f3ac5933fb03d88620c80a91705c268ebbd2f61e1418fb54d8d24ae2c7125e52c4279f8ed8c95fef37f2bdb6e57cb33e7915165db3833631a94384e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/45d113d1193e9426d2b654fa80cbff8e26afb7c3caf74c09b30687003a73ac0ffda96cf194c7eb6c69363f013f45e02c2c08af32500ab779f309a18bd2c8d572", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/05409d4e343e2210cc2d251e7a7c8bd42cce3a51c4150df1787cf30a8b7c7b3c741ba5453db04b8d18668feadbb57d52ca75000e9c9d8053bbadd12e437f32a9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/856440d24b8b275abfd3d4d1698b985527fa6670d198f541b3fc1a8abee4cf57c342af51b54bf8de81370e5d4ae1d324a2a1e2a4bfcf747a2251d889327cbd1c"} 00:47:27 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 00:47:27 INFO - Writing to file /builds/slave/test/properties/blobber_files 00:47:27 INFO - Contents: 00:47:27 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c1c2f6839f3ac5933fb03d88620c80a91705c268ebbd2f61e1418fb54d8d24ae2c7125e52c4279f8ed8c95fef37f2bdb6e57cb33e7915165db3833631a94384e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/45d113d1193e9426d2b654fa80cbff8e26afb7c3caf74c09b30687003a73ac0ffda96cf194c7eb6c69363f013f45e02c2c08af32500ab779f309a18bd2c8d572", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/05409d4e343e2210cc2d251e7a7c8bd42cce3a51c4150df1787cf30a8b7c7b3c741ba5453db04b8d18668feadbb57d52ca75000e9c9d8053bbadd12e437f32a9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/856440d24b8b275abfd3d4d1698b985527fa6670d198f541b3fc1a8abee4cf57c342af51b54bf8de81370e5d4ae1d324a2a1e2a4bfcf747a2251d889327cbd1c"} 00:47:27 INFO - Running post-run listener: copy_logs_to_upload_dir 00:47:27 INFO - Copying logs to upload dir... 00:47:27 INFO - mkdir: /builds/slave/test/build/upload/logs 00:47:27 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1341.047964 ========= master_lag: 0.09 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 21 secs) (at 2017-05-31 00:47:27.227348) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-31 00:47:27.230451) ========= 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.pJ9hemjSLp/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.HFvv0RidAn/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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/c1c2f6839f3ac5933fb03d88620c80a91705c268ebbd2f61e1418fb54d8d24ae2c7125e52c4279f8ed8c95fef37f2bdb6e57cb33e7915165db3833631a94384e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/45d113d1193e9426d2b654fa80cbff8e26afb7c3caf74c09b30687003a73ac0ffda96cf194c7eb6c69363f013f45e02c2c08af32500ab779f309a18bd2c8d572", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/05409d4e343e2210cc2d251e7a7c8bd42cce3a51c4150df1787cf30a8b7c7b3c741ba5453db04b8d18668feadbb57d52ca75000e9c9d8053bbadd12e437f32a9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/856440d24b8b275abfd3d4d1698b985527fa6670d198f541b3fc1a8abee4cf57c342af51b54bf8de81370e5d4ae1d324a2a1e2a4bfcf747a2251d889327cbd1c"} build_url:https://queue.taskcluster.net/v1/task/IkpI-_XtS1i8ZvYndlIGNw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011217 build_url: 'https://queue.taskcluster.net/v1/task/IkpI-_XtS1i8ZvYndlIGNw/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/c1c2f6839f3ac5933fb03d88620c80a91705c268ebbd2f61e1418fb54d8d24ae2c7125e52c4279f8ed8c95fef37f2bdb6e57cb33e7915165db3833631a94384e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/45d113d1193e9426d2b654fa80cbff8e26afb7c3caf74c09b30687003a73ac0ffda96cf194c7eb6c69363f013f45e02c2c08af32500ab779f309a18bd2c8d572", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/05409d4e343e2210cc2d251e7a7c8bd42cce3a51c4150df1787cf30a8b7c7b3c741ba5453db04b8d18668feadbb57d52ca75000e9c9d8053bbadd12e437f32a9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/856440d24b8b275abfd3d4d1698b985527fa6670d198f541b3fc1a8abee4cf57c342af51b54bf8de81370e5d4ae1d324a2a1e2a4bfcf747a2251d889327cbd1c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-31 00:47:27.264671) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 00:47:27.265202) ========= 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.pJ9hemjSLp/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.HFvv0RidAn/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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.005198 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 00:47:27.313870) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-31 00:47:27.314192) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-31 00:47:27.314507) ========= ========= Total master_lag: 2.69 =========