builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-023 starttime: 1482424226.54 results: success (0) buildid: 20161222071954 builduid: 95c3c354216a4947abb48dea7e0fd854 revision: 8460203bc93b9667cea1bc00f9d9990a4b1a9474 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-22 08:30:26.540152) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-22 08:30:26.540587) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-22 08:30:26.540877) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-22 08:30:26.555195) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 08:30:26.555479) ========= 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: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=6d15e502d8e29d5d2676d664000001c3-1482424226.450401-90361196 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-22 08:30:27-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 113M=0s 2016-12-22 08:30:27 (113 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.082406 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 08:30:26.651866) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 08:30:26.652176) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=6d15e502d8e29d5d2676d664000001c3-1482424226.450401-90361196 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017747 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 08:30:26.701570) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 08:30:26.701936) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8460203bc93b9667cea1bc00f9d9990a4b1a9474 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 8460203bc93b9667cea1bc00f9d9990a4b1a9474 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=6d15e502d8e29d5d2676d664000001c3-1482424226.450401-90361196 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-22 08:30:27,438 truncating revision to first 12 chars 2016-12-22 08:30:27,438 Setting DEBUG logging. 2016-12-22 08:30:27,438 attempt 1/10 2016-12-22 08:30:27,438 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8460203bc93b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-22 08:30:27,747 unpacking tar archive at: mozilla-central-8460203bc93b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.628004 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 08:30:27.343244) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-22 08:30:27.343574) ========= script_repo_revision: 8460203bc93b9667cea1bc00f9d9990a4b1a9474 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-22 08:30:27.343947) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-22 08:30:27.344210) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-22 08:30:27.352216) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-12-22 08:30:27.352480) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 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', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=6d15e502d8e29d5d2676d664000001c3-1482424226.450401-90361196 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:30:28 INFO - MultiFileLogger online at 20161222 08:30:28 in /builds/slave/test 08:30:28 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 08:30:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:30:28 INFO - {'append_to_log': False, 08:30:28 INFO - 'base_work_dir': '/builds/slave/test', 08:30:28 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 08:30:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:30:28 INFO - 'branch': 'Firefox-Non-PGO', 08:30:28 INFO - 'buildbot_json_path': 'buildprops.json', 08:30:28 INFO - 'config_files': ('talos/linux_config.py',), 08:30:28 INFO - 'default_actions': ('clobber', 08:30:28 INFO - 'read-buildbot-config', 08:30:28 INFO - 'download-and-extract', 08:30:28 INFO - 'populate-webroot', 08:30:28 INFO - 'create-virtualenv', 08:30:28 INFO - 'install', 08:30:28 INFO - 'run-tests'), 08:30:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:30:28 INFO - 'download_minidump_stackwalk': True, 08:30:28 INFO - 'download_symbols': 'ondemand', 08:30:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:30:28 INFO - 'tooltool.py': '/tools/tooltool.py', 08:30:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:30:28 INFO - '/tools/misc-python/virtualenv.py')}, 08:30:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:30:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:30:28 INFO - 'installer_path': 'installer.exe', 08:30:28 INFO - 'log_level': 'info', 08:30:28 INFO - 'log_name': 'talos', 08:30:28 INFO - 'log_to_console': True, 08:30:28 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:30:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:30:28 INFO - 'opt_config_files': (), 08:30:28 INFO - 'pip_index': False, 08:30:28 INFO - 'sps_profile': False, 08:30:28 INFO - 'sps_profile_interval': 0, 08:30:28 INFO - 'suite': 'dromaeojs-e10s', 08:30:28 INFO - 'system_bits': '32', 08:30:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:30:28 INFO - 'title': 'talos-linux64-ix-023', 08:30:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:30:28 INFO - 'use_talos_json': True, 08:30:28 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:30:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:30:28 INFO - 'work_dir': 'build'} 08:30:28 INFO - [mozharness: 2016-12-22 16:30:28.180950Z] Running clobber step. 08:30:28 INFO - Running pre-action listener: _resource_record_pre_action 08:30:28 INFO - Running main action method: clobber 08:30:28 INFO - rmtree: /builds/slave/test/build 08:30:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:30:28 INFO - Running post-action listener: _resource_record_post_action 08:30:28 INFO - [mozharness: 2016-12-22 16:30:28.653393Z] Finished clobber step (success) 08:30:28 INFO - [mozharness: 2016-12-22 16:30:28.653494Z] Running read-buildbot-config step. 08:30:28 INFO - Running pre-action listener: _resource_record_pre_action 08:30:28 INFO - Running main action method: read_buildbot_config 08:30:28 INFO - Using buildbot properties: 08:30:28 INFO - { 08:30:28 INFO - "project": "", 08:30:28 INFO - "product": "firefox", 08:30:28 INFO - "script_repo_revision": "production", 08:30:28 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 08:30:28 INFO - "repository": "", 08:30:28 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 08:30:28 INFO - "buildid": "20161222071954", 08:30:28 INFO - "pgo_build": "False", 08:30:28 INFO - "basedir": "/builds/slave/test", 08:30:28 INFO - "buildnumber": 584, 08:30:28 INFO - "slavename": "talos-linux64-ix-023", 08:30:28 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 08:30:28 INFO - "platform": "ubuntu64_hw", 08:30:28 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 08:30:28 INFO - "branch": "mozilla-central", 08:30:28 INFO - "repo_path": "mozilla-central", 08:30:28 INFO - "revision": "8460203bc93b9667cea1bc00f9d9990a4b1a9474", 08:30:28 INFO - "stage_platform": "linux64", 08:30:28 INFO - "builduid": "95c3c354216a4947abb48dea7e0fd854", 08:30:28 INFO - "slavebuilddir": "test" 08:30:28 INFO - } 08:30:28 INFO - Found installer url https://queue.taskcluster.net/v1/task/c881nCb6TfG6JvqCivK3oA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 08:30:28 INFO - Running post-action listener: _resource_record_post_action 08:30:28 INFO - [mozharness: 2016-12-22 16:30:28.655955Z] Finished read-buildbot-config step (success) 08:30:28 INFO - [mozharness: 2016-12-22 16:30:28.656044Z] Running download-and-extract step. 08:30:28 INFO - Running pre-action listener: _resource_record_pre_action 08:30:28 INFO - Running main action method: download_and_extract 08:30:28 INFO - mkdir: /builds/slave/test/build/tests 08:30:28 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')]} 08:30:28 INFO - trying https://queue.taskcluster.net/v1/task/c881nCb6TfG6JvqCivK3oA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 08:30:28 INFO - Downloading https://queue.taskcluster.net/v1/task/c881nCb6TfG6JvqCivK3oA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 08:30:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/c881nCb6TfG6JvqCivK3oA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 08:30:29 INFO - Downloaded 1361 bytes. 08:30:29 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 08:30:29 INFO - Using the following test package requirements: 08:30:29 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 08:30:29 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 08:30:29 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 08:30:29 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 08:30:29 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 08:30:29 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 08:30:29 INFO - u'jsshell-linux-x86_64.zip'], 08:30:29 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 08:30:29 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 08:30:29 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 08:30:29 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 08:30:29 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 08:30:29 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 08:30:29 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 08:30:29 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 08:30:29 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 08:30:29 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 08:30:29 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 08:30:29 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:30:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/c881nCb6TfG6JvqCivK3oA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 08:30:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/c881nCb6TfG6JvqCivK3oA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:30:29 INFO - Fetch https://queue.taskcluster.net/v1/task/c881nCb6TfG6JvqCivK3oA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 08:30:30 INFO - Expected file size: 20272629 08:30:30 INFO - Obtained file size: 20272629 08:30:33 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 08:30:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/c881nCb6TfG6JvqCivK3oA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 08:30:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/c881nCb6TfG6JvqCivK3oA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:30:33 INFO - Fetch https://queue.taskcluster.net/v1/task/c881nCb6TfG6JvqCivK3oA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 08:30:34 INFO - Expected file size: 20272629 08:30:34 INFO - Obtained file size: 20272629 08:30:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/c881nCb6TfG6JvqCivK3oA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 08:30:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/c881nCb6TfG6JvqCivK3oA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 08:30:39 INFO - Fetch https://queue.taskcluster.net/v1/task/c881nCb6TfG6JvqCivK3oA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 08:30:39 INFO - Expected file size: 14409776 08:30:39 INFO - Obtained file size: 14409776 08:30:40 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')]} 08:30:40 INFO - trying https://queue.taskcluster.net/v1/task/c881nCb6TfG6JvqCivK3oA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 08:30:40 INFO - Downloading https://queue.taskcluster.net/v1/task/c881nCb6TfG6JvqCivK3oA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 08:30:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/c881nCb6TfG6JvqCivK3oA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 08:30:43 INFO - Downloaded 55745078 bytes. 08:30:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/c881nCb6TfG6JvqCivK3oA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 08:30:43 INFO - mkdir: /builds/slave/test/properties 08:30:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:30:43 INFO - Writing to file /builds/slave/test/properties/build_url 08:30:43 INFO - Contents: 08:30:43 INFO - build_url:https://queue.taskcluster.net/v1/task/c881nCb6TfG6JvqCivK3oA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 08:30:44 INFO - Running post-action listener: _resource_record_post_action 08:30:44 INFO - Running post-action listener: set_extra_try_arguments 08:30:44 INFO - [mozharness: 2016-12-22 16:30:44.362310Z] Finished download-and-extract step (success) 08:30:44 INFO - [mozharness: 2016-12-22 16:30:44.362538Z] Running populate-webroot step. 08:30:44 INFO - Running pre-action listener: _resource_record_pre_action 08:30:44 INFO - Running main action method: populate_webroot 08:30:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:30:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:30:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:30:44 INFO - '--tppagecycles', 08:30:44 INFO - '7'], 08:30:44 INFO - 'tests': ['tsvgm']}}, 08:30:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:30:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:30:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:30:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:30:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:30:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:30:44 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:30:44 INFO - 'talos_options': ['--disable-e10s'], 08:30:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:30:44 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:30:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:30:44 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:30:44 INFO - 'talos_options': ['--disable-e10s'], 08:30:44 INFO - 'tests': ['damp', 'tps']}, 08:30:44 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:30:44 INFO - 'tests': ['damp', 'tps']}, 08:30:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:30:44 INFO - 'tests': ['dromaeo_dom']}, 08:30:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:30:44 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:30:44 INFO - 'tests': ['basic_compositor_video']}, 08:30:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:30:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:30:44 INFO - 'tests': ['a11yr', 08:30:44 INFO - 'ts_paint', 08:30:44 INFO - 'tpaint', 08:30:44 INFO - 'sessionrestore', 08:30:44 INFO - 'sessionrestore_no_auto_restore', 08:30:44 INFO - 'tabpaint']}, 08:30:44 INFO - 'other-e10s': {'tests': ['a11yr', 08:30:44 INFO - 'ts_paint', 08:30:44 INFO - 'tpaint', 08:30:44 INFO - 'sessionrestore', 08:30:44 INFO - 'sessionrestore_no_auto_restore', 08:30:44 INFO - 'tabpaint']}, 08:30:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:30:44 INFO - 'ts_paint', 08:30:44 INFO - 'tpaint', 08:30:44 INFO - 'sessionrestore', 08:30:44 INFO - 'sessionrestore_no_auto_restore', 08:30:44 INFO - 'tabpaint']}, 08:30:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:30:44 INFO - 'ts_paint', 08:30:44 INFO - 'tpaint', 08:30:44 INFO - 'sessionrestore', 08:30:44 INFO - 'sessionrestore_no_auto_restore', 08:30:44 INFO - 'tabpaint']}, 08:30:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:30:44 INFO - 'tests': ['a11yr', 08:30:44 INFO - 'ts_paint', 08:30:44 INFO - 'tpaint', 08:30:44 INFO - 'sessionrestore', 08:30:44 INFO - 'sessionrestore_no_auto_restore', 08:30:44 INFO - 'tabpaint']}, 08:30:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:30:44 INFO - 'tests': ['a11yr', 08:30:44 INFO - 'ts_paint', 08:30:44 INFO - 'tpaint', 08:30:44 INFO - 'sessionrestore', 08:30:44 INFO - 'sessionrestore_no_auto_restore', 08:30:44 INFO - 'tabpaint']}, 08:30:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:30:44 INFO - 'tests': ['tsvgx', 08:30:44 INFO - 'tsvgr_opacity', 08:30:44 INFO - 'tart', 08:30:44 INFO - 'tscrollx', 08:30:44 INFO - 'cart', 08:30:44 INFO - 'tsvg_static']}, 08:30:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:30:44 INFO - 'tsvgr_opacity', 08:30:44 INFO - 'tart', 08:30:44 INFO - 'tscrollx', 08:30:44 INFO - 'cart', 08:30:44 INFO - 'tsvg_static']}, 08:30:44 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:30:44 INFO - 'talos_options': ['--disable-e10s'], 08:30:44 INFO - 'tests': ['tp5o']}, 08:30:44 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:30:44 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:30:44 INFO - 'talos_options': ['--disable-e10s', 08:30:44 INFO - '--xperf_path', 08:30:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:30:44 INFO - 'tests': ['tp5n']}, 08:30:44 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:30:44 INFO - 'talos_options': ['--xperf_path', 08:30:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:30:44 INFO - 'tests': ['tp5n']}}, 08:30:44 INFO - 'talos.zip': {'path': '', 08:30:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:30:44 INFO - Running post-action listener: _resource_record_post_action 08:30:44 INFO - [mozharness: 2016-12-22 16:30:44.384553Z] Finished populate-webroot step (success) 08:30:44 INFO - [mozharness: 2016-12-22 16:30:44.384645Z] Running create-virtualenv step. 08:30:44 INFO - Running pre-action listener: _resource_record_pre_action 08:30:44 INFO - Running main action method: create_virtualenv 08:30:44 INFO - Creating virtualenv /builds/slave/test/build/venv 08:30:44 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 08:30:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:30:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:30:44 INFO - Using real prefix '/usr' 08:30:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:30:45 INFO - Installing distribute.............................................................................................................................................................................................done. 08:30:46 INFO - Installing pip.................done. 08:30:46 INFO - Return code: 0 08:30:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:30:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:30:47 INFO - Reading from file tmpfile_stdout 08:30:47 INFO - Output received: 08:30:47 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 08:30:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:30:47 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')]} 08:30:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:47 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')]} 08:30:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:47 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 0x21371f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2248a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24a9480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a6820>, '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 0x24a7520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24a79b0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1482424226.450401-90361196', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:30:47 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 08:30:47 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 08:30:47 INFO - Using env: {'DISPLAY': ':0', 08:30:47 INFO - 'HOME': '/home/cltbld', 08:30:47 INFO - 'LANG': 'en_US.UTF-8', 08:30:47 INFO - 'LANGUAGE': 'en_US:en', 08:30:47 INFO - 'LOGNAME': 'cltbld', 08:30:47 INFO - 'MAIL': '/var/mail/cltbld', 08:30:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:47 INFO - 'MOZ_NO_REMOTE': '1', 08:30:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:30:47 INFO - 'NO_EM_RESTART': '1', 08:30:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:30:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:47 INFO - 'PWD': '/builds/slave/test', 08:30:47 INFO - 'SHELL': '/bin/bash', 08:30:47 INFO - 'SHLVL': '1', 08:30:47 INFO - 'TERM': 'linux', 08:30:47 INFO - 'TMOUT': '86400', 08:30:47 INFO - 'USER': 'cltbld', 08:30:47 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1482424226.450401-90361196', 08:30:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:47 INFO - '_': '/tools/buildbot/bin/python'} 08:30:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:47 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 08:30:47 INFO - Cleaning up... 08:30:47 INFO - Return code: 0 08:30:47 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:30:47 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')]} 08:30:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:47 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')]} 08:30:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:47 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 0x21371f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2248a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24a9480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a6820>, '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 0x24a7520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24a79b0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1482424226.450401-90361196', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:30:47 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 08:30:47 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 08:30:47 INFO - Using env: {'DISPLAY': ':0', 08:30:47 INFO - 'HOME': '/home/cltbld', 08:30:47 INFO - 'LANG': 'en_US.UTF-8', 08:30:47 INFO - 'LANGUAGE': 'en_US:en', 08:30:47 INFO - 'LOGNAME': 'cltbld', 08:30:47 INFO - 'MAIL': '/var/mail/cltbld', 08:30:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:47 INFO - 'MOZ_NO_REMOTE': '1', 08:30:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:30:47 INFO - 'NO_EM_RESTART': '1', 08:30:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:30:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:47 INFO - 'PWD': '/builds/slave/test', 08:30:47 INFO - 'SHELL': '/bin/bash', 08:30:47 INFO - 'SHLVL': '1', 08:30:47 INFO - 'TERM': 'linux', 08:30:47 INFO - 'TMOUT': '86400', 08:30:47 INFO - 'USER': 'cltbld', 08:30:47 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1482424226.450401-90361196', 08:30:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:47 INFO - '_': '/tools/buildbot/bin/python'} 08:30:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:47 INFO - Downloading/unpacking psutil>=3.1.1 08:30:47 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 08:30:47 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 08:30:47 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 08:30:47 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 08:30:47 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 08:30:47 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 08:30:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:30:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:30:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:30:49 INFO - Installing collected packages: psutil 08:30:49 INFO - Running setup.py install for psutil 08:30:49 INFO - building 'psutil._psutil_linux' extension 08:30:49 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 08:30:49 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 08:30:50 INFO - building 'psutil._psutil_posix' extension 08:30:50 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 08:30:50 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 08:30:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:30:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:30:50 INFO - Successfully installed psutil 08:30:50 INFO - Cleaning up... 08:30:50 INFO - Return code: 0 08:30:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:30:50 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')]} 08:30:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:50 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')]} 08:30:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x21371f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2248a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24a9480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a6820>, '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 0x24a7520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24a79b0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1482424226.450401-90361196', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:30:50 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 08:30:50 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 08:30:50 INFO - Using env: {'DISPLAY': ':0', 08:30:50 INFO - 'HOME': '/home/cltbld', 08:30:50 INFO - 'LANG': 'en_US.UTF-8', 08:30:50 INFO - 'LANGUAGE': 'en_US:en', 08:30:50 INFO - 'LOGNAME': 'cltbld', 08:30:50 INFO - 'MAIL': '/var/mail/cltbld', 08:30:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:50 INFO - 'MOZ_NO_REMOTE': '1', 08:30:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:30:50 INFO - 'NO_EM_RESTART': '1', 08:30:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:30:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:50 INFO - 'PWD': '/builds/slave/test', 08:30:50 INFO - 'SHELL': '/bin/bash', 08:30:50 INFO - 'SHLVL': '1', 08:30:50 INFO - 'TERM': 'linux', 08:30:50 INFO - 'TMOUT': '86400', 08:30:50 INFO - 'USER': 'cltbld', 08:30:50 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1482424226.450401-90361196', 08:30:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:50 INFO - '_': '/tools/buildbot/bin/python'} 08:30:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:30:50 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 08:30:50 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 08:30:50 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 08:30:50 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 08:30:50 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 08:30:50 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 08:30:52 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:30:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:30:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:30:52 INFO - Installing collected packages: mozsystemmonitor 08:30:52 INFO - Running setup.py install for mozsystemmonitor 08:30:52 INFO - Successfully installed mozsystemmonitor 08:30:52 INFO - Cleaning up... 08:30:52 INFO - Return code: 0 08:30:52 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:30:52 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')]} 08:30:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:52 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')]} 08:30:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:52 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 0x21371f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2248a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24a9480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a6820>, '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 0x24a7520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24a79b0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1482424226.450401-90361196', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:30:52 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 08:30:52 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 08:30:52 INFO - Using env: {'DISPLAY': ':0', 08:30:52 INFO - 'HOME': '/home/cltbld', 08:30:52 INFO - 'LANG': 'en_US.UTF-8', 08:30:52 INFO - 'LANGUAGE': 'en_US:en', 08:30:52 INFO - 'LOGNAME': 'cltbld', 08:30:52 INFO - 'MAIL': '/var/mail/cltbld', 08:30:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:52 INFO - 'MOZ_NO_REMOTE': '1', 08:30:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:30:52 INFO - 'NO_EM_RESTART': '1', 08:30:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:30:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:52 INFO - 'PWD': '/builds/slave/test', 08:30:52 INFO - 'SHELL': '/bin/bash', 08:30:52 INFO - 'SHLVL': '1', 08:30:52 INFO - 'TERM': 'linux', 08:30:52 INFO - 'TMOUT': '86400', 08:30:52 INFO - 'USER': 'cltbld', 08:30:52 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1482424226.450401-90361196', 08:30:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:52 INFO - '_': '/tools/buildbot/bin/python'} 08:30:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:52 INFO - Downloading/unpacking jsonschema==2.5.1 08:30:52 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 08:30:52 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 08:30:52 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 08:30:52 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 08:30:52 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 08:30:52 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 08:30:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:30:54 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:30:54 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 08:30:54 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 08:30:54 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 08:30:54 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 08:30:54 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 08:30:54 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 08:30:55 INFO - Downloading functools32-3.2.3-2.tar.gz 08:30:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:30:55 INFO - warning: no files found matching '*.txt' 08:30:55 INFO - no previously-included directories found matching 'build' 08:30:55 INFO - no previously-included directories found matching 'dist' 08:30:55 INFO - no previously-included directories found matching '.git*' 08:30:55 INFO - Installing collected packages: jsonschema, functools32 08:30:55 INFO - Running setup.py install for functools32 08:30:55 INFO - warning: no files found matching '*.txt' 08:30:55 INFO - no previously-included directories found matching 'build' 08:30:55 INFO - no previously-included directories found matching 'dist' 08:30:55 INFO - no previously-included directories found matching '.git*' 08:30:55 INFO - Successfully installed jsonschema functools32 08:30:55 INFO - Cleaning up... 08:30:55 INFO - Return code: 0 08:30:55 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:30:55 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')]} 08:30:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:55 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')]} 08:30:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:55 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 0x21371f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2248a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24a9480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a6820>, '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 0x24a7520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24a79b0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1482424226.450401-90361196', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:30:55 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 08:30:55 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 08:30:55 INFO - Using env: {'DISPLAY': ':0', 08:30:55 INFO - 'HOME': '/home/cltbld', 08:30:55 INFO - 'LANG': 'en_US.UTF-8', 08:30:55 INFO - 'LANGUAGE': 'en_US:en', 08:30:55 INFO - 'LOGNAME': 'cltbld', 08:30:55 INFO - 'MAIL': '/var/mail/cltbld', 08:30:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:55 INFO - 'MOZ_NO_REMOTE': '1', 08:30:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:30:55 INFO - 'NO_EM_RESTART': '1', 08:30:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:30:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:55 INFO - 'PWD': '/builds/slave/test', 08:30:55 INFO - 'SHELL': '/bin/bash', 08:30:55 INFO - 'SHLVL': '1', 08:30:55 INFO - 'TERM': 'linux', 08:30:55 INFO - 'TMOUT': '86400', 08:30:55 INFO - 'USER': 'cltbld', 08:30:55 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1482424226.450401-90361196', 08:30:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:55 INFO - '_': '/tools/buildbot/bin/python'} 08:30:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:55 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:30:55 INFO - Cleaning up... 08:30:55 INFO - Return code: 0 08:30:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:30:55 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')]} 08:30:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:55 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')]} 08:30:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:55 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 0x21371f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2248a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24a9480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a6820>, '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 0x24a7520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24a79b0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1482424226.450401-90361196', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:30:55 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 08:30:55 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 08:30:55 INFO - Using env: {'DISPLAY': ':0', 08:30:55 INFO - 'HOME': '/home/cltbld', 08:30:55 INFO - 'LANG': 'en_US.UTF-8', 08:30:55 INFO - 'LANGUAGE': 'en_US:en', 08:30:55 INFO - 'LOGNAME': 'cltbld', 08:30:55 INFO - 'MAIL': '/var/mail/cltbld', 08:30:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:55 INFO - 'MOZ_NO_REMOTE': '1', 08:30:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:30:55 INFO - 'NO_EM_RESTART': '1', 08:30:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:30:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:55 INFO - 'PWD': '/builds/slave/test', 08:30:55 INFO - 'SHELL': '/bin/bash', 08:30:55 INFO - 'SHLVL': '1', 08:30:55 INFO - 'TERM': 'linux', 08:30:55 INFO - 'TMOUT': '86400', 08:30:55 INFO - 'USER': 'cltbld', 08:30:55 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1482424226.450401-90361196', 08:30:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:55 INFO - '_': '/tools/buildbot/bin/python'} 08:30:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:55 INFO - Downloading/unpacking blobuploader==1.2.4 08:30:55 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 08:30:55 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 08:30:55 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 08:30:55 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 08:30:55 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 08:30:55 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 08:30:57 INFO - Downloading blobuploader-1.2.4.tar.gz 08:30:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:30:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:30:57 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 08:30:57 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 08:30:57 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 08:30:57 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 08:30:57 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 08:30:57 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 08:30:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:30:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:30:58 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 08:30:58 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 08:30:58 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 08:30:58 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 08:30:58 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 08:30:58 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 08:30:58 INFO - Downloading docopt-0.6.1.tar.gz 08:30:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:30:58 INFO - Installing collected packages: blobuploader, requests, docopt 08:30:58 INFO - Running setup.py install for blobuploader 08:30:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:30:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:30:58 INFO - Running setup.py install for requests 08:30:59 INFO - Running setup.py install for docopt 08:30:59 INFO - Successfully installed blobuploader requests docopt 08:30:59 INFO - Cleaning up... 08:30:59 INFO - Return code: 0 08:30:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:30: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')]} 08:30:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30: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')]} 08:30:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:59 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 0x21371f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2248a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24a9480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a6820>, '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 0x24a7520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24a79b0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1482424226.450401-90361196', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:30:59 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 08:30:59 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 08:30:59 INFO - Using env: {'DISPLAY': ':0', 08:30:59 INFO - 'HOME': '/home/cltbld', 08:30:59 INFO - 'LANG': 'en_US.UTF-8', 08:30:59 INFO - 'LANGUAGE': 'en_US:en', 08:30:59 INFO - 'LOGNAME': 'cltbld', 08:30:59 INFO - 'MAIL': '/var/mail/cltbld', 08:30:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:59 INFO - 'MOZ_NO_REMOTE': '1', 08:30:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:30:59 INFO - 'NO_EM_RESTART': '1', 08:30:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:30:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:59 INFO - 'PWD': '/builds/slave/test', 08:30:59 INFO - 'SHELL': '/bin/bash', 08:30:59 INFO - 'SHLVL': '1', 08:30:59 INFO - 'TERM': 'linux', 08:30:59 INFO - 'TMOUT': '86400', 08:30:59 INFO - 'USER': 'cltbld', 08:30:59 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1482424226.450401-90361196', 08:30:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:59 INFO - '_': '/tools/buildbot/bin/python'} 08:30:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:30:59 INFO - Running setup.py (path:/tmp/pip-tDHMoH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:30:59 INFO - Running setup.py (path:/tmp/pip-uWO8ir-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:30:59 INFO - Running setup.py (path:/tmp/pip-4dhu3D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:30:59 INFO - Running setup.py (path:/tmp/pip-Kpu2Cq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:30:59 INFO - Running setup.py (path:/tmp/pip-j7yXfO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:30:59 INFO - Running setup.py (path:/tmp/pip-m6Ivo8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:31:00 INFO - Running setup.py (path:/tmp/pip-nf8lay-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:31:00 INFO - Running setup.py (path:/tmp/pip-LTP27n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:31:00 INFO - Running setup.py (path:/tmp/pip-MobAuF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:31:00 INFO - Running setup.py (path:/tmp/pip-hCbZoM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:31:00 INFO - Running setup.py (path:/tmp/pip-_ENcSk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:31:00 INFO - Running setup.py (path:/tmp/pip-AhF88g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:31:00 INFO - Running setup.py (path:/tmp/pip-uw35mu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:31:00 INFO - Running setup.py (path:/tmp/pip-mO8D_5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:31:00 INFO - Running setup.py (path:/tmp/pip-6W3Y8W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:31:00 INFO - Running setup.py (path:/tmp/pip-KFbFe_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:31:00 INFO - Running setup.py (path:/tmp/pip-pqQiZO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:31:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:31:00 INFO - Running setup.py install for manifestparser 08:31:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:31:00 INFO - Running setup.py install for mozcrash 08:31:00 INFO - Running setup.py install for mozdebug 08:31:01 INFO - Running setup.py install for mozdevice 08:31:01 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:31:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:31:01 INFO - Running setup.py install for mozfile 08:31:01 INFO - Running setup.py install for mozhttpd 08:31:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:31:01 INFO - Running setup.py install for mozinfo 08:31:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:31:01 INFO - Running setup.py install for mozInstall 08:31:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:31:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:31:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:31:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:31:01 INFO - Running setup.py install for mozleak 08:31:01 INFO - Running setup.py install for mozlog 08:31:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:31:01 INFO - Running setup.py install for moznetwork 08:31:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:31:01 INFO - Running setup.py install for mozprocess 08:31:02 INFO - Running setup.py install for mozprofile 08:31:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:31:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:31:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:31:02 INFO - Running setup.py install for mozrunner 08:31:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:31:02 INFO - Running setup.py install for mozscreenshot 08:31:02 INFO - Running setup.py install for moztest 08:31:02 INFO - Running setup.py install for mozversion 08:31:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:31:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:31:02 INFO - Cleaning up... 08:31:02 INFO - Return code: 0 08:31:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:31:02 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')]} 08:31:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:02 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')]} 08:31:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:02 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 0x21371f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2248a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24a9480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a6820>, '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 0x24a7520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24a79b0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1482424226.450401-90361196', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:31:02 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 08:31:02 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 08:31:02 INFO - Using env: {'DISPLAY': ':0', 08:31:02 INFO - 'HOME': '/home/cltbld', 08:31:02 INFO - 'LANG': 'en_US.UTF-8', 08:31:02 INFO - 'LANGUAGE': 'en_US:en', 08:31:02 INFO - 'LOGNAME': 'cltbld', 08:31:02 INFO - 'MAIL': '/var/mail/cltbld', 08:31:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:02 INFO - 'MOZ_NO_REMOTE': '1', 08:31:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:31:02 INFO - 'NO_EM_RESTART': '1', 08:31:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:31:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:02 INFO - 'PWD': '/builds/slave/test', 08:31:02 INFO - 'SHELL': '/bin/bash', 08:31:02 INFO - 'SHLVL': '1', 08:31:02 INFO - 'TERM': 'linux', 08:31:02 INFO - 'TMOUT': '86400', 08:31:02 INFO - 'USER': 'cltbld', 08:31:02 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1482424226.450401-90361196', 08:31:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:02 INFO - '_': '/tools/buildbot/bin/python'} 08:31:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:31:02 INFO - Running setup.py (path:/tmp/pip-HrvSa0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:31:02 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)) 08:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:31:02 INFO - Running setup.py (path:/tmp/pip-JpbYAY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:31:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:31:02 INFO - Running setup.py (path:/tmp/pip-yAgT0B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:31:03 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)) 08:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:31:03 INFO - Running setup.py (path:/tmp/pip-9tKEMD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:31:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 08:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:31:03 INFO - Running setup.py (path:/tmp/pip-I8gqmo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:31:03 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)) 08:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:31:03 INFO - Running setup.py (path:/tmp/pip-Lna37A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:31:03 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)) 08:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:31:03 INFO - Running setup.py (path:/tmp/pip-Au0T5X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:31:03 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)) 08:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:31:03 INFO - Running setup.py (path:/tmp/pip-Ad7nMz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:31:03 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)) 08:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:31:03 INFO - Running setup.py (path:/tmp/pip-gf5Qqb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:31:03 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)) 08:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:31:03 INFO - Running setup.py (path:/tmp/pip-Bgj0ew-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:31:03 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)) 08:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:31:03 INFO - Running setup.py (path:/tmp/pip-al3XV4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:31:03 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)) 08:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:31:03 INFO - Running setup.py (path:/tmp/pip-3GcIrZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:31:03 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)) 08:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:31:03 INFO - Running setup.py (path:/tmp/pip-WU7ddB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:31:03 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)) 08:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:31:03 INFO - Running setup.py (path:/tmp/pip-95JpvH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:31:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:31:03 INFO - Running setup.py (path:/tmp/pip-4Hg2Nj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:31:03 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)) 08:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:31:03 INFO - Running setup.py (path:/tmp/pip-l7aDgT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:31:04 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)) 08:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:31:04 INFO - Running setup.py (path:/tmp/pip-m750dy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:31:04 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)) 08:31:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:31:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:31:04 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)) 08:31:04 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:31:04 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:31:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:31:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:31:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:31:04 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 08:31:04 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 08:31:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:31:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:31:06 INFO - Downloading blessings-1.6.tar.gz 08:31:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:31:06 INFO - Installing collected packages: blessings 08:31:06 INFO - Running setup.py install for blessings 08:31:06 INFO - Successfully installed blessings 08:31:06 INFO - Cleaning up... 08:31:06 INFO - Return code: 0 08:31:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:31:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:31:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:31:06 INFO - Reading from file tmpfile_stdout 08:31:06 INFO - Current package versions: 08:31:06 INFO - argparse == 1.2.1 08:31:06 INFO - blessings == 1.6 08:31:06 INFO - blobuploader == 1.2.4 08:31:06 INFO - docopt == 0.6.1 08:31:06 INFO - functools32 == 3.2.3-2 08:31:06 INFO - jsonschema == 2.5.1 08:31:06 INFO - manifestparser == 1.1 08:31:06 INFO - mozInstall == 1.12 08:31:06 INFO - mozcrash == 1.0 08:31:06 INFO - mozdebug == 0.1 08:31:06 INFO - mozdevice == 0.49 08:31:06 INFO - mozfile == 1.2 08:31:06 INFO - mozhttpd == 0.7 08:31:06 INFO - mozinfo == 0.9 08:31:06 INFO - mozleak == 0.1 08:31:06 INFO - mozlog == 3.4 08:31:06 INFO - moznetwork == 0.27 08:31:06 INFO - mozprocess == 0.23 08:31:06 INFO - mozprofile == 0.28 08:31:06 INFO - mozrunner == 6.13 08:31:06 INFO - mozscreenshot == 0.1 08:31:06 INFO - mozsystemmonitor == 0.3 08:31:06 INFO - moztest == 0.7 08:31:06 INFO - mozversion == 1.4 08:31:06 INFO - psutil == 3.1.1 08:31:06 INFO - requests == 1.2.3 08:31:06 INFO - wsgiref == 0.1.2 08:31:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:31:06 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')]} 08:31:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:06 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')]} 08:31:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:06 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 0x21371f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2248a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24a9480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a6820>, '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 0x24a7520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24a79b0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1482424226.450401-90361196', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:31:06 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 08:31:06 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 08:31:06 INFO - Using env: {'DISPLAY': ':0', 08:31:06 INFO - 'HOME': '/home/cltbld', 08:31:06 INFO - 'LANG': 'en_US.UTF-8', 08:31:06 INFO - 'LANGUAGE': 'en_US:en', 08:31:06 INFO - 'LOGNAME': 'cltbld', 08:31:06 INFO - 'MAIL': '/var/mail/cltbld', 08:31:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:06 INFO - 'MOZ_NO_REMOTE': '1', 08:31:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:31:06 INFO - 'NO_EM_RESTART': '1', 08:31:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:31:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:06 INFO - 'PWD': '/builds/slave/test', 08:31:06 INFO - 'SHELL': '/bin/bash', 08:31:06 INFO - 'SHLVL': '1', 08:31:06 INFO - 'TERM': 'linux', 08:31:06 INFO - 'TMOUT': '86400', 08:31:06 INFO - 'USER': 'cltbld', 08:31:06 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1482424226.450401-90361196', 08:31:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:06 INFO - '_': '/tools/buildbot/bin/python'} 08:31:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:06 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)) 08:31:06 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)) 08:31:06 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)) 08:31:06 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)) 08:31:06 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)) 08:31:06 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)) 08:31:06 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)) 08:31:06 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)) 08:31:06 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)) 08:31:06 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)) 08:31:06 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)) 08:31:06 INFO - Cleaning up... 08:31:06 INFO - Return code: 0 08:31:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:31:06 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')]} 08:31:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:06 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')]} 08:31:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:06 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 0x21371f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2248a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24a9480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a6820>, '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 0x24a7520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24a79b0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1482424226.450401-90361196', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:31:06 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 08:31:06 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 08:31:06 INFO - Using env: {'DISPLAY': ':0', 08:31:06 INFO - 'HOME': '/home/cltbld', 08:31:06 INFO - 'LANG': 'en_US.UTF-8', 08:31:06 INFO - 'LANGUAGE': 'en_US:en', 08:31:06 INFO - 'LOGNAME': 'cltbld', 08:31:06 INFO - 'MAIL': '/var/mail/cltbld', 08:31:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:06 INFO - 'MOZ_NO_REMOTE': '1', 08:31:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:31:06 INFO - 'NO_EM_RESTART': '1', 08:31:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:31:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:06 INFO - 'PWD': '/builds/slave/test', 08:31:06 INFO - 'SHELL': '/bin/bash', 08:31:06 INFO - 'SHLVL': '1', 08:31:06 INFO - 'TERM': 'linux', 08:31:06 INFO - 'TMOUT': '86400', 08:31:06 INFO - 'USER': 'cltbld', 08:31:06 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1482424226.450401-90361196', 08:31:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:06 INFO - '_': '/tools/buildbot/bin/python'} 08:31:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:31:06 INFO - Cleaning up... 08:31:06 INFO - Return code: 0 08:31:06 INFO - Running post-action listener: _resource_record_post_action 08:31:06 INFO - Running post-action listener: _start_resource_monitoring 08:31:07 INFO - Starting resource monitoring. 08:31:07 INFO - [mozharness: 2016-12-22 16:31:07.024627Z] Finished create-virtualenv step (success) 08:31:07 INFO - [mozharness: 2016-12-22 16:31:07.025154Z] Running install step. 08:31:07 INFO - Running pre-action listener: _resource_record_pre_action 08:31:07 INFO - Running main action method: install 08:31:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:31:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:31:07 INFO - Reading from file tmpfile_stdout 08:31:07 INFO - Detecting whether we're running mozinstall >=1.0... 08:31:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:31:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:31:07 INFO - Reading from file tmpfile_stdout 08:31:07 INFO - Output received: 08:31:07 INFO - Usage: mozinstall [options] installer 08:31:07 INFO - Options: 08:31:07 INFO - -h, --help show this help message and exit 08:31:07 INFO - -d DEST, --destination=DEST 08:31:07 INFO - Directory to install application into. [default: 08:31:07 INFO - "/builds/slave/test"] 08:31:07 INFO - --app=APP Application being installed. [default: firefox] 08:31:07 INFO - mkdir: /builds/slave/test/build/application 08:31:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 08:31:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 08:31:23 INFO - Reading from file tmpfile_stdout 08:31:23 INFO - Output received: 08:31:23 INFO - /builds/slave/test/build/application/firefox/firefox 08:31:23 INFO - Running post-action listener: _resource_record_post_action 08:31:23 INFO - [mozharness: 2016-12-22 16:31:23.409306Z] Finished install step (success) 08:31:23 INFO - [mozharness: 2016-12-22 16:31:23.409549Z] Running run-tests step. 08:31:23 INFO - Running pre-action listener: _resource_record_pre_action 08:31:23 INFO - Running main action method: run_tests 08:31:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:31:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:31:23 INFO - Python 2.7.3 08:31:23 INFO - Return code: 0 08:31:23 INFO - grabbing minidump binary from tooltool 08:31:23 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')]} 08:31:23 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/linux64/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 0x24a6820>, '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 0x24a7520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24a79b0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:31:23 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/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 08:31:23 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/linux64/releng.manifest -o -c /builds/tooltool_cache 08:31:23 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/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 08:31:23 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:31:23 INFO - Return code: 0 08:31:23 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:31:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:31:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:31:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:31:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:31:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/c881nCb6TfG6JvqCivK3oA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-023', '--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 08:31:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/c881nCb6TfG6JvqCivK3oA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-023 --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 08:31:23 INFO - Using env: {'DISPLAY': ':0', 08:31:23 INFO - 'HOME': '/home/cltbld', 08:31:23 INFO - 'LANG': 'en_US.UTF-8', 08:31:23 INFO - 'LANGUAGE': 'en_US:en', 08:31:23 INFO - 'LOGNAME': 'cltbld', 08:31:23 INFO - 'MAIL': '/var/mail/cltbld', 08:31:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:31:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:31:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:23 INFO - 'MOZ_NO_REMOTE': '1', 08:31:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:31:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:31:23 INFO - 'NO_EM_RESTART': '1', 08:31:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:31:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:23 INFO - 'PWD': '/builds/slave/test', 08:31:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:31:23 INFO - 'SHELL': '/bin/bash', 08:31:23 INFO - 'SHLVL': '1', 08:31:23 INFO - 'TERM': 'linux', 08:31:23 INFO - 'TMOUT': '86400', 08:31:23 INFO - 'USER': 'cltbld', 08:31:23 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1482424226.450401-90361196', 08:31:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:23 INFO - '_': '/tools/buildbot/bin/python'} 08:31:23 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/c881nCb6TfG6JvqCivK3oA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-023', '--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 08:31:23 INFO - mozversion application_buildid: 20161222071954 08:31:23 INFO - mozversion application_changeset: 8460203bc93b9667cea1bc00f9d9990a4b1a9474 08:31:23 INFO - mozversion application_display_name: Nightly 08:31:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:31:23 INFO - mozversion application_name: Firefox 08:31:23 INFO - mozversion application_remotingname: firefox 08:31:23 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:31:23 INFO - mozversion application_vendor: Mozilla 08:31:23 INFO - mozversion application_version: 53.0a1 08:31:23 INFO - mozversion platform_buildid: 20161222071954 08:31:23 INFO - mozversion platform_changeset: 8460203bc93b9667cea1bc00f9d9990a4b1a9474 08:31:23 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:31:23 INFO - mozversion platform_version: 53.0a1 08:31:23 INFO - using testdate: 1482424283 08:31:23 INFO - actual date: 1482424283 08:31:23 INFO - starting webserver on 'localhost:51068' 08:31:23 INFO - SUITE-START | Running 2 tests 08:31:23 INFO - TEST-START | dromaeo_css 08:31:23 INFO - operating with platform_type : linux_ 08:31:23 INFO - Initialising browser for dromaeo_css test... 08:31:23 INFO - TEST-INFO | started process 7703 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJUTirt/profile http://localhost:51068/getInfo.html) 08:31:24 INFO - PROCESS | 7703 | 1482424284202 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 08:31:27 INFO - PROCESS | 7703 | __metrics Screen width/height:1600/1200 08:31:27 INFO - PROCESS | 7703 | colorDepth:24 08:31:27 INFO - PROCESS | 7703 | Browser inner width/height: 1024/697 08:31:27 INFO - PROCESS | 7703 | __metrics 08:31:28 ERROR - PROCESS | 7703 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3247: TypeError: this.tabs is undefined 08:31:29 INFO - TEST-INFO | 7703: exit 0 08:31:29 INFO - Browser initialized. 08:31:29 INFO - Running cycle 1/1 for dromaeo_css test... 08:31:29 INFO - TEST-INFO | started process 7822 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJUTirt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:31:41 INFO - PROCESS | 7822 | RSS: Main: 158121984 08:31:41 INFO - PROCESS | 7822 | 08:32:31 INFO - PROCESS | 7822 | 1482424351119 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] 08:34:45 INFO - PROCESS | 7822 | Cycle 1(1): loaded http://localhost:51068/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51068/tests/dromaeo/cssquery-ext.html) 08:34:46 INFO - PROCESS | 7822 | RSS: Main: 165011456 08:34:46 INFO - PROCESS | 7822 | 08:36:29 INFO - PROCESS | 7822 | ************************* 08:36:29 INFO - PROCESS | 7822 | A coding exception was thrown and uncaught in a Task. 08:36:29 INFO - PROCESS | 7822 | 08:36:29 INFO - PROCESS | 7822 | Full message: ReferenceError: fetch is not defined 08:36:29 INFO - PROCESS | 7822 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 08:36:29 INFO - PROCESS | 7822 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 08:36:29 INFO - PROCESS | 7822 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 08:36:29 INFO - PROCESS | 7822 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 08:36:29 INFO - PROCESS | 7822 | TaskImpl@resource://gre/modules/Task.jsm:277:3 08:36:29 INFO - PROCESS | 7822 | asyncFunction@resource://gre/modules/Task.jsm:252:14 08:36:29 INFO - PROCESS | 7822 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 08:36:29 INFO - PROCESS | 7822 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 08:36:29 INFO - PROCESS | 7822 | TaskImpl@resource://gre/modules/Task.jsm:277:3 08:36:29 INFO - PROCESS | 7822 | asyncFunction@resource://gre/modules/Task.jsm:252:14 08:36:29 INFO - PROCESS | 7822 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 08:36:29 INFO - PROCESS | 7822 | 08:36:29 INFO - PROCESS | 7822 | ************************* 08:37:48 INFO - PROCESS | 7822 | Cycle 1(1): loaded http://localhost:51068/tests/dromaeo/cssquery-ext.html (next: http://localhost:51068/tests/dromaeo/cssquery-jquery.html) 08:37:49 INFO - PROCESS | 7822 | RSS: Main: 161964032 08:37:49 INFO - PROCESS | 7822 | 08:40:51 INFO - PROCESS | 7822 | Cycle 1(1): loaded http://localhost:51068/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51068/tests/dromaeo/cssquery-mootools.html) 08:40:52 INFO - PROCESS | 7822 | RSS: Main: 167624704 08:40:52 INFO - PROCESS | 7822 | 08:43:54 INFO - PROCESS | 7822 | Cycle 1(1): loaded http://localhost:51068/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51068/tests/dromaeo/cssquery-prototype.html) 08:43:55 INFO - PROCESS | 7822 | RSS: Main: 162430976 08:43:55 INFO - PROCESS | 7822 | 08:46:58 INFO - PROCESS | 7822 | Cycle 1(1): loaded http://localhost:51068/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51068/tests/dromaeo/cssquery-yui.html) 08:46:59 INFO - PROCESS | 7822 | RSS: Main: 165736448 08:46:59 INFO - PROCESS | 7822 | 08:50:02 INFO - PROCESS | 7822 | Cycle 1(1): loaded http://localhost:51068/tests/dromaeo/cssquery-yui.html (next: http://localhost:51068/tests/dromaeo/cssquery-dojo.html) 08:50:02 INFO - PROCESS | 7822 | RSS: Main: 165974016 08:50:02 INFO - PROCESS | 7822 | 08:50:02 INFO - PROCESS | 7822 | __start_tp_report 08:50:02 INFO - PROCESS | 7822 | _x_x_mozilla_page_load 08:50:02 INFO - PROCESS | 7822 | _x_x_mozilla_page_load_details 08:50:02 INFO - PROCESS | 7822 | |i|pagename|runs| 08:50:02 INFO - PROCESS | 7822 | |0;dojo.html;2644.9206523804287;2648.5894686323613;2654.163938359418;2676.3175390275483;2694.5419278722616;4640.211164102111;4828.493008234138;4831.710097394154;4836.492168322328;4884.462709101996;4680.836170734025;4786.492254939701;4786.736729479877;4807.711537307764;4818.204996175636;4365.978170109153;4387.583179597933;4418.1384629997165;4420;4483.4171557697455;4316.546762589915;4390.341448782692;4417.823287068513;4418.955810441887;4433.423654924855;4212.452381190456;4246.214450326668;4479.7088189267915;4482.7310361378095;4527.615152712007;4215.051613386963;4279.358096285551;4281.250781113293;4281.379200016011;4286.8499602514075;4261.169072030955;4316.805743741539;4331.480222373304;4357.891052723676;4362.062156636307;4716;4761.6904901181315;4777.784999675023;4790.640701947333;4817.397825271841;5098.235264710286;5135.229715542699;5136.768845401966;5137.6403651744395;5177.094008548489;5012.949870501321;5057.696538207682;5058.747062646853;5122.897542049175;5129.435762066169;4750;4769.213079841857;4777.450593181759;4786.569208771228;4795;5219.347581552306;5269.894602107936;5309.33633295838;5328.786848526015;5332.440093790174;4983.202687569971;5021.372328458942;5059.72171530562;5070.923936140961;5081.898362032739;4958.727270000112;5034.370703662043;5119;5120.743962801919;5123.052235336475;4963;4965.453800081988;4968.30443737877;5018;5052.292679024939;4920.114379411741;4942.802287908516;4961.950380496221;4963.255511673278;5009.674371165863;4150.937735933963;4197.958020419818;4206.242876282298;4209.2423363794815;4245.8513952012145;3625.184333524936;3651.1602331463914;3697;3728.3848165053005;3739.8130093495215;4391.363252328394;4430.047539778963;4434.578715022068;4463.24124898768;4474.888127796831;5136.666116702402;5165.1735722285;5198.298229739013;5202.661826981234;5258.474152584711;4304.332828411601;4324.610785029363;4325.956740432618;4377.868663940087;4431.778411079433;4522.321651752198;4529.886752831206;4536.432945881765;4538.750368729751;4541.137183935041;4709.788059537329;4766.66633335677;4770.403699537558;4772.117158325582;4776.952230477651;5126.6923984561045;5134.204198349262;5160.690358578498;5193.246979188072;5236.5287124157485;4825.5898248649255;4825.879353016203;4831.830885918976;4836.733979631154;4881.707097574157;4945.97527012377;5083.059633967728;5087.465816089258;5094;5134.358205224347;5030.698158110525;5089.236614507706;5103.5661968733075;5125.282460455464;5225.1117310056625;5006.3992320921725;5014.247862820606;5015.874603134951;5017.222330538772;5064.5441910228255;5039.697618142924;5041.294218809443;5041.420236672683;5043;5043.67216130965;4721.220998535203;4756.429228492603;4813.735244561442;4898.044881248123;4906.730129842893;5045.142325804549;5047.722375269395;5047.722375269395;5078.289039534542;5092.949070509248;4850.102730994778;4898.951010489849;4908.484609115992;4910.680805747615;4959.355283813081 08:50:02 INFO - PROCESS | 7822 | |1;ext.html;500.91801708310055;510.7880229704672;511.55239165729984;520.734425443024;525.802823941022;36848;39049.60950390499;39187.80406097969;39204.82385528439;39259.80370098152;40073;40743.18513629726;40752.59247407525;40800;40830;7516.699332026726;7689.423293253;7698.846023079535;7704.999999999972;7719.536827790323;9300.581473833694;9616.374935629197;9635.662751803688;9906.455144967023;9908.702738917807;9701.660441884536;9730.513474326255;9759.560819763128;9761.658341958033;9765.707028789147;9594.472304023277;9596.472194029291;9633.759156021086;9641.27690423221;9659.806803863883;9354.625814967394;9471.90528094717;9483.762905927339;9487.620495180185;9489.383190092622;32898.6710132898;33021.872265966755;33120.668793312;33180.336393272;33209.16977075568;2873.5689646552973;2946.6611339695996;2971.8514074296413;2988.745956593692;3340.46552551593;25010;25324.873375633004;25634.615480767803;28947;28999.565006524917;3432.2105915639545;3514.3674138655033;3540.9822950885336;3542.681158695704;3544.5392099026963;245478;246574;246744;249532;250473;54692;56085.71957140188;56207.718961404935;56245.156322655195;56317;58897;58989.70505147447;59399.70300148558;59455.40619579227;59464;15098.169600672069;15332.156731379659;15332.693346133015;15359.846401535842;15415.61460963485;9578.377405468622;9878.802423951625;10463.68608941733;11670.299782013106;11826.704332391759;18121.90939045296;18454;18707.90646046761;18766.718499222523;18827.623447531245;6646.335366463315;7052.435805135577;7155.642217889084;7317.26827317264;7322.498887728003;783.8628240058081;853.1212850763724;880.2781718990367;889.4218757807477;932.5756780664781;25258.873705631355;27092.59361109585;27189.320266993484;27656.446871062468;27933;910.4309806371018;940.6002448959165;944.8441007233729;971.1114330387617;999.3853779925294;14248.216348100954;14387.64030899215;14494.85505144935;14547.563573092824;14671.926640366943;345256;346512;351616;352231;352707;48461;49027.303498307934;49082.75458622684;49191.508084919406;49218.26172607414;1143.5768765556631;1172.818213176959;1198.8321634971112;1202.05038019965;1203.4163430736087;25992.59211124896;26522;26893.596596050684;27231.59152612673;27327.590086148328;35959;36027;36099.819500902326;36106.63893361033;36213.11122998467;240134;240751;241168;241932;242156;30471.238219044702;31494.685053149176;32786.18034549156;33725.49411758874;37177.81411092927;39728.40407393949;40731.796341018104;41099.1780164401;43609.781951090044;45136.77431612821;34461;35355.82322088373;35398;35569.64430355767;35673.46489802603;34957.82521087378;34985.65014349824;35120.824395877855;35702;35796.284074317846;31310.530342044432;31502.212444689067;33600.663993359754;33612.159696007795;35236;30223.33771642581;31960.360792783547;32278.35443291168;32284.83857580697;32337.35325293528;24220.152294669602;24528.264152075466;24651.876740616182;24918.875405622857;25011.12461063854 08:50:02 INFO - PROCESS | 7822 | |2;jquery.html;350.2539590671868;360.32079530085787;367.51121009057954;376.26064782701985;379.57866767887657;15233.695326093472;15998.520044398658;16158.838411615896;16235;16244.75632865508;15548.300326485307;16125.758113628304;16341.5914602135;16535.090570018645;16587.66824663506;4840.370751802261;5026.97486512567;5040.722760248185;5049.3940727112795;5054.772535235905;3633.7638053526566;3825.1775868188333;4923.926141107886;4990.251462280668;5407.3781515125875;5632.436756324376;5707.543396528268;5774.884502309951;5791.333996590404;5802.39074897136;5653.981898871294;5778.422157784209;5789.855253618651;5816.185733997244;5864.501517371029;5189.6886186828915;5190.195519694454;5206.453322401131;5208.140656791625;5213.7914483421;19239.80760192394;20196.596068078557;20237.392878213675;20458.033585130073;20634;1837.145727236828;1999.8000199979886;2013.5268211970176;2082.7084208210863;2519.710233323173;11912.821307680391;12930.09489335747;13616.38726257311;13639.727205455934;13691.863081369158;1829.2957211473679;1849.6023354978608;1897.933572324962;1900.4393703857395;1918.9712154317697;58219;59633.45864661633;76685;78067.21932780714;79972.60013699894;8274.255317021498;8499.447535910269;8846.11538846123;8854.601542930584;8969.968453627785;11325.263857849213;11415.771684566429;11426.714332141762;11446.94276528612;11487.25332853362;6547;6769.627670478072;6796.592204467748;6900.378965893094;6960.408365289007;6463.960024984385;6543.640099794462;6800.353966373186;6806.115205023315;6817.113775209191;3625.873094441682;3641.289948460078;3665.890023299305;3740.9438858417147;3752.1557649528636;1648.2582837723073;1776.4315419065774;1778.2264714848823;1793.9192736326897;1834.660587791263;738.9224131466226;774.6978678315461;779.353136896386;852.3564708645057;946.0586716217408;14952.32714527827;15690;16475.6704865902;16496.587585310463;17032.829671703374;768.7501561992264;793.6666600028002;802.7952872017715;817.4155478832663;827.4952279109739;7566.583837888859;9141.3601047926;9797.755056123653;9833.4099954003;9869.21046316293;355167;361811;362599;362672;363120;52883.20675189875;60131.398686013454;67064;71262.64368678123;72467.63766181136;892.7946572288411;949.8480243161061;951.8857737071596;954.1698722111807;985.0199051943363;14639.41442342337;14973.32620032123;15290;15587;15860.286287117338;34695;35331.29337413289;35844;36082.654627449134;36480;47287.290690638976;48038.759806200746;48247.035059297916;50721.239181413046;50747.492525074274;10266.486675666336;10578.259521833706;10949.124070074535;11128.554857805597;11165.944170279097;6497.805065848032;8838.911610884066;11241.71895702614;11298.887011130113;12091.39543022863;7104.4370238547435;7296.1974182838785;7322.304381083789;7592.734254301073;7775.300222979959;7605.543667379754;7635.350995165473;7685;7756.495827771177;7884.94991302254;7564.432667549846;7758.185390533913;7827.608619568885;7932.762017139495;8197.344212463107;10585.36487810703;11252.381119038531;11314.773704525698;11663.76672466563;11663.88336116628;10808.837867431837;10910.78178436411;10959;11062.336259824437;11236.66290011301 08:50:02 INFO - PROCESS | 7822 | |3;mootools.html;1317.4993502469063;1356.5862411964347;1463.2025546077386;1657.809351924529;1663.3263528271048;5899.911501327473;6097.176881121048;6841.7947461576105;7297.817554561132;7355.117385913698;6938.514303998722;7213.170485394181;7218;7350.816229594263;7372.668229929653;2058.5368292134262;2095.31902131807;2098.07684618768;2106.147010460766;2115.851890367675;2078.3349769646243;2108.8523803333774;2110.662294032955;2114.12263910477;2122;2061.155266036896;2082.5834833033377;2092.476880779805;2105.1895020417173;2109.820665243456;2042.9284975025878;2077.491014701393;2081.8542702010864;2083.0313904034547;2087.1025459052603;2035.908384122718;2042.60169266993;2052.271443637517;2055.9486012849648;2061.2167376396874;7155.570665760071;7171.247019062976;7258.745943891991;7260.45546584003;7270.800317947533;4305.84929527465;4443.977780111111;4447.421835161441;4447.533009034069;4447.644188464915;2788.7418540023036;2820.534611789052;2824.7316504932;2892.6094977178022;2897.1887871396043;851.7189327521903;889.9644014239487;893.1158153428124;910.4537277633433;911.8221946720392;9565.569549370295;9567.186789122932;9604.423734575948;9627.229821614254;9646.807063858683;3746.83139258734;3922.2547715933883;3961.623645753649;3982.6415622594104;4011.5787842276723;7890.684372625145;7944.6822127114265;7948.841023179504;7961.562114083781;7991.2807847294025;4483.14820184164;4569.611583015414;4571.794269257892;4572.702774319659;4591.931121033187;3384.1708781348234;3505.4040813061843;3966.305896468164;4138.337865941435;4160.895977600584;3351.2124650707065;3359.9328013439595;3465.6880880720855;3485.372632926047;3489.2672538766637;1132.841402203692;1179.1804695736553;1180.2387460088196;1185.5257896841333;1191.237607930925;785.2265518464422;797.5533701127388;804.1757198871204;816.7304789419477;818.6684392820918;4657.394538709946;4741.383620129361;4803.327534145223;4863.416390033219;5025.899482010339;409.3491348755493;410.46024477811585;418.0864810389309;420.20371396204024;421.306950067144;3367.393869103536;3393.372226138172;3396.3207358528393;3426.9657303426648;3468.341015207153;8423.326133909273;8717.389782715149;8868.290536757044;8894;8895.199432051146;8837.734867953972;8851.513166775889;8856.424332418372;8863.157999989991;8868.778280543038;6800.761973330909;6868.107146071079;6868.278830722702;6877.449804015667;6886.070380498669;4522.389477420506;4546.909061818679;4561.315802629632;4598.172328980816;4658;7949.682012719427;7996.720114795954;8006.59967001636;8029.156938521372;8030.598470076589;8113.0669972953865;8155.347572194329;8193.83612327762;8265.132161122996;8318.916810831814;5374.059539580636;5519.862003449946;6093.421124993119;6140.631562106288;6198;6561.1470508835455;6801.965990170018;6813.625250611264;6973.895391569029;6997.685104170427;6148.723307451086;6191.81424557264;6317.052442133717;6371.7132729028235;6488.902666459912;5942.257217847769;5945.672987985702;6055.1522786809;6058.878822423439;6067.423594758491;5672.290963629546;5775.884482310415;5777.422257774357;5777.653340799566;5780.335261444988;4109.321961876257;4123.505179378494;4246.193223287565;4305.461817272841;4330.025744207528;4015.7841242002983;4124.8556300529335;4129.710920235555;4152.294110001247;4293.463317085364 08:50:02 INFO - PROCESS | 7822 | |4;prototype.html;336.9831508424578;344.8189949423225;353.92036791721847;355.2805568723335;360.0853831268576;4757.762111894409;4869.3426387437685;4952.678075925062;5017.9247311290355;5027.170516864722;4797.6161907047435;4878.48775878533;4897.991823352426;4903.313536104948;4924.187509061001;3937.803109844511;3959.9208015839667;3964.5044369453817;3969.928119407758;3975.264576053425;3674.4855720199193;3807.657310842024;3965.3655415133585;3977.2244412339605;3989.3018721723583;3753.2788728171995;3761.322961866863;3763.379042457991;3769.359208934488;3786.734928555002;3740.981295093507;3752.4746535485056;3755.84976600937;3760.417135344026;3763.6477201748908;3308.801471911693;3309.2885029718727;3310.7185889199445;3324.069260607034;3326.534285200074;4638.814447422099;4728.4798672146035;4794.664373493857;4801.399825021872;4813;4288.24955632763;4315.30955047194;4319.8056087475825;4320.438343015419;4339.222897345701;5061.746912654353;5087;5129.487051294878;5135.3837539495125;5139.563137133348;3435.055359776057;3609.169890925076;3610.6208848070837;3614.457831325296;3615.493830863681;5345.224942383333;5368.409474957791;5411.404745477994;5413.458654134594;5419.83740487786;3623.547056617923;3641.453781932731;3648.945265821015;3661.6521430464595;3676.191237927652;3702.259548090393;3743.2700623378723;3743.794091325003;3749;3751.8311675974646;4169.937450938239;4207.558206388347;4254.87235382939;4265.936010959838;4280.330671038298;5157.355330583677;5161;5161.53546180838;5167.741612919339;5194.32473778414;1227.3985746983913;1251.7482517482517;1252;1262.6275248801626;1264.2856785915928;840.7855996720797;862.4825104936988;868.392125512144;874.711345256064;877.3200769403763;4331.935020974688;4386.627136693417;4398.560143985575;4403.9779801100785;4412.691111622155;5123.769430375642;5128.25640282157;5138.434772175057;5193;5216.426193118754;4390.419448998358;4392.582704643054;4400.371862410864;4418.8453404130705;4429.18403454582;2571.6142578612985;2694.420699549567;2726.386563023304;2736.2885649731215;2737.5893615957766;4720.362751028636;4745.881352966204;4775.21209000518;4776.8566942991765;4871.537203965618;4981.086064652147;5254.369475662868;5260.421353651172;5277.577793776489;5282.445343238982;4788.688735232199;4809.735464549343;4813.205821039489;4820.6143508519235;4829.203181475087;5159.039564965289;5167.638265321391;5186.9221961669855;5187.662801917863;5188.766505507337;4934.333864928189;5029.245613158056;5044.310156097748;5055.2448915341965;5069.340985671839;4998.625103117208;5028.773705183203;5043.420006699276;5050.924236136388;5058.3929928408825;3730.645588669072;3733.6826369757787;3760.3419401605156;3784.9432258515594;3790.6209379061875;5225.320708307896;5451.400345961862;5472.781088756406;5477.287952566141;5501.779928802963;5156.20078887773;5157.896842063213;5178.300929374637;5221.530062294411;5262.97368513155;5168.9741551292;5185.299984502044;5222.42553319127;5266.8946621068135;5279.7096159710045;4938.654294199515;5106.923396148986;5149.189599696273;5160.870978225425;5163.328767260232;5236.738163091754;5244.685318880879;5247.4490178530705;5248.6325957182635;5266.262723218829;5101.260317254051;5112.667676601009;5130.333056702605;5146.485351464824;5149.48505149482 08:50:02 INFO - PROCESS | 7822 | |5;yui.html;589.4649243602571;593.2554643921877;641.8042497038401;665.9500537459692;673.8180691213372;11207.327560346384;12329.938350308246;12374.319412432313;12902.548410805624;13094.279814610192;11537.57710880714;11774.470148843302;11904.345261010641;11937.224080434766;11942.82085768712;767.4282659418741;784.8116452051495;785.6268272570541;786.1273985875673;786.4848524216619;755.0674916478173;784.2667106255641;785.8585454618167;792.5759718550577;794.9205079492034;822.8642274024809;824.5712229640583;825.4015838517057;827.1191181391829;830.4767996162409;818.2308629887887;819.8565251081037;820.1675299570941;824.0811495182888;824.0811495182888;602.3795490644643;604.78530121807;605.3280858247342;606.0152252589542;606.1090197409844;10369.74075648115;12350;12391.318477483735;12458.626241212778;12499.187552809039;10575.259731818776;11645.94177029118;11870.88129118715;11991.64025079249;12121.33332666703;9218.262538996863;9310.441373517542;9523.952380238125;9613.134817866425;9655.08276713711;2683.8658067096762;2701.149138021517;2706.837589744602;2720.782337413002;2781.415902660444;65142.674286628266;65469.672651636436;65715.67142164307;65951;66869.3313066868;9417.105374989365;9450.149486546245;9457.621695132118;9466.526673666289;9521.904780952102;16523.752143717855;16570.6685866282;16580.58548536272;16691.248893799566;16749.665006699797;6469.54713170083;6592.208934927743;6671.899921501182;6676.53264271506;6755.3582409671;3380.3408335374857;3587.7309201809967;3594.5866225384416;3604.4052731299043;3685.649863262986;2610.8955641774496;2728.1133631569423;2732.098407525512;2754.614353990443;2765.1566272286746;430.80613723824405;437.25229856944344;437.7592324221729;443.17126972560874;443.94894587122246;533.6637918111564;548.7338640759228;550.5155463192365;585.4789237578558;588.5909293041381;7959.243871832166;7988.760337189894;8040.206020921532;8040.8793868092025;8192.590370481452;458.4658872413667;473.5572239955584;489.4860396583573;551.0659432262357;553.2475832867298;3411.488276758506;3473.3574288756663;3540.291941611688;3554.9822250889097;3598.5501812273465;50968.49031509637;53384;59322;59531.10703339453;65948.67025664842;51773.48226517762;52212;53532.46467535353;54973.175402368994;56087.43912560904;39297;48170;50249.24626130611;51007.489925101756;51582.226266606034;7827.530348179127;8411.53736544496;8426.199511046441;8442.620082096197;8514.914850851412;33304.66695333113;33454.83272583621;34002.489962650085;35007.47488787619;35206.47190292199;39906.80046599865;42790;44051.77974110109;45375.77312113418;45715.54284457113;9934.552945117632;9974.11389884514;10238.180945524191;10480.790384192122;10669.626563070255;11740.647780566596;11985.28088314704;12026.939865300967;12147.75704485923;12245;10233.641822536176;10704.518296676824;10968.177386696188;11277.210595258253;11539.423028848356;11007.889921100686;11052.668419947415;11268.15488838324;11346.262492937933;11715;10646.520906559379;10808.675739727822;11089;11215.943920280346;11673.12451566153;8790.252828509392;8971.91028089737;9050.411723237969;9316.394434361744;9526.285528585246;9667.274954378308;9697.854532182164;9879.456629885426;9891.604335826487;9945.154661853823 08:50:02 INFO - PROCESS | 7822 | __end_tp_report 08:50:02 INFO - PROCESS | 7822 | __start_cc_report 08:50:02 INFO - PROCESS | 7822 | _x_x_mozilla_cycle_collect,351 08:50:02 INFO - PROCESS | 7822 | __end_cc_report 08:50:02 INFO - PROCESS | 7822 | __startTimestamp1482425402349__endTimestamp 08:50:02 INFO - PROCESS | 7822 | 08:50:02 INFO - PROCESS | 7822 | ------- Summary: start ------- 08:50:02 INFO - PROCESS | 7822 | Number of tests: 6 08:50:02 INFO - PROCESS | 7822 | 08:50:02 INFO - PROCESS | 7822 | [#0] dojo.html Cycles:165 Average:4708.31 Median:4831.71 stddev:512.78 (10.6%) stddev-sans-first:488.14 08:50:02 INFO - PROCESS | 7822 | Values: 2644.9 2648.6 2654.2 2676.3 2694.5 4640.2 4828.5 4831.7 4836.5 4884.5 4680.8 4786.5 4786.7 4807.7 4818.2 4366.0 4387.6 4418.1 4420.0 4483.4 4316.5 4390.3 4417.8 4419.0 4433.4 4212.5 4246.2 4479.7 4482.7 4527.6 4215.1 4279.4 4281.3 4281.4 4286.8 4261.2 4316.8 4331.5 4357.9 4362.1 4716.0 4761.7 4777.8 4790.6 4817.4 5098.2 5135.2 5136.8 5137.6 5177.1 5012.9 5057.7 5058.7 5122.9 5129.4 4750.0 4769.2 4777.5 4786.6 4795.0 5219.3 5269.9 5309.3 5328.8 5332.4 4983.2 5021.4 5059.7 5070.9 5081.9 4958.7 5034.4 5119.0 5120.7 5123.1 4963.0 4965.5 4968.3 5018.0 5052.3 4920.1 4942.8 4962.0 4963.3 5009.7 4150.9 4198.0 4206.2 4209.2 4245.9 3625.2 3651.2 3697.0 3728.4 3739.8 4391.4 4430.0 4434.6 4463.2 4474.9 5136.7 5165.2 5198.3 5202.7 5258.5 4304.3 4324.6 4326.0 4377.9 4431.8 4522.3 4529.9 4536.4 4538.8 4541.1 4709.8 4766.7 4770.4 4772.1 4777.0 5126.7 5134.2 5160.7 5193.2 5236.5 4825.6 4825.9 4831.8 4836.7 4881.7 4946.0 5083.1 5087.5 5094.0 5134.4 5030.7 5089.2 5103.6 5125.3 5225.1 5006.4 5014.2 5015.9 5017.2 5064.5 5039.7 5041.3 5041.4 5043.0 5043.7 4721.2 4756.4 4813.7 4898.0 4906.7 5045.1 5047.7 5047.7 5078.3 5092.9 4850.1 4899.0 4908.5 4910.7 4959.4 08:50:02 INFO - PROCESS | 7822 | 08:50:02 INFO - PROCESS | 7822 | [#1] ext.html Cycles:180 Average:44155.86 Median:3541.83 stddev:74352.39 (2099.3%) stddev-sans-first:74488.72 08:50:02 INFO - PROCESS | 7822 | Values: 500.9 510.8 511.6 520.7 525.8 36848.0 39049.6 39187.8 39204.8 39259.8 40073.0 40743.2 40752.6 40800.0 40830.0 7516.7 7689.4 7698.8 7705.0 7719.5 9300.6 9616.4 9635.7 9906.5 9908.7 9701.7 9730.5 9759.6 9761.7 9765.7 9594.5 9596.5 9633.8 9641.3 9659.8 9354.6 9471.9 9483.8 9487.6 9489.4 32898.7 33021.9 33120.7 33180.3 33209.2 2873.6 2946.7 2971.9 2988.7 3340.5 25010.0 25324.9 25634.6 28947.0 28999.6 3432.2 3514.4 3541.0 3542.7 3544.5 245478.0 246574.0 246744.0 249532.0 250473.0 54692.0 56085.7 56207.7 56245.2 56317.0 58897.0 58989.7 59399.7 59455.4 59464.0 15098.2 15332.2 15332.7 15359.8 15415.6 9578.4 9878.8 10463.7 11670.3 11826.7 18121.9 18454.0 18707.9 18766.7 18827.6 6646.3 7052.4 7155.6 7317.3 7322.5 783.9 853.1 880.3 889.4 932.6 25258.9 27092.6 27189.3 27656.4 27933.0 910.4 940.6 944.8 971.1 999.4 14248.2 14387.6 14494.9 14547.6 14671.9 345256.0 346512.0 351616.0 352231.0 352707.0 48461.0 49027.3 49082.8 49191.5 49218.3 1143.6 1172.8 1198.8 1202.1 1203.4 25992.6 26522.0 26893.6 27231.6 27327.6 35959.0 36027.0 36099.8 36106.6 36213.1 240134.0 240751.0 241168.0 241932.0 242156.0 30471.2 31494.7 32786.2 33725.5 37177.8 39728.4 40731.8 41099.2 43609.8 45136.8 34461.0 35355.8 35398.0 35569.6 35673.5 34957.8 34985.7 35120.8 35702.0 35796.3 31310.5 31502.2 33600.7 33612.2 35236.0 30223.3 31960.4 32278.4 32284.8 32337.4 24220.2 24528.3 24651.9 24918.9 25011.1 08:50:02 INFO - PROCESS | 7822 | 08:50:02 INFO - PROCESS | 7822 | [#2] jquery.html Cycles:180 Average:22931.08 Median:47663.03 stddev:59614.96 (125.1%) stddev-sans-first:59758.08 08:50:02 INFO - PROCESS | 7822 | Values: 350.3 360.3 367.5 376.3 379.6 15233.7 15998.5 16158.8 16235.0 16244.8 15548.3 16125.8 16341.6 16535.1 16587.7 4840.4 5027.0 5040.7 5049.4 5054.8 3633.8 3825.2 4923.9 4990.3 5407.4 5632.4 5707.5 5774.9 5791.3 5802.4 5654.0 5778.4 5789.9 5816.2 5864.5 5189.7 5190.2 5206.5 5208.1 5213.8 19239.8 20196.6 20237.4 20458.0 20634.0 1837.1 1999.8 2013.5 2082.7 2519.7 11912.8 12930.1 13616.4 13639.7 13691.9 1829.3 1849.6 1897.9 1900.4 1919.0 58219.0 59633.5 76685.0 78067.2 79972.6 8274.3 8499.4 8846.1 8854.6 8970.0 11325.3 11415.8 11426.7 11446.9 11487.3 6547.0 6769.6 6796.6 6900.4 6960.4 6464.0 6543.6 6800.4 6806.1 6817.1 3625.9 3641.3 3665.9 3740.9 3752.2 1648.3 1776.4 1778.2 1793.9 1834.7 738.9 774.7 779.4 852.4 946.1 14952.3 15690.0 16475.7 16496.6 17032.8 768.8 793.7 802.8 817.4 827.5 7566.6 9141.4 9797.8 9833.4 9869.2 355167.0 361811.0 362599.0 362672.0 363120.0 52883.2 60131.4 67064.0 71262.6 72467.6 892.8 949.8 951.9 954.2 985.0 14639.4 14973.3 15290.0 15587.0 15860.3 34695.0 35331.3 35844.0 36082.7 36480.0 47287.3 48038.8 48247.0 50721.2 50747.5 10266.5 10578.3 10949.1 11128.6 11165.9 6497.8 8838.9 11241.7 11298.9 12091.4 7104.4 7296.2 7322.3 7592.7 7775.3 7605.5 7635.4 7685.0 7756.5 7884.9 7564.4 7758.2 7827.6 7932.8 8197.3 10585.4 11252.4 11314.8 11663.8 11663.9 10808.8 10910.8 10959.0 11062.3 11236.7 08:50:02 INFO - PROCESS | 7822 | 08:50:02 INFO - PROCESS | 7822 | [#3] mootools.html Cycles:180 Average:4708.86 Median:4699.69 stddev:2582.40 (54.9%) stddev-sans-first:2577.07 08:50:02 INFO - PROCESS | 7822 | Values: 1317.5 1356.6 1463.2 1657.8 1663.3 5899.9 6097.2 6841.8 7297.8 7355.1 6938.5 7213.2 7218.0 7350.8 7372.7 2058.5 2095.3 2098.1 2106.1 2115.9 2078.3 2108.9 2110.7 2114.1 2122.0 2061.2 2082.6 2092.5 2105.2 2109.8 2042.9 2077.5 2081.9 2083.0 2087.1 2035.9 2042.6 2052.3 2055.9 2061.2 7155.6 7171.2 7258.7 7260.5 7270.8 4305.8 4444.0 4447.4 4447.5 4447.6 2788.7 2820.5 2824.7 2892.6 2897.2 851.7 890.0 893.1 910.5 911.8 9565.6 9567.2 9604.4 9627.2 9646.8 3746.8 3922.3 3961.6 3982.6 4011.6 7890.7 7944.7 7948.8 7961.6 7991.3 4483.1 4569.6 4571.8 4572.7 4591.9 3384.2 3505.4 3966.3 4138.3 4160.9 3351.2 3359.9 3465.7 3485.4 3489.3 1132.8 1179.2 1180.2 1185.5 1191.2 785.2 797.6 804.2 816.7 818.7 4657.4 4741.4 4803.3 4863.4 5025.9 409.3 410.5 418.1 420.2 421.3 3367.4 3393.4 3396.3 3427.0 3468.3 8423.3 8717.4 8868.3 8894.0 8895.2 8837.7 8851.5 8856.4 8863.2 8868.8 6800.8 6868.1 6868.3 6877.4 6886.1 4522.4 4546.9 4561.3 4598.2 4658.0 7949.7 7996.7 8006.6 8029.2 8030.6 8113.1 8155.3 8193.8 8265.1 8318.9 5374.1 5519.9 6093.4 6140.6 6198.0 6561.1 6802.0 6813.6 6973.9 6997.7 6148.7 6191.8 6317.1 6371.7 6488.9 5942.3 5945.7 6055.2 6058.9 6067.4 5672.3 5775.9 5777.4 5777.7 5780.3 4109.3 4123.5 4246.2 4305.5 4330.0 4015.8 4124.9 4129.7 4152.3 4293.5 08:50:02 INFO - PROCESS | 7822 | 08:50:02 INFO - PROCESS | 7822 | [#4] prototype.html Cycles:180 Average:4240.88 Median:4805.57 stddev:1242.28 (25.9%) stddev-sans-first:1210.72 08:50:02 INFO - PROCESS | 7822 | Values: 337.0 344.8 353.9 355.3 360.1 4757.8 4869.3 4952.7 5017.9 5027.2 4797.6 4878.5 4898.0 4903.3 4924.2 3937.8 3959.9 3964.5 3969.9 3975.3 3674.5 3807.7 3965.4 3977.2 3989.3 3753.3 3761.3 3763.4 3769.4 3786.7 3741.0 3752.5 3755.8 3760.4 3763.6 3308.8 3309.3 3310.7 3324.1 3326.5 4638.8 4728.5 4794.7 4801.4 4813.0 4288.2 4315.3 4319.8 4320.4 4339.2 5061.7 5087.0 5129.5 5135.4 5139.6 3435.1 3609.2 3610.6 3614.5 3615.5 5345.2 5368.4 5411.4 5413.5 5419.8 3623.5 3641.5 3648.9 3661.7 3676.2 3702.3 3743.3 3743.8 3749.0 3751.8 4169.9 4207.6 4254.9 4265.9 4280.3 5157.4 5161.0 5161.5 5167.7 5194.3 1227.4 1251.7 1252.0 1262.6 1264.3 840.8 862.5 868.4 874.7 877.3 4331.9 4386.6 4398.6 4404.0 4412.7 5123.8 5128.3 5138.4 5193.0 5216.4 4390.4 4392.6 4400.4 4418.8 4429.2 2571.6 2694.4 2726.4 2736.3 2737.6 4720.4 4745.9 4775.2 4776.9 4871.5 4981.1 5254.4 5260.4 5277.6 5282.4 4788.7 4809.7 4813.2 4820.6 4829.2 5159.0 5167.6 5186.9 5187.7 5188.8 4934.3 5029.2 5044.3 5055.2 5069.3 4998.6 5028.8 5043.4 5050.9 5058.4 3730.6 3733.7 3760.3 3784.9 3790.6 5225.3 5451.4 5472.8 5477.3 5501.8 5156.2 5157.9 5178.3 5221.5 5263.0 5169.0 5185.3 5222.4 5266.9 5279.7 4938.7 5106.9 5149.2 5160.9 5163.3 5236.7 5244.7 5247.4 5248.6 5266.3 5101.3 5112.7 5130.3 5146.5 5149.5 08:50:02 INFO - PROCESS | 7822 | 08:50:02 INFO - PROCESS | 7822 | [#5] yui.html Cycles:180 Average:13961.99 Median:51294.86 stddev:17553.70 (34.2%) stddev-sans-first:17574.22 08:50:02 INFO - PROCESS | 7822 | Values: 589.5 593.3 641.8 666.0 673.8 11207.3 12329.9 12374.3 12902.5 13094.3 11537.6 11774.5 11904.3 11937.2 11942.8 767.4 784.8 785.6 786.1 786.5 755.1 784.3 785.9 792.6 794.9 822.9 824.6 825.4 827.1 830.5 818.2 819.9 820.2 824.1 824.1 602.4 604.8 605.3 606.0 606.1 10369.7 12350.0 12391.3 12458.6 12499.2 10575.3 11645.9 11870.9 11991.6 12121.3 9218.3 9310.4 9524.0 9613.1 9655.1 2683.9 2701.1 2706.8 2720.8 2781.4 65142.7 65469.7 65715.7 65951.0 66869.3 9417.1 9450.1 9457.6 9466.5 9521.9 16523.8 16570.7 16580.6 16691.2 16749.7 6469.5 6592.2 6671.9 6676.5 6755.4 3380.3 3587.7 3594.6 3604.4 3685.6 2610.9 2728.1 2732.1 2754.6 2765.2 430.8 437.3 437.8 443.2 443.9 533.7 548.7 550.5 585.5 588.6 7959.2 7988.8 8040.2 8040.9 8192.6 458.5 473.6 489.5 551.1 553.2 3411.5 3473.4 3540.3 3555.0 3598.6 50968.5 53384.0 59322.0 59531.1 65948.7 51773.5 52212.0 53532.5 54973.2 56087.4 39297.0 48170.0 50249.2 51007.5 51582.2 7827.5 8411.5 8426.2 8442.6 8514.9 33304.7 33454.8 34002.5 35007.5 35206.5 39906.8 42790.0 44051.8 45375.8 45715.5 9934.6 9974.1 10238.2 10480.8 10669.6 11740.6 11985.3 12026.9 12147.8 12245.0 10233.6 10704.5 10968.2 11277.2 11539.4 11007.9 11052.7 11268.2 11346.3 11715.0 10646.5 10808.7 11089.0 11215.9 11673.1 8790.3 8971.9 9050.4 9316.4 9526.3 9667.3 9697.9 9879.5 9891.6 9945.2 08:50:02 INFO - PROCESS | 7822 | -------- Summary: end -------- 08:50:02 INFO - PROCESS | 7822 | 08:50:03 INFO - TEST-INFO | 7822: exit 0 08:50:03 INFO - TEST-OK | dromaeo_css | took 1119447ms 08:50:03 INFO - TEST-START | kraken 08:50:03 INFO - operating with platform_type : linux_ 08:50:03 INFO - Initialising browser for kraken test... 08:50:03 INFO - TEST-INFO | started process 8171 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphll9t7/profile http://localhost:51068/getInfo.html) 08:50:03 INFO - PROCESS | 8171 | 1482425403654 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 08:50:07 INFO - PROCESS | 8171 | __metrics Screen width/height:1600/1200 08:50:07 INFO - PROCESS | 8171 | colorDepth:24 08:50:07 INFO - PROCESS | 8171 | Browser inner width/height: 1024/697 08:50:07 INFO - PROCESS | 8171 | __metrics 08:50:07 ERROR - PROCESS | 8171 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3247: TypeError: this.tabs is undefined 08:50:08 INFO - TEST-INFO | 8171: exit 0 08:50:08 INFO - Browser initialized. 08:50:08 INFO - Running cycle 1/1 for kraken test... 08:50:08 INFO - TEST-INFO | started process 8289 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphll9t7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:50:20 INFO - PROCESS | 8289 | RSS: Main: 158416896 08:50:20 INFO - PROCESS | 8289 | 08:50:23 INFO - PROCESS | 8289 | Cycle 1(1): loaded http://localhost:51068/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51068/tests/kraken/driver.html?testName=audio-beat-detection) 08:50:24 INFO - PROCESS | 8289 | RSS: Main: 163459072 08:50:24 INFO - PROCESS | 8289 | 08:50:27 INFO - PROCESS | 8289 | Cycle 1(1): loaded http://localhost:51068/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51068/tests/kraken/driver.html?testName=audio-dft) 08:50:28 INFO - PROCESS | 8289 | RSS: Main: 162332672 08:50:28 INFO - PROCESS | 8289 | 08:50:31 INFO - PROCESS | 8289 | Cycle 1(1): loaded http://localhost:51068/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51068/tests/kraken/driver.html?testName=audio-fft) 08:50:31 INFO - PROCESS | 8289 | RSS: Main: 158969856 08:50:31 INFO - PROCESS | 8289 | 08:50:33 INFO - PROCESS | 8289 | Cycle 1(1): loaded http://localhost:51068/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51068/tests/kraken/driver.html?testName=audio-oscillator) 08:50:34 INFO - PROCESS | 8289 | RSS: Main: 159252480 08:50:34 INFO - PROCESS | 8289 | 08:50:36 INFO - PROCESS | 8289 | Cycle 1(1): loaded http://localhost:51068/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51068/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:50:37 INFO - PROCESS | 8289 | RSS: Main: 159698944 08:50:37 INFO - PROCESS | 8289 | 08:50:40 INFO - PROCESS | 8289 | Cycle 1(1): loaded http://localhost:51068/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51068/tests/kraken/driver.html?testName=imaging-darkroom) 08:50:41 INFO - PROCESS | 8289 | RSS: Main: 163459072 08:50:41 INFO - PROCESS | 8289 | 08:50:44 INFO - PROCESS | 8289 | Cycle 1(1): loaded http://localhost:51068/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51068/tests/kraken/driver.html?testName=imaging-desaturate) 08:50:45 INFO - PROCESS | 8289 | RSS: Main: 161067008 08:50:45 INFO - PROCESS | 8289 | 08:50:48 INFO - PROCESS | 8289 | Cycle 1(1): loaded http://localhost:51068/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51068/tests/kraken/driver.html?testName=json-parse-financial) 08:50:48 INFO - PROCESS | 8289 | RSS: Main: 162295808 08:50:48 INFO - PROCESS | 8289 | 08:50:50 INFO - PROCESS | 8289 | Cycle 1(1): loaded http://localhost:51068/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51068/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:50:51 INFO - PROCESS | 8289 | RSS: Main: 162619392 08:50:51 INFO - PROCESS | 8289 | 08:50:53 INFO - PROCESS | 8289 | Cycle 1(1): loaded http://localhost:51068/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51068/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:50:53 INFO - PROCESS | 8289 | RSS: Main: 162168832 08:50:53 INFO - PROCESS | 8289 | 08:50:55 INFO - PROCESS | 8289 | Cycle 1(1): loaded http://localhost:51068/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51068/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:50:56 INFO - PROCESS | 8289 | RSS: Main: 162979840 08:50:56 INFO - PROCESS | 8289 | 08:50:59 INFO - PROCESS | 8289 | Cycle 1(1): loaded http://localhost:51068/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51068/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:51:00 INFO - PROCESS | 8289 | RSS: Main: 163217408 08:51:00 INFO - PROCESS | 8289 | 08:51:03 INFO - PROCESS | 8289 | Cycle 1(1): loaded http://localhost:51068/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51068/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:51:03 INFO - PROCESS | 8289 | RSS: Main: 164474880 08:51:03 INFO - PROCESS | 8289 | 08:51:05 INFO - PROCESS | 8289 | Cycle 1(1): loaded http://localhost:51068/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51068/tests/kraken/driver.html?testName=ai-astar) 08:51:05 INFO - PROCESS | 8289 | RSS: Main: 164388864 08:51:05 INFO - PROCESS | 8289 | 08:51:05 INFO - PROCESS | 8289 | __start_tp_report 08:51:05 INFO - PROCESS | 8289 | _x_x_mozilla_page_load 08:51:05 INFO - PROCESS | 8289 | _x_x_mozilla_page_load_details 08:51:05 INFO - PROCESS | 8289 | |i|pagename|runs| 08:51:05 INFO - PROCESS | 8289 | |0;ai-astar;109;118;115;118;117;117;117;118;116;115 08:51:05 INFO - PROCESS | 8289 | |1;audio-beat-detection;153;134;129;180;128;128;126;127;150;127 08:51:05 INFO - PROCESS | 8289 | |2;audio-dft;165;168;167;168;168;163;163;164;163;164 08:51:05 INFO - PROCESS | 8289 | |3;audio-fft;76;77;80;115;78;77;78;76;90;80 08:51:05 INFO - PROCESS | 8289 | |4;audio-oscillator;87;85;114;84;85;85;99;89;85;106 08:51:05 INFO - PROCESS | 8289 | |5;imaging-gaussian-blur;118;118;119;119;121;118;118;119;119;118 08:51:05 INFO - PROCESS | 8289 | |6;imaging-darkroom;148;149;147;148;147;148;147;149;147;147 08:51:05 INFO - PROCESS | 8289 | |7;imaging-desaturate;96;96;96;96;96;96;96;97;97;97 08:51:05 INFO - PROCESS | 8289 | |8;json-parse-financial;69;68;68;70;78;68;70;70;69;69 08:51:05 INFO - PROCESS | 8289 | |9;json-stringify-tinderbox;67;68;66;66;68;67;66;67;67;67 08:51:05 INFO - PROCESS | 8289 | |10;stanford-crypto-aes;84;85;85;85;86;84;91;85;91;84 08:51:05 INFO - PROCESS | 8289 | |11;stanford-crypto-ccm;136;142;136;162;138;140;137;136;189;152 08:51:05 INFO - PROCESS | 8289 | |12;stanford-crypto-pbkdf2;181;187;187;180;180;182;183;179;190;179 08:51:05 INFO - PROCESS | 8289 | |13;stanford-crypto-sha256-iterative;70;75;69;69;69;68;70;76;69;74 08:51:05 INFO - PROCESS | 8289 | __end_tp_report 08:51:05 INFO - PROCESS | 8289 | __start_cc_report 08:51:05 INFO - PROCESS | 8289 | _x_x_mozilla_cycle_collect,833 08:51:05 INFO - PROCESS | 8289 | __end_cc_report 08:51:05 INFO - PROCESS | 8289 | __startTimestamp1482425465813__endTimestamp 08:51:05 INFO - PROCESS | 8289 | 08:51:05 INFO - PROCESS | 8289 | ------- Summary: start ------- 08:51:05 INFO - PROCESS | 8289 | Number of tests: 14 08:51:05 INFO - PROCESS | 8289 | 08:51:05 INFO - PROCESS | 8289 | [#0] ai-astar Cycles:10 Average:116.00 Median:117.00 stddev:2.71 (2.3%) stddev-sans-first:1.20 08:51:05 INFO - PROCESS | 8289 | Values: 109.0 118.0 115.0 118.0 117.0 117.0 117.0 118.0 116.0 115.0 08:51:05 INFO - PROCESS | 8289 | 08:51:05 INFO - PROCESS | 8289 | [#1] audio-beat-detection Cycles:10 Average:138.20 Median:131.50 stddev:17.69 (13.5%) stddev-sans-first:17.93 08:51:05 INFO - PROCESS | 8289 | Values: 153.0 134.0 129.0 180.0 128.0 128.0 126.0 127.0 150.0 127.0 08:51:05 INFO - PROCESS | 8289 | 08:51:05 INFO - PROCESS | 8289 | [#2] audio-dft Cycles:10 Average:165.30 Median:166.00 stddev:2.21 (1.3%) stddev-sans-first:2.35 08:51:05 INFO - PROCESS | 8289 | Values: 165.0 168.0 167.0 168.0 168.0 163.0 163.0 164.0 163.0 164.0 08:51:05 INFO - PROCESS | 8289 | 08:51:05 INFO - PROCESS | 8289 | [#3] audio-fft Cycles:10 Average:82.70 Median:78.00 stddev:12.07 (15.5%) stddev-sans-first:12.55 08:51:05 INFO - PROCESS | 8289 | Values: 76.0 77.0 80.0 115.0 78.0 77.0 78.0 76.0 90.0 80.0 08:51:05 INFO - PROCESS | 8289 | 08:51:05 INFO - PROCESS | 8289 | [#4] audio-oscillator Cycles:10 Average:91.90 Median:85.00 stddev:10.66 (12.5%) stddev-sans-first:11.16 08:51:05 INFO - PROCESS | 8289 | Values: 87.0 85.0 114.0 84.0 85.0 85.0 99.0 89.0 85.0 106.0 08:51:05 INFO - PROCESS | 8289 | 08:51:05 INFO - PROCESS | 8289 | [#5] imaging-gaussian-blur Cycles:10 Average:118.70 Median:119.00 stddev:0.95 (0.8%) stddev-sans-first:0.97 08:51:05 INFO - PROCESS | 8289 | Values: 118.0 118.0 119.0 119.0 121.0 118.0 118.0 119.0 119.0 118.0 08:51:05 INFO - PROCESS | 8289 | 08:51:05 INFO - PROCESS | 8289 | [#6] imaging-darkroom Cycles:10 Average:147.70 Median:148.00 stddev:0.82 (0.6%) stddev-sans-first:0.87 08:51:05 INFO - PROCESS | 8289 | Values: 148.0 149.0 147.0 148.0 147.0 148.0 147.0 149.0 147.0 147.0 08:51:05 INFO - PROCESS | 8289 | 08:51:05 INFO - PROCESS | 8289 | [#7] imaging-desaturate Cycles:10 Average:96.30 Median:96.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 08:51:05 INFO - PROCESS | 8289 | Values: 96.0 96.0 96.0 96.0 96.0 96.0 96.0 97.0 97.0 97.0 08:51:05 INFO - PROCESS | 8289 | 08:51:05 INFO - PROCESS | 8289 | [#8] json-parse-financial Cycles:10 Average:69.90 Median:69.50 stddev:2.96 (4.3%) stddev-sans-first:3.12 08:51:05 INFO - PROCESS | 8289 | Values: 69.0 68.0 68.0 70.0 78.0 68.0 70.0 70.0 69.0 69.0 08:51:05 INFO - PROCESS | 8289 | 08:51:05 INFO - PROCESS | 8289 | [#9] json-stringify-tinderbox Cycles:10 Average:66.90 Median:67.00 stddev:0.74 (1.1%) stddev-sans-first:0.78 08:51:05 INFO - PROCESS | 8289 | Values: 67.0 68.0 66.0 66.0 68.0 67.0 66.0 67.0 67.0 67.0 08:51:05 INFO - PROCESS | 8289 | 08:51:05 INFO - PROCESS | 8289 | [#10] stanford-crypto-aes Cycles:10 Average:86.00 Median:85.00 stddev:2.71 (3.2%) stddev-sans-first:2.77 08:51:05 INFO - PROCESS | 8289 | Values: 84.0 85.0 85.0 85.0 86.0 84.0 91.0 85.0 91.0 84.0 08:51:05 INFO - PROCESS | 8289 | 08:51:05 INFO - PROCESS | 8289 | [#11] stanford-crypto-ccm Cycles:10 Average:146.80 Median:141.00 stddev:17.10 (12.1%) stddev-sans-first:17.68 08:51:05 INFO - PROCESS | 8289 | Values: 136.0 142.0 136.0 162.0 138.0 140.0 137.0 136.0 189.0 152.0 08:51:05 INFO - PROCESS | 8289 | 08:51:05 INFO - PROCESS | 8289 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.80 Median:182.50 stddev:3.88 (2.1%) stddev-sans-first:4.06 08:51:05 INFO - PROCESS | 8289 | Values: 181.0 187.0 187.0 180.0 180.0 182.0 183.0 179.0 190.0 179.0 08:51:05 INFO - PROCESS | 8289 | 08:51:05 INFO - PROCESS | 8289 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.90 Median:70.00 stddev:2.92 (4.2%) stddev-sans-first:3.08 08:51:05 INFO - PROCESS | 8289 | Values: 70.0 75.0 69.0 69.0 69.0 68.0 70.0 76.0 69.0 74.0 08:51:05 INFO - PROCESS | 8289 | -------- Summary: end -------- 08:51:05 INFO - PROCESS | 8289 | 08:51:06 INFO - TEST-INFO | 8289: exit 0 08:51:06 INFO - TEST-OK | kraken | took 63827ms 08:51:06 INFO - SUITE-END | took 1183s 08:51:07 INFO - Completed test suite (00:19:43) 08:51:07 INFO - javascript benchmark 08:51:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6190.955942297102, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2644.9206523804287, 2648.5894686323613, 2654.163938359418, 2676.3175390275483, 2694.5419278722616, 4640.211164102111, 4828.493008234138, 4831.710097394154, 4836.492168322328, 4884.462709101996, 4680.836170734025, 4786.492254939701, 4786.736729479877, 4807.711537307764, 4818.204996175636, 4365.978170109153, 4387.583179597933, 4418.1384629997165, 4420.0, 4483.4171557697455, 4316.546762589915, 4390.341448782692, 4417.823287068513, 4418.955810441887, 4433.423654924855, 4212.452381190456, 4246.214450326668, 4479.7088189267915, 4482.7310361378095, 4527.615152712007, 4215.051613386963, 4279.358096285551, 4281.250781113293, 4281.379200016011, 4286.8499602514075, 4261.169072030955, 4316.805743741539, 4331.480222373304, 4357.891052723676, 4362.062156636307, 4716.0, 4761.6904901181315, 4777.784999675023, 4790.640701947333, 4817.397825271841, 5098.235264710286, 5135.229715542699, 5136.768845401966, 5137.6403651744395, 5177.094008548489, 5012.949870501321, 5057.696538207682, 5058.747062646853, 5122.897542049175, 5129.435762066169, 4750.0, 4769.213079841857, 4777.450593181759, 4786.569208771228, 4795.0, 5219.347581552306, 5269.894602107936, 5309.33633295838, 5328.786848526015, 5332.440093790174, 4983.202687569971, 5021.372328458942, 5059.72171530562, 5070.923936140961, 5081.898362032739, 4958.727270000112, 5034.370703662043, 5119.0, 5120.743962801919, 5123.052235336475, 4963.0, 4965.453800081988, 4968.30443737877, 5018.0, 5052.292679024939, 4920.114379411741, 4942.802287908516, 4961.950380496221, 4963.255511673278, 5009.674371165863, 4150.937735933963, 4197.958020419818, 4206.242876282298, 4209.2423363794815, 4245.8513952012145, 3625.184333524936, 3651.1602331463914, 3697.0, 3728.3848165053005, 3739.8130093495215, 4391.363252328394, 4430.047539778963, 4434.578715022068, 4463.24124898768, 4474.888127796831, 5136.666116702402, 5165.1735722285, 5198.298229739013, 5202.661826981234, 5258.474152584711, 4304.332828411601, 4324.610785029363, 4325.956740432618, 4377.868663940087, 4431.778411079433, 4522.321651752198, 4529.886752831206, 4536.432945881765, 4538.750368729751, 4541.137183935041, 4709.788059537329, 4766.66633335677, 4770.403699537558, 4772.117158325582, 4776.952230477651, 5126.6923984561045, 5134.204198349262, 5160.690358578498, 5193.246979188072, 5236.5287124157485, 4825.5898248649255, 4825.879353016203, 4831.830885918976, 4836.733979631154, 4881.707097574157, 4945.97527012377, 5083.059633967728, 5087.465816089258, 5094.0, 5134.358205224347, 5030.698158110525, 5089.236614507706, 5103.5661968733075, 5125.282460455464, 5225.1117310056625, 5006.3992320921725, 5014.247862820606, 5015.874603134951, 5017.222330538772, 5064.5441910228255, 5039.697618142924, 5041.294218809443, 5041.420236672683, 5043.0, 5043.67216130965, 4721.220998535203, 4756.429228492603, 4813.735244561442, 4898.044881248123, 4906.730129842893, 5045.142325804549, 5047.722375269395, 5047.722375269395, 5078.289039534542, 5092.949070509248, 4850.102730994778, 4898.951010489849, 4908.484609115992, 4910.680805747615, 4959.355283813081], "value": 4674.657136532264, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [500.91801708310055, 510.7880229704672, 511.55239165729984, 520.734425443024, 525.802823941022, 36848.0, 39049.60950390499, 39187.80406097969, 39204.82385528439, 39259.80370098152, 40073.0, 40743.18513629726, 40752.59247407525, 40800.0, 40830.0, 7516.699332026726, 7689.423293253, 7698.846023079535, 7704.999999999972, 7719.536827790323, 9300.581473833694, 9616.374935629197, 9635.662751803688, 9906.455144967023, 9908.702738917807, 9701.660441884536, 9730.513474326255, 9759.560819763128, 9761.658341958033, 9765.707028789147, 9594.472304023277, 9596.472194029291, 9633.759156021086, 9641.27690423221, 9659.806803863883, 9354.625814967394, 9471.90528094717, 9483.762905927339, 9487.620495180185, 9489.383190092622, 32898.6710132898, 33021.872265966755, 33120.668793312, 33180.336393272, 33209.16977075568, 2873.5689646552973, 2946.6611339695996, 2971.8514074296413, 2988.745956593692, 3340.46552551593, 25010.0, 25324.873375633004, 25634.615480767803, 28947.0, 28999.565006524917, 3432.2105915639545, 3514.3674138655033, 3540.9822950885336, 3542.681158695704, 3544.5392099026963, 245478.0, 246574.0, 246744.0, 249532.0, 250473.0, 54692.0, 56085.71957140188, 56207.718961404935, 56245.156322655195, 56317.0, 58897.0, 58989.70505147447, 59399.70300148558, 59455.40619579227, 59464.0, 15098.169600672069, 15332.156731379659, 15332.693346133015, 15359.846401535842, 15415.61460963485, 9578.377405468622, 9878.802423951625, 10463.68608941733, 11670.299782013106, 11826.704332391759, 18121.90939045296, 18454.0, 18707.90646046761, 18766.718499222523, 18827.623447531245, 6646.335366463315, 7052.435805135577, 7155.642217889084, 7317.26827317264, 7322.498887728003, 783.8628240058081, 853.1212850763724, 880.2781718990367, 889.4218757807477, 932.5756780664781, 25258.873705631355, 27092.59361109585, 27189.320266993484, 27656.446871062468, 27933.0, 910.4309806371018, 940.6002448959165, 944.8441007233729, 971.1114330387617, 999.3853779925294, 14248.216348100954, 14387.64030899215, 14494.85505144935, 14547.563573092824, 14671.926640366943, 345256.0, 346512.0, 351616.0, 352231.0, 352707.0, 48461.0, 49027.303498307934, 49082.75458622684, 49191.508084919406, 49218.26172607414, 1143.5768765556631, 1172.818213176959, 1198.8321634971112, 1202.05038019965, 1203.4163430736087, 25992.59211124896, 26522.0, 26893.596596050684, 27231.59152612673, 27327.590086148328, 35959.0, 36027.0, 36099.819500902326, 36106.63893361033, 36213.11122998467, 240134.0, 240751.0, 241168.0, 241932.0, 242156.0, 30471.238219044702, 31494.685053149176, 32786.18034549156, 33725.49411758874, 37177.81411092927, 39728.40407393949, 40731.796341018104, 41099.1780164401, 43609.781951090044, 45136.77431612821, 34461.0, 35355.82322088373, 35398.0, 35569.64430355767, 35673.46489802603, 34957.82521087378, 34985.65014349824, 35120.824395877855, 35702.0, 35796.284074317846, 31310.530342044432, 31502.212444689067, 33600.663993359754, 33612.159696007795, 35236.0, 30223.33771642581, 31960.360792783547, 32278.35443291168, 32284.83857580697, 32337.35325293528, 24220.152294669602, 24528.264152075466, 24651.876740616182, 24918.875405622857, 25011.12461063854], "value": 17543.779785362523, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.2539590671868, 360.32079530085787, 367.51121009057954, 376.26064782701985, 379.57866767887657, 15233.695326093472, 15998.520044398658, 16158.838411615896, 16235.0, 16244.75632865508, 15548.300326485307, 16125.758113628304, 16341.5914602135, 16535.090570018645, 16587.66824663506, 4840.370751802261, 5026.97486512567, 5040.722760248185, 5049.3940727112795, 5054.772535235905, 3633.7638053526566, 3825.1775868188333, 4923.926141107886, 4990.251462280668, 5407.3781515125875, 5632.436756324376, 5707.543396528268, 5774.884502309951, 5791.333996590404, 5802.39074897136, 5653.981898871294, 5778.422157784209, 5789.855253618651, 5816.185733997244, 5864.501517371029, 5189.6886186828915, 5190.195519694454, 5206.453322401131, 5208.140656791625, 5213.7914483421, 19239.80760192394, 20196.596068078557, 20237.392878213675, 20458.033585130073, 20634.0, 1837.145727236828, 1999.8000199979886, 2013.5268211970176, 2082.7084208210863, 2519.710233323173, 11912.821307680391, 12930.09489335747, 13616.38726257311, 13639.727205455934, 13691.863081369158, 1829.2957211473679, 1849.6023354978608, 1897.933572324962, 1900.4393703857395, 1918.9712154317697, 58219.0, 59633.45864661633, 76685.0, 78067.21932780714, 79972.60013699894, 8274.255317021498, 8499.447535910269, 8846.11538846123, 8854.601542930584, 8969.968453627785, 11325.263857849213, 11415.771684566429, 11426.714332141762, 11446.94276528612, 11487.25332853362, 6547.0, 6769.627670478072, 6796.592204467748, 6900.378965893094, 6960.408365289007, 6463.960024984385, 6543.640099794462, 6800.353966373186, 6806.115205023315, 6817.113775209191, 3625.873094441682, 3641.289948460078, 3665.890023299305, 3740.9438858417147, 3752.1557649528636, 1648.2582837723073, 1776.4315419065774, 1778.2264714848823, 1793.9192736326897, 1834.660587791263, 738.9224131466226, 774.6978678315461, 779.353136896386, 852.3564708645057, 946.0586716217408, 14952.32714527827, 15690.0, 16475.6704865902, 16496.587585310463, 17032.829671703374, 768.7501561992264, 793.6666600028002, 802.7952872017715, 817.4155478832663, 827.4952279109739, 7566.583837888859, 9141.3601047926, 9797.755056123653, 9833.4099954003, 9869.21046316293, 355167.0, 361811.0, 362599.0, 362672.0, 363120.0, 52883.20675189875, 60131.398686013454, 67064.0, 71262.64368678123, 72467.63766181136, 892.7946572288411, 949.8480243161061, 951.8857737071596, 954.1698722111807, 985.0199051943363, 14639.41442342337, 14973.32620032123, 15290.0, 15587.0, 15860.286287117338, 34695.0, 35331.29337413289, 35844.0, 36082.654627449134, 36480.0, 47287.290690638976, 48038.759806200746, 48247.035059297916, 50721.239181413046, 50747.492525074274, 10266.486675666336, 10578.259521833706, 10949.124070074535, 11128.554857805597, 11165.944170279097, 6497.805065848032, 8838.911610884066, 11241.71895702614, 11298.887011130113, 12091.39543022863, 7104.4370238547435, 7296.1974182838785, 7322.304381083789, 7592.734254301073, 7775.300222979959, 7605.543667379754, 7635.350995165473, 7685.0, 7756.495827771177, 7884.94991302254, 7564.432667549846, 7758.185390533913, 7827.608619568885, 7932.762017139495, 8197.344212463107, 10585.36487810703, 11252.381119038531, 11314.773704525698, 11663.76672466563, 11663.88336116628, 10808.837867431837, 10910.78178436411, 10959.0, 11062.336259824437, 11236.66290011301], "value": 7969.931473751821, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1317.4993502469063, 1356.5862411964347, 1463.2025546077386, 1657.809351924529, 1663.3263528271048, 5899.911501327473, 6097.176881121048, 6841.7947461576105, 7297.817554561132, 7355.117385913698, 6938.514303998722, 7213.170485394181, 7218.0, 7350.816229594263, 7372.668229929653, 2058.5368292134262, 2095.31902131807, 2098.07684618768, 2106.147010460766, 2115.851890367675, 2078.3349769646243, 2108.8523803333774, 2110.662294032955, 2114.12263910477, 2122.0, 2061.155266036896, 2082.5834833033377, 2092.476880779805, 2105.1895020417173, 2109.820665243456, 2042.9284975025878, 2077.491014701393, 2081.8542702010864, 2083.0313904034547, 2087.1025459052603, 2035.908384122718, 2042.60169266993, 2052.271443637517, 2055.9486012849648, 2061.2167376396874, 7155.570665760071, 7171.247019062976, 7258.745943891991, 7260.45546584003, 7270.800317947533, 4305.84929527465, 4443.977780111111, 4447.421835161441, 4447.533009034069, 4447.644188464915, 2788.7418540023036, 2820.534611789052, 2824.7316504932, 2892.6094977178022, 2897.1887871396043, 851.7189327521903, 889.9644014239487, 893.1158153428124, 910.4537277633433, 911.8221946720392, 9565.569549370295, 9567.186789122932, 9604.423734575948, 9627.229821614254, 9646.807063858683, 3746.83139258734, 3922.2547715933883, 3961.623645753649, 3982.6415622594104, 4011.5787842276723, 7890.684372625145, 7944.6822127114265, 7948.841023179504, 7961.562114083781, 7991.2807847294025, 4483.14820184164, 4569.611583015414, 4571.794269257892, 4572.702774319659, 4591.931121033187, 3384.1708781348234, 3505.4040813061843, 3966.305896468164, 4138.337865941435, 4160.895977600584, 3351.2124650707065, 3359.9328013439595, 3465.6880880720855, 3485.372632926047, 3489.2672538766637, 1132.841402203692, 1179.1804695736553, 1180.2387460088196, 1185.5257896841333, 1191.237607930925, 785.2265518464422, 797.5533701127388, 804.1757198871204, 816.7304789419477, 818.6684392820918, 4657.394538709946, 4741.383620129361, 4803.327534145223, 4863.416390033219, 5025.899482010339, 409.3491348755493, 410.46024477811585, 418.0864810389309, 420.20371396204024, 421.306950067144, 3367.393869103536, 3393.372226138172, 3396.3207358528393, 3426.9657303426648, 3468.341015207153, 8423.326133909273, 8717.389782715149, 8868.290536757044, 8894.0, 8895.199432051146, 8837.734867953972, 8851.513166775889, 8856.424332418372, 8863.157999989991, 8868.778280543038, 6800.761973330909, 6868.107146071079, 6868.278830722702, 6877.449804015667, 6886.070380498669, 4522.389477420506, 4546.909061818679, 4561.315802629632, 4598.172328980816, 4658.0, 7949.682012719427, 7996.720114795954, 8006.59967001636, 8029.156938521372, 8030.598470076589, 8113.0669972953865, 8155.347572194329, 8193.83612327762, 8265.132161122996, 8318.916810831814, 5374.059539580636, 5519.862003449946, 6093.421124993119, 6140.631562106288, 6198.0, 6561.1470508835455, 6801.965990170018, 6813.625250611264, 6973.895391569029, 6997.685104170427, 6148.723307451086, 6191.81424557264, 6317.052442133717, 6371.7132729028235, 6488.902666459912, 5942.257217847769, 5945.672987985702, 6055.1522786809, 6058.878822423439, 6067.423594758491, 5672.290963629546, 5775.884482310415, 5777.422257774357, 5777.653340799566, 5780.335261444988, 4109.321961876257, 4123.505179378494, 4246.193223287565, 4305.461817272841, 4330.025744207528, 4015.7841242002983, 4124.8556300529335, 4129.710920235555, 4152.294110001247, 4293.463317085364], "value": 3785.8543233746295, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [336.9831508424578, 344.8189949423225, 353.92036791721847, 355.2805568723335, 360.0853831268576, 4757.762111894409, 4869.3426387437685, 4952.678075925062, 5017.9247311290355, 5027.170516864722, 4797.6161907047435, 4878.48775878533, 4897.991823352426, 4903.313536104948, 4924.187509061001, 3937.803109844511, 3959.9208015839667, 3964.5044369453817, 3969.928119407758, 3975.264576053425, 3674.4855720199193, 3807.657310842024, 3965.3655415133585, 3977.2244412339605, 3989.3018721723583, 3753.2788728171995, 3761.322961866863, 3763.379042457991, 3769.359208934488, 3786.734928555002, 3740.981295093507, 3752.4746535485056, 3755.84976600937, 3760.417135344026, 3763.6477201748908, 3308.801471911693, 3309.2885029718727, 3310.7185889199445, 3324.069260607034, 3326.534285200074, 4638.814447422099, 4728.4798672146035, 4794.664373493857, 4801.399825021872, 4813.0, 4288.24955632763, 4315.30955047194, 4319.8056087475825, 4320.438343015419, 4339.222897345701, 5061.746912654353, 5087.0, 5129.487051294878, 5135.3837539495125, 5139.563137133348, 3435.055359776057, 3609.169890925076, 3610.6208848070837, 3614.457831325296, 3615.493830863681, 5345.224942383333, 5368.409474957791, 5411.404745477994, 5413.458654134594, 5419.83740487786, 3623.547056617923, 3641.453781932731, 3648.945265821015, 3661.6521430464595, 3676.191237927652, 3702.259548090393, 3743.2700623378723, 3743.794091325003, 3749.0, 3751.8311675974646, 4169.937450938239, 4207.558206388347, 4254.87235382939, 4265.936010959838, 4280.330671038298, 5157.355330583677, 5161.0, 5161.53546180838, 5167.741612919339, 5194.32473778414, 1227.3985746983913, 1251.7482517482517, 1252.0, 1262.6275248801626, 1264.2856785915928, 840.7855996720797, 862.4825104936988, 868.392125512144, 874.711345256064, 877.3200769403763, 4331.935020974688, 4386.627136693417, 4398.560143985575, 4403.9779801100785, 4412.691111622155, 5123.769430375642, 5128.25640282157, 5138.434772175057, 5193.0, 5216.426193118754, 4390.419448998358, 4392.582704643054, 4400.371862410864, 4418.8453404130705, 4429.18403454582, 2571.6142578612985, 2694.420699549567, 2726.386563023304, 2736.2885649731215, 2737.5893615957766, 4720.362751028636, 4745.881352966204, 4775.21209000518, 4776.8566942991765, 4871.537203965618, 4981.086064652147, 5254.369475662868, 5260.421353651172, 5277.577793776489, 5282.445343238982, 4788.688735232199, 4809.735464549343, 4813.205821039489, 4820.6143508519235, 4829.203181475087, 5159.039564965289, 5167.638265321391, 5186.9221961669855, 5187.662801917863, 5188.766505507337, 4934.333864928189, 5029.245613158056, 5044.310156097748, 5055.2448915341965, 5069.340985671839, 4998.625103117208, 5028.773705183203, 5043.420006699276, 5050.924236136388, 5058.3929928408825, 3730.645588669072, 3733.6826369757787, 3760.3419401605156, 3784.9432258515594, 3790.6209379061875, 5225.320708307896, 5451.400345961862, 5472.781088756406, 5477.287952566141, 5501.779928802963, 5156.20078887773, 5157.896842063213, 5178.300929374637, 5221.530062294411, 5262.97368513155, 5168.9741551292, 5185.299984502044, 5222.42553319127, 5266.8946621068135, 5279.7096159710045, 4938.654294199515, 5106.923396148986, 5149.189599696273, 5160.870978225425, 5163.328767260232, 5236.738163091754, 5244.685318880879, 5247.4490178530705, 5248.6325957182635, 5266.262723218829, 5101.260317254051, 5112.667676601009, 5130.333056702605, 5146.485351464824, 5149.48505149482], "value": 3857.4165829350254, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [589.4649243602571, 593.2554643921877, 641.8042497038401, 665.9500537459692, 673.8180691213372, 11207.327560346384, 12329.938350308246, 12374.319412432313, 12902.548410805624, 13094.279814610192, 11537.57710880714, 11774.470148843302, 11904.345261010641, 11937.224080434766, 11942.82085768712, 767.4282659418741, 784.8116452051495, 785.6268272570541, 786.1273985875673, 786.4848524216619, 755.0674916478173, 784.2667106255641, 785.8585454618167, 792.5759718550577, 794.9205079492034, 822.8642274024809, 824.5712229640583, 825.4015838517057, 827.1191181391829, 830.4767996162409, 818.2308629887887, 819.8565251081037, 820.1675299570941, 824.0811495182888, 824.0811495182888, 602.3795490644643, 604.78530121807, 605.3280858247342, 606.0152252589542, 606.1090197409844, 10369.74075648115, 12350.0, 12391.318477483735, 12458.626241212778, 12499.187552809039, 10575.259731818776, 11645.94177029118, 11870.88129118715, 11991.64025079249, 12121.33332666703, 9218.262538996863, 9310.441373517542, 9523.952380238125, 9613.134817866425, 9655.08276713711, 2683.8658067096762, 2701.149138021517, 2706.837589744602, 2720.782337413002, 2781.415902660444, 65142.674286628266, 65469.672651636436, 65715.67142164307, 65951.0, 66869.3313066868, 9417.105374989365, 9450.149486546245, 9457.621695132118, 9466.526673666289, 9521.904780952102, 16523.752143717855, 16570.6685866282, 16580.58548536272, 16691.248893799566, 16749.665006699797, 6469.54713170083, 6592.208934927743, 6671.899921501182, 6676.53264271506, 6755.3582409671, 3380.3408335374857, 3587.7309201809967, 3594.5866225384416, 3604.4052731299043, 3685.649863262986, 2610.8955641774496, 2728.1133631569423, 2732.098407525512, 2754.614353990443, 2765.1566272286746, 430.80613723824405, 437.25229856944344, 437.7592324221729, 443.17126972560874, 443.94894587122246, 533.6637918111564, 548.7338640759228, 550.5155463192365, 585.4789237578558, 588.5909293041381, 7959.243871832166, 7988.760337189894, 8040.206020921532, 8040.8793868092025, 8192.590370481452, 458.4658872413667, 473.5572239955584, 489.4860396583573, 551.0659432262357, 553.2475832867298, 3411.488276758506, 3473.3574288756663, 3540.291941611688, 3554.9822250889097, 3598.5501812273465, 50968.49031509637, 53384.0, 59322.0, 59531.10703339453, 65948.67025664842, 51773.48226517762, 52212.0, 53532.46467535353, 54973.175402368994, 56087.43912560904, 39297.0, 48170.0, 50249.24626130611, 51007.489925101756, 51582.226266606034, 7827.530348179127, 8411.53736544496, 8426.199511046441, 8442.620082096197, 8514.914850851412, 33304.66695333113, 33454.83272583621, 34002.489962650085, 35007.47488787619, 35206.47190292199, 39906.80046599865, 42790.0, 44051.77974110109, 45375.77312113418, 45715.54284457113, 9934.552945117632, 9974.11389884514, 10238.180945524191, 10480.790384192122, 10669.626563070255, 11740.647780566596, 11985.28088314704, 12026.939865300967, 12147.75704485923, 12245.0, 10233.641822536176, 10704.518296676824, 10968.177386696188, 11277.210595258253, 11539.423028848356, 11007.889921100686, 11052.668419947415, 11268.15488838324, 11346.262492937933, 11715.0, 10646.520906559379, 10808.675739727822, 11089.0, 11215.943920280346, 11673.12451566153, 8790.252828509392, 8971.91028089737, 9050.411723237969, 9316.394434361744, 9526.285528585246, 9667.274954378308, 9697.854532182164, 9879.456629885426, 9891.604335826487, 9945.154661853823], "value": 5898.0187581569, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1580.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 118.0, 115.0, 118.0, 117.0, 117.0, 117.0, 118.0, 116.0, 115.0], "value": 116.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 134.0, 129.0, 180.0, 128.0, 128.0, 126.0, 127.0, 150.0, 127.0], "value": 138.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 168.0, 167.0, 168.0, 168.0, 163.0, 163.0, 164.0, 163.0, 164.0], "value": 165.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 80.0, 115.0, 78.0, 77.0, 78.0, 76.0, 90.0, 80.0], "value": 82.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 114.0, 84.0, 85.0, 85.0, 99.0, 89.0, 85.0, 106.0], "value": 91.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 119.0, 119.0, 121.0, 118.0, 118.0, 119.0, 119.0, 118.0], "value": 118.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 149.0, 147.0, 148.0, 147.0, 148.0, 147.0, 149.0, 147.0, 147.0], "value": 147.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 97.0, 97.0, 97.0], "value": 96.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 68.0, 70.0, 78.0, 68.0, 70.0, 70.0, 69.0, 69.0], "value": 69.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 66.0, 66.0, 68.0, 67.0, 66.0, 67.0, 67.0, 67.0], "value": 66.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 85.0, 85.0, 86.0, 84.0, 91.0, 85.0, 91.0, 84.0], "value": 86.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 142.0, 136.0, 162.0, 138.0, 140.0, 137.0, 136.0, 189.0, 152.0], "value": 146.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 187.0, 187.0, 180.0, 180.0, 182.0, 183.0, 179.0, 190.0, 179.0], "value": 182.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 75.0, 69.0, 69.0, 69.0, 68.0, 70.0, 76.0, 69.0, 74.0], "value": 70.9, "unit": "score"}]}]} 08:51:07 INFO - Return code: 0 08:51:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:51:07 ERROR - # TBPL SUCCESS # 08:51:07 INFO - Running post-action listener: _resource_record_post_action 08:51:07 INFO - [mozharness: 2016-12-22 16:51:07.614840Z] Finished run-tests step (success) 08:51:07 INFO - Running post-run listener: _resource_record_post_run 08:51:07 INFO - Total resource usage - Wall time: 1200s; CPU: 14.0%; Read bytes: 4096; Write bytes: 384516096; Read time: 16; Write time: 295892 08:51:07 INFO - TinderboxPrint: CPU usage
13.5% 08:51:07 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 08:51:07 INFO - TinderboxPrint: I/O write bytes / time
384,516,096 / 295,892 08:51:07 INFO - TinderboxPrint: CPU idle
8,280.3 (86.5%) 08:51:07 INFO - TinderboxPrint: CPU user
1,235.1 (12.9%) 08:51:07 INFO - TinderboxPrint: Swap in / out
0 / 0 08:51:07 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 100888576; Read time: 0; Write time: 66704 08:51:07 INFO - run-tests - Wall time: 1184s; CPU: 14.0%; Read bytes: 0; Write bytes: 282480640; Read time: 0; Write time: 229160 08:51:08 INFO - Running post-run listener: _upload_blobber_files 08:51:08 INFO - Blob upload gear active. 08:51:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:51:08 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 08:51:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:51:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:51:08 INFO - (blobuploader) - INFO - Open directory for files ... 08:51:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:51:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:51:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:51:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:51:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:51:09 INFO - (blobuploader) - INFO - Done attempting. 08:51:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:51:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:51:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:51:10 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:51:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:51:10 INFO - (blobuploader) - INFO - Done attempting. 08:51:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:51:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:51:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:51:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:51:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:51:11 INFO - (blobuploader) - INFO - Done attempting. 08:51:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:51:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:51:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:51:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:51:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:51:14 INFO - (blobuploader) - INFO - Done attempting. 08:51:14 INFO - (blobuploader) - INFO - Iteration through files over. 08:51:14 INFO - Return code: 0 08:51:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:51:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:51:14 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/48f0a50c4ea1d4dd314bace3a269912213ead0721ece0251b764d0321ed10dd1408509476ea6c4eee4912f7d905e71b159c44825ff844461a0ebfaf20fb9fa7c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/be3275a4bf8662a644882fd2203823a26b51ca8705678ec8b76bedd80e3f4d8227bf40207173e169aefceda6d601032759d20b151b58151dbb31c9d6ceb34f5e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b97696ffc971e2eb7aedc4b04e8f21c91c7d927be1b4c00025777b60809434086c172d291eda1a3266a5fa803b0893f4f7c1966b210f7ee8967b76d80c91a10c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/86d8910c697e281d0acb66f6f10feaac321cc81be91c093de855b4572966d381c1b7f33546a59d77ad608c087227de5c4eda14715853e4cc1037f171ced53fb7"} 08:51:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:51:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:51:14 INFO - Contents: 08:51:14 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/48f0a50c4ea1d4dd314bace3a269912213ead0721ece0251b764d0321ed10dd1408509476ea6c4eee4912f7d905e71b159c44825ff844461a0ebfaf20fb9fa7c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/be3275a4bf8662a644882fd2203823a26b51ca8705678ec8b76bedd80e3f4d8227bf40207173e169aefceda6d601032759d20b151b58151dbb31c9d6ceb34f5e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b97696ffc971e2eb7aedc4b04e8f21c91c7d927be1b4c00025777b60809434086c172d291eda1a3266a5fa803b0893f4f7c1966b210f7ee8967b76d80c91a10c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/86d8910c697e281d0acb66f6f10feaac321cc81be91c093de855b4572966d381c1b7f33546a59d77ad608c087227de5c4eda14715853e4cc1037f171ced53fb7"} 08:51:14 INFO - Running post-run listener: copy_logs_to_upload_dir 08:51:14 INFO - Copying logs to upload dir... 08:51:14 INFO - mkdir: /builds/slave/test/build/upload/logs 08:51:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1246.959338 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-12-22 08:51:14.318389) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-22 08:51:14.321907) ========= 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: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=6d15e502d8e29d5d2676d664000001c3-1482424226.450401-90361196 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/48f0a50c4ea1d4dd314bace3a269912213ead0721ece0251b764d0321ed10dd1408509476ea6c4eee4912f7d905e71b159c44825ff844461a0ebfaf20fb9fa7c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/be3275a4bf8662a644882fd2203823a26b51ca8705678ec8b76bedd80e3f4d8227bf40207173e169aefceda6d601032759d20b151b58151dbb31c9d6ceb34f5e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b97696ffc971e2eb7aedc4b04e8f21c91c7d927be1b4c00025777b60809434086c172d291eda1a3266a5fa803b0893f4f7c1966b210f7ee8967b76d80c91a10c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/86d8910c697e281d0acb66f6f10feaac321cc81be91c093de855b4572966d381c1b7f33546a59d77ad608c087227de5c4eda14715853e4cc1037f171ced53fb7"} build_url:https://queue.taskcluster.net/v1/task/c881nCb6TfG6JvqCivK3oA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011297 build_url: 'https://queue.taskcluster.net/v1/task/c881nCb6TfG6JvqCivK3oA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/48f0a50c4ea1d4dd314bace3a269912213ead0721ece0251b764d0321ed10dd1408509476ea6c4eee4912f7d905e71b159c44825ff844461a0ebfaf20fb9fa7c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/be3275a4bf8662a644882fd2203823a26b51ca8705678ec8b76bedd80e3f4d8227bf40207173e169aefceda6d601032759d20b151b58151dbb31c9d6ceb34f5e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b97696ffc971e2eb7aedc4b04e8f21c91c7d927be1b4c00025777b60809434086c172d291eda1a3266a5fa803b0893f4f7c1966b210f7ee8967b76d80c91a10c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/86d8910c697e281d0acb66f6f10feaac321cc81be91c093de855b4572966d381c1b7f33546a59d77ad608c087227de5c4eda14715853e4cc1037f171ced53fb7"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-22 08:51:14.346554) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 08:51:14.346843) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=6d15e502d8e29d5d2676d664000001c3-1482424226.450401-90361196 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004550 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 08:51:14.432037) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-22 08:51:14.432339) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-22 08:51:14.432605) ========= ========= Total master_lag: 0.16 =========