builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-041 starttime: 1472519724.41 results: success (0) buildid: 20160829173530 builduid: e719c450bbf348dcb54fbfc2249261ef revision: 7293879d87d780790cb0f67e98226b7d4de6d4e1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-29 18:15:24.409448) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-29 18:15:24.409962) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-29 18:15:24.410267) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-29 18:15:24.450606) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 18:15:24.450896) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1472515787.361389-1398414265 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-29 18:15:24-- 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: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 225M=0s 2016-08-29 18:15:24 (225 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.117999 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 18:15:24.582447) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 18:15:24.582747) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1472515787.361389-1398414265 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017501 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 18:15:24.631614) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 18:15:24.631955) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 7293879d87d780790cb0f67e98226b7d4de6d4e1 --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 7293879d87d780790cb0f67e98226b7d4de6d4e1 --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=8c6b6742ce8f6f39ef4ce27d000001f1-1472515787.361389-1398414265 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-29 18:15:24,889 truncating revision to first 12 chars 2016-08-29 18:15:24,889 Setting DEBUG logging. 2016-08-29 18:15:24,889 attempt 1/10 2016-08-29 18:15:24,889 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/7293879d87d7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-29 18:15:25,223 unpacking tar archive at: mozilla-central-7293879d87d7/testing/mozharness/ program finished with exit code 0 elapsedTime=0.666950 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 18:15:25.313272) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-29 18:15:25.313578) ========= script_repo_revision: 7293879d87d780790cb0f67e98226b7d4de6d4e1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-29 18:15:25.313984) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-29 18:15:25.314281) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-29 18:15:25.324006) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-08-29 18:15:25.324392) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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=8c6b6742ce8f6f39ef4ce27d000001f1-1472515787.361389-1398414265 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:15:25 INFO - MultiFileLogger online at 20160829 18:15:25 in /builds/slave/test 18:15:25 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --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 18:15:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:15:25 INFO - {'append_to_log': False, 18:15:25 INFO - 'base_work_dir': '/builds/slave/test', 18:15:25 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 18:15:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:15:25 INFO - 'branch': 'Firefox-Non-PGO', 18:15:25 INFO - 'buildbot_json_path': 'buildprops.json', 18:15:25 INFO - 'config_files': ('talos/linux_config.py',), 18:15:25 INFO - 'default_actions': ('clobber', 18:15:25 INFO - 'read-buildbot-config', 18:15:25 INFO - 'download-and-extract', 18:15:25 INFO - 'populate-webroot', 18:15:25 INFO - 'create-virtualenv', 18:15:25 INFO - 'install', 18:15:25 INFO - 'run-tests'), 18:15:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:15:25 INFO - 'download_minidump_stackwalk': True, 18:15:25 INFO - 'download_symbols': 'ondemand', 18:15:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:15:25 INFO - 'tooltool.py': '/tools/tooltool.py', 18:15:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:15:25 INFO - '/tools/misc-python/virtualenv.py')}, 18:15:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:15:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:15:25 INFO - 'installer_path': 'installer.exe', 18:15:25 INFO - 'log_level': 'info', 18:15:25 INFO - 'log_name': 'talos', 18:15:25 INFO - 'log_to_console': True, 18:15:25 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:15:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:15:25 INFO - 'opt_config_files': (), 18:15:25 INFO - 'pip_index': False, 18:15:25 INFO - 'sps_profile': False, 18:15:25 INFO - 'sps_profile_interval': 0, 18:15:25 INFO - 'suite': 'dromaeojs', 18:15:25 INFO - 'system_bits': '32', 18:15:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:15:25 INFO - 'title': 'talos-linux64-ix-041', 18:15:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:15:25 INFO - 'use_talos_json': True, 18:15:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:15:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:15:25 INFO - 'work_dir': 'build'} 18:15:25 INFO - [mozharness: 2016-08-30 01:15:25.681576Z] Running clobber step. 18:15:25 INFO - Running pre-action listener: _resource_record_pre_action 18:15:25 INFO - Running main action method: clobber 18:15:25 INFO - rmtree: /builds/slave/test/build 18:15:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:15:26 INFO - Running post-action listener: _resource_record_post_action 18:15:26 INFO - [mozharness: 2016-08-30 01:15:26.152225Z] Finished clobber step (success) 18:15:26 INFO - [mozharness: 2016-08-30 01:15:26.152327Z] Running read-buildbot-config step. 18:15:26 INFO - Running pre-action listener: _resource_record_pre_action 18:15:26 INFO - Running main action method: read_buildbot_config 18:15:26 INFO - Using buildbot properties: 18:15:26 INFO - { 18:15:26 INFO - "project": "", 18:15:26 INFO - "product": "firefox", 18:15:26 INFO - "script_repo_revision": "production", 18:15:26 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 18:15:26 INFO - "repository": "", 18:15:26 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 18:15:26 INFO - "buildid": "20160829173530", 18:15:26 INFO - "pgo_build": "False", 18:15:26 INFO - "basedir": "/builds/slave/test", 18:15:26 INFO - "buildnumber": 193, 18:15:26 INFO - "slavename": "talos-linux64-ix-041", 18:15:26 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 18:15:26 INFO - "platform": "ubuntu64_hw", 18:15:26 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 18:15:26 INFO - "branch": "mozilla-central", 18:15:26 INFO - "repo_path": "mozilla-central", 18:15:26 INFO - "revision": "7293879d87d780790cb0f67e98226b7d4de6d4e1", 18:15:26 INFO - "stage_platform": "linux64", 18:15:26 INFO - "builduid": "e719c450bbf348dcb54fbfc2249261ef", 18:15:26 INFO - "slavebuilddir": "test" 18:15:26 INFO - } 18:15:26 INFO - Found installer url https://queue.taskcluster.net/v1/task/Er5BLUC3Siidl0rw56MISQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 18:15:26 INFO - Running post-action listener: _resource_record_post_action 18:15:26 INFO - [mozharness: 2016-08-30 01:15:26.154770Z] Finished read-buildbot-config step (success) 18:15:26 INFO - [mozharness: 2016-08-30 01:15:26.154861Z] Running download-and-extract step. 18:15:26 INFO - Running pre-action listener: _resource_record_pre_action 18:15:26 INFO - Running main action method: download_and_extract 18:15:26 INFO - mkdir: /builds/slave/test/build/tests 18:15:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:15:26 INFO - trying https://queue.taskcluster.net/v1/task/Er5BLUC3Siidl0rw56MISQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 18:15:26 INFO - Downloading https://queue.taskcluster.net/v1/task/Er5BLUC3Siidl0rw56MISQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 18:15:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Er5BLUC3Siidl0rw56MISQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 18:15:26 INFO - Downloaded 1361 bytes. 18:15:26 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 18:15:26 INFO - Using the following test package requirements: 18:15:26 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 18:15:26 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:15:26 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:15:26 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:15:26 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 18:15:26 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:15:26 INFO - u'jsshell-linux-x86_64.zip'], 18:15:26 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:15:26 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:15:26 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 18:15:26 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:15:26 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:15:26 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:15:26 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:15:26 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:15:26 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:15:26 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:15:26 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:15:26 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:15:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:15:26 INFO - trying https://queue.taskcluster.net/v1/task/Er5BLUC3Siidl0rw56MISQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 18:15:26 INFO - Downloading https://queue.taskcluster.net/v1/task/Er5BLUC3Siidl0rw56MISQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 18:15:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Er5BLUC3Siidl0rw56MISQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:15:27 INFO - Downloaded 20307782 bytes. 18:15:27 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 18:15:31 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 18:15:31 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')]} 18:15:31 INFO - trying https://queue.taskcluster.net/v1/task/Er5BLUC3Siidl0rw56MISQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 18:15:31 INFO - Downloading https://queue.taskcluster.net/v1/task/Er5BLUC3Siidl0rw56MISQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 18:15:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Er5BLUC3Siidl0rw56MISQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:15:32 INFO - Downloaded 20307782 bytes. 18:15:32 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 18:15:37 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')]} 18:15:37 INFO - trying https://queue.taskcluster.net/v1/task/Er5BLUC3Siidl0rw56MISQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 18:15:37 INFO - Downloading https://queue.taskcluster.net/v1/task/Er5BLUC3Siidl0rw56MISQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 18:15:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Er5BLUC3Siidl0rw56MISQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 18:15:37 INFO - Downloaded 14413998 bytes. 18:15:37 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/tests 18:15:38 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')]} 18:15:38 INFO - trying https://queue.taskcluster.net/v1/task/Er5BLUC3Siidl0rw56MISQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 18:15:38 INFO - Downloading https://queue.taskcluster.net/v1/task/Er5BLUC3Siidl0rw56MISQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 18:15:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Er5BLUC3Siidl0rw56MISQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 18:15:39 INFO - Downloaded 55868703 bytes. 18:15:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Er5BLUC3Siidl0rw56MISQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 18:15:39 INFO - mkdir: /builds/slave/test/properties 18:15:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:15:39 INFO - Writing to file /builds/slave/test/properties/build_url 18:15:39 INFO - Contents: 18:15:39 INFO - build_url:https://queue.taskcluster.net/v1/task/Er5BLUC3Siidl0rw56MISQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 18:15:39 INFO - Running post-action listener: _resource_record_post_action 18:15:39 INFO - Running post-action listener: set_extra_try_arguments 18:15:39 INFO - [mozharness: 2016-08-30 01:15:39.975858Z] Finished download-and-extract step (success) 18:15:39 INFO - [mozharness: 2016-08-30 01:15:39.976086Z] Running populate-webroot step. 18:15:39 INFO - Running pre-action listener: _resource_record_pre_action 18:15:39 INFO - Running main action method: populate_webroot 18:15:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:15:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:15:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:15:39 INFO - '--tppagecycles', 18:15:39 INFO - '7'], 18:15:39 INFO - 'tests': ['tsvgm']}}, 18:15:39 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:15:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:15:39 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:15:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:15:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:15:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:15:39 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:15:39 INFO - 'talos_options': ['--disable-e10s'], 18:15:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:15:39 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:15:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:15:39 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:15:39 INFO - 'talos_options': ['--disable-e10s'], 18:15:39 INFO - 'tests': ['damp', 'tps']}, 18:15:39 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:15:39 INFO - 'tests': ['damp', 'tps']}, 18:15:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:15:39 INFO - 'tests': ['dromaeo_dom']}, 18:15:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:15:39 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:15:39 INFO - 'tests': ['basic_compositor_video']}, 18:15:39 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:15:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:15:39 INFO - 'tests': ['a11yr', 18:15:39 INFO - 'ts_paint', 18:15:39 INFO - 'tpaint', 18:15:39 INFO - 'sessionrestore', 18:15:39 INFO - 'sessionrestore_no_auto_restore', 18:15:39 INFO - 'tabpaint']}, 18:15:39 INFO - 'other-e10s': {'tests': ['a11yr', 18:15:39 INFO - 'ts_paint', 18:15:39 INFO - 'tpaint', 18:15:39 INFO - 'sessionrestore', 18:15:39 INFO - 'sessionrestore_no_auto_restore', 18:15:39 INFO - 'tabpaint']}, 18:15:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:15:39 INFO - 'ts_paint', 18:15:39 INFO - 'tpaint', 18:15:39 INFO - 'sessionrestore', 18:15:39 INFO - 'sessionrestore_no_auto_restore', 18:15:39 INFO - 'tabpaint']}, 18:15:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:15:39 INFO - 'ts_paint', 18:15:39 INFO - 'tpaint', 18:15:39 INFO - 'sessionrestore', 18:15:39 INFO - 'sessionrestore_no_auto_restore', 18:15:39 INFO - 'tabpaint']}, 18:15:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:15:39 INFO - 'tests': ['a11yr', 18:15:39 INFO - 'ts_paint', 18:15:39 INFO - 'tpaint', 18:15:39 INFO - 'sessionrestore', 18:15:39 INFO - 'sessionrestore_no_auto_restore', 18:15:39 INFO - 'tabpaint']}, 18:15:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:15:39 INFO - 'tests': ['a11yr', 18:15:39 INFO - 'ts_paint', 18:15:39 INFO - 'tpaint', 18:15:39 INFO - 'sessionrestore', 18:15:39 INFO - 'sessionrestore_no_auto_restore', 18:15:39 INFO - 'tabpaint']}, 18:15:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:15:39 INFO - 'tests': ['tsvgx', 18:15:39 INFO - 'tsvgr_opacity', 18:15:39 INFO - 'tart', 18:15:39 INFO - 'tscrollx', 18:15:39 INFO - 'cart']}, 18:15:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:15:39 INFO - 'tsvgr_opacity', 18:15:39 INFO - 'tart', 18:15:39 INFO - 'tscrollx', 18:15:39 INFO - 'cart']}, 18:15:39 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:15:39 INFO - 'talos_options': ['--disable-e10s'], 18:15:39 INFO - 'tests': ['tp5o']}, 18:15:39 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:15:39 INFO - 'tests': ['tp5o']}, 18:15:39 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:15:39 INFO - 'talos_options': ['--disable-e10s', 18:15:39 INFO - '--xperf_path', 18:15:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:15:39 INFO - 'tests': ['tp5n']}, 18:15:39 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:15:39 INFO - 'talos_options': ['--xperf_path', 18:15:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:15:39 INFO - 'tests': ['tp5n']}}, 18:15:39 INFO - 'talos.zip': {'path': '', 18:15:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:15:39 INFO - Running post-action listener: _resource_record_post_action 18:15:39 INFO - [mozharness: 2016-08-30 01:15:39.994978Z] Finished populate-webroot step (success) 18:15:39 INFO - [mozharness: 2016-08-30 01:15:39.995067Z] Running create-virtualenv step. 18:15:39 INFO - Running pre-action listener: _resource_record_pre_action 18:15:39 INFO - Running main action method: create_virtualenv 18:15:39 INFO - Creating virtualenv /builds/slave/test/build/venv 18:15:39 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 18:15:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:15:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:15:40 INFO - Using real prefix '/usr' 18:15:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:15:40 INFO - Installing distribute.............................................................................................................................................................................................done. 18:15:42 INFO - Installing pip.................done. 18:15:42 INFO - Return code: 0 18:15:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:15:42 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')]} 18:15:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:15:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:15:42 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')]} 18:15:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:15:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:15:42 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 0x7fb8d47b1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc438a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd12230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd0c090>, '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 0xd0f9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd0fe40>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1472515787.361389-1398414265', '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 18:15:42 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 18:15:42 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 18:15:42 INFO - Using env: {'DISPLAY': ':0', 18:15:42 INFO - 'HOME': '/home/cltbld', 18:15:42 INFO - 'LANG': 'en_US.UTF-8', 18:15:42 INFO - 'LANGUAGE': 'en_US:en', 18:15:42 INFO - 'LOGNAME': 'cltbld', 18:15:42 INFO - 'MAIL': '/var/mail/cltbld', 18:15:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:15:42 INFO - 'MOZ_NO_REMOTE': '1', 18:15:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:15:42 INFO - 'NO_EM_RESTART': '1', 18:15:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:15:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:15:42 INFO - 'PWD': '/builds/slave/test', 18:15:42 INFO - 'SHELL': '/bin/bash', 18:15:42 INFO - 'SHLVL': '1', 18:15:42 INFO - 'TERM': 'linux', 18:15:42 INFO - 'TMOUT': '86400', 18:15:42 INFO - 'USER': 'cltbld', 18:15:42 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1472515787.361389-1398414265', 18:15:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:15:42 INFO - '_': '/tools/buildbot/bin/python'} 18:15:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:15:42 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 18:15:42 INFO - Cleaning up... 18:15:42 INFO - Return code: 0 18:15:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:15:42 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')]} 18:15:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:15:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:15:42 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')]} 18:15:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:15:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:15:42 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 0x7fb8d47b1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc438a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd12230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd0c090>, '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 0xd0f9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd0fe40>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1472515787.361389-1398414265', '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 18:15:42 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 18:15:42 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 18:15:42 INFO - Using env: {'DISPLAY': ':0', 18:15:42 INFO - 'HOME': '/home/cltbld', 18:15:42 INFO - 'LANG': 'en_US.UTF-8', 18:15:42 INFO - 'LANGUAGE': 'en_US:en', 18:15:42 INFO - 'LOGNAME': 'cltbld', 18:15:42 INFO - 'MAIL': '/var/mail/cltbld', 18:15:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:15:42 INFO - 'MOZ_NO_REMOTE': '1', 18:15:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:15:42 INFO - 'NO_EM_RESTART': '1', 18:15:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:15:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:15:42 INFO - 'PWD': '/builds/slave/test', 18:15:42 INFO - 'SHELL': '/bin/bash', 18:15:42 INFO - 'SHLVL': '1', 18:15:42 INFO - 'TERM': 'linux', 18:15:42 INFO - 'TMOUT': '86400', 18:15:42 INFO - 'USER': 'cltbld', 18:15:42 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1472515787.361389-1398414265', 18:15:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:15:42 INFO - '_': '/tools/buildbot/bin/python'} 18:15:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:15:42 INFO - Downloading/unpacking psutil>=3.1.1 18:15:42 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 18:15:42 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 18:15:42 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 18:15:42 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 18:15:42 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 18:15:42 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 18:15:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:15:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:15:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:15:45 INFO - Installing collected packages: psutil 18:15:45 INFO - Running setup.py install for psutil 18:15:45 INFO - building 'psutil._psutil_linux' extension 18:15:45 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 18:15:45 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 18:15:45 INFO - building 'psutil._psutil_posix' extension 18:15:45 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 18:15:45 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 18:15:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:15:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:15:45 INFO - Successfully installed psutil 18:15:45 INFO - Cleaning up... 18:15:45 INFO - Return code: 0 18:15:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:15:45 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')]} 18:15:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:15:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:15:45 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')]} 18:15:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:15:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:15:45 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 0x7fb8d47b1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc438a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd12230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd0c090>, '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 0xd0f9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd0fe40>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1472515787.361389-1398414265', '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 18:15:45 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 18:15:45 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 18:15:45 INFO - Using env: {'DISPLAY': ':0', 18:15:45 INFO - 'HOME': '/home/cltbld', 18:15:45 INFO - 'LANG': 'en_US.UTF-8', 18:15:45 INFO - 'LANGUAGE': 'en_US:en', 18:15:45 INFO - 'LOGNAME': 'cltbld', 18:15:45 INFO - 'MAIL': '/var/mail/cltbld', 18:15:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:15:45 INFO - 'MOZ_NO_REMOTE': '1', 18:15:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:15:45 INFO - 'NO_EM_RESTART': '1', 18:15:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:15:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:15:45 INFO - 'PWD': '/builds/slave/test', 18:15:45 INFO - 'SHELL': '/bin/bash', 18:15:45 INFO - 'SHLVL': '1', 18:15:45 INFO - 'TERM': 'linux', 18:15:45 INFO - 'TMOUT': '86400', 18:15:45 INFO - 'USER': 'cltbld', 18:15:45 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1472515787.361389-1398414265', 18:15:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:15:45 INFO - '_': '/tools/buildbot/bin/python'} 18:15:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:15:45 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:15:45 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 18:15:45 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 18:15:45 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 18:15:45 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 18:15:45 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 18:15:45 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 18:15:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:15:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:15:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:15:47 INFO - Installing collected packages: mozsystemmonitor 18:15:47 INFO - Running setup.py install for mozsystemmonitor 18:15:47 INFO - Successfully installed mozsystemmonitor 18:15:47 INFO - Cleaning up... 18:15:47 INFO - Return code: 0 18:15:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:15: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')]} 18:15:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:15:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:15: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')]} 18:15:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:15:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:15: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', '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 0x7fb8d47b1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc438a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd12230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd0c090>, '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 0xd0f9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd0fe40>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1472515787.361389-1398414265', '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 18:15: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:15: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 jsonschema==2.5.1 18:15:47 INFO - Using env: {'DISPLAY': ':0', 18:15:47 INFO - 'HOME': '/home/cltbld', 18:15:47 INFO - 'LANG': 'en_US.UTF-8', 18:15:47 INFO - 'LANGUAGE': 'en_US:en', 18:15:47 INFO - 'LOGNAME': 'cltbld', 18:15:47 INFO - 'MAIL': '/var/mail/cltbld', 18:15:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:15:47 INFO - 'MOZ_NO_REMOTE': '1', 18:15:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:15:47 INFO - 'NO_EM_RESTART': '1', 18:15:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:15:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:15:47 INFO - 'PWD': '/builds/slave/test', 18:15:47 INFO - 'SHELL': '/bin/bash', 18:15:47 INFO - 'SHLVL': '1', 18:15:47 INFO - 'TERM': 'linux', 18:15:47 INFO - 'TMOUT': '86400', 18:15:47 INFO - 'USER': 'cltbld', 18:15:47 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1472515787.361389-1398414265', 18:15:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:15:47 INFO - '_': '/tools/buildbot/bin/python'} 18:15:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:15:48 INFO - Downloading/unpacking jsonschema==2.5.1 18:15:48 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 18:15:48 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 18:15:48 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 18:15:48 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 18:15:48 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 18:15:48 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 18:15:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:15:49 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:15:49 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 18:15:49 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 18:15:49 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 18:15:49 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 18:15:49 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 18:15:49 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 18:15:50 INFO - Downloading functools32-3.2.3-2.tar.gz 18:15:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:15:50 INFO - warning: no files found matching '*.txt' 18:15:50 INFO - no previously-included directories found matching 'build' 18:15:50 INFO - no previously-included directories found matching 'dist' 18:15:50 INFO - no previously-included directories found matching '.git*' 18:15:50 INFO - Installing collected packages: jsonschema, functools32 18:15:50 INFO - Running setup.py install for functools32 18:15:50 INFO - warning: no files found matching '*.txt' 18:15:50 INFO - no previously-included directories found matching 'build' 18:15:50 INFO - no previously-included directories found matching 'dist' 18:15:50 INFO - no previously-included directories found matching '.git*' 18:15:50 INFO - Successfully installed jsonschema functools32 18:15:50 INFO - Cleaning up... 18:15:50 INFO - Return code: 0 18:15:50 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:15: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')]} 18:15:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:15:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:15: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')]} 18:15:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:15:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:15: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', '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 0x7fb8d47b1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc438a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd12230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd0c090>, '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 0xd0f9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd0fe40>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1472515787.361389-1398414265', '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 18:15: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:15: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 functools32==3.2.3-2 18:15:50 INFO - Using env: {'DISPLAY': ':0', 18:15:50 INFO - 'HOME': '/home/cltbld', 18:15:50 INFO - 'LANG': 'en_US.UTF-8', 18:15:50 INFO - 'LANGUAGE': 'en_US:en', 18:15:50 INFO - 'LOGNAME': 'cltbld', 18:15:50 INFO - 'MAIL': '/var/mail/cltbld', 18:15:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:15:50 INFO - 'MOZ_NO_REMOTE': '1', 18:15:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:15:50 INFO - 'NO_EM_RESTART': '1', 18:15:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:15:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:15:50 INFO - 'PWD': '/builds/slave/test', 18:15:50 INFO - 'SHELL': '/bin/bash', 18:15:50 INFO - 'SHLVL': '1', 18:15:50 INFO - 'TERM': 'linux', 18:15:50 INFO - 'TMOUT': '86400', 18:15:50 INFO - 'USER': 'cltbld', 18:15:50 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1472515787.361389-1398414265', 18:15:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:15:50 INFO - '_': '/tools/buildbot/bin/python'} 18:15:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:15:50 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:15:50 INFO - Cleaning up... 18:15:50 INFO - Return code: 0 18:15:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:15: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')]} 18:15:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:15:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:15: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')]} 18:15:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:15:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:15: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', '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 0x7fb8d47b1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc438a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd12230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd0c090>, '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 0xd0f9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd0fe40>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1472515787.361389-1398414265', '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 18:15: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:15: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 blobuploader==1.2.4 18:15:50 INFO - Using env: {'DISPLAY': ':0', 18:15:50 INFO - 'HOME': '/home/cltbld', 18:15:50 INFO - 'LANG': 'en_US.UTF-8', 18:15:50 INFO - 'LANGUAGE': 'en_US:en', 18:15:50 INFO - 'LOGNAME': 'cltbld', 18:15:50 INFO - 'MAIL': '/var/mail/cltbld', 18:15:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:15:50 INFO - 'MOZ_NO_REMOTE': '1', 18:15:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:15:50 INFO - 'NO_EM_RESTART': '1', 18:15:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:15:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:15:50 INFO - 'PWD': '/builds/slave/test', 18:15:50 INFO - 'SHELL': '/bin/bash', 18:15:50 INFO - 'SHLVL': '1', 18:15:50 INFO - 'TERM': 'linux', 18:15:50 INFO - 'TMOUT': '86400', 18:15:50 INFO - 'USER': 'cltbld', 18:15:50 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1472515787.361389-1398414265', 18:15:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:15:50 INFO - '_': '/tools/buildbot/bin/python'} 18:15:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:15:50 INFO - Downloading/unpacking blobuploader==1.2.4 18:15: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 18:15: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 18:15: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 18:15: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 18:15: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 18:15: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 18:15:52 INFO - Downloading blobuploader-1.2.4.tar.gz 18:15:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:15:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:15: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 18:15: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 18:15: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 18:15: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 18:15: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 18:15: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 18:15:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:15:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:15:53 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 18:15:53 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 18:15:53 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 18:15:53 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 18:15:53 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 18:15:53 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 18:15:53 INFO - Downloading docopt-0.6.1.tar.gz 18:15:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:15:53 INFO - Installing collected packages: blobuploader, requests, docopt 18:15:53 INFO - Running setup.py install for blobuploader 18:15:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:15:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:15:53 INFO - Running setup.py install for requests 18:15:53 INFO - Running setup.py install for docopt 18:15:54 INFO - Successfully installed blobuploader requests docopt 18:15:54 INFO - Cleaning up... 18:15:54 INFO - Return code: 0 18:15:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:15:54 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')]} 18:15:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:15:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:15:54 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')]} 18:15:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:15:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:15:54 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 0x7fb8d47b1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc438a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd12230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd0c090>, '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 0xd0f9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd0fe40>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1472515787.361389-1398414265', '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 18:15:54 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 18:15:54 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 18:15:54 INFO - Using env: {'DISPLAY': ':0', 18:15:54 INFO - 'HOME': '/home/cltbld', 18:15:54 INFO - 'LANG': 'en_US.UTF-8', 18:15:54 INFO - 'LANGUAGE': 'en_US:en', 18:15:54 INFO - 'LOGNAME': 'cltbld', 18:15:54 INFO - 'MAIL': '/var/mail/cltbld', 18:15:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:15:54 INFO - 'MOZ_NO_REMOTE': '1', 18:15:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:15:54 INFO - 'NO_EM_RESTART': '1', 18:15:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:15:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:15:54 INFO - 'PWD': '/builds/slave/test', 18:15:54 INFO - 'SHELL': '/bin/bash', 18:15:54 INFO - 'SHLVL': '1', 18:15:54 INFO - 'TERM': 'linux', 18:15:54 INFO - 'TMOUT': '86400', 18:15:54 INFO - 'USER': 'cltbld', 18:15:54 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1472515787.361389-1398414265', 18:15:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:15:54 INFO - '_': '/tools/buildbot/bin/python'} 18:15:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:15:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:15:54 INFO - Running setup.py (path:/tmp/pip-251CJA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:15:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:15:54 INFO - Running setup.py (path:/tmp/pip-tcqqj5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:15:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:15:54 INFO - Running setup.py (path:/tmp/pip-kr_duv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:15:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:15:54 INFO - Running setup.py (path:/tmp/pip-FmfopJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:15:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:15:54 INFO - Running setup.py (path:/tmp/pip-15iR2d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:15:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:15:54 INFO - Running setup.py (path:/tmp/pip-hk0cry-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:15:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:15:54 INFO - Running setup.py (path:/tmp/pip-xCuaKC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:15:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:15:54 INFO - Running setup.py (path:/tmp/pip-W8Pfxh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:15:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:15:54 INFO - Running setup.py (path:/tmp/pip-BBjD9y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:15:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:15:54 INFO - Running setup.py (path:/tmp/pip-r3Lx1g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:15:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:15:55 INFO - Running setup.py (path:/tmp/pip-IvTSs5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:15:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:15:55 INFO - Running setup.py (path:/tmp/pip-sbbuIM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:15:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:15:55 INFO - Running setup.py (path:/tmp/pip-Dl_8IY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:15:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:15:55 INFO - Running setup.py (path:/tmp/pip-mYpVpG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:15:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:15:55 INFO - Running setup.py (path:/tmp/pip-4FAhM5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:15:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:15:55 INFO - Running setup.py (path:/tmp/pip-XxiamP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:15:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:15:55 INFO - Running setup.py (path:/tmp/pip-7QtjIe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:15:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:15:55 INFO - Running setup.py install for manifestparser 18:15:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:15:55 INFO - Running setup.py install for mozcrash 18:15:55 INFO - Running setup.py install for mozdebug 18:15:55 INFO - Running setup.py install for mozdevice 18:15:55 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:15:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:15:55 INFO - Running setup.py install for mozfile 18:15:56 INFO - Running setup.py install for mozhttpd 18:15:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:15:56 INFO - Running setup.py install for mozinfo 18:15:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:15:56 INFO - Running setup.py install for mozInstall 18:15:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:15:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:15:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:15:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:15:56 INFO - Running setup.py install for mozleak 18:15:56 INFO - Running setup.py install for mozlog 18:15:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:15:56 INFO - Running setup.py install for moznetwork 18:15:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:15:56 INFO - Running setup.py install for mozprocess 18:15:56 INFO - Running setup.py install for mozprofile 18:15:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:15:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:15:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:15:56 INFO - Running setup.py install for mozrunner 18:15:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:15:57 INFO - Running setup.py install for mozscreenshot 18:15:57 INFO - Running setup.py install for moztest 18:15:57 INFO - Running setup.py install for mozversion 18:15:57 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:15:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:15:57 INFO - Cleaning up... 18:15:57 INFO - Return code: 0 18:15:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:15:57 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')]} 18:15:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:15:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:15:57 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')]} 18:15:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:15:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:15:57 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 0x7fb8d47b1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc438a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd12230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd0c090>, '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 0xd0f9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd0fe40>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1472515787.361389-1398414265', '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 18:15:57 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 18:15:57 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 18:15:57 INFO - Using env: {'DISPLAY': ':0', 18:15:57 INFO - 'HOME': '/home/cltbld', 18:15:57 INFO - 'LANG': 'en_US.UTF-8', 18:15:57 INFO - 'LANGUAGE': 'en_US:en', 18:15:57 INFO - 'LOGNAME': 'cltbld', 18:15:57 INFO - 'MAIL': '/var/mail/cltbld', 18:15:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:15:57 INFO - 'MOZ_NO_REMOTE': '1', 18:15:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:15:57 INFO - 'NO_EM_RESTART': '1', 18:15:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:15:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:15:57 INFO - 'PWD': '/builds/slave/test', 18:15:57 INFO - 'SHELL': '/bin/bash', 18:15:57 INFO - 'SHLVL': '1', 18:15:57 INFO - 'TERM': 'linux', 18:15:57 INFO - 'TMOUT': '86400', 18:15:57 INFO - 'USER': 'cltbld', 18:15:57 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1472515787.361389-1398414265', 18:15:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:15:57 INFO - '_': '/tools/buildbot/bin/python'} 18:15:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:15:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:15:57 INFO - Running setup.py (path:/tmp/pip-89bNwX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:15:57 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)) 18:15:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:15:57 INFO - Running setup.py (path:/tmp/pip-oCwS4O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:15:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:15:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:15:57 INFO - Running setup.py (path:/tmp/pip-w2Ny9l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:15:57 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)) 18:15:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:15:57 INFO - Running setup.py (path:/tmp/pip-Wpz0DX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:15:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:15:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:15:57 INFO - Running setup.py (path:/tmp/pip-i63nSq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:15:57 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)) 18:15:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:15:57 INFO - Running setup.py (path:/tmp/pip-T3RO4b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:15:57 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)) 18:15:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:15:57 INFO - Running setup.py (path:/tmp/pip-QYCYkP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:15:58 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)) 18:15:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:15:58 INFO - Running setup.py (path:/tmp/pip-pfNasS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:15:58 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)) 18:15:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:15:58 INFO - Running setup.py (path:/tmp/pip-pB6sUE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:15:58 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)) 18:15:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:15:58 INFO - Running setup.py (path:/tmp/pip-VsUWh0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:15:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 18:15:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:15:58 INFO - Running setup.py (path:/tmp/pip-AqdTx5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:15:58 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)) 18:15:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:15:58 INFO - Running setup.py (path:/tmp/pip-7CGbAX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:15:58 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)) 18:15:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:15:58 INFO - Running setup.py (path:/tmp/pip-SB1MgH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:15:58 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)) 18:15:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:15:58 INFO - Running setup.py (path:/tmp/pip-UyDOC2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:15:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 18:15:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:15:58 INFO - Running setup.py (path:/tmp/pip-NY8RgK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:15:58 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)) 18:15:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:15:58 INFO - Running setup.py (path:/tmp/pip-QJuH8h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:15:58 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)) 18:15:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:15:58 INFO - Running setup.py (path:/tmp/pip-UUAke0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:15:58 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)) 18:15:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:15:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:15:58 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)) 18:15:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:15:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:15:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:15: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 18:15: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 18:15: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 18:15: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 18:15: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 18:15: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 18:16:00 INFO - Downloading blessings-1.6.tar.gz 18:16:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:16:00 INFO - Installing collected packages: blessings 18:16:00 INFO - Running setup.py install for blessings 18:16:00 INFO - Successfully installed blessings 18:16:00 INFO - Cleaning up... 18:16:00 INFO - Return code: 0 18:16:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:16:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:16:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:16:01 INFO - Reading from file tmpfile_stdout 18:16:01 INFO - Current package versions: 18:16:01 INFO - argparse == 1.2.1 18:16:01 INFO - blessings == 1.6 18:16:01 INFO - blobuploader == 1.2.4 18:16:01 INFO - docopt == 0.6.1 18:16:01 INFO - functools32 == 3.2.3-2 18:16:01 INFO - jsonschema == 2.5.1 18:16:01 INFO - manifestparser == 1.1 18:16:01 INFO - mozInstall == 1.12 18:16:01 INFO - mozcrash == 0.17 18:16:01 INFO - mozdebug == 0.1 18:16:01 INFO - mozdevice == 0.48 18:16:01 INFO - mozfile == 1.2 18:16:01 INFO - mozhttpd == 0.7 18:16:01 INFO - mozinfo == 0.9 18:16:01 INFO - mozleak == 0.1 18:16:01 INFO - mozlog == 3.3 18:16:01 INFO - moznetwork == 0.27 18:16:01 INFO - mozprocess == 0.23 18:16:01 INFO - mozprofile == 0.28 18:16:01 INFO - mozrunner == 6.12 18:16:01 INFO - mozscreenshot == 0.1 18:16:01 INFO - mozsystemmonitor == 0.3 18:16:01 INFO - moztest == 0.7 18:16:01 INFO - mozversion == 1.4 18:16:01 INFO - psutil == 3.1.1 18:16:01 INFO - requests == 1.2.3 18:16:01 INFO - wsgiref == 0.1.2 18:16:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:16:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:01 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 0x7fb8d47b1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc438a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd12230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd0c090>, '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 0xd0f9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd0fe40>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1472515787.361389-1398414265', '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 18:16:01 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 18:16:01 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 18:16:01 INFO - Using env: {'DISPLAY': ':0', 18:16:01 INFO - 'HOME': '/home/cltbld', 18:16:01 INFO - 'LANG': 'en_US.UTF-8', 18:16:01 INFO - 'LANGUAGE': 'en_US:en', 18:16:01 INFO - 'LOGNAME': 'cltbld', 18:16:01 INFO - 'MAIL': '/var/mail/cltbld', 18:16:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:01 INFO - 'MOZ_NO_REMOTE': '1', 18:16:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:16:01 INFO - 'NO_EM_RESTART': '1', 18:16:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:16:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:01 INFO - 'PWD': '/builds/slave/test', 18:16:01 INFO - 'SHELL': '/bin/bash', 18:16:01 INFO - 'SHLVL': '1', 18:16:01 INFO - 'TERM': 'linux', 18:16:01 INFO - 'TMOUT': '86400', 18:16:01 INFO - 'USER': 'cltbld', 18:16:01 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1472515787.361389-1398414265', 18:16:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:01 INFO - '_': '/tools/buildbot/bin/python'} 18:16:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:01 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)) 18:16:01 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)) 18:16:01 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)) 18:16:01 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)) 18:16:01 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)) 18:16:01 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)) 18:16:01 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)) 18:16:01 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)) 18:16:01 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)) 18:16:01 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)) 18:16:01 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)) 18:16:01 INFO - Cleaning up... 18:16:01 INFO - Return code: 0 18:16:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:16:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:01 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 0x7fb8d47b1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc438a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd12230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd0c090>, '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 0xd0f9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd0fe40>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1472515787.361389-1398414265', '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 18:16:01 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 18:16:01 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 18:16:01 INFO - Using env: {'DISPLAY': ':0', 18:16:01 INFO - 'HOME': '/home/cltbld', 18:16:01 INFO - 'LANG': 'en_US.UTF-8', 18:16:01 INFO - 'LANGUAGE': 'en_US:en', 18:16:01 INFO - 'LOGNAME': 'cltbld', 18:16:01 INFO - 'MAIL': '/var/mail/cltbld', 18:16:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:01 INFO - 'MOZ_NO_REMOTE': '1', 18:16:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:16:01 INFO - 'NO_EM_RESTART': '1', 18:16:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:16:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:01 INFO - 'PWD': '/builds/slave/test', 18:16:01 INFO - 'SHELL': '/bin/bash', 18:16:01 INFO - 'SHLVL': '1', 18:16:01 INFO - 'TERM': 'linux', 18:16:01 INFO - 'TMOUT': '86400', 18:16:01 INFO - 'USER': 'cltbld', 18:16:01 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1472515787.361389-1398414265', 18:16:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:01 INFO - '_': '/tools/buildbot/bin/python'} 18:16:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:01 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:16:01 INFO - Cleaning up... 18:16:01 INFO - Return code: 0 18:16:01 INFO - Running post-action listener: _resource_record_post_action 18:16:01 INFO - Running post-action listener: _start_resource_monitoring 18:16:01 INFO - Starting resource monitoring. 18:16:01 INFO - [mozharness: 2016-08-30 01:16:01.578068Z] Finished create-virtualenv step (success) 18:16:01 INFO - [mozharness: 2016-08-30 01:16:01.578529Z] Running install step. 18:16:01 INFO - Running pre-action listener: _resource_record_pre_action 18:16:01 INFO - Running main action method: install 18:16:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:16:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:16:01 INFO - Reading from file tmpfile_stdout 18:16:01 INFO - Detecting whether we're running mozinstall >=1.0... 18:16:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:16:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:16:01 INFO - Reading from file tmpfile_stdout 18:16:01 INFO - Output received: 18:16:01 INFO - Usage: mozinstall [options] installer 18:16:01 INFO - Options: 18:16:01 INFO - -h, --help show this help message and exit 18:16:01 INFO - -d DEST, --destination=DEST 18:16:01 INFO - Directory to install application into. [default: 18:16:01 INFO - "/builds/slave/test"] 18:16:01 INFO - --app=APP Application being installed. [default: firefox] 18:16:01 INFO - mkdir: /builds/slave/test/build/application 18:16:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 18:16:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 18:16:18 INFO - Reading from file tmpfile_stdout 18:16:18 INFO - Output received: 18:16:18 INFO - /builds/slave/test/build/application/firefox/firefox 18:16:18 INFO - Running post-action listener: _resource_record_post_action 18:16:18 INFO - [mozharness: 2016-08-30 01:16:18.030675Z] Finished install step (success) 18:16:18 INFO - [mozharness: 2016-08-30 01:16:18.030958Z] Running run-tests step. 18:16:18 INFO - Running pre-action listener: _resource_record_pre_action 18:16:18 INFO - Running main action method: run_tests 18:16:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:16:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:16:18 INFO - Python 2.7.3 18:16:18 INFO - Return code: 0 18:16:18 INFO - grabbing minidump binary from tooltool 18:16:18 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')]} 18:16:18 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 0xd0c090>, '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 0xd0f9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd0fe40>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:16:18 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 18:16:18 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 18:16:18 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 18:16:18 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:16:18 INFO - Return code: 0 18:16:18 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:16:18 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:16:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:16:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:16:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:16:18 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', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Er5BLUC3Siidl0rw56MISQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-041', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 18:16:18 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 --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Er5BLUC3Siidl0rw56MISQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-041 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 18:16:18 INFO - Using env: {'DISPLAY': ':0', 18:16:18 INFO - 'HOME': '/home/cltbld', 18:16:18 INFO - 'LANG': 'en_US.UTF-8', 18:16:18 INFO - 'LANGUAGE': 'en_US:en', 18:16:18 INFO - 'LOGNAME': 'cltbld', 18:16:18 INFO - 'MAIL': '/var/mail/cltbld', 18:16:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:16:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:16:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:18 INFO - 'MOZ_NO_REMOTE': '1', 18:16:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:16:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:16:18 INFO - 'NO_EM_RESTART': '1', 18:16:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:16:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:18 INFO - 'PWD': '/builds/slave/test', 18:16:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:16:18 INFO - 'SHELL': '/bin/bash', 18:16:18 INFO - 'SHLVL': '1', 18:16:18 INFO - 'TERM': 'linux', 18:16:18 INFO - 'TMOUT': '86400', 18:16:18 INFO - 'USER': 'cltbld', 18:16:18 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1472515787.361389-1398414265', 18:16:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:18 INFO - '_': '/tools/buildbot/bin/python'} 18:16:18 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', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Er5BLUC3Siidl0rw56MISQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-041', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 18:16:18 INFO - mozversion application_buildid: 20160829173530 18:16:18 INFO - mozversion application_changeset: 7293879d87d780790cb0f67e98226b7d4de6d4e1 18:16:18 INFO - mozversion application_display_name: Nightly 18:16:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:16:18 INFO - mozversion application_name: Firefox 18:16:18 INFO - mozversion application_remotingname: firefox 18:16:18 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:16:18 INFO - mozversion application_vendor: Mozilla 18:16:18 INFO - mozversion application_version: 51.0a1 18:16:18 INFO - mozversion platform_buildid: 20160829173530 18:16:18 INFO - mozversion platform_changeset: 7293879d87d780790cb0f67e98226b7d4de6d4e1 18:16:18 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:16:18 INFO - mozversion platform_version: 51.0a1 18:16:18 INFO - using testdate: 1472519778 18:16:18 INFO - actual date: 1472519778 18:16:18 INFO - starting webserver on 'localhost:33969' 18:16:18 INFO - SUITE-START | Running 2 tests 18:16:18 INFO - TEST-START | dromaeo_css 18:16:18 INFO - operating with platform_type : linux_ 18:16:18 INFO - Initialising browser for dromaeo_css test... 18:16:18 INFO - TEST-INFO | started process 13544 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLvykjx/profile http://localhost:33969/getInfo.html) 18:16:22 INFO - PROCESS | 13544 | __metrics Screen width/height:1600/1200 18:16:22 INFO - PROCESS | 13544 | colorDepth:24 18:16:22 INFO - PROCESS | 13544 | Browser inner width/height: 1024/697 18:16:22 INFO - PROCESS | 13544 | __metrics 18:16:23 INFO - PROCESS | 13544 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:16:23 INFO - PROCESS | 13544 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:16:24 INFO - PROCESS | 13544 | console.error: PushService: 18:16:24 INFO - PROCESS | 13544 | stateChangeProcessEnqueue: Error transitioning state 18:16:24 INFO - PROCESS | 13544 | UnknownError 18:16:24 INFO - TEST-INFO | 13544: exit 0 18:16:24 INFO - Browser initialized. 18:16:24 INFO - Running cycle 1/1 for dromaeo_css test... 18:16:24 INFO - TEST-INFO | started process 13627 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLvykjx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:16:36 INFO - PROCESS | 13627 | RSS: Main: 181686272 18:16:36 INFO - PROCESS | 13627 | 18:17:27 INFO - PROCESS | 13627 | 1472519847888 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] 18:19:41 INFO - PROCESS | 13627 | Cycle 1(1): loaded http://localhost:33969/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33969/tests/dromaeo/cssquery-ext.html) 18:19:42 INFO - PROCESS | 13627 | RSS: Main: 281780224 18:19:42 INFO - PROCESS | 13627 | 18:22:45 INFO - PROCESS | 13627 | Cycle 1(1): loaded http://localhost:33969/tests/dromaeo/cssquery-ext.html (next: http://localhost:33969/tests/dromaeo/cssquery-jquery.html) 18:22:45 INFO - PROCESS | 13627 | RSS: Main: 275247104 18:22:45 INFO - PROCESS | 13627 | 18:25:47 INFO - PROCESS | 13627 | Cycle 1(1): loaded http://localhost:33969/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33969/tests/dromaeo/cssquery-mootools.html) 18:25:48 INFO - PROCESS | 13627 | RSS: Main: 246734848 18:25:48 INFO - PROCESS | 13627 | 18:28:50 INFO - PROCESS | 13627 | Cycle 1(1): loaded http://localhost:33969/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33969/tests/dromaeo/cssquery-prototype.html) 18:28:51 INFO - PROCESS | 13627 | RSS: Main: 220012544 18:28:51 INFO - PROCESS | 13627 | 18:31:54 INFO - PROCESS | 13627 | Cycle 1(1): loaded http://localhost:33969/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33969/tests/dromaeo/cssquery-yui.html) 18:31:54 INFO - PROCESS | 13627 | RSS: Main: 221896704 18:31:54 INFO - PROCESS | 13627 | 18:34:58 INFO - PROCESS | 13627 | Cycle 1(1): loaded http://localhost:33969/tests/dromaeo/cssquery-yui.html (next: http://localhost:33969/tests/dromaeo/cssquery-dojo.html) 18:34:58 INFO - PROCESS | 13627 | RSS: Main: 312311808 18:34:58 INFO - PROCESS | 13627 | 18:34:58 INFO - PROCESS | 13627 | __start_tp_report 18:34:58 INFO - PROCESS | 13627 | _x_x_mozilla_page_load 18:34:58 INFO - PROCESS | 13627 | _x_x_mozilla_page_load_details 18:34:58 INFO - PROCESS | 13627 | |i|pagename|runs| 18:34:58 INFO - PROCESS | 13627 | |0;dojo.html;2577.832440891342;2587.482503499298;2597;2611.777998870096;2620.816542842002;4630.444346678395;4640.09518143962;4669.509701481338;4737.242041273397;4756.66703330767;4640.141573808848;4671.953280467194;4680.625549956004;4684.695494792836;4745.335653008581;4255.404243405926;4279.935800962988;4335.349697545377;4393.428854248943;4466.218411777926;4370.978145109286;4396.56034396561;4406.757628330441;4411.977940110295;4442.8667139985855;4233.745975241495;4268.637165840907;4275.123599662062;4285.935710964338;4288.44250247469;4269.380939763717;4287.335463003239;4393.319035549463;4400.119976004812;4406.603405693503;4294.592013758688;4311.91376172478;4313.352997050436;4348.347747837818;4377.890552736207;4595.839145629887;4698;4738.4313882334;4739.691920025221;4743.715377077387;4960.70235785854;4966;4983.551480366785;4998.6750861193905;5002.52476014778;4985.501449855022;5015.297858299805;5018.272350509155;5060.367454068241;5089.592832573385;4624.398828152319;4637.86086417408;4680.906381872343;4687.9765601171775;4703.811847526061;5128.7179205144075;5153.716545589954;5174.6377753557645;5229.764660590283;5238.97380513095;4927.926081108786;4937;4939.703617782944;4942.481039490802;4998.075356059141;4892.535209155196;4964.230544265644;5021.774020169101;5028.195488721787;5036.748162591855;4964.3298154748645;5014.598832093424;5018.272350509155;5049.974750126225;5063.670861394071;4965.851024469272;4970.8757281068265;4980.178270585313;5003.549680528697;5032.3961124665275;4177.4778152730905;4214.852480163179;4281.529031806499;4298.183345164409;4349.543297953733;3696.334659761215;3704.5369328833895;3709.146896213832;3723.646253605903;3820.7516511427225;4347.195768782722;4353.956460435418;4354.629856462173;4391.077873646506;4402.515723270438;4912.72269210008;5044.470330615232;5051.393832740021;5055.51972562606;5058.038972595195;4255.680823938217;4268.445102136702;4271.209826182167;4290.678199135077;4369.71596846204;4486.147631949919;4508.504064552896;4513.984353520432;4518.570735780096;4559.977200113978;4677.906441871075;4682.1103990241745;4693.061387722538;4694.507076757028;4756.548127927841;4911.0423467424935;4963.131451995959;4984.7258400788305;5001.749912504288;5078.289039534542;4651.441826980784;4668.5098064702715;4677.415323084614;4685.601723853374;4750.5249475052215;4890;4925.778339974638;4926.827561035347;4928.137575924127;4985.6510044298;4868.196747536616;4973.751312434436;5023.045621331935;5035.7985680572365;5115.897682046412;4870.439899411613;4888.413390393175;4888.584470320063;4897.971426000579;4968.080905032581;4873.731944743073;4909.705417674951;4910.729909854992;4919.631027672867;4982.451930287593;4683.203855344532;4748.382710247645;4809;4818.855434336975;4888.560029597354;4904.362432883845;4936.062148191832;4940.851774446773;4942.233953737176;4996.2505624156665;4805.27920811881;4827.879303017453;4828.589569886459;4830.2996065571;4886.975565122151 18:34:58 INFO - PROCESS | 13627 | |1;ext.html;574.2792795042222;574.3481148896004;579.4466284698113;585.3853453873435;586.5893874287998;27126.583732439743;37107.443388349195;38426.23147537048;38507.80746096273;41823.58176418235;38129.809350953205;38431.615683843156;38456.80771596145;38515.807420962854;38541.614583854156;7556.5466072035715;7674.386049116058;7688.38492920565;7691.769246922573;7696.576688282143;7623.961880190619;7662.1954694756805;7691.807704807368;7695.153533111354;7712.498687585317;7677.539347639132;7713.112992005935;7717.305442510173;7717.305442510173;7724.9227507725045;7545.170031296553;7550.848983020336;7597.6960921562795;7659.310662040443;7678.193789652062;7617.200193979607;7675.15573286938;7683.231676832328;7687.884681729778;7689.077310722693;36774.63225367739;37086.258274834356;37114.443283350774;37125;37133.62866371329;2848.3591191981845;2853.259521612743;2916.0377075565225;2958.4378967996226;2986.3728616990456;27457;27468;28134.718652813415;28227.435451290858;28419.289517762016;3480.825958702055;3497.342020064744;3515.1739341254784;3611.765235259726;3645.2527231917657;240774;241279;241763;242460;242474;48202.517974820505;48478.75760621245;48495;48497.75751121222;48514.60397772193;31461.685383146334;32387.35225295481;51534.48465515372;51601;51619.38664293539;13157.276349800853;13487;13499.257540835151;13533.052686311863;13650.522231721841;9453.101955314234;9460.8580871287;9462.810743785085;9464.242860571137;9464.47945362998;17184.570385740455;17497.125143742764;17549.649007020045;17561.648767024588;17662.646747064988;6653.667316634149;6701.827180243438;6715.496337774686;6735.259121496631;6743.966280168568;747.6448686873691;836.0427310729302;861.0184389795638;872.7707482315458;945.5933948402253;24765.380865478506;25556.23331300064;26037.21888343315;26450.941962321678;26955.86522067377;774.9651265693058;894.2577660541734;925.796324808541;932.990670093304;1002.9598816047277;13713.588592342247;13737.656558586115;13794.65513362174;14223.217723025333;14399.99200056007;360395;416863;422351;423039;622164;47842.5215747845;47915.281270780964;47945;47948.760256199195;48007.519924801;1044.102072217892;1126.8760436352159;1146.9942650286696;1148.3454430974432;1152.590830255264;24952.625710613993;27500.724992750616;27717.584236236875;27749.306267342672;27995;38578.421323679606;39107;39145.804270978464;39148.412773807846;39153.80423097866;314486;318183;318190.09049547894;318217;318777;22122.22572209965;22634.43413914665;22634.43413914665;24486.632700509865;24849.875750621854;39563.406548901214;39743.403848942864;40899.38650920179;40932.79533602432;41301;33939.15152121217;34147.829260853534;34335.828320858236;34445.31109377748;34720.479192811625;34018.65981340155;34100.82949585335;34459.310813783086;34784.82607586946;34918;34206.94045215373;34238.315233695685;34680;34777.47833782445;35390.11524711903;29114.41771164607;31211;31244.375112497168;31362.215944600655;31736.365272693954;23937.64093538633;24133.75866241315;24235.39411514726;24475;25142.24573262805 18:34:58 INFO - PROCESS | 13627 | |2;jquery.html;334.3813944203226;360.2650592790709;360.6123417326374;360.778121455305;383.8061778801705;12299.446524906378;12454.128211025221;13394.330283485824;16195.514134575953;16358.509244722647;14377.568672939802;14727.705445891102;15200.087994720325;15306.770398444005;15309.387624495035;4859.319695242669;4896.804127834882;4993.101241776479;5035.622328325372;5092.057969275677;3570.2145527983803;4951.282064100703;5042.722650254234;5047.67190132642;5619.7471113799975;5917.467427931485;5954.196183515235;5989.6406215626985;5996.16053752475;6011.699415029253;5754.683492407916;5917.142014407908;5968.552358573081;5986.431289027535;6024.698765061729;5250.159974404077;5264.447233040514;5271.815486457994;5275.103232450454;5276.683398996034;17890.821091789177;19269.4219173425;19801.900990495098;19950.70073948892;20010.599788004303;1782.1445706061163;1855.7494738210303;1926.6339395514808;2003.9980009995002;2317.385892738426;8595.5702214889;9344.439333640003;9405.482698451582;12229.816552751716;12345.567905123367;1706.5542223791256;1716.699577573932;1728.8271172882737;1736.6353065856185;1743.8779285450023;66224.66887665579;67523;68258.65870670615;68791.65604171997;69358;9283.071692830663;9316.767080823034;9414;9584.712458626253;9610.711678649652;12752.6811829705;12759.448110377962;12832.615021549369;12840;12851.742965140831;6563.1796025496815;6645.302243264484;6829.795106146724;6912.758053468205;6945.583265004116;6188.9690551546955;6340.334264902119;6342.39747224013;6485.254195767453;6531.575447595986;4102.733322334038;4107.691923105779;4117.197146556393;4139.523954745183;4242.596953289432;1731.2815181699705;1781.750554922312;1804.756357891668;1824.7354133650547;1850.3338798032698;796.38678217772;832.4422636833336;838.8951381077366;870.6778491958016;923.5151545438698;16217.432389866299;16251.268692908847;16726.16369181536;17003;17313.480595582154;839.8404303182376;854.1586537260794;863.1799790199336;865.2947847504264;920.7293934370659;8718.215360617449;9379.343445958717;9416.905830941603;9508;9737.26970477217;368578;371548;374121;374718;377754;49794;51726;75304.62347688337;77580;78627;879.2131042716733;892.7812685891998;910.1990248581207;910.8132832769267;911.462237280008;14434.133951962496;14639.70720585561;14847.925760371128;15002.549923502313;15319;35833.28333433369;38606;39118.60881391264;39433.80283098566;39460.40809387804;49658.751706242685;50960.490395097055;51730.48269517407;52736.208956866445;53460.73269633627;6576.375244351971;6814.250432452525;6885.1049363582415;7182.569045857265;7715.382769378549;10865.619703310345;10907.454627268446;11267.887321126684;11348.262362946381;11711.063114950954;9757.658481953098;9888.604455821975;10101.949490252502;10242.948785256027;10964.945175274073;10126.949365253127;10221.9488902555;10485.790284194121;10674.8932510677;10922.781544368909;10708.411037393018;10767.892321077003;10771.569137234736;10875.456227188768;11281.435928203393;7500.36246919018;7518.699252029857;7830.530168189927;7837.3426915532145;7859.410544209093;7955.403344749283;9967.202623789934;10310.536025879004;10471.21465890046;10625.096866766411 18:34:58 INFO - PROCESS | 13627 | |3;mootools.html;1227.6562562482509;1329.1227789658822;1372.9244891530966;1446.7395868743629;1678.1106013812685;7090.574565526072;7297.489175757699;7362.631868406585;7582.241775822415;7589.506682065662;7478.925210747891;7549.282818132282;7591.051118610174;7600.961995190017;7602.429817763662;2148.205164089285;2170.70695456113;2173.0981642618294;2173.217641649005;2178.3900507857825;2084.864483808555;2114.9259775907844;2116.7354080739906;2120.957580848382;2125.1605615781755;2124.0547956159517;2135.7864213578673;2142.2502124256544;2144.0994782191515;2173.217641649005;2099.3072286145534;2105.799949004842;2105.978940210609;2107.0412962102205;2107.71545841311;2058.9176432942663;2120.088362004353;2121.437818977957;2121.4802373418456;2123.3205374280237;7439.925600744032;7522.586257755821;7577.696892124363;7597.468177227596;7599.126100498458;4307.655387568987;4412.801423935898;4412.889677758049;4415.094905544388;4417.160739459492;2713.3623598454346;2843.8720257588457;2888.292368369742;2894.2185609885214;2898.087102562686;948.8813898262717;952.0479520479521;976.6679329028093;978.5938835383307;980.7352014955993;10270.691879243559;10334.793304133875;10338.431386273753;10339.689809305657;10355.689329320132;3849.1339448623835;3879.96301941483;3987.601239876047;4012.7792971386853;4017.8995525112105;8310.085890552034;8316.833663326699;8321.542315172723;8333.083360830304;8338.499690018618;4430.5126436092005;4436.44544431946;4438.866833994985;4446.26636604963;4455.064436468313;3607.1703508193264;3975.1453437511072;4111.465509483808;4132.359484279941;4132.442120313719;3314.254292784104;3364.7644664873224;3403.5915690117345;3433.364827506919;3447.6035255945385;992.9553170107218;1013.7820368620781;1017.9745506362252;1020.8621836052038;1026.1277913773233;693.1647364925253;742.9814254643569;743.0303453992484;805.8106345008919;818.108261994429;4986.775595098229;5053.1157047516535;5054.49455054499;5152.871178220575;5403.83788486346;415.6550063447331;417.26977788869476;420.11356038758197;422.42761058765547;422.9788510574459;3601.6938560222675;3601.8559257629927;3638.1086633774894;3654.3239500692457;3757.1734218471893;8936.24041956428;9369.859452108223;9371.671991480265;9372.90627093734;9395.906040939502;9333.439993600407;9360.642706807475;9360.90639093614;9385.343025988115;9415.246780257563;7010.334018268256;7135.607541585263;7247.369341898031;7263.709451621981;7269.563826170447;5106.514881086291;5118.38579370478;5138.685841015954;5219.295395121761;5463.235147079492;8742.213200811957;8817.206451419403;8866;8884.866726998971;8900.73297801067;8192.959035204785;8440.83118337642;8517;8885.4668719877;8918.643254270017;6123.510119190365;6512.6092434454085;6672.733090676318;6689.866202676017;6845.794626161223;7399.59302238382;7496.700131994659;7507.136679281734;7510.511816731895;7511.9624401877645;6622.10601568782;6746.325367463214;6758;6798.898016529657;6885.070515480346;6424.871502570016;6500.089987401673;6552.049952756919;6580.440662543546;6748.089007984056;6044.758209671564;6151.477124444473;6151.72317245716;6153.692315384303;6157.938420615916;4485.439320084989;4537.954620453753;4539.931901021553;4543.954560454353;4610.216263235325;4451.3545535897765;4486.170058539181;4591.816327346869;4633.212353899778;4706.388169537938 18:34:58 INFO - PROCESS | 13627 | |4;prototype.html;282.9830210187388;283.4076779530782;286.5100677840892;293.75764993880045;299.1116384338514;4808.37491126154;4810.711357318563;4855.3688020557365;4861.319415281859;4864.294677271798;4747.8813029674275;4750.5724484796365;4780.689255198409;4786.473487916326;4794.3048258002655;3727.739058265916;3739.7943113128767;3762.7742335459825;3764.73646844721;3767.7362584619086;3659.505966694502;3784.9432258516144;3787.2425514896995;3788.9431658525145;3796.3925771876507;3742;3769.6795772359383;3776.0748616588976;3792.5448946126367;3794.392897136459;3712.981435092834;3743.1577894973684;3755.324041672498;3768.6042965488505;3788.753731007476;3086.783925125242;3165.2403423178503;3299.4555898276753;3302.290007648367;3303.702666760003;4614.8154073837;4615.884602884921;4616.5152658970665;4633.559811817872;4637.373954516131;4257.531671516162;4259.70182087254;4259.893502662428;4263.189993901149;4264.253755592759;4684.5549672781035;4696.802315409549;4954.3063971044085;4979.352684151074;5004.524570165829;3597.2625611749527;3624.5288112545463;3626.8549258029648;3630.7276954228273;3631.4371272452813;5094.974525127351;5185.662931909414;5186.9366779810425;5195.896082078338;5206.7917283308625;3611.7471776975885;3707.870224542128;3733.3093377724663;3739.8878033659034;3757.4739536464917;3763.1909139535132;3784.4323351496946;3787.7727336359358;3793.468914351993;3810.047488127968;4208.737378786352;4225.746455212697;4246.193223287627;4254.7021708480725;4265.253580623378;4922.360093187935;4934.02638944418;4934.519644106754;4942.085714142895;4982.825601103944;1190.9285442873281;1257.8553516300124;1262.7348256865978;1267.847858257015;1283.9599924061354;800.2597597222546;807.4590024683476;824.8886400335998;827.2389401750404;834.5701963488808;3976.7812770297332;3980.3034468968394;3990.1620659661216;3990.4213888986515;4004.7985604318587;4501.437320334958;4551.38556294896;4564.292534657134;4577.519360467103;4615.930761038587;3911.413288006765;3944.4674968878835;3957.248122856648;4004.3192657247755;4010.859619913289;2394.533066052103;2422.9878850605633;2528.1783420388447;2719.741624545665;2735.2510726719916;4636.9536304637195;4738.1708201064675;4879.292502587176;4896.559309662076;4896.828610998598;5263.473652634706;5267.7366131694025;5268.814516733781;5292.9735351323;5298.231756395378;4869.050535145612;4874.025194961022;4874.610031197425;4876.341693871282;4884.291777692286;5035.29505869186;5106.157484015096;5122.692638441706;5140.691558506502;5157.871053223699;4993.176125939324;5004.1492946198505;5019.598432125495;5022.7146648275675;5022.799088036438;4858.63560232991;4877.658643872964;4887.926681099715;4890.731009794496;4899.363082799213;3424.1781972325966;3557.982210088933;3573.910652233611;3586.515820364322;3644.6355364463343;5135.306733590917;5143.534092783539;5146.176611742103;5155.510226528474;5180.818671346485;5040.899182016413;5052.469490703424;5062.114130027304;5063.3417655704525;5067.341245638044;4896.167651499325;4953.0589188054155;4996.575291100152;5006.774695138801;5050.545450909289;4877.317175595348;4901.656884018084;4909.091818013679;4911.287863259879;4918.852434426973;5030.195168772979;5033.2701758245585;5039.042581909425;5039.874003149951;5050.469700681522;4695.154872123051;4911.017796440655;4916.729579873141;4926.630502712239;4929.211326187793 18:34:58 INFO - PROCESS | 13627 | |5;yui.html;625.8028720952902;627.538759012126;658.7727234104235;666.1107421592175;748.8764353881608;10724.678259652213;11994.640160795168;12019.399030048506;12097.999999999989;12140.75718485631;11824.940875295611;11847.526098956054;11848.703782405444;11854.940725296383;11862;594.9672767997758;715.4419552748839;720.0423436829005;739.970401183952;742.3541518878583;729.8941653460272;732.1068296678044;734.988975165373;735.0444422251079;735.477810754365;789.0610173893077;789.1950210784996;790.3716545346464;790.8853216283635;791.1574173505227;630.2751835389294;755.1957165618599;770.5530792140601;781.4490783997325;790.2334735306744;569.7891780041369;570.3213176320207;570.3327107284487;570.7260514952845;570.9029464990962;11845.23006004607;11885;11887.762244755142;11908.702282442922;11910.702232444171;11743.823842642367;11984.820227696591;12022.819657705142;12104.576339828152;12728.236305821587;10125.949370253174;10311.278210525266;10340.431276279802;10391.896081039169;10529.947350263275;4054.898627534306;4101.589841015904;4125.8968525786795;4275.508316543606;4281.871543853689;60048.099278510854;61012.389876101115;61199.388006119814;61539;61927;9979.750506237402;10044.648437304659;10218.284720069672;10237.436940968319;10558.52486638103;18875.150618222215;18877.244910203437;18890.54422925011;18894.62210755777;18927.90536047311;6473.579217350857;6511.967440162769;6540.4767618590395;6601.636909969901;6767.357101075489;3554.9670983862966;3578.0228554319183;3605.891823245307;3620;3652.251288485854;2551.5407226699003;2597.5194589001094;2614.372550587845;2622.318197268724;2637.736226377347;428.4900967848251;486.75905426813245;488.83868323453174;496.0822478414976;496.3919198981219;530.6577257668782;551.2337850387964;569.0411656359048;571.3343954293202;592.8221533539921;7473.887891681629;7632.771016869503;7682.462227644011;7864.331531819859;7926.682932682744;441.03738364279087;548.9451054894558;549.7251374312843;551.6827824001215;570.823044856096;3630.8184590770884;3683.7053035757613;3748.700103991674;3789.109559253573;3925.685945124383;59429.40570594239;71138.2886171142;73941;73943;87414;72861.27138728545;76626.61686691531;77115;78304.60847695725;80959;42890;53489;66384;68860.31139688539;69450;7390.29792169743;7801.531908085533;7817.960910195413;7897.1707970662255;7904.13054564009;41500.99999999879;41779.7911010443;42313.365299521145;42386.36420453634;42442.78778606211;55541.44458555524;59827;60731;63336.36663633489;63489.047664284146;10799.946000269949;10807.783844322912;10888.45557722095;10958.835617465904;11477.139214558774;12063.939680301542;12285.631431057083;12661.430235639602;12908.225506469642;12995.155314904501;11272.380019099028;11448.671260576024;11660.475278612314;11761.353125578175;12362.258264504158;10693.14454843595;10794.244402891722;10905.78188436211;11512.136589755635;11575.24760890573;11088.501017454395;11228.55085796559;11408.429578520874;11460.885391146316;11720.179587428798;9664.256069981802;9732.659356922757;10170.542325595514;10331.22515811302;10381;9353.81292374135;9910.405375677483;10154.441505717257;10188.554746860847;10270.845937311096 18:34:58 INFO - PROCESS | 13627 | __end_tp_report 18:34:58 INFO - PROCESS | 13627 | __start_cc_report 18:34:58 INFO - PROCESS | 13627 | _x_x_mozilla_cycle_collect,952 18:34:58 INFO - PROCESS | 13627 | __end_cc_report 18:34:58 INFO - PROCESS | 13627 | __startTimestamp1472520898609__endTimestamp 18:34:58 INFO - PROCESS | 13627 | 18:34:58 INFO - PROCESS | 13627 | ------- Summary: start ------- 18:34:58 INFO - PROCESS | 13627 | Number of tests: 6 18:34:58 INFO - PROCESS | 13627 | 18:34:58 INFO - PROCESS | 13627 | [#0] dojo.html Cycles:165 Average:4639.22 Median:4745.34 stddev:485.09 (10.2%) stddev-sans-first:458.83 18:34:58 INFO - PROCESS | 13627 | Values: 2577.8 2587.5 2597.0 2611.8 2620.8 4630.4 4640.1 4669.5 4737.2 4756.7 4640.1 4672.0 4680.6 4684.7 4745.3 4255.4 4279.9 4335.3 4393.4 4466.2 4371.0 4396.6 4406.8 4412.0 4442.9 4233.7 4268.6 4275.1 4285.9 4288.4 4269.4 4287.3 4393.3 4400.1 4406.6 4294.6 4311.9 4313.4 4348.3 4377.9 4595.8 4698.0 4738.4 4739.7 4743.7 4960.7 4966.0 4983.6 4998.7 5002.5 4985.5 5015.3 5018.3 5060.4 5089.6 4624.4 4637.9 4680.9 4688.0 4703.8 5128.7 5153.7 5174.6 5229.8 5239.0 4927.9 4937.0 4939.7 4942.5 4998.1 4892.5 4964.2 5021.8 5028.2 5036.7 4964.3 5014.6 5018.3 5050.0 5063.7 4965.9 4970.9 4980.2 5003.5 5032.4 4177.5 4214.9 4281.5 4298.2 4349.5 3696.3 3704.5 3709.1 3723.6 3820.8 4347.2 4354.0 4354.6 4391.1 4402.5 4912.7 5044.5 5051.4 5055.5 5058.0 4255.7 4268.4 4271.2 4290.7 4369.7 4486.1 4508.5 4514.0 4518.6 4560.0 4677.9 4682.1 4693.1 4694.5 4756.5 4911.0 4963.1 4984.7 5001.7 5078.3 4651.4 4668.5 4677.4 4685.6 4750.5 4890.0 4925.8 4926.8 4928.1 4985.7 4868.2 4973.8 5023.0 5035.8 5115.9 4870.4 4888.4 4888.6 4898.0 4968.1 4873.7 4909.7 4910.7 4919.6 4982.5 4683.2 4748.4 4809.0 4818.9 4888.6 4904.4 4936.1 4940.9 4942.2 4996.3 4805.3 4827.9 4828.6 4830.3 4887.0 18:34:58 INFO - PROCESS | 13627 | 18:34:58 INFO - PROCESS | 13627 | [#1] ext.html Cycles:180 Average:47568.74 Median:36930.45 stddev:93126.02 (252.2%) stddev-sans-first:93320.42 18:34:58 INFO - PROCESS | 13627 | Values: 574.3 574.3 579.4 585.4 586.6 27126.6 37107.4 38426.2 38507.8 41823.6 38129.8 38431.6 38456.8 38515.8 38541.6 7556.5 7674.4 7688.4 7691.8 7696.6 7624.0 7662.2 7691.8 7695.2 7712.5 7677.5 7713.1 7717.3 7717.3 7724.9 7545.2 7550.8 7597.7 7659.3 7678.2 7617.2 7675.2 7683.2 7687.9 7689.1 36774.6 37086.3 37114.4 37125.0 37133.6 2848.4 2853.3 2916.0 2958.4 2986.4 27457.0 27468.0 28134.7 28227.4 28419.3 3480.8 3497.3 3515.2 3611.8 3645.3 240774.0 241279.0 241763.0 242460.0 242474.0 48202.5 48478.8 48495.0 48497.8 48514.6 31461.7 32387.4 51534.5 51601.0 51619.4 13157.3 13487.0 13499.3 13533.1 13650.5 9453.1 9460.9 9462.8 9464.2 9464.5 17184.6 17497.1 17549.6 17561.6 17662.6 6653.7 6701.8 6715.5 6735.3 6744.0 747.6 836.0 861.0 872.8 945.6 24765.4 25556.2 26037.2 26450.9 26955.9 775.0 894.3 925.8 933.0 1003.0 13713.6 13737.7 13794.7 14223.2 14400.0 360395.0 416863.0 422351.0 423039.0 622164.0 47842.5 47915.3 47945.0 47948.8 48007.5 1044.1 1126.9 1147.0 1148.3 1152.6 24952.6 27500.7 27717.6 27749.3 27995.0 38578.4 39107.0 39145.8 39148.4 39153.8 314486.0 318183.0 318190.1 318217.0 318777.0 22122.2 22634.4 22634.4 24486.6 24849.9 39563.4 39743.4 40899.4 40932.8 41301.0 33939.2 34147.8 34335.8 34445.3 34720.5 34018.7 34100.8 34459.3 34784.8 34918.0 34206.9 34238.3 34680.0 34777.5 35390.1 29114.4 31211.0 31244.4 31362.2 31736.4 23937.6 24133.8 24235.4 24475.0 25142.2 18:34:58 INFO - PROCESS | 13627 | 18:34:58 INFO - PROCESS | 13627 | [#2] jquery.html Cycles:180 Average:23344.22 Median:4924.04 stddev:61602.28 (1251.1%) stddev-sans-first:61750.87 18:34:58 INFO - PROCESS | 13627 | Values: 334.4 360.3 360.6 360.8 383.8 12299.4 12454.1 13394.3 16195.5 16358.5 14377.6 14727.7 15200.1 15306.8 15309.4 4859.3 4896.8 4993.1 5035.6 5092.1 3570.2 4951.3 5042.7 5047.7 5619.7 5917.5 5954.2 5989.6 5996.2 6011.7 5754.7 5917.1 5968.6 5986.4 6024.7 5250.2 5264.4 5271.8 5275.1 5276.7 17890.8 19269.4 19801.9 19950.7 20010.6 1782.1 1855.7 1926.6 2004.0 2317.4 8595.6 9344.4 9405.5 12229.8 12345.6 1706.6 1716.7 1728.8 1736.6 1743.9 66224.7 67523.0 68258.7 68791.7 69358.0 9283.1 9316.8 9414.0 9584.7 9610.7 12752.7 12759.4 12832.6 12840.0 12851.7 6563.2 6645.3 6829.8 6912.8 6945.6 6189.0 6340.3 6342.4 6485.3 6531.6 4102.7 4107.7 4117.2 4139.5 4242.6 1731.3 1781.8 1804.8 1824.7 1850.3 796.4 832.4 838.9 870.7 923.5 16217.4 16251.3 16726.2 17003.0 17313.5 839.8 854.2 863.2 865.3 920.7 8718.2 9379.3 9416.9 9508.0 9737.3 368578.0 371548.0 374121.0 374718.0 377754.0 49794.0 51726.0 75304.6 77580.0 78627.0 879.2 892.8 910.2 910.8 911.5 14434.1 14639.7 14847.9 15002.5 15319.0 35833.3 38606.0 39118.6 39433.8 39460.4 49658.8 50960.5 51730.5 52736.2 53460.7 6576.4 6814.3 6885.1 7182.6 7715.4 10865.6 10907.5 11267.9 11348.3 11711.1 9757.7 9888.6 10101.9 10242.9 10964.9 10126.9 10221.9 10485.8 10674.9 10922.8 10708.4 10767.9 10771.6 10875.5 11281.4 7500.4 7518.7 7830.5 7837.3 7859.4 7955.4 9967.2 10310.5 10471.2 10625.1 18:34:58 INFO - PROCESS | 13627 | 18:34:58 INFO - PROCESS | 13627 | [#3] mootools.html Cycles:180 Average:4979.78 Median:5053.81 stddev:2803.97 (55.5%) stddev-sans-first:2797.66 18:34:58 INFO - PROCESS | 13627 | Values: 1227.7 1329.1 1372.9 1446.7 1678.1 7090.6 7297.5 7362.6 7582.2 7589.5 7478.9 7549.3 7591.1 7601.0 7602.4 2148.2 2170.7 2173.1 2173.2 2178.4 2084.9 2114.9 2116.7 2121.0 2125.2 2124.1 2135.8 2142.3 2144.1 2173.2 2099.3 2105.8 2106.0 2107.0 2107.7 2058.9 2120.1 2121.4 2121.5 2123.3 7439.9 7522.6 7577.7 7597.5 7599.1 4307.7 4412.8 4412.9 4415.1 4417.2 2713.4 2843.9 2888.3 2894.2 2898.1 948.9 952.0 976.7 978.6 980.7 10270.7 10334.8 10338.4 10339.7 10355.7 3849.1 3880.0 3987.6 4012.8 4017.9 8310.1 8316.8 8321.5 8333.1 8338.5 4430.5 4436.4 4438.9 4446.3 4455.1 3607.2 3975.1 4111.5 4132.4 4132.4 3314.3 3364.8 3403.6 3433.4 3447.6 993.0 1013.8 1018.0 1020.9 1026.1 693.2 743.0 743.0 805.8 818.1 4986.8 5053.1 5054.5 5152.9 5403.8 415.7 417.3 420.1 422.4 423.0 3601.7 3601.9 3638.1 3654.3 3757.2 8936.2 9369.9 9371.7 9372.9 9395.9 9333.4 9360.6 9360.9 9385.3 9415.2 7010.3 7135.6 7247.4 7263.7 7269.6 5106.5 5118.4 5138.7 5219.3 5463.2 8742.2 8817.2 8866.0 8884.9 8900.7 8193.0 8440.8 8517.0 8885.5 8918.6 6123.5 6512.6 6672.7 6689.9 6845.8 7399.6 7496.7 7507.1 7510.5 7512.0 6622.1 6746.3 6758.0 6798.9 6885.1 6424.9 6500.1 6552.0 6580.4 6748.1 6044.8 6151.5 6151.7 6153.7 6157.9 4485.4 4538.0 4539.9 4544.0 4610.2 4451.4 4486.2 4591.8 4633.2 4706.4 18:34:58 INFO - PROCESS | 13627 | 18:34:58 INFO - PROCESS | 13627 | [#4] prototype.html Cycles:180 Average:4113.55 Median:4660.96 stddev:1202.22 (25.8%) stddev-sans-first:1170.71 18:34:58 INFO - PROCESS | 13627 | Values: 283.0 283.4 286.5 293.8 299.1 4808.4 4810.7 4855.4 4861.3 4864.3 4747.9 4750.6 4780.7 4786.5 4794.3 3727.7 3739.8 3762.8 3764.7 3767.7 3659.5 3784.9 3787.2 3788.9 3796.4 3742.0 3769.7 3776.1 3792.5 3794.4 3713.0 3743.2 3755.3 3768.6 3788.8 3086.8 3165.2 3299.5 3302.3 3303.7 4614.8 4615.9 4616.5 4633.6 4637.4 4257.5 4259.7 4259.9 4263.2 4264.3 4684.6 4696.8 4954.3 4979.4 5004.5 3597.3 3624.5 3626.9 3630.7 3631.4 5095.0 5185.7 5186.9 5195.9 5206.8 3611.7 3707.9 3733.3 3739.9 3757.5 3763.2 3784.4 3787.8 3793.5 3810.0 4208.7 4225.7 4246.2 4254.7 4265.3 4922.4 4934.0 4934.5 4942.1 4982.8 1190.9 1257.9 1262.7 1267.8 1284.0 800.3 807.5 824.9 827.2 834.6 3976.8 3980.3 3990.2 3990.4 4004.8 4501.4 4551.4 4564.3 4577.5 4615.9 3911.4 3944.5 3957.2 4004.3 4010.9 2394.5 2423.0 2528.2 2719.7 2735.3 4637.0 4738.2 4879.3 4896.6 4896.8 5263.5 5267.7 5268.8 5293.0 5298.2 4869.1 4874.0 4874.6 4876.3 4884.3 5035.3 5106.2 5122.7 5140.7 5157.9 4993.2 5004.1 5019.6 5022.7 5022.8 4858.6 4877.7 4887.9 4890.7 4899.4 3424.2 3558.0 3573.9 3586.5 3644.6 5135.3 5143.5 5146.2 5155.5 5180.8 5040.9 5052.5 5062.1 5063.3 5067.3 4896.2 4953.1 4996.6 5006.8 5050.5 4877.3 4901.7 4909.1 4911.3 4918.9 5030.2 5033.3 5039.0 5039.9 5050.5 4695.2 4911.0 4916.7 4926.6 4929.2 18:34:58 INFO - PROCESS | 13627 | 18:34:58 INFO - PROCESS | 13627 | [#5] yui.html Cycles:180 Average:16144.82 Median:23585.94 stddev:21894.18 (92.8%) stddev-sans-first:21924.58 18:34:58 INFO - PROCESS | 13627 | Values: 625.8 627.5 658.8 666.1 748.9 10724.7 11994.6 12019.4 12098.0 12140.8 11824.9 11847.5 11848.7 11854.9 11862.0 595.0 715.4 720.0 740.0 742.4 729.9 732.1 735.0 735.0 735.5 789.1 789.2 790.4 790.9 791.2 630.3 755.2 770.6 781.4 790.2 569.8 570.3 570.3 570.7 570.9 11845.2 11885.0 11887.8 11908.7 11910.7 11743.8 11984.8 12022.8 12104.6 12728.2 10125.9 10311.3 10340.4 10391.9 10529.9 4054.9 4101.6 4125.9 4275.5 4281.9 60048.1 61012.4 61199.4 61539.0 61927.0 9979.8 10044.6 10218.3 10237.4 10558.5 18875.2 18877.2 18890.5 18894.6 18927.9 6473.6 6512.0 6540.5 6601.6 6767.4 3555.0 3578.0 3605.9 3620.0 3652.3 2551.5 2597.5 2614.4 2622.3 2637.7 428.5 486.8 488.8 496.1 496.4 530.7 551.2 569.0 571.3 592.8 7473.9 7632.8 7682.5 7864.3 7926.7 441.0 548.9 549.7 551.7 570.8 3630.8 3683.7 3748.7 3789.1 3925.7 59429.4 71138.3 73941.0 73943.0 87414.0 72861.3 76626.6 77115.0 78304.6 80959.0 42890.0 53489.0 66384.0 68860.3 69450.0 7390.3 7801.5 7818.0 7897.2 7904.1 41501.0 41779.8 42313.4 42386.4 42442.8 55541.4 59827.0 60731.0 63336.4 63489.0 10799.9 10807.8 10888.5 10958.8 11477.1 12063.9 12285.6 12661.4 12908.2 12995.2 11272.4 11448.7 11660.5 11761.4 12362.3 10693.1 10794.2 10905.8 11512.1 11575.2 11088.5 11228.6 11408.4 11460.9 11720.2 9664.3 9732.7 10170.5 10331.2 10381.0 9353.8 9910.4 10154.4 10188.6 10270.8 18:34:58 INFO - PROCESS | 13627 | -------- Summary: end -------- 18:34:58 INFO - PROCESS | 13627 | 18:34:59 INFO - TEST-INFO | 13627: exit 0 18:34:59 INFO - TEST-OK | dromaeo_css | took 1121258ms 18:34:59 INFO - TEST-START | kraken 18:34:59 INFO - operating with platform_type : linux_ 18:34:59 INFO - Initialising browser for kraken test... 18:34:59 INFO - TEST-INFO | started process 13934 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptzZcuq/profile http://localhost:33969/getInfo.html) 18:35:04 INFO - PROCESS | 13934 | __metrics Screen width/height:1600/1200 18:35:04 INFO - PROCESS | 13934 | colorDepth:24 18:35:04 INFO - PROCESS | 13934 | Browser inner width/height: 1024/697 18:35:04 INFO - PROCESS | 13934 | __metrics 18:35:04 INFO - PROCESS | 13934 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:35:04 INFO - PROCESS | 13934 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:35:05 INFO - PROCESS | 13934 | console.error: PushService: 18:35:05 INFO - PROCESS | 13934 | stateChangeProcessEnqueue: Error transitioning state 18:35:05 INFO - PROCESS | 13934 | UnknownError 18:35:05 INFO - TEST-INFO | 13934: exit 0 18:35:05 INFO - Browser initialized. 18:35:05 INFO - Running cycle 1/1 for kraken test... 18:35:05 INFO - TEST-INFO | started process 14013 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptzZcuq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:35:18 INFO - PROCESS | 14013 | RSS: Main: 181592064 18:35:18 INFO - PROCESS | 14013 | 18:35:20 INFO - PROCESS | 14013 | Cycle 1(1): loaded http://localhost:33969/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33969/tests/kraken/driver.html?testName=audio-beat-detection) 18:35:21 INFO - PROCESS | 14013 | RSS: Main: 235008000 18:35:21 INFO - PROCESS | 14013 | 18:35:24 INFO - PROCESS | 14013 | Cycle 1(1): loaded http://localhost:33969/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33969/tests/kraken/driver.html?testName=audio-dft) 18:35:25 INFO - PROCESS | 14013 | RSS: Main: 225906688 18:35:25 INFO - PROCESS | 14013 | 18:35:28 INFO - PROCESS | 14013 | Cycle 1(1): loaded http://localhost:33969/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33969/tests/kraken/driver.html?testName=audio-fft) 18:35:29 INFO - PROCESS | 14013 | RSS: Main: 303976448 18:35:29 INFO - PROCESS | 14013 | 18:35:31 INFO - PROCESS | 14013 | Cycle 1(1): loaded http://localhost:33969/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33969/tests/kraken/driver.html?testName=audio-oscillator) 18:35:31 INFO - PROCESS | 14013 | RSS: Main: 212811776 18:35:31 INFO - PROCESS | 14013 | 18:35:34 INFO - PROCESS | 14013 | Cycle 1(1): loaded http://localhost:33969/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33969/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:35:34 INFO - PROCESS | 14013 | RSS: Main: 214716416 18:35:34 INFO - PROCESS | 14013 | 18:35:37 INFO - PROCESS | 14013 | Cycle 1(1): loaded http://localhost:33969/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33969/tests/kraken/driver.html?testName=imaging-darkroom) 18:35:38 INFO - PROCESS | 14013 | RSS: Main: 253345792 18:35:38 INFO - PROCESS | 14013 | 18:35:41 INFO - PROCESS | 14013 | Cycle 1(1): loaded http://localhost:33969/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33969/tests/kraken/driver.html?testName=imaging-desaturate) 18:35:42 INFO - PROCESS | 14013 | RSS: Main: 247488512 18:35:42 INFO - PROCESS | 14013 | 18:35:45 INFO - PROCESS | 14013 | Cycle 1(1): loaded http://localhost:33969/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33969/tests/kraken/driver.html?testName=json-parse-financial) 18:35:45 INFO - PROCESS | 14013 | RSS: Main: 250155008 18:35:45 INFO - PROCESS | 14013 | 18:35:47 INFO - PROCESS | 14013 | Cycle 1(1): loaded http://localhost:33969/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33969/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:35:48 INFO - PROCESS | 14013 | RSS: Main: 214347776 18:35:48 INFO - PROCESS | 14013 | 18:35:50 INFO - PROCESS | 14013 | Cycle 1(1): loaded http://localhost:33969/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33969/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:35:50 INFO - PROCESS | 14013 | RSS: Main: 221450240 18:35:50 INFO - PROCESS | 14013 | 18:35:52 INFO - PROCESS | 14013 | Cycle 1(1): loaded http://localhost:33969/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33969/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:35:53 INFO - PROCESS | 14013 | RSS: Main: 222404608 18:35:53 INFO - PROCESS | 14013 | 18:35:56 INFO - PROCESS | 14013 | Cycle 1(1): loaded http://localhost:33969/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33969/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:35:57 INFO - PROCESS | 14013 | RSS: Main: 223379456 18:35:57 INFO - PROCESS | 14013 | 18:36:00 INFO - PROCESS | 14013 | Cycle 1(1): loaded http://localhost:33969/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33969/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:36:00 INFO - PROCESS | 14013 | RSS: Main: 221671424 18:36:00 INFO - PROCESS | 14013 | 18:36:02 INFO - PROCESS | 14013 | Cycle 1(1): loaded http://localhost:33969/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33969/tests/kraken/driver.html?testName=ai-astar) 18:36:02 INFO - PROCESS | 14013 | RSS: Main: 272277504 18:36:02 INFO - PROCESS | 14013 | 18:36:02 INFO - PROCESS | 14013 | __start_tp_report 18:36:02 INFO - PROCESS | 14013 | _x_x_mozilla_page_load 18:36:02 INFO - PROCESS | 14013 | _x_x_mozilla_page_load_details 18:36:02 INFO - PROCESS | 14013 | |i|pagename|runs| 18:36:02 INFO - PROCESS | 14013 | |0;ai-astar;116;116;132;114;116;114;114;115;114;115 18:36:02 INFO - PROCESS | 14013 | |1;audio-beat-detection;157;128;153;190;125;146;149;130;190;125 18:36:02 INFO - PROCESS | 14013 | |2;audio-dft;162;161;165;161;161;161;160;162;160;161 18:36:02 INFO - PROCESS | 14013 | |3;audio-fft;77;77;76;164;76;75;76;76;156;77 18:36:02 INFO - PROCESS | 14013 | |4;audio-oscillator;83;84;139;83;82;83;146;84;84;136 18:36:02 INFO - PROCESS | 14013 | |5;imaging-gaussian-blur;117;117;117;116;117;117;117;117;117;117 18:36:02 INFO - PROCESS | 14013 | |6;imaging-darkroom;147;147;146;146;146;147;147;146;146;147 18:36:02 INFO - PROCESS | 14013 | |7;imaging-desaturate;96;96;96;97;95;95;96;96;96;95 18:36:02 INFO - PROCESS | 14013 | |8;json-parse-financial;63;65;64;65;65;66;65;65;67;65 18:36:02 INFO - PROCESS | 14013 | |9;json-stringify-tinderbox;66;66;66;66;67;66;66;66;66;67 18:36:02 INFO - PROCESS | 14013 | |10;stanford-crypto-aes;82;82;81;83;82;82;82;82;84;82 18:36:02 INFO - PROCESS | 14013 | |11;stanford-crypto-ccm;133;131;134;133;132;133;135;148;176;134 18:36:02 INFO - PROCESS | 14013 | |12;stanford-crypto-pbkdf2;179;177;173;175;172;175;170;176;171;175 18:36:02 INFO - PROCESS | 14013 | |13;stanford-crypto-sha256-iterative;69;74;70;68;72;69;70;69;68;68 18:36:02 INFO - PROCESS | 14013 | __end_tp_report 18:36:02 INFO - PROCESS | 14013 | __start_cc_report 18:36:02 INFO - PROCESS | 14013 | _x_x_mozilla_cycle_collect,1977 18:36:02 INFO - PROCESS | 14013 | __end_cc_report 18:36:02 INFO - PROCESS | 14013 | __startTimestamp1472520962697__endTimestamp 18:36:02 INFO - PROCESS | 14013 | 18:36:02 INFO - PROCESS | 14013 | ------- Summary: start ------- 18:36:02 INFO - PROCESS | 14013 | Number of tests: 14 18:36:02 INFO - PROCESS | 14013 | 18:36:02 INFO - PROCESS | 14013 | [#0] ai-astar Cycles:10 Average:116.60 Median:115.50 stddev:5.48 (4.7%) stddev-sans-first:5.81 18:36:02 INFO - PROCESS | 14013 | Values: 116.0 116.0 132.0 114.0 116.0 114.0 114.0 115.0 114.0 115.0 18:36:02 INFO - PROCESS | 14013 | 18:36:02 INFO - PROCESS | 14013 | [#1] audio-beat-detection Cycles:10 Average:149.30 Median:151.00 stddev:24.50 (16.2%) stddev-sans-first:25.83 18:36:02 INFO - PROCESS | 14013 | Values: 157.0 128.0 153.0 190.0 125.0 146.0 149.0 130.0 190.0 125.0 18:36:02 INFO - PROCESS | 14013 | 18:36:02 INFO - PROCESS | 14013 | [#2] audio-dft Cycles:10 Average:161.40 Median:161.00 stddev:1.43 (0.9%) stddev-sans-first:1.50 18:36:02 INFO - PROCESS | 14013 | Values: 162.0 161.0 165.0 161.0 161.0 161.0 160.0 162.0 160.0 161.0 18:36:02 INFO - PROCESS | 14013 | 18:36:02 INFO - PROCESS | 14013 | [#3] audio-fft Cycles:10 Average:93.00 Median:76.00 stddev:35.37 (46.5%) stddev-sans-first:37.04 18:36:02 INFO - PROCESS | 14013 | Values: 77.0 77.0 76.0 164.0 76.0 75.0 76.0 76.0 156.0 77.0 18:36:02 INFO - PROCESS | 14013 | 18:36:02 INFO - PROCESS | 14013 | [#4] audio-oscillator Cycles:10 Average:100.40 Median:83.00 stddev:27.67 (33.3%) stddev-sans-first:28.62 18:36:02 INFO - PROCESS | 14013 | Values: 83.0 84.0 139.0 83.0 82.0 83.0 146.0 84.0 84.0 136.0 18:36:02 INFO - PROCESS | 14013 | 18:36:02 INFO - PROCESS | 14013 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 18:36:02 INFO - PROCESS | 14013 | Values: 117.0 117.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 117.0 18:36:02 INFO - PROCESS | 14013 | 18:36:02 INFO - PROCESS | 14013 | [#6] imaging-darkroom Cycles:10 Average:146.50 Median:147.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 18:36:02 INFO - PROCESS | 14013 | Values: 147.0 147.0 146.0 146.0 146.0 147.0 147.0 146.0 146.0 147.0 18:36:02 INFO - PROCESS | 14013 | 18:36:02 INFO - PROCESS | 14013 | [#7] imaging-desaturate Cycles:10 Average:95.80 Median:96.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 18:36:02 INFO - PROCESS | 14013 | Values: 96.0 96.0 96.0 97.0 95.0 95.0 96.0 96.0 96.0 95.0 18:36:02 INFO - PROCESS | 14013 | 18:36:02 INFO - PROCESS | 14013 | [#8] json-parse-financial Cycles:10 Average:65.00 Median:65.00 stddev:1.05 (1.6%) stddev-sans-first:0.83 18:36:02 INFO - PROCESS | 14013 | Values: 63.0 65.0 64.0 65.0 65.0 66.0 65.0 65.0 67.0 65.0 18:36:02 INFO - PROCESS | 14013 | 18:36:02 INFO - PROCESS | 14013 | [#9] json-stringify-tinderbox Cycles:10 Average:66.20 Median:66.00 stddev:0.42 (0.6%) stddev-sans-first:0.44 18:36:02 INFO - PROCESS | 14013 | Values: 66.0 66.0 66.0 66.0 67.0 66.0 66.0 66.0 66.0 67.0 18:36:02 INFO - PROCESS | 14013 | 18:36:02 INFO - PROCESS | 14013 | [#10] stanford-crypto-aes Cycles:10 Average:82.20 Median:82.00 stddev:0.79 (1.0%) stddev-sans-first:0.83 18:36:02 INFO - PROCESS | 14013 | Values: 82.0 82.0 81.0 83.0 82.0 82.0 82.0 82.0 84.0 82.0 18:36:02 INFO - PROCESS | 14013 | 18:36:02 INFO - PROCESS | 14013 | [#11] stanford-crypto-ccm Cycles:10 Average:138.90 Median:134.00 stddev:13.89 (10.4%) stddev-sans-first:14.57 18:36:02 INFO - PROCESS | 14013 | Values: 133.0 131.0 134.0 133.0 132.0 133.0 135.0 148.0 176.0 134.0 18:36:02 INFO - PROCESS | 14013 | 18:36:02 INFO - PROCESS | 14013 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.30 Median:175.00 stddev:2.79 (1.6%) stddev-sans-first:2.39 18:36:02 INFO - PROCESS | 14013 | Values: 179.0 177.0 173.0 175.0 172.0 175.0 170.0 176.0 171.0 175.0 18:36:02 INFO - PROCESS | 14013 | 18:36:02 INFO - PROCESS | 14013 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.70 Median:69.50 stddev:1.95 (2.8%) stddev-sans-first:2.05 18:36:02 INFO - PROCESS | 14013 | Values: 69.0 74.0 70.0 68.0 72.0 69.0 70.0 69.0 68.0 68.0 18:36:02 INFO - PROCESS | 14013 | -------- Summary: end -------- 18:36:02 INFO - PROCESS | 14013 | 18:36:03 INFO - TEST-INFO | 14013: exit 0 18:36:03 INFO - TEST-OK | kraken | took 64400ms 18:36:03 INFO - SUITE-END | took 1185s 18:36:04 INFO - Completed test suite (00:19:46) 18:36:04 INFO - javascript benchmark 18:36:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2577.832440891342, 2587.482503499298, 2597.0, 2611.777998870096, 2620.816542842002, 4630.444346678395, 4640.09518143962, 4669.509701481338, 4737.242041273397, 4756.66703330767, 4640.141573808848, 4671.953280467194, 4680.625549956004, 4684.695494792836, 4745.335653008581, 4255.404243405926, 4279.935800962988, 4335.349697545377, 4393.428854248943, 4466.218411777926, 4370.978145109286, 4396.56034396561, 4406.757628330441, 4411.977940110295, 4442.8667139985855, 4233.745975241495, 4268.637165840907, 4275.123599662062, 4285.935710964338, 4288.44250247469, 4269.380939763717, 4287.335463003239, 4393.319035549463, 4400.119976004812, 4406.603405693503, 4294.592013758688, 4311.91376172478, 4313.352997050436, 4348.347747837818, 4377.890552736207, 4595.839145629887, 4698.0, 4738.4313882334, 4739.691920025221, 4743.715377077387, 4960.70235785854, 4966.0, 4983.551480366785, 4998.6750861193905, 5002.52476014778, 4985.501449855022, 5015.297858299805, 5018.272350509155, 5060.367454068241, 5089.592832573385, 4624.398828152319, 4637.86086417408, 4680.906381872343, 4687.9765601171775, 4703.811847526061, 5128.7179205144075, 5153.716545589954, 5174.6377753557645, 5229.764660590283, 5238.97380513095, 4927.926081108786, 4937.0, 4939.703617782944, 4942.481039490802, 4998.075356059141, 4892.535209155196, 4964.230544265644, 5021.774020169101, 5028.195488721787, 5036.748162591855, 4964.3298154748645, 5014.598832093424, 5018.272350509155, 5049.974750126225, 5063.670861394071, 4965.851024469272, 4970.8757281068265, 4980.178270585313, 5003.549680528697, 5032.3961124665275, 4177.4778152730905, 4214.852480163179, 4281.529031806499, 4298.183345164409, 4349.543297953733, 3696.334659761215, 3704.5369328833895, 3709.146896213832, 3723.646253605903, 3820.7516511427225, 4347.195768782722, 4353.956460435418, 4354.629856462173, 4391.077873646506, 4402.515723270438, 4912.72269210008, 5044.470330615232, 5051.393832740021, 5055.51972562606, 5058.038972595195, 4255.680823938217, 4268.445102136702, 4271.209826182167, 4290.678199135077, 4369.71596846204, 4486.147631949919, 4508.504064552896, 4513.984353520432, 4518.570735780096, 4559.977200113978, 4677.906441871075, 4682.1103990241745, 4693.061387722538, 4694.507076757028, 4756.548127927841, 4911.0423467424935, 4963.131451995959, 4984.7258400788305, 5001.749912504288, 5078.289039534542, 4651.441826980784, 4668.5098064702715, 4677.415323084614, 4685.601723853374, 4750.5249475052215, 4890.0, 4925.778339974638, 4926.827561035347, 4928.137575924127, 4985.6510044298, 4868.196747536616, 4973.751312434436, 5023.045621331935, 5035.7985680572365, 5115.897682046412, 4870.439899411613, 4888.413390393175, 4888.584470320063, 4897.971426000579, 4968.080905032581, 4873.731944743073, 4909.705417674951, 4910.729909854992, 4919.631027672867, 4982.451930287593, 4683.203855344532, 4748.382710247645, 4809.0, 4818.855434336975, 4888.560029597354, 4904.362432883845, 4936.062148191832, 4940.851774446773, 4942.233953737176, 4996.2505624156665, 4805.27920811881, 4827.879303017453, 4828.589569886459, 4830.2996065571, 4886.975565122151], "value": 4608.056402926674, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [574.2792795042222, 574.3481148896004, 579.4466284698113, 585.3853453873435, 586.5893874287998, 27126.583732439743, 37107.443388349195, 38426.23147537048, 38507.80746096273, 41823.58176418235, 38129.809350953205, 38431.615683843156, 38456.80771596145, 38515.807420962854, 38541.614583854156, 7556.5466072035715, 7674.386049116058, 7688.38492920565, 7691.769246922573, 7696.576688282143, 7623.961880190619, 7662.1954694756805, 7691.807704807368, 7695.153533111354, 7712.498687585317, 7677.539347639132, 7713.112992005935, 7717.305442510173, 7717.305442510173, 7724.9227507725045, 7545.170031296553, 7550.848983020336, 7597.6960921562795, 7659.310662040443, 7678.193789652062, 7617.200193979607, 7675.15573286938, 7683.231676832328, 7687.884681729778, 7689.077310722693, 36774.63225367739, 37086.258274834356, 37114.443283350774, 37125.0, 37133.62866371329, 2848.3591191981845, 2853.259521612743, 2916.0377075565225, 2958.4378967996226, 2986.3728616990456, 27457.0, 27468.0, 28134.718652813415, 28227.435451290858, 28419.289517762016, 3480.825958702055, 3497.342020064744, 3515.1739341254784, 3611.765235259726, 3645.2527231917657, 240774.0, 241279.0, 241763.0, 242460.0, 242474.0, 48202.517974820505, 48478.75760621245, 48495.0, 48497.75751121222, 48514.60397772193, 31461.685383146334, 32387.35225295481, 51534.48465515372, 51601.0, 51619.38664293539, 13157.276349800853, 13487.0, 13499.257540835151, 13533.052686311863, 13650.522231721841, 9453.101955314234, 9460.8580871287, 9462.810743785085, 9464.242860571137, 9464.47945362998, 17184.570385740455, 17497.125143742764, 17549.649007020045, 17561.648767024588, 17662.646747064988, 6653.667316634149, 6701.827180243438, 6715.496337774686, 6735.259121496631, 6743.966280168568, 747.6448686873691, 836.0427310729302, 861.0184389795638, 872.7707482315458, 945.5933948402253, 24765.380865478506, 25556.23331300064, 26037.21888343315, 26450.941962321678, 26955.86522067377, 774.9651265693058, 894.2577660541734, 925.796324808541, 932.990670093304, 1002.9598816047277, 13713.588592342247, 13737.656558586115, 13794.65513362174, 14223.217723025333, 14399.99200056007, 360395.0, 416863.0, 422351.0, 423039.0, 622164.0, 47842.5215747845, 47915.281270780964, 47945.0, 47948.760256199195, 48007.519924801, 1044.102072217892, 1126.8760436352159, 1146.9942650286696, 1148.3454430974432, 1152.590830255264, 24952.625710613993, 27500.724992750616, 27717.584236236875, 27749.306267342672, 27995.0, 38578.421323679606, 39107.0, 39145.804270978464, 39148.412773807846, 39153.80423097866, 314486.0, 318183.0, 318190.09049547894, 318217.0, 318777.0, 22122.22572209965, 22634.43413914665, 22634.43413914665, 24486.632700509865, 24849.875750621854, 39563.406548901214, 39743.403848942864, 40899.38650920179, 40932.79533602432, 41301.0, 33939.15152121217, 34147.829260853534, 34335.828320858236, 34445.31109377748, 34720.479192811625, 34018.65981340155, 34100.82949585335, 34459.310813783086, 34784.82607586946, 34918.0, 34206.94045215373, 34238.315233695685, 34680.0, 34777.47833782445, 35390.11524711903, 29114.41771164607, 31211.0, 31244.375112497168, 31362.215944600655, 31736.365272693954, 23937.64093538633, 24133.75866241315, 24235.39411514726, 24475.0, 25142.24573262805], "value": 16759.87481642124, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [334.3813944203226, 360.2650592790709, 360.6123417326374, 360.778121455305, 383.8061778801705, 12299.446524906378, 12454.128211025221, 13394.330283485824, 16195.514134575953, 16358.509244722647, 14377.568672939802, 14727.705445891102, 15200.087994720325, 15306.770398444005, 15309.387624495035, 4859.319695242669, 4896.804127834882, 4993.101241776479, 5035.622328325372, 5092.057969275677, 3570.2145527983803, 4951.282064100703, 5042.722650254234, 5047.67190132642, 5619.7471113799975, 5917.467427931485, 5954.196183515235, 5989.6406215626985, 5996.16053752475, 6011.699415029253, 5754.683492407916, 5917.142014407908, 5968.552358573081, 5986.431289027535, 6024.698765061729, 5250.159974404077, 5264.447233040514, 5271.815486457994, 5275.103232450454, 5276.683398996034, 17890.821091789177, 19269.4219173425, 19801.900990495098, 19950.70073948892, 20010.599788004303, 1782.1445706061163, 1855.7494738210303, 1926.6339395514808, 2003.9980009995002, 2317.385892738426, 8595.5702214889, 9344.439333640003, 9405.482698451582, 12229.816552751716, 12345.567905123367, 1706.5542223791256, 1716.699577573932, 1728.8271172882737, 1736.6353065856185, 1743.8779285450023, 66224.66887665579, 67523.0, 68258.65870670615, 68791.65604171997, 69358.0, 9283.071692830663, 9316.767080823034, 9414.0, 9584.712458626253, 9610.711678649652, 12752.6811829705, 12759.448110377962, 12832.615021549369, 12840.0, 12851.742965140831, 6563.1796025496815, 6645.302243264484, 6829.795106146724, 6912.758053468205, 6945.583265004116, 6188.9690551546955, 6340.334264902119, 6342.39747224013, 6485.254195767453, 6531.575447595986, 4102.733322334038, 4107.691923105779, 4117.197146556393, 4139.523954745183, 4242.596953289432, 1731.2815181699705, 1781.750554922312, 1804.756357891668, 1824.7354133650547, 1850.3338798032698, 796.38678217772, 832.4422636833336, 838.8951381077366, 870.6778491958016, 923.5151545438698, 16217.432389866299, 16251.268692908847, 16726.16369181536, 17003.0, 17313.480595582154, 839.8404303182376, 854.1586537260794, 863.1799790199336, 865.2947847504264, 920.7293934370659, 8718.215360617449, 9379.343445958717, 9416.905830941603, 9508.0, 9737.26970477217, 368578.0, 371548.0, 374121.0, 374718.0, 377754.0, 49794.0, 51726.0, 75304.62347688337, 77580.0, 78627.0, 879.2131042716733, 892.7812685891998, 910.1990248581207, 910.8132832769267, 911.462237280008, 14434.133951962496, 14639.70720585561, 14847.925760371128, 15002.549923502313, 15319.0, 35833.28333433369, 38606.0, 39118.60881391264, 39433.80283098566, 39460.40809387804, 49658.751706242685, 50960.490395097055, 51730.48269517407, 52736.208956866445, 53460.73269633627, 6576.375244351971, 6814.250432452525, 6885.1049363582415, 7182.569045857265, 7715.382769378549, 10865.619703310345, 10907.454627268446, 11267.887321126684, 11348.262362946381, 11711.063114950954, 9757.658481953098, 9888.604455821975, 10101.949490252502, 10242.948785256027, 10964.945175274073, 10126.949365253127, 10221.9488902555, 10485.790284194121, 10674.8932510677, 10922.781544368909, 10708.411037393018, 10767.892321077003, 10771.569137234736, 10875.456227188768, 11281.435928203393, 7500.36246919018, 7518.699252029857, 7830.530168189927, 7837.3426915532145, 7859.410544209093, 7955.403344749283, 9967.202623789934, 10310.536025879004, 10471.21465890046, 10625.096866766411], "value": 8007.832455291106, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1227.6562562482509, 1329.1227789658822, 1372.9244891530966, 1446.7395868743629, 1678.1106013812685, 7090.574565526072, 7297.489175757699, 7362.631868406585, 7582.241775822415, 7589.506682065662, 7478.925210747891, 7549.282818132282, 7591.051118610174, 7600.961995190017, 7602.429817763662, 2148.205164089285, 2170.70695456113, 2173.0981642618294, 2173.217641649005, 2178.3900507857825, 2084.864483808555, 2114.9259775907844, 2116.7354080739906, 2120.957580848382, 2125.1605615781755, 2124.0547956159517, 2135.7864213578673, 2142.2502124256544, 2144.0994782191515, 2173.217641649005, 2099.3072286145534, 2105.799949004842, 2105.978940210609, 2107.0412962102205, 2107.71545841311, 2058.9176432942663, 2120.088362004353, 2121.437818977957, 2121.4802373418456, 2123.3205374280237, 7439.925600744032, 7522.586257755821, 7577.696892124363, 7597.468177227596, 7599.126100498458, 4307.655387568987, 4412.801423935898, 4412.889677758049, 4415.094905544388, 4417.160739459492, 2713.3623598454346, 2843.8720257588457, 2888.292368369742, 2894.2185609885214, 2898.087102562686, 948.8813898262717, 952.0479520479521, 976.6679329028093, 978.5938835383307, 980.7352014955993, 10270.691879243559, 10334.793304133875, 10338.431386273753, 10339.689809305657, 10355.689329320132, 3849.1339448623835, 3879.96301941483, 3987.601239876047, 4012.7792971386853, 4017.8995525112105, 8310.085890552034, 8316.833663326699, 8321.542315172723, 8333.083360830304, 8338.499690018618, 4430.5126436092005, 4436.44544431946, 4438.866833994985, 4446.26636604963, 4455.064436468313, 3607.1703508193264, 3975.1453437511072, 4111.465509483808, 4132.359484279941, 4132.442120313719, 3314.254292784104, 3364.7644664873224, 3403.5915690117345, 3433.364827506919, 3447.6035255945385, 992.9553170107218, 1013.7820368620781, 1017.9745506362252, 1020.8621836052038, 1026.1277913773233, 693.1647364925253, 742.9814254643569, 743.0303453992484, 805.8106345008919, 818.108261994429, 4986.775595098229, 5053.1157047516535, 5054.49455054499, 5152.871178220575, 5403.83788486346, 415.6550063447331, 417.26977788869476, 420.11356038758197, 422.42761058765547, 422.9788510574459, 3601.6938560222675, 3601.8559257629927, 3638.1086633774894, 3654.3239500692457, 3757.1734218471893, 8936.24041956428, 9369.859452108223, 9371.671991480265, 9372.90627093734, 9395.906040939502, 9333.439993600407, 9360.642706807475, 9360.90639093614, 9385.343025988115, 9415.246780257563, 7010.334018268256, 7135.607541585263, 7247.369341898031, 7263.709451621981, 7269.563826170447, 5106.514881086291, 5118.38579370478, 5138.685841015954, 5219.295395121761, 5463.235147079492, 8742.213200811957, 8817.206451419403, 8866.0, 8884.866726998971, 8900.73297801067, 8192.959035204785, 8440.83118337642, 8517.0, 8885.4668719877, 8918.643254270017, 6123.510119190365, 6512.6092434454085, 6672.733090676318, 6689.866202676017, 6845.794626161223, 7399.59302238382, 7496.700131994659, 7507.136679281734, 7510.511816731895, 7511.9624401877645, 6622.10601568782, 6746.325367463214, 6758.0, 6798.898016529657, 6885.070515480346, 6424.871502570016, 6500.089987401673, 6552.049952756919, 6580.440662543546, 6748.089007984056, 6044.758209671564, 6151.477124444473, 6151.72317245716, 6153.692315384303, 6157.938420615916, 4485.439320084989, 4537.954620453753, 4539.931901021553, 4543.954560454353, 4610.216263235325, 4451.3545535897765, 4486.170058539181, 4591.816327346869, 4633.212353899778, 4706.388169537938], "value": 3937.814955007158, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [282.9830210187388, 283.4076779530782, 286.5100677840892, 293.75764993880045, 299.1116384338514, 4808.37491126154, 4810.711357318563, 4855.3688020557365, 4861.319415281859, 4864.294677271798, 4747.8813029674275, 4750.5724484796365, 4780.689255198409, 4786.473487916326, 4794.3048258002655, 3727.739058265916, 3739.7943113128767, 3762.7742335459825, 3764.73646844721, 3767.7362584619086, 3659.505966694502, 3784.9432258516144, 3787.2425514896995, 3788.9431658525145, 3796.3925771876507, 3742.0, 3769.6795772359383, 3776.0748616588976, 3792.5448946126367, 3794.392897136459, 3712.981435092834, 3743.1577894973684, 3755.324041672498, 3768.6042965488505, 3788.753731007476, 3086.783925125242, 3165.2403423178503, 3299.4555898276753, 3302.290007648367, 3303.702666760003, 4614.8154073837, 4615.884602884921, 4616.5152658970665, 4633.559811817872, 4637.373954516131, 4257.531671516162, 4259.70182087254, 4259.893502662428, 4263.189993901149, 4264.253755592759, 4684.5549672781035, 4696.802315409549, 4954.3063971044085, 4979.352684151074, 5004.524570165829, 3597.2625611749527, 3624.5288112545463, 3626.8549258029648, 3630.7276954228273, 3631.4371272452813, 5094.974525127351, 5185.662931909414, 5186.9366779810425, 5195.896082078338, 5206.7917283308625, 3611.7471776975885, 3707.870224542128, 3733.3093377724663, 3739.8878033659034, 3757.4739536464917, 3763.1909139535132, 3784.4323351496946, 3787.7727336359358, 3793.468914351993, 3810.047488127968, 4208.737378786352, 4225.746455212697, 4246.193223287627, 4254.7021708480725, 4265.253580623378, 4922.360093187935, 4934.02638944418, 4934.519644106754, 4942.085714142895, 4982.825601103944, 1190.9285442873281, 1257.8553516300124, 1262.7348256865978, 1267.847858257015, 1283.9599924061354, 800.2597597222546, 807.4590024683476, 824.8886400335998, 827.2389401750404, 834.5701963488808, 3976.7812770297332, 3980.3034468968394, 3990.1620659661216, 3990.4213888986515, 4004.7985604318587, 4501.437320334958, 4551.38556294896, 4564.292534657134, 4577.519360467103, 4615.930761038587, 3911.413288006765, 3944.4674968878835, 3957.248122856648, 4004.3192657247755, 4010.859619913289, 2394.533066052103, 2422.9878850605633, 2528.1783420388447, 2719.741624545665, 2735.2510726719916, 4636.9536304637195, 4738.1708201064675, 4879.292502587176, 4896.559309662076, 4896.828610998598, 5263.473652634706, 5267.7366131694025, 5268.814516733781, 5292.9735351323, 5298.231756395378, 4869.050535145612, 4874.025194961022, 4874.610031197425, 4876.341693871282, 4884.291777692286, 5035.29505869186, 5106.157484015096, 5122.692638441706, 5140.691558506502, 5157.871053223699, 4993.176125939324, 5004.1492946198505, 5019.598432125495, 5022.7146648275675, 5022.799088036438, 4858.63560232991, 4877.658643872964, 4887.926681099715, 4890.731009794496, 4899.363082799213, 3424.1781972325966, 3557.982210088933, 3573.910652233611, 3586.515820364322, 3644.6355364463343, 5135.306733590917, 5143.534092783539, 5146.176611742103, 5155.510226528474, 5180.818671346485, 5040.899182016413, 5052.469490703424, 5062.114130027304, 5063.3417655704525, 5067.341245638044, 4896.167651499325, 4953.0589188054155, 4996.575291100152, 5006.774695138801, 5050.545450909289, 4877.317175595348, 4901.656884018084, 4909.091818013679, 4911.287863259879, 4918.852434426973, 5030.195168772979, 5033.2701758245585, 5039.042581909425, 5039.874003149951, 5050.469700681522, 4695.154872123051, 4911.017796440655, 4916.729579873141, 4926.630502712239, 4929.211326187793], "value": 3727.993928378121, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [625.8028720952902, 627.538759012126, 658.7727234104235, 666.1107421592175, 748.8764353881608, 10724.678259652213, 11994.640160795168, 12019.399030048506, 12097.999999999989, 12140.75718485631, 11824.940875295611, 11847.526098956054, 11848.703782405444, 11854.940725296383, 11862.0, 594.9672767997758, 715.4419552748839, 720.0423436829005, 739.970401183952, 742.3541518878583, 729.8941653460272, 732.1068296678044, 734.988975165373, 735.0444422251079, 735.477810754365, 789.0610173893077, 789.1950210784996, 790.3716545346464, 790.8853216283635, 791.1574173505227, 630.2751835389294, 755.1957165618599, 770.5530792140601, 781.4490783997325, 790.2334735306744, 569.7891780041369, 570.3213176320207, 570.3327107284487, 570.7260514952845, 570.9029464990962, 11845.23006004607, 11885.0, 11887.762244755142, 11908.702282442922, 11910.702232444171, 11743.823842642367, 11984.820227696591, 12022.819657705142, 12104.576339828152, 12728.236305821587, 10125.949370253174, 10311.278210525266, 10340.431276279802, 10391.896081039169, 10529.947350263275, 4054.898627534306, 4101.589841015904, 4125.8968525786795, 4275.508316543606, 4281.871543853689, 60048.099278510854, 61012.389876101115, 61199.388006119814, 61539.0, 61927.0, 9979.750506237402, 10044.648437304659, 10218.284720069672, 10237.436940968319, 10558.52486638103, 18875.150618222215, 18877.244910203437, 18890.54422925011, 18894.62210755777, 18927.90536047311, 6473.579217350857, 6511.967440162769, 6540.4767618590395, 6601.636909969901, 6767.357101075489, 3554.9670983862966, 3578.0228554319183, 3605.891823245307, 3620.0, 3652.251288485854, 2551.5407226699003, 2597.5194589001094, 2614.372550587845, 2622.318197268724, 2637.736226377347, 428.4900967848251, 486.75905426813245, 488.83868323453174, 496.0822478414976, 496.3919198981219, 530.6577257668782, 551.2337850387964, 569.0411656359048, 571.3343954293202, 592.8221533539921, 7473.887891681629, 7632.771016869503, 7682.462227644011, 7864.331531819859, 7926.682932682744, 441.03738364279087, 548.9451054894558, 549.7251374312843, 551.6827824001215, 570.823044856096, 3630.8184590770884, 3683.7053035757613, 3748.700103991674, 3789.109559253573, 3925.685945124383, 59429.40570594239, 71138.2886171142, 73941.0, 73943.0, 87414.0, 72861.27138728545, 76626.61686691531, 77115.0, 78304.60847695725, 80959.0, 42890.0, 53489.0, 66384.0, 68860.31139688539, 69450.0, 7390.29792169743, 7801.531908085533, 7817.960910195413, 7897.1707970662255, 7904.13054564009, 41500.99999999879, 41779.7911010443, 42313.365299521145, 42386.36420453634, 42442.78778606211, 55541.44458555524, 59827.0, 60731.0, 63336.36663633489, 63489.047664284146, 10799.946000269949, 10807.783844322912, 10888.45557722095, 10958.835617465904, 11477.139214558774, 12063.939680301542, 12285.631431057083, 12661.430235639602, 12908.225506469642, 12995.155314904501, 11272.380019099028, 11448.671260576024, 11660.475278612314, 11761.353125578175, 12362.258264504158, 10693.14454843595, 10794.244402891722, 10905.78188436211, 11512.136589755635, 11575.24760890573, 11088.501017454395, 11228.55085796559, 11408.429578520874, 11460.885391146316, 11720.179587428798, 9664.256069981802, 9732.659356922757, 10170.542325595514, 10331.22515811302, 10381.0, 9353.81292374135, 9910.405375677483, 10154.441505717257, 10188.554746860847, 10270.845937311096], "value": 6239.724482434363, "unit": "score"}], "name": "dromaeo_css", "value": 6197.382357921351, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 132.0, 114.0, 116.0, 114.0, 114.0, 115.0, 114.0, 115.0], "value": 116.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 128.0, 153.0, 190.0, 125.0, 146.0, 149.0, 130.0, 190.0, 125.0], "value": 149.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 165.0, 161.0, 161.0, 161.0, 160.0, 162.0, 160.0, 161.0], "value": 161.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 77.0, 76.0, 164.0, 76.0, 75.0, 76.0, 76.0, 156.0, 77.0], "value": 93.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 139.0, 83.0, 82.0, 83.0, 146.0, 84.0, 84.0, 136.0], "value": 100.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 146.0, 146.0, 146.0, 147.0, 147.0, 146.0, 146.0, 147.0], "value": 146.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 97.0, 95.0, 95.0, 96.0, 96.0, 96.0, 95.0], "value": 95.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 64.0, 65.0, 65.0, 66.0, 65.0, 65.0, 67.0, 65.0], "value": 65.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 66.0, 67.0, 66.0, 66.0, 66.0, 66.0, 67.0], "value": 66.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 81.0, 83.0, 82.0, 82.0, 82.0, 82.0, 84.0, 82.0], "value": 82.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 131.0, 134.0, 133.0, 132.0, 133.0, 135.0, 148.0, 176.0, 134.0], "value": 138.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 177.0, 173.0, 175.0, 172.0, 175.0, 170.0, 176.0, 171.0, 175.0], "value": 174.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 74.0, 70.0, 68.0, 72.0, 69.0, 70.0, 69.0, 68.0, 68.0], "value": 69.7, "unit": "score"}], "name": "kraken", "value": 1576.2, "alertThreshold": 2.0}]} 18:36:04 INFO - Return code: 0 18:36:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:36:04 INFO - # TBPL SUCCESS # 18:36:04 INFO - Running post-action listener: _resource_record_post_action 18:36:04 INFO - [mozharness: 2016-08-30 01:36:04.472593Z] Finished run-tests step (success) 18:36:04 INFO - Running post-run listener: _resource_record_post_run 18:36:04 INFO - Total resource usage - Wall time: 1202s; CPU: 13.0%; Read bytes: 4096; Write bytes: 418254848; Read time: 8; Write time: 321528 18:36:04 INFO - TinderboxPrint: CPU usage
13.0% 18:36:04 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 18:36:04 INFO - TinderboxPrint: I/O write bytes / time
418,254,848 / 321,528 18:36:04 INFO - TinderboxPrint: CPU idle
8,338.8 (86.9%) 18:36:04 INFO - TinderboxPrint: CPU user
1,203.0 (12.5%) 18:36:04 INFO - TinderboxPrint: Swap in / out
0 / 0 18:36:04 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 125001728; Read time: 0; Write time: 89672 18:36:04 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 0; Write bytes: 293253120; Read time: 0; Write time: 231856 18:36:05 INFO - Running post-run listener: _upload_blobber_files 18:36:05 INFO - Blob upload gear active. 18:36:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:36:05 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 18:36:05 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'] 18:36:05 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 18:36:05 INFO - (blobuploader) - INFO - Open directory for files ... 18:36:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:36:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:36:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:36:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:36:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:36:09 INFO - (blobuploader) - INFO - Done attempting. 18:36:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:36:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:36:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:36:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:36:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:36:10 INFO - (blobuploader) - INFO - Done attempting. 18:36:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:36:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:36:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:36:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:36:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:36:11 INFO - (blobuploader) - INFO - Done attempting. 18:36:11 INFO - (blobuploader) - INFO - Iteration through files over. 18:36:11 INFO - Return code: 0 18:36:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:36:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:36:11 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8fa0754f16271ebb48d27c8e97bc9bbf424ce65e70c05ae4e2632428af4f2c888f191c43216b92385938be80c7e2477fcc4f2893c4720ffca7d42d9b59526c5b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f2cdcf2e63b7d1385cb9767bdc124b6adba3b662f322ad8a33af35b9785c00b1845a98dfb6a1064021c987c755344f560909979fac98a790ee3738281317b420", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1b0cedc040e84a146cce65c8612a75d29e608adee8da6e55b65814764d88eae788c5f61a6770dd64d9d94d515161aa270f74cc7742d4fda18da4388dbabfa0c5"} 18:36:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:36:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:36:11 INFO - Contents: 18:36:11 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8fa0754f16271ebb48d27c8e97bc9bbf424ce65e70c05ae4e2632428af4f2c888f191c43216b92385938be80c7e2477fcc4f2893c4720ffca7d42d9b59526c5b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f2cdcf2e63b7d1385cb9767bdc124b6adba3b662f322ad8a33af35b9785c00b1845a98dfb6a1064021c987c755344f560909979fac98a790ee3738281317b420", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1b0cedc040e84a146cce65c8612a75d29e608adee8da6e55b65814764d88eae788c5f61a6770dd64d9d94d515161aa270f74cc7742d4fda18da4388dbabfa0c5"} 18:36:11 INFO - Running post-run listener: copy_logs_to_upload_dir 18:36:11 INFO - Copying logs to upload dir... 18:36:11 INFO - mkdir: /builds/slave/test/build/upload/logs 18:36:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1245.893077 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-08-29 18:36:11.231974) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-29 18:36:11.235500) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1472515787.361389-1398414265 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8fa0754f16271ebb48d27c8e97bc9bbf424ce65e70c05ae4e2632428af4f2c888f191c43216b92385938be80c7e2477fcc4f2893c4720ffca7d42d9b59526c5b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f2cdcf2e63b7d1385cb9767bdc124b6adba3b662f322ad8a33af35b9785c00b1845a98dfb6a1064021c987c755344f560909979fac98a790ee3738281317b420", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1b0cedc040e84a146cce65c8612a75d29e608adee8da6e55b65814764d88eae788c5f61a6770dd64d9d94d515161aa270f74cc7742d4fda18da4388dbabfa0c5"} build_url:https://queue.taskcluster.net/v1/task/Er5BLUC3Siidl0rw56MISQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011153 build_url: 'https://queue.taskcluster.net/v1/task/Er5BLUC3Siidl0rw56MISQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8fa0754f16271ebb48d27c8e97bc9bbf424ce65e70c05ae4e2632428af4f2c888f191c43216b92385938be80c7e2477fcc4f2893c4720ffca7d42d9b59526c5b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f2cdcf2e63b7d1385cb9767bdc124b6adba3b662f322ad8a33af35b9785c00b1845a98dfb6a1064021c987c755344f560909979fac98a790ee3738281317b420", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1b0cedc040e84a146cce65c8612a75d29e608adee8da6e55b65814764d88eae788c5f61a6770dd64d9d94d515161aa270f74cc7742d4fda18da4388dbabfa0c5"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-29 18:36:11.261715) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 18:36:11.262194) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1472515787.361389-1398414265 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004490 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 18:36:11.348509) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-29 18:36:11.349035) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-29 18:36:11.349545) ========= ========= Total master_lag: 0.17 =========