builder: autoland_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0081 starttime: 1476091290.88 results: success (0) buildid: 20161009232216 builduid: e9d6ba2a934c470db386bd8b6f3644a9 revision: c44ee9cb3ba869e50c59dab44afbe757ff1b45d1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-10 02:21:30.878216) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-10 02:21:30.878708) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-10 02:21:30.878987) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-10 02:21:30.907156) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 02:21:30.907437) ========= 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.KlCpefsbxK/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.mznUvlfXJn/Listeners TMPDIR=/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 --2016-10-10 02:21:30-- 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: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 13.9M=0.001s 2016-10-10 02:21:31 (13.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.186036 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 02:21:31.115042) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 02:21:31.115368) ========= 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.KlCpefsbxK/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.mznUvlfXJn/Listeners TMPDIR=/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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.020957 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 02:21:31.163856) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-10 02:21:31.164169) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev c44ee9cb3ba869e50c59dab44afbe757ff1b45d1 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev c44ee9cb3ba869e50c59dab44afbe757ff1b45d1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.KlCpefsbxK/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.mznUvlfXJn/Listeners TMPDIR=/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 2016-10-10 02:21:31,242 truncating revision to first 12 chars 2016-10-10 02:21:31,243 Setting DEBUG logging. 2016-10-10 02:21:31,243 attempt 1/10 2016-10-10 02:21:31,243 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/c44ee9cb3ba8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-10 02:21:32,023 unpacking tar archive at: autoland-c44ee9cb3ba8/testing/mozharness/ program finished with exit code 0 elapsedTime=1.190995 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-10 02:21:32.377205) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-10 02:21:32.377548) ========= script_repo_revision: c44ee9cb3ba869e50c59dab44afbe757ff1b45d1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-10 02:21:32.377969) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-10 02:21:32.378256) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-10 02:21:32.396318) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-10-10 02:21:32.396605) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.KlCpefsbxK/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.mznUvlfXJn/Listeners TMPDIR=/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 02:21:32 INFO - MultiFileLogger online at 20161010 02:21:32 in /builds/slave/test 02:21:32 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 02:21:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:21:32 INFO - {'append_to_log': False, 02:21:32 INFO - 'base_work_dir': '/builds/slave/test', 02:21:32 INFO - 'blob_upload_branch': 'Autoland', 02:21:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:21:32 INFO - 'branch': 'Autoland', 02:21:32 INFO - 'buildbot_json_path': 'buildprops.json', 02:21:32 INFO - 'config_files': ('talos/mac_config.py',), 02:21:32 INFO - 'default_actions': ('clobber', 02:21:32 INFO - 'read-buildbot-config', 02:21:32 INFO - 'download-and-extract', 02:21:32 INFO - 'populate-webroot', 02:21:32 INFO - 'create-virtualenv', 02:21:32 INFO - 'install', 02:21:32 INFO - 'run-tests'), 02:21:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:21:32 INFO - 'download_minidump_stackwalk': True, 02:21:32 INFO - 'download_symbols': 'ondemand', 02:21:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:21:32 INFO - 'tooltool.py': '/tools/tooltool.py', 02:21:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:21:32 INFO - '/tools/misc-python/virtualenv.py')}, 02:21:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:21:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:21:32 INFO - 'installer_path': 'installer.exe', 02:21:32 INFO - 'log_level': 'info', 02:21:32 INFO - 'log_name': 'talos', 02:21:32 INFO - 'log_to_console': True, 02:21:32 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:21:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:21:32 INFO - 'opt_config_files': (), 02:21:32 INFO - 'pip_index': False, 02:21:32 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:21:32 INFO - 'cmd': ('bash', 02:21:32 INFO - '-c', 02:21:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:21:32 INFO - 'enabled': True, 02:21:32 INFO - 'halt_on_failure': False, 02:21:32 INFO - 'name': 'check_screen_resolution'},), 02:21:32 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:21:32 INFO - 'cmd': ('bash', 02:21:32 INFO - '-c', 02:21:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:21:32 INFO - 'enabled': True, 02:21:32 INFO - 'halt_on_failure': False, 02:21:32 INFO - 'name': 'check_screen_resolution'},), 02:21:32 INFO - 'run_cmd_checks_enabled': True, 02:21:32 INFO - 'sps_profile': False, 02:21:32 INFO - 'sps_profile_interval': 0, 02:21:32 INFO - 'suite': 'dromaeojs-e10s', 02:21:32 INFO - 'system_bits': '32', 02:21:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:21:32 INFO - 'title': 't-yosemite-r7-0081', 02:21:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:21:32 INFO - 'use_talos_json': True, 02:21:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:21:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:21:32 INFO - 'work_dir': 'build'} 02:21:32 INFO - [mozharness: 2016-10-10 09:21:32.602455Z] Running clobber step. 02:21:32 INFO - Running pre-action listener: _resource_record_pre_action 02:21:32 INFO - Running main action method: clobber 02:21:32 INFO - rmtree: /builds/slave/test/build 02:21:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:21:34 INFO - Running post-action listener: _resource_record_post_action 02:21:34 INFO - [mozharness: 2016-10-10 09:21:34.042748Z] Finished clobber step (success) 02:21:34 INFO - [mozharness: 2016-10-10 09:21:34.042952Z] Running read-buildbot-config step. 02:21:34 INFO - Running pre-action listener: _resource_record_pre_action 02:21:34 INFO - Running main action method: read_buildbot_config 02:21:34 INFO - Using buildbot properties: 02:21:34 INFO - { 02:21:34 INFO - "project": "", 02:21:34 INFO - "product": "firefox", 02:21:34 INFO - "script_repo_revision": "production", 02:21:34 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs-e10s", 02:21:34 INFO - "repository": "", 02:21:34 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs-e10s", 02:21:34 INFO - "buildid": "20161009232216", 02:21:34 INFO - "pgo_build": "False", 02:21:34 INFO - "basedir": "/builds/slave/test", 02:21:34 INFO - "buildnumber": 42, 02:21:34 INFO - "slavename": "t-yosemite-r7-0081", 02:21:34 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 02:21:34 INFO - "platform": "yosemite_r7", 02:21:34 INFO - "scheduler": "tests-autoland-macosx64-talos-8-3600", 02:21:34 INFO - "branch": "autoland", 02:21:34 INFO - "repo_path": "integration/autoland", 02:21:34 INFO - "revision": "c44ee9cb3ba869e50c59dab44afbe757ff1b45d1", 02:21:34 INFO - "stage_platform": "macosx64", 02:21:34 INFO - "builduid": "e9d6ba2a934c470db386bd8b6f3644a9", 02:21:34 INFO - "slavebuilddir": "test" 02:21:34 INFO - } 02:21:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/HC7Otaw-QreY8xmm1Z6umw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 02:21:34 INFO - Running post-action listener: _resource_record_post_action 02:21:34 INFO - [mozharness: 2016-10-10 09:21:34.047457Z] Finished read-buildbot-config step (success) 02:21:34 INFO - [mozharness: 2016-10-10 09:21:34.047615Z] Running download-and-extract step. 02:21:34 INFO - Running pre-action listener: _resource_record_pre_action 02:21:34 INFO - Running main action method: download_and_extract 02:21:34 INFO - mkdir: /builds/slave/test/build/tests 02:21:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 02:21:34 INFO - trying https://queue.taskcluster.net/v1/task/HC7Otaw-QreY8xmm1Z6umw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 02:21:34 INFO - Downloading https://queue.taskcluster.net/v1/task/HC7Otaw-QreY8xmm1Z6umw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 02:21:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HC7Otaw-QreY8xmm1Z6umw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 02:21:35 INFO - Downloaded 1199 bytes. 02:21:35 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 02:21:35 INFO - Using the following test package requirements: 02:21:35 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 02:21:35 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 02:21:35 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 02:21:35 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 02:21:35 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 02:21:35 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 02:21:35 INFO - u'jsshell-mac.zip'], 02:21:35 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 02:21:35 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 02:21:35 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 02:21:35 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 02:21:35 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 02:21:35 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 02:21:35 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 02:21:35 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 02:21:35 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 02:21:35 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 02:21:35 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 02:21:35 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 02:21:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HC7Otaw-QreY8xmm1Z6umw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 02:21:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HC7Otaw-QreY8xmm1Z6umw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 02:21:35 INFO - Fetch https://queue.taskcluster.net/v1/task/HC7Otaw-QreY8xmm1Z6umw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 02:21:35 INFO - Http code: 200 02:21:35 INFO - via: 1.1 76bce8bb4fbd102fc0b3aa2e41094b79.cloudfront.net (CloudFront) 02:21:35 INFO - x-amz-cf-id: ZFDvfVav1fUOtJxdDpyLL0QmRVXCTLyNYR0_h4XClNfNz4o8wkBFAA== 02:21:35 INFO - x-amz-version-id: 2O_hoX4JIW_IWPistTANXVsNOIlAS892 02:21:36 INFO - Expected file size: 16496377 02:21:36 INFO - Obtained file size: 16496377 02:21:42 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 02:21:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HC7Otaw-QreY8xmm1Z6umw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 02:21:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HC7Otaw-QreY8xmm1Z6umw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 02:21:42 INFO - Fetch https://queue.taskcluster.net/v1/task/HC7Otaw-QreY8xmm1Z6umw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 02:21:43 INFO - Http code: 200 02:21:43 INFO - via: 1.1 336f0e6ef9a3462f682d6ca49029b665.cloudfront.net (CloudFront) 02:21:43 INFO - x-amz-cf-id: 5t1B3hoxNV8RzjLtgWR6riEu4mK-NnLeBYkFACM-T3rwB5AnkQ21Tg== 02:21:43 INFO - x-amz-version-id: 2O_hoX4JIW_IWPistTANXVsNOIlAS892 02:21:43 INFO - Expected file size: 16496377 02:21:43 INFO - Obtained file size: 16496377 02:21:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HC7Otaw-QreY8xmm1Z6umw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 02:21:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HC7Otaw-QreY8xmm1Z6umw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 02:21:50 INFO - Fetch https://queue.taskcluster.net/v1/task/HC7Otaw-QreY8xmm1Z6umw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 02:21:51 INFO - Http code: 200 02:21:51 INFO - via: 1.1 76bce8bb4fbd102fc0b3aa2e41094b79.cloudfront.net (CloudFront) 02:21:51 INFO - x-amz-cf-id: 1qp3zmby7FqlWPOQzJ_5HRUn_gwPtlZwnqGlNvG0cJwj9EGXPbpu0w== 02:21:51 INFO - x-amz-version-id: F4kZhwbbpT3KQU83u6rTkCiahY9OIPO3 02:21:51 INFO - Expected file size: 14409006 02:21:51 INFO - Obtained file size: 14409006 02:21:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 02:21:51 INFO - trying https://queue.taskcluster.net/v1/task/HC7Otaw-QreY8xmm1Z6umw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 02:21:51 INFO - Downloading https://queue.taskcluster.net/v1/task/HC7Otaw-QreY8xmm1Z6umw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 02:21:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HC7Otaw-QreY8xmm1Z6umw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 02:21:54 INFO - Downloaded 111716233 bytes. 02:21:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HC7Otaw-QreY8xmm1Z6umw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 02:21:54 INFO - mkdir: /builds/slave/test/properties 02:21:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:21:54 INFO - Writing to file /builds/slave/test/properties/build_url 02:21:54 INFO - Contents: 02:21:54 INFO - build_url:https://queue.taskcluster.net/v1/task/HC7Otaw-QreY8xmm1Z6umw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 02:21:55 INFO - Running post-action listener: _resource_record_post_action 02:21:55 INFO - Running post-action listener: set_extra_try_arguments 02:21:55 INFO - [mozharness: 2016-10-10 09:21:55.351904Z] Finished download-and-extract step (success) 02:21:55 INFO - [mozharness: 2016-10-10 09:21:55.352017Z] Running populate-webroot step. 02:21:55 INFO - Running pre-action listener: _resource_record_pre_action 02:21:55 INFO - Running main action method: populate_webroot 02:21:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:21:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:21:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:21:55 INFO - '--tppagecycles', 02:21:55 INFO - '7'], 02:21:55 INFO - 'tests': ['tsvgm']}}, 02:21:55 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:21:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:21:55 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:21:55 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:21:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:21:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:21:55 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:21:55 INFO - 'talos_options': ['--disable-e10s'], 02:21:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:21:55 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:21:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:21:55 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:21:55 INFO - 'talos_options': ['--disable-e10s'], 02:21:55 INFO - 'tests': ['damp', 'tps']}, 02:21:55 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:21:55 INFO - 'tests': ['damp', 'tps']}, 02:21:55 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:21:55 INFO - 'tests': ['dromaeo_dom']}, 02:21:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:21:55 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:21:55 INFO - 'tests': ['basic_compositor_video']}, 02:21:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:21:55 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:21:55 INFO - 'tests': ['a11yr', 02:21:55 INFO - 'ts_paint', 02:21:55 INFO - 'tpaint', 02:21:55 INFO - 'sessionrestore', 02:21:55 INFO - 'sessionrestore_no_auto_restore', 02:21:55 INFO - 'tabpaint']}, 02:21:55 INFO - 'other-e10s': {'tests': ['a11yr', 02:21:55 INFO - 'ts_paint', 02:21:55 INFO - 'tpaint', 02:21:55 INFO - 'sessionrestore', 02:21:55 INFO - 'sessionrestore_no_auto_restore', 02:21:55 INFO - 'tabpaint']}, 02:21:55 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:21:55 INFO - 'ts_paint', 02:21:55 INFO - 'tpaint', 02:21:55 INFO - 'sessionrestore', 02:21:55 INFO - 'sessionrestore_no_auto_restore', 02:21:55 INFO - 'tabpaint']}, 02:21:55 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:21:55 INFO - 'ts_paint', 02:21:55 INFO - 'tpaint', 02:21:55 INFO - 'sessionrestore', 02:21:55 INFO - 'sessionrestore_no_auto_restore', 02:21:55 INFO - 'tabpaint']}, 02:21:55 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:21:55 INFO - 'tests': ['a11yr', 02:21:55 INFO - 'ts_paint', 02:21:55 INFO - 'tpaint', 02:21:55 INFO - 'sessionrestore', 02:21:55 INFO - 'sessionrestore_no_auto_restore', 02:21:55 INFO - 'tabpaint']}, 02:21:55 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:21:55 INFO - 'tests': ['a11yr', 02:21:55 INFO - 'ts_paint', 02:21:55 INFO - 'tpaint', 02:21:55 INFO - 'sessionrestore', 02:21:55 INFO - 'sessionrestore_no_auto_restore', 02:21:55 INFO - 'tabpaint']}, 02:21:55 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:21:55 INFO - 'tests': ['tsvgx', 02:21:55 INFO - 'tsvgr_opacity', 02:21:55 INFO - 'tart', 02:21:55 INFO - 'tscrollx', 02:21:55 INFO - 'cart']}, 02:21:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:21:55 INFO - 'tsvgr_opacity', 02:21:55 INFO - 'tart', 02:21:55 INFO - 'tscrollx', 02:21:55 INFO - 'cart']}, 02:21:55 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:21:55 INFO - 'talos_options': ['--disable-e10s'], 02:21:55 INFO - 'tests': ['tp5o']}, 02:21:55 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:21:55 INFO - 'tests': ['tp5o']}, 02:21:55 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:21:55 INFO - 'talos_options': ['--disable-e10s', 02:21:55 INFO - '--xperf_path', 02:21:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:21:55 INFO - 'tests': ['tp5n']}, 02:21:55 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:21:55 INFO - 'talos_options': ['--xperf_path', 02:21:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:21:55 INFO - 'tests': ['tp5n']}}, 02:21:55 INFO - 'talos.zip': {'path': '', 02:21:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:21:55 INFO - Running post-action listener: _resource_record_post_action 02:21:55 INFO - [mozharness: 2016-10-10 09:21:55.370977Z] Finished populate-webroot step (success) 02:21:55 INFO - [mozharness: 2016-10-10 09:21:55.371113Z] Running create-virtualenv step. 02:21:55 INFO - Running pre-action listener: _resource_record_pre_action 02:21:55 INFO - Running main action method: create_virtualenv 02:21:55 INFO - Creating virtualenv /builds/slave/test/build/venv 02:21: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 02:21:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:21:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:21:55 INFO - Using real prefix '/tools/python27' 02:21:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:21:56 INFO - Installing distribute.............................................................................................................................................................................................done. 02:21:59 INFO - Installing pip.................done. 02:21:59 INFO - Return code: 0 02:21:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:21:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:21:59 INFO - Reading from file tmpfile_stdout 02:21:59 INFO - Output received: 02:21: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) 02:21:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:21:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 02:21:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:21:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:21:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 02:21:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:21:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:21: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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x107082490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ff8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa2bc70880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e96d30>, '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 0x106cbcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e8eb20>, '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.KlCpefsbxK/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.mznUvlfXJn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 02:21:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 02:21:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:21:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KlCpefsbxK/Render', 02:21:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:21:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:21:59 INFO - 'HOME': '/Users/cltbld', 02:21:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:21:59 INFO - 'LOGNAME': 'cltbld', 02:21:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:21:59 INFO - 'MOZ_NO_REMOTE': '1', 02:21:59 INFO - 'NO_EM_RESTART': '1', 02:21:59 INFO - 'PAGER': '/bin/cat', 02:21:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:21:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:21:59 INFO - 'PWD': '/builds/slave/test', 02:21:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:21:59 INFO - 'SHELL': '/bin/bash', 02:21:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mznUvlfXJn/Listeners', 02:21:59 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 02:21:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:21:59 INFO - 'USER': 'cltbld', 02:21:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:21:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:21:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:21:59 INFO - 'XPC_FLAGS': '0x0', 02:21:59 INFO - 'XPC_SERVICE_NAME': '0', 02:21:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:21:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:21:59 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 02:21:59 INFO - Cleaning up... 02:21:59 INFO - Return code: 0 02:21:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:21:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 02:21:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:21:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:21:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 02:21:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:21:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:21: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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x107082490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ff8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa2bc70880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e96d30>, '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 0x106cbcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e8eb20>, '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.KlCpefsbxK/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.mznUvlfXJn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 02:21:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 02:21:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 02:21:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KlCpefsbxK/Render', 02:21:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:21:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:21:59 INFO - 'HOME': '/Users/cltbld', 02:21:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:21:59 INFO - 'LOGNAME': 'cltbld', 02:21:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:21:59 INFO - 'MOZ_NO_REMOTE': '1', 02:21:59 INFO - 'NO_EM_RESTART': '1', 02:21:59 INFO - 'PAGER': '/bin/cat', 02:21:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:21:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:21:59 INFO - 'PWD': '/builds/slave/test', 02:21:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:21:59 INFO - 'SHELL': '/bin/bash', 02:21:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mznUvlfXJn/Listeners', 02:21:59 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 02:21:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:21:59 INFO - 'USER': 'cltbld', 02:21:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:21:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:21:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:21:59 INFO - 'XPC_FLAGS': '0x0', 02:21:59 INFO - 'XPC_SERVICE_NAME': '0', 02:21:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:21:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:21:59 INFO - Downloading/unpacking psutil>=3.1.1 02:21:59 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 02:21:59 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 02:21:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:21:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:21:59 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 02:21:59 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 02:22:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:22:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:22:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:22:03 INFO - Installing collected packages: psutil 02:22:03 INFO - Running setup.py install for psutil 02:22:04 INFO - building 'psutil._psutil_osx' extension 02:22:04 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 02:22:04 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 02:22:04 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 02:22:04 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 02:22:04 INFO - building 'psutil._psutil_posix' extension 02:22:04 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 02:22:04 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:22:04 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:22:04 INFO - ^ 02:22:04 INFO - 1 warning generated. 02:22:04 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 02:22:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:22:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:22:04 INFO - Successfully installed psutil 02:22:04 INFO - Cleaning up... 02:22:04 INFO - Return code: 0 02:22:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:22:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 02:22:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:22:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 02:22:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:22:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:04 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x107082490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ff8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa2bc70880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e96d30>, '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 0x106cbcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e8eb20>, '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.KlCpefsbxK/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.mznUvlfXJn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 02:22:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 02:22:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 02:22:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KlCpefsbxK/Render', 02:22:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:22:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:22:04 INFO - 'HOME': '/Users/cltbld', 02:22:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:22:04 INFO - 'LOGNAME': 'cltbld', 02:22:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:22:04 INFO - 'MOZ_NO_REMOTE': '1', 02:22:04 INFO - 'NO_EM_RESTART': '1', 02:22:04 INFO - 'PAGER': '/bin/cat', 02:22:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:22:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:22:04 INFO - 'PWD': '/builds/slave/test', 02:22:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:22:04 INFO - 'SHELL': '/bin/bash', 02:22:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mznUvlfXJn/Listeners', 02:22:04 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 02:22:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:22:04 INFO - 'USER': 'cltbld', 02:22:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:22:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:22:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:22:04 INFO - 'XPC_FLAGS': '0x0', 02:22:04 INFO - 'XPC_SERVICE_NAME': '0', 02:22:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:22:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:22:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:22:05 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 02:22:05 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 02:22:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:22:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:22:05 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 02:22:05 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 02:22:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:22:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:22:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:22:07 INFO - Installing collected packages: mozsystemmonitor 02:22:07 INFO - Running setup.py install for mozsystemmonitor 02:22:07 INFO - Successfully installed mozsystemmonitor 02:22:07 INFO - Cleaning up... 02:22:07 INFO - Return code: 0 02:22:07 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:22:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 02:22:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:22:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 02:22:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:22:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:07 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x107082490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ff8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa2bc70880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e96d30>, '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 0x106cbcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e8eb20>, '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.KlCpefsbxK/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.mznUvlfXJn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 02:22:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 02:22:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:22:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KlCpefsbxK/Render', 02:22:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:22:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:22:07 INFO - 'HOME': '/Users/cltbld', 02:22:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:22:07 INFO - 'LOGNAME': 'cltbld', 02:22:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:22:07 INFO - 'MOZ_NO_REMOTE': '1', 02:22:07 INFO - 'NO_EM_RESTART': '1', 02:22:07 INFO - 'PAGER': '/bin/cat', 02:22:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:22:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:22:07 INFO - 'PWD': '/builds/slave/test', 02:22:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:22:07 INFO - 'SHELL': '/bin/bash', 02:22:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mznUvlfXJn/Listeners', 02:22:07 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 02:22:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:22:07 INFO - 'USER': 'cltbld', 02:22:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:22:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:22:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:22:07 INFO - 'XPC_FLAGS': '0x0', 02:22:07 INFO - 'XPC_SERVICE_NAME': '0', 02:22:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:22:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:22:08 INFO - Downloading/unpacking jsonschema==2.5.1 02:22: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 02:22: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 02:22:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:22:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:22: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 02:22: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 02:22:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:22:10 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:22: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 02:22: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 02:22:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:22:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:22: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 02:22: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 02:22:11 INFO - Downloading functools32-3.2.3-2.tar.gz 02:22:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:22:11 INFO - warning: no files found matching '*.txt' 02:22:11 INFO - no previously-included directories found matching 'build' 02:22:11 INFO - no previously-included directories found matching 'dist' 02:22:11 INFO - no previously-included directories found matching '.git*' 02:22:11 INFO - Installing collected packages: jsonschema, functools32 02:22:11 INFO - Running setup.py install for functools32 02:22:11 INFO - warning: no files found matching '*.txt' 02:22:11 INFO - no previously-included directories found matching 'build' 02:22:11 INFO - no previously-included directories found matching 'dist' 02:22:11 INFO - no previously-included directories found matching '.git*' 02:22:11 INFO - Successfully installed jsonschema functools32 02:22:11 INFO - Cleaning up... 02:22:11 INFO - Return code: 0 02:22:11 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:22:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 02:22:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:22:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 02:22:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:22:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x107082490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ff8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa2bc70880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e96d30>, '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 0x106cbcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e8eb20>, '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.KlCpefsbxK/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.mznUvlfXJn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 02:22:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 02:22:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 02:22:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KlCpefsbxK/Render', 02:22:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:22:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:22:11 INFO - 'HOME': '/Users/cltbld', 02:22:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:22:11 INFO - 'LOGNAME': 'cltbld', 02:22:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:22:11 INFO - 'MOZ_NO_REMOTE': '1', 02:22:11 INFO - 'NO_EM_RESTART': '1', 02:22:11 INFO - 'PAGER': '/bin/cat', 02:22:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:22:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:22:11 INFO - 'PWD': '/builds/slave/test', 02:22:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:22:11 INFO - 'SHELL': '/bin/bash', 02:22:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mznUvlfXJn/Listeners', 02:22:11 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 02:22:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:22:11 INFO - 'USER': 'cltbld', 02:22:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:22:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:22:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:22:11 INFO - 'XPC_FLAGS': '0x0', 02:22:11 INFO - 'XPC_SERVICE_NAME': '0', 02:22:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:22:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:22:11 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:22:11 INFO - Cleaning up... 02:22:11 INFO - Return code: 0 02:22:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:22:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 02:22:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:22:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 02:22:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:22:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x107082490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ff8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa2bc70880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e96d30>, '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 0x106cbcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e8eb20>, '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.KlCpefsbxK/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.mznUvlfXJn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 02:22:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 02:22:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:22:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KlCpefsbxK/Render', 02:22:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:22:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:22:11 INFO - 'HOME': '/Users/cltbld', 02:22:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:22:11 INFO - 'LOGNAME': 'cltbld', 02:22:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:22:11 INFO - 'MOZ_NO_REMOTE': '1', 02:22:11 INFO - 'NO_EM_RESTART': '1', 02:22:11 INFO - 'PAGER': '/bin/cat', 02:22:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:22:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:22:11 INFO - 'PWD': '/builds/slave/test', 02:22:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:22:11 INFO - 'SHELL': '/bin/bash', 02:22:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mznUvlfXJn/Listeners', 02:22:11 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 02:22:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:22:11 INFO - 'USER': 'cltbld', 02:22:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:22:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:22:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:22:11 INFO - 'XPC_FLAGS': '0x0', 02:22:11 INFO - 'XPC_SERVICE_NAME': '0', 02:22:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:22:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:22:11 INFO - Downloading/unpacking blobuploader==1.2.4 02:22:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:22:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:22:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:22:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:22:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:22:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:22:14 INFO - Downloading blobuploader-1.2.4.tar.gz 02:22:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:22:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:22:14 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 02:22:14 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 02:22:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:22:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:22:14 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 02:22:14 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 02:22:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:22:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:22:15 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 02:22:15 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 02:22:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:22:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:22:15 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 02:22:15 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 02:22:15 INFO - Downloading docopt-0.6.1.tar.gz 02:22:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:22:15 INFO - Installing collected packages: blobuploader, requests, docopt 02:22:15 INFO - Running setup.py install for blobuploader 02:22:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:22:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:22:15 INFO - Running setup.py install for requests 02:22:16 INFO - Running setup.py install for docopt 02:22:16 INFO - Successfully installed blobuploader requests docopt 02:22:16 INFO - Cleaning up... 02:22:16 INFO - Return code: 0 02:22:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:22:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 02:22:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:22:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 02:22:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:22:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:16 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x107082490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ff8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa2bc70880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e96d30>, '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 0x106cbcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e8eb20>, '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.KlCpefsbxK/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.mznUvlfXJn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 02:22:16 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 02:22:16 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:22:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KlCpefsbxK/Render', 02:22:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:22:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:22:16 INFO - 'HOME': '/Users/cltbld', 02:22:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:22:16 INFO - 'LOGNAME': 'cltbld', 02:22:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:22:16 INFO - 'MOZ_NO_REMOTE': '1', 02:22:16 INFO - 'NO_EM_RESTART': '1', 02:22:16 INFO - 'PAGER': '/bin/cat', 02:22:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:22:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:22:16 INFO - 'PWD': '/builds/slave/test', 02:22:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:22:16 INFO - 'SHELL': '/bin/bash', 02:22:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mznUvlfXJn/Listeners', 02:22:16 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 02:22:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:22:16 INFO - 'USER': 'cltbld', 02:22:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:22:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:22:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:22:16 INFO - 'XPC_FLAGS': '0x0', 02:22:16 INFO - 'XPC_SERVICE_NAME': '0', 02:22:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:22:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:22:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:22:16 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-M30eBo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:22:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:22:16 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-XBs8Ea-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:22:17 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-bqzTHt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:22:17 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-nfkqGs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:22:17 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-lTi_m3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:22:17 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-JBMGMM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:22:17 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-xPBaGj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:22:17 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-yQp8eD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:22:17 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-K3wg39-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:22:17 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-gULFxj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:22:17 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-Z30vt6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:22:18 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-iHevBR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:22:18 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-FjF32v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:22:18 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-ggq8c6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:22:18 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-GOAY0P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:22:18 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-p3Ld_m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:22:18 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-PpscEX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:22:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:22:18 INFO - Running setup.py install for manifestparser 02:22:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:22:18 INFO - Running setup.py install for mozcrash 02:22:18 INFO - Running setup.py install for mozdebug 02:22:19 INFO - Running setup.py install for mozdevice 02:22:19 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:22:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:22:19 INFO - Running setup.py install for mozfile 02:22:19 INFO - Running setup.py install for mozhttpd 02:22:19 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:22:19 INFO - Running setup.py install for mozinfo 02:22:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:22:19 INFO - Running setup.py install for mozInstall 02:22:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:22:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:22:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:22:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:22:19 INFO - Running setup.py install for mozleak 02:22:19 INFO - Running setup.py install for mozlog 02:22:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:22:20 INFO - Running setup.py install for moznetwork 02:22:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:22:20 INFO - Running setup.py install for mozprocess 02:22:20 INFO - Running setup.py install for mozprofile 02:22:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:22:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:22:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:22:20 INFO - Running setup.py install for mozrunner 02:22:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:22:20 INFO - Running setup.py install for mozscreenshot 02:22:20 INFO - Running setup.py install for moztest 02:22:21 INFO - Running setup.py install for mozversion 02:22:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:22:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:22:21 INFO - Cleaning up... 02:22:21 INFO - Return code: 0 02:22:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:22:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 02:22:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:22:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 02:22:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:22:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:21 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x107082490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ff8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa2bc70880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e96d30>, '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 0x106cbcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e8eb20>, '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.KlCpefsbxK/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.mznUvlfXJn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 02:22:21 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 02:22:21 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:22:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KlCpefsbxK/Render', 02:22:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:22:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:22:21 INFO - 'HOME': '/Users/cltbld', 02:22:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:22:21 INFO - 'LOGNAME': 'cltbld', 02:22:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:22:21 INFO - 'MOZ_NO_REMOTE': '1', 02:22:21 INFO - 'NO_EM_RESTART': '1', 02:22:21 INFO - 'PAGER': '/bin/cat', 02:22:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:22:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:22:21 INFO - 'PWD': '/builds/slave/test', 02:22:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:22:21 INFO - 'SHELL': '/bin/bash', 02:22:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mznUvlfXJn/Listeners', 02:22:21 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 02:22:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:22:21 INFO - 'USER': 'cltbld', 02:22:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:22:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:22:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:22:21 INFO - 'XPC_FLAGS': '0x0', 02:22:21 INFO - 'XPC_SERVICE_NAME': '0', 02:22:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:22:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:22:21 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-vFzQr2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:22:21 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)) 02:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:22:21 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-ESRhY0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:22:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 02:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:22:21 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-femmaU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:22:21 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)) 02:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:22:21 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-f7bVkC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:22:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 02:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:22:21 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-KYse7f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:22:22 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)) 02:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:22:22 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-xjl6yH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:22:22 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)) 02:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:22:22 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-2MoTfq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:22:22 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)) 02:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:22:22 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-zJAQsy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:22:22 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)) 02:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:22:22 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-A1AHgT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:22:22 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)) 02:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:22:22 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-5pme7D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:22:22 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)) 02:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:22:22 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-vxva8P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:22:22 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)) 02:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:22:22 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-n8bkGM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:22:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 02:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:22:22 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-h6bCgX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:22:22 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)) 02:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:22:22 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-vUtBOA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:22:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 02:22:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:22:23 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-RAp97k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:22:23 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)) 02:22:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:22:23 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-Xm9ZSN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:22:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 02:22:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:22:23 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-230S0w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:22:23 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)) 02:22:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:22:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:22:23 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)) 02:22:23 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:22:23 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:22:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:22:23 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 02:22:23 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 02:22:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:22:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:22:23 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 02:22:23 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 02:22:25 INFO - Downloading blessings-1.6.tar.gz 02:22:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:22:25 INFO - Installing collected packages: blessings 02:22:25 INFO - Running setup.py install for blessings 02:22:26 INFO - Successfully installed blessings 02:22:26 INFO - Cleaning up... 02:22:26 INFO - Return code: 0 02:22:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:22:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:22:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:22:26 INFO - Reading from file tmpfile_stdout 02:22:26 INFO - Current package versions: 02:22:26 INFO - blessings == 1.6 02:22:26 INFO - blobuploader == 1.2.4 02:22:26 INFO - docopt == 0.6.1 02:22:26 INFO - functools32 == 3.2.3-2 02:22:26 INFO - jsonschema == 2.5.1 02:22:26 INFO - manifestparser == 1.1 02:22:26 INFO - mozInstall == 1.12 02:22:26 INFO - mozcrash == 0.17 02:22:26 INFO - mozdebug == 0.1 02:22:26 INFO - mozdevice == 0.48 02:22:26 INFO - mozfile == 1.2 02:22:26 INFO - mozhttpd == 0.7 02:22:26 INFO - mozinfo == 0.9 02:22:26 INFO - mozleak == 0.1 02:22:26 INFO - mozlog == 3.4 02:22:26 INFO - moznetwork == 0.27 02:22:26 INFO - mozprocess == 0.23 02:22:26 INFO - mozprofile == 0.28 02:22:26 INFO - mozrunner == 6.12 02:22:26 INFO - mozscreenshot == 0.1 02:22:26 INFO - mozsystemmonitor == 0.3 02:22:26 INFO - moztest == 0.7 02:22:26 INFO - mozversion == 1.4 02:22:26 INFO - psutil == 3.1.1 02:22:26 INFO - requests == 1.2.3 02:22:26 INFO - wsgiref == 0.1.2 02:22:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:22:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 02:22:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:22:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 02:22:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:22:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:26 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x107082490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ff8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa2bc70880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e96d30>, '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 0x106cbcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e8eb20>, '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.KlCpefsbxK/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.mznUvlfXJn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 02:22:26 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 02:22:26 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:22:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KlCpefsbxK/Render', 02:22:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:22:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:22:26 INFO - 'HOME': '/Users/cltbld', 02:22:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:22:26 INFO - 'LOGNAME': 'cltbld', 02:22:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:22:26 INFO - 'MOZ_NO_REMOTE': '1', 02:22:26 INFO - 'NO_EM_RESTART': '1', 02:22:26 INFO - 'PAGER': '/bin/cat', 02:22:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:22:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:22:26 INFO - 'PWD': '/builds/slave/test', 02:22:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:22:26 INFO - 'SHELL': '/bin/bash', 02:22:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mznUvlfXJn/Listeners', 02:22:26 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 02:22:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:22:26 INFO - 'USER': 'cltbld', 02:22:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:22:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:22:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:22:26 INFO - 'XPC_FLAGS': '0x0', 02:22:26 INFO - 'XPC_SERVICE_NAME': '0', 02:22:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:22:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:22:26 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)) 02:22:26 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)) 02:22:26 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)) 02:22:26 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)) 02:22:26 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)) 02:22:26 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)) 02:22:26 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)) 02:22:26 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)) 02:22:26 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)) 02:22:26 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)) 02:22:26 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)) 02:22:26 INFO - Cleaning up... 02:22:26 INFO - Return code: 0 02:22:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:22:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 02:22:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:22:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 02:22:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:22:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x107082490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ff8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa2bc70880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e96d30>, '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 0x106cbcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e8eb20>, '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.KlCpefsbxK/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.mznUvlfXJn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 02:22:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:22:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:22:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KlCpefsbxK/Render', 02:22:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:22:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:22:26 INFO - 'HOME': '/Users/cltbld', 02:22:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:22:26 INFO - 'LOGNAME': 'cltbld', 02:22:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:22:26 INFO - 'MOZ_NO_REMOTE': '1', 02:22:26 INFO - 'NO_EM_RESTART': '1', 02:22:26 INFO - 'PAGER': '/bin/cat', 02:22:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:22:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:22:26 INFO - 'PWD': '/builds/slave/test', 02:22:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:22:26 INFO - 'SHELL': '/bin/bash', 02:22:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mznUvlfXJn/Listeners', 02:22:26 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 02:22:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:22:26 INFO - 'USER': 'cltbld', 02:22:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:22:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:22:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:22:26 INFO - 'XPC_FLAGS': '0x0', 02:22:26 INFO - 'XPC_SERVICE_NAME': '0', 02:22:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:22:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:22:27 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:22:27 INFO - Cleaning up... 02:22:27 INFO - Return code: 0 02:22:27 INFO - Running post-action listener: _resource_record_post_action 02:22:27 INFO - Running post-action listener: _start_resource_monitoring 02:22:27 INFO - Starting resource monitoring. 02:22:27 INFO - [mozharness: 2016-10-10 09:22:27.063303Z] Finished create-virtualenv step (success) 02:22:27 INFO - [mozharness: 2016-10-10 09:22:27.064311Z] Running install step. 02:22:27 INFO - Running pre-action listener: _resource_record_pre_action 02:22:27 INFO - Running main action method: install 02:22:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:22:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:22:27 INFO - Reading from file tmpfile_stdout 02:22:27 INFO - Detecting whether we're running mozinstall >=1.0... 02:22:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:22:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:22:27 INFO - Reading from file tmpfile_stdout 02:22:27 INFO - Output received: 02:22:27 INFO - Usage: mozinstall [options] installer 02:22:27 INFO - Options: 02:22:27 INFO - -h, --help show this help message and exit 02:22:27 INFO - -d DEST, --destination=DEST 02:22:27 INFO - Directory to install application into. [default: 02:22:27 INFO - "/builds/slave/test"] 02:22:27 INFO - --app=APP Application being installed. [default: firefox] 02:22:27 INFO - mkdir: /builds/slave/test/build/application 02:22:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:22:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 02:23:01 INFO - Reading from file tmpfile_stdout 02:23:01 INFO - Output received: 02:23:01 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 02:23:01 INFO - Running post-action listener: _resource_record_post_action 02:23:01 INFO - [mozharness: 2016-10-10 09:23:01.607245Z] Finished install step (success) 02:23:01 INFO - [mozharness: 2016-10-10 09:23:01.607360Z] Running run-tests step. 02:23:01 INFO - Running pre-action listener: _resource_record_pre_action 02:23:01 INFO - Running main action method: run_tests 02:23:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:23:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:23:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:23:01 INFO - 2016-10-10 02:23:01.619 screenresolution[1940:23398] starting screenresolution argv=screenresolution get 02:23:01 INFO - 2016-10-10 02:23:01.622 screenresolution[1940:23398] Display 0: 1600x1200x32@60 02:23:01 INFO - 2016-10-10 02:23:01.628 screenresolution[1941:23400] starting screenresolution argv=screenresolution list 02:23:01 INFO - Available Modes on Display 0 02:23:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:23:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:23:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:23:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:23:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:23:01 INFO - Intel Iris: 02:23:01 INFO - Chipset Model: Intel Iris 02:23:01 INFO - Type: GPU 02:23:01 INFO - Bus: Built-In 02:23:01 INFO - VRAM (Dynamic, Max): 1536 MB 02:23:01 INFO - Vendor: Intel (0x8086) 02:23:01 INFO - Device ID: 0x0a2e 02:23:01 INFO - Revision ID: 0x0009 02:23:01 INFO - Displays: 02:23:01 INFO - Display: 02:23:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:23:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:23:01 INFO - Display Serial Number: 1600x1200 60 02:23:01 INFO - Main Display: Yes 02:23:01 INFO - Mirror: Off 02:23:01 INFO - Online: Yes 02:23:01 INFO - Rotation: Supported 02:23:01 INFO - Return code: 0 02:23:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:23:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:23:01 INFO - Python 2.7.3 02:23:01 INFO - Return code: 0 02:23:01 INFO - grabbing minidump binary from tooltool 02:23:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 02:23:01 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 0x106e96d30>, '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 0x106cbcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e8eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:23:01 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 02:23:01 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 02:23:01 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 02:23:01 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:23:01 INFO - Return code: 0 02:23:01 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:23:01 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:23:01 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:23:01 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:23:01 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:23:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HC7Otaw-QreY8xmm1Z6umw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0081', '--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 02:23:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/HC7Otaw-QreY8xmm1Z6umw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0081 --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 02:23:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KlCpefsbxK/Render', 02:23:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:23:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:23:01 INFO - 'HOME': '/Users/cltbld', 02:23:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:23:01 INFO - 'LOGNAME': 'cltbld', 02:23:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:23:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:23:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:23:01 INFO - 'MOZ_NO_REMOTE': '1', 02:23:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:23:01 INFO - 'NO_EM_RESTART': '1', 02:23:01 INFO - 'PAGER': '/bin/cat', 02:23:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:23:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:23:01 INFO - 'PWD': '/builds/slave/test', 02:23:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:23:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:23:01 INFO - 'SHELL': '/bin/bash', 02:23:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mznUvlfXJn/Listeners', 02:23:01 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 02:23:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:23:01 INFO - 'USER': 'cltbld', 02:23:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:23:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:23:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:23:01 INFO - 'XPC_FLAGS': '0x0', 02:23:01 INFO - 'XPC_SERVICE_NAME': '0', 02:23:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:23:01 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HC7Otaw-QreY8xmm1Z6umw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0081', '--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 02:23:02 INFO - mozversion application_buildid: 20161009232216 02:23:02 INFO - mozversion application_changeset: c44ee9cb3ba869e50c59dab44afbe757ff1b45d1 02:23:02 INFO - mozversion application_display_name: Nightly 02:23:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:23:02 INFO - mozversion application_name: Firefox 02:23:02 INFO - mozversion application_remotingname: firefox 02:23:02 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 02:23:02 INFO - mozversion application_vendor: Mozilla 02:23:02 INFO - mozversion application_version: 52.0a1 02:23:02 INFO - mozversion platform_buildid: 20161009232216 02:23:02 INFO - mozversion platform_changeset: c44ee9cb3ba869e50c59dab44afbe757ff1b45d1 02:23:02 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 02:23:02 INFO - mozversion platform_version: 52.0a1 02:23:02 INFO - using testdate: 1476091382 02:23:02 INFO - actual date: 1476091382 02:23:02 INFO - starting webserver on 'localhost:53106' 02:23:02 INFO - SUITE-START | Running 2 tests 02:23:02 INFO - TEST-START | dromaeo_css 02:23:02 INFO - operating with platform_type : mac_ 02:23:02 INFO - Initialising browser for dromaeo_css test... 02:23:02 INFO - TEST-INFO | started process 1951 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/tmptbeMJ2/profile http://localhost:53106/getInfo.html) 02:23:04 INFO - PROCESS | 1951 | 2016-10-10 02:23:04.707 plugin-container[1953:23562] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d3b, name = 'com.apple.tsm.portname' 02:23:04 INFO - PROCESS | 1951 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:23:04 INFO - PROCESS | 1951 | 2016-10-10 02:23:04.710 plugin-container[1953:23562] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c07, name = 'com.apple.CFPasteboardClient' 02:23:04 INFO - PROCESS | 1951 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:23:04 INFO - PROCESS | 1951 | 2016-10-10 02:23:04.710 plugin-container[1953:23562] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:23:04 INFO - PROCESS | 1951 | __metrics Screen width/height:1600/1200 02:23:04 INFO - PROCESS | 1951 | colorDepth:24 02:23:04 INFO - PROCESS | 1951 | Browser inner width/height: 1024/689 02:23:04 INFO - PROCESS | 1951 | __metrics 02:23:04 ERROR - PROCESS | 1951 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3083: TypeError: this.tabs is undefined 02:23:05 INFO - TEST-INFO | 1951: exit 0 02:23:05 INFO - Browser initialized. 02:23:05 INFO - Running cycle 1/1 for dromaeo_css test... 02:23:05 INFO - TEST-INFO | started process 1954 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/tmptbeMJ2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:23:07 INFO - PROCESS | 1954 | 2016-10-10 02:23:07.895 plugin-container[1955:23681] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a1f, name = 'com.apple.tsm.portname' 02:23:07 INFO - PROCESS | 1954 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:23:07 INFO - PROCESS | 1954 | 2016-10-10 02:23:07.901 plugin-container[1955:23681] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9103, name = 'com.apple.CFPasteboardClient' 02:23:07 INFO - PROCESS | 1954 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:23:07 INFO - PROCESS | 1954 | 2016-10-10 02:23:07.901 plugin-container[1955:23681] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:23:17 INFO - PROCESS | 1954 | RSS: Main: 239169536 02:23:17 INFO - PROCESS | 1954 | 02:24:07 INFO - PROCESS | 1954 | 1476091447141 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 02:26:20 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:53106/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53106/tests/dromaeo/cssquery-ext.html) 02:26:21 INFO - PROCESS | 1954 | RSS: Main: 254832640 02:26:21 INFO - PROCESS | 1954 | 02:29:24 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:53106/tests/dromaeo/cssquery-ext.html (next: http://localhost:53106/tests/dromaeo/cssquery-jquery.html) 02:29:24 INFO - PROCESS | 1954 | RSS: Main: 265998336 02:29:24 INFO - PROCESS | 1954 | 02:32:26 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:53106/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53106/tests/dromaeo/cssquery-mootools.html) 02:32:27 INFO - PROCESS | 1954 | RSS: Main: 269410304 02:32:27 INFO - PROCESS | 1954 | 02:35:29 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:53106/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53106/tests/dromaeo/cssquery-prototype.html) 02:35:29 INFO - PROCESS | 1954 | RSS: Main: 269086720 02:35:29 INFO - PROCESS | 1954 | 02:38:32 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:53106/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53106/tests/dromaeo/cssquery-yui.html) 02:38:33 INFO - PROCESS | 1954 | RSS: Main: 274485248 02:38:33 INFO - PROCESS | 1954 | 02:41:35 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:53106/tests/dromaeo/cssquery-yui.html (next: http://localhost:53106/tests/dromaeo/cssquery-dojo.html) 02:41:36 INFO - PROCESS | 1954 | RSS: Main: 277315584 02:41:36 INFO - PROCESS | 1954 | 02:41:36 INFO - PROCESS | 1954 | __start_tp_report 02:41:36 INFO - PROCESS | 1954 | _x_x_mozilla_page_load 02:41:36 INFO - PROCESS | 1954 | _x_x_mozilla_page_load_details 02:41:36 INFO - PROCESS | 1954 | |i|pagename|runs| 02:41:36 INFO - PROCESS | 1954 | |0;dojo.html;3336.7163791077755;3360.9663903360965;3382.5095361172616;3395.660433956604;3409.335179639968;5276.736163191844;5593.35676397214;5634.77460901564;5634.957532856424;5797.275340582427;4301.526832048472;4735.3844000280005;4914.410270767513;4986.501349865011;5579.525740312079;4388.297872340426;4442.97778511107;4992.026554821811;5133.666311689728;5366.141417373221;4471.843485478009;4830.120403271688;5260.842174734764;5353.892922141555;5391.406945236021;4986.925196122061;5164.61717574989;5179.197224430181;5180.533751962342;5292.179712144623;4736.478987311393;4901.779419926112;5233.319668443116;5242;5279.841604751864;5181.300524429192;5203.349581302337;5217.608679349064;5221.425643179247;5243.764030618631;5262.710550919698;5304.469553044703;5346.331708536433;5544.362398324204;5630.493255607015;5180.378354597435;6186.628802271878;6268.749250029993;6270.122182894398;6292.62244265341;6225.19072520574;6262.530310226705;6262.655553944532;6271.811845644638;6325.019621958604;5705.515031222392;5715.485606295453;5745.770169193185;5886.735096920649;5916.846214988036;5196.012757576124;5251.711155886386;5844.824655260349;5892.674148316594;6282.120503129566;6036.788712395045;6170.969145154246;6197.938020619827;6202.72087756052;6228;6089.751600921801;6116.785912493041;6191.19514463117;6194.132821405007;6265.561410701298;6137.723802428812;6143.296949342275;6148.708771158017;6193.566450348432;6209.037599172136;6186.350433204539;6191.25704915413;6199.504039676815;6207.565470417027;6224.097505861715;4820.180569303227;4837.080954618611;4959.08256972454;4986.775595098229;4998.775055122528;4398.8020539075815;4402.581754733295;4408.074304396113;4413;4590.67865249436;5527.723613819293;5749.252597162342;5915.171875937286;5929.762809487658;5937.109433584997;6244.969580019337;6261.029540421242;6272.749090036439;6278.434940855345;6316.778912738032;5576.498115169654;5578.748956296977;5612.130119831432;5756.42435756421;5769.336526299529;5224.9738751306;5228.764705588258;5243.344581927259;5250.921236181461;5266.526012658879;6144.969275153506;6223.906641400383;6224.502039836802;6243.500519958483;6263.185785847811;5415.431379705232;5456.754446050017;6088.786892458743;6124.716609780976;6168.753249869955;6269.0909818075215;6302.432781049728;6311.495080393649;6326.398992095744;6331.493480521455;6061.363556826646;6101.084837274267;6206.131141640264;6222.751089956351;6229.096780966644;6171;6208.0998255253635;6218.81343559694;6242.406971337716;6319.778807741891;6011.098335249748;6068.544859135494;6099.512039036955;6168.074788781719;6180.6909654517995;6052.485538729081;6098.793081995769;6116.17431646722;6154.107654390178;6161.075838624063;5106.748846532623;5139.48605139498;5978.910316345171;5990.880182396415;6007.54943379257;5888.558358123244;5934.851628709317;5994.850128746816;6011.819645410645;6114.847128821815;5858.970705146447;5860.238169037998;5871.853203669943;5895.7052147391605;5896.410358964069 02:41:36 INFO - PROCESS | 1954 | |1;ext.html;463.86316036769153;471.4813704924583;494.42893458055937;509.3268612607585;522.6733291692692;40501.79749101254;42167.15665686684;42351.78824105879;44602.55397446024;44713.329300060526;43562.782186089105;43940.78029609848;43983.80080995953;44191.779041104746;44278.77860610693;9738.464384458834;9912.454814985173;9927.007299270088;9928.90071099287;9937.950310248438;9721;9787.608495660164;9812.313138080337;9823.115919567237;9864.901350986505;9818.410895346266;9847.556859941284;9857.260705447085;9862.211023118134;9870.358426702242;8600.741977740678;9793.706188814347;9796.461194634292;9804.31369804114;9827.754306142333;8596.398252122353;9723.416595004323;9783.657571984946;9801.607935682565;9806.558704858298;41679.95800104991;41939;42012.36981445281;42053.78973105145;42109.57890421087;3243.8702451901895;3327.1183136468853;3804.9619503804884;4082.0815316553835;4082.9183416331803;25326.493470130496;30834.383312333626;31547.895823646293;31952.041438756874;32799;3284.9835750821094;3333.616634087087;3365.125067482448;3430.9142271443166;3431.7083047940955;491371;495627;496965.5151724254;497019;500279;58467.70766146142;58776.824463511344;58936.41063589395;59052.11421828676;60135.69932150311;63009.36990630127;63523;63529.28236458741;63637.68181159064;63669;16267.105309207871;16373.508794736177;16460.6707865842;16483.505494834935;16526.917365413097;12576.559820406243;12754.23474591509;12785.744285114431;12790.104692671424;12819.230846149261;16742.497725068268;16862.662746744998;16870.987740735596;16891.493255202364;16972.660546788997;5263.710495922761;6254.249490061222;6310.8422289442215;6345.777897773556;6346.619202847844;863.6156910174911;924.1590152960773;991.6330957545754;1013.1793247469574;1028.285341687535;29074.563881541795;30285.242868928453;30488;30567.38865222683;30584.38831223363;1056.9101626361692;1103.442761405485;1130.2483848240827;1167.1129941244715;1173.8473998380327;13490.52783152585;15665.68668626621;15676;15807.920960395355;16203.83796162047;654825;655938;660364;661010.6949465318;666665;55827;57432.42567574354;57438.42561574414;57552.71223643855;57648.42351576514;1325.5691900132495;1364.7543442180304;1387.0775934003975;1390.4438224709995;1393.4356585582855;23196.188133415926;27512.03707870295;29155;30817.69182308238;30832.69167308388;51169;51537.7423112882;51704.965900681025;51749.965000700526;51768.48231517787;475945;476779.6161019173;476972;477286;478348;39691;41436.378454323814;41669.37495937624;41822.163556729305;41861.37207941822;47226.055478890914;47397.52602473931;49033.2838350656;50203;50686.98626027385;40074;40237.39643905402;40971.687282883984;41449.79275103605;41750;32872.178195545304;40213.18842094282;40517.59482405138;40702.79648601738;41054.58945410508;38066.61933380631;38583.61416385912;39038.804805975786;39330.213395731356;39822.20355592814;27037.459250815267;31955.840220799677;33873.32253354968;35482.467762984095;35554.28891422209;21756.67364989558;21817;25045.24864254075;25473.49053018966;26586.676665416824 02:41:36 INFO - PROCESS | 1954 | |2;jquery.html;377.9792111433871;378.89201577550386;384.09545520299696;391.4441493079826;407.37467986640513;16780;18418.171182296795;20612;20626.48433789152;20848.583028339443;13286;16644.58388540284;20269;20342.898285508552;20365.49086272844;5694.259746232994;5735.942640573582;5901.3508514063415;6020.217371741667;6027;5898.321693005317;6020.939790602104;6022.96988515059;6031.849203769897;6061.515078793708;5547.223388725582;5653.434656534355;5888.499477544414;5988.7604495820115;6011.489023433013;4469.039156581351;5623.987682217199;5851.063829787214;5856.707164641751;5885.941140588582;4480.507144214133;5014.774335154927;5017.824376146854;5079.23811428285;5086.669366491204;25149.371265718502;25571.872140639363;25652;25861.35346616331;26049.348766280993;2219.833512486554;2735.302497863052;2990.8055976361684;3029.742471889892;3089.304906396043;15579.909406341561;15781.447649332218;15811.209439527978;16203.837961620351;16534;1790.6060666653445;1793.8116497767678;1828.3692126216567;1858.0709645177412;1929.556202073517;109146.362804558;110876.89123108746;122940;125049;127417.62911854463;10264.897351026395;10390.844137337946;10511.369317840954;10860.67417977462;11047;13426.731465370638;13483.662908427172;13491.730165396833;13500.797488037688;13521;8285;8566.957165214135;8662.263707584925;8700.303975682056;8734.301255899512;7486.363659088929;8569.885914831031;8657.480551166951;8799;8929.33030022751;6498.6425746583445;6602.7028783704845;6630.86738265232;6650.135482387259;6753.831154221184;1433.6917562724063;1775.6771205452021;1821.0712536606427;1840.1351364858494;1865.3378050792044;815.9699818342453;912.2018234045211;923.6951805904035;926.2219735422143;1025.015984654725;18326.717129801054;20062.699059514118;20824.47938801542;21534.56930861374;21579;909.7225346269319;925.6713866577403;936.134075979716;993.8558908958161;1051.4479898053582;9128.954355228181;10103.646372376932;10844.349339039683;10987.285826421417;10992.945035274772;556135;568474;569828;578590;587264;93976.53011734897;108085.45957270163;110365.44817276023;113008.43495782634;113072;848.5333066813228;874.9737507874773;938.8075444533854;1014.1025192704362;1025.7845852370936;14104.294785260901;15201.391944322546;18903.810961890755;19136.61726765485;19333.61332773365;47057;51306.74346628392;52142.21786673279;52160;53029.93940121099;69774;71385.28614713787;71396;71579.21051973742;73635.26364736499;6223.004319308889;7176.96411517939;7271.200167981624;7990.760277191694;8053.154418786177;11948.470850589236;12653;12790.360481975678;12820.038497112568;12862.871371286168;10660.786784264115;10840;10879.836802447811;11130.332180069221;11145.164112691418;8483;10666.73333166677;10679.412632305297;10720.678379648623;10789.946050269698;10475.476226188508;11001.339919604849;11020.834687479533;11033.613823516138;11155.330680158891;6196.628202307876;6894.413974812197;7282.308180722823;7324.791409417387;7493.363064139391;7959.522428654299;9051.54742262871;10613.628523001968;10799.730006749895;10923.126149908147 02:41:36 INFO - PROCESS | 1954 | |3;mootools.html;1483.0656686287637;1512.387483069357;1710.3928105522539;1793.9282428702836;1841.6869132247516;7979.12229654739;8252.422330436872;8461.619227134779;9028.368014239006;9059.682911098113;6740.325967403257;8311.376646751487;8616.353773466984;8638.654453821855;9024.503652299121;2068.5449201175793;2309.203324852923;2348.495073559184;2354.658574506704;2354.8704821234824;2285.1202287119495;2306.3888069661552;2364.373441038126;2371.1463873005705;2371.845830021052;2330.417395651087;2345.57779599672;2357.552065107624;2357.705286839145;2357.9882100589475;2296.40293523685;2297.5864344417996;2318.860868347896;2322.059565875823;2322.6399908014287;2308.8225005247277;2310.4223944013997;2313.1788215183706;2314.4329639238326;2316.305108467453;7145.356917877416;8902.020777714446;8968.282537396992;8974;8993.685221017233;4534.31985202219;5124.718140502308;5329.360476742816;5345.33183352081;5351.839444816662;3430.2110514581536;3502.1944952660774;3632.5640923089136;3633.073566240618;3648.3797754381817;1046.3408052926684;1073.3613499967507;1078.2129045796612;1081.5403453532297;1105.1214284643727;11578.247413918152;11647.534098636044;11714.53141874324;11717.296962182296;11744.471498782576;4197.265478541243;4505.45934487857;4584.289435137559;4745.335653008581;4769.998300356964;9877.11106000449;9931.702048938543;9961.651342203133;9967.40195588267;9969.501524923724;5352.57179425645;5358.30342055538;5361.195820626859;5365.141577347606;5368.302120724281;4273.829046838154;4452.198604251201;4829.758512074382;4884.023195360942;4911.214205727067;3850;3857.286402015636;3942.980285098556;3966.0283230608106;4013.959860401417;1104.436737264001;1273.8280332155225;1277.9872201277867;1279.2068917271351;1296.949470928532;872.2106493623281;889.7152911068525;895.6909866096187;904.2043002158059;907.9228265597366;5663.377028526858;5810.854728631818;5883.529317654577;6012.368701286389;6064.9090263646085;443.5209973228905;451.28020806813737;457.04933737825246;824.4435006370795;961.9610820314044;3940.9605903941165;4112.773797441169;4191.769452680071;4245.745255284631;4247.893802654897;10531.52608132636;10843.1867609928;10849.077828384676;10856.457177141112;10865.130789536817;10782.56869725218;10792.298500597437;10815.945920270347;10820.513076911559;10826.18803589734;7922.631952284431;8136.633851476698;8223.54770487617;8307.376946728898;8334;5960.195373624505;6010.909836352371;6101.176341193882;6115.6330620162935;6151.72317245716;8350.791230219293;9975.45135017581;10130.442825644366;10156.441395723306;10184.287099902936;9736.880258770332;10125.088742013255;10172.79654406893;10273.537690803782;10292.742681433025;6966.9303306966285;7175.8564828704175;7373.262673732584;7511.924880751122;7687.385009199139;8303.750887473385;8516.957415212884;8533.615987280464;8539.402241843012;8539.871901921351;7299.941508481346;7413.629318533944;7572.924270757222;7673.117591476828;7685.769426917202;7180.533265337736;7266.927330726837;7399.408047356306;7498.98763666898;7591.506552074097;6959.860802784017;7008.789736307919;7033.964830175816;7051.823704407431;7061.964690176516;4899.657024008286;4902.632302577393;5086.465921078381;5092.0579692756955;5118.3090282811345;5008.499150084962;5037.748252026905;5111.156659151198;5167.974160129175;5207.4271830097905 02:41:36 INFO - PROCESS | 1954 | |4;prototype.html;307.5801530910308;312.6998081841432;320.3145269124074;325.2210954763343;325.83870983862977;5114.514121158489;5396.514313711765;5488.506034456898;5512.503874651281;5584.8603784905345;5356.491133342326;5457.680485793541;5488.353493951809;5522.585806064551;5569.192467092269;4141.7100802943805;4204.810783514734;4206.032612499127;4230.365445183232;4235.49174099107;4105.568915263899;4209.936850947238;4238.194742998836;4259.829606815739;4266.402703621496;3475.409180439319;3539;4210.852620158295;4223.36649502574;4231.619154276114;3688.8708895188806;3975.741576797499;3990.700697447674;4001.6198461146146;4207.453031105937;3653.6894363979095;3657.2868290683327;3667.2482141161;3667.4963265061197;3681.7790932543867;4522.638188944877;4659.044895796387;4819.397575303087;5329.854081372523;5332.493727947897;4000.7599544027453;4013.8394464221396;4810.663253572251;4880.536349046871;4882;5624.55003599711;5795.333536643256;5835.3369289752645;5854.531637469035;5890.204822348971;3006.8045577037424;3201.679832016803;3980.383040628707;3988.700847436454;4036.2129384769687;5079.6952182869145;6009.939900600982;6132.172156758825;6171.506279497629;6180.5982611130585;3526.453399723047;3571.6428357164077;3798.9240215196096;3814.5613254475534;4043.433919251307;3260.9673903261137;3585;4086.346184610448;4138.2826127156895;4151.46031015972;4412.867613971586;4536.888462326751;4752.904941901143;4822.614190864722;4824.589909857701;4871.220604703301;5786.772092744323;5789.53683705311;5868.23712917318;5873.2658417697785;1293.4761421624069;1328.9867101328864;1397.2594524901817;1405.5291477355202;1410.5697762182633;827.7268501394525;861.7414775567305;879.7888506758331;887.8845750052452;889.6041261638638;4265.55211702773;4342.739435633872;4565.726056436624;4662.225975544546;4672.8551504881525;4281.464816897887;4926;5007.7245751484015;5049.974750126225;5059.874852561859;4234.184267462165;4480.252701446439;4649.234120362517;4650.906981860344;4663.930041049387;2310.6777688423026;2349.0502882607393;2432.761859089642;2506.511230310072;2571.5213752092623;4233.089885674594;4268;4902.803887844446;4904.509549045067;5201.661891977009;4423.646108311392;4578.5650363215445;4940.110780059624;5001.574866136347;5075.695458272516;3946.7631942083567;4630.69900456476;4819.397575303087;5168.793248270027;5249.7663049184175;4076.510818701775;4329.545397733193;4544.613707834871;4904.950950490449;5102.285680004728;4096.877093687194;4935.679180853233;4938.728369939688;4978.5270399312;4980.452150263396;4605.6085232755595;4609.769511524478;4725.8582242532775;4877.074155103442;5269.78920843162;3035.4687929611787;3178.4437723398773;3545.7872527648424;3550.751447398657;3597.6941959933697;4230.873073807791;4430.202563538594;5027.832465546829;5053.570446512088;5318.228856815817;5361.571074313967;5439.292891924025;5730.593150876559;5751.539876809761;5761.711914404347;5738.598298119258;5743.282089738783;5756.021476348947;5778.393268706725;5797.9420205797405;4312.374705667723;4829.27560865873;5386.622936394415;5686.573506987075;5698.088305871041;5882.176495290577;5914.58597898143;5940.73259707168;5976.402359763989;5984.491318237824;5516.66899985986;5730.025895597842;5775.711214439178;5807.390224026416;5874.471297583238 02:41:36 INFO - PROCESS | 1954 | |5;yui.html;693.9063226464426;711.4273010226767;720.099875156055;726.7710671138591;752.6124046116249;15266.465673701408;16118.677626447465;16137.83862161378;16152.353905843767;16182.029078255313;14227.075240109412;14288.428462861473;14354.497592584261;14381.928090359534;14415.279236038183;848.6902280667574;876.8903887014123;878.4246318661287;880.4409200157913;885.2210055151457;678.2742465561851;707.9398251148633;857.4683696108422;857.5797859049084;859.5745106172453;865.9653613855438;899.8740176375313;914.2411798207505;920.1120918313826;925.2413021322518;868.110187058264;887.5429153985702;903.2999425445266;909.1499448016126;909.4815954905706;670.808819486444;680.2585182151429;681.0261326303433;684.1106561470069;685.3078390825252;14797.40810367584;14903.850961490463;14954.47659331929;15003.1748253846;15033.548993530212;13693.520726774615;13720.65698357539;13765.862341376558;13770.423602478315;13896.096753710977;12642.304673242968;12673.809892851614;12688;12700.42848071839;12707;4103.015276333688;4471.35165401015;4483.641308695296;4486.68593198473;4541.091781643651;106751;114453.85546144516;114653;114709;115531.42234288859;10848.10158222308;11086.501107450023;11101.1674124441;11169;11173.10615150786;21608.675869861963;21657.241996530047;21663.78336216649;21690.34928952134;21713.56572868534;7664.463487555818;7796.142424333319;7822.1004584472375;7837.09873364559;8004.63979120941;4330.545292744279;4361.367601697736;4399.450068741407;4439.267520859022;4542.750148741785;3403.7787543809573;3457.896263112111;3515.455104458813;3516.1209697575605;3529.4529347951107;597.4115496236204;626.5332327416013;637.8309747916805;646.5991085527004;719.5323040024026;570.5179123640518;580.901246788047;601.3535449391834;624.7782037376757;668.0947316386226;8962.507062111516;9162.908370916206;9312.767180820534;9367.531623418801;9568.808623827623;530.8805518758248;614.5237440983274;685.6400389795398;687.089606271692;688.2360579756466;4436.112777444524;4656.906861862743;4658.767061646972;4664.618331271687;4676.275177347448;101528;125800.37099814443;142279;165851.1707441455;169536;87542;95481.0451895486;103621;115154.42422787832;141841;74310.6284468585;104585;107176.46411767892;111742.88257117488;111914;8243.917560824315;9400.81198376015;9627.5667594957;9820;9840.852387214341;46117;54375;62943.6852815733;64519.677401612695;65362.59305056878;64636;75119;88886;89461.55269223612;91181.72045698646;12130.211536250115;12415.875841241834;12746.980241580837;12964.286964217059;13044.152130111512;11345.943270283597;14360.640983975483;14368.568942931728;14465.927670362002;14625;13217.537386191438;13534.479320384304;13585.796213056614;13671.86328136706;13979.301034948008;10885.782284354427;13438.328083595585;13438.46246150143;13951.651208719863;13973.969176389468;12852.217926746463;13100.868991309964;13154.736905262032;13293.335333233106;13563.982701297246;11322.150838687296;11471.713207169887;11546.19176657626;11621.360825154698;11743.29540227555;8114.797130071795;8363.916360836314;9750.85373719409;10007.449590272605;11216.270942388719 02:41:36 INFO - PROCESS | 1954 | __end_tp_report 02:41:36 INFO - PROCESS | 1954 | __start_cc_report 02:41:36 INFO - PROCESS | 1954 | _x_x_mozilla_cycle_collect,365 02:41:36 INFO - PROCESS | 1954 | __end_cc_report 02:41:36 INFO - PROCESS | 1954 | __startTimestamp1476092496232__endTimestamp 02:41:36 INFO - PROCESS | 1954 | 02:41:36 INFO - PROCESS | 1954 | ------- Summary: start ------- 02:41:36 INFO - PROCESS | 1954 | Number of tests: 6 02:41:36 INFO - PROCESS | 1954 | 02:41:36 INFO - PROCESS | 1954 | [#0] dojo.html Cycles:165 Average:5637.19 Median:5888.56 stddev:676.76 (11.5%) stddev-sans-first:654.33 02:41:36 INFO - PROCESS | 1954 | Values: 3336.7 3361.0 3382.5 3395.7 3409.3 5276.7 5593.4 5634.8 5635.0 5797.3 4301.5 4735.4 4914.4 4986.5 5579.5 4388.3 4443.0 4992.0 5133.7 5366.1 4471.8 4830.1 5260.8 5353.9 5391.4 4986.9 5164.6 5179.2 5180.5 5292.2 4736.5 4901.8 5233.3 5242.0 5279.8 5181.3 5203.3 5217.6 5221.4 5243.8 5262.7 5304.5 5346.3 5544.4 5630.5 5180.4 6186.6 6268.7 6270.1 6292.6 6225.2 6262.5 6262.7 6271.8 6325.0 5705.5 5715.5 5745.8 5886.7 5916.8 5196.0 5251.7 5844.8 5892.7 6282.1 6036.8 6171.0 6197.9 6202.7 6228.0 6089.8 6116.8 6191.2 6194.1 6265.6 6137.7 6143.3 6148.7 6193.6 6209.0 6186.4 6191.3 6199.5 6207.6 6224.1 4820.2 4837.1 4959.1 4986.8 4998.8 4398.8 4402.6 4408.1 4413.0 4590.7 5527.7 5749.3 5915.2 5929.8 5937.1 6245.0 6261.0 6272.7 6278.4 6316.8 5576.5 5578.7 5612.1 5756.4 5769.3 5225.0 5228.8 5243.3 5250.9 5266.5 6145.0 6223.9 6224.5 6243.5 6263.2 5415.4 5456.8 6088.8 6124.7 6168.8 6269.1 6302.4 6311.5 6326.4 6331.5 6061.4 6101.1 6206.1 6222.8 6229.1 6171.0 6208.1 6218.8 6242.4 6319.8 6011.1 6068.5 6099.5 6168.1 6180.7 6052.5 6098.8 6116.2 6154.1 6161.1 5106.7 5139.5 5978.9 5990.9 6007.5 5888.6 5934.9 5994.9 6011.8 6114.8 5859.0 5860.2 5871.9 5895.7 5896.4 02:41:36 INFO - PROCESS | 1954 | 02:41:36 INFO - PROCESS | 1954 | [#1] ext.html Cycles:180 Average:68762.60 Median:42033.08 stddev:146903.70 (349.5%) stddev-sans-first:147226.30 02:41:36 INFO - PROCESS | 1954 | Values: 463.9 471.5 494.4 509.3 522.7 40501.8 42167.2 42351.8 44602.6 44713.3 43562.8 43940.8 43983.8 44191.8 44278.8 9738.5 9912.5 9927.0 9928.9 9938.0 9721.0 9787.6 9812.3 9823.1 9864.9 9818.4 9847.6 9857.3 9862.2 9870.4 8600.7 9793.7 9796.5 9804.3 9827.8 8596.4 9723.4 9783.7 9801.6 9806.6 41680.0 41939.0 42012.4 42053.8 42109.6 3243.9 3327.1 3805.0 4082.1 4082.9 25326.5 30834.4 31547.9 31952.0 32799.0 3285.0 3333.6 3365.1 3430.9 3431.7 491371.0 495627.0 496965.5 497019.0 500279.0 58467.7 58776.8 58936.4 59052.1 60135.7 63009.4 63523.0 63529.3 63637.7 63669.0 16267.1 16373.5 16460.7 16483.5 16526.9 12576.6 12754.2 12785.7 12790.1 12819.2 16742.5 16862.7 16871.0 16891.5 16972.7 5263.7 6254.2 6310.8 6345.8 6346.6 863.6 924.2 991.6 1013.2 1028.3 29074.6 30285.2 30488.0 30567.4 30584.4 1056.9 1103.4 1130.2 1167.1 1173.8 13490.5 15665.7 15676.0 15807.9 16203.8 654825.0 655938.0 660364.0 661010.7 666665.0 55827.0 57432.4 57438.4 57552.7 57648.4 1325.6 1364.8 1387.1 1390.4 1393.4 23196.2 27512.0 29155.0 30817.7 30832.7 51169.0 51537.7 51705.0 51750.0 51768.5 475945.0 476779.6 476972.0 477286.0 478348.0 39691.0 41436.4 41669.4 41822.2 41861.4 47226.1 47397.5 49033.3 50203.0 50687.0 40074.0 40237.4 40971.7 41449.8 41750.0 32872.2 40213.2 40517.6 40702.8 41054.6 38066.6 38583.6 39038.8 39330.2 39822.2 27037.5 31955.8 33873.3 35482.5 35554.3 21756.7 21817.0 25045.2 25473.5 26586.7 02:41:36 INFO - PROCESS | 1954 | 02:41:36 INFO - PROCESS | 1954 | [#2] jquery.html Cycles:180 Average:33433.98 Median:2863.05 stddev:95218.87 (3325.8%) stddev-sans-first:95453.63 02:41:36 INFO - PROCESS | 1954 | Values: 378.0 378.9 384.1 391.4 407.4 16780.0 18418.2 20612.0 20626.5 20848.6 13286.0 16644.6 20269.0 20342.9 20365.5 5694.3 5735.9 5901.4 6020.2 6027.0 5898.3 6020.9 6023.0 6031.8 6061.5 5547.2 5653.4 5888.5 5988.8 6011.5 4469.0 5624.0 5851.1 5856.7 5885.9 4480.5 5014.8 5017.8 5079.2 5086.7 25149.4 25571.9 25652.0 25861.4 26049.3 2219.8 2735.3 2990.8 3029.7 3089.3 15579.9 15781.4 15811.2 16203.8 16534.0 1790.6 1793.8 1828.4 1858.1 1929.6 109146.4 110876.9 122940.0 125049.0 127417.6 10264.9 10390.8 10511.4 10860.7 11047.0 13426.7 13483.7 13491.7 13500.8 13521.0 8285.0 8567.0 8662.3 8700.3 8734.3 7486.4 8569.9 8657.5 8799.0 8929.3 6498.6 6602.7 6630.9 6650.1 6753.8 1433.7 1775.7 1821.1 1840.1 1865.3 816.0 912.2 923.7 926.2 1025.0 18326.7 20062.7 20824.5 21534.6 21579.0 909.7 925.7 936.1 993.9 1051.4 9129.0 10103.6 10844.3 10987.3 10992.9 556135.0 568474.0 569828.0 578590.0 587264.0 93976.5 108085.5 110365.4 113008.4 113072.0 848.5 875.0 938.8 1014.1 1025.8 14104.3 15201.4 18903.8 19136.6 19333.6 47057.0 51306.7 52142.2 52160.0 53029.9 69774.0 71385.3 71396.0 71579.2 73635.3 6223.0 7177.0 7271.2 7990.8 8053.2 11948.5 12653.0 12790.4 12820.0 12862.9 10660.8 10840.0 10879.8 11130.3 11145.2 8483.0 10666.7 10679.4 10720.7 10789.9 10475.5 11001.3 11020.8 11033.6 11155.3 6196.6 6894.4 7282.3 7324.8 7493.4 7959.5 9051.5 10613.6 10799.7 10923.1 02:41:36 INFO - PROCESS | 1954 | 02:41:36 INFO - PROCESS | 1954 | [#3] mootools.html Cycles:180 Average:5695.54 Median:4901.14 stddev:3211.21 (65.5%) stddev-sans-first:3204.61 02:41:36 INFO - PROCESS | 1954 | Values: 1483.1 1512.4 1710.4 1793.9 1841.7 7979.1 8252.4 8461.6 9028.4 9059.7 6740.3 8311.4 8616.4 8638.7 9024.5 2068.5 2309.2 2348.5 2354.7 2354.9 2285.1 2306.4 2364.4 2371.1 2371.8 2330.4 2345.6 2357.6 2357.7 2358.0 2296.4 2297.6 2318.9 2322.1 2322.6 2308.8 2310.4 2313.2 2314.4 2316.3 7145.4 8902.0 8968.3 8974.0 8993.7 4534.3 5124.7 5329.4 5345.3 5351.8 3430.2 3502.2 3632.6 3633.1 3648.4 1046.3 1073.4 1078.2 1081.5 1105.1 11578.2 11647.5 11714.5 11717.3 11744.5 4197.3 4505.5 4584.3 4745.3 4770.0 9877.1 9931.7 9961.7 9967.4 9969.5 5352.6 5358.3 5361.2 5365.1 5368.3 4273.8 4452.2 4829.8 4884.0 4911.2 3850.0 3857.3 3943.0 3966.0 4014.0 1104.4 1273.8 1278.0 1279.2 1296.9 872.2 889.7 895.7 904.2 907.9 5663.4 5810.9 5883.5 6012.4 6064.9 443.5 451.3 457.0 824.4 962.0 3941.0 4112.8 4191.8 4245.7 4247.9 10531.5 10843.2 10849.1 10856.5 10865.1 10782.6 10792.3 10815.9 10820.5 10826.2 7922.6 8136.6 8223.5 8307.4 8334.0 5960.2 6010.9 6101.2 6115.6 6151.7 8350.8 9975.5 10130.4 10156.4 10184.3 9736.9 10125.1 10172.8 10273.5 10292.7 6966.9 7175.9 7373.3 7511.9 7687.4 8303.8 8517.0 8533.6 8539.4 8539.9 7299.9 7413.6 7572.9 7673.1 7685.8 7180.5 7266.9 7399.4 7499.0 7591.5 6959.9 7008.8 7034.0 7051.8 7062.0 4899.7 4902.6 5086.5 5092.1 5118.3 5008.5 5037.7 5111.2 5168.0 5207.4 02:41:36 INFO - PROCESS | 1954 | 02:41:36 INFO - PROCESS | 1954 | [#4] prototype.html Cycles:180 Average:4371.88 Median:4668.39 stddev:1355.65 (29.0%) stddev-sans-first:1324.69 02:41:36 INFO - PROCESS | 1954 | Values: 307.6 312.7 320.3 325.2 325.8 5114.5 5396.5 5488.5 5512.5 5584.9 5356.5 5457.7 5488.4 5522.6 5569.2 4141.7 4204.8 4206.0 4230.4 4235.5 4105.6 4209.9 4238.2 4259.8 4266.4 3475.4 3539.0 4210.9 4223.4 4231.6 3688.9 3975.7 3990.7 4001.6 4207.5 3653.7 3657.3 3667.2 3667.5 3681.8 4522.6 4659.0 4819.4 5329.9 5332.5 4000.8 4013.8 4810.7 4880.5 4882.0 5624.6 5795.3 5835.3 5854.5 5890.2 3006.8 3201.7 3980.4 3988.7 4036.2 5079.7 6009.9 6132.2 6171.5 6180.6 3526.5 3571.6 3798.9 3814.6 4043.4 3261.0 3585.0 4086.3 4138.3 4151.5 4412.9 4536.9 4752.9 4822.6 4824.6 4871.2 5786.8 5789.5 5868.2 5873.3 1293.5 1329.0 1397.3 1405.5 1410.6 827.7 861.7 879.8 887.9 889.6 4265.6 4342.7 4565.7 4662.2 4672.9 4281.5 4926.0 5007.7 5050.0 5059.9 4234.2 4480.3 4649.2 4650.9 4663.9 2310.7 2349.1 2432.8 2506.5 2571.5 4233.1 4268.0 4902.8 4904.5 5201.7 4423.6 4578.6 4940.1 5001.6 5075.7 3946.8 4630.7 4819.4 5168.8 5249.8 4076.5 4329.5 4544.6 4905.0 5102.3 4096.9 4935.7 4938.7 4978.5 4980.5 4605.6 4609.8 4725.9 4877.1 5269.8 3035.5 3178.4 3545.8 3550.8 3597.7 4230.9 4430.2 5027.8 5053.6 5318.2 5361.6 5439.3 5730.6 5751.5 5761.7 5738.6 5743.3 5756.0 5778.4 5797.9 4312.4 4829.3 5386.6 5686.6 5698.1 5882.2 5914.6 5940.7 5976.4 5984.5 5516.7 5730.0 5775.7 5807.4 5874.5 02:41:36 INFO - PROCESS | 1954 | 02:41:36 INFO - PROCESS | 1954 | [#5] yui.html Cycles:180 Average:23541.36 Median:4345.96 stddev:37195.35 (855.9%) stddev-sans-first:37260.14 02:41:36 INFO - PROCESS | 1954 | Values: 693.9 711.4 720.1 726.8 752.6 15266.5 16118.7 16137.8 16152.4 16182.0 14227.1 14288.4 14354.5 14381.9 14415.3 848.7 876.9 878.4 880.4 885.2 678.3 707.9 857.5 857.6 859.6 866.0 899.9 914.2 920.1 925.2 868.1 887.5 903.3 909.1 909.5 670.8 680.3 681.0 684.1 685.3 14797.4 14903.9 14954.5 15003.2 15033.5 13693.5 13720.7 13765.9 13770.4 13896.1 12642.3 12673.8 12688.0 12700.4 12707.0 4103.0 4471.4 4483.6 4486.7 4541.1 106751.0 114453.9 114653.0 114709.0 115531.4 10848.1 11086.5 11101.2 11169.0 11173.1 21608.7 21657.2 21663.8 21690.3 21713.6 7664.5 7796.1 7822.1 7837.1 8004.6 4330.5 4361.4 4399.5 4439.3 4542.8 3403.8 3457.9 3515.5 3516.1 3529.5 597.4 626.5 637.8 646.6 719.5 570.5 580.9 601.4 624.8 668.1 8962.5 9162.9 9312.8 9367.5 9568.8 530.9 614.5 685.6 687.1 688.2 4436.1 4656.9 4658.8 4664.6 4676.3 101528.0 125800.4 142279.0 165851.2 169536.0 87542.0 95481.0 103621.0 115154.4 141841.0 74310.6 104585.0 107176.5 111742.9 111914.0 8243.9 9400.8 9627.6 9820.0 9840.9 46117.0 54375.0 62943.7 64519.7 65362.6 64636.0 75119.0 88886.0 89461.6 91181.7 12130.2 12415.9 12747.0 12964.3 13044.2 11345.9 14360.6 14368.6 14465.9 14625.0 13217.5 13534.5 13585.8 13671.9 13979.3 10885.8 13438.3 13438.5 13951.7 13974.0 12852.2 13100.9 13154.7 13293.3 13564.0 11322.2 11471.7 11546.2 11621.4 11743.3 8114.8 8363.9 9750.9 10007.4 11216.3 02:41:36 INFO - PROCESS | 1954 | -------- Summary: end -------- 02:41:36 INFO - PROCESS | 1954 | 02:41:36 INFO - TEST-INFO | 1954: exit 0 02:41:36 INFO - TEST-OK | dromaeo_css | took 1114765ms 02:41:36 INFO - TEST-START | kraken 02:41:36 INFO - operating with platform_type : mac_ 02:41:36 INFO - Initialising browser for kraken test... 02:41:36 INFO - TEST-INFO | started process 1961 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/tmpBdiud9/profile http://localhost:53106/getInfo.html) 02:41:39 INFO - PROCESS | 1961 | 2016-10-10 02:41:39.987 plugin-container[1962:25772] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d27, name = 'com.apple.tsm.portname' 02:41:39 INFO - PROCESS | 1961 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:41:39 INFO - PROCESS | 1961 | 2016-10-10 02:41:39.991 plugin-container[1962:25772] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9103, name = 'com.apple.CFPasteboardClient' 02:41:39 INFO - PROCESS | 1961 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:41:39 INFO - PROCESS | 1961 | 2016-10-10 02:41:39.991 plugin-container[1962:25772] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:41:40 INFO - PROCESS | 1961 | __metrics Screen width/height:1600/1200 02:41:40 INFO - PROCESS | 1961 | colorDepth:24 02:41:40 INFO - PROCESS | 1961 | Browser inner width/height: 1024/689 02:41:40 INFO - PROCESS | 1961 | __metrics 02:41:40 ERROR - PROCESS | 1961 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3083: TypeError: this.tabs is undefined 02:41:40 INFO - TEST-INFO | 1961: exit 0 02:41:40 INFO - Browser initialized. 02:41:40 INFO - Running cycle 1/1 for kraken test... 02:41:40 INFO - TEST-INFO | started process 1963 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/tmpBdiud9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:41:43 INFO - PROCESS | 1963 | 2016-10-10 02:41:43.392 plugin-container[1964:25884] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b1f, name = 'com.apple.tsm.portname' 02:41:43 INFO - PROCESS | 1963 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:41:43 INFO - PROCESS | 1963 | 2016-10-10 02:41:43.394 plugin-container[1964:25884] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9203, name = 'com.apple.CFPasteboardClient' 02:41:43 INFO - PROCESS | 1963 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:41:43 INFO - PROCESS | 1963 | 2016-10-10 02:41:43.394 plugin-container[1964:25884] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:41:53 INFO - PROCESS | 1963 | RSS: Main: 239370240 02:41:53 INFO - PROCESS | 1963 | 02:41:56 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:53106/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53106/tests/kraken/driver.html?testName=audio-beat-detection) 02:41:56 INFO - PROCESS | 1963 | RSS: Main: 253087744 02:41:56 INFO - PROCESS | 1963 | 02:41:59 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:53106/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53106/tests/kraken/driver.html?testName=audio-dft) 02:42:00 INFO - PROCESS | 1963 | RSS: Main: 260526080 02:42:00 INFO - PROCESS | 1963 | 02:42:03 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:53106/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53106/tests/kraken/driver.html?testName=audio-fft) 02:42:04 INFO - PROCESS | 1963 | RSS: Main: 261464064 02:42:04 INFO - PROCESS | 1963 | 02:42:06 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:53106/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53106/tests/kraken/driver.html?testName=audio-oscillator) 02:42:06 INFO - PROCESS | 1963 | RSS: Main: 263659520 02:42:06 INFO - PROCESS | 1963 | 02:42:08 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:53106/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53106/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:42:09 INFO - PROCESS | 1963 | RSS: Main: 263983104 02:42:09 INFO - PROCESS | 1963 | 02:42:12 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:53106/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53106/tests/kraken/driver.html?testName=imaging-darkroom) 02:42:13 INFO - PROCESS | 1963 | RSS: Main: 264835072 02:42:13 INFO - PROCESS | 1963 | 02:42:16 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:53106/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53106/tests/kraken/driver.html?testName=imaging-desaturate) 02:42:16 INFO - PROCESS | 1963 | RSS: Main: 265011200 02:42:16 INFO - PROCESS | 1963 | 02:42:19 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:53106/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53106/tests/kraken/driver.html?testName=json-parse-financial) 02:42:20 INFO - PROCESS | 1963 | RSS: Main: 265449472 02:42:20 INFO - PROCESS | 1963 | 02:42:21 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:53106/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53106/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:42:22 INFO - PROCESS | 1963 | RSS: Main: 264880128 02:42:22 INFO - PROCESS | 1963 | 02:42:24 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:53106/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53106/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:42:25 INFO - PROCESS | 1963 | RSS: Main: 264949760 02:42:25 INFO - PROCESS | 1963 | 02:42:27 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:53106/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53106/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:42:28 INFO - PROCESS | 1963 | RSS: Main: 264994816 02:42:28 INFO - PROCESS | 1963 | 02:42:31 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:53106/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53106/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:42:31 INFO - PROCESS | 1963 | RSS: Main: 265531392 02:42:31 INFO - PROCESS | 1963 | 02:42:35 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:53106/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53106/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:42:35 INFO - PROCESS | 1963 | RSS: Main: 265052160 02:42:35 INFO - PROCESS | 1963 | 02:42:37 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:53106/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53106/tests/kraken/driver.html?testName=ai-astar) 02:42:37 INFO - PROCESS | 1963 | RSS: Main: 266768384 02:42:37 INFO - PROCESS | 1963 | 02:42:37 INFO - PROCESS | 1963 | __start_tp_report 02:42:37 INFO - PROCESS | 1963 | _x_x_mozilla_page_load 02:42:37 INFO - PROCESS | 1963 | _x_x_mozilla_page_load_details 02:42:37 INFO - PROCESS | 1963 | |i|pagename|runs| 02:42:37 INFO - PROCESS | 1963 | |0;ai-astar;111;117;126;172;162;117;119;116;117;117 02:42:37 INFO - PROCESS | 1963 | |1;audio-beat-detection;177;107;148;144;149;105;150;147;182;119 02:42:37 INFO - PROCESS | 1963 | |2;audio-dft;203;223;199;201;197;195;198;196;200;194 02:42:37 INFO - PROCESS | 1963 | |3;audio-fft;83;59;59;83;60;62;61;57;79;59 02:42:37 INFO - PROCESS | 1963 | |4;audio-oscillator;84;73;87;77;70;71;90;97;96;87 02:42:37 INFO - PROCESS | 1963 | |5;imaging-gaussian-blur;88;86;87;85;117;81;138;132;121;87 02:42:37 INFO - PROCESS | 1963 | |6;imaging-darkroom;122;95;93;98;152;151;96;94;94;96 02:42:37 INFO - PROCESS | 1963 | |7;imaging-desaturate;79;100;76;85;78;103;146;148;95;145 02:42:37 INFO - PROCESS | 1963 | |8;json-parse-financial;56;54;55;55;57;57;56;55;55;55 02:42:37 INFO - PROCESS | 1963 | |9;json-stringify-tinderbox;68;75;77;86;74;72;74;73;72;73 02:42:37 INFO - PROCESS | 1963 | |10;stanford-crypto-aes;95;116;76;100;109;77;87;102;112;113 02:42:37 INFO - PROCESS | 1963 | |11;stanford-crypto-ccm;144;151;150;141;155;143;209;164;216;150 02:42:37 INFO - PROCESS | 1963 | |12;stanford-crypto-pbkdf2;177;180;183;241;240;174;180;174;178;178 02:42:37 INFO - PROCESS | 1963 | |13;stanford-crypto-sha256-iterative;73;68;71;71;71;68;70;69;71;69 02:42:37 INFO - PROCESS | 1963 | __end_tp_report 02:42:37 INFO - PROCESS | 1963 | __start_cc_report 02:42:37 INFO - PROCESS | 1963 | _x_x_mozilla_cycle_collect,951 02:42:37 INFO - PROCESS | 1963 | __end_cc_report 02:42:37 INFO - PROCESS | 1963 | __startTimestamp1476092557635__endTimestamp 02:42:37 INFO - PROCESS | 1963 | 02:42:37 INFO - PROCESS | 1963 | ------- Summary: start ------- 02:42:37 INFO - PROCESS | 1963 | Number of tests: 14 02:42:37 INFO - PROCESS | 1963 | 02:42:37 INFO - PROCESS | 1963 | [#0] ai-astar Cycles:10 Average:127.40 Median:118.00 stddev:21.32 (18.1%) stddev-sans-first:21.77 02:42:37 INFO - PROCESS | 1963 | Values: 111.0 117.0 126.0 172.0 162.0 117.0 119.0 116.0 117.0 117.0 02:42:37 INFO - PROCESS | 1963 | 02:42:37 INFO - PROCESS | 1963 | [#1] audio-beat-detection Cycles:10 Average:142.80 Median:148.50 stddev:26.08 (17.6%) stddev-sans-first:24.55 02:42:37 INFO - PROCESS | 1963 | Values: 177.0 107.0 148.0 144.0 149.0 105.0 150.0 147.0 182.0 119.0 02:42:37 INFO - PROCESS | 1963 | 02:42:37 INFO - PROCESS | 1963 | [#2] audio-dft Cycles:10 Average:200.60 Median:199.50 stddev:8.34 (4.2%) stddev-sans-first:8.80 02:42:37 INFO - PROCESS | 1963 | Values: 203.0 223.0 199.0 201.0 197.0 195.0 198.0 196.0 200.0 194.0 02:42:37 INFO - PROCESS | 1963 | 02:42:37 INFO - PROCESS | 1963 | [#3] audio-fft Cycles:10 Average:66.20 Median:61.50 stddev:10.81 (17.6%) stddev-sans-first:9.60 02:42:37 INFO - PROCESS | 1963 | Values: 83.0 59.0 59.0 83.0 60.0 62.0 61.0 57.0 79.0 59.0 02:42:37 INFO - PROCESS | 1963 | 02:42:37 INFO - PROCESS | 1963 | [#4] audio-oscillator Cycles:10 Average:83.20 Median:87.00 stddev:9.98 (11.5%) stddev-sans-first:10.58 02:42:37 INFO - PROCESS | 1963 | Values: 84.0 73.0 87.0 77.0 70.0 71.0 90.0 97.0 96.0 87.0 02:42:37 INFO - PROCESS | 1963 | 02:42:37 INFO - PROCESS | 1963 | [#5] imaging-gaussian-blur Cycles:10 Average:102.20 Median:85.50 stddev:22.14 (25.9%) stddev-sans-first:22.88 02:42:37 INFO - PROCESS | 1963 | Values: 88.0 86.0 87.0 85.0 117.0 81.0 138.0 132.0 121.0 87.0 02:42:37 INFO - PROCESS | 1963 | 02:42:37 INFO - PROCESS | 1963 | [#6] imaging-darkroom Cycles:10 Average:109.10 Median:94.50 stddev:23.90 (25.3%) stddev-sans-first:24.89 02:42:37 INFO - PROCESS | 1963 | Values: 122.0 95.0 93.0 98.0 152.0 151.0 96.0 94.0 94.0 96.0 02:42:37 INFO - PROCESS | 1963 | 02:42:37 INFO - PROCESS | 1963 | [#7] imaging-desaturate Cycles:10 Average:105.50 Median:77.00 stddev:29.63 (38.5%) stddev-sans-first:29.84 02:42:37 INFO - PROCESS | 1963 | Values: 79.0 100.0 76.0 85.0 78.0 103.0 146.0 148.0 95.0 145.0 02:42:37 INFO - PROCESS | 1963 | 02:42:37 INFO - PROCESS | 1963 | [#8] json-parse-financial Cycles:10 Average:55.50 Median:55.50 stddev:0.97 (1.8%) stddev-sans-first:1.01 02:42:37 INFO - PROCESS | 1963 | Values: 56.0 54.0 55.0 55.0 57.0 57.0 56.0 55.0 55.0 55.0 02:42:37 INFO - PROCESS | 1963 | 02:42:37 INFO - PROCESS | 1963 | [#9] json-stringify-tinderbox Cycles:10 Average:74.40 Median:74.00 stddev:4.70 (6.3%) stddev-sans-first:4.37 02:42:37 INFO - PROCESS | 1963 | Values: 68.0 75.0 77.0 86.0 74.0 72.0 74.0 73.0 72.0 73.0 02:42:37 INFO - PROCESS | 1963 | 02:42:37 INFO - PROCESS | 1963 | [#10] stanford-crypto-aes Cycles:10 Average:98.70 Median:96.00 stddev:14.67 (15.3%) stddev-sans-first:15.50 02:42:37 INFO - PROCESS | 1963 | Values: 95.0 116.0 76.0 100.0 109.0 77.0 87.0 102.0 112.0 113.0 02:42:37 INFO - PROCESS | 1963 | 02:42:37 INFO - PROCESS | 1963 | [#11] stanford-crypto-ccm Cycles:10 Average:162.30 Median:153.00 stddev:27.31 (17.8%) stddev-sans-first:28.15 02:42:37 INFO - PROCESS | 1963 | Values: 144.0 151.0 150.0 141.0 155.0 143.0 209.0 164.0 216.0 150.0 02:42:37 INFO - PROCESS | 1963 | 02:42:37 INFO - PROCESS | 1963 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:190.50 Median:180.00 stddev:26.49 (14.7%) stddev-sans-first:27.65 02:42:37 INFO - PROCESS | 1963 | Values: 177.0 180.0 183.0 241.0 240.0 174.0 180.0 174.0 178.0 178.0 02:42:37 INFO - PROCESS | 1963 | 02:42:37 INFO - PROCESS | 1963 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.10 Median:71.00 stddev:1.60 (2.2%) stddev-sans-first:1.30 02:42:37 INFO - PROCESS | 1963 | Values: 73.0 68.0 71.0 71.0 71.0 68.0 70.0 69.0 71.0 69.0 02:42:37 INFO - PROCESS | 1963 | -------- Summary: end -------- 02:42:37 INFO - PROCESS | 1963 | 02:42:38 INFO - TEST-INFO | 1963: exit 0 02:42:38 INFO - TEST-OK | kraken | took 61741ms 02:42:38 INFO - SUITE-END | took 1176s 02:42:38 INFO - Completed test suite (00:19:36) 02:42:38 INFO - javascript benchmark 02:42:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7266.708572342877, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3336.7163791077755, 3360.9663903360965, 3382.5095361172616, 3395.660433956604, 3409.335179639968, 5276.736163191844, 5593.35676397214, 5634.77460901564, 5634.957532856424, 5797.275340582427, 4301.526832048472, 4735.3844000280005, 4914.410270767513, 4986.501349865011, 5579.525740312079, 4388.297872340426, 4442.97778511107, 4992.026554821811, 5133.666311689728, 5366.141417373221, 4471.843485478009, 4830.120403271688, 5260.842174734764, 5353.892922141555, 5391.406945236021, 4986.925196122061, 5164.61717574989, 5179.197224430181, 5180.533751962342, 5292.179712144623, 4736.478987311393, 4901.779419926112, 5233.319668443116, 5242.0, 5279.841604751864, 5181.300524429192, 5203.349581302337, 5217.608679349064, 5221.425643179247, 5243.764030618631, 5262.710550919698, 5304.469553044703, 5346.331708536433, 5544.362398324204, 5630.493255607015, 5180.378354597435, 6186.628802271878, 6268.749250029993, 6270.122182894398, 6292.62244265341, 6225.19072520574, 6262.530310226705, 6262.655553944532, 6271.811845644638, 6325.019621958604, 5705.515031222392, 5715.485606295453, 5745.770169193185, 5886.735096920649, 5916.846214988036, 5196.012757576124, 5251.711155886386, 5844.824655260349, 5892.674148316594, 6282.120503129566, 6036.788712395045, 6170.969145154246, 6197.938020619827, 6202.72087756052, 6228.0, 6089.751600921801, 6116.785912493041, 6191.19514463117, 6194.132821405007, 6265.561410701298, 6137.723802428812, 6143.296949342275, 6148.708771158017, 6193.566450348432, 6209.037599172136, 6186.350433204539, 6191.25704915413, 6199.504039676815, 6207.565470417027, 6224.097505861715, 4820.180569303227, 4837.080954618611, 4959.08256972454, 4986.775595098229, 4998.775055122528, 4398.8020539075815, 4402.581754733295, 4408.074304396113, 4413.0, 4590.67865249436, 5527.723613819293, 5749.252597162342, 5915.171875937286, 5929.762809487658, 5937.109433584997, 6244.969580019337, 6261.029540421242, 6272.749090036439, 6278.434940855345, 6316.778912738032, 5576.498115169654, 5578.748956296977, 5612.130119831432, 5756.42435756421, 5769.336526299529, 5224.9738751306, 5228.764705588258, 5243.344581927259, 5250.921236181461, 5266.526012658879, 6144.969275153506, 6223.906641400383, 6224.502039836802, 6243.500519958483, 6263.185785847811, 5415.431379705232, 5456.754446050017, 6088.786892458743, 6124.716609780976, 6168.753249869955, 6269.0909818075215, 6302.432781049728, 6311.495080393649, 6326.398992095744, 6331.493480521455, 6061.363556826646, 6101.084837274267, 6206.131141640264, 6222.751089956351, 6229.096780966644, 6171.0, 6208.0998255253635, 6218.81343559694, 6242.406971337716, 6319.778807741891, 6011.098335249748, 6068.544859135494, 6099.512039036955, 6168.074788781719, 6180.6909654517995, 6052.485538729081, 6098.793081995769, 6116.17431646722, 6154.107654390178, 6161.075838624063, 5106.748846532623, 5139.48605139498, 5978.910316345171, 5990.880182396415, 6007.54943379257, 5888.558358123244, 5934.851628709317, 5994.850128746816, 6011.819645410645, 6114.847128821815, 5858.970705146447, 5860.238169037998, 5871.853203669943, 5895.7052147391605, 5896.410358964069], "value": 5594.552002831166, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [463.86316036769153, 471.4813704924583, 494.42893458055937, 509.3268612607585, 522.6733291692692, 40501.79749101254, 42167.15665686684, 42351.78824105879, 44602.55397446024, 44713.329300060526, 43562.782186089105, 43940.78029609848, 43983.80080995953, 44191.779041104746, 44278.77860610693, 9738.464384458834, 9912.454814985173, 9927.007299270088, 9928.90071099287, 9937.950310248438, 9721.0, 9787.608495660164, 9812.313138080337, 9823.115919567237, 9864.901350986505, 9818.410895346266, 9847.556859941284, 9857.260705447085, 9862.211023118134, 9870.358426702242, 8600.741977740678, 9793.706188814347, 9796.461194634292, 9804.31369804114, 9827.754306142333, 8596.398252122353, 9723.416595004323, 9783.657571984946, 9801.607935682565, 9806.558704858298, 41679.95800104991, 41939.0, 42012.36981445281, 42053.78973105145, 42109.57890421087, 3243.8702451901895, 3327.1183136468853, 3804.9619503804884, 4082.0815316553835, 4082.9183416331803, 25326.493470130496, 30834.383312333626, 31547.895823646293, 31952.041438756874, 32799.0, 3284.9835750821094, 3333.616634087087, 3365.125067482448, 3430.9142271443166, 3431.7083047940955, 491371.0, 495627.0, 496965.5151724254, 497019.0, 500279.0, 58467.70766146142, 58776.824463511344, 58936.41063589395, 59052.11421828676, 60135.69932150311, 63009.36990630127, 63523.0, 63529.28236458741, 63637.68181159064, 63669.0, 16267.105309207871, 16373.508794736177, 16460.6707865842, 16483.505494834935, 16526.917365413097, 12576.559820406243, 12754.23474591509, 12785.744285114431, 12790.104692671424, 12819.230846149261, 16742.497725068268, 16862.662746744998, 16870.987740735596, 16891.493255202364, 16972.660546788997, 5263.710495922761, 6254.249490061222, 6310.8422289442215, 6345.777897773556, 6346.619202847844, 863.6156910174911, 924.1590152960773, 991.6330957545754, 1013.1793247469574, 1028.285341687535, 29074.563881541795, 30285.242868928453, 30488.0, 30567.38865222683, 30584.38831223363, 1056.9101626361692, 1103.442761405485, 1130.2483848240827, 1167.1129941244715, 1173.8473998380327, 13490.52783152585, 15665.68668626621, 15676.0, 15807.920960395355, 16203.83796162047, 654825.0, 655938.0, 660364.0, 661010.6949465318, 666665.0, 55827.0, 57432.42567574354, 57438.42561574414, 57552.71223643855, 57648.42351576514, 1325.5691900132495, 1364.7543442180304, 1387.0775934003975, 1390.4438224709995, 1393.4356585582855, 23196.188133415926, 27512.03707870295, 29155.0, 30817.69182308238, 30832.69167308388, 51169.0, 51537.7423112882, 51704.965900681025, 51749.965000700526, 51768.48231517787, 475945.0, 476779.6161019173, 476972.0, 477286.0, 478348.0, 39691.0, 41436.378454323814, 41669.37495937624, 41822.163556729305, 41861.37207941822, 47226.055478890914, 47397.52602473931, 49033.2838350656, 50203.0, 50686.98626027385, 40074.0, 40237.39643905402, 40971.687282883984, 41449.79275103605, 41750.0, 32872.178195545304, 40213.18842094282, 40517.59482405138, 40702.79648601738, 41054.58945410508, 38066.61933380631, 38583.61416385912, 39038.804805975786, 39330.213395731356, 39822.20355592814, 27037.459250815267, 31955.840220799677, 33873.32253354968, 35482.467762984095, 35554.28891422209, 21756.67364989558, 21817.0, 25045.24864254075, 25473.49053018966, 26586.676665416824], "value": 20176.340091352515, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [377.9792111433871, 378.89201577550386, 384.09545520299696, 391.4441493079826, 407.37467986640513, 16780.0, 18418.171182296795, 20612.0, 20626.48433789152, 20848.583028339443, 13286.0, 16644.58388540284, 20269.0, 20342.898285508552, 20365.49086272844, 5694.259746232994, 5735.942640573582, 5901.3508514063415, 6020.217371741667, 6027.0, 5898.321693005317, 6020.939790602104, 6022.96988515059, 6031.849203769897, 6061.515078793708, 5547.223388725582, 5653.434656534355, 5888.499477544414, 5988.7604495820115, 6011.489023433013, 4469.039156581351, 5623.987682217199, 5851.063829787214, 5856.707164641751, 5885.941140588582, 4480.507144214133, 5014.774335154927, 5017.824376146854, 5079.23811428285, 5086.669366491204, 25149.371265718502, 25571.872140639363, 25652.0, 25861.35346616331, 26049.348766280993, 2219.833512486554, 2735.302497863052, 2990.8055976361684, 3029.742471889892, 3089.304906396043, 15579.909406341561, 15781.447649332218, 15811.209439527978, 16203.837961620351, 16534.0, 1790.6060666653445, 1793.8116497767678, 1828.3692126216567, 1858.0709645177412, 1929.556202073517, 109146.362804558, 110876.89123108746, 122940.0, 125049.0, 127417.62911854463, 10264.897351026395, 10390.844137337946, 10511.369317840954, 10860.67417977462, 11047.0, 13426.731465370638, 13483.662908427172, 13491.730165396833, 13500.797488037688, 13521.0, 8285.0, 8566.957165214135, 8662.263707584925, 8700.303975682056, 8734.301255899512, 7486.363659088929, 8569.885914831031, 8657.480551166951, 8799.0, 8929.33030022751, 6498.6425746583445, 6602.7028783704845, 6630.86738265232, 6650.135482387259, 6753.831154221184, 1433.6917562724063, 1775.6771205452021, 1821.0712536606427, 1840.1351364858494, 1865.3378050792044, 815.9699818342453, 912.2018234045211, 923.6951805904035, 926.2219735422143, 1025.015984654725, 18326.717129801054, 20062.699059514118, 20824.47938801542, 21534.56930861374, 21579.0, 909.7225346269319, 925.6713866577403, 936.134075979716, 993.8558908958161, 1051.4479898053582, 9128.954355228181, 10103.646372376932, 10844.349339039683, 10987.285826421417, 10992.945035274772, 556135.0, 568474.0, 569828.0, 578590.0, 587264.0, 93976.53011734897, 108085.45957270163, 110365.44817276023, 113008.43495782634, 113072.0, 848.5333066813228, 874.9737507874773, 938.8075444533854, 1014.1025192704362, 1025.7845852370936, 14104.294785260901, 15201.391944322546, 18903.810961890755, 19136.61726765485, 19333.61332773365, 47057.0, 51306.74346628392, 52142.21786673279, 52160.0, 53029.93940121099, 69774.0, 71385.28614713787, 71396.0, 71579.21051973742, 73635.26364736499, 6223.004319308889, 7176.96411517939, 7271.200167981624, 7990.760277191694, 8053.154418786177, 11948.470850589236, 12653.0, 12790.360481975678, 12820.038497112568, 12862.871371286168, 10660.786784264115, 10840.0, 10879.836802447811, 11130.332180069221, 11145.164112691418, 8483.0, 10666.73333166677, 10679.412632305297, 10720.678379648623, 10789.946050269698, 10475.476226188508, 11001.339919604849, 11020.834687479533, 11033.613823516138, 11155.330680158891, 6196.628202307876, 6894.413974812197, 7282.308180722823, 7324.791409417387, 7493.363064139391, 7959.522428654299, 9051.54742262871, 10613.628523001968, 10799.730006749895, 10923.126149908147], "value": 9448.792423916202, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1483.0656686287637, 1512.387483069357, 1710.3928105522539, 1793.9282428702836, 1841.6869132247516, 7979.12229654739, 8252.422330436872, 8461.619227134779, 9028.368014239006, 9059.682911098113, 6740.325967403257, 8311.376646751487, 8616.353773466984, 8638.654453821855, 9024.503652299121, 2068.5449201175793, 2309.203324852923, 2348.495073559184, 2354.658574506704, 2354.8704821234824, 2285.1202287119495, 2306.3888069661552, 2364.373441038126, 2371.1463873005705, 2371.845830021052, 2330.417395651087, 2345.57779599672, 2357.552065107624, 2357.705286839145, 2357.9882100589475, 2296.40293523685, 2297.5864344417996, 2318.860868347896, 2322.059565875823, 2322.6399908014287, 2308.8225005247277, 2310.4223944013997, 2313.1788215183706, 2314.4329639238326, 2316.305108467453, 7145.356917877416, 8902.020777714446, 8968.282537396992, 8974.0, 8993.685221017233, 4534.31985202219, 5124.718140502308, 5329.360476742816, 5345.33183352081, 5351.839444816662, 3430.2110514581536, 3502.1944952660774, 3632.5640923089136, 3633.073566240618, 3648.3797754381817, 1046.3408052926684, 1073.3613499967507, 1078.2129045796612, 1081.5403453532297, 1105.1214284643727, 11578.247413918152, 11647.534098636044, 11714.53141874324, 11717.296962182296, 11744.471498782576, 4197.265478541243, 4505.45934487857, 4584.289435137559, 4745.335653008581, 4769.998300356964, 9877.11106000449, 9931.702048938543, 9961.651342203133, 9967.40195588267, 9969.501524923724, 5352.57179425645, 5358.30342055538, 5361.195820626859, 5365.141577347606, 5368.302120724281, 4273.829046838154, 4452.198604251201, 4829.758512074382, 4884.023195360942, 4911.214205727067, 3850.0, 3857.286402015636, 3942.980285098556, 3966.0283230608106, 4013.959860401417, 1104.436737264001, 1273.8280332155225, 1277.9872201277867, 1279.2068917271351, 1296.949470928532, 872.2106493623281, 889.7152911068525, 895.6909866096187, 904.2043002158059, 907.9228265597366, 5663.377028526858, 5810.854728631818, 5883.529317654577, 6012.368701286389, 6064.9090263646085, 443.5209973228905, 451.28020806813737, 457.04933737825246, 824.4435006370795, 961.9610820314044, 3940.9605903941165, 4112.773797441169, 4191.769452680071, 4245.745255284631, 4247.893802654897, 10531.52608132636, 10843.1867609928, 10849.077828384676, 10856.457177141112, 10865.130789536817, 10782.56869725218, 10792.298500597437, 10815.945920270347, 10820.513076911559, 10826.18803589734, 7922.631952284431, 8136.633851476698, 8223.54770487617, 8307.376946728898, 8334.0, 5960.195373624505, 6010.909836352371, 6101.176341193882, 6115.6330620162935, 6151.72317245716, 8350.791230219293, 9975.45135017581, 10130.442825644366, 10156.441395723306, 10184.287099902936, 9736.880258770332, 10125.088742013255, 10172.79654406893, 10273.537690803782, 10292.742681433025, 6966.9303306966285, 7175.8564828704175, 7373.262673732584, 7511.924880751122, 7687.385009199139, 8303.750887473385, 8516.957415212884, 8533.615987280464, 8539.402241843012, 8539.871901921351, 7299.941508481346, 7413.629318533944, 7572.924270757222, 7673.117591476828, 7685.769426917202, 7180.533265337736, 7266.927330726837, 7399.408047356306, 7498.98763666898, 7591.506552074097, 6959.860802784017, 7008.789736307919, 7033.964830175816, 7051.823704407431, 7061.964690176516, 4899.657024008286, 4902.632302577393, 5086.465921078381, 5092.0579692756955, 5118.3090282811345, 5008.499150084962, 5037.748252026905, 5111.156659151198, 5167.974160129175, 5207.4271830097905], "value": 4533.64219992596, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [307.5801530910308, 312.6998081841432, 320.3145269124074, 325.2210954763343, 325.83870983862977, 5114.514121158489, 5396.514313711765, 5488.506034456898, 5512.503874651281, 5584.8603784905345, 5356.491133342326, 5457.680485793541, 5488.353493951809, 5522.585806064551, 5569.192467092269, 4141.7100802943805, 4204.810783514734, 4206.032612499127, 4230.365445183232, 4235.49174099107, 4105.568915263899, 4209.936850947238, 4238.194742998836, 4259.829606815739, 4266.402703621496, 3475.409180439319, 3539.0, 4210.852620158295, 4223.36649502574, 4231.619154276114, 3688.8708895188806, 3975.741576797499, 3990.700697447674, 4001.6198461146146, 4207.453031105937, 3653.6894363979095, 3657.2868290683327, 3667.2482141161, 3667.4963265061197, 3681.7790932543867, 4522.638188944877, 4659.044895796387, 4819.397575303087, 5329.854081372523, 5332.493727947897, 4000.7599544027453, 4013.8394464221396, 4810.663253572251, 4880.536349046871, 4882.0, 5624.55003599711, 5795.333536643256, 5835.3369289752645, 5854.531637469035, 5890.204822348971, 3006.8045577037424, 3201.679832016803, 3980.383040628707, 3988.700847436454, 4036.2129384769687, 5079.6952182869145, 6009.939900600982, 6132.172156758825, 6171.506279497629, 6180.5982611130585, 3526.453399723047, 3571.6428357164077, 3798.9240215196096, 3814.5613254475534, 4043.433919251307, 3260.9673903261137, 3585.0, 4086.346184610448, 4138.2826127156895, 4151.46031015972, 4412.867613971586, 4536.888462326751, 4752.904941901143, 4822.614190864722, 4824.589909857701, 4871.220604703301, 5786.772092744323, 5789.53683705311, 5868.23712917318, 5873.2658417697785, 1293.4761421624069, 1328.9867101328864, 1397.2594524901817, 1405.5291477355202, 1410.5697762182633, 827.7268501394525, 861.7414775567305, 879.7888506758331, 887.8845750052452, 889.6041261638638, 4265.55211702773, 4342.739435633872, 4565.726056436624, 4662.225975544546, 4672.8551504881525, 4281.464816897887, 4926.0, 5007.7245751484015, 5049.974750126225, 5059.874852561859, 4234.184267462165, 4480.252701446439, 4649.234120362517, 4650.906981860344, 4663.930041049387, 2310.6777688423026, 2349.0502882607393, 2432.761859089642, 2506.511230310072, 2571.5213752092623, 4233.089885674594, 4268.0, 4902.803887844446, 4904.509549045067, 5201.661891977009, 4423.646108311392, 4578.5650363215445, 4940.110780059624, 5001.574866136347, 5075.695458272516, 3946.7631942083567, 4630.69900456476, 4819.397575303087, 5168.793248270027, 5249.7663049184175, 4076.510818701775, 4329.545397733193, 4544.613707834871, 4904.950950490449, 5102.285680004728, 4096.877093687194, 4935.679180853233, 4938.728369939688, 4978.5270399312, 4980.452150263396, 4605.6085232755595, 4609.769511524478, 4725.8582242532775, 4877.074155103442, 5269.78920843162, 3035.4687929611787, 3178.4437723398773, 3545.7872527648424, 3550.751447398657, 3597.6941959933697, 4230.873073807791, 4430.202563538594, 5027.832465546829, 5053.570446512088, 5318.228856815817, 5361.571074313967, 5439.292891924025, 5730.593150876559, 5751.539876809761, 5761.711914404347, 5738.598298119258, 5743.282089738783, 5756.021476348947, 5778.393268706725, 5797.9420205797405, 4312.374705667723, 4829.27560865873, 5386.622936394415, 5686.573506987075, 5698.088305871041, 5882.176495290577, 5914.58597898143, 5940.73259707168, 5976.402359763989, 5984.491318237824, 5516.66899985986, 5730.025895597842, 5775.711214439178, 5807.390224026416, 5874.471297583238], "value": 3953.4645979334423, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [693.9063226464426, 711.4273010226767, 720.099875156055, 726.7710671138591, 752.6124046116249, 15266.465673701408, 16118.677626447465, 16137.83862161378, 16152.353905843767, 16182.029078255313, 14227.075240109412, 14288.428462861473, 14354.497592584261, 14381.928090359534, 14415.279236038183, 848.6902280667574, 876.8903887014123, 878.4246318661287, 880.4409200157913, 885.2210055151457, 678.2742465561851, 707.9398251148633, 857.4683696108422, 857.5797859049084, 859.5745106172453, 865.9653613855438, 899.8740176375313, 914.2411798207505, 920.1120918313826, 925.2413021322518, 868.110187058264, 887.5429153985702, 903.2999425445266, 909.1499448016126, 909.4815954905706, 670.808819486444, 680.2585182151429, 681.0261326303433, 684.1106561470069, 685.3078390825252, 14797.40810367584, 14903.850961490463, 14954.47659331929, 15003.1748253846, 15033.548993530212, 13693.520726774615, 13720.65698357539, 13765.862341376558, 13770.423602478315, 13896.096753710977, 12642.304673242968, 12673.809892851614, 12688.0, 12700.42848071839, 12707.0, 4103.015276333688, 4471.35165401015, 4483.641308695296, 4486.68593198473, 4541.091781643651, 106751.0, 114453.85546144516, 114653.0, 114709.0, 115531.42234288859, 10848.10158222308, 11086.501107450023, 11101.1674124441, 11169.0, 11173.10615150786, 21608.675869861963, 21657.241996530047, 21663.78336216649, 21690.34928952134, 21713.56572868534, 7664.463487555818, 7796.142424333319, 7822.1004584472375, 7837.09873364559, 8004.63979120941, 4330.545292744279, 4361.367601697736, 4399.450068741407, 4439.267520859022, 4542.750148741785, 3403.7787543809573, 3457.896263112111, 3515.455104458813, 3516.1209697575605, 3529.4529347951107, 597.4115496236204, 626.5332327416013, 637.8309747916805, 646.5991085527004, 719.5323040024026, 570.5179123640518, 580.901246788047, 601.3535449391834, 624.7782037376757, 668.0947316386226, 8962.507062111516, 9162.908370916206, 9312.767180820534, 9367.531623418801, 9568.808623827623, 530.8805518758248, 614.5237440983274, 685.6400389795398, 687.089606271692, 688.2360579756466, 4436.112777444524, 4656.906861862743, 4658.767061646972, 4664.618331271687, 4676.275177347448, 101528.0, 125800.37099814443, 142279.0, 165851.1707441455, 169536.0, 87542.0, 95481.0451895486, 103621.0, 115154.42422787832, 141841.0, 74310.6284468585, 104585.0, 107176.46411767892, 111742.88257117488, 111914.0, 8243.917560824315, 9400.81198376015, 9627.5667594957, 9820.0, 9840.852387214341, 46117.0, 54375.0, 62943.6852815733, 64519.677401612695, 65362.59305056878, 64636.0, 75119.0, 88886.0, 89461.55269223612, 91181.72045698646, 12130.211536250115, 12415.875841241834, 12746.980241580837, 12964.286964217059, 13044.152130111512, 11345.943270283597, 14360.640983975483, 14368.568942931728, 14465.927670362002, 14625.0, 13217.537386191438, 13534.479320384304, 13585.796213056614, 13671.86328136706, 13979.301034948008, 10885.782284354427, 13438.328083595585, 13438.46246150143, 13951.651208719863, 13973.969176389468, 12852.217926746463, 13100.868991309964, 13154.736905262032, 13293.335333233106, 13563.982701297246, 11322.150838687296, 11471.713207169887, 11546.19176657626, 11621.360825154698, 11743.29540227555, 8114.797130071795, 8363.916360836314, 9750.85373719409, 10007.449590272605, 11216.270942388719], "value": 7701.384398535195, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1588.5000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 117.0, 126.0, 172.0, 162.0, 117.0, 119.0, 116.0, 117.0, 117.0], "value": 127.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 107.0, 148.0, 144.0, 149.0, 105.0, 150.0, 147.0, 182.0, 119.0], "value": 142.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [203.0, 223.0, 199.0, 201.0, 197.0, 195.0, 198.0, 196.0, 200.0, 194.0], "value": 200.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 59.0, 59.0, 83.0, 60.0, 62.0, 61.0, 57.0, 79.0, 59.0], "value": 66.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 73.0, 87.0, 77.0, 70.0, 71.0, 90.0, 97.0, 96.0, 87.0], "value": 83.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 87.0, 85.0, 117.0, 81.0, 138.0, 132.0, 121.0, 87.0], "value": 102.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 95.0, 93.0, 98.0, 152.0, 151.0, 96.0, 94.0, 94.0, 96.0], "value": 109.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 100.0, 76.0, 85.0, 78.0, 103.0, 146.0, 148.0, 95.0, 145.0], "value": 105.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 55.0, 55.0, 57.0, 57.0, 56.0, 55.0, 55.0, 55.0], "value": 55.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 75.0, 77.0, 86.0, 74.0, 72.0, 74.0, 73.0, 72.0, 73.0], "value": 74.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 116.0, 76.0, 100.0, 109.0, 77.0, 87.0, 102.0, 112.0, 113.0], "value": 98.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 151.0, 150.0, 141.0, 155.0, 143.0, 209.0, 164.0, 216.0, 150.0], "value": 162.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 180.0, 183.0, 241.0, 240.0, 174.0, 180.0, 174.0, 178.0, 178.0], "value": 190.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 68.0, 71.0, 71.0, 71.0, 68.0, 70.0, 69.0, 71.0, 69.0], "value": 70.1, "unit": "score"}]}]} 02:42:38 INFO - Return code: 0 02:42:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:42:38 ERROR - # TBPL SUCCESS # 02:42:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:42:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:42:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:42:38 INFO - 2016-10-10 02:42:38.953 screenresolution[1968:26268] starting screenresolution argv=screenresolution get 02:42:38 INFO - 2016-10-10 02:42:38.957 screenresolution[1968:26268] Display 0: 1600x1200x32@60 02:42:38 INFO - 2016-10-10 02:42:38.964 screenresolution[1969:26271] starting screenresolution argv=screenresolution list 02:42:38 INFO - Available Modes on Display 0 02:42:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:42:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:42:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:42:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:42:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:42:39 INFO - Intel Iris: 02:42:39 INFO - Chipset Model: Intel Iris 02:42:39 INFO - Type: GPU 02:42:39 INFO - Bus: Built-In 02:42:39 INFO - VRAM (Dynamic, Max): 1536 MB 02:42:39 INFO - Vendor: Intel (0x8086) 02:42:39 INFO - Device ID: 0x0a2e 02:42:39 INFO - Revision ID: 0x0009 02:42:39 INFO - Displays: 02:42:39 INFO - Display: 02:42:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:42:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:42:39 INFO - Display Serial Number: 1600x1200 60 02:42:39 INFO - Main Display: Yes 02:42:39 INFO - Mirror: Off 02:42:39 INFO - Online: Yes 02:42:39 INFO - Rotation: Supported 02:42:39 INFO - Return code: 0 02:42:39 INFO - Running post-action listener: _resource_record_post_action 02:42:39 INFO - [mozharness: 2016-10-10 09:42:39.212052Z] Finished run-tests step (success) 02:42:39 INFO - Running post-run listener: _resource_record_post_run 02:42:39 INFO - Total resource usage - Wall time: 1211s; CPU: 55.0%; Read bytes: 1267200; Write bytes: 712974336; Read time: 13; Write time: 2219 02:42:39 INFO - TinderboxPrint: CPU usage
54.7% 02:42:39 INFO - TinderboxPrint: I/O read bytes / time
1,267,200 / 13 02:42:39 INFO - TinderboxPrint: I/O write bytes / time
712,974,336 / 2,219 02:42:39 INFO - TinderboxPrint: CPU idle
2,194.3 (45.3%) 02:42:39 INFO - TinderboxPrint: CPU system
983.0 (20.3%) 02:42:39 INFO - TinderboxPrint: CPU user
1,668.4 (34.4%) 02:42:39 INFO - TinderboxPrint: Swap in / out
408,842,240 / 0 02:42:39 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 399907328; Write bytes: 395319296; Read time: 29476; Write time: 905 02:42:39 INFO - run-tests - Wall time: 1178s; CPU: 55.0%; Read bytes: 1250816; Write bytes: 300791808; Read time: 12; Write time: 1274 02:42:39 INFO - Running post-run listener: _upload_blobber_files 02:42:39 INFO - Blob upload gear active. 02:42:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:42:39 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 02:42:39 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:42:39 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:42:40 INFO - (blobuploader) - INFO - Open directory for files ... 02:42:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:42:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:42:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:42:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:42:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:42:40 INFO - (blobuploader) - INFO - Done attempting. 02:42:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:42:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:42:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:42:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:42:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:42:41 INFO - (blobuploader) - INFO - Done attempting. 02:42:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:42:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:42:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:42:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:42:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:42:44 INFO - (blobuploader) - INFO - Done attempting. 02:42:44 INFO - (blobuploader) - INFO - Iteration through files over. 02:42:44 INFO - Return code: 0 02:42:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:42:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:42:44 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1e5ea76c15a73eabb2fa8ce5a5b2f3fc4f9bcd399af5ad0a3d9bb3234a3cf28c6dc81ba1b864b46c7329a6581d9dc1e9266a3853ac77c4d11b4066dd2ccd03a6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c7a31c775c0a45f713c5ca14268491f705adda9e66b65c38df23213fbb98e8071415c7aa1a6915265cd3c158161644d65e6d73dd954cefccdda560185ac2bc18", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a4faf4aff509bc7a46042ca72848aec1b1aab3f03e864283d4d8db6ed997926b955adf061f59020a30a9ebdf743b2d28c5986614740e13798bb799eb6a0ecb09"} 02:42:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:42:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:42:44 INFO - Contents: 02:42:44 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1e5ea76c15a73eabb2fa8ce5a5b2f3fc4f9bcd399af5ad0a3d9bb3234a3cf28c6dc81ba1b864b46c7329a6581d9dc1e9266a3853ac77c4d11b4066dd2ccd03a6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c7a31c775c0a45f713c5ca14268491f705adda9e66b65c38df23213fbb98e8071415c7aa1a6915265cd3c158161644d65e6d73dd954cefccdda560185ac2bc18", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a4faf4aff509bc7a46042ca72848aec1b1aab3f03e864283d4d8db6ed997926b955adf061f59020a30a9ebdf743b2d28c5986614740e13798bb799eb6a0ecb09"} 02:42:44 INFO - Running post-run listener: copy_logs_to_upload_dir 02:42:44 INFO - Copying logs to upload dir... 02:42:44 INFO - mkdir: /builds/slave/test/build/upload/logs 02:42:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.741904 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-10-10 02:42:44.182364) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-10 02:42:44.185764) ========= 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.KlCpefsbxK/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.mznUvlfXJn/Listeners TMPDIR=/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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/Autoland/sha512/1e5ea76c15a73eabb2fa8ce5a5b2f3fc4f9bcd399af5ad0a3d9bb3234a3cf28c6dc81ba1b864b46c7329a6581d9dc1e9266a3853ac77c4d11b4066dd2ccd03a6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c7a31c775c0a45f713c5ca14268491f705adda9e66b65c38df23213fbb98e8071415c7aa1a6915265cd3c158161644d65e6d73dd954cefccdda560185ac2bc18", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a4faf4aff509bc7a46042ca72848aec1b1aab3f03e864283d4d8db6ed997926b955adf061f59020a30a9ebdf743b2d28c5986614740e13798bb799eb6a0ecb09"} build_url:https://queue.taskcluster.net/v1/task/HC7Otaw-QreY8xmm1Z6umw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011319 build_url: 'https://queue.taskcluster.net/v1/task/HC7Otaw-QreY8xmm1Z6umw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1e5ea76c15a73eabb2fa8ce5a5b2f3fc4f9bcd399af5ad0a3d9bb3234a3cf28c6dc81ba1b864b46c7329a6581d9dc1e9266a3853ac77c4d11b4066dd2ccd03a6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c7a31c775c0a45f713c5ca14268491f705adda9e66b65c38df23213fbb98e8071415c7aa1a6915265cd3c158161644d65e6d73dd954cefccdda560185ac2bc18", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a4faf4aff509bc7a46042ca72848aec1b1aab3f03e864283d4d8db6ed997926b955adf061f59020a30a9ebdf743b2d28c5986614740e13798bb799eb6a0ecb09"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-10 02:42:44.218594) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 02:42:44.218899) ========= 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.KlCpefsbxK/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.mznUvlfXJn/Listeners TMPDIR=/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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.005283 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 02:42:44.267059) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-10 02:42:44.267371) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-10 02:42:44.267764) ========= ========= Total master_lag: 0.18 =========