builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-034 starttime: 1470633410.99 results: success (0) buildid: 20160807205552 builduid: 9c518ae8cb2f4230946c565c1cd36042 revision: 513d42e7a166bab34785c7f727704bf8d32c1a36 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-07 22:16:50.988408) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-07 22:16:50.988832) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-07 22:16:50.989095) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-07 22:16:51.015345) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-07 22:16:51.015663) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1470632736.956772-1408881702 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-07 22:16:51-- 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% 46.5M=0s 2016-08-07 22:16:51 (46.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.080526 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-07 22:16:51.117970) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-07 22:16:51.118334) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1470632736.956772-1408881702 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020151 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-07 22:16:51.171786) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-07 22:16:51.172118) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 513d42e7a166bab34785c7f727704bf8d32c1a36 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 513d42e7a166bab34785c7f727704bf8d32c1a36 --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=d6bbd613908ccac0c6c9cb47000001f7-1470632736.956772-1408881702 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-07 22:16:51,457 truncating revision to first 12 chars 2016-08-07 22:16:51,457 Setting DEBUG logging. 2016-08-07 22:16:51,457 attempt 1/10 2016-08-07 22:16:51,457 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/513d42e7a166?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-07 22:16:51,818 unpacking tar archive at: autoland-513d42e7a166/testing/mozharness/ program finished with exit code 0 elapsedTime=0.694687 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-07 22:16:51.879814) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-07 22:16:51.880072) ========= script_repo_revision: 513d42e7a166bab34785c7f727704bf8d32c1a36 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-07 22:16:51.880423) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-07 22:16:51.880688) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-07 22:16:51.894974) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-08-07 22:16:51.895248) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-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', 'Autoland-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland-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=d6bbd613908ccac0c6c9cb47000001f7-1470632736.956772-1408881702 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:16:52 INFO - MultiFileLogger online at 20160807 22:16:52 in /builds/slave/test 22:16:52 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 22:16:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:16:52 INFO - {'append_to_log': False, 22:16:52 INFO - 'base_work_dir': '/builds/slave/test', 22:16:52 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 22:16:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:16:52 INFO - 'branch': 'Autoland-Non-PGO', 22:16:52 INFO - 'buildbot_json_path': 'buildprops.json', 22:16:52 INFO - 'config_files': ('talos/linux_config.py',), 22:16:52 INFO - 'default_actions': ('clobber', 22:16:52 INFO - 'read-buildbot-config', 22:16:52 INFO - 'download-and-extract', 22:16:52 INFO - 'populate-webroot', 22:16:52 INFO - 'create-virtualenv', 22:16:52 INFO - 'install', 22:16:52 INFO - 'run-tests'), 22:16:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:16:52 INFO - 'download_minidump_stackwalk': True, 22:16:52 INFO - 'download_symbols': 'ondemand', 22:16:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:16:52 INFO - 'tooltool.py': '/tools/tooltool.py', 22:16:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:16:52 INFO - '/tools/misc-python/virtualenv.py')}, 22:16:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:16:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:16:52 INFO - 'installer_path': 'installer.exe', 22:16:52 INFO - 'log_level': 'info', 22:16:52 INFO - 'log_name': 'talos', 22:16:52 INFO - 'log_to_console': True, 22:16:52 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:16:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:16:52 INFO - 'opt_config_files': (), 22:16:52 INFO - 'pip_index': False, 22:16:52 INFO - 'sps_profile': False, 22:16:52 INFO - 'sps_profile_interval': 0, 22:16:52 INFO - 'suite': 'dromaeojs', 22:16:52 INFO - 'system_bits': '32', 22:16:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:16:52 INFO - 'title': 'talos-linux64-ix-034', 22:16:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:16:52 INFO - 'use_talos_json': True, 22:16:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:16:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:16:52 INFO - 'work_dir': 'build'} 22:16:52 INFO - [mozharness: 2016-08-08 05:16:52.276231Z] Running clobber step. 22:16:52 INFO - Running pre-action listener: _resource_record_pre_action 22:16:52 INFO - Running main action method: clobber 22:16:52 INFO - rmtree: /builds/slave/test/build 22:16:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:16:52 INFO - Running post-action listener: _resource_record_post_action 22:16:52 INFO - [mozharness: 2016-08-08 05:16:52.737198Z] Finished clobber step (success) 22:16:52 INFO - [mozharness: 2016-08-08 05:16:52.737301Z] Running read-buildbot-config step. 22:16:52 INFO - Running pre-action listener: _resource_record_pre_action 22:16:52 INFO - Running main action method: read_buildbot_config 22:16:52 INFO - Using buildbot properties: 22:16:52 INFO - { 22:16:52 INFO - "project": "", 22:16:52 INFO - "product": "firefox", 22:16:52 INFO - "script_repo_revision": "production", 22:16:52 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 22:16:52 INFO - "repository": "", 22:16:52 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 22:16:52 INFO - "buildid": "20160807205552", 22:16:52 INFO - "pgo_build": "False", 22:16:52 INFO - "basedir": "/builds/slave/test", 22:16:52 INFO - "buildnumber": 312, 22:16:52 INFO - "slavename": "talos-linux64-ix-034", 22:16:52 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 22:16:52 INFO - "platform": "ubuntu64_hw", 22:16:52 INFO - "scheduler": "tests-autoland-linux64-talos", 22:16:52 INFO - "branch": "autoland", 22:16:52 INFO - "repo_path": "integration/autoland", 22:16:52 INFO - "revision": "513d42e7a166bab34785c7f727704bf8d32c1a36", 22:16:52 INFO - "stage_platform": "linux64", 22:16:52 INFO - "builduid": "9c518ae8cb2f4230946c565c1cd36042", 22:16:52 INFO - "slavebuilddir": "test" 22:16:52 INFO - } 22:16:52 INFO - Found installer url https://queue.taskcluster.net/v1/task/Rnk5KSAQT8u2mUUaU9BysA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 22:16:52 INFO - Running post-action listener: _resource_record_post_action 22:16:52 INFO - [mozharness: 2016-08-08 05:16:52.739795Z] Finished read-buildbot-config step (success) 22:16:52 INFO - [mozharness: 2016-08-08 05:16:52.739884Z] Running download-and-extract step. 22:16:52 INFO - Running pre-action listener: _resource_record_pre_action 22:16:52 INFO - Running main action method: download_and_extract 22:16:52 INFO - mkdir: /builds/slave/test/build/tests 22:16:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:16:52 INFO - trying https://queue.taskcluster.net/v1/task/Rnk5KSAQT8u2mUUaU9BysA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 22:16:52 INFO - Downloading https://queue.taskcluster.net/v1/task/Rnk5KSAQT8u2mUUaU9BysA/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 22:16:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Rnk5KSAQT8u2mUUaU9BysA/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 22:16:53 INFO - Downloaded 1361 bytes. 22:16:53 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 22:16:53 INFO - Using the following test package requirements: 22:16:53 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 22:16:53 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:16:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:16:53 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:16:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:16:53 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:16:53 INFO - u'jsshell-linux-x86_64.zip'], 22:16:53 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:16:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:16:53 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 22:16:53 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:16:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:16:53 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:16:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:16:53 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:16:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:16:53 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:16:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:16:53 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:16:53 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')]} 22:16:53 INFO - trying https://queue.taskcluster.net/v1/task/Rnk5KSAQT8u2mUUaU9BysA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 22:16:53 INFO - Downloading https://queue.taskcluster.net/v1/task/Rnk5KSAQT8u2mUUaU9BysA/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 22:16:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Rnk5KSAQT8u2mUUaU9BysA/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 22:16:54 INFO - Downloaded 20257815 bytes. 22:16:54 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:16:54 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:16:55 INFO - Return code: 0 22:16:55 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 22:16:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:16:55 INFO - trying https://queue.taskcluster.net/v1/task/Rnk5KSAQT8u2mUUaU9BysA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 22:16:55 INFO - Downloading https://queue.taskcluster.net/v1/task/Rnk5KSAQT8u2mUUaU9BysA/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 22:16:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Rnk5KSAQT8u2mUUaU9BysA/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 22:16:56 INFO - Downloaded 20257815 bytes. 22:16:56 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:16:56 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:16:57 INFO - Return code: 0 22:16:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:16:57 INFO - trying https://queue.taskcluster.net/v1/task/Rnk5KSAQT8u2mUUaU9BysA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 22:16:57 INFO - Downloading https://queue.taskcluster.net/v1/task/Rnk5KSAQT8u2mUUaU9BysA/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 22:16:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Rnk5KSAQT8u2mUUaU9BysA/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 22:16:58 INFO - Downloaded 14413723 bytes. 22:16:58 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:16:58 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 22:16:59 INFO - Return code: 0 22:16:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:16:59 INFO - trying https://queue.taskcluster.net/v1/task/Rnk5KSAQT8u2mUUaU9BysA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 22:16:59 INFO - Downloading https://queue.taskcluster.net/v1/task/Rnk5KSAQT8u2mUUaU9BysA/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 22:16:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Rnk5KSAQT8u2mUUaU9BysA/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 22:17:01 INFO - Downloaded 56140008 bytes. 22:17:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Rnk5KSAQT8u2mUUaU9BysA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 22:17:01 INFO - mkdir: /builds/slave/test/properties 22:17:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:17:01 INFO - Writing to file /builds/slave/test/properties/build_url 22:17:01 INFO - Contents: 22:17:01 INFO - build_url:https://queue.taskcluster.net/v1/task/Rnk5KSAQT8u2mUUaU9BysA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 22:17:02 INFO - Running post-action listener: _resource_record_post_action 22:17:02 INFO - Running post-action listener: set_extra_try_arguments 22:17:02 INFO - [mozharness: 2016-08-08 05:17:02.201534Z] Finished download-and-extract step (success) 22:17:02 INFO - [mozharness: 2016-08-08 05:17:02.201768Z] Running populate-webroot step. 22:17:02 INFO - Running pre-action listener: _resource_record_pre_action 22:17:02 INFO - Running main action method: populate_webroot 22:17:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:17:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:17:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:17:02 INFO - '--tppagecycles', 22:17:02 INFO - '7'], 22:17:02 INFO - 'tests': ['tsvgm']}}, 22:17:02 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:17:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:17:02 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:17:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:17:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:17:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:17:02 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:17:02 INFO - 'talos_options': ['--disable-e10s'], 22:17:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:17:02 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:17:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:17:02 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:17:02 INFO - 'talos_options': ['--disable-e10s'], 22:17:02 INFO - 'tests': ['damp', 'tps']}, 22:17:02 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:17:02 INFO - 'tests': ['damp', 'tps']}, 22:17:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:17:02 INFO - 'tests': ['dromaeo_dom']}, 22:17:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:17:02 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:17:02 INFO - 'tests': ['basic_compositor_video']}, 22:17:02 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:17:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:17:02 INFO - 'tests': ['a11yr', 22:17:02 INFO - 'ts_paint', 22:17:02 INFO - 'tpaint', 22:17:02 INFO - 'sessionrestore', 22:17:02 INFO - 'sessionrestore_no_auto_restore', 22:17:02 INFO - 'tabpaint']}, 22:17:02 INFO - 'other-e10s': {'tests': ['a11yr', 22:17:02 INFO - 'ts_paint', 22:17:02 INFO - 'tpaint', 22:17:02 INFO - 'sessionrestore', 22:17:02 INFO - 'sessionrestore_no_auto_restore', 22:17:02 INFO - 'tabpaint']}, 22:17:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:17:02 INFO - 'ts_paint', 22:17:02 INFO - 'tpaint', 22:17:02 INFO - 'sessionrestore', 22:17:02 INFO - 'sessionrestore_no_auto_restore', 22:17:02 INFO - 'tabpaint']}, 22:17:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:17:02 INFO - 'ts_paint', 22:17:02 INFO - 'tpaint', 22:17:02 INFO - 'sessionrestore', 22:17:02 INFO - 'sessionrestore_no_auto_restore', 22:17:02 INFO - 'tabpaint']}, 22:17:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:17:02 INFO - 'tests': ['a11yr', 22:17:02 INFO - 'ts_paint', 22:17:02 INFO - 'tpaint', 22:17:02 INFO - 'sessionrestore', 22:17:02 INFO - 'sessionrestore_no_auto_restore', 22:17:02 INFO - 'tabpaint']}, 22:17:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:17:02 INFO - 'tests': ['a11yr', 22:17:02 INFO - 'ts_paint', 22:17:02 INFO - 'tpaint', 22:17:02 INFO - 'sessionrestore', 22:17:02 INFO - 'sessionrestore_no_auto_restore', 22:17:02 INFO - 'tabpaint']}, 22:17:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:17:02 INFO - 'tests': ['tsvgx', 22:17:02 INFO - 'tsvgr_opacity', 22:17:02 INFO - 'tart', 22:17:02 INFO - 'tscrollx', 22:17:02 INFO - 'cart']}, 22:17:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:17:02 INFO - 'tsvgr_opacity', 22:17:02 INFO - 'tart', 22:17:02 INFO - 'tscrollx', 22:17:02 INFO - 'cart']}, 22:17:02 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:17:02 INFO - 'talos_options': ['--disable-e10s'], 22:17:02 INFO - 'tests': ['tp5o']}, 22:17:02 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:17:02 INFO - 'tests': ['tp5o']}, 22:17:02 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:17:02 INFO - 'talos_options': ['--disable-e10s', 22:17:02 INFO - '--xperf_path', 22:17:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:17:02 INFO - 'tests': ['tp5n']}, 22:17:02 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:17:02 INFO - 'talos_options': ['--xperf_path', 22:17:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:17:02 INFO - 'tests': ['tp5n']}}, 22:17:02 INFO - 'talos.zip': {'path': '', 22:17:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:17:02 INFO - Running post-action listener: _resource_record_post_action 22:17:02 INFO - [mozharness: 2016-08-08 05:17:02.220685Z] Finished populate-webroot step (success) 22:17:02 INFO - [mozharness: 2016-08-08 05:17:02.220777Z] Running create-virtualenv step. 22:17:02 INFO - Running pre-action listener: _resource_record_pre_action 22:17:02 INFO - Running main action method: create_virtualenv 22:17:02 INFO - Creating virtualenv /builds/slave/test/build/venv 22:17:02 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 22:17:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:17:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:17:02 INFO - Using real prefix '/usr' 22:17:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:17:02 INFO - Installing distribute.............................................................................................................................................................................................done. 22:17:04 INFO - Installing pip.................done. 22:17:04 INFO - Return code: 0 22:17:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:17:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:17:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:17:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2480030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25e38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2741180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x273f3b0>, '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 0x27402b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2740740>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1470632736.956772-1408881702', '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 22:17:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:17:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:17:04 INFO - Using env: {'DISPLAY': ':0', 22:17:04 INFO - 'HOME': '/home/cltbld', 22:17:04 INFO - 'LANG': 'en_US.UTF-8', 22:17:04 INFO - 'LANGUAGE': 'en_US:en', 22:17:04 INFO - 'LOGNAME': 'cltbld', 22:17:04 INFO - 'MAIL': '/var/mail/cltbld', 22:17:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:04 INFO - 'MOZ_NO_REMOTE': '1', 22:17:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:17:04 INFO - 'NO_EM_RESTART': '1', 22:17:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:17:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:04 INFO - 'PWD': '/builds/slave/test', 22:17:04 INFO - 'SHELL': '/bin/bash', 22:17:04 INFO - 'SHLVL': '1', 22:17:04 INFO - 'TERM': 'linux', 22:17:04 INFO - 'TMOUT': '86400', 22:17:04 INFO - 'USER': 'cltbld', 22:17:04 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1470632736.956772-1408881702', 22:17:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:04 INFO - '_': '/tools/buildbot/bin/python'} 22:17:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:04 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 22:17:04 INFO - Cleaning up... 22:17:05 INFO - Return code: 0 22:17:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:17: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')]} 22:17:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17: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')]} 22:17:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17: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', '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 0x2480030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25e38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2741180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x273f3b0>, '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 0x27402b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2740740>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1470632736.956772-1408881702', '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 22:17: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', 'psutil>=3.1.1'] in /builds/slave/test/build 22:17: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 psutil>=3.1.1 22:17:05 INFO - Using env: {'DISPLAY': ':0', 22:17:05 INFO - 'HOME': '/home/cltbld', 22:17:05 INFO - 'LANG': 'en_US.UTF-8', 22:17:05 INFO - 'LANGUAGE': 'en_US:en', 22:17:05 INFO - 'LOGNAME': 'cltbld', 22:17:05 INFO - 'MAIL': '/var/mail/cltbld', 22:17:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:05 INFO - 'MOZ_NO_REMOTE': '1', 22:17:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:17:05 INFO - 'NO_EM_RESTART': '1', 22:17:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:17:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:05 INFO - 'PWD': '/builds/slave/test', 22:17:05 INFO - 'SHELL': '/bin/bash', 22:17:05 INFO - 'SHLVL': '1', 22:17:05 INFO - 'TERM': 'linux', 22:17:05 INFO - 'TMOUT': '86400', 22:17:05 INFO - 'USER': 'cltbld', 22:17:05 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1470632736.956772-1408881702', 22:17:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:05 INFO - '_': '/tools/buildbot/bin/python'} 22:17:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:05 INFO - Downloading/unpacking psutil>=3.1.1 22:17:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:17:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:17:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:17:07 INFO - Installing collected packages: psutil 22:17:07 INFO - Running setup.py install for psutil 22:17:07 INFO - building 'psutil._psutil_linux' extension 22:17:07 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 22:17:07 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 22:17:07 INFO - building 'psutil._psutil_posix' extension 22:17:07 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 22:17:07 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 22:17:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:17:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:17:07 INFO - Successfully installed psutil 22:17:07 INFO - Cleaning up... 22:17:07 INFO - Return code: 0 22:17:07 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:17:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:17:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:17:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2480030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25e38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2741180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x273f3b0>, '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 0x27402b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2740740>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1470632736.956772-1408881702', '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 22:17:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 22:17:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 22:17:07 INFO - Using env: {'DISPLAY': ':0', 22:17:07 INFO - 'HOME': '/home/cltbld', 22:17:07 INFO - 'LANG': 'en_US.UTF-8', 22:17:07 INFO - 'LANGUAGE': 'en_US:en', 22:17:07 INFO - 'LOGNAME': 'cltbld', 22:17:07 INFO - 'MAIL': '/var/mail/cltbld', 22:17:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:07 INFO - 'MOZ_NO_REMOTE': '1', 22:17:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:17:07 INFO - 'NO_EM_RESTART': '1', 22:17:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:17:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:07 INFO - 'PWD': '/builds/slave/test', 22:17:07 INFO - 'SHELL': '/bin/bash', 22:17:07 INFO - 'SHLVL': '1', 22:17:07 INFO - 'TERM': 'linux', 22:17:07 INFO - 'TMOUT': '86400', 22:17:07 INFO - 'USER': 'cltbld', 22:17:07 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1470632736.956772-1408881702', 22:17:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:07 INFO - '_': '/tools/buildbot/bin/python'} 22:17:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:07 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:17:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:07 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 22:17:07 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 22:17:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:09 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:17:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:17:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:17:09 INFO - Installing collected packages: mozsystemmonitor 22:17:09 INFO - Running setup.py install for mozsystemmonitor 22:17:09 INFO - Successfully installed mozsystemmonitor 22:17:09 INFO - Cleaning up... 22:17:10 INFO - Return code: 0 22:17:10 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:17:10 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')]} 22:17:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:10 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')]} 22:17:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:10 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 0x2480030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25e38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2741180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x273f3b0>, '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 0x27402b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2740740>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1470632736.956772-1408881702', '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 22:17:10 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 22:17:10 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 22:17:10 INFO - Using env: {'DISPLAY': ':0', 22:17:10 INFO - 'HOME': '/home/cltbld', 22:17:10 INFO - 'LANG': 'en_US.UTF-8', 22:17:10 INFO - 'LANGUAGE': 'en_US:en', 22:17:10 INFO - 'LOGNAME': 'cltbld', 22:17:10 INFO - 'MAIL': '/var/mail/cltbld', 22:17:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:10 INFO - 'MOZ_NO_REMOTE': '1', 22:17:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:17:10 INFO - 'NO_EM_RESTART': '1', 22:17:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:17:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:10 INFO - 'PWD': '/builds/slave/test', 22:17:10 INFO - 'SHELL': '/bin/bash', 22:17:10 INFO - 'SHLVL': '1', 22:17:10 INFO - 'TERM': 'linux', 22:17:10 INFO - 'TMOUT': '86400', 22:17:10 INFO - 'USER': 'cltbld', 22:17:10 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1470632736.956772-1408881702', 22:17:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:10 INFO - '_': '/tools/buildbot/bin/python'} 22:17:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:10 INFO - Downloading/unpacking jsonschema==2.5.1 22:17:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:17:12 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:17:12 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 22:17:12 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 22:17:12 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 22:17:12 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 22:17:12 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 22:17:12 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 22:17:12 INFO - Downloading functools32-3.2.3-2.tar.gz 22:17:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:17:12 INFO - warning: no files found matching '*.txt' 22:17:12 INFO - no previously-included directories found matching 'build' 22:17:12 INFO - no previously-included directories found matching 'dist' 22:17:12 INFO - no previously-included directories found matching '.git*' 22:17:12 INFO - Installing collected packages: jsonschema, functools32 22:17:12 INFO - Running setup.py install for functools32 22:17:12 INFO - warning: no files found matching '*.txt' 22:17:12 INFO - no previously-included directories found matching 'build' 22:17:12 INFO - no previously-included directories found matching 'dist' 22:17:12 INFO - no previously-included directories found matching '.git*' 22:17:12 INFO - Successfully installed jsonschema functools32 22:17:12 INFO - Cleaning up... 22:17:12 INFO - Return code: 0 22:17:12 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:17:12 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')]} 22:17:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:12 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')]} 22:17:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:12 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 0x2480030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25e38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2741180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x273f3b0>, '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 0x27402b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2740740>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1470632736.956772-1408881702', '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 22:17:12 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 22:17:12 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 22:17:12 INFO - Using env: {'DISPLAY': ':0', 22:17:12 INFO - 'HOME': '/home/cltbld', 22:17:12 INFO - 'LANG': 'en_US.UTF-8', 22:17:12 INFO - 'LANGUAGE': 'en_US:en', 22:17:12 INFO - 'LOGNAME': 'cltbld', 22:17:12 INFO - 'MAIL': '/var/mail/cltbld', 22:17:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:12 INFO - 'MOZ_NO_REMOTE': '1', 22:17:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:17:12 INFO - 'NO_EM_RESTART': '1', 22:17:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:17:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:12 INFO - 'PWD': '/builds/slave/test', 22:17:12 INFO - 'SHELL': '/bin/bash', 22:17:12 INFO - 'SHLVL': '1', 22:17:12 INFO - 'TERM': 'linux', 22:17:12 INFO - 'TMOUT': '86400', 22:17:12 INFO - 'USER': 'cltbld', 22:17:12 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1470632736.956772-1408881702', 22:17:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:12 INFO - '_': '/tools/buildbot/bin/python'} 22:17:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:12 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:17:12 INFO - Cleaning up... 22:17:12 INFO - Return code: 0 22:17:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:17:12 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')]} 22:17:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:12 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')]} 22:17:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:12 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 0x2480030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25e38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2741180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x273f3b0>, '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 0x27402b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2740740>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1470632736.956772-1408881702', '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 22:17:12 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 22:17:12 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 22:17:12 INFO - Using env: {'DISPLAY': ':0', 22:17:12 INFO - 'HOME': '/home/cltbld', 22:17:12 INFO - 'LANG': 'en_US.UTF-8', 22:17:12 INFO - 'LANGUAGE': 'en_US:en', 22:17:12 INFO - 'LOGNAME': 'cltbld', 22:17:12 INFO - 'MAIL': '/var/mail/cltbld', 22:17:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:12 INFO - 'MOZ_NO_REMOTE': '1', 22:17:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:17:12 INFO - 'NO_EM_RESTART': '1', 22:17:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:17:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:12 INFO - 'PWD': '/builds/slave/test', 22:17:12 INFO - 'SHELL': '/bin/bash', 22:17:12 INFO - 'SHLVL': '1', 22:17:12 INFO - 'TERM': 'linux', 22:17:12 INFO - 'TMOUT': '86400', 22:17:12 INFO - 'USER': 'cltbld', 22:17:12 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1470632736.956772-1408881702', 22:17:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:12 INFO - '_': '/tools/buildbot/bin/python'} 22:17:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:13 INFO - Downloading/unpacking blobuploader==1.2.4 22:17:13 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 22:17:13 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 22:17:13 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 22:17:13 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 22:17:13 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 22:17:13 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 22:17:15 INFO - Downloading blobuploader-1.2.4.tar.gz 22:17:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:17:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:17:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:17:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:17:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:15 INFO - Downloading docopt-0.6.1.tar.gz 22:17:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:17:15 INFO - Installing collected packages: blobuploader, requests, docopt 22:17:15 INFO - Running setup.py install for blobuploader 22:17:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:17:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:17:15 INFO - Running setup.py install for requests 22:17:16 INFO - Running setup.py install for docopt 22:17:16 INFO - Successfully installed blobuploader requests docopt 22:17:16 INFO - Cleaning up... 22:17:16 INFO - Return code: 0 22:17:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:17:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:17:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:17:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2480030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25e38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2741180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x273f3b0>, '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 0x27402b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2740740>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1470632736.956772-1408881702', '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 22:17:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:17:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:17:16 INFO - Using env: {'DISPLAY': ':0', 22:17:16 INFO - 'HOME': '/home/cltbld', 22:17:16 INFO - 'LANG': 'en_US.UTF-8', 22:17:16 INFO - 'LANGUAGE': 'en_US:en', 22:17:16 INFO - 'LOGNAME': 'cltbld', 22:17:16 INFO - 'MAIL': '/var/mail/cltbld', 22:17:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:16 INFO - 'MOZ_NO_REMOTE': '1', 22:17:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:17:16 INFO - 'NO_EM_RESTART': '1', 22:17:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:17:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:16 INFO - 'PWD': '/builds/slave/test', 22:17:16 INFO - 'SHELL': '/bin/bash', 22:17:16 INFO - 'SHLVL': '1', 22:17:16 INFO - 'TERM': 'linux', 22:17:16 INFO - 'TMOUT': '86400', 22:17:16 INFO - 'USER': 'cltbld', 22:17:16 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1470632736.956772-1408881702', 22:17:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:16 INFO - '_': '/tools/buildbot/bin/python'} 22:17:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:17:16 INFO - Running setup.py (path:/tmp/pip-tQQ9io-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:17:16 INFO - Running setup.py (path:/tmp/pip-BBF6p0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:17:16 INFO - Running setup.py (path:/tmp/pip-oaSCIj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:17:16 INFO - Running setup.py (path:/tmp/pip-GdeXMi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:17:16 INFO - Running setup.py (path:/tmp/pip-ndZPud-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:17:17 INFO - Running setup.py (path:/tmp/pip-bBhw5Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:17:17 INFO - Running setup.py (path:/tmp/pip-XiHjRZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:17:17 INFO - Running setup.py (path:/tmp/pip-HdOcj5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:17:17 INFO - Running setup.py (path:/tmp/pip-UPUHOk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:17:17 INFO - Running setup.py (path:/tmp/pip-sLFY45-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:17:17 INFO - Running setup.py (path:/tmp/pip-mw_H77-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:17:17 INFO - Running setup.py (path:/tmp/pip-0saYcm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:17:17 INFO - Running setup.py (path:/tmp/pip-IH73aO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:17:17 INFO - Running setup.py (path:/tmp/pip-GJB_i1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:17:17 INFO - Running setup.py (path:/tmp/pip-fXREZd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:17:17 INFO - Running setup.py (path:/tmp/pip-kHOME2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:17:17 INFO - Running setup.py (path:/tmp/pip-gMjT2c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:17:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:17:17 INFO - Running setup.py install for manifestparser 22:17:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:17:17 INFO - Running setup.py install for mozcrash 22:17:18 INFO - Running setup.py install for mozdebug 22:17:18 INFO - Running setup.py install for mozdevice 22:17:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:17:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:17:18 INFO - Running setup.py install for mozfile 22:17:18 INFO - Running setup.py install for mozhttpd 22:17:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:17:18 INFO - Running setup.py install for mozinfo 22:17:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:17:18 INFO - Running setup.py install for mozInstall 22:17:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:17:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:17:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:17:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:17:18 INFO - Running setup.py install for mozleak 22:17:18 INFO - Running setup.py install for mozlog 22:17:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:17:18 INFO - Running setup.py install for moznetwork 22:17:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:17:19 INFO - Running setup.py install for mozprocess 22:17:19 INFO - Running setup.py install for mozprofile 22:17:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:17:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:17:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:17:19 INFO - Running setup.py install for mozrunner 22:17:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:17:19 INFO - Running setup.py install for mozscreenshot 22:17:19 INFO - Running setup.py install for moztest 22:17:19 INFO - Running setup.py install for mozversion 22:17:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:17:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:17:19 INFO - Cleaning up... 22:17:19 INFO - Return code: 0 22:17:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:17:19 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')]} 22:17:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:19 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')]} 22:17:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:19 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 0x2480030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25e38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2741180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x273f3b0>, '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 0x27402b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2740740>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1470632736.956772-1408881702', '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 22:17:19 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 22:17:19 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 22:17:19 INFO - Using env: {'DISPLAY': ':0', 22:17:19 INFO - 'HOME': '/home/cltbld', 22:17:19 INFO - 'LANG': 'en_US.UTF-8', 22:17:19 INFO - 'LANGUAGE': 'en_US:en', 22:17:19 INFO - 'LOGNAME': 'cltbld', 22:17:19 INFO - 'MAIL': '/var/mail/cltbld', 22:17:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:19 INFO - 'MOZ_NO_REMOTE': '1', 22:17:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:17:19 INFO - 'NO_EM_RESTART': '1', 22:17:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:17:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:19 INFO - 'PWD': '/builds/slave/test', 22:17:19 INFO - 'SHELL': '/bin/bash', 22:17:19 INFO - 'SHLVL': '1', 22:17:19 INFO - 'TERM': 'linux', 22:17:19 INFO - 'TMOUT': '86400', 22:17:19 INFO - 'USER': 'cltbld', 22:17:19 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1470632736.956772-1408881702', 22:17:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:19 INFO - '_': '/tools/buildbot/bin/python'} 22:17:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:17:19 INFO - Running setup.py (path:/tmp/pip-bR_ccc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:17:19 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)) 22:17:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:17:19 INFO - Running setup.py (path:/tmp/pip-lTmywZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:17:20 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)) 22:17:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:17:20 INFO - Running setup.py (path:/tmp/pip-J3185i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:17:20 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)) 22:17:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:17:20 INFO - Running setup.py (path:/tmp/pip-VWW5xR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:17:20 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)) 22:17:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:17:20 INFO - Running setup.py (path:/tmp/pip-XXgtoj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:17:20 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)) 22:17:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:17:20 INFO - Running setup.py (path:/tmp/pip-uUxkK_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:17:20 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)) 22:17:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:17:20 INFO - Running setup.py (path:/tmp/pip-7KBXKk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:17:20 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)) 22:17:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:17:20 INFO - Running setup.py (path:/tmp/pip-_irqFh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:17:20 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)) 22:17:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:17:20 INFO - Running setup.py (path:/tmp/pip-KeMYCA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:17:20 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)) 22:17:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:17:20 INFO - Running setup.py (path:/tmp/pip-IW__l6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:17:20 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)) 22:17:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:17:20 INFO - Running setup.py (path:/tmp/pip-k0tc6z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:17:20 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)) 22:17:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:17:20 INFO - Running setup.py (path:/tmp/pip-xlqWCE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:17:20 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)) 22:17:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:17:20 INFO - Running setup.py (path:/tmp/pip-ndXQNk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:17:20 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)) 22:17:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:17:20 INFO - Running setup.py (path:/tmp/pip-sQjQPQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:17:20 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)) 22:17:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:17:20 INFO - Running setup.py (path:/tmp/pip-1mesF2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:17:20 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)) 22:17:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:17:20 INFO - Running setup.py (path:/tmp/pip-J3uUjT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:17:21 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)) 22:17:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:17:21 INFO - Running setup.py (path:/tmp/pip-3kxQ66-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:17:21 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)) 22:17:21 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)) 22:17:21 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)) 22:17:21 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)) 22:17:21 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)) 22:17:21 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)) 22:17:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:17:21 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 22:17:21 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 22:17:21 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 22:17:21 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 22:17:21 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 22:17:21 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 22:17:23 INFO - Downloading blessings-1.6.tar.gz 22:17:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:17:23 INFO - Installing collected packages: blessings 22:17:23 INFO - Running setup.py install for blessings 22:17:23 INFO - Successfully installed blessings 22:17:23 INFO - Cleaning up... 22:17:23 INFO - Return code: 0 22:17:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:17:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:17:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:17:23 INFO - Reading from file tmpfile_stdout 22:17:23 INFO - Current package versions: 22:17:23 INFO - argparse == 1.2.1 22:17:23 INFO - blessings == 1.6 22:17:23 INFO - blobuploader == 1.2.4 22:17:23 INFO - docopt == 0.6.1 22:17:23 INFO - functools32 == 3.2.3-2 22:17:23 INFO - jsonschema == 2.5.1 22:17:23 INFO - manifestparser == 1.1 22:17:23 INFO - mozInstall == 1.12 22:17:23 INFO - mozcrash == 0.17 22:17:23 INFO - mozdebug == 0.1 22:17:23 INFO - mozdevice == 0.48 22:17:23 INFO - mozfile == 1.2 22:17:23 INFO - mozhttpd == 0.7 22:17:23 INFO - mozinfo == 0.9 22:17:23 INFO - mozleak == 0.1 22:17:23 INFO - mozlog == 3.3 22:17:23 INFO - moznetwork == 0.27 22:17:23 INFO - mozprocess == 0.23 22:17:23 INFO - mozprofile == 0.28 22:17:23 INFO - mozrunner == 6.12 22:17:23 INFO - mozscreenshot == 0.1 22:17:23 INFO - mozsystemmonitor == 0.3 22:17:23 INFO - moztest == 0.7 22:17:23 INFO - mozversion == 1.4 22:17:23 INFO - psutil == 3.1.1 22:17:23 INFO - requests == 1.2.3 22:17:23 INFO - wsgiref == 0.1.2 22:17:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:17:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:17:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:17:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:23 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 0x2480030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25e38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2741180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x273f3b0>, '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 0x27402b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2740740>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1470632736.956772-1408881702', '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 22:17:23 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 22:17:23 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 22:17:23 INFO - Using env: {'DISPLAY': ':0', 22:17:23 INFO - 'HOME': '/home/cltbld', 22:17:23 INFO - 'LANG': 'en_US.UTF-8', 22:17:23 INFO - 'LANGUAGE': 'en_US:en', 22:17:23 INFO - 'LOGNAME': 'cltbld', 22:17:23 INFO - 'MAIL': '/var/mail/cltbld', 22:17:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:23 INFO - 'MOZ_NO_REMOTE': '1', 22:17:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:17:23 INFO - 'NO_EM_RESTART': '1', 22:17:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:17:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:23 INFO - 'PWD': '/builds/slave/test', 22:17:23 INFO - 'SHELL': '/bin/bash', 22:17:23 INFO - 'SHLVL': '1', 22:17:23 INFO - 'TERM': 'linux', 22:17:23 INFO - 'TMOUT': '86400', 22:17:23 INFO - 'USER': 'cltbld', 22:17:23 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1470632736.956772-1408881702', 22:17:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:23 INFO - '_': '/tools/buildbot/bin/python'} 22:17:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:23 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)) 22:17:23 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)) 22:17:23 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)) 22:17:23 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)) 22:17:23 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)) 22:17:23 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)) 22:17:23 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)) 22:17:23 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)) 22:17:23 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)) 22:17:23 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)) 22:17:23 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)) 22:17:23 INFO - Cleaning up... 22:17:23 INFO - Return code: 0 22:17:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:17:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:17:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:17:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:23 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 0x2480030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25e38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2741180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x273f3b0>, '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 0x27402b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2740740>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1470632736.956772-1408881702', '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 22:17:23 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 22:17:23 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 22:17:23 INFO - Using env: {'DISPLAY': ':0', 22:17:23 INFO - 'HOME': '/home/cltbld', 22:17:23 INFO - 'LANG': 'en_US.UTF-8', 22:17:23 INFO - 'LANGUAGE': 'en_US:en', 22:17:23 INFO - 'LOGNAME': 'cltbld', 22:17:23 INFO - 'MAIL': '/var/mail/cltbld', 22:17:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:23 INFO - 'MOZ_NO_REMOTE': '1', 22:17:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:17:23 INFO - 'NO_EM_RESTART': '1', 22:17:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:17:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:23 INFO - 'PWD': '/builds/slave/test', 22:17:23 INFO - 'SHELL': '/bin/bash', 22:17:23 INFO - 'SHLVL': '1', 22:17:23 INFO - 'TERM': 'linux', 22:17:23 INFO - 'TMOUT': '86400', 22:17:23 INFO - 'USER': 'cltbld', 22:17:23 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1470632736.956772-1408881702', 22:17:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:23 INFO - '_': '/tools/buildbot/bin/python'} 22:17:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:23 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:17:23 INFO - Cleaning up... 22:17:23 INFO - Return code: 0 22:17:23 INFO - Running post-action listener: _resource_record_post_action 22:17:23 INFO - Running post-action listener: _start_resource_monitoring 22:17:23 INFO - Starting resource monitoring. 22:17:23 INFO - [mozharness: 2016-08-08 05:17:23.945266Z] Finished create-virtualenv step (success) 22:17:23 INFO - [mozharness: 2016-08-08 05:17:23.945788Z] Running install step. 22:17:23 INFO - Running pre-action listener: _resource_record_pre_action 22:17:23 INFO - Running main action method: install 22:17:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:17:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:17:24 INFO - Reading from file tmpfile_stdout 22:17:24 INFO - Detecting whether we're running mozinstall >=1.0... 22:17:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:17:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:17:24 INFO - Reading from file tmpfile_stdout 22:17:24 INFO - Output received: 22:17:24 INFO - Usage: mozinstall [options] installer 22:17:24 INFO - Options: 22:17:24 INFO - -h, --help show this help message and exit 22:17:24 INFO - -d DEST, --destination=DEST 22:17:24 INFO - Directory to install application into. [default: 22:17:24 INFO - "/builds/slave/test"] 22:17:24 INFO - --app=APP Application being installed. [default: firefox] 22:17:24 INFO - mkdir: /builds/slave/test/build/application 22:17:24 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'] 22:17:24 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 22:17:40 INFO - Reading from file tmpfile_stdout 22:17:40 INFO - Output received: 22:17:40 INFO - /builds/slave/test/build/application/firefox/firefox 22:17:40 INFO - Running post-action listener: _resource_record_post_action 22:17:40 INFO - [mozharness: 2016-08-08 05:17:40.689147Z] Finished install step (success) 22:17:40 INFO - [mozharness: 2016-08-08 05:17:40.689400Z] Running run-tests step. 22:17:40 INFO - Running pre-action listener: _resource_record_pre_action 22:17:40 INFO - Running main action method: run_tests 22:17:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:17:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:17:40 INFO - Python 2.7.3 22:17:40 INFO - Return code: 0 22:17:40 INFO - grabbing minidump binary from tooltool 22:17: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')]} 22:17:40 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 0x273f3b0>, '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 0x27402b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2740740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:17:40 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 22:17:40 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 22:17:40 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 22:17:40 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:17:40 INFO - Return code: 0 22:17:40 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:17:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:17:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:17:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:17:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:17:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Rnk5KSAQT8u2mUUaU9BysA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--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 22:17:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Rnk5KSAQT8u2mUUaU9BysA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-034 --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 22:17:40 INFO - Using env: {'DISPLAY': ':0', 22:17:40 INFO - 'HOME': '/home/cltbld', 22:17:40 INFO - 'LANG': 'en_US.UTF-8', 22:17:40 INFO - 'LANGUAGE': 'en_US:en', 22:17:40 INFO - 'LOGNAME': 'cltbld', 22:17:40 INFO - 'MAIL': '/var/mail/cltbld', 22:17:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:17:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:17:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:40 INFO - 'MOZ_NO_REMOTE': '1', 22:17:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:17:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:17:40 INFO - 'NO_EM_RESTART': '1', 22:17:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:17:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:40 INFO - 'PWD': '/builds/slave/test', 22:17:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:17:40 INFO - 'SHELL': '/bin/bash', 22:17:40 INFO - 'SHLVL': '1', 22:17:40 INFO - 'TERM': 'linux', 22:17:40 INFO - 'TMOUT': '86400', 22:17:40 INFO - 'USER': 'cltbld', 22:17:40 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1470632736.956772-1408881702', 22:17:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:40 INFO - '_': '/tools/buildbot/bin/python'} 22:17:40 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Rnk5KSAQT8u2mUUaU9BysA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--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 22:17:40 INFO - mozversion application_buildid: 20160807205552 22:17:40 INFO - mozversion application_changeset: 513d42e7a166bab34785c7f727704bf8d32c1a36 22:17:40 INFO - mozversion application_display_name: Nightly 22:17:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:17:40 INFO - mozversion application_name: Firefox 22:17:40 INFO - mozversion application_remotingname: firefox 22:17:40 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 22:17:40 INFO - mozversion application_vendor: Mozilla 22:17:40 INFO - mozversion application_version: 51.0a1 22:17:40 INFO - mozversion platform_buildid: 20160807205552 22:17:40 INFO - mozversion platform_changeset: 513d42e7a166bab34785c7f727704bf8d32c1a36 22:17:40 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 22:17:40 INFO - mozversion platform_version: 51.0a1 22:17:40 INFO - using testdate: 1470633460 22:17:40 INFO - actual date: 1470633460 22:17:40 INFO - starting webserver on 'localhost:34636' 22:17:40 INFO - SUITE-START | Running 2 tests 22:17:40 INFO - TEST-START | dromaeo_css 22:17:40 INFO - operating with platform_type : linux_ 22:17:40 INFO - Initialising browser for dromaeo_css test... 22:17:40 INFO - TEST-INFO | started process 25756 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpuycKro/profile http://localhost:34636/getInfo.html) 22:17:45 INFO - PROCESS | 25756 | __metrics Screen width/height:1600/1200 22:17:45 INFO - PROCESS | 25756 | colorDepth:24 22:17:45 INFO - PROCESS | 25756 | Browser inner width/height: 1024/697 22:17:45 INFO - PROCESS | 25756 | __metrics 22:17:46 INFO - PROCESS | 25756 | 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" 22:17:46 INFO - PROCESS | 25756 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:17:47 INFO - TEST-INFO | 25756: exit 0 22:17:47 INFO - Browser initialized. 22:17:47 INFO - Running cycle 1/1 for dromaeo_css test... 22:17:47 INFO - TEST-INFO | started process 25837 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpuycKro/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:17:59 INFO - PROCESS | 25837 | RSS: Main: 157921280 22:17:59 INFO - PROCESS | 25837 | 22:18:51 INFO - PROCESS | 25837 | 1470633531404 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] 22:21:03 INFO - PROCESS | 25837 | Cycle 1(1): loaded http://localhost:34636/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34636/tests/dromaeo/cssquery-ext.html) 22:21:04 INFO - PROCESS | 25837 | RSS: Main: 242814976 22:21:04 INFO - PROCESS | 25837 | 22:24:06 INFO - PROCESS | 25837 | Cycle 1(1): loaded http://localhost:34636/tests/dromaeo/cssquery-ext.html (next: http://localhost:34636/tests/dromaeo/cssquery-jquery.html) 22:24:07 INFO - PROCESS | 25837 | RSS: Main: 240111616 22:24:07 INFO - PROCESS | 25837 | 22:27:09 INFO - PROCESS | 25837 | Cycle 1(1): loaded http://localhost:34636/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34636/tests/dromaeo/cssquery-mootools.html) 22:27:10 INFO - PROCESS | 25837 | RSS: Main: 222531584 22:27:10 INFO - PROCESS | 25837 | 22:30:12 INFO - PROCESS | 25837 | Cycle 1(1): loaded http://localhost:34636/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34636/tests/dromaeo/cssquery-prototype.html) 22:30:12 INFO - PROCESS | 25837 | RSS: Main: 186814464 22:30:12 INFO - PROCESS | 25837 | 22:33:15 INFO - PROCESS | 25837 | Cycle 1(1): loaded http://localhost:34636/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34636/tests/dromaeo/cssquery-yui.html) 22:33:16 INFO - PROCESS | 25837 | RSS: Main: 186703872 22:33:16 INFO - PROCESS | 25837 | 22:36:19 INFO - PROCESS | 25837 | Cycle 1(1): loaded http://localhost:34636/tests/dromaeo/cssquery-yui.html (next: http://localhost:34636/tests/dromaeo/cssquery-dojo.html) 22:36:19 INFO - PROCESS | 25837 | RSS: Main: 277975040 22:36:19 INFO - PROCESS | 25837 | 22:36:19 INFO - PROCESS | 25837 | __start_tp_report 22:36:19 INFO - PROCESS | 25837 | _x_x_mozilla_page_load 22:36:19 INFO - PROCESS | 25837 | _x_x_mozilla_page_load_details 22:36:19 INFO - PROCESS | 25837 | |i|pagename|runs| 22:36:19 INFO - PROCESS | 25837 | |0;dojo.html;2597.194869590427;2625.094342451854;2631.605259211117;2661.9600705989415;2666.5866790647456;4567.817287308504;4605.263157894738;4709.3171490133955;4722.858314250574;4739.739314337714;4669.766511674411;4703.341532185496;4714.4106986626675;4715.976420117903;4751.287306903966;4268.807903644328;4278.61492465678;4360.36774667673;4409.338599210112;4483.708558943658;4394.7143435676735;4430.26900561407;4459.487158976711;4477.664175186857;4483.7758112094425;4210.873673789792;4242.851500197478;4350;4357.498887627931;4419.292913133899;4245.150969806051;4264.616184543375;4276.166147601219;4333.06839029607;4351.43431353922;4239.152169566069;4277.572242775728;4304.3543468479975;4319.4816622005565;4338.739675619473;4533.977330113361;4710.69380490267;4728.196206644879;4764.332993380929;4771.6421268404665;5029.723365214912;5070.08738427083;5076.923846142311;5087.465816089331;5144.459831717653;4999.9500004999845;5105.540501354859;5181;5185.429602743695;5186.559142472895;4573.63410927125;4673.135469938073;4764.2615394613895;4809.567138957441;4823.155947709137;5142.717150556755;5232.607554433356;5259.184826351921;5265.657732247391;5269.578433725293;5009.7495125243595;5011.599072074298;5051.368578927634;5056.696598204119;5060.291559181717;5080.060188865072;5090.211017292326;5126.66676666023;5133.4096578893905;5139;4818.518148185153;4884.560389564886;4981.42713587942;4990.875228119254;5071.416787069535;4993.700377977333;5003.199488081962;5005.874853128701;5009.499050095034;5055.772490237948;4257.5529569395385;4263.381809637585;4272.166927449118;4276.401303817468;4310.353446982915;3662.340778659867;3664.7617904836634;3702;3706.3884459064493;3746.85012599493;4371.75955322454;4398.296272596369;4398.538153493837;4434.024514606781;4443.7111587746695;5061.392632884077;5081.060003899216;5082.415522214918;5084.694918304839;5125.8718532037;4205.558416366299;4241.533431322552;4333;4382;4388.27593447084;4546.499885012646;4564.771761411983;4594.150082234731;4602.263637817933;4608.239640459286;4782.450018247946;4794.640401969797;4796.616270698406;4797.040591881568;4802.975985120052;4911.926321105115;4989.55094041538;5014.874628134326;5105.055564720538;5105.948940510547;4749.430068391815;4754.334393185026;4763.214069678603;4780.760961951958;4783.880402989814;4972.7762250699525;4973.47778483254;4976.552110310089;4980.676256043491;5028.145215313332;4868.099401610713;5009.624278179078;5041.294218809443;5059.089363914531;5090.643654944267;4905.705657660552;4912.582430493305;4913.533214344631;4939.110960027229;4944.060628480577;4901.980356144711;4910.975445122752;4920.31115643803;4926.334944782552;4964.106460837084;4646.790894409692;4780.067886762048;4800.231962885921;4872.780724867461;4922.950770492249;4915.21356582945;4934.679245849008;4939.061578300111;4964.4539100698175;4994.425641051181;4819.638527110411;4823.879403014953;4825.782839772149;4831.855044348676;4874.390701162355 22:36:19 INFO - PROCESS | 25837 | |1;ext.html;548.6845064088147;561.5199004850855;580.3065336922378;587.8971180043492;596.7105953612498;28424.147275581712;31745;31746.682533174662;40110;41212.38181427281;41297.17405651885;41564.584354156446;41956.160876782444;42004;42012.159756804846;7749.651265693044;7897.289243968042;7898.605069746518;7903.36773058154;7908.604569771517;7416.221296763843;9862.211023118169;10043.045910638477;10045.64840230592;10371.170306375472;10128.493575321241;10299.382037077761;10307.587696492132;10311.329763565382;10312.742181445486;10047.899521004769;10099.596016159345;10102.191824654083;10121.848172277421;10124.29129960903;9994.750131246705;10009.947960668045;10049.598016079348;10062.345947513388;10079.59681612742;38706.41940370897;38858.80570597129;38997;39045;39068.21863562741;3052.6184226971627;3139.7645176611845;3221.6617255188335;3467.861285548575;3731.4589384539363;28097.43805123886;29604.851975739984;29780.70219297801;30065.69934300651;31097.37805243905;2760.2409337432164;2763.903263385772;2787.038471727251;2814.5074611942828;2898.463784199909;238080;238876.80561597255;239030;240733;240924;54505.18242226291;54677.45322546803;54744;54836.17745733817;54887.17669234965;33573.49639755406;34367;34449.13877153091;36305.818470908;51943;14965.92517037408;15105.3202605883;15163.166025868682;15185.936984410986;15196.772048419283;9589.328746987643;10279.640212592523;10280.07479326864;10314.071733544017;10324.328918620266;18203.45389638313;18767.436976890716;18821;19373.806261937483;19455.708164377545;7150.676030290072;7158.2841715828;7244.891326630105;7280.6723697433745;7286.927130728731;834.407570624851;877.929765618749;906.7577418935967;989.9109080182818;1043.0977204717865;28055.71944280531;28117.156485305473;28757.137285881458;28796.280092997422;28962.855185723936;943.7640589852537;956.3305686019814;982.597135174575;1002.9719537474148;1033.8397548380012;14676.633084172981;15228.238588070551;15299.61750956235;15429.922850385901;15473.690526189414;393944;394239;394911;396053;398734;50008.499915000386;50938;51015.234771478456;51075;51106.488935110174;1086.0768346905224;1126.9380184089953;1129.102363620907;1177.735009622828;1200.1598880783313;26287.868560657073;27857.303567410978;30428.087157385315;30440.543391849584;30454.390912182076;44904;45636;45651;45652.771736141105;45703.542964569926;351591;356773;357055;357336;357706;38865;39479.80260098681;42743.14513709771;43387.78306108449;43562;42943.57056429521;42979.785101074296;43170.352444713986;43207.78396108125;43850;36045.00044643333;36256.63743362533;36435.63564364429;36507.452388214726;37710.24579508449;36302.27395452129;37153.62846371502;37550.624493754716;37606.81196594109;38125.80937095297;35079.298414031065;35134.812099721414;36227.637723623484;36457.63542364542;36644.26711465809;31562.842185789843;33462.996110116736;34033.14917127092;34103.82948085244;34345.313093738485;25204.243872683848;25286.873565632053;25637;25670.61494077553;26045.869770651025 22:36:19 INFO - PROCESS | 25837 | |2;jquery.html;331.65342217382835;350.0950044135907;353.54569378348816;356.71641045368926;377.1043771043771;12606.243625382473;17174.39889603864;17693.38073167441;18274;18419.07904604771;15427.151506667129;16290.51128466145;16331.59171020725;16362.100084495349;16518.25667844947;4970.130227210242;5044.142495775727;5078.238264260353;5112.539871411572;5116.846494605149;3697.704183665314;5028.321176641143;5118.283440318359;5732.5127364174095;6036.275646922375;6084.08738689198;6104.786332478364;6107.5113990880845;6108.938910610881;6111.724972376232;5937.732802023897;5960.1357803118735;6082.604630699013;6109.969450152766;6123.693815309217;5185.377754669464;5340.305760251182;5343.198520221998;5345.09133447311;5347.946520534783;21267.46831329229;21613.891930540405;21629;21740.782592174033;21747.891260543754;1887.2073729033839;1948.8635795494322;2018.5155562665002;2137.4015275722822;2195.648696208615;13391.397387117602;13601.659958501017;13605.659858503517;13607.387667554984;14350.425982960669;1702.6850032743982;1746.4062218845527;1756.3062590276897;1759.2786957347555;1793.4440323499757;69728;72676.63661681658;73116;73443.26556734418;73939.63030184868;9202.549044108458;9247.537623118951;9475.620975160917;9485.478298693639;9826.9017309826;13253.867461325262;13261.403236854172;13279.136856104322;13297.401616927262;13379.665508362368;6899.17209934811;7140;7243.927560724431;7374.446916481178;7379.631018449056;6933.479989000845;6952;6962;7000.299970002959;7224.277572242839;6032.577719559677;6126.754929802847;6158.384161583896;6173.753049877954;6325.810225693236;1716.8025677047299;1724.0862342958253;1727.5594723345719;1750.6673731990882;1751.816059313779;707.4906067631297;895.0333639669142;904.2494729374604;917.1012407840353;936.8688383626297;17454.56363590895;17643.382481613084;18407.447776566725;18462.81537184611;18816.153273102744;879.4503435352905;885.1016218538189;890.3589415620743;899.739075668062;928.5218112671979;9931.751706207402;10507.211959103099;10548.208884333706;10646.627368042082;10728.624498142528;399818;403612;413958;414072;414367;43285.78357108194;48650.756746216044;73940.26059739334;74368.62815685887;75867.24132758602;890.6303883888244;935.0929598289648;954.9474778887228;957.3537861943161;960.0687333286701;15698.843011569737;15798.21008949571;16202.837971620604;16234.074562784735;16458;36741.26517469689;37271;37702.811485942395;38020.23959520849;38241.80879095587;47479.287810683556;49239.75380123076;50168.74915625399;50202.49797501978;51259.92440453585;4913.263010548446;5046.444891061907;7330.010448589373;7524.134724506533;7790.9220907790195;10744.409057501913;10990.120790336914;11334.94332528332;11664.825027624423;12264.816027759769;9709.029097090235;9735.075167859042;9777.853332200166;9992.550335234786;10259.640912568024;10204.744881378025;10284.2801003932;10342.638007669695;10651.254412191072;10869.402182880018;4994.850154495371;6248.593841400295;7912.762617121495;9965.202783777426;10100.074682598954;7661.463697541119;7715.729949451967;7783.610819459118;7900.284026245431;8037.879431808645;8852.291816654524;9430.292728045286;9482.194013508653;9500.524973751422;10003.14973227284 22:36:19 INFO - PROCESS | 25837 | |3;mootools.html;1401.7266633006564;1425.1805212003098;1509.4415066425424;1691.4587332053748;1731.6017316017317;7321.560706357615;7865.842683146334;7866.567338796364;7871.881921771178;7874.448788584801;6316.557840951135;7507.549547027183;7752.457327987043;7795.688172473095;7803.648835802388;2134.2743467221144;2171.695962565242;2172.1311475409802;2173.3805865328304;2176.0534167637047;2129.7976692214293;2169.69624252604;2191.5726433345503;2192.967105493411;2194.484296190394;1771.4508502364308;1829.1311626977213;1837.7519034930315;1840.6410749903707;2088.8015638514394;2130.659094544873;2161.3732017714765;2162.264829957822;2168.3711723600136;2168.9783102168935;2169.5986242545023;2197.0772275644267;2198.7251593550805;2206.5476577301615;2209.3151123151874;7891.565963871984;7909.050913890313;7935.206479352077;7944.841103177904;7946.920530794676;4251.149770045972;4441.555844415565;4442.62237709795;4448.933266001012;4448.977755111204;2611.308003379106;2739.9589006164924;2773.875175617082;2789.846558439285;2800.1739486851225;864.0409145848104;869.1091631078195;871.0462044061805;879.692107762278;901.1619194149439;10480.423576703279;10480.895191048068;10497.4226417547;10502.527386267637;10505.107065899409;3760.8871733848027;3955.802209889494;4085.6527195188146;4096.672266218696;4096.815643296059;8933.374663773473;8936.70417789417;8942.687005954891;8946.731598052069;8953.238974687094;4357.237483440385;4395.164918665443;4395.472543294761;4395.494518130456;4395.7362558246605;3947.9013024674036;4007.2987227235117;4030.5969403059457;4032.4757781488806;4038.6163314485075;3532.2582257726167;3590.317839610466;3647.304003638321;3693.261347730465;3775.9433608495897;1067.8611780468489;1074.5164675895721;1076.4348716923678;1081.464674985887;1117.972050698739;728.8760910645203;826.6032304493876;830.5307501261768;841.886345343383;846.4921047371528;4981.452040275567;5008.348914641073;5065.974670126626;5107.438181800073;5126.718030508283;398.5775078416887;851.4848516647463;939.1735272959752;953.5613617735919;961.6680896957764;3360.12636714456;3610.097475631092;3780.981095094507;3807.295650304647;3840.423936409561;8988.370814042953;9040.547972601344;9148;9167.908320916838;9168.220701240336;9103;9109.954450227706;9114.589843456928;9117.225035871892;9117.589708463134;6801.4558835293055;7100.5029647925185;7270.600116993616;7273;7275.454340924452;5037.219231019198;5258.395284542174;5327.706976116351;5356.946430535645;5385.488378604026;8459;8679.522626255364;8687.956560217159;8703.43427677199;8731.69439069655;8774.12258774143;8808.251298639687;8842.690505832265;8866.290696744374;8905.599248033725;6757.121574195323;6873.415759660485;6874.381305682513;6875.07186529812;6876.17485901695;7006.264342244195;7022.157341119098;7050.330218629222;7063.929360706327;7122.60825654594;6441.613503189824;6458.741650334122;6477.1579694639395;6479.935200647933;6496.0255961606135;6252.624842509464;6262.4990000800735;6311.621302721851;6435.066915297162;6446.419822216023;6017.548683848817;6034.185384973148;6071.907056729654;6097.573169878244;6104.969475152596;4387.473503179639;4449;4517.164324599828;4534.2065138599955;4629.93055104167;4615.907681846412;4718.079974404958;4742.07529531738;4756.762161891961;4801.207800712983 22:36:19 INFO - PROCESS | 25837 | |4;prototype.html;283.66684023903395;288.04800135551994;292.5042053718946;293.6182962149207;293.9970600293996;4871.512848715127;4899.657024008312;4901.411830580327;4924.1382758017335;4928.162212423897;4811.182099043162;4816.205326121186;4872.853814385574;4892.192788189945;4898.8775280618;3820.9044773880737;3836.462895194675;3837.539495260559;3842.405401758274;3872.244912242114;3600.261946301015;3703.1112532992024;3855.556610989744;3870.322693528635;3881;3203.423383790917;3598.4062629666205;3633.3278343506404;3649.708023358125;3693.1874987502842;3617.801020943847;3725.9254814903684;3736.215394767102;3746.2132952079824;3753.11801726594;3294.1764558860286;3301.752368572367;3309.271960168783;3309.6430463509846;3309.983450082734;4480.910381792379;4522.23122069249;4698.483166851644;4720.221163508017;4726.9600687848615;4273.50427350428;4274.316109422509;4276.144771045803;4282.807273672693;4328.826846926119;5033.949660503421;5044.646874718771;5094.286799848024;5097.617678674114;5116.309298244727;3508.859645614172;3572.8570857165682;3599.9100022499647;3613.1870329175986;3613.313470440608;5203.947960520384;5208.348956380452;5208.8437346879655;5210.791568337262;5217.373915130209;3688.0779805048737;3689.704823614158;3708.5721997030796;3741.5884252732176;3747.2318174774105;3652.6712595866497;3762.2663580602066;3763.7553559018575;3783.886483405502;3793.0327766419387;4230;4238.0888109056705;4240.660747140221;4276.957230427718;4301.720388174821;4965.950340496621;4978.2034874419915;4985.9501404985485;4994.525520075587;4996.625253106032;1263.9052071094704;1277.757226127033;1280.866433206619;1282.499825068224;1282.923024618526;818.1246066708684;823.5141266652704;824.9564301159039;831.8793774902605;841.7895526118471;3921.843126274974;3971.6822654187595;3997.2804895119157;3998.9200215996098;4002.639102705036;4561.384213131251;4572.291294849304;4575.0197328324575;4580.473245576734;4596;3932.05363345146;3951.5258169019903;3978.5026871641044;4044.8472185427013;4058.3766493402286;2365.8107351411845;2433.549793288247;2448.449098952721;2462.9014839406223;2476.418041760185;4344.891377715519;4352.020795321091;4369.875538989574;4383.268608899531;4707.717536947725;5192.3769147702515;5205;5222.321098257202;5271.815486457956;5275.947240527622;4420.226460369422;4835.661503694778;4851.58761505276;4882.780274887638;4894.898647804215;5153.819616313411;5156.845294641167;5177.249298851721;5178.6374953752875;5184.807494276413;5005.749712514433;5089.312942752829;5105.5149760772665;5109.233614957786;5118.513741194581;4680.836170734008;4873.439554451284;4907.68100073494;4917.336159618549;4968.950310496849;3605.9278814424088;3632.800195989246;3654.780713157219;3661.743677942625;3685.797281149455;5280.841574752763;5302.125149350468;5304.711079130963;5310.389305229948;5330.387005494418;4963.851084467472;5036.748162591929;5037.319961805256;5043.613006423263;5059.418166910852;5044.394672639307;5050.469700681376;5054.74726263678;5064.569511591409;5065.024640390177;4996.975015125046;5002.149634562061;5033.622478314214;5038.420581633159;5048;4843.200871856246;5071.594272458268;5091.821786237612;5101.3113229715;5101.438841727333;4936.259561065869;4939.0243902439315;4943.728094954668;4974.37820272466;5019.472955339783 22:36:19 INFO - PROCESS | 25837 | |5;yui.html;568.8236646639541;590.0824218340481;631.5358211714392;674.0563211503894;682.3040498691338;11524.942375288123;12427.13010089294;12490.81263781044;12517.061220408483;12988.480460781557;13680.384382702778;13791.586252412419;13820.792688109661;13825.654358641039;13845.169289842592;632.9493640508771;751.1624538639405;768.7808974442285;770.0220719685996;771.2595907928395;743.8698227810139;765.406809722464;769.9076110866675;775.2983549887362;775.7595145504883;822.2599660305713;823.6375994562374;823.8475881961826;824.0646865807322;824.381713714714;661.877552652756;818.1899919080128;818.6725309876067;818.8567000774871;819.5451524403953;575.1372940589116;588.8262962426095;590.1236663554661;590.2297501760202;592.2478452365472;12580.68548286291;12626.936865315614;12629.305388203646;12649.304288264142;12659.936700316532;12182.329971851545;12623.116381853182;12823.935880320632;12984;13083;12057.758844823142;12147.696307592381;12249.693757656041;12319.93840030803;13014.34928253593;4032.8374033283144;4063.4920634920636;4143.1713657268665;4144.171165766828;4219.43037689911;65645.3435465644;65806.3419365805;66198.33801662018;66813.3318666812;67621.32378676199;9528.714138575853;9594.952025239829;9650.855237171449;9707.271954603433;9899.1585715215;17382.826171738372;17403.825961740473;17409.47771566855;17422.128893555273;17485;6712.160979877515;6758.966205169041;6825.044493770876;6847.21257055435;6858.759943401957;3714.57282412526;3770.792606406674;3795.221979494197;3841;3847.3844184930836;2717.9728202718115;2814.746672799458;2817.830930144157;2819.8590070496393;2820.830750154956;466.22141024489184;468.6133939499926;472.94324681038495;486.7517566041344;489.9804007839718;418.06353767560444;480.48588010828774;493.9481354457802;497.6839706786217;517.720430967281;7005.264447233069;7074.610896400747;7151.922135283902;7200.315969982843;7366.394317580985;484.022275004496;491.5404097169158;498.6783524626596;512.3288492075393;517.0537915614492;3492.1269682579355;3551.698105660996;3652.433872749728;3680.0615842959874;3687.225682606629;56479;70106.64946675336;97105;109775;110016;94581;94864.52567737117;94896;95181.52409238048;95532.52233738925;49335;76054.61972690212;76461.61769191119;79595;85571;6983.231844497;7142.357187853118;7220.783376498714;7256.637168141467;7353.77938661841;33778;35697.643023569435;46504.53495465002;48473;49017;53167;54003.45996540142;56743.71628141833;59772.70113649404;60511.69744151251;8723.956380218058;8864;9237.491937943209;10743.516541755796;11920.880791191978;9840.852387214341;9863.112319891245;10023.74940626461;10030.548625311732;10498.895011049792;9641.662541811284;9665.468399237829;9781.657641982496;9922.355046921926;10187.185025198114;11537.942310288396;11611.6516504505;11974.161808673658;12009.519619215134;12638.494460221487;11030.22788404804;11226.494807733508;11403.657890263305;11423;11427.714307142389;9154.267658587163;9335.25317974574;9501.42991420517;9597.040295970348;9835.409875407498;10099.920063948959;10919.39943303126;10943.781124377627;11067.280626759235;11077.335359878432 22:36:19 INFO - PROCESS | 25837 | __end_tp_report 22:36:19 INFO - PROCESS | 25837 | __start_cc_report 22:36:19 INFO - PROCESS | 25837 | _x_x_mozilla_cycle_collect,904 22:36:19 INFO - PROCESS | 25837 | __end_cc_report 22:36:19 INFO - PROCESS | 25837 | __startTimestamp1470634579708__endTimestamp 22:36:19 INFO - PROCESS | 25837 | 22:36:19 INFO - PROCESS | 25837 | ------- Summary: start ------- 22:36:19 INFO - PROCESS | 25837 | Number of tests: 6 22:36:19 INFO - PROCESS | 25837 | 22:36:19 INFO - PROCESS | 25837 | [#0] dojo.html Cycles:165 Average:4670.01 Median:4796.62 stddev:497.38 (10.4%) stddev-sans-first:471.57 22:36:19 INFO - PROCESS | 25837 | Values: 2597.2 2625.1 2631.6 2662.0 2666.6 4567.8 4605.3 4709.3 4722.9 4739.7 4669.8 4703.3 4714.4 4716.0 4751.3 4268.8 4278.6 4360.4 4409.3 4483.7 4394.7 4430.3 4459.5 4477.7 4483.8 4210.9 4242.9 4350.0 4357.5 4419.3 4245.2 4264.6 4276.2 4333.1 4351.4 4239.2 4277.6 4304.4 4319.5 4338.7 4534.0 4710.7 4728.2 4764.3 4771.6 5029.7 5070.1 5076.9 5087.5 5144.5 5000.0 5105.5 5181.0 5185.4 5186.6 4573.6 4673.1 4764.3 4809.6 4823.2 5142.7 5232.6 5259.2 5265.7 5269.6 5009.7 5011.6 5051.4 5056.7 5060.3 5080.1 5090.2 5126.7 5133.4 5139.0 4818.5 4884.6 4981.4 4990.9 5071.4 4993.7 5003.2 5005.9 5009.5 5055.8 4257.6 4263.4 4272.2 4276.4 4310.4 3662.3 3664.8 3702.0 3706.4 3746.9 4371.8 4398.3 4398.5 4434.0 4443.7 5061.4 5081.1 5082.4 5084.7 5125.9 4205.6 4241.5 4333.0 4382.0 4388.3 4546.5 4564.8 4594.2 4602.3 4608.2 4782.5 4794.6 4796.6 4797.0 4803.0 4911.9 4989.6 5014.9 5105.1 5105.9 4749.4 4754.3 4763.2 4780.8 4783.9 4972.8 4973.5 4976.6 4980.7 5028.1 4868.1 5009.6 5041.3 5059.1 5090.6 4905.7 4912.6 4913.5 4939.1 4944.1 4902.0 4911.0 4920.3 4926.3 4964.1 4646.8 4780.1 4800.2 4872.8 4923.0 4915.2 4934.7 4939.1 4964.5 4994.4 4819.6 4823.9 4825.8 4831.9 4874.4 22:36:19 INFO - PROCESS | 25837 | 22:36:19 INFO - PROCESS | 25837 | [#1] ext.html Cycles:180 Average:48919.46 Median:193362.91 stddev:88684.78 (45.9%) stddev-sans-first:88859.20 22:36:19 INFO - PROCESS | 25837 | Values: 548.7 561.5 580.3 587.9 596.7 28424.1 31745.0 31746.7 40110.0 41212.4 41297.2 41564.6 41956.2 42004.0 42012.2 7749.7 7897.3 7898.6 7903.4 7908.6 7416.2 9862.2 10043.0 10045.6 10371.2 10128.5 10299.4 10307.6 10311.3 10312.7 10047.9 10099.6 10102.2 10121.8 10124.3 9994.8 10009.9 10049.6 10062.3 10079.6 38706.4 38858.8 38997.0 39045.0 39068.2 3052.6 3139.8 3221.7 3467.9 3731.5 28097.4 29604.9 29780.7 30065.7 31097.4 2760.2 2763.9 2787.0 2814.5 2898.5 238080.0 238876.8 239030.0 240733.0 240924.0 54505.2 54677.5 54744.0 54836.2 54887.2 33573.5 34367.0 34449.1 36305.8 51943.0 14965.9 15105.3 15163.2 15185.9 15196.8 9589.3 10279.6 10280.1 10314.1 10324.3 18203.5 18767.4 18821.0 19373.8 19455.7 7150.7 7158.3 7244.9 7280.7 7286.9 834.4 877.9 906.8 989.9 1043.1 28055.7 28117.2 28757.1 28796.3 28962.9 943.8 956.3 982.6 1003.0 1033.8 14676.6 15228.2 15299.6 15429.9 15473.7 393944.0 394239.0 394911.0 396053.0 398734.0 50008.5 50938.0 51015.2 51075.0 51106.5 1086.1 1126.9 1129.1 1177.7 1200.2 26287.9 27857.3 30428.1 30440.5 30454.4 44904.0 45636.0 45651.0 45652.8 45703.5 351591.0 356773.0 357055.0 357336.0 357706.0 38865.0 39479.8 42743.1 43387.8 43562.0 42943.6 42979.8 43170.4 43207.8 43850.0 36045.0 36256.6 36435.6 36507.5 37710.2 36302.3 37153.6 37550.6 37606.8 38125.8 35079.3 35134.8 36227.6 36457.6 36644.3 31562.8 33463.0 34033.1 34103.8 34345.3 25204.2 25286.9 25637.0 25670.6 26045.9 22:36:19 INFO - PROCESS | 25837 | 22:36:19 INFO - PROCESS | 25837 | [#2] jquery.html Cycles:180 Average:24667.63 Median:50185.62 stddev:67263.87 (134.0%) stddev-sans-first:67427.74 22:36:19 INFO - PROCESS | 25837 | Values: 331.7 350.1 353.5 356.7 377.1 12606.2 17174.4 17693.4 18274.0 18419.1 15427.2 16290.5 16331.6 16362.1 16518.3 4970.1 5044.1 5078.2 5112.5 5116.8 3697.7 5028.3 5118.3 5732.5 6036.3 6084.1 6104.8 6107.5 6108.9 6111.7 5937.7 5960.1 6082.6 6110.0 6123.7 5185.4 5340.3 5343.2 5345.1 5347.9 21267.5 21613.9 21629.0 21740.8 21747.9 1887.2 1948.9 2018.5 2137.4 2195.6 13391.4 13601.7 13605.7 13607.4 14350.4 1702.7 1746.4 1756.3 1759.3 1793.4 69728.0 72676.6 73116.0 73443.3 73939.6 9202.5 9247.5 9475.6 9485.5 9826.9 13253.9 13261.4 13279.1 13297.4 13379.7 6899.2 7140.0 7243.9 7374.4 7379.6 6933.5 6952.0 6962.0 7000.3 7224.3 6032.6 6126.8 6158.4 6173.8 6325.8 1716.8 1724.1 1727.6 1750.7 1751.8 707.5 895.0 904.2 917.1 936.9 17454.6 17643.4 18407.4 18462.8 18816.2 879.5 885.1 890.4 899.7 928.5 9931.8 10507.2 10548.2 10646.6 10728.6 399818.0 403612.0 413958.0 414072.0 414367.0 43285.8 48650.8 73940.3 74368.6 75867.2 890.6 935.1 954.9 957.4 960.1 15698.8 15798.2 16202.8 16234.1 16458.0 36741.3 37271.0 37702.8 38020.2 38241.8 47479.3 49239.8 50168.7 50202.5 51259.9 4913.3 5046.4 7330.0 7524.1 7790.9 10744.4 10990.1 11334.9 11664.8 12264.8 9709.0 9735.1 9777.9 9992.6 10259.6 10204.7 10284.3 10342.6 10651.3 10869.4 4994.9 6248.6 7912.8 9965.2 10100.1 7661.5 7715.7 7783.6 7900.3 8037.9 8852.3 9430.3 9482.2 9500.5 10003.1 22:36:19 INFO - PROCESS | 25837 | 22:36:19 INFO - PROCESS | 25837 | [#3] mootools.html Cycles:180 Average:5009.56 Median:5117.08 stddev:2798.13 (54.7%) stddev-sans-first:2792.85 22:36:19 INFO - PROCESS | 25837 | Values: 1401.7 1425.2 1509.4 1691.5 1731.6 7321.6 7865.8 7866.6 7871.9 7874.4 6316.6 7507.5 7752.5 7795.7 7803.6 2134.3 2171.7 2172.1 2173.4 2176.1 2129.8 2169.7 2191.6 2193.0 2194.5 1771.5 1829.1 1837.8 1840.6 2088.8 2130.7 2161.4 2162.3 2168.4 2169.0 2169.6 2197.1 2198.7 2206.5 2209.3 7891.6 7909.1 7935.2 7944.8 7946.9 4251.1 4441.6 4442.6 4448.9 4449.0 2611.3 2740.0 2773.9 2789.8 2800.2 864.0 869.1 871.0 879.7 901.2 10480.4 10480.9 10497.4 10502.5 10505.1 3760.9 3955.8 4085.7 4096.7 4096.8 8933.4 8936.7 8942.7 8946.7 8953.2 4357.2 4395.2 4395.5 4395.5 4395.7 3947.9 4007.3 4030.6 4032.5 4038.6 3532.3 3590.3 3647.3 3693.3 3775.9 1067.9 1074.5 1076.4 1081.5 1118.0 728.9 826.6 830.5 841.9 846.5 4981.5 5008.3 5066.0 5107.4 5126.7 398.6 851.5 939.2 953.6 961.7 3360.1 3610.1 3781.0 3807.3 3840.4 8988.4 9040.5 9148.0 9167.9 9168.2 9103.0 9110.0 9114.6 9117.2 9117.6 6801.5 7100.5 7270.6 7273.0 7275.5 5037.2 5258.4 5327.7 5356.9 5385.5 8459.0 8679.5 8688.0 8703.4 8731.7 8774.1 8808.3 8842.7 8866.3 8905.6 6757.1 6873.4 6874.4 6875.1 6876.2 7006.3 7022.2 7050.3 7063.9 7122.6 6441.6 6458.7 6477.2 6479.9 6496.0 6252.6 6262.5 6311.6 6435.1 6446.4 6017.5 6034.2 6071.9 6097.6 6105.0 4387.5 4449.0 4517.2 4534.2 4629.9 4615.9 4718.1 4742.1 4756.8 4801.2 22:36:19 INFO - PROCESS | 25837 | 22:36:19 INFO - PROCESS | 25837 | [#4] prototype.html Cycles:180 Average:4124.25 Median:4588.24 stddev:1218.19 (26.6%) stddev-sans-first:1187.01 22:36:19 INFO - PROCESS | 25837 | Values: 283.7 288.0 292.5 293.6 294.0 4871.5 4899.7 4901.4 4924.1 4928.2 4811.2 4816.2 4872.9 4892.2 4898.9 3820.9 3836.5 3837.5 3842.4 3872.2 3600.3 3703.1 3855.6 3870.3 3881.0 3203.4 3598.4 3633.3 3649.7 3693.2 3617.8 3725.9 3736.2 3746.2 3753.1 3294.2 3301.8 3309.3 3309.6 3310.0 4480.9 4522.2 4698.5 4720.2 4727.0 4273.5 4274.3 4276.1 4282.8 4328.8 5033.9 5044.6 5094.3 5097.6 5116.3 3508.9 3572.9 3599.9 3613.2 3613.3 5203.9 5208.3 5208.8 5210.8 5217.4 3688.1 3689.7 3708.6 3741.6 3747.2 3652.7 3762.3 3763.8 3783.9 3793.0 4230.0 4238.1 4240.7 4277.0 4301.7 4966.0 4978.2 4986.0 4994.5 4996.6 1263.9 1277.8 1280.9 1282.5 1282.9 818.1 823.5 825.0 831.9 841.8 3921.8 3971.7 3997.3 3998.9 4002.6 4561.4 4572.3 4575.0 4580.5 4596.0 3932.1 3951.5 3978.5 4044.8 4058.4 2365.8 2433.5 2448.4 2462.9 2476.4 4344.9 4352.0 4369.9 4383.3 4707.7 5192.4 5205.0 5222.3 5271.8 5275.9 4420.2 4835.7 4851.6 4882.8 4894.9 5153.8 5156.8 5177.2 5178.6 5184.8 5005.7 5089.3 5105.5 5109.2 5118.5 4680.8 4873.4 4907.7 4917.3 4969.0 3605.9 3632.8 3654.8 3661.7 3685.8 5280.8 5302.1 5304.7 5310.4 5330.4 4963.9 5036.7 5037.3 5043.6 5059.4 5044.4 5050.5 5054.7 5064.6 5065.0 4997.0 5002.1 5033.6 5038.4 5048.0 4843.2 5071.6 5091.8 5101.3 5101.4 4936.3 4939.0 4943.7 4974.4 5019.5 22:36:19 INFO - PROCESS | 25837 | 22:36:19 INFO - PROCESS | 25837 | [#5] yui.html Cycles:180 Average:17463.55 Median:505.50 stddev:25699.30 (5083.9%) stddev-sans-first:25740.09 22:36:19 INFO - PROCESS | 25837 | Values: 568.8 590.1 631.5 674.1 682.3 11524.9 12427.1 12490.8 12517.1 12988.5 13680.4 13791.6 13820.8 13825.7 13845.2 632.9 751.2 768.8 770.0 771.3 743.9 765.4 769.9 775.3 775.8 822.3 823.6 823.8 824.1 824.4 661.9 818.2 818.7 818.9 819.5 575.1 588.8 590.1 590.2 592.2 12580.7 12626.9 12629.3 12649.3 12659.9 12182.3 12623.1 12823.9 12984.0 13083.0 12057.8 12147.7 12249.7 12319.9 13014.3 4032.8 4063.5 4143.2 4144.2 4219.4 65645.3 65806.3 66198.3 66813.3 67621.3 9528.7 9595.0 9650.9 9707.3 9899.2 17382.8 17403.8 17409.5 17422.1 17485.0 6712.2 6759.0 6825.0 6847.2 6858.8 3714.6 3770.8 3795.2 3841.0 3847.4 2718.0 2814.7 2817.8 2819.9 2820.8 466.2 468.6 472.9 486.8 490.0 418.1 480.5 493.9 497.7 517.7 7005.3 7074.6 7151.9 7200.3 7366.4 484.0 491.5 498.7 512.3 517.1 3492.1 3551.7 3652.4 3680.1 3687.2 56479.0 70106.6 97105.0 109775.0 110016.0 94581.0 94864.5 94896.0 95181.5 95532.5 49335.0 76054.6 76461.6 79595.0 85571.0 6983.2 7142.4 7220.8 7256.6 7353.8 33778.0 35697.6 46504.5 48473.0 49017.0 53167.0 54003.5 56743.7 59772.7 60511.7 8724.0 8864.0 9237.5 10743.5 11920.9 9840.9 9863.1 10023.7 10030.5 10498.9 9641.7 9665.5 9781.7 9922.4 10187.2 11537.9 11611.7 11974.2 12009.5 12638.5 11030.2 11226.5 11403.7 11423.0 11427.7 9154.3 9335.3 9501.4 9597.0 9835.4 10099.9 10919.4 10943.8 11067.3 11077.3 22:36:19 INFO - PROCESS | 25837 | -------- Summary: end -------- 22:36:19 INFO - PROCESS | 25837 | 22:36:20 INFO - TEST-INFO | 25837: exit 0 22:36:20 INFO - TEST-OK | dromaeo_css | took 1119641ms 22:36:20 INFO - TEST-START | kraken 22:36:20 INFO - operating with platform_type : linux_ 22:36:20 INFO - Initialising browser for kraken test... 22:36:20 INFO - TEST-INFO | started process 26030 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0CJeG0/profile http://localhost:34636/getInfo.html) 22:36:24 INFO - PROCESS | 26030 | __metrics Screen width/height:1600/1200 22:36:24 INFO - PROCESS | 26030 | colorDepth:24 22:36:24 INFO - PROCESS | 26030 | Browser inner width/height: 1024/697 22:36:24 INFO - PROCESS | 26030 | __metrics 22:36:25 INFO - PROCESS | 26030 | 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" 22:36:25 INFO - PROCESS | 26030 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:36:26 INFO - TEST-INFO | 26030: exit 0 22:36:26 INFO - Browser initialized. 22:36:26 INFO - Running cycle 1/1 for kraken test... 22:36:26 INFO - TEST-INFO | started process 26110 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0CJeG0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:36:38 INFO - PROCESS | 26110 | RSS: Main: 157663232 22:36:38 INFO - PROCESS | 26110 | 22:36:41 INFO - PROCESS | 26110 | Cycle 1(1): loaded http://localhost:34636/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34636/tests/kraken/driver.html?testName=audio-beat-detection) 22:36:42 INFO - PROCESS | 26110 | RSS: Main: 213831680 22:36:42 INFO - PROCESS | 26110 | 22:36:44 INFO - PROCESS | 26110 | Cycle 1(1): loaded http://localhost:34636/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34636/tests/kraken/driver.html?testName=audio-dft) 22:36:45 INFO - PROCESS | 26110 | RSS: Main: 192675840 22:36:45 INFO - PROCESS | 26110 | 22:36:48 INFO - PROCESS | 26110 | Cycle 1(1): loaded http://localhost:34636/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34636/tests/kraken/driver.html?testName=audio-fft) 22:36:49 INFO - PROCESS | 26110 | RSS: Main: 271347712 22:36:49 INFO - PROCESS | 26110 | 22:36:51 INFO - PROCESS | 26110 | Cycle 1(1): loaded http://localhost:34636/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34636/tests/kraken/driver.html?testName=audio-oscillator) 22:36:52 INFO - PROCESS | 26110 | RSS: Main: 180551680 22:36:52 INFO - PROCESS | 26110 | 22:36:54 INFO - PROCESS | 26110 | Cycle 1(1): loaded http://localhost:34636/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34636/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:36:54 INFO - PROCESS | 26110 | RSS: Main: 185434112 22:36:54 INFO - PROCESS | 26110 | 22:36:58 INFO - PROCESS | 26110 | Cycle 1(1): loaded http://localhost:34636/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34636/tests/kraken/driver.html?testName=imaging-darkroom) 22:36:58 INFO - PROCESS | 26110 | RSS: Main: 221593600 22:36:58 INFO - PROCESS | 26110 | 22:37:02 INFO - PROCESS | 26110 | Cycle 1(1): loaded http://localhost:34636/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34636/tests/kraken/driver.html?testName=imaging-desaturate) 22:37:02 INFO - PROCESS | 26110 | RSS: Main: 215621632 22:37:02 INFO - PROCESS | 26110 | 22:37:05 INFO - PROCESS | 26110 | Cycle 1(1): loaded http://localhost:34636/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34636/tests/kraken/driver.html?testName=json-parse-financial) 22:37:06 INFO - PROCESS | 26110 | RSS: Main: 218365952 22:37:06 INFO - PROCESS | 26110 | 22:37:08 INFO - PROCESS | 26110 | Cycle 1(1): loaded http://localhost:34636/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34636/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:37:08 INFO - PROCESS | 26110 | RSS: Main: 184455168 22:37:08 INFO - PROCESS | 26110 | 22:37:10 INFO - PROCESS | 26110 | Cycle 1(1): loaded http://localhost:34636/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34636/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:37:11 INFO - PROCESS | 26110 | RSS: Main: 190697472 22:37:11 INFO - PROCESS | 26110 | 22:37:13 INFO - PROCESS | 26110 | Cycle 1(1): loaded http://localhost:34636/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34636/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:37:14 INFO - PROCESS | 26110 | RSS: Main: 194527232 22:37:14 INFO - PROCESS | 26110 | 22:37:16 INFO - PROCESS | 26110 | Cycle 1(1): loaded http://localhost:34636/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34636/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:37:17 INFO - PROCESS | 26110 | RSS: Main: 190279680 22:37:17 INFO - PROCESS | 26110 | 22:37:20 INFO - PROCESS | 26110 | Cycle 1(1): loaded http://localhost:34636/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34636/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:37:21 INFO - PROCESS | 26110 | RSS: Main: 192704512 22:37:21 INFO - PROCESS | 26110 | 22:37:22 INFO - PROCESS | 26110 | Cycle 1(1): loaded http://localhost:34636/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34636/tests/kraken/driver.html?testName=ai-astar) 22:37:23 INFO - PROCESS | 26110 | RSS: Main: 243675136 22:37:23 INFO - PROCESS | 26110 | 22:37:23 INFO - PROCESS | 26110 | __start_tp_report 22:37:23 INFO - PROCESS | 26110 | _x_x_mozilla_page_load 22:37:23 INFO - PROCESS | 26110 | _x_x_mozilla_page_load_details 22:37:23 INFO - PROCESS | 26110 | |i|pagename|runs| 22:37:23 INFO - PROCESS | 26110 | |0;ai-astar;105;114;115;114;114;116;113;115;113;117 22:37:23 INFO - PROCESS | 26110 | |1;audio-beat-detection;145;120;124;198;120;124;152;121;162;123 22:37:23 INFO - PROCESS | 26110 | |2;audio-dft;161;161;160;161;161;160;161;163;160;161 22:37:23 INFO - PROCESS | 26110 | |3;audio-fft;76;76;75;103;76;76;75;75;102;76 22:37:23 INFO - PROCESS | 26110 | |4;audio-oscillator;85;84;126;84;86;85;119;85;85;137 22:37:23 INFO - PROCESS | 26110 | |5;imaging-gaussian-blur;117;116;117;117;117;118;117;116;116;117 22:37:23 INFO - PROCESS | 26110 | |6;imaging-darkroom;146;146;146;145;146;146;146;147;146;146 22:37:23 INFO - PROCESS | 26110 | |7;imaging-desaturate;95;95;95;95;95;96;96;95;96;95 22:37:23 INFO - PROCESS | 26110 | |8;json-parse-financial;64;65;63;65;65;64;65;64;64;64 22:37:23 INFO - PROCESS | 26110 | |9;json-stringify-tinderbox;65;65;65;66;65;65;65;65;64;66 22:37:23 INFO - PROCESS | 26110 | |10;stanford-crypto-aes;80;90;80;81;85;80;90;80;79;80 22:37:23 INFO - PROCESS | 26110 | |11;stanford-crypto-ccm;139;130;137;145;137;129;129;136;147;134 22:37:23 INFO - PROCESS | 26110 | |12;stanford-crypto-pbkdf2;165;166;164;171;166;168;164;167;166;166 22:37:23 INFO - PROCESS | 26110 | |13;stanford-crypto-sha256-iterative;67;67;67;66;65;65;66;70;66;67 22:37:23 INFO - PROCESS | 26110 | __end_tp_report 22:37:23 INFO - PROCESS | 26110 | __start_cc_report 22:37:23 INFO - PROCESS | 26110 | _x_x_mozilla_cycle_collect,1940 22:37:23 INFO - PROCESS | 26110 | __end_cc_report 22:37:23 INFO - PROCESS | 26110 | __startTimestamp1470634643014__endTimestamp 22:37:23 INFO - PROCESS | 26110 | 22:37:23 INFO - PROCESS | 26110 | ------- Summary: start ------- 22:37:23 INFO - PROCESS | 26110 | Number of tests: 14 22:37:23 INFO - PROCESS | 26110 | 22:37:23 INFO - PROCESS | 26110 | [#0] ai-astar Cycles:10 Average:113.60 Median:114.50 stddev:3.27 (2.9%) stddev-sans-first:1.33 22:37:23 INFO - PROCESS | 26110 | Values: 105.0 114.0 115.0 114.0 114.0 116.0 113.0 115.0 113.0 117.0 22:37:23 INFO - PROCESS | 26110 | 22:37:23 INFO - PROCESS | 26110 | [#1] audio-beat-detection Cycles:10 Average:138.90 Median:134.50 stddev:25.75 (19.1%) stddev-sans-first:27.22 22:37:23 INFO - PROCESS | 26110 | Values: 145.0 120.0 124.0 198.0 120.0 124.0 152.0 121.0 162.0 123.0 22:37:23 INFO - PROCESS | 26110 | 22:37:23 INFO - PROCESS | 26110 | [#2] audio-dft Cycles:10 Average:160.90 Median:161.00 stddev:0.88 (0.5%) stddev-sans-first:0.93 22:37:23 INFO - PROCESS | 26110 | Values: 161.0 161.0 160.0 161.0 161.0 160.0 161.0 163.0 160.0 161.0 22:37:23 INFO - PROCESS | 26110 | 22:37:23 INFO - PROCESS | 26110 | [#3] audio-fft Cycles:10 Average:81.00 Median:76.00 stddev:11.34 (14.9%) stddev-sans-first:11.89 22:37:23 INFO - PROCESS | 26110 | Values: 76.0 76.0 75.0 103.0 76.0 76.0 75.0 75.0 102.0 76.0 22:37:23 INFO - PROCESS | 26110 | 22:37:23 INFO - PROCESS | 26110 | [#4] audio-oscillator Cycles:10 Average:97.60 Median:85.00 stddev:20.97 (24.7%) stddev-sans-first:21.74 22:37:23 INFO - PROCESS | 26110 | Values: 85.0 84.0 126.0 84.0 86.0 85.0 119.0 85.0 85.0 137.0 22:37:23 INFO - PROCESS | 26110 | 22:37:23 INFO - PROCESS | 26110 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.63 (0.5%) stddev-sans-first:0.67 22:37:23 INFO - PROCESS | 26110 | Values: 117.0 116.0 117.0 117.0 117.0 118.0 117.0 116.0 116.0 117.0 22:37:23 INFO - PROCESS | 26110 | 22:37:23 INFO - PROCESS | 26110 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.47 (0.3%) stddev-sans-first:0.50 22:37:23 INFO - PROCESS | 26110 | Values: 146.0 146.0 146.0 145.0 146.0 146.0 146.0 147.0 146.0 146.0 22:37:23 INFO - PROCESS | 26110 | 22:37:23 INFO - PROCESS | 26110 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 22:37:23 INFO - PROCESS | 26110 | Values: 95.0 95.0 95.0 95.0 95.0 96.0 96.0 95.0 96.0 95.0 22:37:23 INFO - PROCESS | 26110 | 22:37:23 INFO - PROCESS | 26110 | [#8] json-parse-financial Cycles:10 Average:64.30 Median:64.50 stddev:0.67 (1.0%) stddev-sans-first:0.71 22:37:23 INFO - PROCESS | 26110 | Values: 64.0 65.0 63.0 65.0 65.0 64.0 65.0 64.0 64.0 64.0 22:37:23 INFO - PROCESS | 26110 | 22:37:23 INFO - PROCESS | 26110 | [#9] json-stringify-tinderbox Cycles:10 Average:65.10 Median:65.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 22:37:23 INFO - PROCESS | 26110 | Values: 65.0 65.0 65.0 66.0 65.0 65.0 65.0 65.0 64.0 66.0 22:37:23 INFO - PROCESS | 26110 | 22:37:23 INFO - PROCESS | 26110 | [#10] stanford-crypto-aes Cycles:10 Average:82.50 Median:80.50 stddev:4.28 (5.3%) stddev-sans-first:4.44 22:37:23 INFO - PROCESS | 26110 | Values: 80.0 90.0 80.0 81.0 85.0 80.0 90.0 80.0 79.0 80.0 22:37:23 INFO - PROCESS | 26110 | 22:37:23 INFO - PROCESS | 26110 | [#11] stanford-crypto-ccm Cycles:10 Average:136.30 Median:137.00 stddev:6.24 (4.6%) stddev-sans-first:6.54 22:37:23 INFO - PROCESS | 26110 | Values: 139.0 130.0 137.0 145.0 137.0 129.0 129.0 136.0 147.0 134.0 22:37:23 INFO - PROCESS | 26110 | 22:37:23 INFO - PROCESS | 26110 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.30 Median:166.00 stddev:2.06 (1.2%) stddev-sans-first:2.13 22:37:23 INFO - PROCESS | 26110 | Values: 165.0 166.0 164.0 171.0 166.0 168.0 164.0 167.0 166.0 166.0 22:37:23 INFO - PROCESS | 26110 | 22:37:23 INFO - PROCESS | 26110 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.60 Median:67.00 stddev:1.43 (2.1%) stddev-sans-first:1.51 22:37:23 INFO - PROCESS | 26110 | Values: 67.0 67.0 67.0 66.0 65.0 65.0 66.0 70.0 66.0 67.0 22:37:23 INFO - PROCESS | 26110 | -------- Summary: end -------- 22:37:23 INFO - PROCESS | 26110 | 22:37:24 INFO - TEST-INFO | 26110: exit 0 22:37:24 INFO - TEST-OK | kraken | took 63563ms 22:37:24 INFO - SUITE-END | took 1183s 22:37:24 INFO - Completed test suite (00:19:43) 22:37:24 INFO - javascript benchmark 22:37:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2597.194869590427, 2625.094342451854, 2631.605259211117, 2661.9600705989415, 2666.5866790647456, 4567.817287308504, 4605.263157894738, 4709.3171490133955, 4722.858314250574, 4739.739314337714, 4669.766511674411, 4703.341532185496, 4714.4106986626675, 4715.976420117903, 4751.287306903966, 4268.807903644328, 4278.61492465678, 4360.36774667673, 4409.338599210112, 4483.708558943658, 4394.7143435676735, 4430.26900561407, 4459.487158976711, 4477.664175186857, 4483.7758112094425, 4210.873673789792, 4242.851500197478, 4350.0, 4357.498887627931, 4419.292913133899, 4245.150969806051, 4264.616184543375, 4276.166147601219, 4333.06839029607, 4351.43431353922, 4239.152169566069, 4277.572242775728, 4304.3543468479975, 4319.4816622005565, 4338.739675619473, 4533.977330113361, 4710.69380490267, 4728.196206644879, 4764.332993380929, 4771.6421268404665, 5029.723365214912, 5070.08738427083, 5076.923846142311, 5087.465816089331, 5144.459831717653, 4999.9500004999845, 5105.540501354859, 5181.0, 5185.429602743695, 5186.559142472895, 4573.63410927125, 4673.135469938073, 4764.2615394613895, 4809.567138957441, 4823.155947709137, 5142.717150556755, 5232.607554433356, 5259.184826351921, 5265.657732247391, 5269.578433725293, 5009.7495125243595, 5011.599072074298, 5051.368578927634, 5056.696598204119, 5060.291559181717, 5080.060188865072, 5090.211017292326, 5126.66676666023, 5133.4096578893905, 5139.0, 4818.518148185153, 4884.560389564886, 4981.42713587942, 4990.875228119254, 5071.416787069535, 4993.700377977333, 5003.199488081962, 5005.874853128701, 5009.499050095034, 5055.772490237948, 4257.5529569395385, 4263.381809637585, 4272.166927449118, 4276.401303817468, 4310.353446982915, 3662.340778659867, 3664.7617904836634, 3702.0, 3706.3884459064493, 3746.85012599493, 4371.75955322454, 4398.296272596369, 4398.538153493837, 4434.024514606781, 4443.7111587746695, 5061.392632884077, 5081.060003899216, 5082.415522214918, 5084.694918304839, 5125.8718532037, 4205.558416366299, 4241.533431322552, 4333.0, 4382.0, 4388.27593447084, 4546.499885012646, 4564.771761411983, 4594.150082234731, 4602.263637817933, 4608.239640459286, 4782.450018247946, 4794.640401969797, 4796.616270698406, 4797.040591881568, 4802.975985120052, 4911.926321105115, 4989.55094041538, 5014.874628134326, 5105.055564720538, 5105.948940510547, 4749.430068391815, 4754.334393185026, 4763.214069678603, 4780.760961951958, 4783.880402989814, 4972.7762250699525, 4973.47778483254, 4976.552110310089, 4980.676256043491, 5028.145215313332, 4868.099401610713, 5009.624278179078, 5041.294218809443, 5059.089363914531, 5090.643654944267, 4905.705657660552, 4912.582430493305, 4913.533214344631, 4939.110960027229, 4944.060628480577, 4901.980356144711, 4910.975445122752, 4920.31115643803, 4926.334944782552, 4964.106460837084, 4646.790894409692, 4780.067886762048, 4800.231962885921, 4872.780724867461, 4922.950770492249, 4915.21356582945, 4934.679245849008, 4939.061578300111, 4964.4539100698175, 4994.425641051181, 4819.638527110411, 4823.879403014953, 4825.782839772149, 4831.855044348676, 4874.390701162355], "value": 4637.90251968664, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [548.6845064088147, 561.5199004850855, 580.3065336922378, 587.8971180043492, 596.7105953612498, 28424.147275581712, 31745.0, 31746.682533174662, 40110.0, 41212.38181427281, 41297.17405651885, 41564.584354156446, 41956.160876782444, 42004.0, 42012.159756804846, 7749.651265693044, 7897.289243968042, 7898.605069746518, 7903.36773058154, 7908.604569771517, 7416.221296763843, 9862.211023118169, 10043.045910638477, 10045.64840230592, 10371.170306375472, 10128.493575321241, 10299.382037077761, 10307.587696492132, 10311.329763565382, 10312.742181445486, 10047.899521004769, 10099.596016159345, 10102.191824654083, 10121.848172277421, 10124.29129960903, 9994.750131246705, 10009.947960668045, 10049.598016079348, 10062.345947513388, 10079.59681612742, 38706.41940370897, 38858.80570597129, 38997.0, 39045.0, 39068.21863562741, 3052.6184226971627, 3139.7645176611845, 3221.6617255188335, 3467.861285548575, 3731.4589384539363, 28097.43805123886, 29604.851975739984, 29780.70219297801, 30065.69934300651, 31097.37805243905, 2760.2409337432164, 2763.903263385772, 2787.038471727251, 2814.5074611942828, 2898.463784199909, 238080.0, 238876.80561597255, 239030.0, 240733.0, 240924.0, 54505.18242226291, 54677.45322546803, 54744.0, 54836.17745733817, 54887.17669234965, 33573.49639755406, 34367.0, 34449.13877153091, 36305.818470908, 51943.0, 14965.92517037408, 15105.3202605883, 15163.166025868682, 15185.936984410986, 15196.772048419283, 9589.328746987643, 10279.640212592523, 10280.07479326864, 10314.071733544017, 10324.328918620266, 18203.45389638313, 18767.436976890716, 18821.0, 19373.806261937483, 19455.708164377545, 7150.676030290072, 7158.2841715828, 7244.891326630105, 7280.6723697433745, 7286.927130728731, 834.407570624851, 877.929765618749, 906.7577418935967, 989.9109080182818, 1043.0977204717865, 28055.71944280531, 28117.156485305473, 28757.137285881458, 28796.280092997422, 28962.855185723936, 943.7640589852537, 956.3305686019814, 982.597135174575, 1002.9719537474148, 1033.8397548380012, 14676.633084172981, 15228.238588070551, 15299.61750956235, 15429.922850385901, 15473.690526189414, 393944.0, 394239.0, 394911.0, 396053.0, 398734.0, 50008.499915000386, 50938.0, 51015.234771478456, 51075.0, 51106.488935110174, 1086.0768346905224, 1126.9380184089953, 1129.102363620907, 1177.735009622828, 1200.1598880783313, 26287.868560657073, 27857.303567410978, 30428.087157385315, 30440.543391849584, 30454.390912182076, 44904.0, 45636.0, 45651.0, 45652.771736141105, 45703.542964569926, 351591.0, 356773.0, 357055.0, 357336.0, 357706.0, 38865.0, 39479.80260098681, 42743.14513709771, 43387.78306108449, 43562.0, 42943.57056429521, 42979.785101074296, 43170.352444713986, 43207.78396108125, 43850.0, 36045.00044643333, 36256.63743362533, 36435.63564364429, 36507.452388214726, 37710.24579508449, 36302.27395452129, 37153.62846371502, 37550.624493754716, 37606.81196594109, 38125.80937095297, 35079.298414031065, 35134.812099721414, 36227.637723623484, 36457.63542364542, 36644.26711465809, 31562.842185789843, 33462.996110116736, 34033.14917127092, 34103.82948085244, 34345.313093738485, 25204.243872683848, 25286.873565632053, 25637.0, 25670.61494077553, 26045.869770651025], "value": 18224.01148428121, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [331.65342217382835, 350.0950044135907, 353.54569378348816, 356.71641045368926, 377.1043771043771, 12606.243625382473, 17174.39889603864, 17693.38073167441, 18274.0, 18419.07904604771, 15427.151506667129, 16290.51128466145, 16331.59171020725, 16362.100084495349, 16518.25667844947, 4970.130227210242, 5044.142495775727, 5078.238264260353, 5112.539871411572, 5116.846494605149, 3697.704183665314, 5028.321176641143, 5118.283440318359, 5732.5127364174095, 6036.275646922375, 6084.08738689198, 6104.786332478364, 6107.5113990880845, 6108.938910610881, 6111.724972376232, 5937.732802023897, 5960.1357803118735, 6082.604630699013, 6109.969450152766, 6123.693815309217, 5185.377754669464, 5340.305760251182, 5343.198520221998, 5345.09133447311, 5347.946520534783, 21267.46831329229, 21613.891930540405, 21629.0, 21740.782592174033, 21747.891260543754, 1887.2073729033839, 1948.8635795494322, 2018.5155562665002, 2137.4015275722822, 2195.648696208615, 13391.397387117602, 13601.659958501017, 13605.659858503517, 13607.387667554984, 14350.425982960669, 1702.6850032743982, 1746.4062218845527, 1756.3062590276897, 1759.2786957347555, 1793.4440323499757, 69728.0, 72676.63661681658, 73116.0, 73443.26556734418, 73939.63030184868, 9202.549044108458, 9247.537623118951, 9475.620975160917, 9485.478298693639, 9826.9017309826, 13253.867461325262, 13261.403236854172, 13279.136856104322, 13297.401616927262, 13379.665508362368, 6899.17209934811, 7140.0, 7243.927560724431, 7374.446916481178, 7379.631018449056, 6933.479989000845, 6952.0, 6962.0, 7000.299970002959, 7224.277572242839, 6032.577719559677, 6126.754929802847, 6158.384161583896, 6173.753049877954, 6325.810225693236, 1716.8025677047299, 1724.0862342958253, 1727.5594723345719, 1750.6673731990882, 1751.816059313779, 707.4906067631297, 895.0333639669142, 904.2494729374604, 917.1012407840353, 936.8688383626297, 17454.56363590895, 17643.382481613084, 18407.447776566725, 18462.81537184611, 18816.153273102744, 879.4503435352905, 885.1016218538189, 890.3589415620743, 899.739075668062, 928.5218112671979, 9931.751706207402, 10507.211959103099, 10548.208884333706, 10646.627368042082, 10728.624498142528, 399818.0, 403612.0, 413958.0, 414072.0, 414367.0, 43285.78357108194, 48650.756746216044, 73940.26059739334, 74368.62815685887, 75867.24132758602, 890.6303883888244, 935.0929598289648, 954.9474778887228, 957.3537861943161, 960.0687333286701, 15698.843011569737, 15798.21008949571, 16202.837971620604, 16234.074562784735, 16458.0, 36741.26517469689, 37271.0, 37702.811485942395, 38020.23959520849, 38241.80879095587, 47479.287810683556, 49239.75380123076, 50168.74915625399, 50202.49797501978, 51259.92440453585, 4913.263010548446, 5046.444891061907, 7330.010448589373, 7524.134724506533, 7790.9220907790195, 10744.409057501913, 10990.120790336914, 11334.94332528332, 11664.825027624423, 12264.816027759769, 9709.029097090235, 9735.075167859042, 9777.853332200166, 9992.550335234786, 10259.640912568024, 10204.744881378025, 10284.2801003932, 10342.638007669695, 10651.254412191072, 10869.402182880018, 4994.850154495371, 6248.593841400295, 7912.762617121495, 9965.202783777426, 10100.074682598954, 7661.463697541119, 7715.729949451967, 7783.610819459118, 7900.284026245431, 8037.879431808645, 8852.291816654524, 9430.292728045286, 9482.194013508653, 9500.524973751422, 10003.14973227284], "value": 8304.326210511641, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1401.7266633006564, 1425.1805212003098, 1509.4415066425424, 1691.4587332053748, 1731.6017316017317, 7321.560706357615, 7865.842683146334, 7866.567338796364, 7871.881921771178, 7874.448788584801, 6316.557840951135, 7507.549547027183, 7752.457327987043, 7795.688172473095, 7803.648835802388, 2134.2743467221144, 2171.695962565242, 2172.1311475409802, 2173.3805865328304, 2176.0534167637047, 2129.7976692214293, 2169.69624252604, 2191.5726433345503, 2192.967105493411, 2194.484296190394, 1771.4508502364308, 1829.1311626977213, 1837.7519034930315, 1840.6410749903707, 2088.8015638514394, 2130.659094544873, 2161.3732017714765, 2162.264829957822, 2168.3711723600136, 2168.9783102168935, 2169.5986242545023, 2197.0772275644267, 2198.7251593550805, 2206.5476577301615, 2209.3151123151874, 7891.565963871984, 7909.050913890313, 7935.206479352077, 7944.841103177904, 7946.920530794676, 4251.149770045972, 4441.555844415565, 4442.62237709795, 4448.933266001012, 4448.977755111204, 2611.308003379106, 2739.9589006164924, 2773.875175617082, 2789.846558439285, 2800.1739486851225, 864.0409145848104, 869.1091631078195, 871.0462044061805, 879.692107762278, 901.1619194149439, 10480.423576703279, 10480.895191048068, 10497.4226417547, 10502.527386267637, 10505.107065899409, 3760.8871733848027, 3955.802209889494, 4085.6527195188146, 4096.672266218696, 4096.815643296059, 8933.374663773473, 8936.70417789417, 8942.687005954891, 8946.731598052069, 8953.238974687094, 4357.237483440385, 4395.164918665443, 4395.472543294761, 4395.494518130456, 4395.7362558246605, 3947.9013024674036, 4007.2987227235117, 4030.5969403059457, 4032.4757781488806, 4038.6163314485075, 3532.2582257726167, 3590.317839610466, 3647.304003638321, 3693.261347730465, 3775.9433608495897, 1067.8611780468489, 1074.5164675895721, 1076.4348716923678, 1081.464674985887, 1117.972050698739, 728.8760910645203, 826.6032304493876, 830.5307501261768, 841.886345343383, 846.4921047371528, 4981.452040275567, 5008.348914641073, 5065.974670126626, 5107.438181800073, 5126.718030508283, 398.5775078416887, 851.4848516647463, 939.1735272959752, 953.5613617735919, 961.6680896957764, 3360.12636714456, 3610.097475631092, 3780.981095094507, 3807.295650304647, 3840.423936409561, 8988.370814042953, 9040.547972601344, 9148.0, 9167.908320916838, 9168.220701240336, 9103.0, 9109.954450227706, 9114.589843456928, 9117.225035871892, 9117.589708463134, 6801.4558835293055, 7100.5029647925185, 7270.600116993616, 7273.0, 7275.454340924452, 5037.219231019198, 5258.395284542174, 5327.706976116351, 5356.946430535645, 5385.488378604026, 8459.0, 8679.522626255364, 8687.956560217159, 8703.43427677199, 8731.69439069655, 8774.12258774143, 8808.251298639687, 8842.690505832265, 8866.290696744374, 8905.599248033725, 6757.121574195323, 6873.415759660485, 6874.381305682513, 6875.07186529812, 6876.17485901695, 7006.264342244195, 7022.157341119098, 7050.330218629222, 7063.929360706327, 7122.60825654594, 6441.613503189824, 6458.741650334122, 6477.1579694639395, 6479.935200647933, 6496.0255961606135, 6252.624842509464, 6262.4990000800735, 6311.621302721851, 6435.066915297162, 6446.419822216023, 6017.548683848817, 6034.185384973148, 6071.907056729654, 6097.573169878244, 6104.969475152596, 4387.473503179639, 4449.0, 4517.164324599828, 4534.2065138599955, 4629.93055104167, 4615.907681846412, 4718.079974404958, 4742.07529531738, 4756.762161891961, 4801.207800712983], "value": 4031.82290970436, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [283.66684023903395, 288.04800135551994, 292.5042053718946, 293.6182962149207, 293.9970600293996, 4871.512848715127, 4899.657024008312, 4901.411830580327, 4924.1382758017335, 4928.162212423897, 4811.182099043162, 4816.205326121186, 4872.853814385574, 4892.192788189945, 4898.8775280618, 3820.9044773880737, 3836.462895194675, 3837.539495260559, 3842.405401758274, 3872.244912242114, 3600.261946301015, 3703.1112532992024, 3855.556610989744, 3870.322693528635, 3881.0, 3203.423383790917, 3598.4062629666205, 3633.3278343506404, 3649.708023358125, 3693.1874987502842, 3617.801020943847, 3725.9254814903684, 3736.215394767102, 3746.2132952079824, 3753.11801726594, 3294.1764558860286, 3301.752368572367, 3309.271960168783, 3309.6430463509846, 3309.983450082734, 4480.910381792379, 4522.23122069249, 4698.483166851644, 4720.221163508017, 4726.9600687848615, 4273.50427350428, 4274.316109422509, 4276.144771045803, 4282.807273672693, 4328.826846926119, 5033.949660503421, 5044.646874718771, 5094.286799848024, 5097.617678674114, 5116.309298244727, 3508.859645614172, 3572.8570857165682, 3599.9100022499647, 3613.1870329175986, 3613.313470440608, 5203.947960520384, 5208.348956380452, 5208.8437346879655, 5210.791568337262, 5217.373915130209, 3688.0779805048737, 3689.704823614158, 3708.5721997030796, 3741.5884252732176, 3747.2318174774105, 3652.6712595866497, 3762.2663580602066, 3763.7553559018575, 3783.886483405502, 3793.0327766419387, 4230.0, 4238.0888109056705, 4240.660747140221, 4276.957230427718, 4301.720388174821, 4965.950340496621, 4978.2034874419915, 4985.9501404985485, 4994.525520075587, 4996.625253106032, 1263.9052071094704, 1277.757226127033, 1280.866433206619, 1282.499825068224, 1282.923024618526, 818.1246066708684, 823.5141266652704, 824.9564301159039, 831.8793774902605, 841.7895526118471, 3921.843126274974, 3971.6822654187595, 3997.2804895119157, 3998.9200215996098, 4002.639102705036, 4561.384213131251, 4572.291294849304, 4575.0197328324575, 4580.473245576734, 4596.0, 3932.05363345146, 3951.5258169019903, 3978.5026871641044, 4044.8472185427013, 4058.3766493402286, 2365.8107351411845, 2433.549793288247, 2448.449098952721, 2462.9014839406223, 2476.418041760185, 4344.891377715519, 4352.020795321091, 4369.875538989574, 4383.268608899531, 4707.717536947725, 5192.3769147702515, 5205.0, 5222.321098257202, 5271.815486457956, 5275.947240527622, 4420.226460369422, 4835.661503694778, 4851.58761505276, 4882.780274887638, 4894.898647804215, 5153.819616313411, 5156.845294641167, 5177.249298851721, 5178.6374953752875, 5184.807494276413, 5005.749712514433, 5089.312942752829, 5105.5149760772665, 5109.233614957786, 5118.513741194581, 4680.836170734008, 4873.439554451284, 4907.68100073494, 4917.336159618549, 4968.950310496849, 3605.9278814424088, 3632.800195989246, 3654.780713157219, 3661.743677942625, 3685.797281149455, 5280.841574752763, 5302.125149350468, 5304.711079130963, 5310.389305229948, 5330.387005494418, 4963.851084467472, 5036.748162591929, 5037.319961805256, 5043.613006423263, 5059.418166910852, 5044.394672639307, 5050.469700681376, 5054.74726263678, 5064.569511591409, 5065.024640390177, 4996.975015125046, 5002.149634562061, 5033.622478314214, 5038.420581633159, 5048.0, 4843.200871856246, 5071.594272458268, 5091.821786237612, 5101.3113229715, 5101.438841727333, 4936.259561065869, 4939.0243902439315, 4943.728094954668, 4974.37820272466, 5019.472955339783], "value": 3735.6896534540165, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [568.8236646639541, 590.0824218340481, 631.5358211714392, 674.0563211503894, 682.3040498691338, 11524.942375288123, 12427.13010089294, 12490.81263781044, 12517.061220408483, 12988.480460781557, 13680.384382702778, 13791.586252412419, 13820.792688109661, 13825.654358641039, 13845.169289842592, 632.9493640508771, 751.1624538639405, 768.7808974442285, 770.0220719685996, 771.2595907928395, 743.8698227810139, 765.406809722464, 769.9076110866675, 775.2983549887362, 775.7595145504883, 822.2599660305713, 823.6375994562374, 823.8475881961826, 824.0646865807322, 824.381713714714, 661.877552652756, 818.1899919080128, 818.6725309876067, 818.8567000774871, 819.5451524403953, 575.1372940589116, 588.8262962426095, 590.1236663554661, 590.2297501760202, 592.2478452365472, 12580.68548286291, 12626.936865315614, 12629.305388203646, 12649.304288264142, 12659.936700316532, 12182.329971851545, 12623.116381853182, 12823.935880320632, 12984.0, 13083.0, 12057.758844823142, 12147.696307592381, 12249.693757656041, 12319.93840030803, 13014.34928253593, 4032.8374033283144, 4063.4920634920636, 4143.1713657268665, 4144.171165766828, 4219.43037689911, 65645.3435465644, 65806.3419365805, 66198.33801662018, 66813.3318666812, 67621.32378676199, 9528.714138575853, 9594.952025239829, 9650.855237171449, 9707.271954603433, 9899.1585715215, 17382.826171738372, 17403.825961740473, 17409.47771566855, 17422.128893555273, 17485.0, 6712.160979877515, 6758.966205169041, 6825.044493770876, 6847.21257055435, 6858.759943401957, 3714.57282412526, 3770.792606406674, 3795.221979494197, 3841.0, 3847.3844184930836, 2717.9728202718115, 2814.746672799458, 2817.830930144157, 2819.8590070496393, 2820.830750154956, 466.22141024489184, 468.6133939499926, 472.94324681038495, 486.7517566041344, 489.9804007839718, 418.06353767560444, 480.48588010828774, 493.9481354457802, 497.6839706786217, 517.720430967281, 7005.264447233069, 7074.610896400747, 7151.922135283902, 7200.315969982843, 7366.394317580985, 484.022275004496, 491.5404097169158, 498.6783524626596, 512.3288492075393, 517.0537915614492, 3492.1269682579355, 3551.698105660996, 3652.433872749728, 3680.0615842959874, 3687.225682606629, 56479.0, 70106.64946675336, 97105.0, 109775.0, 110016.0, 94581.0, 94864.52567737117, 94896.0, 95181.52409238048, 95532.52233738925, 49335.0, 76054.61972690212, 76461.61769191119, 79595.0, 85571.0, 6983.231844497, 7142.357187853118, 7220.783376498714, 7256.637168141467, 7353.77938661841, 33778.0, 35697.643023569435, 46504.53495465002, 48473.0, 49017.0, 53167.0, 54003.45996540142, 56743.71628141833, 59772.70113649404, 60511.69744151251, 8723.956380218058, 8864.0, 9237.491937943209, 10743.516541755796, 11920.880791191978, 9840.852387214341, 9863.112319891245, 10023.74940626461, 10030.548625311732, 10498.895011049792, 9641.662541811284, 9665.468399237829, 9781.657641982496, 9922.355046921926, 10187.185025198114, 11537.942310288396, 11611.6516504505, 11974.161808673658, 12009.519619215134, 12638.494460221487, 11030.22788404804, 11226.494807733508, 11403.657890263305, 11423.0, 11427.714307142389, 9154.267658587163, 9335.25317974574, 9501.42991420517, 9597.040295970348, 9835.409875407498, 10099.920063948959, 10919.39943303126, 10943.781124377627, 11067.280626759235, 11077.335359878432], "value": 6321.98589889122, "unit": "score"}], "name": "dromaeo_css", "value": 6370.519614782999, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 114.0, 115.0, 114.0, 114.0, 116.0, 113.0, 115.0, 113.0, 117.0], "value": 113.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 120.0, 124.0, 198.0, 120.0, 124.0, 152.0, 121.0, 162.0, 123.0], "value": 138.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 160.0, 161.0, 161.0, 160.0, 161.0, 163.0, 160.0, 161.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 75.0, 103.0, 76.0, 76.0, 75.0, 75.0, 102.0, 76.0], "value": 81.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 126.0, 84.0, 86.0, 85.0, 119.0, 85.0, 85.0, 137.0], "value": 97.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 117.0, 117.0, 118.0, 117.0, 116.0, 116.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 145.0, 146.0, 146.0, 146.0, 147.0, 146.0, 146.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 96.0, 95.0, 96.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 63.0, 65.0, 65.0, 64.0, 65.0, 64.0, 64.0, 64.0], "value": 64.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 66.0, 65.0, 65.0, 65.0, 65.0, 64.0, 66.0], "value": 65.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 90.0, 80.0, 81.0, 85.0, 80.0, 90.0, 80.0, 79.0, 80.0], "value": 82.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 130.0, 137.0, 145.0, 137.0, 129.0, 129.0, 136.0, 147.0, 134.0], "value": 136.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 166.0, 164.0, 171.0, 166.0, 168.0, 164.0, 167.0, 166.0, 166.0], "value": 166.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 66.0, 65.0, 65.0, 66.0, 70.0, 66.0, 67.0], "value": 66.6, "unit": "score"}], "name": "kraken", "value": 1531.1999999999996, "alertThreshold": 2.0}]} 22:37:24 INFO - Return code: 0 22:37:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:37:24 INFO - # TBPL SUCCESS # 22:37:24 INFO - Running post-action listener: _resource_record_post_action 22:37:24 INFO - [mozharness: 2016-08-08 05:37:24.831623Z] Finished run-tests step (success) 22:37:24 INFO - Running post-run listener: _resource_record_post_run 22:37:24 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 12288; Write bytes: 535199744; Read time: 204; Write time: 606508 22:37:24 INFO - TinderboxPrint: CPU usage
13.0% 22:37:24 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 204 22:37:24 INFO - TinderboxPrint: I/O write bytes / time
535,199,744 / 606,508 22:37:24 INFO - TinderboxPrint: CPU idle
8,330.3 (87.0%) 22:37:24 INFO - TinderboxPrint: CPU user
1,201.6 (12.5%) 22:37:24 INFO - TinderboxPrint: Swap in / out
0 / 0 22:37:24 INFO - install - Wall time: 17s; CPU: 15.0%; Read bytes: 0; Write bytes: 248688640; Read time: 0; Write time: 386960 22:37:24 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 12288; Write bytes: 286511104; Read time: 204; Write time: 219548 22:37:25 INFO - Running post-run listener: _upload_blobber_files 22:37:25 INFO - Blob upload gear active. 22:37:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:37:25 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 22:37:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:37:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:37:25 INFO - (blobuploader) - INFO - Open directory for files ... 22:37:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:37:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:37:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:37:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:37:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:37:26 INFO - (blobuploader) - INFO - Done attempting. 22:37:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:37:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:37:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:37:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:37:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:37:27 INFO - (blobuploader) - INFO - Done attempting. 22:37:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:37:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:37:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:37:30 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:37:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:37:30 INFO - (blobuploader) - INFO - Done attempting. 22:37:30 INFO - (blobuploader) - INFO - Iteration through files over. 22:37:30 INFO - Return code: 0 22:37:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:37:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:37:30 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/2c1c1310a86353927aa98f3f28e5d64fcc4cae1f34191924826f53ac0697cc466a7eaaf91f18449ff81616fd728adedbef357475878f5a8b82d95f0ca37f5c6a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/75a47a59a8e6813586711413e10251ca5c37146a91d4d53104546d6c3d3585cc400f13706c84f47ce5c6bc96448f91315e2a6bdd50ba58fb35134b286e3d047e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/d3b00270dce7cfcefd3c3a289aba4debd7436beb0c14c793ae2744861c10bf2bbb3a3fd39b6fe53366f87e6d38a5d0801c02239bf7dc97b78f92c7c0404e03e7"} 22:37:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:37:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:37:30 INFO - Contents: 22:37:30 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/2c1c1310a86353927aa98f3f28e5d64fcc4cae1f34191924826f53ac0697cc466a7eaaf91f18449ff81616fd728adedbef357475878f5a8b82d95f0ca37f5c6a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/75a47a59a8e6813586711413e10251ca5c37146a91d4d53104546d6c3d3585cc400f13706c84f47ce5c6bc96448f91315e2a6bdd50ba58fb35134b286e3d047e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/d3b00270dce7cfcefd3c3a289aba4debd7436beb0c14c793ae2744861c10bf2bbb3a3fd39b6fe53366f87e6d38a5d0801c02239bf7dc97b78f92c7c0404e03e7"} 22:37:30 INFO - Running post-run listener: copy_logs_to_upload_dir 22:37:30 INFO - Copying logs to upload dir... 22:37:30 INFO - mkdir: /builds/slave/test/build/upload/logs 22:37:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.495122 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-08-07 22:37:30.411234) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-07 22:37:30.415512) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1470632736.956772-1408881702 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/2c1c1310a86353927aa98f3f28e5d64fcc4cae1f34191924826f53ac0697cc466a7eaaf91f18449ff81616fd728adedbef357475878f5a8b82d95f0ca37f5c6a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/75a47a59a8e6813586711413e10251ca5c37146a91d4d53104546d6c3d3585cc400f13706c84f47ce5c6bc96448f91315e2a6bdd50ba58fb35134b286e3d047e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/d3b00270dce7cfcefd3c3a289aba4debd7436beb0c14c793ae2744861c10bf2bbb3a3fd39b6fe53366f87e6d38a5d0801c02239bf7dc97b78f92c7c0404e03e7"} build_url:https://queue.taskcluster.net/v1/task/Rnk5KSAQT8u2mUUaU9BysA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011246 build_url: 'https://queue.taskcluster.net/v1/task/Rnk5KSAQT8u2mUUaU9BysA/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/Autoland-Non-PGO/sha512/2c1c1310a86353927aa98f3f28e5d64fcc4cae1f34191924826f53ac0697cc466a7eaaf91f18449ff81616fd728adedbef357475878f5a8b82d95f0ca37f5c6a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/75a47a59a8e6813586711413e10251ca5c37146a91d4d53104546d6c3d3585cc400f13706c84f47ce5c6bc96448f91315e2a6bdd50ba58fb35134b286e3d047e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/d3b00270dce7cfcefd3c3a289aba4debd7436beb0c14c793ae2744861c10bf2bbb3a3fd39b6fe53366f87e6d38a5d0801c02239bf7dc97b78f92c7c0404e03e7"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-07 22:37:30.442055) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-07 22:37:30.442374) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1470632736.956772-1408881702 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004411 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-07 22:37:30.523844) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-07 22:37:30.527175) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-07 22:37:30.527490) ========= ========= Total master_lag: 0.18 =========