builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-057 starttime: 1472756187.64 results: success (0) buildid: 20160901103520 builduid: 60d37b5689c14c2ebe5cb2188341c886 revision: 3ba5426a03b495b6417fffb872d42874edb80855 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 11:56:27.641756) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 11:56:27.642234) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 11:56:27.642509) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 11:56:27.658508) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 11:56:27.658903) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1472756187.437388-177872028 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-01 11:56:28-- 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% 221M=0s 2016-09-01 11:56:28 (221 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.140351 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 11:56:27.813149) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 11:56:27.813476) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1472756187.437388-177872028 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019777 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 11:56:27.863231) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 11:56:27.863581) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3ba5426a03b495b6417fffb872d42874edb80855 --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 3ba5426a03b495b6417fffb872d42874edb80855 --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=934fc3100db09dcb9af4d4b6000001ab-1472756187.437388-177872028 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-01 11:56:28,457 truncating revision to first 12 chars 2016-09-01 11:56:28,457 Setting DEBUG logging. 2016-09-01 11:56:28,457 attempt 1/10 2016-09-01 11:56:28,457 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3ba5426a03b4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-01 11:56:28,770 unpacking tar archive at: mozilla-central-3ba5426a03b4/testing/mozharness/ program finished with exit code 0 elapsedTime=0.644740 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 11:56:28.523742) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 11:56:28.524072) ========= script_repo_revision: 3ba5426a03b495b6417fffb872d42874edb80855 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 11:56:28.524471) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 11:56:28.524752) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 11:56:28.535293) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2016-09-01 11:56:28.535721) ========= /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=934fc3100db09dcb9af4d4b6000001ab-1472756187.437388-177872028 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:56:29 INFO - MultiFileLogger online at 20160901 11:56:29 in /builds/slave/test 11:56:29 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 11:56:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:56:29 INFO - {'append_to_log': False, 11:56:29 INFO - 'base_work_dir': '/builds/slave/test', 11:56:29 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 11:56:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:56:29 INFO - 'branch': 'Firefox-Non-PGO', 11:56:29 INFO - 'buildbot_json_path': 'buildprops.json', 11:56:29 INFO - 'config_files': ('talos/linux_config.py',), 11:56:29 INFO - 'default_actions': ('clobber', 11:56:29 INFO - 'read-buildbot-config', 11:56:29 INFO - 'download-and-extract', 11:56:29 INFO - 'populate-webroot', 11:56:29 INFO - 'create-virtualenv', 11:56:29 INFO - 'install', 11:56:29 INFO - 'run-tests'), 11:56:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:56:29 INFO - 'download_minidump_stackwalk': True, 11:56:29 INFO - 'download_symbols': 'ondemand', 11:56:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:56:29 INFO - 'tooltool.py': '/tools/tooltool.py', 11:56:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:56:29 INFO - '/tools/misc-python/virtualenv.py')}, 11:56:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:56:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:56:29 INFO - 'installer_path': 'installer.exe', 11:56:29 INFO - 'log_level': 'info', 11:56:29 INFO - 'log_name': 'talos', 11:56:29 INFO - 'log_to_console': True, 11:56:29 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:56:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:56:29 INFO - 'opt_config_files': (), 11:56:29 INFO - 'pip_index': False, 11:56:29 INFO - 'sps_profile': False, 11:56:29 INFO - 'sps_profile_interval': 0, 11:56:29 INFO - 'suite': 'dromaeojs', 11:56:29 INFO - 'system_bits': '32', 11:56:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:56:29 INFO - 'title': 'talos-linux64-ix-057', 11:56:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:56:29 INFO - 'use_talos_json': True, 11:56:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:56:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:56:29 INFO - 'work_dir': 'build'} 11:56:29 INFO - [mozharness: 2016-09-01 18:56:29.232067Z] Running clobber step. 11:56:29 INFO - Running pre-action listener: _resource_record_pre_action 11:56:29 INFO - Running main action method: clobber 11:56:29 INFO - rmtree: /builds/slave/test/build 11:56:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:56:29 INFO - Running post-action listener: _resource_record_post_action 11:56:29 INFO - [mozharness: 2016-09-01 18:56:29.689859Z] Finished clobber step (success) 11:56:29 INFO - [mozharness: 2016-09-01 18:56:29.689962Z] Running read-buildbot-config step. 11:56:29 INFO - Running pre-action listener: _resource_record_pre_action 11:56:29 INFO - Running main action method: read_buildbot_config 11:56:29 INFO - Using buildbot properties: 11:56:29 INFO - { 11:56:29 INFO - "project": "", 11:56:29 INFO - "product": "firefox", 11:56:29 INFO - "script_repo_revision": "production", 11:56:29 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 11:56:29 INFO - "repository": "", 11:56:29 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 11:56:29 INFO - "buildid": "20160901103520", 11:56:29 INFO - "pgo_build": "False", 11:56:29 INFO - "basedir": "/builds/slave/test", 11:56:29 INFO - "buildnumber": 703, 11:56:29 INFO - "slavename": "talos-linux64-ix-057", 11:56:29 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 11:56:29 INFO - "platform": "ubuntu64_hw", 11:56:29 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 11:56:29 INFO - "branch": "mozilla-central", 11:56:29 INFO - "repo_path": "mozilla-central", 11:56:29 INFO - "revision": "3ba5426a03b495b6417fffb872d42874edb80855", 11:56:29 INFO - "stage_platform": "linux64", 11:56:29 INFO - "builduid": "60d37b5689c14c2ebe5cb2188341c886", 11:56:29 INFO - "slavebuilddir": "test" 11:56:29 INFO - } 11:56:29 INFO - Found installer url https://queue.taskcluster.net/v1/task/J2vD5DrAT-2vs1ss5UrKCw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 11:56:29 INFO - Running post-action listener: _resource_record_post_action 11:56:29 INFO - [mozharness: 2016-09-01 18:56:29.692406Z] Finished read-buildbot-config step (success) 11:56:29 INFO - [mozharness: 2016-09-01 18:56:29.692495Z] Running download-and-extract step. 11:56:29 INFO - Running pre-action listener: _resource_record_pre_action 11:56:29 INFO - Running main action method: download_and_extract 11:56:29 INFO - mkdir: /builds/slave/test/build/tests 11:56:29 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')]} 11:56:29 INFO - trying https://queue.taskcluster.net/v1/task/J2vD5DrAT-2vs1ss5UrKCw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 11:56:29 INFO - Downloading https://queue.taskcluster.net/v1/task/J2vD5DrAT-2vs1ss5UrKCw/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 11:56:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J2vD5DrAT-2vs1ss5UrKCw/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 11:56:30 INFO - Downloaded 1361 bytes. 11:56:30 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 11:56:30 INFO - Using the following test package requirements: 11:56:30 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 11:56:30 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 11:56:30 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 11:56:30 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 11:56:30 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 11:56:30 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 11:56:30 INFO - u'jsshell-linux-x86_64.zip'], 11:56:30 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 11:56:30 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 11:56:30 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 11:56:30 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 11:56:30 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 11:56:30 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 11:56:30 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 11:56:30 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 11:56:30 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 11:56:30 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 11:56:30 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 11:56:30 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 11:56:30 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')]} 11:56:30 INFO - trying https://queue.taskcluster.net/v1/task/J2vD5DrAT-2vs1ss5UrKCw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 11:56:30 INFO - Downloading https://queue.taskcluster.net/v1/task/J2vD5DrAT-2vs1ss5UrKCw/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 11:56:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J2vD5DrAT-2vs1ss5UrKCw/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 11:56:31 INFO - Downloaded 20334479 bytes. 11:56:31 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 11:56:35 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 11:56:35 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')]} 11:56:35 INFO - trying https://queue.taskcluster.net/v1/task/J2vD5DrAT-2vs1ss5UrKCw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 11:56:35 INFO - Downloading https://queue.taskcluster.net/v1/task/J2vD5DrAT-2vs1ss5UrKCw/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 11:56:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J2vD5DrAT-2vs1ss5UrKCw/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 11:56:36 INFO - Downloaded 20334479 bytes. 11:56:36 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 11:56:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:56:40 INFO - trying https://queue.taskcluster.net/v1/task/J2vD5DrAT-2vs1ss5UrKCw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 11:56:40 INFO - Downloading https://queue.taskcluster.net/v1/task/J2vD5DrAT-2vs1ss5UrKCw/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 11:56:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J2vD5DrAT-2vs1ss5UrKCw/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 11:56:41 INFO - Downloaded 14413998 bytes. 11:56:41 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 11:56: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')]} 11:56:42 INFO - trying https://queue.taskcluster.net/v1/task/J2vD5DrAT-2vs1ss5UrKCw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 11:56:42 INFO - Downloading https://queue.taskcluster.net/v1/task/J2vD5DrAT-2vs1ss5UrKCw/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 11:56:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J2vD5DrAT-2vs1ss5UrKCw/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 11:56:43 INFO - Downloaded 55865810 bytes. 11:56:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/J2vD5DrAT-2vs1ss5UrKCw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 11:56:43 INFO - mkdir: /builds/slave/test/properties 11:56:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:56:43 INFO - Writing to file /builds/slave/test/properties/build_url 11:56:43 INFO - Contents: 11:56:43 INFO - build_url:https://queue.taskcluster.net/v1/task/J2vD5DrAT-2vs1ss5UrKCw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 11:56:43 INFO - Running post-action listener: _resource_record_post_action 11:56:43 INFO - Running post-action listener: set_extra_try_arguments 11:56:43 INFO - [mozharness: 2016-09-01 18:56:43.884314Z] Finished download-and-extract step (success) 11:56:43 INFO - [mozharness: 2016-09-01 18:56:43.884614Z] Running populate-webroot step. 11:56:43 INFO - Running pre-action listener: _resource_record_pre_action 11:56:43 INFO - Running main action method: populate_webroot 11:56:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:56:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:56:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:56:43 INFO - '--tppagecycles', 11:56:43 INFO - '7'], 11:56:43 INFO - 'tests': ['tsvgm']}}, 11:56:43 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:56:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:56:43 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:56:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:56:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:56:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:56:43 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:56:43 INFO - 'talos_options': ['--disable-e10s'], 11:56:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:56:43 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:56:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:56:43 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:56:43 INFO - 'talos_options': ['--disable-e10s'], 11:56:43 INFO - 'tests': ['damp', 'tps']}, 11:56:43 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:56:43 INFO - 'tests': ['damp', 'tps']}, 11:56:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:56:43 INFO - 'tests': ['dromaeo_dom']}, 11:56:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:56:43 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:56:43 INFO - 'tests': ['basic_compositor_video']}, 11:56:43 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:56:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:56:43 INFO - 'tests': ['a11yr', 11:56:43 INFO - 'ts_paint', 11:56:43 INFO - 'tpaint', 11:56:43 INFO - 'sessionrestore', 11:56:43 INFO - 'sessionrestore_no_auto_restore', 11:56:43 INFO - 'tabpaint']}, 11:56:43 INFO - 'other-e10s': {'tests': ['a11yr', 11:56:43 INFO - 'ts_paint', 11:56:43 INFO - 'tpaint', 11:56:43 INFO - 'sessionrestore', 11:56:43 INFO - 'sessionrestore_no_auto_restore', 11:56:43 INFO - 'tabpaint']}, 11:56:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:56:43 INFO - 'ts_paint', 11:56:43 INFO - 'tpaint', 11:56:43 INFO - 'sessionrestore', 11:56:43 INFO - 'sessionrestore_no_auto_restore', 11:56:43 INFO - 'tabpaint']}, 11:56:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:56:43 INFO - 'ts_paint', 11:56:43 INFO - 'tpaint', 11:56:43 INFO - 'sessionrestore', 11:56:43 INFO - 'sessionrestore_no_auto_restore', 11:56:43 INFO - 'tabpaint']}, 11:56:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:56:43 INFO - 'tests': ['a11yr', 11:56:43 INFO - 'ts_paint', 11:56:43 INFO - 'tpaint', 11:56:43 INFO - 'sessionrestore', 11:56:43 INFO - 'sessionrestore_no_auto_restore', 11:56:43 INFO - 'tabpaint']}, 11:56:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:56:43 INFO - 'tests': ['a11yr', 11:56:43 INFO - 'ts_paint', 11:56:43 INFO - 'tpaint', 11:56:43 INFO - 'sessionrestore', 11:56:43 INFO - 'sessionrestore_no_auto_restore', 11:56:43 INFO - 'tabpaint']}, 11:56:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:56:43 INFO - 'tests': ['tsvgx', 11:56:43 INFO - 'tsvgr_opacity', 11:56:43 INFO - 'tart', 11:56:43 INFO - 'tscrollx', 11:56:43 INFO - 'cart']}, 11:56:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:56:43 INFO - 'tsvgr_opacity', 11:56:43 INFO - 'tart', 11:56:43 INFO - 'tscrollx', 11:56:43 INFO - 'cart']}, 11:56:43 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:56:43 INFO - 'talos_options': ['--disable-e10s'], 11:56:43 INFO - 'tests': ['tp5o']}, 11:56:43 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:56:43 INFO - 'tests': ['tp5o']}, 11:56:43 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:56:43 INFO - 'talos_options': ['--disable-e10s', 11:56:43 INFO - '--xperf_path', 11:56:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:56:43 INFO - 'tests': ['tp5n']}, 11:56:43 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:56:43 INFO - 'talos_options': ['--xperf_path', 11:56:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:56:43 INFO - 'tests': ['tp5n']}}, 11:56:43 INFO - 'talos.zip': {'path': '', 11:56:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:56:43 INFO - Running post-action listener: _resource_record_post_action 11:56:43 INFO - [mozharness: 2016-09-01 18:56:43.902754Z] Finished populate-webroot step (success) 11:56:43 INFO - [mozharness: 2016-09-01 18:56:43.902849Z] Running create-virtualenv step. 11:56:43 INFO - Running pre-action listener: _resource_record_pre_action 11:56:43 INFO - Running main action method: create_virtualenv 11:56:43 INFO - Creating virtualenv /builds/slave/test/build/venv 11:56:43 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 11:56:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:56:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:56:43 INFO - Using real prefix '/usr' 11:56:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:56:44 INFO - Installing distribute.............................................................................................................................................................................................done. 11:56:46 INFO - Installing pip.................done. 11:56:46 INFO - Return code: 0 11:56:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:56:46 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')]} 11:56:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:56:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:56:46 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')]} 11:56:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:56:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:56:46 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 0x7f01d0c69f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d428a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e11230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e0b090>, '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 0x1e0e9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e0ee40>, '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': '934fc3100db09dcb9af4d4b6000001ab-1472756187.437388-177872028', '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 11:56:46 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 11:56:46 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 11:56:46 INFO - Using env: {'DISPLAY': ':0', 11:56:46 INFO - 'HOME': '/home/cltbld', 11:56:46 INFO - 'LANG': 'en_US.UTF-8', 11:56:46 INFO - 'LANGUAGE': 'en_US:en', 11:56:46 INFO - 'LOGNAME': 'cltbld', 11:56:46 INFO - 'MAIL': '/var/mail/cltbld', 11:56:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:56:46 INFO - 'MOZ_NO_REMOTE': '1', 11:56:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:56:46 INFO - 'NO_EM_RESTART': '1', 11:56:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:56:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:56:46 INFO - 'PWD': '/builds/slave/test', 11:56:46 INFO - 'SHELL': '/bin/bash', 11:56:46 INFO - 'SHLVL': '1', 11:56:46 INFO - 'TERM': 'linux', 11:56:46 INFO - 'TMOUT': '86400', 11:56:46 INFO - 'USER': 'cltbld', 11:56:46 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1472756187.437388-177872028', 11:56:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:56:46 INFO - '_': '/tools/buildbot/bin/python'} 11:56:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:56:46 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 11:56:46 INFO - Cleaning up... 11:56:46 INFO - Return code: 0 11:56:46 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:56:46 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')]} 11:56:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:56:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:56:46 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')]} 11:56:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:56:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:56:46 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 0x7f01d0c69f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d428a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e11230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e0b090>, '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 0x1e0e9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e0ee40>, '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': '934fc3100db09dcb9af4d4b6000001ab-1472756187.437388-177872028', '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 11:56:46 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 11:56:46 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 11:56:46 INFO - Using env: {'DISPLAY': ':0', 11:56:46 INFO - 'HOME': '/home/cltbld', 11:56:46 INFO - 'LANG': 'en_US.UTF-8', 11:56:46 INFO - 'LANGUAGE': 'en_US:en', 11:56:46 INFO - 'LOGNAME': 'cltbld', 11:56:46 INFO - 'MAIL': '/var/mail/cltbld', 11:56:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:56:46 INFO - 'MOZ_NO_REMOTE': '1', 11:56:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:56:46 INFO - 'NO_EM_RESTART': '1', 11:56:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:56:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:56:46 INFO - 'PWD': '/builds/slave/test', 11:56:46 INFO - 'SHELL': '/bin/bash', 11:56:46 INFO - 'SHLVL': '1', 11:56:46 INFO - 'TERM': 'linux', 11:56:46 INFO - 'TMOUT': '86400', 11:56:46 INFO - 'USER': 'cltbld', 11:56:46 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1472756187.437388-177872028', 11:56:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:56:46 INFO - '_': '/tools/buildbot/bin/python'} 11:56:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:56:46 INFO - Downloading/unpacking psutil>=3.1.1 11:56:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:56:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:56:46 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 11:56:46 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 11:56:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:56:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:56:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:56:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:56:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:56:48 INFO - Installing collected packages: psutil 11:56:48 INFO - Running setup.py install for psutil 11:56:48 INFO - building 'psutil._psutil_linux' extension 11:56:48 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 11:56:49 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 11:56:49 INFO - building 'psutil._psutil_posix' extension 11:56:49 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 11:56:49 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 11:56:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:56:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:56:49 INFO - Successfully installed psutil 11:56:49 INFO - Cleaning up... 11:56:49 INFO - Return code: 0 11:56:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:56:49 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')]} 11:56:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:56:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:56:49 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')]} 11:56:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:56:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:56:49 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 0x7f01d0c69f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d428a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e11230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e0b090>, '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 0x1e0e9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e0ee40>, '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': '934fc3100db09dcb9af4d4b6000001ab-1472756187.437388-177872028', '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 11:56:49 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 11:56:49 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 11:56:49 INFO - Using env: {'DISPLAY': ':0', 11:56:49 INFO - 'HOME': '/home/cltbld', 11:56:49 INFO - 'LANG': 'en_US.UTF-8', 11:56:49 INFO - 'LANGUAGE': 'en_US:en', 11:56:49 INFO - 'LOGNAME': 'cltbld', 11:56:49 INFO - 'MAIL': '/var/mail/cltbld', 11:56:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:56:49 INFO - 'MOZ_NO_REMOTE': '1', 11:56:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:56:49 INFO - 'NO_EM_RESTART': '1', 11:56:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:56:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:56:49 INFO - 'PWD': '/builds/slave/test', 11:56:49 INFO - 'SHELL': '/bin/bash', 11:56:49 INFO - 'SHLVL': '1', 11:56:49 INFO - 'TERM': 'linux', 11:56:49 INFO - 'TMOUT': '86400', 11:56:49 INFO - 'USER': 'cltbld', 11:56:49 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1472756187.437388-177872028', 11:56:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:56:49 INFO - '_': '/tools/buildbot/bin/python'} 11:56:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:56:49 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:56: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 11:56: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 11:56: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 11:56: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 11:56: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 11:56: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 11:56:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:56:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:56:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:56:51 INFO - Installing collected packages: mozsystemmonitor 11:56:51 INFO - Running setup.py install for mozsystemmonitor 11:56:51 INFO - Successfully installed mozsystemmonitor 11:56:51 INFO - Cleaning up... 11:56:51 INFO - Return code: 0 11:56:51 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:56:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:56:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:56:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:56:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:56:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:56:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:56:51 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 0x7f01d0c69f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d428a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e11230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e0b090>, '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 0x1e0e9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e0ee40>, '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': '934fc3100db09dcb9af4d4b6000001ab-1472756187.437388-177872028', '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 11:56:51 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 11:56:51 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 11:56:51 INFO - Using env: {'DISPLAY': ':0', 11:56:51 INFO - 'HOME': '/home/cltbld', 11:56:51 INFO - 'LANG': 'en_US.UTF-8', 11:56:51 INFO - 'LANGUAGE': 'en_US:en', 11:56:51 INFO - 'LOGNAME': 'cltbld', 11:56:51 INFO - 'MAIL': '/var/mail/cltbld', 11:56:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:56:51 INFO - 'MOZ_NO_REMOTE': '1', 11:56:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:56:51 INFO - 'NO_EM_RESTART': '1', 11:56:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:56:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:56:51 INFO - 'PWD': '/builds/slave/test', 11:56:51 INFO - 'SHELL': '/bin/bash', 11:56:51 INFO - 'SHLVL': '1', 11:56:51 INFO - 'TERM': 'linux', 11:56:51 INFO - 'TMOUT': '86400', 11:56:51 INFO - 'USER': 'cltbld', 11:56:51 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1472756187.437388-177872028', 11:56:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:56:51 INFO - '_': '/tools/buildbot/bin/python'} 11:56:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:56:51 INFO - Downloading/unpacking jsonschema==2.5.1 11:56:51 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 11:56:51 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 11:56:51 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 11:56:51 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 11:56:51 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 11:56:51 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 11:56:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:56:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:56: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 11:56: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 11:56: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 11:56: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 11:56: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 11:56: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 11:56:54 INFO - Downloading functools32-3.2.3-2.tar.gz 11:56:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:56:54 INFO - warning: no files found matching '*.txt' 11:56:54 INFO - no previously-included directories found matching 'build' 11:56:54 INFO - no previously-included directories found matching 'dist' 11:56:54 INFO - no previously-included directories found matching '.git*' 11:56:54 INFO - Installing collected packages: jsonschema, functools32 11:56:54 INFO - Running setup.py install for functools32 11:56:54 INFO - warning: no files found matching '*.txt' 11:56:54 INFO - no previously-included directories found matching 'build' 11:56:54 INFO - no previously-included directories found matching 'dist' 11:56:54 INFO - no previously-included directories found matching '.git*' 11:56:54 INFO - Successfully installed jsonschema functools32 11:56:54 INFO - Cleaning up... 11:56:54 INFO - Return code: 0 11:56:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:56: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')]} 11:56:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:56:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:56: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')]} 11:56:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:56:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:56:54 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 0x7f01d0c69f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d428a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e11230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e0b090>, '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 0x1e0e9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e0ee40>, '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': '934fc3100db09dcb9af4d4b6000001ab-1472756187.437388-177872028', '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 11:56:54 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 11:56:54 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 11:56:54 INFO - Using env: {'DISPLAY': ':0', 11:56:54 INFO - 'HOME': '/home/cltbld', 11:56:54 INFO - 'LANG': 'en_US.UTF-8', 11:56:54 INFO - 'LANGUAGE': 'en_US:en', 11:56:54 INFO - 'LOGNAME': 'cltbld', 11:56:54 INFO - 'MAIL': '/var/mail/cltbld', 11:56:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:56:54 INFO - 'MOZ_NO_REMOTE': '1', 11:56:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:56:54 INFO - 'NO_EM_RESTART': '1', 11:56:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:56:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:56:54 INFO - 'PWD': '/builds/slave/test', 11:56:54 INFO - 'SHELL': '/bin/bash', 11:56:54 INFO - 'SHLVL': '1', 11:56:54 INFO - 'TERM': 'linux', 11:56:54 INFO - 'TMOUT': '86400', 11:56:54 INFO - 'USER': 'cltbld', 11:56:54 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1472756187.437388-177872028', 11:56:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:56:54 INFO - '_': '/tools/buildbot/bin/python'} 11:56:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:56:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:56:54 INFO - Cleaning up... 11:56:54 INFO - Return code: 0 11:56:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:56: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')]} 11:56:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:56:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:56: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')]} 11:56:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:56:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:56:54 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 0x7f01d0c69f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d428a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e11230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e0b090>, '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 0x1e0e9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e0ee40>, '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': '934fc3100db09dcb9af4d4b6000001ab-1472756187.437388-177872028', '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 11:56:54 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 11:56:54 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 11:56:54 INFO - Using env: {'DISPLAY': ':0', 11:56:54 INFO - 'HOME': '/home/cltbld', 11:56:54 INFO - 'LANG': 'en_US.UTF-8', 11:56:54 INFO - 'LANGUAGE': 'en_US:en', 11:56:54 INFO - 'LOGNAME': 'cltbld', 11:56:54 INFO - 'MAIL': '/var/mail/cltbld', 11:56:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:56:54 INFO - 'MOZ_NO_REMOTE': '1', 11:56:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:56:54 INFO - 'NO_EM_RESTART': '1', 11:56:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:56:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:56:54 INFO - 'PWD': '/builds/slave/test', 11:56:54 INFO - 'SHELL': '/bin/bash', 11:56:54 INFO - 'SHLVL': '1', 11:56:54 INFO - 'TERM': 'linux', 11:56:54 INFO - 'TMOUT': '86400', 11:56:54 INFO - 'USER': 'cltbld', 11:56:54 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1472756187.437388-177872028', 11:56:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:56:54 INFO - '_': '/tools/buildbot/bin/python'} 11:56:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:56:54 INFO - Downloading/unpacking blobuploader==1.2.4 11:56:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:56:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:56:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:56:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:56:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:56:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:56:56 INFO - Downloading blobuploader-1.2.4.tar.gz 11:56:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:56:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:56:56 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 11:56:56 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 11:56:56 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 11:56:56 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 11:56:56 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 11:56:56 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 11:56:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:56:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:56:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:56:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:56:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:56:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:56:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:56:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:56:57 INFO - Downloading docopt-0.6.1.tar.gz 11:56:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:56:57 INFO - Installing collected packages: blobuploader, requests, docopt 11:56:57 INFO - Running setup.py install for blobuploader 11:56:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:56:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:56:57 INFO - Running setup.py install for requests 11:56:57 INFO - Running setup.py install for docopt 11:56:57 INFO - Successfully installed blobuploader requests docopt 11:56:57 INFO - Cleaning up... 11:56:58 INFO - Return code: 0 11:56:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:56:58 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')]} 11:56:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:56:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:56:58 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')]} 11:56:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:56:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:56:58 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 0x7f01d0c69f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d428a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e11230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e0b090>, '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 0x1e0e9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e0ee40>, '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': '934fc3100db09dcb9af4d4b6000001ab-1472756187.437388-177872028', '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 11:56:58 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 11:56:58 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 11:56:58 INFO - Using env: {'DISPLAY': ':0', 11:56:58 INFO - 'HOME': '/home/cltbld', 11:56:58 INFO - 'LANG': 'en_US.UTF-8', 11:56:58 INFO - 'LANGUAGE': 'en_US:en', 11:56:58 INFO - 'LOGNAME': 'cltbld', 11:56:58 INFO - 'MAIL': '/var/mail/cltbld', 11:56:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:56:58 INFO - 'MOZ_NO_REMOTE': '1', 11:56:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:56:58 INFO - 'NO_EM_RESTART': '1', 11:56:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:56:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:56:58 INFO - 'PWD': '/builds/slave/test', 11:56:58 INFO - 'SHELL': '/bin/bash', 11:56:58 INFO - 'SHLVL': '1', 11:56:58 INFO - 'TERM': 'linux', 11:56:58 INFO - 'TMOUT': '86400', 11:56:58 INFO - 'USER': 'cltbld', 11:56:58 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1472756187.437388-177872028', 11:56:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:56:58 INFO - '_': '/tools/buildbot/bin/python'} 11:56:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:56:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:56:58 INFO - Running setup.py (path:/tmp/pip-riOsfQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:56:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:56:58 INFO - Running setup.py (path:/tmp/pip-39_0nN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:56:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:56:58 INFO - Running setup.py (path:/tmp/pip-F2yFnV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:56:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:56:58 INFO - Running setup.py (path:/tmp/pip-mXg4Uj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:56:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:56:58 INFO - Running setup.py (path:/tmp/pip-2yjxlq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:56:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:56:58 INFO - Running setup.py (path:/tmp/pip-5WBM1u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:56:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:56:58 INFO - Running setup.py (path:/tmp/pip-RzZa7H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:56:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:56:58 INFO - Running setup.py (path:/tmp/pip-UpXRSn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:56:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:56:58 INFO - Running setup.py (path:/tmp/pip-72EYHm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:56:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:56:58 INFO - Running setup.py (path:/tmp/pip-25T9Jp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:56:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:56:58 INFO - Running setup.py (path:/tmp/pip-tVjE7u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:56:59 INFO - Running setup.py (path:/tmp/pip-hhLoH6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:56:59 INFO - Running setup.py (path:/tmp/pip-GAuIkI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:56:59 INFO - Running setup.py (path:/tmp/pip-5LhOOJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:56:59 INFO - Running setup.py (path:/tmp/pip-84N6dd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:56:59 INFO - Running setup.py (path:/tmp/pip-uS41fW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:56:59 INFO - Running setup.py (path:/tmp/pip-iYeNBf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:56:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:56:59 INFO - Running setup.py install for manifestparser 11:56:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:56:59 INFO - Running setup.py install for mozcrash 11:56:59 INFO - Running setup.py install for mozdebug 11:56:59 INFO - Running setup.py install for mozdevice 11:56:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:56:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:56:59 INFO - Running setup.py install for mozfile 11:56:59 INFO - Running setup.py install for mozhttpd 11:57:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:57:00 INFO - Running setup.py install for mozinfo 11:57:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:57:00 INFO - Running setup.py install for mozInstall 11:57:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:57:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:57:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:57:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:57:00 INFO - Running setup.py install for mozleak 11:57:00 INFO - Running setup.py install for mozlog 11:57:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:57:00 INFO - Running setup.py install for moznetwork 11:57:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:57:00 INFO - Running setup.py install for mozprocess 11:57:00 INFO - Running setup.py install for mozprofile 11:57:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:57:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:57:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:57:00 INFO - Running setup.py install for mozrunner 11:57:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:57:00 INFO - Running setup.py install for mozscreenshot 11:57:01 INFO - Running setup.py install for moztest 11:57:01 INFO - Running setup.py install for mozversion 11:57:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:57:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:57:01 INFO - Cleaning up... 11:57:01 INFO - Return code: 0 11:57:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:57: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')]} 11:57:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57: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')]} 11:57:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:01 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 0x7f01d0c69f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d428a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e11230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e0b090>, '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 0x1e0e9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e0ee40>, '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': '934fc3100db09dcb9af4d4b6000001ab-1472756187.437388-177872028', '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 11:57:01 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 11:57:01 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 11:57:01 INFO - Using env: {'DISPLAY': ':0', 11:57:01 INFO - 'HOME': '/home/cltbld', 11:57:01 INFO - 'LANG': 'en_US.UTF-8', 11:57:01 INFO - 'LANGUAGE': 'en_US:en', 11:57:01 INFO - 'LOGNAME': 'cltbld', 11:57:01 INFO - 'MAIL': '/var/mail/cltbld', 11:57:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:01 INFO - 'MOZ_NO_REMOTE': '1', 11:57:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:57:01 INFO - 'NO_EM_RESTART': '1', 11:57:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:57:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:01 INFO - 'PWD': '/builds/slave/test', 11:57:01 INFO - 'SHELL': '/bin/bash', 11:57:01 INFO - 'SHLVL': '1', 11:57:01 INFO - 'TERM': 'linux', 11:57:01 INFO - 'TMOUT': '86400', 11:57:01 INFO - 'USER': 'cltbld', 11:57:01 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1472756187.437388-177872028', 11:57:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:01 INFO - '_': '/tools/buildbot/bin/python'} 11:57:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:57:01 INFO - Running setup.py (path:/tmp/pip-9TyZ76-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:57:01 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)) 11:57:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:57:01 INFO - Running setup.py (path:/tmp/pip-Nru_Eq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:57:01 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)) 11:57:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:57:01 INFO - Running setup.py (path:/tmp/pip-qF3ayM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:57:01 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)) 11:57:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:57:01 INFO - Running setup.py (path:/tmp/pip-FFBMw1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:57:01 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)) 11:57:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:57:01 INFO - Running setup.py (path:/tmp/pip-siRX7N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:57:01 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)) 11:57:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:57:01 INFO - Running setup.py (path:/tmp/pip-YDyL2U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:57:01 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)) 11:57:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:57:01 INFO - Running setup.py (path:/tmp/pip-J7ver9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:57:02 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)) 11:57:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:57:02 INFO - Running setup.py (path:/tmp/pip-wJ07od-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:57:02 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)) 11:57:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:57:02 INFO - Running setup.py (path:/tmp/pip-XM1I02-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:57:02 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)) 11:57:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:57:02 INFO - Running setup.py (path:/tmp/pip-CxpOPE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:57:02 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)) 11:57:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:57:02 INFO - Running setup.py (path:/tmp/pip-pg1QWL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:57:02 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)) 11:57:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:57:02 INFO - Running setup.py (path:/tmp/pip-3Bn7Qi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:57:02 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)) 11:57:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:57:02 INFO - Running setup.py (path:/tmp/pip-Bcpqza-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:57:02 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)) 11:57:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:57:02 INFO - Running setup.py (path:/tmp/pip-tlMj9c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:57:02 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)) 11:57:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:57:02 INFO - Running setup.py (path:/tmp/pip-ipCnBX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:57:02 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)) 11:57:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:57:02 INFO - Running setup.py (path:/tmp/pip-Eaafbb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:57:02 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)) 11:57:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:57:02 INFO - Running setup.py (path:/tmp/pip-eD2qFO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:57:02 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)) 11:57:02 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)) 11:57:02 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)) 11:57:02 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)) 11:57:02 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)) 11:57:02 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)) 11:57:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:57:02 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 11:57:02 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 11:57:02 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 11:57:02 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 11:57:02 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 11:57:02 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 11:57:04 INFO - Downloading blessings-1.6.tar.gz 11:57:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:57:04 INFO - Installing collected packages: blessings 11:57:04 INFO - Running setup.py install for blessings 11:57:04 INFO - Successfully installed blessings 11:57:04 INFO - Cleaning up... 11:57:04 INFO - Return code: 0 11:57:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:57:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:57:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:57:05 INFO - Reading from file tmpfile_stdout 11:57:05 INFO - Current package versions: 11:57:05 INFO - argparse == 1.2.1 11:57:05 INFO - blessings == 1.6 11:57:05 INFO - blobuploader == 1.2.4 11:57:05 INFO - docopt == 0.6.1 11:57:05 INFO - functools32 == 3.2.3-2 11:57:05 INFO - jsonschema == 2.5.1 11:57:05 INFO - manifestparser == 1.1 11:57:05 INFO - mozInstall == 1.12 11:57:05 INFO - mozcrash == 0.17 11:57:05 INFO - mozdebug == 0.1 11:57:05 INFO - mozdevice == 0.48 11:57:05 INFO - mozfile == 1.2 11:57:05 INFO - mozhttpd == 0.7 11:57:05 INFO - mozinfo == 0.9 11:57:05 INFO - mozleak == 0.1 11:57:05 INFO - mozlog == 3.3 11:57:05 INFO - moznetwork == 0.27 11:57:05 INFO - mozprocess == 0.23 11:57:05 INFO - mozprofile == 0.28 11:57:05 INFO - mozrunner == 6.12 11:57:05 INFO - mozscreenshot == 0.1 11:57:05 INFO - mozsystemmonitor == 0.3 11:57:05 INFO - moztest == 0.7 11:57:05 INFO - mozversion == 1.4 11:57:05 INFO - psutil == 3.1.1 11:57:05 INFO - requests == 1.2.3 11:57:05 INFO - wsgiref == 0.1.2 11:57:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:57:05 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')]} 11:57:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:05 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')]} 11:57:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:05 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 0x7f01d0c69f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d428a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e11230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e0b090>, '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 0x1e0e9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e0ee40>, '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': '934fc3100db09dcb9af4d4b6000001ab-1472756187.437388-177872028', '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 11:57:05 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 11:57:05 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 11:57:05 INFO - Using env: {'DISPLAY': ':0', 11:57:05 INFO - 'HOME': '/home/cltbld', 11:57:05 INFO - 'LANG': 'en_US.UTF-8', 11:57:05 INFO - 'LANGUAGE': 'en_US:en', 11:57:05 INFO - 'LOGNAME': 'cltbld', 11:57:05 INFO - 'MAIL': '/var/mail/cltbld', 11:57:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:05 INFO - 'MOZ_NO_REMOTE': '1', 11:57:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:57:05 INFO - 'NO_EM_RESTART': '1', 11:57:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:57:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:05 INFO - 'PWD': '/builds/slave/test', 11:57:05 INFO - 'SHELL': '/bin/bash', 11:57:05 INFO - 'SHLVL': '1', 11:57:05 INFO - 'TERM': 'linux', 11:57:05 INFO - 'TMOUT': '86400', 11:57:05 INFO - 'USER': 'cltbld', 11:57:05 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1472756187.437388-177872028', 11:57:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:05 INFO - '_': '/tools/buildbot/bin/python'} 11:57:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:05 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)) 11:57:05 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)) 11:57:05 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)) 11:57:05 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)) 11:57:05 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)) 11:57:05 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)) 11:57:05 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)) 11:57:05 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)) 11:57:05 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)) 11:57:05 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)) 11:57:05 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)) 11:57:05 INFO - Cleaning up... 11:57:05 INFO - Return code: 0 11:57:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:57:05 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')]} 11:57:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:05 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')]} 11:57:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x7f01d0c69f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d428a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e11230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e0b090>, '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 0x1e0e9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e0ee40>, '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': '934fc3100db09dcb9af4d4b6000001ab-1472756187.437388-177872028', '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 11:57:05 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 11:57:05 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 11:57:05 INFO - Using env: {'DISPLAY': ':0', 11:57:05 INFO - 'HOME': '/home/cltbld', 11:57:05 INFO - 'LANG': 'en_US.UTF-8', 11:57:05 INFO - 'LANGUAGE': 'en_US:en', 11:57:05 INFO - 'LOGNAME': 'cltbld', 11:57:05 INFO - 'MAIL': '/var/mail/cltbld', 11:57:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:05 INFO - 'MOZ_NO_REMOTE': '1', 11:57:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:57:05 INFO - 'NO_EM_RESTART': '1', 11:57:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:57:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:05 INFO - 'PWD': '/builds/slave/test', 11:57:05 INFO - 'SHELL': '/bin/bash', 11:57:05 INFO - 'SHLVL': '1', 11:57:05 INFO - 'TERM': 'linux', 11:57:05 INFO - 'TMOUT': '86400', 11:57:05 INFO - 'USER': 'cltbld', 11:57:05 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1472756187.437388-177872028', 11:57:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:05 INFO - '_': '/tools/buildbot/bin/python'} 11:57:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:57:05 INFO - Cleaning up... 11:57:05 INFO - Return code: 0 11:57:05 INFO - Running post-action listener: _resource_record_post_action 11:57:05 INFO - Running post-action listener: _start_resource_monitoring 11:57:05 INFO - Starting resource monitoring. 11:57:05 INFO - [mozharness: 2016-09-01 18:57:05.530046Z] Finished create-virtualenv step (success) 11:57:05 INFO - [mozharness: 2016-09-01 18:57:05.530559Z] Running install step. 11:57:05 INFO - Running pre-action listener: _resource_record_pre_action 11:57:05 INFO - Running main action method: install 11:57:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:57:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:57:05 INFO - Reading from file tmpfile_stdout 11:57:05 INFO - Detecting whether we're running mozinstall >=1.0... 11:57:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:57:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:57:05 INFO - Reading from file tmpfile_stdout 11:57:05 INFO - Output received: 11:57:05 INFO - Usage: mozinstall [options] installer 11:57:05 INFO - Options: 11:57:05 INFO - -h, --help show this help message and exit 11:57:05 INFO - -d DEST, --destination=DEST 11:57:05 INFO - Directory to install application into. [default: 11:57:05 INFO - "/builds/slave/test"] 11:57:05 INFO - --app=APP Application being installed. [default: firefox] 11:57:05 INFO - mkdir: /builds/slave/test/build/application 11:57:05 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'] 11:57:05 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 11:57:21 INFO - Reading from file tmpfile_stdout 11:57:21 INFO - Output received: 11:57:21 INFO - /builds/slave/test/build/application/firefox/firefox 11:57:21 INFO - Running post-action listener: _resource_record_post_action 11:57:21 INFO - [mozharness: 2016-09-01 18:57:21.985796Z] Finished install step (success) 11:57:21 INFO - [mozharness: 2016-09-01 18:57:21.986079Z] Running run-tests step. 11:57:21 INFO - Running pre-action listener: _resource_record_pre_action 11:57:21 INFO - Running main action method: run_tests 11:57:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:57:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:57:21 INFO - Python 2.7.3 11:57:21 INFO - Return code: 0 11:57:21 INFO - grabbing minidump binary from tooltool 11:57:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:21 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 0x1e0b090>, '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 0x1e0e9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e0ee40>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:57:21 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 11:57:21 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 11:57:21 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 11:57:22 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:57:22 INFO - Return code: 0 11:57:22 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:57:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:57:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:57:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:57:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:57:22 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/J2vD5DrAT-2vs1ss5UrKCw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-057', '--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 11:57:22 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/J2vD5DrAT-2vs1ss5UrKCw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-057 --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 11:57:22 INFO - Using env: {'DISPLAY': ':0', 11:57:22 INFO - 'HOME': '/home/cltbld', 11:57:22 INFO - 'LANG': 'en_US.UTF-8', 11:57:22 INFO - 'LANGUAGE': 'en_US:en', 11:57:22 INFO - 'LOGNAME': 'cltbld', 11:57:22 INFO - 'MAIL': '/var/mail/cltbld', 11:57:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:57:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:57:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:22 INFO - 'MOZ_NO_REMOTE': '1', 11:57:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:57:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:57:22 INFO - 'NO_EM_RESTART': '1', 11:57:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:57:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:22 INFO - 'PWD': '/builds/slave/test', 11:57:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:57:22 INFO - 'SHELL': '/bin/bash', 11:57:22 INFO - 'SHLVL': '1', 11:57:22 INFO - 'TERM': 'linux', 11:57:22 INFO - 'TMOUT': '86400', 11:57:22 INFO - 'USER': 'cltbld', 11:57:22 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1472756187.437388-177872028', 11:57:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:22 INFO - '_': '/tools/buildbot/bin/python'} 11:57:22 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/J2vD5DrAT-2vs1ss5UrKCw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-057', '--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 11:57:22 INFO - mozversion application_buildid: 20160901103520 11:57:22 INFO - mozversion application_changeset: 3ba5426a03b495b6417fffb872d42874edb80855 11:57:22 INFO - mozversion application_display_name: Nightly 11:57:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:57:22 INFO - mozversion application_name: Firefox 11:57:22 INFO - mozversion application_remotingname: firefox 11:57:22 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:57:22 INFO - mozversion application_vendor: Mozilla 11:57:22 INFO - mozversion application_version: 51.0a1 11:57:22 INFO - mozversion platform_buildid: 20160901103520 11:57:22 INFO - mozversion platform_changeset: 3ba5426a03b495b6417fffb872d42874edb80855 11:57:22 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:57:22 INFO - mozversion platform_version: 51.0a1 11:57:22 INFO - using testdate: 1472756242 11:57:22 INFO - actual date: 1472756242 11:57:22 INFO - starting webserver on 'localhost:44190' 11:57:22 INFO - SUITE-START | Running 2 tests 11:57:22 INFO - TEST-START | dromaeo_css 11:57:22 INFO - operating with platform_type : linux_ 11:57:22 INFO - Initialising browser for dromaeo_css test... 11:57:22 INFO - TEST-INFO | started process 24619 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbtA4_D/profile http://localhost:44190/getInfo.html) 11:57:27 INFO - PROCESS | 24619 | __metrics Screen width/height:1600/1200 11:57:27 INFO - PROCESS | 24619 | colorDepth:24 11:57:27 INFO - PROCESS | 24619 | Browser inner width/height: 1024/697 11:57:27 INFO - PROCESS | 24619 | __metrics 11:57:27 INFO - PROCESS | 24619 | 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" 11:57:27 INFO - PROCESS | 24619 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:57:28 INFO - PROCESS | 24619 | console.error: PushService: 11:57:28 INFO - PROCESS | 24619 | stateChangeProcessEnqueue: Error transitioning state 11:57:28 INFO - PROCESS | 24619 | UnknownError 11:57:28 INFO - TEST-INFO | 24619: exit 0 11:57:28 INFO - Browser initialized. 11:57:28 INFO - Running cycle 1/1 for dromaeo_css test... 11:57:28 INFO - TEST-INFO | started process 24701 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbtA4_D/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:57:41 INFO - PROCESS | 24701 | RSS: Main: 180813824 11:57:41 INFO - PROCESS | 24701 | 11:58:32 INFO - PROCESS | 24701 | 1472756312305 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] 12:00:45 INFO - PROCESS | 24701 | Cycle 1(1): loaded http://localhost:44190/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44190/tests/dromaeo/cssquery-ext.html) 12:00:47 INFO - PROCESS | 24701 | RSS: Main: 308948992 12:00:47 INFO - PROCESS | 24701 | 12:03:49 INFO - PROCESS | 24701 | Cycle 1(1): loaded http://localhost:44190/tests/dromaeo/cssquery-ext.html (next: http://localhost:44190/tests/dromaeo/cssquery-jquery.html) 12:03:50 INFO - PROCESS | 24701 | RSS: Main: 275402752 12:03:50 INFO - PROCESS | 24701 | 12:06:52 INFO - PROCESS | 24701 | Cycle 1(1): loaded http://localhost:44190/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44190/tests/dromaeo/cssquery-mootools.html) 12:06:53 INFO - PROCESS | 24701 | RSS: Main: 256524288 12:06:53 INFO - PROCESS | 24701 | 12:09:55 INFO - PROCESS | 24701 | Cycle 1(1): loaded http://localhost:44190/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44190/tests/dromaeo/cssquery-prototype.html) 12:09:56 INFO - PROCESS | 24701 | RSS: Main: 228810752 12:09:56 INFO - PROCESS | 24701 | 12:12:59 INFO - PROCESS | 24701 | Cycle 1(1): loaded http://localhost:44190/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44190/tests/dromaeo/cssquery-yui.html) 12:13:00 INFO - PROCESS | 24701 | RSS: Main: 219127808 12:13:00 INFO - PROCESS | 24701 | 12:16:03 INFO - PROCESS | 24701 | Cycle 1(1): loaded http://localhost:44190/tests/dromaeo/cssquery-yui.html (next: http://localhost:44190/tests/dromaeo/cssquery-dojo.html) 12:16:03 INFO - PROCESS | 24701 | RSS: Main: 310046720 12:16:03 INFO - PROCESS | 24701 | 12:16:03 INFO - PROCESS | 24701 | __start_tp_report 12:16:03 INFO - PROCESS | 24701 | _x_x_mozilla_page_load 12:16:03 INFO - PROCESS | 24701 | _x_x_mozilla_page_load_details 12:16:03 INFO - PROCESS | 24701 | |i|pagename|runs| 12:16:03 INFO - PROCESS | 24701 | |0;dojo.html;2631;2639.6700412448445;2658.667666541682;2705.2019654201995;2714.3214196450876;4611.123886461578;4666.953330466695;4769.499202583726;4854.830080947172;4934.950650493494;4626.676132670715;4675.088357770236;4705.600023997956;4726.291056341551;4752.667313288071;4381.058072514416;4417.668674849383;4436.068425630621;4453.955460445402;4467.307567327053;4481.3726078349055;4514.255147900592;4515.277555591106;4519.389882365871;4543.432070991126;4430.202563538562;4604.562566556172;4608.976955115237;4611.515790841963;4613.331066995283;4362.781860906942;4431.8448854289945;4469.10617876426;4483.170613436492;4498.685092043558;4357.738535687838;4375.059362237134;4380.167768124046;4448.466184057901;4453.465584129893;4751.833685821014;4752.5485078917445;4764.595009424203;4827.758612069382;4854.830080947185;5171.663841850305;5199.220116982445;5201.089809283398;5204.453532379083;5213.400458947193;5016.021875734234;5146.176611742103;5198.350206224222;5223.451537588537;5226.346706661668;4860.684055536379;4862.270659401118;4862.635302352338;4866.075445665383;4917.483664215278;5290.603204759582;5350.6254562180275;5352.946470535323;5356.223347614574;5420.83737487876;4955.925661115086;4976.154053810861;5014;5021.548060674557;5118.078745825713;5102.234664800236;5113.795448182031;5118.411382690963;5119.6416250862085;5169.560587350117;5066.493350664904;5075.289459475676;5079;5081.060003899216;5133.8716532087;4967.5529202371235;5022.296878437021;5047.697138171721;5049.596032317406;5098.311727916684;4330;4333.58830911063;4384.7369157850635;4386.188555117314;4395.934060989088;3786.1481166737813;3796.0699628591174;3800.467934489174;3846.769193848378;3852.653261206505;4389.912201755947;4475.70907890986;4484.304932735431;4496.64026877849;4507.414036175276;5177.792888284426;5201.869953251124;5203.947960520422;5258.079836028756;5264.763085661154;4488.618467430239;4494.056248187851;4502;4505.707129036602;4557.453105627346;4478.57453541913;4549.909001819945;4603.8849028773875;4644.094401591725;4700.506446823103;4788.305695674038;4806.327114203944;4812.35033270518;4858.562729354375;4868.318435418973;5123.641345105807;5129.666571672829;5134.691918484903;5140.2803607495725;5190.792368305226;4648.349231107715;4804.855854324376;4808.519148085164;4849.854504364875;4877.707337559616;5094.312267843794;5110.386753589593;5113.53978141969;5150.381954165524;5182.093133701663;5171.379434467888;5181.1969144782615;5185.585153187661;5188.896222075462;5252.343457067866;4917.680350777188;4988.27669987857;5026;5027.145385284437;5046.747662616928;5035.5719763819025;5045.772940217773;5047.4952504749235;5099.69401835891;5113;4942.308076869169;4945.381827271591;4947.950520494893;4967.180415231446;4990.426100998432;4923.990581930624;4986.301917731448;5016.197408414636;5027.069992051483;5096.974515127401;4956.925646115239;4980.452150263541;4986.351774269467;5029.170186919117;5038.042771873333 12:16:03 INFO - PROCESS | 24701 | |1;ext.html;557.3172863242528;570.449516216851;580.6777238632558;593.9020061689821;594.3669991459095;27491.862540687292;30782.53826192609;30911.536326955058;30914;30923.69076309239;30965.845170774115;31482;42824;43987;44051.33922991158;7436.1076670799575;7541.811454713648;7547.811304717398;7548.245175482462;7553.962230188841;7580.9241907581045;7634.198409167041;7644.120926093487;7667.808304792369;7683.039620047494;7675.692972281102;7721.266479684421;7732.6520306586335;7735.729249476241;7738;7656.425768067417;7691.307782299592;7705;7706.075270967463;7707.421943354271;7636.08366995964;7685.116211635622;7690.884636730453;7699.576523291216;7705.15243323234;38645.80677096624;38923;38945.221095578214;38971.220575588326;38994.41508377377;2878.2228798224364;3083.845807709628;3175.2061984503875;3530.38218311797;3590.2819436112622;28379.57430638542;28493.430131397257;28841.85579072112;28851.27871803201;30091;3393.33829903169;3404.9829750851336;3422.4866270059442;3452.637473065317;3554.6445355464507;253762;255157;255957.99999999814;256199;257134;30459.431085337375;50034.49965500371;50663.746681266355;50702.49297506978;50718;30570.54144187839;32084.358312833614;32085.197870053435;32116.35767284641;32135.357292854478;13330.400131994083;13453.798193027113;13454.125481843648;13454.663633409244;13467.393967271497;11229.71925701864;11234.213605047567;11236.381998990135;11237.38194399316;11252.66242012741;7526.774196774106;7561;7604.581748003802;7642.847143057108;7657.655405506765;2962.4223276460884;2978.3596526746855;2984.65676447207;3016.351484430814;3052.9694703052687;811.7645882694071;844.615699856576;872.3718922375879;872.9345299102592;961.077365728894;25012.874935625205;26885;27180.32049198786;27219.727802722115;27389.589156162674;766.5247546521081;945.4091193004373;948.691675205561;963.0706368354571;996.6113215845826;14421.855781442262;14764.094362255184;14827.143278405185;15109.244537773067;15167.089974601558;371851;373388;374731;375031;375983;49364.25953610699;49864;49894.25158622624;49985;50031.74984125129;1047.2198212331862;1086.4676308608882;1135.7103938495632;1166.2244607336027;1200.8078707406773;27665;28676.28309292201;29089;29374;29628;41400.1719965605;42206.78896605497;42209;42213.366799498646;42216.155676886905;292244;295558;295614;295956;296037;22435.66346504771;22510.774892251524;22695;23421.76578234264;25050.37374065663;41085.79457102815;41492.37761433521;41576.16847662969;41631.7918410406;42196.367054493596;35000;35070.82464587763;35236;35297.1175720609;35669.75155869532;35501.82249088738;35579;35630.10924726799;35794.463083053255;35833.46249806203;34364.48453273153;34734.131646708025;35149.824250878584;35243.47134793032;35812.64187358093;28781.926325785807;30727.2318192047;30949.845250774502;31150.688493115686;31301.217469563442;24894;24934.875325623256;24960.750392495844;25056.24831255065;25264.368390790376 12:16:03 INFO - PROCESS | 24701 | |2;jquery.html;341.7966310045522;363.74174336221296;368.2303984672036;370.7367768884091;384.75568014310903;13019.934900325497;14350.856491435083;16536.669266614677;17460.912695436506;17521.474355769347;15785.763213551805;16631.750523742154;16720.665586688257;16912.915435422805;16917.91541042293;4961.826336078238;5035.848924532252;5092.516210959953;5109.489051094898;5139.177731562951;3627.3470775260444;3759.793211373373;5044.672096313746;5128.153854614003;5136.8715782105555;3970.9205815883665;5856.707164641773;5893.64638121712;6038.486728628071;6052.546059045567;5837.387074357174;6001.339852616209;6029.638221706689;6034.306054803666;6040.57715959883;5288.92066619001;5304.602154838364;5304.681719096867;5308.044551980642;5309.203619457074;19612.215511379527;20547.280845170495;21046.473838154016;21362.89318553413;21428.57142857134;1862.9813701862943;1920.2990908318502;1932.3526618582791;1933.4296382567177;2006.468285904222;12901.225926444444;13628.250446225453;13696.178229306273;14167;14295.499657511937;1675.0452242221938;1675.7821483207106;1676.362982066819;1708.7009773289624;1799.478151336124;69449;71330.64334678346;71700.64149679219;72935.63532182359;74383.25616743817;9348.626054957726;9477.573509191965;9726.221902247944;9782.853257201148;10033.698989030341;13538.323083845768;13571.38928748189;13579.049466537444;13591.932040339932;13601;7373.557586544717;7561.017067781153;7658.2724641159;7721.305082542598;7763.417743669247;7282.235365286675;7311.122665280201;7327.37717294036;7518.812029699301;7531.510451820724;6046.520926420147;6150.907736383958;6237.251529816451;6250.655899093963;6444.903326450107;1675.346614820221;1682.9915850420668;1690.619610587608;1743.2765402358134;1743.5553933746814;756.1908757629284;868.0885070675765;872.5419154943705;891.643342662927;900.3607438718452;17105.23026463812;17556.38552650651;17973.640527189382;18189.81810181881;18488.537786555175;809.4536188073145;864.7535452396037;867.4578388507183;870.621279743301;933.9766505837273;9575.952120239353;9818.607255709834;10384.480775961172;10417.915218346832;10538;369709;384655;384683;384800;384898;68261;69013;70205.64897175481;71117.64441177865;72837.63581182166;888.6045709659139;918.6830543462495;938.3759799733238;943.2265542255421;949.3591825517748;15617.765733514234;15883.126428046568;16144.838551614333;16330;16497.340106395608;38256;38867;39017.609823901395;39259.214815704094;39959.60040399559;51294.48705512897;55740.72129639326;56683;56687.433125669864;57368.71315643395;4778.737169455713;7289.16174639902;7424.331810137113;7533.849323013618;8072.394570407126;8724.81887478764;11098;11254.155938304497;11307.378094204569;12375.876241237833;9711.902880971382;10030.89969100329;10139.493025348851;10167.79664406693;10687.358758474516;10231.181505479693;10424.426656533964;10476.528556214836;10530.631427899987;10655.520501577292;10426.322289051188;10553.155747540024;10753;10915.235933484897;10949.671509854717;7795.181505942021;8067.636956336862;8069.515829050276;8077.717279895175;8094.069182044141;9705.611775529182;9959.900400995897;10125.291229613857;10267.7433064174;10819 12:16:03 INFO - PROCESS | 24701 | |3;mootools.html;1246.2771592476365;1339.8191244182035;1391.5199256256592;1415.6885485193254;1674.062524986008;7519.436042296829;7911.881321780171;7976.2821346078845;8080.7878818177305;8136.9186308136905;7979.56112413817;8074.71738489153;8094.91905080949;8096.797580060502;8097.67609295629;2202.361315218585;2227.164813195052;2230.620794464945;2231.866088034715;2234.731832180141;2158.946026349338;2216.6785816056663;2234.8659080455222;2236.206146817881;2244.147224054857;2167.1656412281304;2180.291405293278;2188.4310079379316;2192.736871575405;2237.284069097889;2122.076896549998;2135.9679604805942;2148.0977989244634;2148.334016454904;2148.677698345245;2123.331150687544;2169.53355028668;2170.522485053286;2171.9891400543056;2175.7715439878903;7843.411744119214;8061.395395345373;8123.106458289583;8186.017677878693;8189.918100818975;4477.261251893434;4646.047560250141;4650.4186976627925;4662.347271382009;4662.7202367858035;2797.510435673749;2960.437516871809;3010;3010.262485690998;3014.6382434108045;870.9564521773885;882.6778225947547;892.8794612727264;906.6373450619739;924.5746956400064;10911.399873006982;10954.616588419367;10990.670279891536;10992.076386632307;10993.175511836644;3804.0518598086705;4000.979995100006;4120.2583534964;4130.215259100762;4200.978995105005;8619.956900215459;8690.348223883235;8697.08680588529;8699.739007829648;8749.256313213447;4435.112977404532;4445.044315472189;4448.911021779546;4458.9554104459185;4459.063596644741;3512.3677738006895;4091.57038510958;4093.2222877653153;4103.322951712994;4312.654987600984;3652.1052342176336;3767.2842159989514;3869.342211823997;3898.064464528493;3908.4918960534947;1208.3897631696113;1220.7314390834001;1223.61456141317;1232.303748382179;1238.59745582686;712.6614857942436;818.8976377952756;826.2316046077086;831.8960129983752;836.9916300837035;5230.398504172069;5364.7317634118135;5380.192971054373;5401.702906340189;5575.358833734092;894.4543828264754;895.0353973029269;955.4553904274496;963.8361478548663;981.4160574458328;3281.392942305681;3316.1046517440154;3440.3807314683595;3501.649835016478;3736.3461394255896;9456.14894659484;9791.461469619097;9817.558209880572;10127.898721012843;10146.949265253626;9885.308028438085;9893.45585992763;9897.109260166619;10017.348872323275;10020.148287395654;7283.162436319785;7493.512921660074;7550.962245188739;7599.620018998918;7600.087989441303;5287.629865909349;5323.441038691037;5431.755570999394;5432.2123292123215;5664.207011018543;9017.458952462874;9190.264778817545;9263.953680231556;9264.722058337991;9318.114779095975;9252.51959686447;9434.528273586157;9465.574049167943;9519.476428796206;9532.571034303335;6110.7861224856915;6554.672266386757;6633.502487313374;6865.27914568983;6880.139982502187;7605.125410577854;7611.809704757426;7675.155732869268;7675.462717609714;7678.731244406419;6763.695633696397;6824.522283440111;6831.897521537082;6884.242733299233;7020.297970203143;6733.259341472336;6832.931670683429;6848.315168483112;6924.065251191025;7137.857242855218;6115.205023347115;6611.07444957716;6704.128463299739;6713.563618364986;6715.630640314829;4503;4519.480259770037;4547.522510136388;4563.680542362135;4611.838585649486;4720.315554244684;4754.31062495943;4763.857084287477;4797.928031079607;4975.303457515878 12:16:03 INFO - PROCESS | 24701 | |4;prototype.html;292.9501984662609;304.65726058184543;306.4009860722288;307.1706392739604;312.93897689950455;4597.793059331514;4765.213739732944;4787.8084876604935;4788.736619485927;4805.8798530036765;4605;4699.835505757299;4708.811647534095;4719.4100737407825;4730;3764.5858955514873;3772.9434058489146;3774.3960966245404;3778.622137786227;3792.962070379302;3573.94639080414;3632.364336241147;3792.4880141181;3803.790791506466;3827.3302172119907;3770.622937706235;3779.9811000944956;3780.0360907968566;3782.7352085354037;3787.3182827091114;3729.8366947170575;3757.839242435583;3762.473253744478;3783.2622638585626;3788.867389641377;3340.8641062038782;3350.932981340384;3353.6143343515564;3365.1923538350866;3368.1411240133853;4488.730676159408;4595.356650068993;4610.884727881797;4630.930536041962;4669.95330046702;4205.789710514462;4239.173361194568;4253.064325848309;4257.339637541361;4260.723053001545;4914.311996320518;4929.313524129322;4929.531694489017;4949.975250123726;4974.030064137494;3311.867525298985;3479.599846017715;3576.3204991051616;3580.516630254934;3590.9281814363567;5155.793768249266;5166.018456493254;5189.948100518985;5194.844154675328;5230.555402790767;3641.672249497558;3648.759421796602;3655.7440979131206;3664.9816750916607;3669.3945498992366;3700.296943580711;3718.5909549949483;3723.311187430334;3729.4778730977687;3733.216024634857;4013.8996525087105;4147.3778933159665;4184.184084103571;4202.453681021448;4257.893552661209;4974.602031837444;4980.203167493184;4984.352034235599;4995.325631039836;4997.925031124536;1200.789861774186;1234.1854376111903;1271.3960868587346;1279.1429742072833;1292.2504947130642;856.9400141990001;865.7575878753959;880.9383343166045;885.2563846369081;885.8759773631697;4424.933625995613;4429.955700443019;4430.272193844403;4436.824241575985;4441.422615060086;5060.367454068241;5062.797683007903;5067.641828027893;5069.391672999263;5098.617603679739;4391.14372697321;4403.031333106712;4407.955920440819;4414.470263568328;4418.939454530953;2287.645414960684;2365.7870791628834;2396.904123835027;2398.328468028955;2401.1115887121878;4219.38818871262;4233.433629557084;4273.230818452646;4284;4304.526502084768;4522.773861306921;4558.635309175258;4580.610648094949;4596.47140578831;4607.562281583244;4066.1461093170765;4232.153569286155;4269.914601707948;4287;4346.847860324873;4481.282994720829;4490.955090449053;4528.41130653013;4534.682572220045;4542.88642783933;4423.60187583119;4439.800208990668;4449;4452.688311818141;4486.820527178877;4236.301010333261;4244.575542445731;4253.61717445419;4501.662375321798;4849.4239372203465;3664.6885014774043;3664.8900532984053;3667.4132138857653;3669.8990302908487;3693.833777479966;4944.208926571731;5141.203113517411;5159.9102363542825;5184.7926082957765;5187.121147762877;4878.4631991363585;4992.2761199626575;4999.450060493416;5004.924926126184;5013.147764880052;4962.925556116734;4978.6763860348965;4979.875503112451;4992.975035124801;5004.2493625956395;4809.148477836128;4915.631327650369;4943.876403089952;4979.800807967641;4986.102501549756;5005.324281221989;5006.449290578106;5010.423801262901;5034.974825125997;5041.495850414929;4866.6153584742515;4872.6832755870755;4880.487548807466;4903.632227583017;4906.031850730256 12:16:03 INFO - PROCESS | 24701 | |5;yui.html;624.965626890521;648.6173157836876;662.5395350231585;663.9302873198315;732.9409003989234;13381.866181338184;14733.747631451326;14747.926260368697;14750.33623486943;14758.557243282692;12396.13227074105;12634.62096137115;12634.873651263484;12640.557580484685;12660.11379203456;589.4105894105894;712.8752468318023;718.7915504503701;737.6311844077961;743.4424181863602;744.1368013104773;746.4625469661835;746.5520687587458;747.1930315259507;747.4618274842132;791.5448617045193;794.4399198564997;794.7377365469381;794.9682012719484;795.1094773853292;616.1774031667707;757.8901059346364;775.329340120797;777.2616014785976;784.396015068397;569.3680015183144;571.2488078177183;571.8198767388301;571.8541771848194;571.8656115812822;12021.038316934711;12056.276623402624;12059.457324420335;12069.094817888606;12076.577319793852;12037;12486.562970295996;12762.17045892014;13016.739665206644;13392.732145357038;11402.486888089972;11718.765624687543;12139.514419423302;12151.878481215163;12868.935655321664;4075.714699971003;4129.318662420727;4208.137331846964;4266.743995360257;4354.259775838115;55167.72416137894;75758.62120689417;75772;75981;76288;11068.833967490495;11083.556657733601;11312.03847672957;11656.825147622792;11665.825012624817;20350;20537.48656283605;20681;20684.896575517327;20685.17259309641;5873.735681894325;5974.850628734316;6179.598326108879;6834.897476537856;6940.9652951734915;3426.638293228568;3545.7872527648424;3623.710103191791;3640.690541303966;3671.6695497405362;2848.2594525423124;2979.6275465566805;2987.6116104906223;3001;3002.1143762590086;501.8068043803162;505.41372008470734;511.1336284996969;515.8839261166282;517.0950836036935;461.87298492914044;486.45031114840714;519.8076711616726;520.9426963034064;526.8972550352721;6829.487788415889;7156.340743028159;7276.688017746968;7356.337929586363;7737.032870891138;489.657239932049;538.7225578826907;568.6445971267957;577.83531693468;606.915031895535;3514.6661067198575;3643.398839191557;3718.3678774608384;3735.4957080793747;3875.9806200968815;56586.15120773192;56882.14676779851;84846;94713;105688.47155764172;90860;91211.54394227987;91230.54384728166;91547;93218.53390733003;63642;64200.357996420375;64357.35642643701;82587;85801.13286971953;7551.546907185587;7826.1651790621045;7833.804154896173;7939.563324017235;8419.789505262172;41980.79009604932;42073.579264206965;45265.09469810651;45412.594055446636;45653;36010.27979440449;36464.635353646125;40108.79945600253;41396.586034139276;41402.378964316165;9979.750506237111;10152.74618134523;10501;10673.839892401465;10910.78178436411;12619.87380126187;12783.872161278268;12792.424340904496;12967.935160324138;13458.327083645974;11611.245269057485;11836.46735896904;11933.164678472423;12604.621861344174;12633.178843375152;10451.68644940653;10615.787684246117;11158.944205278922;11188.83216751733;11241.269317494336;11287.153463490107;11315.377654229096;12401.62795116148;12431;12431.75136497247;9090.636374544943;9426.811463770824;9427.764305892408;9865.506724663595;9929.106380425796;8306.335493160412;9538.904610953801;9562.235021198427;9664.468454234804;9727.65953191635 12:16:03 INFO - PROCESS | 24701 | __end_tp_report 12:16:03 INFO - PROCESS | 24701 | __start_cc_report 12:16:03 INFO - PROCESS | 24701 | _x_x_mozilla_cycle_collect,1159 12:16:03 INFO - PROCESS | 24701 | __end_cc_report 12:16:03 INFO - PROCESS | 24701 | __startTimestamp1472757363647__endTimestamp 12:16:03 INFO - PROCESS | 24701 | 12:16:03 INFO - PROCESS | 24701 | ------- Summary: start ------- 12:16:03 INFO - PROCESS | 24701 | Number of tests: 6 12:16:03 INFO - PROCESS | 24701 | 12:16:03 INFO - PROCESS | 24701 | [#0] dojo.html Cycles:165 Average:4765.49 Median:4877.71 stddev:499.96 (10.2%) stddev-sans-first:472.62 12:16:03 INFO - PROCESS | 24701 | Values: 2631.0 2639.7 2658.7 2705.2 2714.3 4611.1 4667.0 4769.5 4854.8 4935.0 4626.7 4675.1 4705.6 4726.3 4752.7 4381.1 4417.7 4436.1 4454.0 4467.3 4481.4 4514.3 4515.3 4519.4 4543.4 4430.2 4604.6 4609.0 4611.5 4613.3 4362.8 4431.8 4469.1 4483.2 4498.7 4357.7 4375.1 4380.2 4448.5 4453.5 4751.8 4752.5 4764.6 4827.8 4854.8 5171.7 5199.2 5201.1 5204.5 5213.4 5016.0 5146.2 5198.4 5223.5 5226.3 4860.7 4862.3 4862.6 4866.1 4917.5 5290.6 5350.6 5352.9 5356.2 5420.8 4955.9 4976.2 5014.0 5021.5 5118.1 5102.2 5113.8 5118.4 5119.6 5169.6 5066.5 5075.3 5079.0 5081.1 5133.9 4967.6 5022.3 5047.7 5049.6 5098.3 4330.0 4333.6 4384.7 4386.2 4395.9 3786.1 3796.1 3800.5 3846.8 3852.7 4389.9 4475.7 4484.3 4496.6 4507.4 5177.8 5201.9 5203.9 5258.1 5264.8 4488.6 4494.1 4502.0 4505.7 4557.5 4478.6 4549.9 4603.9 4644.1 4700.5 4788.3 4806.3 4812.4 4858.6 4868.3 5123.6 5129.7 5134.7 5140.3 5190.8 4648.3 4804.9 4808.5 4849.9 4877.7 5094.3 5110.4 5113.5 5150.4 5182.1 5171.4 5181.2 5185.6 5188.9 5252.3 4917.7 4988.3 5026.0 5027.1 5046.7 5035.6 5045.8 5047.5 5099.7 5113.0 4942.3 4945.4 4948.0 4967.2 4990.4 4924.0 4986.3 5016.2 5027.1 5097.0 4956.9 4980.5 4986.4 5029.2 5038.0 12:16:03 INFO - PROCESS | 24701 | 12:16:03 INFO - PROCESS | 24701 | [#1] ext.html Cycles:180 Average:44789.65 Median:35239.74 stddev:82313.42 (233.6%) stddev-sans-first:82477.34 12:16:03 INFO - PROCESS | 24701 | Values: 557.3 570.4 580.7 593.9 594.4 27491.9 30782.5 30911.5 30914.0 30923.7 30965.8 31482.0 42824.0 43987.0 44051.3 7436.1 7541.8 7547.8 7548.2 7554.0 7580.9 7634.2 7644.1 7667.8 7683.0 7675.7 7721.3 7732.7 7735.7 7738.0 7656.4 7691.3 7705.0 7706.1 7707.4 7636.1 7685.1 7690.9 7699.6 7705.2 38645.8 38923.0 38945.2 38971.2 38994.4 2878.2 3083.8 3175.2 3530.4 3590.3 28379.6 28493.4 28841.9 28851.3 30091.0 3393.3 3405.0 3422.5 3452.6 3554.6 253762.0 255157.0 255958.0 256199.0 257134.0 30459.4 50034.5 50663.7 50702.5 50718.0 30570.5 32084.4 32085.2 32116.4 32135.4 13330.4 13453.8 13454.1 13454.7 13467.4 11229.7 11234.2 11236.4 11237.4 11252.7 7526.8 7561.0 7604.6 7642.8 7657.7 2962.4 2978.4 2984.7 3016.4 3053.0 811.8 844.6 872.4 872.9 961.1 25012.9 26885.0 27180.3 27219.7 27389.6 766.5 945.4 948.7 963.1 996.6 14421.9 14764.1 14827.1 15109.2 15167.1 371851.0 373388.0 374731.0 375031.0 375983.0 49364.3 49864.0 49894.3 49985.0 50031.7 1047.2 1086.5 1135.7 1166.2 1200.8 27665.0 28676.3 29089.0 29374.0 29628.0 41400.2 42206.8 42209.0 42213.4 42216.2 292244.0 295558.0 295614.0 295956.0 296037.0 22435.7 22510.8 22695.0 23421.8 25050.4 41085.8 41492.4 41576.2 41631.8 42196.4 35000.0 35070.8 35236.0 35297.1 35669.8 35501.8 35579.0 35630.1 35794.5 35833.5 34364.5 34734.1 35149.8 35243.5 35812.6 28781.9 30727.2 30949.8 31150.7 31301.2 24894.0 24934.9 24960.8 25056.2 25264.4 12:16:03 INFO - PROCESS | 24701 | 12:16:03 INFO - PROCESS | 24701 | [#2] jquery.html Cycles:180 Average:24345.77 Median:21615.07 stddev:63036.58 (291.6%) stddev-sans-first:63187.65 12:16:03 INFO - PROCESS | 24701 | Values: 341.8 363.7 368.2 370.7 384.8 13019.9 14350.9 16536.7 17460.9 17521.5 15785.8 16631.8 16720.7 16912.9 16917.9 4961.8 5035.8 5092.5 5109.5 5139.2 3627.3 3759.8 5044.7 5128.2 5136.9 3970.9 5856.7 5893.6 6038.5 6052.5 5837.4 6001.3 6029.6 6034.3 6040.6 5288.9 5304.6 5304.7 5308.0 5309.2 19612.2 20547.3 21046.5 21362.9 21428.6 1863.0 1920.3 1932.4 1933.4 2006.5 12901.2 13628.3 13696.2 14167.0 14295.5 1675.0 1675.8 1676.4 1708.7 1799.5 69449.0 71330.6 71700.6 72935.6 74383.3 9348.6 9477.6 9726.2 9782.9 10033.7 13538.3 13571.4 13579.0 13591.9 13601.0 7373.6 7561.0 7658.3 7721.3 7763.4 7282.2 7311.1 7327.4 7518.8 7531.5 6046.5 6150.9 6237.3 6250.7 6444.9 1675.3 1683.0 1690.6 1743.3 1743.6 756.2 868.1 872.5 891.6 900.4 17105.2 17556.4 17973.6 18189.8 18488.5 809.5 864.8 867.5 870.6 934.0 9576.0 9818.6 10384.5 10417.9 10538.0 369709.0 384655.0 384683.0 384800.0 384898.0 68261.0 69013.0 70205.6 71117.6 72837.6 888.6 918.7 938.4 943.2 949.4 15617.8 15883.1 16144.8 16330.0 16497.3 38256.0 38867.0 39017.6 39259.2 39959.6 51294.5 55740.7 56683.0 56687.4 57368.7 4778.7 7289.2 7424.3 7533.8 8072.4 8724.8 11098.0 11254.2 11307.4 12375.9 9711.9 10030.9 10139.5 10167.8 10687.4 10231.2 10424.4 10476.5 10530.6 10655.5 10426.3 10553.2 10753.0 10915.2 10949.7 7795.2 8067.6 8069.5 8077.7 8094.1 9705.6 9959.9 10125.3 10267.7 10819.0 12:16:03 INFO - PROCESS | 24701 | 12:16:03 INFO - PROCESS | 24701 | [#3] mootools.html Cycles:180 Average:5223.75 Median:5305.54 stddev:2965.74 (55.9%) stddev-sans-first:2958.99 12:16:03 INFO - PROCESS | 24701 | Values: 1246.3 1339.8 1391.5 1415.7 1674.1 7519.4 7911.9 7976.3 8080.8 8136.9 7979.6 8074.7 8094.9 8096.8 8097.7 2202.4 2227.2 2230.6 2231.9 2234.7 2158.9 2216.7 2234.9 2236.2 2244.1 2167.2 2180.3 2188.4 2192.7 2237.3 2122.1 2136.0 2148.1 2148.3 2148.7 2123.3 2169.5 2170.5 2172.0 2175.8 7843.4 8061.4 8123.1 8186.0 8189.9 4477.3 4646.0 4650.4 4662.3 4662.7 2797.5 2960.4 3010.0 3010.3 3014.6 871.0 882.7 892.9 906.6 924.6 10911.4 10954.6 10990.7 10992.1 10993.2 3804.1 4001.0 4120.3 4130.2 4201.0 8620.0 8690.3 8697.1 8699.7 8749.3 4435.1 4445.0 4448.9 4459.0 4459.1 3512.4 4091.6 4093.2 4103.3 4312.7 3652.1 3767.3 3869.3 3898.1 3908.5 1208.4 1220.7 1223.6 1232.3 1238.6 712.7 818.9 826.2 831.9 837.0 5230.4 5364.7 5380.2 5401.7 5575.4 894.5 895.0 955.5 963.8 981.4 3281.4 3316.1 3440.4 3501.6 3736.3 9456.1 9791.5 9817.6 10127.9 10146.9 9885.3 9893.5 9897.1 10017.3 10020.1 7283.2 7493.5 7551.0 7599.6 7600.1 5287.6 5323.4 5431.8 5432.2 5664.2 9017.5 9190.3 9264.0 9264.7 9318.1 9252.5 9434.5 9465.6 9519.5 9532.6 6110.8 6554.7 6633.5 6865.3 6880.1 7605.1 7611.8 7675.2 7675.5 7678.7 6763.7 6824.5 6831.9 6884.2 7020.3 6733.3 6832.9 6848.3 6924.1 7137.9 6115.2 6611.1 6704.1 6713.6 6715.6 4503.0 4519.5 4547.5 4563.7 4611.8 4720.3 4754.3 4763.9 4797.9 4975.3 12:16:03 INFO - PROCESS | 24701 | 12:16:03 INFO - PROCESS | 24701 | [#4] prototype.html Cycles:180 Average:4044.79 Median:4430.11 stddev:1158.85 (26.2%) stddev-sans-first:1127.37 12:16:03 INFO - PROCESS | 24701 | Values: 293.0 304.7 306.4 307.2 312.9 4597.8 4765.2 4787.8 4788.7 4805.9 4605.0 4699.8 4708.8 4719.4 4730.0 3764.6 3772.9 3774.4 3778.6 3793.0 3573.9 3632.4 3792.5 3803.8 3827.3 3770.6 3780.0 3780.0 3782.7 3787.3 3729.8 3757.8 3762.5 3783.3 3788.9 3340.9 3350.9 3353.6 3365.2 3368.1 4488.7 4595.4 4610.9 4630.9 4670.0 4205.8 4239.2 4253.1 4257.3 4260.7 4914.3 4929.3 4929.5 4950.0 4974.0 3311.9 3479.6 3576.3 3580.5 3590.9 5155.8 5166.0 5189.9 5194.8 5230.6 3641.7 3648.8 3655.7 3665.0 3669.4 3700.3 3718.6 3723.3 3729.5 3733.2 4013.9 4147.4 4184.2 4202.5 4257.9 4974.6 4980.2 4984.4 4995.3 4997.9 1200.8 1234.2 1271.4 1279.1 1292.3 856.9 865.8 880.9 885.3 885.9 4424.9 4430.0 4430.3 4436.8 4441.4 5060.4 5062.8 5067.6 5069.4 5098.6 4391.1 4403.0 4408.0 4414.5 4418.9 2287.6 2365.8 2396.9 2398.3 2401.1 4219.4 4233.4 4273.2 4284.0 4304.5 4522.8 4558.6 4580.6 4596.5 4607.6 4066.1 4232.2 4269.9 4287.0 4346.8 4481.3 4491.0 4528.4 4534.7 4542.9 4423.6 4439.8 4449.0 4452.7 4486.8 4236.3 4244.6 4253.6 4501.7 4849.4 3664.7 3664.9 3667.4 3669.9 3693.8 4944.2 5141.2 5159.9 5184.8 5187.1 4878.5 4992.3 4999.5 5004.9 5013.1 4962.9 4978.7 4979.9 4993.0 5004.2 4809.1 4915.6 4943.9 4979.8 4986.1 5005.3 5006.4 5010.4 5035.0 5041.5 4866.6 4872.7 4880.5 4903.6 4906.0 12:16:03 INFO - PROCESS | 24701 | 12:16:03 INFO - PROCESS | 24701 | [#5] yui.html Cycles:180 Average:17038.26 Median:474.16 stddev:24307.74 (5126.5%) stddev-sans-first:24344.69 12:16:03 INFO - PROCESS | 24701 | Values: 625.0 648.6 662.5 663.9 732.9 13381.9 14733.7 14747.9 14750.3 14758.6 12396.1 12634.6 12634.9 12640.6 12660.1 589.4 712.9 718.8 737.6 743.4 744.1 746.5 746.6 747.2 747.5 791.5 794.4 794.7 795.0 795.1 616.2 757.9 775.3 777.3 784.4 569.4 571.2 571.8 571.9 571.9 12021.0 12056.3 12059.5 12069.1 12076.6 12037.0 12486.6 12762.2 13016.7 13392.7 11402.5 11718.8 12139.5 12151.9 12868.9 4075.7 4129.3 4208.1 4266.7 4354.3 55167.7 75758.6 75772.0 75981.0 76288.0 11068.8 11083.6 11312.0 11656.8 11665.8 20350.0 20537.5 20681.0 20684.9 20685.2 5873.7 5974.9 6179.6 6834.9 6941.0 3426.6 3545.8 3623.7 3640.7 3671.7 2848.3 2979.6 2987.6 3001.0 3002.1 501.8 505.4 511.1 515.9 517.1 461.9 486.5 519.8 520.9 526.9 6829.5 7156.3 7276.7 7356.3 7737.0 489.7 538.7 568.6 577.8 606.9 3514.7 3643.4 3718.4 3735.5 3876.0 56586.2 56882.1 84846.0 94713.0 105688.5 90860.0 91211.5 91230.5 91547.0 93218.5 63642.0 64200.4 64357.4 82587.0 85801.1 7551.5 7826.2 7833.8 7939.6 8419.8 41980.8 42073.6 45265.1 45412.6 45653.0 36010.3 36464.6 40108.8 41396.6 41402.4 9979.8 10152.7 10501.0 10673.8 10910.8 12619.9 12783.9 12792.4 12967.9 13458.3 11611.2 11836.5 11933.2 12604.6 12633.2 10451.7 10615.8 11158.9 11188.8 11241.3 11287.2 11315.4 12401.6 12431.0 12431.8 9090.6 9426.8 9427.8 9865.5 9929.1 8306.3 9538.9 9562.2 9664.5 9727.7 12:16:03 INFO - PROCESS | 24701 | -------- Summary: end -------- 12:16:03 INFO - PROCESS | 24701 | 12:16:04 INFO - TEST-INFO | 24701: exit 0 12:16:04 INFO - TEST-OK | dromaeo_css | took 1122444ms 12:16:04 INFO - TEST-START | kraken 12:16:04 INFO - operating with platform_type : linux_ 12:16:04 INFO - Initialising browser for kraken test... 12:16:04 INFO - TEST-INFO | started process 25016 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpktnsjb/profile http://localhost:44190/getInfo.html) 12:16:09 INFO - PROCESS | 25016 | __metrics Screen width/height:1600/1200 12:16:09 INFO - PROCESS | 25016 | colorDepth:24 12:16:09 INFO - PROCESS | 25016 | Browser inner width/height: 1024/697 12:16:09 INFO - PROCESS | 25016 | __metrics 12:16:10 INFO - PROCESS | 25016 | 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" 12:16:10 INFO - PROCESS | 25016 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:16:10 INFO - PROCESS | 25016 | console.error: PushService: 12:16:10 INFO - PROCESS | 25016 | stateChangeProcessEnqueue: Error transitioning state 12:16:10 INFO - PROCESS | 25016 | UnknownError 12:16:11 INFO - TEST-INFO | 25016: exit 0 12:16:11 INFO - Browser initialized. 12:16:11 INFO - Running cycle 1/1 for kraken test... 12:16:11 INFO - TEST-INFO | started process 25101 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpktnsjb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:16:23 INFO - PROCESS | 25101 | RSS: Main: 180396032 12:16:23 INFO - PROCESS | 25101 | 12:16:26 INFO - PROCESS | 25101 | Cycle 1(1): loaded http://localhost:44190/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44190/tests/kraken/driver.html?testName=audio-beat-detection) 12:16:27 INFO - PROCESS | 25101 | RSS: Main: 234909696 12:16:27 INFO - PROCESS | 25101 | 12:16:30 INFO - PROCESS | 25101 | Cycle 1(1): loaded http://localhost:44190/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44190/tests/kraken/driver.html?testName=audio-dft) 12:16:30 INFO - PROCESS | 25101 | RSS: Main: 223522816 12:16:30 INFO - PROCESS | 25101 | 12:16:34 INFO - PROCESS | 25101 | Cycle 1(1): loaded http://localhost:44190/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44190/tests/kraken/driver.html?testName=audio-fft) 12:16:34 INFO - PROCESS | 25101 | RSS: Main: 302329856 12:16:34 INFO - PROCESS | 25101 | 12:16:36 INFO - PROCESS | 25101 | Cycle 1(1): loaded http://localhost:44190/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44190/tests/kraken/driver.html?testName=audio-oscillator) 12:16:37 INFO - PROCESS | 25101 | RSS: Main: 212287488 12:16:37 INFO - PROCESS | 25101 | 12:16:39 INFO - PROCESS | 25101 | Cycle 1(1): loaded http://localhost:44190/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44190/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:16:40 INFO - PROCESS | 25101 | RSS: Main: 214818816 12:16:40 INFO - PROCESS | 25101 | 12:16:43 INFO - PROCESS | 25101 | Cycle 1(1): loaded http://localhost:44190/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44190/tests/kraken/driver.html?testName=imaging-darkroom) 12:16:44 INFO - PROCESS | 25101 | RSS: Main: 253579264 12:16:44 INFO - PROCESS | 25101 | 12:16:47 INFO - PROCESS | 25101 | Cycle 1(1): loaded http://localhost:44190/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44190/tests/kraken/driver.html?testName=imaging-desaturate) 12:16:48 INFO - PROCESS | 25101 | RSS: Main: 247435264 12:16:48 INFO - PROCESS | 25101 | 12:16:51 INFO - PROCESS | 25101 | Cycle 1(1): loaded http://localhost:44190/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44190/tests/kraken/driver.html?testName=json-parse-financial) 12:16:51 INFO - PROCESS | 25101 | RSS: Main: 250728448 12:16:51 INFO - PROCESS | 25101 | 12:16:53 INFO - PROCESS | 25101 | Cycle 1(1): loaded http://localhost:44190/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44190/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:16:54 INFO - PROCESS | 25101 | RSS: Main: 214245376 12:16:54 INFO - PROCESS | 25101 | 12:16:56 INFO - PROCESS | 25101 | Cycle 1(1): loaded http://localhost:44190/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44190/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:16:56 INFO - PROCESS | 25101 | RSS: Main: 221757440 12:16:56 INFO - PROCESS | 25101 | 12:16:58 INFO - PROCESS | 25101 | Cycle 1(1): loaded http://localhost:44190/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44190/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:16:59 INFO - PROCESS | 25101 | RSS: Main: 222126080 12:16:59 INFO - PROCESS | 25101 | 12:17:02 INFO - PROCESS | 25101 | Cycle 1(1): loaded http://localhost:44190/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44190/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:17:03 INFO - PROCESS | 25101 | RSS: Main: 223318016 12:17:03 INFO - PROCESS | 25101 | 12:17:06 INFO - PROCESS | 25101 | Cycle 1(1): loaded http://localhost:44190/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44190/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:17:06 INFO - PROCESS | 25101 | RSS: Main: 221343744 12:17:06 INFO - PROCESS | 25101 | 12:17:08 INFO - PROCESS | 25101 | Cycle 1(1): loaded http://localhost:44190/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44190/tests/kraken/driver.html?testName=ai-astar) 12:17:08 INFO - PROCESS | 25101 | RSS: Main: 273215488 12:17:08 INFO - PROCESS | 25101 | 12:17:08 INFO - PROCESS | 25101 | __start_tp_report 12:17:08 INFO - PROCESS | 25101 | _x_x_mozilla_page_load 12:17:08 INFO - PROCESS | 25101 | _x_x_mozilla_page_load_details 12:17:08 INFO - PROCESS | 25101 | |i|pagename|runs| 12:17:08 INFO - PROCESS | 25101 | |0;ai-astar;114;116;121;115;114;115;114;113;115;129 12:17:08 INFO - PROCESS | 25101 | |1;audio-beat-detection;154;130;160;191;125;146;146;124;189;122 12:17:08 INFO - PROCESS | 25101 | |2;audio-dft;163;163;162;162;162;163;161;162;163;162 12:17:08 INFO - PROCESS | 25101 | |3;audio-fft;75;76;77;162;77;76;77;77;156;78 12:17:08 INFO - PROCESS | 25101 | |4;audio-oscillator;84;85;147;84;83;84;138;83;84;133 12:17:08 INFO - PROCESS | 25101 | |5;imaging-gaussian-blur;117;118;117;118;118;117;118;117;116;117 12:17:08 INFO - PROCESS | 25101 | |6;imaging-darkroom;146;146;147;148;148;147;147;146;151;146 12:17:08 INFO - PROCESS | 25101 | |7;imaging-desaturate;97;96;94;95;95;95;95;95;95;95 12:17:08 INFO - PROCESS | 25101 | |8;json-parse-financial;65;65;67;67;68;67;67;68;68;65 12:17:08 INFO - PROCESS | 25101 | |9;json-stringify-tinderbox;64;65;65;66;66;65;64;65;66;66 12:17:08 INFO - PROCESS | 25101 | |10;stanford-crypto-aes;83;82;96;82;83;85;82;83;82;81 12:17:08 INFO - PROCESS | 25101 | |11;stanford-crypto-ccm;141;132;133;135;150;140;140;132;177;138 12:17:08 INFO - PROCESS | 25101 | |12;stanford-crypto-pbkdf2;180;176;176;178;179;177;176;176;172;175 12:17:08 INFO - PROCESS | 25101 | |13;stanford-crypto-sha256-iterative;69;76;72;69;70;68;72;70;68;68 12:17:08 INFO - PROCESS | 25101 | __end_tp_report 12:17:08 INFO - PROCESS | 25101 | __start_cc_report 12:17:08 INFO - PROCESS | 25101 | _x_x_mozilla_cycle_collect,2005 12:17:08 INFO - PROCESS | 25101 | __end_cc_report 12:17:08 INFO - PROCESS | 25101 | __startTimestamp1472757428688__endTimestamp 12:17:08 INFO - PROCESS | 25101 | 12:17:08 INFO - PROCESS | 25101 | ------- Summary: start ------- 12:17:08 INFO - PROCESS | 25101 | Number of tests: 14 12:17:08 INFO - PROCESS | 25101 | 12:17:08 INFO - PROCESS | 25101 | [#0] ai-astar Cycles:10 Average:116.60 Median:115.00 stddev:4.88 (4.2%) stddev-sans-first:5.09 12:17:08 INFO - PROCESS | 25101 | Values: 114.0 116.0 121.0 115.0 114.0 115.0 114.0 113.0 115.0 129.0 12:17:08 INFO - PROCESS | 25101 | 12:17:08 INFO - PROCESS | 25101 | [#1] audio-beat-detection Cycles:10 Average:148.70 Median:150.00 stddev:25.43 (17.0%) stddev-sans-first:26.90 12:17:08 INFO - PROCESS | 25101 | Values: 154.0 130.0 160.0 191.0 125.0 146.0 146.0 124.0 189.0 122.0 12:17:08 INFO - PROCESS | 25101 | 12:17:08 INFO - PROCESS | 25101 | [#2] audio-dft Cycles:10 Average:162.30 Median:162.50 stddev:0.67 (0.4%) stddev-sans-first:0.67 12:17:08 INFO - PROCESS | 25101 | Values: 163.0 163.0 162.0 162.0 162.0 163.0 161.0 162.0 163.0 162.0 12:17:08 INFO - PROCESS | 25101 | 12:17:08 INFO - PROCESS | 25101 | [#3] audio-fft Cycles:10 Average:93.10 Median:77.00 stddev:34.77 (45.2%) stddev-sans-first:36.26 12:17:08 INFO - PROCESS | 25101 | Values: 75.0 76.0 77.0 162.0 77.0 76.0 77.0 77.0 156.0 78.0 12:17:08 INFO - PROCESS | 25101 | 12:17:08 INFO - PROCESS | 25101 | [#4] audio-oscillator Cycles:10 Average:100.50 Median:84.00 stddev:27.01 (32.2%) stddev-sans-first:27.98 12:17:08 INFO - PROCESS | 25101 | Values: 84.0 85.0 147.0 84.0 83.0 84.0 138.0 83.0 84.0 133.0 12:17:08 INFO - PROCESS | 25101 | 12:17:08 INFO - PROCESS | 25101 | [#5] imaging-gaussian-blur Cycles:10 Average:117.30 Median:117.50 stddev:0.67 (0.6%) stddev-sans-first:0.71 12:17:08 INFO - PROCESS | 25101 | Values: 117.0 118.0 117.0 118.0 118.0 117.0 118.0 117.0 116.0 117.0 12:17:08 INFO - PROCESS | 25101 | 12:17:08 INFO - PROCESS | 25101 | [#6] imaging-darkroom Cycles:10 Average:147.20 Median:147.00 stddev:1.55 (1.1%) stddev-sans-first:1.58 12:17:08 INFO - PROCESS | 25101 | Values: 146.0 146.0 147.0 148.0 148.0 147.0 147.0 146.0 151.0 146.0 12:17:08 INFO - PROCESS | 25101 | 12:17:08 INFO - PROCESS | 25101 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.79 (0.8%) stddev-sans-first:0.50 12:17:08 INFO - PROCESS | 25101 | Values: 97.0 96.0 94.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 12:17:08 INFO - PROCESS | 25101 | 12:17:08 INFO - PROCESS | 25101 | [#8] json-parse-financial Cycles:10 Average:66.70 Median:67.00 stddev:1.25 (1.9%) stddev-sans-first:1.17 12:17:08 INFO - PROCESS | 25101 | Values: 65.0 65.0 67.0 67.0 68.0 67.0 67.0 68.0 68.0 65.0 12:17:08 INFO - PROCESS | 25101 | 12:17:08 INFO - PROCESS | 25101 | [#9] json-stringify-tinderbox Cycles:10 Average:65.20 Median:65.50 stddev:0.79 (1.2%) stddev-sans-first:0.71 12:17:08 INFO - PROCESS | 25101 | Values: 64.0 65.0 65.0 66.0 66.0 65.0 64.0 65.0 66.0 66.0 12:17:08 INFO - PROCESS | 25101 | 12:17:08 INFO - PROCESS | 25101 | [#10] stanford-crypto-aes Cycles:10 Average:83.90 Median:83.00 stddev:4.38 (5.3%) stddev-sans-first:4.64 12:17:08 INFO - PROCESS | 25101 | Values: 83.0 82.0 96.0 82.0 83.0 85.0 82.0 83.0 82.0 81.0 12:17:08 INFO - PROCESS | 25101 | 12:17:08 INFO - PROCESS | 25101 | [#11] stanford-crypto-ccm Cycles:10 Average:141.80 Median:140.00 stddev:13.51 (9.7%) stddev-sans-first:14.33 12:17:08 INFO - PROCESS | 25101 | Values: 141.0 132.0 133.0 135.0 150.0 140.0 140.0 132.0 177.0 138.0 12:17:08 INFO - PROCESS | 25101 | 12:17:08 INFO - PROCESS | 25101 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.50 Median:176.50 stddev:2.22 (1.3%) stddev-sans-first:1.96 12:17:08 INFO - PROCESS | 25101 | Values: 180.0 176.0 176.0 178.0 179.0 177.0 176.0 176.0 172.0 175.0 12:17:08 INFO - PROCESS | 25101 | 12:17:08 INFO - PROCESS | 25101 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.20 Median:70.00 stddev:2.53 (3.6%) stddev-sans-first:2.65 12:17:08 INFO - PROCESS | 25101 | Values: 69.0 76.0 72.0 69.0 70.0 68.0 72.0 70.0 68.0 68.0 12:17:08 INFO - PROCESS | 25101 | -------- Summary: end -------- 12:17:08 INFO - PROCESS | 25101 | 12:17:09 INFO - TEST-INFO | 25101: exit 0 12:17:10 INFO - TEST-OK | kraken | took 65324ms 12:17:10 INFO - SUITE-END | took 1187s 12:17:10 INFO - Completed test suite (00:19:48) 12:17:10 INFO - javascript benchmark 12:17:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2631.0, 2639.6700412448445, 2658.667666541682, 2705.2019654201995, 2714.3214196450876, 4611.123886461578, 4666.953330466695, 4769.499202583726, 4854.830080947172, 4934.950650493494, 4626.676132670715, 4675.088357770236, 4705.600023997956, 4726.291056341551, 4752.667313288071, 4381.058072514416, 4417.668674849383, 4436.068425630621, 4453.955460445402, 4467.307567327053, 4481.3726078349055, 4514.255147900592, 4515.277555591106, 4519.389882365871, 4543.432070991126, 4430.202563538562, 4604.562566556172, 4608.976955115237, 4611.515790841963, 4613.331066995283, 4362.781860906942, 4431.8448854289945, 4469.10617876426, 4483.170613436492, 4498.685092043558, 4357.738535687838, 4375.059362237134, 4380.167768124046, 4448.466184057901, 4453.465584129893, 4751.833685821014, 4752.5485078917445, 4764.595009424203, 4827.758612069382, 4854.830080947185, 5171.663841850305, 5199.220116982445, 5201.089809283398, 5204.453532379083, 5213.400458947193, 5016.021875734234, 5146.176611742103, 5198.350206224222, 5223.451537588537, 5226.346706661668, 4860.684055536379, 4862.270659401118, 4862.635302352338, 4866.075445665383, 4917.483664215278, 5290.603204759582, 5350.6254562180275, 5352.946470535323, 5356.223347614574, 5420.83737487876, 4955.925661115086, 4976.154053810861, 5014.0, 5021.548060674557, 5118.078745825713, 5102.234664800236, 5113.795448182031, 5118.411382690963, 5119.6416250862085, 5169.560587350117, 5066.493350664904, 5075.289459475676, 5079.0, 5081.060003899216, 5133.8716532087, 4967.5529202371235, 5022.296878437021, 5047.697138171721, 5049.596032317406, 5098.311727916684, 4330.0, 4333.58830911063, 4384.7369157850635, 4386.188555117314, 4395.934060989088, 3786.1481166737813, 3796.0699628591174, 3800.467934489174, 3846.769193848378, 3852.653261206505, 4389.912201755947, 4475.70907890986, 4484.304932735431, 4496.64026877849, 4507.414036175276, 5177.792888284426, 5201.869953251124, 5203.947960520422, 5258.079836028756, 5264.763085661154, 4488.618467430239, 4494.056248187851, 4502.0, 4505.707129036602, 4557.453105627346, 4478.57453541913, 4549.909001819945, 4603.8849028773875, 4644.094401591725, 4700.506446823103, 4788.305695674038, 4806.327114203944, 4812.35033270518, 4858.562729354375, 4868.318435418973, 5123.641345105807, 5129.666571672829, 5134.691918484903, 5140.2803607495725, 5190.792368305226, 4648.349231107715, 4804.855854324376, 4808.519148085164, 4849.854504364875, 4877.707337559616, 5094.312267843794, 5110.386753589593, 5113.53978141969, 5150.381954165524, 5182.093133701663, 5171.379434467888, 5181.1969144782615, 5185.585153187661, 5188.896222075462, 5252.343457067866, 4917.680350777188, 4988.27669987857, 5026.0, 5027.145385284437, 5046.747662616928, 5035.5719763819025, 5045.772940217773, 5047.4952504749235, 5099.69401835891, 5113.0, 4942.308076869169, 4945.381827271591, 4947.950520494893, 4967.180415231446, 4990.426100998432, 4923.990581930624, 4986.301917731448, 5016.197408414636, 5027.069992051483, 5096.974515127401, 4956.925646115239, 4980.452150263541, 4986.351774269467, 5029.170186919117, 5038.042771873333], "value": 4733.34699102839, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [557.3172863242528, 570.449516216851, 580.6777238632558, 593.9020061689821, 594.3669991459095, 27491.862540687292, 30782.53826192609, 30911.536326955058, 30914.0, 30923.69076309239, 30965.845170774115, 31482.0, 42824.0, 43987.0, 44051.33922991158, 7436.1076670799575, 7541.811454713648, 7547.811304717398, 7548.245175482462, 7553.962230188841, 7580.9241907581045, 7634.198409167041, 7644.120926093487, 7667.808304792369, 7683.039620047494, 7675.692972281102, 7721.266479684421, 7732.6520306586335, 7735.729249476241, 7738.0, 7656.425768067417, 7691.307782299592, 7705.0, 7706.075270967463, 7707.421943354271, 7636.08366995964, 7685.116211635622, 7690.884636730453, 7699.576523291216, 7705.15243323234, 38645.80677096624, 38923.0, 38945.221095578214, 38971.220575588326, 38994.41508377377, 2878.2228798224364, 3083.845807709628, 3175.2061984503875, 3530.38218311797, 3590.2819436112622, 28379.57430638542, 28493.430131397257, 28841.85579072112, 28851.27871803201, 30091.0, 3393.33829903169, 3404.9829750851336, 3422.4866270059442, 3452.637473065317, 3554.6445355464507, 253762.0, 255157.0, 255957.99999999814, 256199.0, 257134.0, 30459.431085337375, 50034.49965500371, 50663.746681266355, 50702.49297506978, 50718.0, 30570.54144187839, 32084.358312833614, 32085.197870053435, 32116.35767284641, 32135.357292854478, 13330.400131994083, 13453.798193027113, 13454.125481843648, 13454.663633409244, 13467.393967271497, 11229.71925701864, 11234.213605047567, 11236.381998990135, 11237.38194399316, 11252.66242012741, 7526.774196774106, 7561.0, 7604.581748003802, 7642.847143057108, 7657.655405506765, 2962.4223276460884, 2978.3596526746855, 2984.65676447207, 3016.351484430814, 3052.9694703052687, 811.7645882694071, 844.615699856576, 872.3718922375879, 872.9345299102592, 961.077365728894, 25012.874935625205, 26885.0, 27180.32049198786, 27219.727802722115, 27389.589156162674, 766.5247546521081, 945.4091193004373, 948.691675205561, 963.0706368354571, 996.6113215845826, 14421.855781442262, 14764.094362255184, 14827.143278405185, 15109.244537773067, 15167.089974601558, 371851.0, 373388.0, 374731.0, 375031.0, 375983.0, 49364.25953610699, 49864.0, 49894.25158622624, 49985.0, 50031.74984125129, 1047.2198212331862, 1086.4676308608882, 1135.7103938495632, 1166.2244607336027, 1200.8078707406773, 27665.0, 28676.28309292201, 29089.0, 29374.0, 29628.0, 41400.1719965605, 42206.78896605497, 42209.0, 42213.366799498646, 42216.155676886905, 292244.0, 295558.0, 295614.0, 295956.0, 296037.0, 22435.66346504771, 22510.774892251524, 22695.0, 23421.76578234264, 25050.37374065663, 41085.79457102815, 41492.37761433521, 41576.16847662969, 41631.7918410406, 42196.367054493596, 35000.0, 35070.82464587763, 35236.0, 35297.1175720609, 35669.75155869532, 35501.82249088738, 35579.0, 35630.10924726799, 35794.463083053255, 35833.46249806203, 34364.48453273153, 34734.131646708025, 35149.824250878584, 35243.47134793032, 35812.64187358093, 28781.926325785807, 30727.2318192047, 30949.845250774502, 31150.688493115686, 31301.217469563442, 24894.0, 24934.875325623256, 24960.750392495844, 25056.24831255065, 25264.368390790376], "value": 16014.020737935612, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.7966310045522, 363.74174336221296, 368.2303984672036, 370.7367768884091, 384.75568014310903, 13019.934900325497, 14350.856491435083, 16536.669266614677, 17460.912695436506, 17521.474355769347, 15785.763213551805, 16631.750523742154, 16720.665586688257, 16912.915435422805, 16917.91541042293, 4961.826336078238, 5035.848924532252, 5092.516210959953, 5109.489051094898, 5139.177731562951, 3627.3470775260444, 3759.793211373373, 5044.672096313746, 5128.153854614003, 5136.8715782105555, 3970.9205815883665, 5856.707164641773, 5893.64638121712, 6038.486728628071, 6052.546059045567, 5837.387074357174, 6001.339852616209, 6029.638221706689, 6034.306054803666, 6040.57715959883, 5288.92066619001, 5304.602154838364, 5304.681719096867, 5308.044551980642, 5309.203619457074, 19612.215511379527, 20547.280845170495, 21046.473838154016, 21362.89318553413, 21428.57142857134, 1862.9813701862943, 1920.2990908318502, 1932.3526618582791, 1933.4296382567177, 2006.468285904222, 12901.225926444444, 13628.250446225453, 13696.178229306273, 14167.0, 14295.499657511937, 1675.0452242221938, 1675.7821483207106, 1676.362982066819, 1708.7009773289624, 1799.478151336124, 69449.0, 71330.64334678346, 71700.64149679219, 72935.63532182359, 74383.25616743817, 9348.626054957726, 9477.573509191965, 9726.221902247944, 9782.853257201148, 10033.698989030341, 13538.323083845768, 13571.38928748189, 13579.049466537444, 13591.932040339932, 13601.0, 7373.557586544717, 7561.017067781153, 7658.2724641159, 7721.305082542598, 7763.417743669247, 7282.235365286675, 7311.122665280201, 7327.37717294036, 7518.812029699301, 7531.510451820724, 6046.520926420147, 6150.907736383958, 6237.251529816451, 6250.655899093963, 6444.903326450107, 1675.346614820221, 1682.9915850420668, 1690.619610587608, 1743.2765402358134, 1743.5553933746814, 756.1908757629284, 868.0885070675765, 872.5419154943705, 891.643342662927, 900.3607438718452, 17105.23026463812, 17556.38552650651, 17973.640527189382, 18189.81810181881, 18488.537786555175, 809.4536188073145, 864.7535452396037, 867.4578388507183, 870.621279743301, 933.9766505837273, 9575.952120239353, 9818.607255709834, 10384.480775961172, 10417.915218346832, 10538.0, 369709.0, 384655.0, 384683.0, 384800.0, 384898.0, 68261.0, 69013.0, 70205.64897175481, 71117.64441177865, 72837.63581182166, 888.6045709659139, 918.6830543462495, 938.3759799733238, 943.2265542255421, 949.3591825517748, 15617.765733514234, 15883.126428046568, 16144.838551614333, 16330.0, 16497.340106395608, 38256.0, 38867.0, 39017.609823901395, 39259.214815704094, 39959.60040399559, 51294.48705512897, 55740.72129639326, 56683.0, 56687.433125669864, 57368.71315643395, 4778.737169455713, 7289.16174639902, 7424.331810137113, 7533.849323013618, 8072.394570407126, 8724.81887478764, 11098.0, 11254.155938304497, 11307.378094204569, 12375.876241237833, 9711.902880971382, 10030.89969100329, 10139.493025348851, 10167.79664406693, 10687.358758474516, 10231.181505479693, 10424.426656533964, 10476.528556214836, 10530.631427899987, 10655.520501577292, 10426.322289051188, 10553.155747540024, 10753.0, 10915.235933484897, 10949.671509854717, 7795.181505942021, 8067.636956336862, 8069.515829050276, 8077.717279895175, 8094.069182044141, 9705.611775529182, 9959.900400995897, 10125.291229613857, 10267.7433064174, 10819.0], "value": 8383.587792230675, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1246.2771592476365, 1339.8191244182035, 1391.5199256256592, 1415.6885485193254, 1674.062524986008, 7519.436042296829, 7911.881321780171, 7976.2821346078845, 8080.7878818177305, 8136.9186308136905, 7979.56112413817, 8074.71738489153, 8094.91905080949, 8096.797580060502, 8097.67609295629, 2202.361315218585, 2227.164813195052, 2230.620794464945, 2231.866088034715, 2234.731832180141, 2158.946026349338, 2216.6785816056663, 2234.8659080455222, 2236.206146817881, 2244.147224054857, 2167.1656412281304, 2180.291405293278, 2188.4310079379316, 2192.736871575405, 2237.284069097889, 2122.076896549998, 2135.9679604805942, 2148.0977989244634, 2148.334016454904, 2148.677698345245, 2123.331150687544, 2169.53355028668, 2170.522485053286, 2171.9891400543056, 2175.7715439878903, 7843.411744119214, 8061.395395345373, 8123.106458289583, 8186.017677878693, 8189.918100818975, 4477.261251893434, 4646.047560250141, 4650.4186976627925, 4662.347271382009, 4662.7202367858035, 2797.510435673749, 2960.437516871809, 3010.0, 3010.262485690998, 3014.6382434108045, 870.9564521773885, 882.6778225947547, 892.8794612727264, 906.6373450619739, 924.5746956400064, 10911.399873006982, 10954.616588419367, 10990.670279891536, 10992.076386632307, 10993.175511836644, 3804.0518598086705, 4000.979995100006, 4120.2583534964, 4130.215259100762, 4200.978995105005, 8619.956900215459, 8690.348223883235, 8697.08680588529, 8699.739007829648, 8749.256313213447, 4435.112977404532, 4445.044315472189, 4448.911021779546, 4458.9554104459185, 4459.063596644741, 3512.3677738006895, 4091.57038510958, 4093.2222877653153, 4103.322951712994, 4312.654987600984, 3652.1052342176336, 3767.2842159989514, 3869.342211823997, 3898.064464528493, 3908.4918960534947, 1208.3897631696113, 1220.7314390834001, 1223.61456141317, 1232.303748382179, 1238.59745582686, 712.6614857942436, 818.8976377952756, 826.2316046077086, 831.8960129983752, 836.9916300837035, 5230.398504172069, 5364.7317634118135, 5380.192971054373, 5401.702906340189, 5575.358833734092, 894.4543828264754, 895.0353973029269, 955.4553904274496, 963.8361478548663, 981.4160574458328, 3281.392942305681, 3316.1046517440154, 3440.3807314683595, 3501.649835016478, 3736.3461394255896, 9456.14894659484, 9791.461469619097, 9817.558209880572, 10127.898721012843, 10146.949265253626, 9885.308028438085, 9893.45585992763, 9897.109260166619, 10017.348872323275, 10020.148287395654, 7283.162436319785, 7493.512921660074, 7550.962245188739, 7599.620018998918, 7600.087989441303, 5287.629865909349, 5323.441038691037, 5431.755570999394, 5432.2123292123215, 5664.207011018543, 9017.458952462874, 9190.264778817545, 9263.953680231556, 9264.722058337991, 9318.114779095975, 9252.51959686447, 9434.528273586157, 9465.574049167943, 9519.476428796206, 9532.571034303335, 6110.7861224856915, 6554.672266386757, 6633.502487313374, 6865.27914568983, 6880.139982502187, 7605.125410577854, 7611.809704757426, 7675.155732869268, 7675.462717609714, 7678.731244406419, 6763.695633696397, 6824.522283440111, 6831.897521537082, 6884.242733299233, 7020.297970203143, 6733.259341472336, 6832.931670683429, 6848.315168483112, 6924.065251191025, 7137.857242855218, 6115.205023347115, 6611.07444957716, 6704.128463299739, 6713.563618364986, 6715.630640314829, 4503.0, 4519.480259770037, 4547.522510136388, 4563.680542362135, 4611.838585649486, 4720.315554244684, 4754.31062495943, 4763.857084287477, 4797.928031079607, 4975.303457515878], "value": 4186.753370023036, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [292.9501984662609, 304.65726058184543, 306.4009860722288, 307.1706392739604, 312.93897689950455, 4597.793059331514, 4765.213739732944, 4787.8084876604935, 4788.736619485927, 4805.8798530036765, 4605.0, 4699.835505757299, 4708.811647534095, 4719.4100737407825, 4730.0, 3764.5858955514873, 3772.9434058489146, 3774.3960966245404, 3778.622137786227, 3792.962070379302, 3573.94639080414, 3632.364336241147, 3792.4880141181, 3803.790791506466, 3827.3302172119907, 3770.622937706235, 3779.9811000944956, 3780.0360907968566, 3782.7352085354037, 3787.3182827091114, 3729.8366947170575, 3757.839242435583, 3762.473253744478, 3783.2622638585626, 3788.867389641377, 3340.8641062038782, 3350.932981340384, 3353.6143343515564, 3365.1923538350866, 3368.1411240133853, 4488.730676159408, 4595.356650068993, 4610.884727881797, 4630.930536041962, 4669.95330046702, 4205.789710514462, 4239.173361194568, 4253.064325848309, 4257.339637541361, 4260.723053001545, 4914.311996320518, 4929.313524129322, 4929.531694489017, 4949.975250123726, 4974.030064137494, 3311.867525298985, 3479.599846017715, 3576.3204991051616, 3580.516630254934, 3590.9281814363567, 5155.793768249266, 5166.018456493254, 5189.948100518985, 5194.844154675328, 5230.555402790767, 3641.672249497558, 3648.759421796602, 3655.7440979131206, 3664.9816750916607, 3669.3945498992366, 3700.296943580711, 3718.5909549949483, 3723.311187430334, 3729.4778730977687, 3733.216024634857, 4013.8996525087105, 4147.3778933159665, 4184.184084103571, 4202.453681021448, 4257.893552661209, 4974.602031837444, 4980.203167493184, 4984.352034235599, 4995.325631039836, 4997.925031124536, 1200.789861774186, 1234.1854376111903, 1271.3960868587346, 1279.1429742072833, 1292.2504947130642, 856.9400141990001, 865.7575878753959, 880.9383343166045, 885.2563846369081, 885.8759773631697, 4424.933625995613, 4429.955700443019, 4430.272193844403, 4436.824241575985, 4441.422615060086, 5060.367454068241, 5062.797683007903, 5067.641828027893, 5069.391672999263, 5098.617603679739, 4391.14372697321, 4403.031333106712, 4407.955920440819, 4414.470263568328, 4418.939454530953, 2287.645414960684, 2365.7870791628834, 2396.904123835027, 2398.328468028955, 2401.1115887121878, 4219.38818871262, 4233.433629557084, 4273.230818452646, 4284.0, 4304.526502084768, 4522.773861306921, 4558.635309175258, 4580.610648094949, 4596.47140578831, 4607.562281583244, 4066.1461093170765, 4232.153569286155, 4269.914601707948, 4287.0, 4346.847860324873, 4481.282994720829, 4490.955090449053, 4528.41130653013, 4534.682572220045, 4542.88642783933, 4423.60187583119, 4439.800208990668, 4449.0, 4452.688311818141, 4486.820527178877, 4236.301010333261, 4244.575542445731, 4253.61717445419, 4501.662375321798, 4849.4239372203465, 3664.6885014774043, 3664.8900532984053, 3667.4132138857653, 3669.8990302908487, 3693.833777479966, 4944.208926571731, 5141.203113517411, 5159.9102363542825, 5184.7926082957765, 5187.121147762877, 4878.4631991363585, 4992.2761199626575, 4999.450060493416, 5004.924926126184, 5013.147764880052, 4962.925556116734, 4978.6763860348965, 4979.875503112451, 4992.975035124801, 5004.2493625956395, 4809.148477836128, 4915.631327650369, 4943.876403089952, 4979.800807967641, 4986.102501549756, 5005.324281221989, 5006.449290578106, 5010.423801262901, 5034.974825125997, 5041.495850414929, 4866.6153584742515, 4872.6832755870755, 4880.487548807466, 4903.632227583017, 4906.031850730256], "value": 3683.1646522224937, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [624.965626890521, 648.6173157836876, 662.5395350231585, 663.9302873198315, 732.9409003989234, 13381.866181338184, 14733.747631451326, 14747.926260368697, 14750.33623486943, 14758.557243282692, 12396.13227074105, 12634.62096137115, 12634.873651263484, 12640.557580484685, 12660.11379203456, 589.4105894105894, 712.8752468318023, 718.7915504503701, 737.6311844077961, 743.4424181863602, 744.1368013104773, 746.4625469661835, 746.5520687587458, 747.1930315259507, 747.4618274842132, 791.5448617045193, 794.4399198564997, 794.7377365469381, 794.9682012719484, 795.1094773853292, 616.1774031667707, 757.8901059346364, 775.329340120797, 777.2616014785976, 784.396015068397, 569.3680015183144, 571.2488078177183, 571.8198767388301, 571.8541771848194, 571.8656115812822, 12021.038316934711, 12056.276623402624, 12059.457324420335, 12069.094817888606, 12076.577319793852, 12037.0, 12486.562970295996, 12762.17045892014, 13016.739665206644, 13392.732145357038, 11402.486888089972, 11718.765624687543, 12139.514419423302, 12151.878481215163, 12868.935655321664, 4075.714699971003, 4129.318662420727, 4208.137331846964, 4266.743995360257, 4354.259775838115, 55167.72416137894, 75758.62120689417, 75772.0, 75981.0, 76288.0, 11068.833967490495, 11083.556657733601, 11312.03847672957, 11656.825147622792, 11665.825012624817, 20350.0, 20537.48656283605, 20681.0, 20684.896575517327, 20685.17259309641, 5873.735681894325, 5974.850628734316, 6179.598326108879, 6834.897476537856, 6940.9652951734915, 3426.638293228568, 3545.7872527648424, 3623.710103191791, 3640.690541303966, 3671.6695497405362, 2848.2594525423124, 2979.6275465566805, 2987.6116104906223, 3001.0, 3002.1143762590086, 501.8068043803162, 505.41372008470734, 511.1336284996969, 515.8839261166282, 517.0950836036935, 461.87298492914044, 486.45031114840714, 519.8076711616726, 520.9426963034064, 526.8972550352721, 6829.487788415889, 7156.340743028159, 7276.688017746968, 7356.337929586363, 7737.032870891138, 489.657239932049, 538.7225578826907, 568.6445971267957, 577.83531693468, 606.915031895535, 3514.6661067198575, 3643.398839191557, 3718.3678774608384, 3735.4957080793747, 3875.9806200968815, 56586.15120773192, 56882.14676779851, 84846.0, 94713.0, 105688.47155764172, 90860.0, 91211.54394227987, 91230.54384728166, 91547.0, 93218.53390733003, 63642.0, 64200.357996420375, 64357.35642643701, 82587.0, 85801.13286971953, 7551.546907185587, 7826.1651790621045, 7833.804154896173, 7939.563324017235, 8419.789505262172, 41980.79009604932, 42073.579264206965, 45265.09469810651, 45412.594055446636, 45653.0, 36010.27979440449, 36464.635353646125, 40108.79945600253, 41396.586034139276, 41402.378964316165, 9979.750506237111, 10152.74618134523, 10501.0, 10673.839892401465, 10910.78178436411, 12619.87380126187, 12783.872161278268, 12792.424340904496, 12967.935160324138, 13458.327083645974, 11611.245269057485, 11836.46735896904, 11933.164678472423, 12604.621861344174, 12633.178843375152, 10451.68644940653, 10615.787684246117, 11158.944205278922, 11188.83216751733, 11241.269317494336, 11287.153463490107, 11315.377654229096, 12401.62795116148, 12431.0, 12431.75136497247, 9090.636374544943, 9426.811463770824, 9427.764305892408, 9865.506724663595, 9929.106380425796, 8306.335493160412, 9538.904610953801, 9562.235021198427, 9664.468454234804, 9727.65953191635], "value": 6392.328616688837, "unit": "score"}], "name": "dromaeo_css", "value": 6302.078904240074, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 116.0, 121.0, 115.0, 114.0, 115.0, 114.0, 113.0, 115.0, 129.0], "value": 116.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 130.0, 160.0, 191.0, 125.0, 146.0, 146.0, 124.0, 189.0, 122.0], "value": 148.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 162.0, 162.0, 162.0, 163.0, 161.0, 162.0, 163.0, 162.0], "value": 162.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 77.0, 162.0, 77.0, 76.0, 77.0, 77.0, 156.0, 78.0], "value": 93.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 147.0, 84.0, 83.0, 84.0, 138.0, 83.0, 84.0, 133.0], "value": 100.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 117.0, 118.0, 118.0, 117.0, 118.0, 117.0, 116.0, 117.0], "value": 117.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 147.0, 148.0, 148.0, 147.0, 147.0, 146.0, 151.0, 146.0], "value": 147.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 67.0, 67.0, 68.0, 67.0, 67.0, 68.0, 68.0, 65.0], "value": 66.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 65.0, 66.0, 66.0, 65.0, 64.0, 65.0, 66.0, 66.0], "value": 65.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 96.0, 82.0, 83.0, 85.0, 82.0, 83.0, 82.0, 81.0], "value": 83.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 132.0, 133.0, 135.0, 150.0, 140.0, 140.0, 132.0, 177.0, 138.0], "value": 141.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 176.0, 176.0, 178.0, 179.0, 177.0, 176.0, 176.0, 172.0, 175.0], "value": 176.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 76.0, 72.0, 69.0, 70.0, 68.0, 72.0, 70.0, 68.0, 68.0], "value": 70.2, "unit": "score"}], "name": "kraken", "value": 1585.2, "alertThreshold": 2.0}]} 12:17:10 INFO - Return code: 0 12:17:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:17:10 INFO - # TBPL SUCCESS # 12:17:10 INFO - Running post-action listener: _resource_record_post_action 12:17:10 INFO - [mozharness: 2016-09-01 19:17:10.476223Z] Finished run-tests step (success) 12:17:10 INFO - Running post-run listener: _resource_record_post_run 12:17:10 INFO - Total resource usage - Wall time: 1204s; CPU: 13.0%; Read bytes: 8192; Write bytes: 405389312; Read time: 216; Write time: 424936 12:17:10 INFO - TinderboxPrint: CPU usage
13.1% 12:17:10 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 216 12:17:10 INFO - TinderboxPrint: I/O write bytes / time
405,389,312 / 424,936 12:17:10 INFO - TinderboxPrint: CPU idle
8,348.6 (86.9%) 12:17:10 INFO - TinderboxPrint: CPU user
1,208.1 (12.6%) 12:17:10 INFO - TinderboxPrint: Swap in / out
0 / 0 12:17:10 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 4096; Write bytes: 113868800; Read time: 204; Write time: 167040 12:17:10 INFO - run-tests - Wall time: 1188s; CPU: 13.0%; Read bytes: 0; Write bytes: 290906112; Read time: 0; Write time: 257896 12:17:11 INFO - Running post-run listener: _upload_blobber_files 12:17:11 INFO - Blob upload gear active. 12:17:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:17:11 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 12:17:11 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'] 12:17:11 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 12:17:11 INFO - (blobuploader) - INFO - Open directory for files ... 12:17:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:17:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:17:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:17:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:17:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:17:12 INFO - (blobuploader) - INFO - Done attempting. 12:17:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:17:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:17:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:17:23 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:17:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:17:23 INFO - (blobuploader) - INFO - Done attempting. 12:17:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:17:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:17:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:17:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:17:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:17:24 INFO - (blobuploader) - INFO - Done attempting. 12:17:24 INFO - (blobuploader) - INFO - Iteration through files over. 12:17:24 INFO - Return code: 0 12:17:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:17:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:17:24 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7e3c336a34a1391e399c02f8ba99c1f33664424e4b333f44975774e189dd999ec995bc8b64a67464b05a4c5d399ed9b0280eb444a0cea2fb1f72ef0a8ab62ecf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/da9e624891f2c5aff39e4cda1577696eede4ff6c68516b1e0bdd14cb467fd9654c5fc32b71e31246274ecd1faf841ef0111ab87a396c98dfbd7f8614d49e2a4a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e1e24a3bd209a878839fdaac67f160b2071761150302493867110dc2754a9bd2c50268f2d053a319dcf36c422ba30c2113004c2f9dca4e5805530492be048a01"} 12:17:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:17:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:17:24 INFO - Contents: 12:17:24 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7e3c336a34a1391e399c02f8ba99c1f33664424e4b333f44975774e189dd999ec995bc8b64a67464b05a4c5d399ed9b0280eb444a0cea2fb1f72ef0a8ab62ecf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/da9e624891f2c5aff39e4cda1577696eede4ff6c68516b1e0bdd14cb467fd9654c5fc32b71e31246274ecd1faf841ef0111ab87a396c98dfbd7f8614d49e2a4a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e1e24a3bd209a878839fdaac67f160b2071761150302493867110dc2754a9bd2c50268f2d053a319dcf36c422ba30c2113004c2f9dca4e5805530492be048a01"} 12:17:24 INFO - Running post-run listener: copy_logs_to_upload_dir 12:17:24 INFO - Copying logs to upload dir... 12:17:24 INFO - mkdir: /builds/slave/test/build/upload/logs 12:17:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1255.300318 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2016-09-01 12:17:23.849135) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 12:17:23.856126) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1472756187.437388-177872028 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/7e3c336a34a1391e399c02f8ba99c1f33664424e4b333f44975774e189dd999ec995bc8b64a67464b05a4c5d399ed9b0280eb444a0cea2fb1f72ef0a8ab62ecf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/da9e624891f2c5aff39e4cda1577696eede4ff6c68516b1e0bdd14cb467fd9654c5fc32b71e31246274ecd1faf841ef0111ab87a396c98dfbd7f8614d49e2a4a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e1e24a3bd209a878839fdaac67f160b2071761150302493867110dc2754a9bd2c50268f2d053a319dcf36c422ba30c2113004c2f9dca4e5805530492be048a01"} build_url:https://queue.taskcluster.net/v1/task/J2vD5DrAT-2vs1ss5UrKCw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011085 build_url: 'https://queue.taskcluster.net/v1/task/J2vD5DrAT-2vs1ss5UrKCw/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/7e3c336a34a1391e399c02f8ba99c1f33664424e4b333f44975774e189dd999ec995bc8b64a67464b05a4c5d399ed9b0280eb444a0cea2fb1f72ef0a8ab62ecf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/da9e624891f2c5aff39e4cda1577696eede4ff6c68516b1e0bdd14cb467fd9654c5fc32b71e31246274ecd1faf841ef0111ab87a396c98dfbd7f8614d49e2a4a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e1e24a3bd209a878839fdaac67f160b2071761150302493867110dc2754a9bd2c50268f2d053a319dcf36c422ba30c2113004c2f9dca4e5805530492be048a01"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 12:17:23.880746) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 12:17:23.881042) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1472756187.437388-177872028 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004399 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 12:17:23.964298) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 12:17:23.964605) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 12:17:23.967897) ========= ========= Total master_lag: 0.16 =========