builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-067 starttime: 1469076836.77 results: success (0) buildid: 20160720211719 builduid: 86b581f21e4b4223b9c4701f6055f8e4 revision: 17409ee90c864e7e982a43e2026e6814a0797e4d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-20 21:53:56.769658) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-20 21:53:56.770097) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-20 21:53:56.770413) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-20 21:53:56.785329) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 21:53:56.785654) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1469076790.561368-759460913 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-20 21:53:56-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|: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-07-20 21:53:56 (46.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.131583 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 21:53:56.941573) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 21:53:56.941952) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1469076790.561368-759460913 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018141 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 21:53:56.992169) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 21:53:56.992555) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 17409ee90c864e7e982a43e2026e6814a0797e4d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 17409ee90c864e7e982a43e2026e6814a0797e4d --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=e0e61f6f900f4dc42e3e8956000001ad-1469076790.561368-759460913 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-20 21:53:57,017 truncating revision to first 12 chars 2016-07-20 21:53:57,017 Setting DEBUG logging. 2016-07-20 21:53:57,018 attempt 1/10 2016-07-20 21:53:57,018 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/17409ee90c86?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-20 21:53:57,326 unpacking tar archive at: fx-team-17409ee90c86/testing/mozharness/ program finished with exit code 0 elapsedTime=0.684531 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 21:53:57.690895) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-20 21:53:57.691227) ========= script_repo_revision: 17409ee90c864e7e982a43e2026e6814a0797e4d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-20 21:53:57.691710) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-20 21:53:57.692008) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-20 21:53:57.703599) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2016-07-20 21:53:57.704038) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-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', 'Fx-Team-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-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=e0e61f6f900f4dc42e3e8956000001ad-1469076790.561368-759460913 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:53:57 INFO - MultiFileLogger online at 20160720 21:53:57 in /builds/slave/test 21:53:57 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 21:53:57 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:53:57 INFO - {'append_to_log': False, 21:53:57 INFO - 'base_work_dir': '/builds/slave/test', 21:53:57 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 21:53:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:53:57 INFO - 'branch': 'Fx-Team-Non-PGO', 21:53:57 INFO - 'buildbot_json_path': 'buildprops.json', 21:53:57 INFO - 'config_files': ('talos/linux_config.py',), 21:53:57 INFO - 'default_actions': ('clobber', 21:53:57 INFO - 'read-buildbot-config', 21:53:57 INFO - 'download-and-extract', 21:53:57 INFO - 'populate-webroot', 21:53:57 INFO - 'create-virtualenv', 21:53:57 INFO - 'install', 21:53:57 INFO - 'run-tests'), 21:53:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:53:57 INFO - 'download_minidump_stackwalk': True, 21:53:57 INFO - 'download_symbols': 'ondemand', 21:53:57 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:53:57 INFO - 'tooltool.py': '/tools/tooltool.py', 21:53:57 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:53:57 INFO - '/tools/misc-python/virtualenv.py')}, 21:53:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:53:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:53:57 INFO - 'installer_path': 'installer.exe', 21:53:57 INFO - 'log_level': 'info', 21:53:57 INFO - 'log_name': 'talos', 21:53:57 INFO - 'log_to_console': True, 21:53:57 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:53:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:53:57 INFO - 'opt_config_files': (), 21:53:57 INFO - 'pip_index': False, 21:53:57 INFO - 'sps_profile': False, 21:53:57 INFO - 'sps_profile_interval': 0, 21:53:57 INFO - 'suite': 'dromaeojs', 21:53:57 INFO - 'system_bits': '32', 21:53:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:53:57 INFO - 'title': 'talos-linux64-ix-067', 21:53:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:53:57 INFO - 'use_talos_json': True, 21:53:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:53:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:53:57 INFO - 'work_dir': 'build'} 21:53:57 INFO - [mozharness: 2016-07-21 04:53:57.822470Z] Running clobber step. 21:53:57 INFO - Running pre-action listener: _resource_record_pre_action 21:53:57 INFO - Running main action method: clobber 21:53:57 INFO - rmtree: /builds/slave/test/build 21:53:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:53:58 INFO - Running post-action listener: _resource_record_post_action 21:53:58 INFO - [mozharness: 2016-07-21 04:53:58.283486Z] Finished clobber step (success) 21:53:58 INFO - [mozharness: 2016-07-21 04:53:58.283588Z] Running read-buildbot-config step. 21:53:58 INFO - Running pre-action listener: _resource_record_pre_action 21:53:58 INFO - Running main action method: read_buildbot_config 21:53:58 INFO - Using buildbot properties: 21:53:58 INFO - { 21:53:58 INFO - "project": "", 21:53:58 INFO - "product": "firefox", 21:53:58 INFO - "script_repo_revision": "production", 21:53:58 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 21:53:58 INFO - "repository": "", 21:53:58 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 21:53:58 INFO - "buildid": "20160720211719", 21:53:58 INFO - "pgo_build": "False", 21:53:58 INFO - "basedir": "/builds/slave/test", 21:53:58 INFO - "buildnumber": 3189, 21:53:58 INFO - "slavename": "talos-linux64-ix-067", 21:53:58 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 21:53:58 INFO - "platform": "ubuntu64_hw", 21:53:58 INFO - "scheduler": "tests-fx-team-linux64-talos", 21:53:58 INFO - "branch": "fx-team", 21:53:58 INFO - "repo_path": "integration/fx-team", 21:53:58 INFO - "revision": "17409ee90c864e7e982a43e2026e6814a0797e4d", 21:53:58 INFO - "stage_platform": "linux64", 21:53:58 INFO - "builduid": "86b581f21e4b4223b9c4701f6055f8e4", 21:53:58 INFO - "slavebuilddir": "test" 21:53:58 INFO - } 21:53:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/WU40-pKsSU2-tXtDsUp6KA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 21:53:58 INFO - Running post-action listener: _resource_record_post_action 21:53:58 INFO - [mozharness: 2016-07-21 04:53:58.286027Z] Finished read-buildbot-config step (success) 21:53:58 INFO - [mozharness: 2016-07-21 04:53:58.286114Z] Running download-and-extract step. 21:53:58 INFO - Running pre-action listener: _resource_record_pre_action 21:53:58 INFO - Running main action method: download_and_extract 21:53:58 INFO - mkdir: /builds/slave/test/build/tests 21:53:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:53:58 INFO - https://queue.taskcluster.net/v1/task/WU40-pKsSU2-tXtDsUp6KA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 21:53:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WU40-pKsSU2-tXtDsUp6KA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 21:53:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WU40-pKsSU2-tXtDsUp6KA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 21:53:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WU40-pKsSU2-tXtDsUp6KA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 21:53:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WU40-pKsSU2-tXtDsUp6KA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 21:53:58 INFO - Downloaded 1361 bytes. 21:53:58 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 21:53:58 INFO - Using the following test package requirements: 21:53:58 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 21:53:58 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 21:53:58 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 21:53:58 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 21:53:58 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 21:53:58 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 21:53:58 INFO - u'jsshell-linux-x86_64.zip'], 21:53:58 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 21:53:58 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 21:53:58 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 21:53:58 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 21:53:58 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 21:53:58 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 21:53:58 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 21:53:58 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 21:53:58 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 21:53:58 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 21:53:58 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 21:53:58 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:53:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:53:58 INFO - https://queue.taskcluster.net/v1/task/WU40-pKsSU2-tXtDsUp6KA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:53:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WU40-pKsSU2-tXtDsUp6KA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 21:53:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WU40-pKsSU2-tXtDsUp6KA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 21:53:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WU40-pKsSU2-tXtDsUp6KA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 21:53:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WU40-pKsSU2-tXtDsUp6KA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:54:00 INFO - Downloaded 22772070 bytes. 21:54:00 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:54:00 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 21:54:01 INFO - Return code: 0 21:54:01 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 21:54:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:54:01 INFO - https://queue.taskcluster.net/v1/task/WU40-pKsSU2-tXtDsUp6KA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:54:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WU40-pKsSU2-tXtDsUp6KA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 21:54:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WU40-pKsSU2-tXtDsUp6KA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 21:54:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WU40-pKsSU2-tXtDsUp6KA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 21:54:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WU40-pKsSU2-tXtDsUp6KA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:54:04 INFO - Downloaded 22772070 bytes. 21:54:04 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:54:04 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 21:54:06 INFO - Return code: 0 21:54:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:54:06 INFO - https://queue.taskcluster.net/v1/task/WU40-pKsSU2-tXtDsUp6KA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 21:54:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WU40-pKsSU2-tXtDsUp6KA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 21:54:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WU40-pKsSU2-tXtDsUp6KA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 21:54:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WU40-pKsSU2-tXtDsUp6KA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 21:54:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WU40-pKsSU2-tXtDsUp6KA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 21:54:06 INFO - Downloaded 14403317 bytes. 21:54:06 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:54:06 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 21:54:07 INFO - Return code: 0 21:54: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:54:07 INFO - https://queue.taskcluster.net/v1/task/WU40-pKsSU2-tXtDsUp6KA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 21:54:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WU40-pKsSU2-tXtDsUp6KA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 21:54:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WU40-pKsSU2-tXtDsUp6KA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 21:54:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WU40-pKsSU2-tXtDsUp6KA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 21:54:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WU40-pKsSU2-tXtDsUp6KA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 21:54:08 INFO - Downloaded 57114688 bytes. 21:54:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WU40-pKsSU2-tXtDsUp6KA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 21:54:08 INFO - mkdir: /builds/slave/test/properties 21:54:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:54:08 INFO - Writing to file /builds/slave/test/properties/build_url 21:54:08 INFO - Contents: 21:54:08 INFO - build_url:https://queue.taskcluster.net/v1/task/WU40-pKsSU2-tXtDsUp6KA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 21:54:09 INFO - Running post-action listener: _resource_record_post_action 21:54:09 INFO - Running post-action listener: set_extra_try_arguments 21:54:09 INFO - [mozharness: 2016-07-21 04:54:09.215402Z] Finished download-and-extract step (success) 21:54:09 INFO - [mozharness: 2016-07-21 04:54:09.215635Z] Running populate-webroot step. 21:54:09 INFO - Running pre-action listener: _resource_record_pre_action 21:54:09 INFO - Running main action method: populate_webroot 21:54:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:54:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:54:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:54:09 INFO - '--tppagecycles', 21:54:09 INFO - '7'], 21:54:09 INFO - 'tests': ['tsvgm']}}, 21:54:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:54:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:54:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:54:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:54:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:54:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:54:09 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:54:09 INFO - 'talos_options': ['--disable-e10s'], 21:54:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:54:09 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:54:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:54:09 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:54:09 INFO - 'talos_options': ['--disable-e10s'], 21:54:09 INFO - 'tests': ['damp', 'tps']}, 21:54:09 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:54:09 INFO - 'tests': ['damp', 'tps']}, 21:54:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:54:09 INFO - 'tests': ['dromaeo_dom']}, 21:54:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:54:09 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:54:09 INFO - 'tests': ['basic_compositor_video']}, 21:54:09 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:54:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:54:09 INFO - 'tests': ['a11yr', 21:54:09 INFO - 'ts_paint', 21:54:09 INFO - 'tpaint', 21:54:09 INFO - 'sessionrestore', 21:54:09 INFO - 'sessionrestore_no_auto_restore', 21:54:09 INFO - 'tabpaint']}, 21:54:09 INFO - 'other-e10s': {'tests': ['a11yr', 21:54:09 INFO - 'ts_paint', 21:54:09 INFO - 'tpaint', 21:54:09 INFO - 'sessionrestore', 21:54:09 INFO - 'sessionrestore_no_auto_restore', 21:54:09 INFO - 'tabpaint']}, 21:54:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:54:09 INFO - 'ts_paint', 21:54:09 INFO - 'tpaint', 21:54:09 INFO - 'sessionrestore', 21:54:09 INFO - 'sessionrestore_no_auto_restore', 21:54:09 INFO - 'tabpaint']}, 21:54:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:54:09 INFO - 'ts_paint', 21:54:09 INFO - 'tpaint', 21:54:09 INFO - 'sessionrestore', 21:54:09 INFO - 'sessionrestore_no_auto_restore', 21:54:09 INFO - 'tabpaint']}, 21:54:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:54:09 INFO - 'tests': ['a11yr', 21:54:09 INFO - 'ts_paint', 21:54:09 INFO - 'tpaint', 21:54:09 INFO - 'sessionrestore', 21:54:09 INFO - 'sessionrestore_no_auto_restore', 21:54:09 INFO - 'tabpaint']}, 21:54:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:54:09 INFO - 'tests': ['a11yr', 21:54:09 INFO - 'ts_paint', 21:54:09 INFO - 'tpaint', 21:54:09 INFO - 'sessionrestore', 21:54:09 INFO - 'sessionrestore_no_auto_restore', 21:54:09 INFO - 'tabpaint']}, 21:54:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:54:09 INFO - 'tests': ['tsvgx', 21:54:09 INFO - 'tsvgr_opacity', 21:54:09 INFO - 'tart', 21:54:09 INFO - 'tscrollx', 21:54:09 INFO - 'cart']}, 21:54:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:54:09 INFO - 'tsvgr_opacity', 21:54:09 INFO - 'tart', 21:54:09 INFO - 'tscrollx', 21:54:09 INFO - 'cart']}, 21:54:09 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:54:09 INFO - 'talos_options': ['--disable-e10s'], 21:54:09 INFO - 'tests': ['tp5o']}, 21:54:09 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:54:09 INFO - 'tests': ['tp5o']}, 21:54:09 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:54:09 INFO - 'talos_options': ['--disable-e10s', 21:54:09 INFO - '--xperf_path', 21:54:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:54:09 INFO - 'tests': ['tp5n']}, 21:54:09 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:54:09 INFO - 'talos_options': ['--xperf_path', 21:54:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:54:09 INFO - 'tests': ['tp5n']}}, 21:54:09 INFO - 'talos.zip': {'path': '', 21:54:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:54:09 INFO - Running post-action listener: _resource_record_post_action 21:54:09 INFO - [mozharness: 2016-07-21 04:54:09.232770Z] Finished populate-webroot step (success) 21:54:09 INFO - [mozharness: 2016-07-21 04:54:09.232859Z] Running create-virtualenv step. 21:54:09 INFO - Running pre-action listener: _resource_record_pre_action 21:54:09 INFO - Running main action method: create_virtualenv 21:54:09 INFO - Creating virtualenv /builds/slave/test/build/venv 21:54:09 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 21:54:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:54:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:54:09 INFO - Using real prefix '/usr' 21:54:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:54:09 INFO - Installing distribute.............................................................................................................................................................................................done. 21:54:12 INFO - Installing pip.................done. 21:54:12 INFO - Return code: 0 21:54:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:54: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:54:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:54:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:54:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:54:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfd2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11358a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1293180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12913b0>, '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 0x12922b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1292740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1469076790.561368-759460913', '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 21:54: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', 'pip>=1.5'] in /builds/slave/test/build 21:54: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 pip>=1.5 21:54:12 INFO - Using env: {'DISPLAY': ':0', 21:54:12 INFO - 'HOME': '/home/cltbld', 21:54:12 INFO - 'LANG': 'en_US.UTF-8', 21:54:12 INFO - 'LANGUAGE': 'en_US:en', 21:54:12 INFO - 'LOGNAME': 'cltbld', 21:54:12 INFO - 'MAIL': '/var/mail/cltbld', 21:54:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:54:12 INFO - 'MOZ_NO_REMOTE': '1', 21:54:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:54:12 INFO - 'NO_EM_RESTART': '1', 21:54:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:54:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:54:12 INFO - 'PWD': '/builds/slave/test', 21:54:12 INFO - 'SHELL': '/bin/bash', 21:54:12 INFO - 'SHLVL': '1', 21:54:12 INFO - 'TERM': 'linux', 21:54:12 INFO - 'TMOUT': '86400', 21:54:12 INFO - 'USER': 'cltbld', 21:54:12 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1469076790.561368-759460913', 21:54:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:54:12 INFO - '_': '/tools/buildbot/bin/python'} 21:54:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:54:12 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 21:54:12 INFO - Cleaning up... 21:54:12 INFO - Return code: 0 21:54:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:54: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:54:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:54:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:54:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:54:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54: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', '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 0xfd2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11358a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1293180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12913b0>, '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 0x12922b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1292740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1469076790.561368-759460913', '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 21:54: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', 'psutil>=3.1.1'] in /builds/slave/test/build 21:54: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 psutil>=3.1.1 21:54:12 INFO - Using env: {'DISPLAY': ':0', 21:54:12 INFO - 'HOME': '/home/cltbld', 21:54:12 INFO - 'LANG': 'en_US.UTF-8', 21:54:12 INFO - 'LANGUAGE': 'en_US:en', 21:54:12 INFO - 'LOGNAME': 'cltbld', 21:54:12 INFO - 'MAIL': '/var/mail/cltbld', 21:54:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:54:12 INFO - 'MOZ_NO_REMOTE': '1', 21:54:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:54:12 INFO - 'NO_EM_RESTART': '1', 21:54:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:54:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:54:12 INFO - 'PWD': '/builds/slave/test', 21:54:12 INFO - 'SHELL': '/bin/bash', 21:54:12 INFO - 'SHLVL': '1', 21:54:12 INFO - 'TERM': 'linux', 21:54:12 INFO - 'TMOUT': '86400', 21:54:12 INFO - 'USER': 'cltbld', 21:54:12 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1469076790.561368-759460913', 21:54:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:54:12 INFO - '_': '/tools/buildbot/bin/python'} 21:54:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:54:12 INFO - Downloading/unpacking psutil>=3.1.1 21:54: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 21:54: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 21:54: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 21:54: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 21:54: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 21:54: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 21:54:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:54:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:54:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:54:14 INFO - Installing collected packages: psutil 21:54:14 INFO - Running setup.py install for psutil 21:54:14 INFO - building 'psutil._psutil_linux' extension 21:54:14 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 21:54:15 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 21:54:15 INFO - building 'psutil._psutil_posix' extension 21:54:15 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 21:54:15 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 21:54:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:54:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:54:15 INFO - Successfully installed psutil 21:54:15 INFO - Cleaning up... 21:54:15 INFO - Return code: 0 21:54:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:54:15 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:54:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:54:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:15 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:54:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:54:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:15 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 0xfd2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11358a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1293180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12913b0>, '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 0x12922b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1292740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1469076790.561368-759460913', '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 21:54:15 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 21:54:15 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 21:54:15 INFO - Using env: {'DISPLAY': ':0', 21:54:15 INFO - 'HOME': '/home/cltbld', 21:54:15 INFO - 'LANG': 'en_US.UTF-8', 21:54:15 INFO - 'LANGUAGE': 'en_US:en', 21:54:15 INFO - 'LOGNAME': 'cltbld', 21:54:15 INFO - 'MAIL': '/var/mail/cltbld', 21:54:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:54:15 INFO - 'MOZ_NO_REMOTE': '1', 21:54:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:54:15 INFO - 'NO_EM_RESTART': '1', 21:54:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:54:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:54:15 INFO - 'PWD': '/builds/slave/test', 21:54:15 INFO - 'SHELL': '/bin/bash', 21:54:15 INFO - 'SHLVL': '1', 21:54:15 INFO - 'TERM': 'linux', 21:54:15 INFO - 'TMOUT': '86400', 21:54:15 INFO - 'USER': 'cltbld', 21:54:15 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1469076790.561368-759460913', 21:54:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:54:15 INFO - '_': '/tools/buildbot/bin/python'} 21:54:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:54:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:54: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 21:54: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 21:54: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 21:54: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 21:54: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 21:54: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 21:54:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:54:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:54:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:54:17 INFO - Installing collected packages: mozsystemmonitor 21:54:17 INFO - Running setup.py install for mozsystemmonitor 21:54:17 INFO - Successfully installed mozsystemmonitor 21:54:17 INFO - Cleaning up... 21:54:17 INFO - Return code: 0 21:54:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:54:17 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:54:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:54:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:17 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:54:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:54:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:17 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 0xfd2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11358a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1293180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12913b0>, '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 0x12922b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1292740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1469076790.561368-759460913', '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 21:54:17 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 21:54:17 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 21:54:17 INFO - Using env: {'DISPLAY': ':0', 21:54:17 INFO - 'HOME': '/home/cltbld', 21:54:17 INFO - 'LANG': 'en_US.UTF-8', 21:54:17 INFO - 'LANGUAGE': 'en_US:en', 21:54:17 INFO - 'LOGNAME': 'cltbld', 21:54:17 INFO - 'MAIL': '/var/mail/cltbld', 21:54:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:54:17 INFO - 'MOZ_NO_REMOTE': '1', 21:54:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:54:17 INFO - 'NO_EM_RESTART': '1', 21:54:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:54:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:54:17 INFO - 'PWD': '/builds/slave/test', 21:54:17 INFO - 'SHELL': '/bin/bash', 21:54:17 INFO - 'SHLVL': '1', 21:54:17 INFO - 'TERM': 'linux', 21:54:17 INFO - 'TMOUT': '86400', 21:54:17 INFO - 'USER': 'cltbld', 21:54:17 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1469076790.561368-759460913', 21:54:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:54:17 INFO - '_': '/tools/buildbot/bin/python'} 21:54:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:54:18 INFO - Downloading/unpacking jsonschema==2.5.1 21:54:18 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 21:54:18 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 21:54:18 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 21:54:18 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 21:54:18 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 21:54:18 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 21:54:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:54:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:54:20 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 21:54:20 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 21:54:20 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 21:54:20 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 21:54:20 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 21:54:20 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 21:54:20 INFO - Downloading functools32-3.2.3-2.tar.gz 21:54:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:54:20 INFO - warning: no files found matching '*.txt' 21:54:20 INFO - no previously-included directories found matching 'build' 21:54:20 INFO - no previously-included directories found matching 'dist' 21:54:20 INFO - no previously-included directories found matching '.git*' 21:54:20 INFO - Installing collected packages: jsonschema, functools32 21:54:20 INFO - Running setup.py install for functools32 21:54:20 INFO - warning: no files found matching '*.txt' 21:54:20 INFO - no previously-included directories found matching 'build' 21:54:20 INFO - no previously-included directories found matching 'dist' 21:54:20 INFO - no previously-included directories found matching '.git*' 21:54:20 INFO - Successfully installed jsonschema functools32 21:54:20 INFO - Cleaning up... 21:54:20 INFO - Return code: 0 21:54:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:54:20 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:54:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:54:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:20 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:54:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:54:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:20 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 0xfd2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11358a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1293180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12913b0>, '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 0x12922b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1292740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1469076790.561368-759460913', '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 21:54:20 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 21:54:20 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 21:54:20 INFO - Using env: {'DISPLAY': ':0', 21:54:20 INFO - 'HOME': '/home/cltbld', 21:54:20 INFO - 'LANG': 'en_US.UTF-8', 21:54:20 INFO - 'LANGUAGE': 'en_US:en', 21:54:20 INFO - 'LOGNAME': 'cltbld', 21:54:20 INFO - 'MAIL': '/var/mail/cltbld', 21:54:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:54:20 INFO - 'MOZ_NO_REMOTE': '1', 21:54:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:54:20 INFO - 'NO_EM_RESTART': '1', 21:54:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:54:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:54:20 INFO - 'PWD': '/builds/slave/test', 21:54:20 INFO - 'SHELL': '/bin/bash', 21:54:20 INFO - 'SHLVL': '1', 21:54:20 INFO - 'TERM': 'linux', 21:54:20 INFO - 'TMOUT': '86400', 21:54:20 INFO - 'USER': 'cltbld', 21:54:20 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1469076790.561368-759460913', 21:54:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:54:20 INFO - '_': '/tools/buildbot/bin/python'} 21:54:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:54:20 INFO - Downloading/unpacking blobuploader==1.2.4 21:54:20 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 21:54:20 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 21:54:20 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 21:54:20 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 21:54:20 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 21:54:20 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 21:54:22 INFO - Downloading blobuploader-1.2.4.tar.gz 21:54:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:54:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:54:22 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 21:54:22 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 21:54:22 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 21:54:22 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 21:54:22 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 21:54:22 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 21:54:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:54:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:54:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:54:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:54:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:54:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:54:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:54:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:54:23 INFO - Downloading docopt-0.6.1.tar.gz 21:54:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:54:23 INFO - Installing collected packages: blobuploader, requests, docopt 21:54:23 INFO - Running setup.py install for blobuploader 21:54:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:54:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:54:23 INFO - Running setup.py install for requests 21:54:23 INFO - Running setup.py install for docopt 21:54:24 INFO - Successfully installed blobuploader requests docopt 21:54:24 INFO - Cleaning up... 21:54:24 INFO - Return code: 0 21:54:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:54:24 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:54:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:54:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:24 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:54:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:54:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:24 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 0xfd2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11358a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1293180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12913b0>, '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 0x12922b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1292740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1469076790.561368-759460913', '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 21:54:24 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 21:54:24 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 21:54:24 INFO - Using env: {'DISPLAY': ':0', 21:54:24 INFO - 'HOME': '/home/cltbld', 21:54:24 INFO - 'LANG': 'en_US.UTF-8', 21:54:24 INFO - 'LANGUAGE': 'en_US:en', 21:54:24 INFO - 'LOGNAME': 'cltbld', 21:54:24 INFO - 'MAIL': '/var/mail/cltbld', 21:54:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:54:24 INFO - 'MOZ_NO_REMOTE': '1', 21:54:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:54:24 INFO - 'NO_EM_RESTART': '1', 21:54:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:54:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:54:24 INFO - 'PWD': '/builds/slave/test', 21:54:24 INFO - 'SHELL': '/bin/bash', 21:54:24 INFO - 'SHLVL': '1', 21:54:24 INFO - 'TERM': 'linux', 21:54:24 INFO - 'TMOUT': '86400', 21:54:24 INFO - 'USER': 'cltbld', 21:54:24 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1469076790.561368-759460913', 21:54:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:54:24 INFO - '_': '/tools/buildbot/bin/python'} 21:54:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:54:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:54:24 INFO - Running setup.py (path:/tmp/pip-ns1x5d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:54:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:54:24 INFO - Running setup.py (path:/tmp/pip-3W5AkJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:54:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:54:24 INFO - Running setup.py (path:/tmp/pip-cenzP6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:54:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:54:24 INFO - Running setup.py (path:/tmp/pip-0y7z6c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:54:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:54:24 INFO - Running setup.py (path:/tmp/pip-bF6Jdy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:54:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:54:24 INFO - Running setup.py (path:/tmp/pip-CHrExc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:54:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:54:24 INFO - Running setup.py (path:/tmp/pip-sZC0K2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:54:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:54:24 INFO - Running setup.py (path:/tmp/pip-I1QDQ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:54:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:54:24 INFO - Running setup.py (path:/tmp/pip-oyY8Cn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:54:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:54:24 INFO - Running setup.py (path:/tmp/pip-FWFE4s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:54:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:54:25 INFO - Running setup.py (path:/tmp/pip-Ayh0cE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:54:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:54:25 INFO - Running setup.py (path:/tmp/pip-q58CrM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:54:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:54:25 INFO - Running setup.py (path:/tmp/pip-aNQ338-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:54:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:54:25 INFO - Running setup.py (path:/tmp/pip-ZqUuzr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:54:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:54:25 INFO - Running setup.py (path:/tmp/pip-tKJa7M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:54:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:54:25 INFO - Running setup.py (path:/tmp/pip-2zAYyL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:54:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:54:25 INFO - Running setup.py (path:/tmp/pip-XhURcq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:54:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:54:25 INFO - Running setup.py install for manifestparser 21:54:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:54:25 INFO - Running setup.py install for mozcrash 21:54:25 INFO - Running setup.py install for mozdebug 21:54:25 INFO - Running setup.py install for mozdevice 21:54:25 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:54:25 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:54:25 INFO - Running setup.py install for mozfile 21:54:26 INFO - Running setup.py install for mozhttpd 21:54:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:54:26 INFO - Running setup.py install for mozinfo 21:54:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:54:26 INFO - Running setup.py install for mozInstall 21:54:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:54:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:54:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:54:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:54:26 INFO - Running setup.py install for mozleak 21:54:26 INFO - Running setup.py install for mozlog 21:54:26 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:54:26 INFO - Running setup.py install for moznetwork 21:54:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:54:26 INFO - Running setup.py install for mozprocess 21:54:26 INFO - Running setup.py install for mozprofile 21:54:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:54:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:54:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:54:26 INFO - Running setup.py install for mozrunner 21:54:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:54:27 INFO - Running setup.py install for mozscreenshot 21:54:27 INFO - Running setup.py install for moztest 21:54:27 INFO - Running setup.py install for mozversion 21:54:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:54:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:54:27 INFO - Cleaning up... 21:54:27 INFO - Return code: 0 21:54:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:54:27 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:54:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:54:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:27 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:54:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:54:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:27 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 0xfd2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11358a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1293180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12913b0>, '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 0x12922b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1292740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1469076790.561368-759460913', '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 21:54:27 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 21:54:27 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 21:54:27 INFO - Using env: {'DISPLAY': ':0', 21:54:27 INFO - 'HOME': '/home/cltbld', 21:54:27 INFO - 'LANG': 'en_US.UTF-8', 21:54:27 INFO - 'LANGUAGE': 'en_US:en', 21:54:27 INFO - 'LOGNAME': 'cltbld', 21:54:27 INFO - 'MAIL': '/var/mail/cltbld', 21:54:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:54:27 INFO - 'MOZ_NO_REMOTE': '1', 21:54:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:54:27 INFO - 'NO_EM_RESTART': '1', 21:54:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:54:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:54:27 INFO - 'PWD': '/builds/slave/test', 21:54:27 INFO - 'SHELL': '/bin/bash', 21:54:27 INFO - 'SHLVL': '1', 21:54:27 INFO - 'TERM': 'linux', 21:54:27 INFO - 'TMOUT': '86400', 21:54:27 INFO - 'USER': 'cltbld', 21:54:27 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1469076790.561368-759460913', 21:54:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:54:27 INFO - '_': '/tools/buildbot/bin/python'} 21:54:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:54:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:54:27 INFO - Running setup.py (path:/tmp/pip-puwjyf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:54:27 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)) 21:54:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:54:27 INFO - Running setup.py (path:/tmp/pip-rQ7_8o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:54:27 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)) 21:54:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:54:27 INFO - Running setup.py (path:/tmp/pip-rNtkHg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:54:27 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)) 21:54:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:54:27 INFO - Running setup.py (path:/tmp/pip-rtlq0f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:54:27 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)) 21:54:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:54:27 INFO - Running setup.py (path:/tmp/pip-wm4D1D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:54:27 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)) 21:54:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:54:27 INFO - Running setup.py (path:/tmp/pip-wvkFvx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:54:28 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)) 21:54:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:54:28 INFO - Running setup.py (path:/tmp/pip-5YIGJz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:54:28 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)) 21:54:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:54:28 INFO - Running setup.py (path:/tmp/pip-l1N2TY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:54:28 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)) 21:54:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:54:28 INFO - Running setup.py (path:/tmp/pip-GYKrMI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:54:28 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)) 21:54:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:54:28 INFO - Running setup.py (path:/tmp/pip-CEAWEW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:54:28 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)) 21:54:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:54:28 INFO - Running setup.py (path:/tmp/pip-J5U_ZU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:54:28 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)) 21:54:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:54:28 INFO - Running setup.py (path:/tmp/pip-FMBf7P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:54:28 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)) 21:54:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:54:28 INFO - Running setup.py (path:/tmp/pip-FUlLkx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:54:28 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)) 21:54:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:54:28 INFO - Running setup.py (path:/tmp/pip-Ey3t5T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:54:28 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)) 21:54:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:54:28 INFO - Running setup.py (path:/tmp/pip-u2JIYp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:54:28 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)) 21:54:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:54:28 INFO - Running setup.py (path:/tmp/pip-3EyJU4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:54:28 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)) 21:54:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:54:28 INFO - Running setup.py (path:/tmp/pip-HRYUwd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:54:28 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)) 21:54:28 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)) 21:54:28 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)) 21:54:28 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)) 21:54:28 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)) 21:54:28 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)) 21:54:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:54:28 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 21:54:28 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 21:54:28 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 21:54:28 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 21:54:28 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 21:54:28 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 21:54:30 INFO - Downloading blessings-1.6.tar.gz 21:54:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:54:30 INFO - Installing collected packages: blessings 21:54:30 INFO - Running setup.py install for blessings 21:54:30 INFO - Successfully installed blessings 21:54:30 INFO - Cleaning up... 21:54:30 INFO - Return code: 0 21:54:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:54:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:54:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:54:31 INFO - Reading from file tmpfile_stdout 21:54:31 INFO - Current package versions: 21:54:31 INFO - argparse == 1.2.1 21:54:31 INFO - blessings == 1.6 21:54:31 INFO - blobuploader == 1.2.4 21:54:31 INFO - docopt == 0.6.1 21:54:31 INFO - functools32 == 3.2.3-2 21:54:31 INFO - jsonschema == 2.5.1 21:54:31 INFO - manifestparser == 1.1 21:54:31 INFO - mozInstall == 1.12 21:54:31 INFO - mozcrash == 0.17 21:54:31 INFO - mozdebug == 0.1 21:54:31 INFO - mozdevice == 0.48 21:54:31 INFO - mozfile == 1.2 21:54:31 INFO - mozhttpd == 0.7 21:54:31 INFO - mozinfo == 0.9 21:54:31 INFO - mozleak == 0.1 21:54:31 INFO - mozlog == 3.3 21:54:31 INFO - moznetwork == 0.27 21:54:31 INFO - mozprocess == 0.23 21:54:31 INFO - mozprofile == 0.28 21:54:31 INFO - mozrunner == 6.12 21:54:31 INFO - mozscreenshot == 0.1 21:54:31 INFO - mozsystemmonitor == 0.3 21:54:31 INFO - moztest == 0.7 21:54:31 INFO - mozversion == 1.4 21:54:31 INFO - psutil == 3.1.1 21:54:31 INFO - requests == 1.2.3 21:54:31 INFO - wsgiref == 0.1.2 21:54:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:54:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:54:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:54:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:54:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:54:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:31 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 0xfd2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11358a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1293180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12913b0>, '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 0x12922b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1292740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1469076790.561368-759460913', '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 21:54:31 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 21:54:31 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 21:54:31 INFO - Using env: {'DISPLAY': ':0', 21:54:31 INFO - 'HOME': '/home/cltbld', 21:54:31 INFO - 'LANG': 'en_US.UTF-8', 21:54:31 INFO - 'LANGUAGE': 'en_US:en', 21:54:31 INFO - 'LOGNAME': 'cltbld', 21:54:31 INFO - 'MAIL': '/var/mail/cltbld', 21:54:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:54:31 INFO - 'MOZ_NO_REMOTE': '1', 21:54:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:54:31 INFO - 'NO_EM_RESTART': '1', 21:54:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:54:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:54:31 INFO - 'PWD': '/builds/slave/test', 21:54:31 INFO - 'SHELL': '/bin/bash', 21:54:31 INFO - 'SHLVL': '1', 21:54:31 INFO - 'TERM': 'linux', 21:54:31 INFO - 'TMOUT': '86400', 21:54:31 INFO - 'USER': 'cltbld', 21:54:31 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1469076790.561368-759460913', 21:54:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:54:31 INFO - '_': '/tools/buildbot/bin/python'} 21:54:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:54:31 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)) 21:54:31 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)) 21:54:31 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)) 21:54:31 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)) 21:54:31 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)) 21:54:31 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)) 21:54:31 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)) 21:54:31 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)) 21:54:31 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)) 21:54:31 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)) 21:54:31 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)) 21:54:31 INFO - Cleaning up... 21:54:31 INFO - Return code: 0 21:54:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:54:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:54:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:54:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:54:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:54:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:31 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 0xfd2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11358a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1293180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12913b0>, '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 0x12922b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1292740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1469076790.561368-759460913', '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 21:54:31 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 21:54:31 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 21:54:31 INFO - Using env: {'DISPLAY': ':0', 21:54:31 INFO - 'HOME': '/home/cltbld', 21:54:31 INFO - 'LANG': 'en_US.UTF-8', 21:54:31 INFO - 'LANGUAGE': 'en_US:en', 21:54:31 INFO - 'LOGNAME': 'cltbld', 21:54:31 INFO - 'MAIL': '/var/mail/cltbld', 21:54:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:54:31 INFO - 'MOZ_NO_REMOTE': '1', 21:54:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:54:31 INFO - 'NO_EM_RESTART': '1', 21:54:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:54:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:54:31 INFO - 'PWD': '/builds/slave/test', 21:54:31 INFO - 'SHELL': '/bin/bash', 21:54:31 INFO - 'SHLVL': '1', 21:54:31 INFO - 'TERM': 'linux', 21:54:31 INFO - 'TMOUT': '86400', 21:54:31 INFO - 'USER': 'cltbld', 21:54:31 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1469076790.561368-759460913', 21:54:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:54:31 INFO - '_': '/tools/buildbot/bin/python'} 21:54:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:54:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:54:31 INFO - Cleaning up... 21:54:31 INFO - Return code: 0 21:54:31 INFO - Running post-action listener: _resource_record_post_action 21:54:31 INFO - Running post-action listener: _start_resource_monitoring 21:54:31 INFO - Starting resource monitoring. 21:54:31 INFO - [mozharness: 2016-07-21 04:54:31.639536Z] Finished create-virtualenv step (success) 21:54:31 INFO - [mozharness: 2016-07-21 04:54:31.639957Z] Running install step. 21:54:31 INFO - Running pre-action listener: _resource_record_pre_action 21:54:31 INFO - Running main action method: install 21:54:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:54:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:54:31 INFO - Reading from file tmpfile_stdout 21:54:31 INFO - Detecting whether we're running mozinstall >=1.0... 21:54:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:54:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:54:31 INFO - Reading from file tmpfile_stdout 21:54:31 INFO - Output received: 21:54:31 INFO - Usage: mozinstall [options] installer 21:54:31 INFO - Options: 21:54:31 INFO - -h, --help show this help message and exit 21:54:31 INFO - -d DEST, --destination=DEST 21:54:31 INFO - Directory to install application into. [default: 21:54:31 INFO - "/builds/slave/test"] 21:54:31 INFO - --app=APP Application being installed. [default: firefox] 21:54:31 INFO - mkdir: /builds/slave/test/build/application 21:54:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 21:54:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 21:54:48 INFO - Reading from file tmpfile_stdout 21:54:48 INFO - Output received: 21:54:48 INFO - /builds/slave/test/build/application/firefox/firefox 21:54:48 INFO - Running post-action listener: _resource_record_post_action 21:54:48 INFO - [mozharness: 2016-07-21 04:54:48.314777Z] Finished install step (success) 21:54:48 INFO - [mozharness: 2016-07-21 04:54:48.315056Z] Running run-tests step. 21:54:48 INFO - Running pre-action listener: _resource_record_pre_action 21:54:48 INFO - Running main action method: run_tests 21:54:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:54:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:54:48 INFO - Python 2.7.3 21:54:48 INFO - Return code: 0 21:54:48 INFO - grabbing minidump binary from tooltool 21:54:48 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:54:48 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 0x12913b0>, '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 0x12922b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1292740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:54:48 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 21:54:48 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 21:54:48 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 21:54:48 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:54:48 INFO - Return code: 0 21:54:48 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:54:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:54:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:54:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:54:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:54:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WU40-pKsSU2-tXtDsUp6KA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-067', '--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 21:54:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/WU40-pKsSU2-tXtDsUp6KA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-067 --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 21:54:48 INFO - Using env: {'DISPLAY': ':0', 21:54:48 INFO - 'HOME': '/home/cltbld', 21:54:48 INFO - 'LANG': 'en_US.UTF-8', 21:54:48 INFO - 'LANGUAGE': 'en_US:en', 21:54:48 INFO - 'LOGNAME': 'cltbld', 21:54:48 INFO - 'MAIL': '/var/mail/cltbld', 21:54:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:54:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:54:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:54:48 INFO - 'MOZ_NO_REMOTE': '1', 21:54:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:54:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:54:48 INFO - 'NO_EM_RESTART': '1', 21:54:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:54:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:54:48 INFO - 'PWD': '/builds/slave/test', 21:54:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:54:48 INFO - 'SHELL': '/bin/bash', 21:54:48 INFO - 'SHLVL': '1', 21:54:48 INFO - 'TERM': 'linux', 21:54:48 INFO - 'TMOUT': '86400', 21:54:48 INFO - 'USER': 'cltbld', 21:54:48 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1469076790.561368-759460913', 21:54:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:54:48 INFO - '_': '/tools/buildbot/bin/python'} 21:54:48 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WU40-pKsSU2-tXtDsUp6KA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-067', '--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 21:54:48 INFO - mozversion application_buildid: 20160720211719 21:54:48 INFO - mozversion application_changeset: 17409ee90c864e7e982a43e2026e6814a0797e4d 21:54:48 INFO - mozversion application_display_name: Nightly 21:54:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:54:48 INFO - mozversion application_name: Firefox 21:54:48 INFO - mozversion application_remotingname: firefox 21:54:48 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 21:54:48 INFO - mozversion application_vendor: Mozilla 21:54:48 INFO - mozversion application_version: 50.0a1 21:54:48 INFO - mozversion platform_buildid: 20160720211719 21:54:48 INFO - mozversion platform_changeset: 17409ee90c864e7e982a43e2026e6814a0797e4d 21:54:48 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 21:54:48 INFO - mozversion platform_version: 50.0a1 21:54:48 INFO - using testdate: 1469076888 21:54:48 INFO - actual date: 1469076888 21:54:48 INFO - starting webserver on 'localhost:50122' 21:54:48 INFO - SUITE-START | Running 2 tests 21:54:48 INFO - TEST-START | dromaeo_css 21:54:48 INFO - operating with platform_type : linux_ 21:54:48 INFO - Initialising browser for dromaeo_css test... 21:54:48 INFO - TEST-INFO | started process 5321 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7pSX1A/profile http://localhost:50122/getInfo.html) 21:54:52 INFO - PROCESS | 5321 | __metrics Screen width/height:1600/1200 21:54:52 INFO - PROCESS | 5321 | colorDepth:24 21:54:52 INFO - PROCESS | 5321 | Browser inner width/height: 1024/697 21:54:52 INFO - PROCESS | 5321 | __metrics 21:54:54 INFO - PROCESS | 5321 | 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" 21:54:54 INFO - PROCESS | 5321 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:54:54 INFO - PROCESS | 5321 | console.error: PushService: 21:54:54 INFO - PROCESS | 5321 | stateChangeProcessEnqueue: Error transitioning state 21:54:54 INFO - PROCESS | 5321 | UnknownError 21:54:55 INFO - TEST-INFO | 5321: exit 0 21:54:55 INFO - Browser initialized. 21:54:55 INFO - Running cycle 1/1 for dromaeo_css test... 21:54:55 INFO - TEST-INFO | started process 5406 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7pSX1A/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:55:07 INFO - PROCESS | 5406 | RSS: Main: 158535680 21:55:07 INFO - PROCESS | 5406 | 21:55:58 INFO - PROCESS | 5406 | 1469076958543 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] 21:58:11 INFO - PROCESS | 5406 | Cycle 1(1): loaded http://localhost:50122/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50122/tests/dromaeo/cssquery-ext.html) 21:58:12 INFO - PROCESS | 5406 | RSS: Main: 249094144 21:58:12 INFO - PROCESS | 5406 | 22:01:15 INFO - PROCESS | 5406 | Cycle 1(1): loaded http://localhost:50122/tests/dromaeo/cssquery-ext.html (next: http://localhost:50122/tests/dromaeo/cssquery-jquery.html) 22:01:15 INFO - PROCESS | 5406 | RSS: Main: 249946112 22:01:15 INFO - PROCESS | 5406 | 22:04:17 INFO - PROCESS | 5406 | Cycle 1(1): loaded http://localhost:50122/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50122/tests/dromaeo/cssquery-mootools.html) 22:04:18 INFO - PROCESS | 5406 | RSS: Main: 189030400 22:04:18 INFO - PROCESS | 5406 | 22:07:20 INFO - PROCESS | 5406 | Cycle 1(1): loaded http://localhost:50122/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50122/tests/dromaeo/cssquery-prototype.html) 22:07:21 INFO - PROCESS | 5406 | RSS: Main: 191434752 22:07:21 INFO - PROCESS | 5406 | 22:10:24 INFO - PROCESS | 5406 | Cycle 1(1): loaded http://localhost:50122/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50122/tests/dromaeo/cssquery-yui.html) 22:10:24 INFO - PROCESS | 5406 | RSS: Main: 191520768 22:10:24 INFO - PROCESS | 5406 | 22:13:27 INFO - PROCESS | 5406 | Cycle 1(1): loaded http://localhost:50122/tests/dromaeo/cssquery-yui.html (next: http://localhost:50122/tests/dromaeo/cssquery-dojo.html) 22:13:27 INFO - PROCESS | 5406 | RSS: Main: 281944064 22:13:27 INFO - PROCESS | 5406 | 22:13:27 INFO - PROCESS | 5406 | __start_tp_report 22:13:27 INFO - PROCESS | 5406 | _x_x_mozilla_page_load 22:13:27 INFO - PROCESS | 5406 | _x_x_mozilla_page_load_details 22:13:27 INFO - PROCESS | 5406 | |i|pagename|runs| 22:13:27 INFO - PROCESS | 5406 | |0;dojo.html;2575.4849030193964;2599.220233929821;2634.1965700461355;2649.920502384928;2652.164568161029;4652.278896770998;4655.278431843066;4696.154692155411;4697.318888761128;4718.914649630587;4630.259158534635;4672.626189904808;4686.156491831477;4695.600873925712;4739.194336962716;4291.570842915715;4319.71921825082;4358.5423530529315;4404.845830395937;4513.909721805562;4437.156940181371;4484.66365022623;4495.348174514694;4500.909981800362;4514.932276015862;4204.453421055274;4240.130773191519;4242.936355954663;4286.592773686495;4396.758178300192;4249.2563801334645;4260.510041345254;4316.633086187678;4316.784160791979;4381.890452738675;4320;4329.7618630975285;4335.891602709926;4365.017870979036;4377.671674624416;4555.567221113989;4627.213373726475;4709.011107667394;4709.411323584552;4761.666683332168;4988.077205716918;5045.621578381636;5046.2178362353525;5100.081985242655;5112.207607820794;5060.924086138711;5087.465816089331;5121.487851214886;5131.820386286462;5139.974300128475;4593.081383723269;4715.74063426508;4740.715557066587;4747.881302967419;4789.281607758864;5215.739213039333;5233.685978841282;5268.315119034501;5287.4976877196605;5288.65623734456;5033.421156567042;5039.3448851649055;5072.54347108762;5080.847574572768;5085.364329458817;4934.062528119646;4965.453800081988;5001.824936127218;5152.124138896396;5161;5006.4743201964;5008.84973450797;5014.222795466709;5050.090983622983;5060.544550990428;4950.356453661073;4956.306117143603;4964.801407943714;4966.90066198674;5010.123228435009;4238.554951730086;4263.936040959388;4307.763073031013;4309.784510774449;4350.912981740411;3737.028372623138;3740.831662575191;3784.1863999240295;3820.2932457495453;3842.8078596070086;4352.521222665504;4359.585839345257;4360.454943132108;4362.0185458271635;4410.16206920684;4928.457869634337;4960.751962401865;5034.496550344937;5040.747962601855;5084.957583695334;4309.310510318334;4348.347747837786;4361.956380436155;4370.2352088384405;4416.227160246944;4568.771561421916;4569.497355290915;4574.382458368144;4579.244424669892;4621.514740952151;4642.837500687459;4708.552687494683;4746.620270378361;4750.168720473835;4790.616750659939;4979;4999.375078115236;5002.774875130701;5046.823361182341;5062.898742025212;4722.952770472251;4728.361671174347;4745.928811067905;4760.78576464053;4798.592119669867;4881.90236195267;4946.08497427977;4960.131976904099;4970.502949705001;5005.924911126263;4962.727050012284;5005.34930459038;5035.219540971156;5053.74731263428;5155.639105262596;4844.806207751792;4845.75771211445;4848.737452676195;4850.1269771441475;4909.26361045846;4842.927356089591;4858.246971719389;4868.2940973557925;4870.147724148331;4925.113479573711;4755.191617424977;4761.142994261067;4836.105320515716;4837.7097374157665;4918.016396720599;4920.803167873245;4935.901281974412;4938.037082768969;4938.259261110862;4980.576650984707;4719;4832.589229915497;4848.9757551212015;4852.2479015752615;4894.265860120868 22:13:27 INFO - PROCESS | 5406 | |1;ext.html;558.7346010644944;576.9942300576994;577.2121054760254;582.0832189301848;582.9562782791289;27561.58657620135;40124.79937600312;41671.58328416715;41677.37483937739;46038.76980615092;42347.788261058726;42602.14795704084;42633;42656.146877062514;42681.57318426823;7501.174870764212;7823.921760782405;8015.7194498192575;8026.478278911881;8027.638756255953;7681.539107653531;7807.141214466405;7843;10164.491775411238;10400.479976001208;10388.63639772608;10439.164866810637;10455.58177672892;10455.895441045568;10464.319819211727;10340.534675939602;10396.220283478693;10411.687649370531;10420.791584168312;10422.583096676124;7512.173660897297;9981.251406144496;10163.898361016443;10371.844422333596;10377.429241391721;40153.19693606112;40431.7978410109;40536.75705943517;40575;40604;2753.765929895961;2829.3983810765403;2908.4473949949443;3004.2038859702197;3145.9370812583848;29930.70069299323;30018.699813002026;30047.248818779488;30298.84850575733;31233;3264.069740124053;3289.3750187464307;3363.8930178270493;3397.5583174187445;3456.9308613827584;267023;269121;269537;269629;270016;54873.176902346495;55093.44906550883;55096.72451637716;55141.72429137828;55166.44833551613;58553.41446585565;58844;58893;59046.70476647675;59048.40951590515;15136.54590362269;15427.382904683911;15597.142157181464;15615.141167235684;15646.217689115727;11403.42982850854;11424.42877856104;11445.599404020986;11462.140339474407;11471.311721296748;15208.163551004802;17870.91064544669;18196.45410637683;18396.540086497946;18478.445646630622;5848.181254624356;5860.384659610765;5861.238039054895;5914.172015917775;5997.940020599738;780.7384526183679;884.0805562215224;922.1331947968888;930.8231436027133;1002.5438425516372;28588.428231435253;28992.565111523345;29209;29282.414351712847;29585.704142958726;912.648630277348;988.619381538113;998.6255154317131;1043.4261156363968;1049.7060822969581;14868.553943381716;15587.220638968007;15595;15864;15912.443064492687;397204;400093;400267;400884;401190;50429.49570504321;50450.49549504458;50511.24233136506;50551.49448505468;50732;1021.4075836015094;1130.9830352544718;1192.3501691577937;1211.2792888231488;1220.7558488302552;28292.575611366257;29771.255718607208;30296.242593935327;30424.543631845983;30777.538336924517;43128.56871431245;43782.12435751203;43801.56198438102;43807.34288985732;43814.56185438232;326954;329056;329229;329352;329688;32274.838625806722;37584.812075939444;37932.81033594815;38379.2324153521;41675.16649667051;42789.78605106955;42925.78537107295;43009.56990430056;43429;44259;37604.623953760114;37660.57544693728;38051.61948380481;38112.237755245296;39310.80344598373;36609;36646;37162;37444.43833342448;37785.81107094557;36033.81983090068;36537.451938220416;36610.26779464449;36691;36696.63303367039;32087.358252834347;33216;33345.16637083995;33491.83254083714;33665.15837104092;25421.491570168124;25574.872125639253;25775.742242578086;25795.484090318463;26274.08040718566 22:13:27 INFO - PROCESS | 5406 | |2;jquery.html;344.8723972130312;354.969827564657;365.95425571803526;369.7060836634875;375.72628788407303;13005.479780808768;16370.83629163708;16597.25312360945;17381.91309043453;18293.451196464095;17103.82896171041;17555.122243887818;17604.207810648553;17667.73498397525;17813.7327940081;5090.720010399426;5174.818881339153;5444.727763611823;5454.7272636368025;5528.06022976093;3533.734969877257;5156;5450.482204190615;5494.780208791643;5496.6701997880255;5433.972830135864;5445.237666726662;5480;5482.39693633702;5485.204645326426;5485.01269771441;5491.478309560605;5502.614816962814;5994.6403215807195;6026.9095963560585;5362.839114826562;5370.516653501165;5371.677699338053;5372.086745253317;5374.355077390699;20024.499387515283;20946.476338091517;20969.68545471819;21108.366748997556;21173.153073877027;1755.7015307397774;1774.2459454731816;1807.764990551233;1810.9094545272683;1836.4674244469088;9038.774030649221;9160.816783664355;9215.078492150798;9658;9687.27345449087;1650.6038550747853;1696.7539706742577;1751.868609854266;1774.1838754173095;1806.846418054467;64914.67542662256;67647.66176169137;68791.3120868795;70135.64932175358;70944;8532.8720069199;8537.871931921027;8679.26226270774;9778.16885564735;10312.639057632947;13767.518136865161;13774.65563360904;13789.448422063006;13809.723805523834;13811.861881381057;6835.282295359015;7349.04462419882;7363.374113200438;7501.28737769911;7623.275788800055;6885.208201056943;6992.860142797115;7004.0544526488275;7057.6471176442;7342.192358840523;4155.898686847988;4252.043290259667;4310.965368311646;4364.759938203366;4411.823527058882;1723.6552689461907;1749.7200447928526;1757.0599729144847;1817.0551313317;1845.7600511933363;627.5230824573357;821.1706176761395;835.3776436554806;911.9908800912038;917.4816228830689;16692.165391730367;16908.746368804477;17301;17519.64960700779;17737.37919172823;839.8110425154291;845.3533047218882;853.6628031927354;881.625309243569;933.883264591926;9478.763030924281;9709.174720148725;9862.704118876445;10007.483063992662;10031.147352474976;390688;397618;407436;409384;410299;80996;81126;81133;81267.99999999882;83793.58103209567;892.2103938015033;916.4180745226696;941.8445956417252;943.2077055273603;944.5938246554048;15011;15355.769663455281;15573.844261557693;15708.136052516764;16073.75889361684;37619;38006.619933800306;38575.807120964215;39311.41032884452;39611.405828913164;52078.73960630173;52224.21663674972;52288.47711522836;52290;52327.476725233784;6896.724131034903;6929.411000065051;7196.712131514681;7301.817454563678;7423.257674232534;10768.892311076788;11377.374244416636;12225.816612750637;12260.75478490443;12401.25592464455;9530.857037144588;9723.805523889341;9772.364796288219;9886.604535818487;9950.402975821184;10403.167746580104;10552.894471055499;10644.787104258026;10646.391634049707;10887.455627218766;9566;9861.408315500807;9894.109530142321;9938.10557049869;9963.950180249052;6814.591124532544;7103.396211322096;7140.46446516503;7308.780736577911;7502.774916752506;9521.19069879068;9730.75673108178;9813.803723925625;9888.555015024485;10015.449150296803 22:13:27 INFO - PROCESS | 5406 | |3;mootools.html;1366.0096430088188;1417.773156294993;1469.3902030657277;1693.864490840733;1699.4816580942816;7569.810754731135;7906.762797116081;7952.085510166333;7952.244536769005;7960.721374751877;6595.208574970999;6746.257911629717;6752.628605426699;6960.54756440831;7732.845343093135;1896.2983696032488;2134.7331583552054;2220.6891035255076;2222.48882756966;2234.0393630738777;2189.430748005522;2201.1305534313938;2204.096320508592;2208.503086805472;2212.1151539384214;2193.396815875639;2240.8767517786514;2241.540484200735;2242.439390152462;2243.6298010828277;1904.2192700992596;2130.5312831177116;2191.769864164272;2192.495725983033;2207.9558408831736;2162.1135334512774;2167.5448155887284;2171.4354267890462;2177.2162021672343;2177.368563116694;7726.150123486412;7752.3410510106705;7777.02787151606;7778.338841198448;7787;4508.075844451912;4623.768811559408;4632.7683615819415;4647.465541462741;4647.721136731807;2814.4089741154175;2829.9858500707364;2911.7088291170926;2942.7204415580486;2943.3083225442006;964.5081008685551;967.6129548180713;970.815545046446;984.714432814483;988.6193815381058;10639.095676867475;10642;10653.147748180127;10655.360678359246;10663.946680266627;4186.958130418657;4210.789460526961;4303.698741088094;4306.741595504217;4413.16149931512;8258.628361723617;8385.538795366192;8385.95807020961;8386.203310685476;8396.664133434717;4643.187442197602;4657.464391594942;4659.58063774262;4667.206574882211;4671;4204.8528301509305;4264.955086003949;4342.392065110887;4343.869683909487;4349.043210493686;3202.8718851246153;3216.533602627606;3264.542963985044;3270.1824543864036;3276.541284220211;990.8662330585279;998.3311181508407;1000.8448690452846;1001.62439085343;1010.4139599032543;645.2998496631208;769.9307062364414;774.8527779721835;792.1919641965163;802.5144787403653;4824.734639594786;4892.730899800546;4976.776045077981;5011.373578302712;5164.48355164488;403.3586597310291;407.90618157823866;409.76643313311126;415.43085972217654;415.70277251765134;3121.8923318210504;3321.0368992992244;3338.315645292709;3356.7985920844826;3403.3873902697255;9091.31815113856;9382.061793820563;9544.284178686627;9614.086661767122;9614.134727874525;9423.199028082552;9437.952810235905;9527.809443811224;9557.713268601952;9568.904310956941;7136.429085673134;7154.39187669054;7258.165310989199;7347.448941329315;7415.555066696015;4852.296417019583;4873.342098816614;4879.170541007966;4886.486918873609;4991.750412479289;8814.955925220333;8816.471011739317;8829.602667879832;8836.293096552388;8910.643574257216;8659.874216351835;9075.092490751136;9167.541622918694;9169.724908252763;9177.08229177077;5651.943480565142;5964.761409543569;6146.078088286793;6243.157173781481;6311;7528.774136775905;7586.924130758622;7595.240475952361;7596.392288617008;7601.543907365576;6525.64108974011;6556.442702370352;6567.704453299709;6581.14445122131;6726.990951357336;6425.228972523327;6440;6466.288708242184;6491.870162596816;6701.698423571049;6189.133521307111;6252;6257.155284036597;6264.21697287839;6265.718042687999;4181.121964387513;4332.935005974976;4371.978140109279;4413.3159360299205;4420.535843736361;4206.579342065769;4262.616364527207;4262.616364527207;4362.432883725095;4456.777161141995 22:13:27 INFO - PROCESS | 5406 | |4;prototype.html;299.28918817807704;299.3563837748841;305.38007844076526;306.4132186862159;306.76379188025237;4872.098661747575;4874.78063487143;4898.608111351093;4929.4824043475455;4939.234418665108;4829.7585120744;4835.782389792459;4843.46721860596;4849.10291596055;4862.683925544837;3858.6720128789016;3861.1891502784447;3871.477350557681;3893.0462036801023;3898.142408670102;3587.121155316951;3804.733668643182;3890.2414029264305;3918.8628398006076;3919.5492518360325;3761.849526018956;3779.39529675252;3790.9620903791024;3794.392897136459;3795.9430608540756;3759.575074077205;3760.623937606245;3762.9247415051545;3774.1885494618787;3774.905627359324;3100.945678469309;3178.666240044808;3178.8728450861936;3308.7353011759;3323.900282991514;4647.140279048387;4686.9765651171865;4702.952970470285;4710.764461776897;4714.339992401066;4292.205941900758;4300.569943005706;4310.741355518648;4316.223079845627;4335.479742430897;4717.787699553528;4822.445418776851;5073.162928116856;5092.319534553589;5115;3605.3510368133725;3608.0979755061235;3618.8009659468717;3623.746337756358;3625.981870090659;5226.73866306687;5227.163653815372;5268.288781014552;5274.367075950872;5276.12943864262;3645.7265705072227;3686.64976827201;3697.4638677391627;3722.106694393326;3732.0483276764794;3713.9814300928865;3722.181120153562;3727.6086010969;3764.6047165047275;3765.3787125124595;4286.507051689034;4286.914261714748;4293.635041021485;4338.414314067623;4346.1525002624185;5030.314844527051;5056.974715126401;5063.367079115111;5063.670861393997;5065.189569668909;1234.1422711215798;1298.1367390685277;1298.4935875008755;1298.6298904812083;1306.3664122900575;805.1988271669726;816.5957850863741;830.543201239316;830.8089139497949;841.2680967558264;4031.5565287818317;4040.8585699500377;4057.797110144481;4094.959050409517;4161.979190104091;4633.443986721548;4651.511591282867;4689.484156742756;4699.9085406994245;4700.952990470051;4087.346024636044;4096.6313031826685;4104.876853694334;4153.460050193516;4160.460629567019;2328.6157783965796;2456.7788898999174;2463.8398504097177;2475.8266921315694;2490.788282995966;4369.257226271541;4704.364910737075;4801.927971080437;4984.127777638899;5002.449730529639;5425.321834770653;5435.619506634576;5436.565074794007;5458.972705136449;5463.453654634584;5015.3730783652045;5023.8492845214705;5029.547340739351;5031.84904452867;5033;5033.697978121324;5247.9737601311745;5252.579793616578;5278.97360513195;5281.260623512634;5166.2767212589515;5181.2746215529105;5181.326427564392;5203.19350500673;5221.929504451502;4785.59322457595;5015.22264049073;5016.197408414782;5018.974905125451;5027.622928280321;3699.8335074921156;3709.0356507307756;3711.3319602471815;3716.3310604090443;3716.4239542870896;5233.947660523499;5252.343457067866;5255.710935898563;5318.654287471302;5329.413764485981;5041.596672266137;5045.268436076822;5057.494250574913;5058.519440653132;5093;4766.189747742823;5008.298838162587;5016.247562865599;5017.422996355466;5021.397432308147;5038.294638750651;5051.267566202807;5069;5073.18828987375;5129.102407078821;5011.899762004667;5184;5207.140821764518;5221.843344699665;5241.397239317527;5021.924671129863;5045.747712614428;5048.798048078036;5060.8987820242655;5064.29099926018 22:13:27 INFO - PROCESS | 5406 | |5;yui.html;604.6368709546325;690.4303949241873;695.15538620576;713.126420135334;718.8274814044631;12385.876141238585;12538.623841284765;12581.93709031456;12649.494020239192;12677.112602117855;12688.682782930431;13110.803337949937;13287.069905106646;13646.317684115804;13667.248301343423;787.1852632525338;804.0311424733183;805.6374631415857;806.6450761664848;806.7499075286651;620.9720562574695;780.2080887898787;803.0203152154362;807.2855522862251;810.5055915891331;847.3094428041127;848.367965865428;848.4866655673321;849.1932663969224;850.1371108325025;843.2958479669484;843.6372359885247;844.2022288936957;845;845.1379592815325;481.7157876852674;589.4047012517345;606.8118883146238;611.2237458427816;615.8214117905802;13264.204147751167;13274.867251327556;13280.86719132806;13298;13337.066405351628;12119.636410907688;12181.23372111039;12469.127161098726;12602.68493287666;13133.934330328382;11083.168762342775;11264.267822591506;11474.082073434105;11980.940095299555;12079.214851034654;4010.197960407901;4063.9796801015805;4084.326086195774;4123.340265557496;4156.5635608261;61680.38319616791;61835.69082154606;62363.68818155926;62615.37384626141;62826;10032;10086.697399078039;10126.05230497766;10382.844257336146;10757.085647720032;17849.643007139784;17874.374396896306;17904.731429028576;17905.641887162183;17908.373206937693;6627.635480048547;6633.4693224541925;6640.634765087966;6641.169853768279;6641.535092543476;3599.5500562429697;3685.6130106338983;3705.814709264526;3771.113788259757;3771.132639492932;2524.1922584773065;2608.595667671514;2619.2796980830426;2640.8943642254094;2647.3116989582845;490.7668857292829;494.57960733377064;547.4252035362853;568.590614757374;580.0516156084832;525.136151031551;535.563515734675;538.8410418926426;547.2120146988403;549.9202615620712;7906.209379062047;7908.655528560159;8896.510691912006;9086.091390860862;9107.544622768835;446.2191165460444;496.31012892080037;513.7713717395798;515.3712470785636;536.419820662614;3609.8375573099274;3651.726120540917;3658.835352409148;3793.4878791363367;3814.9237015260096;57212;57383.42616573781;57481.71259143761;96460.0353996451;98169;87292.56353718191;90147.54926225326;91381;91793;91988.10089243199;55868;74509.6274518624;75071.24928750751;76447.61776191083;80060;6911.5853048817235;6992.720291188499;7003.6848341823725;7068.010478532907;7108;44105.33841992437;44469.77765111154;46001;46009.07981840277;46427;59242.40757592541;59786.70106649439;66366.33633663795;67493.3250667487;67797.3220267791;9242.399244049115;9317.39436936597;9321.72034838956;9348.672796452092;9469.573869175765;10187.541560629641;10415.218858585788;10440.269181157246;10555.41945192991;11112;9994.300398972293;10162.339447935861;10218;10239.180865530889;10248.180145588483;9775.657851975147;9913;10264.538095785558;10430.37417754937;10678.679639610824;8986.281097512052;9248.861267080865;9267.814643706954;9356.4386136832;9535.666251681158;8386.748397548083;8488.151184881463;8688.044315125457;8691.91308086911;8825.64697412122;8215.507069575844;8343.54110523927;8345.248927596545;8425.83148337042;8463.449875758057 22:13:27 INFO - PROCESS | 5406 | __end_tp_report 22:13:27 INFO - PROCESS | 5406 | __start_cc_report 22:13:27 INFO - PROCESS | 5406 | _x_x_mozilla_cycle_collect,1008 22:13:27 INFO - PROCESS | 5406 | __end_cc_report 22:13:27 INFO - PROCESS | 5406 | __startTimestamp1469078007899__endTimestamp 22:13:27 INFO - PROCESS | 5406 | 22:13:27 INFO - PROCESS | 5406 | ------- Summary: start ------- 22:13:27 INFO - PROCESS | 5406 | Number of tests: 6 22:13:27 INFO - PROCESS | 5406 | 22:13:27 INFO - PROCESS | 5406 | [#0] dojo.html Cycles:165 Average:4664.23 Median:4760.79 stddev:486.45 (10.2%) stddev-sans-first:459.51 22:13:27 INFO - PROCESS | 5406 | Values: 2575.5 2599.2 2634.2 2649.9 2652.2 4652.3 4655.3 4696.2 4697.3 4718.9 4630.3 4672.6 4686.2 4695.6 4739.2 4291.6 4319.7 4358.5 4404.8 4513.9 4437.2 4484.7 4495.3 4500.9 4514.9 4204.5 4240.1 4242.9 4286.6 4396.8 4249.3 4260.5 4316.6 4316.8 4381.9 4320.0 4329.8 4335.9 4365.0 4377.7 4555.6 4627.2 4709.0 4709.4 4761.7 4988.1 5045.6 5046.2 5100.1 5112.2 5060.9 5087.5 5121.5 5131.8 5140.0 4593.1 4715.7 4740.7 4747.9 4789.3 5215.7 5233.7 5268.3 5287.5 5288.7 5033.4 5039.3 5072.5 5080.8 5085.4 4934.1 4965.5 5001.8 5152.1 5161.0 5006.5 5008.8 5014.2 5050.1 5060.5 4950.4 4956.3 4964.8 4966.9 5010.1 4238.6 4263.9 4307.8 4309.8 4350.9 3737.0 3740.8 3784.2 3820.3 3842.8 4352.5 4359.6 4360.5 4362.0 4410.2 4928.5 4960.8 5034.5 5040.7 5085.0 4309.3 4348.3 4362.0 4370.2 4416.2 4568.8 4569.5 4574.4 4579.2 4621.5 4642.8 4708.6 4746.6 4750.2 4790.6 4979.0 4999.4 5002.8 5046.8 5062.9 4723.0 4728.4 4745.9 4760.8 4798.6 4881.9 4946.1 4960.1 4970.5 5005.9 4962.7 5005.3 5035.2 5053.7 5155.6 4844.8 4845.8 4848.7 4850.1 4909.3 4842.9 4858.2 4868.3 4870.1 4925.1 4755.2 4761.1 4836.1 4837.7 4918.0 4920.8 4935.9 4938.0 4938.3 4980.6 4719.0 4832.6 4849.0 4852.2 4894.3 22:13:27 INFO - PROCESS | 5406 | 22:13:27 INFO - PROCESS | 5406 | [#1] ext.html Cycles:180 Average:49791.56 Median:36285.64 stddev:88527.05 (244.0%) stddev-sans-first:88698.22 22:13:27 INFO - PROCESS | 5406 | Values: 558.7 577.0 577.2 582.1 583.0 27561.6 40124.8 41671.6 41677.4 46038.8 42347.8 42602.1 42633.0 42656.1 42681.6 7501.2 7823.9 8015.7 8026.5 8027.6 7681.5 7807.1 7843.0 10164.5 10400.5 10388.6 10439.2 10455.6 10455.9 10464.3 10340.5 10396.2 10411.7 10420.8 10422.6 7512.2 9981.3 10163.9 10371.8 10377.4 40153.2 40431.8 40536.8 40575.0 40604.0 2753.8 2829.4 2908.4 3004.2 3145.9 29930.7 30018.7 30047.2 30298.8 31233.0 3264.1 3289.4 3363.9 3397.6 3456.9 267023.0 269121.0 269537.0 269629.0 270016.0 54873.2 55093.4 55096.7 55141.7 55166.4 58553.4 58844.0 58893.0 59046.7 59048.4 15136.5 15427.4 15597.1 15615.1 15646.2 11403.4 11424.4 11445.6 11462.1 11471.3 15208.2 17870.9 18196.5 18396.5 18478.4 5848.2 5860.4 5861.2 5914.2 5997.9 780.7 884.1 922.1 930.8 1002.5 28588.4 28992.6 29209.0 29282.4 29585.7 912.6 988.6 998.6 1043.4 1049.7 14868.6 15587.2 15595.0 15864.0 15912.4 397204.0 400093.0 400267.0 400884.0 401190.0 50429.5 50450.5 50511.2 50551.5 50732.0 1021.4 1131.0 1192.4 1211.3 1220.8 28292.6 29771.3 30296.2 30424.5 30777.5 43128.6 43782.1 43801.6 43807.3 43814.6 326954.0 329056.0 329229.0 329352.0 329688.0 32274.8 37584.8 37932.8 38379.2 41675.2 42789.8 42925.8 43009.6 43429.0 44259.0 37604.6 37660.6 38051.6 38112.2 39310.8 36609.0 36646.0 37162.0 37444.4 37785.8 36033.8 36537.5 36610.3 36691.0 36696.6 32087.4 33216.0 33345.2 33491.8 33665.2 25421.5 25574.9 25775.7 25795.5 26274.1 22:13:27 INFO - PROCESS | 5406 | 22:13:27 INFO - PROCESS | 5406 | [#2] jquery.html Cycles:180 Average:24827.34 Median:5371.88 stddev:66615.36 (1240.1%) stddev-sans-first:66776.87 22:13:27 INFO - PROCESS | 5406 | Values: 344.9 355.0 366.0 369.7 375.7 13005.5 16370.8 16597.3 17381.9 18293.5 17103.8 17555.1 17604.2 17667.7 17813.7 5090.7 5174.8 5444.7 5454.7 5528.1 3533.7 5156.0 5450.5 5494.8 5496.7 5434.0 5445.2 5480.0 5482.4 5485.2 5485.0 5491.5 5502.6 5994.6 6026.9 5362.8 5370.5 5371.7 5372.1 5374.4 20024.5 20946.5 20969.7 21108.4 21173.2 1755.7 1774.2 1807.8 1810.9 1836.5 9038.8 9160.8 9215.1 9658.0 9687.3 1650.6 1696.8 1751.9 1774.2 1806.8 64914.7 67647.7 68791.3 70135.6 70944.0 8532.9 8537.9 8679.3 9778.2 10312.6 13767.5 13774.7 13789.4 13809.7 13811.9 6835.3 7349.0 7363.4 7501.3 7623.3 6885.2 6992.9 7004.1 7057.6 7342.2 4155.9 4252.0 4311.0 4364.8 4411.8 1723.7 1749.7 1757.1 1817.1 1845.8 627.5 821.2 835.4 912.0 917.5 16692.2 16908.7 17301.0 17519.6 17737.4 839.8 845.4 853.7 881.6 933.9 9478.8 9709.2 9862.7 10007.5 10031.1 390688.0 397618.0 407436.0 409384.0 410299.0 80996.0 81126.0 81133.0 81268.0 83793.6 892.2 916.4 941.8 943.2 944.6 15011.0 15355.8 15573.8 15708.1 16073.8 37619.0 38006.6 38575.8 39311.4 39611.4 52078.7 52224.2 52288.5 52290.0 52327.5 6896.7 6929.4 7196.7 7301.8 7423.3 10768.9 11377.4 12225.8 12260.8 12401.3 9530.9 9723.8 9772.4 9886.6 9950.4 10403.2 10552.9 10644.8 10646.4 10887.5 9566.0 9861.4 9894.1 9938.1 9964.0 6814.6 7103.4 7140.5 7308.8 7502.8 9521.2 9730.8 9813.8 9888.6 10015.4 22:13:27 INFO - PROCESS | 5406 | 22:13:27 INFO - PROCESS | 5406 | [#3] mootools.html Cycles:180 Average:5001.87 Median:4876.26 stddev:2844.73 (58.3%) stddev-sans-first:2839.59 22:13:27 INFO - PROCESS | 5406 | Values: 1366.0 1417.8 1469.4 1693.9 1699.5 7569.8 7906.8 7952.1 7952.2 7960.7 6595.2 6746.3 6752.6 6960.5 7732.8 1896.3 2134.7 2220.7 2222.5 2234.0 2189.4 2201.1 2204.1 2208.5 2212.1 2193.4 2240.9 2241.5 2242.4 2243.6 1904.2 2130.5 2191.8 2192.5 2208.0 2162.1 2167.5 2171.4 2177.2 2177.4 7726.2 7752.3 7777.0 7778.3 7787.0 4508.1 4623.8 4632.8 4647.5 4647.7 2814.4 2830.0 2911.7 2942.7 2943.3 964.5 967.6 970.8 984.7 988.6 10639.1 10642.0 10653.1 10655.4 10663.9 4187.0 4210.8 4303.7 4306.7 4413.2 8258.6 8385.5 8386.0 8386.2 8396.7 4643.2 4657.5 4659.6 4667.2 4671.0 4204.9 4265.0 4342.4 4343.9 4349.0 3202.9 3216.5 3264.5 3270.2 3276.5 990.9 998.3 1000.8 1001.6 1010.4 645.3 769.9 774.9 792.2 802.5 4824.7 4892.7 4976.8 5011.4 5164.5 403.4 407.9 409.8 415.4 415.7 3121.9 3321.0 3338.3 3356.8 3403.4 9091.3 9382.1 9544.3 9614.1 9614.1 9423.2 9438.0 9527.8 9557.7 9568.9 7136.4 7154.4 7258.2 7347.4 7415.6 4852.3 4873.3 4879.2 4886.5 4991.8 8815.0 8816.5 8829.6 8836.3 8910.6 8659.9 9075.1 9167.5 9169.7 9177.1 5651.9 5964.8 6146.1 6243.2 6311.0 7528.8 7586.9 7595.2 7596.4 7601.5 6525.6 6556.4 6567.7 6581.1 6727.0 6425.2 6440.0 6466.3 6491.9 6701.7 6189.1 6252.0 6257.2 6264.2 6265.7 4181.1 4332.9 4372.0 4413.3 4420.5 4206.6 4262.6 4262.6 4362.4 4456.8 22:13:27 INFO - PROCESS | 5406 | 22:13:27 INFO - PROCESS | 5406 | [#4] prototype.html Cycles:180 Average:4181.16 Median:4712.55 stddev:1237.73 (26.3%) stddev-sans-first:1206.42 22:13:27 INFO - PROCESS | 5406 | Values: 299.3 299.4 305.4 306.4 306.8 4872.1 4874.8 4898.6 4929.5 4939.2 4829.8 4835.8 4843.5 4849.1 4862.7 3858.7 3861.2 3871.5 3893.0 3898.1 3587.1 3804.7 3890.2 3918.9 3919.5 3761.8 3779.4 3791.0 3794.4 3795.9 3759.6 3760.6 3762.9 3774.2 3774.9 3100.9 3178.7 3178.9 3308.7 3323.9 4647.1 4687.0 4703.0 4710.8 4714.3 4292.2 4300.6 4310.7 4316.2 4335.5 4717.8 4822.4 5073.2 5092.3 5115.0 3605.4 3608.1 3618.8 3623.7 3626.0 5226.7 5227.2 5268.3 5274.4 5276.1 3645.7 3686.6 3697.5 3722.1 3732.0 3714.0 3722.2 3727.6 3764.6 3765.4 4286.5 4286.9 4293.6 4338.4 4346.2 5030.3 5057.0 5063.4 5063.7 5065.2 1234.1 1298.1 1298.5 1298.6 1306.4 805.2 816.6 830.5 830.8 841.3 4031.6 4040.9 4057.8 4095.0 4162.0 4633.4 4651.5 4689.5 4699.9 4701.0 4087.3 4096.6 4104.9 4153.5 4160.5 2328.6 2456.8 2463.8 2475.8 2490.8 4369.3 4704.4 4801.9 4984.1 5002.4 5425.3 5435.6 5436.6 5459.0 5463.5 5015.4 5023.8 5029.5 5031.8 5033.0 5033.7 5248.0 5252.6 5279.0 5281.3 5166.3 5181.3 5181.3 5203.2 5221.9 4785.6 5015.2 5016.2 5019.0 5027.6 3699.8 3709.0 3711.3 3716.3 3716.4 5233.9 5252.3 5255.7 5318.7 5329.4 5041.6 5045.3 5057.5 5058.5 5093.0 4766.2 5008.3 5016.2 5017.4 5021.4 5038.3 5051.3 5069.0 5073.2 5129.1 5011.9 5184.0 5207.1 5221.8 5241.4 5021.9 5045.7 5048.8 5060.9 5064.3 22:13:27 INFO - PROCESS | 5406 | 22:13:27 INFO - PROCESS | 5406 | [#5] yui.html Cycles:180 Average:16900.09 Median:29030.88 stddev:24302.35 (83.7%) stddev-sans-first:24339.73 22:13:27 INFO - PROCESS | 5406 | Values: 604.6 690.4 695.2 713.1 718.8 12385.9 12538.6 12581.9 12649.5 12677.1 12688.7 13110.8 13287.1 13646.3 13667.2 787.2 804.0 805.6 806.6 806.7 621.0 780.2 803.0 807.3 810.5 847.3 848.4 848.5 849.2 850.1 843.3 843.6 844.2 845.0 845.1 481.7 589.4 606.8 611.2 615.8 13264.2 13274.9 13280.9 13298.0 13337.1 12119.6 12181.2 12469.1 12602.7 13133.9 11083.2 11264.3 11474.1 11980.9 12079.2 4010.2 4064.0 4084.3 4123.3 4156.6 61680.4 61835.7 62363.7 62615.4 62826.0 10032.0 10086.7 10126.1 10382.8 10757.1 17849.6 17874.4 17904.7 17905.6 17908.4 6627.6 6633.5 6640.6 6641.2 6641.5 3599.6 3685.6 3705.8 3771.1 3771.1 2524.2 2608.6 2619.3 2640.9 2647.3 490.8 494.6 547.4 568.6 580.1 525.1 535.6 538.8 547.2 549.9 7906.2 7908.7 8896.5 9086.1 9107.5 446.2 496.3 513.8 515.4 536.4 3609.8 3651.7 3658.8 3793.5 3814.9 57212.0 57383.4 57481.7 96460.0 98169.0 87292.6 90147.5 91381.0 91793.0 91988.1 55868.0 74509.6 75071.2 76447.6 80060.0 6911.6 6992.7 7003.7 7068.0 7108.0 44105.3 44469.8 46001.0 46009.1 46427.0 59242.4 59786.7 66366.3 67493.3 67797.3 9242.4 9317.4 9321.7 9348.7 9469.6 10187.5 10415.2 10440.3 10555.4 11112.0 9994.3 10162.3 10218.0 10239.2 10248.2 9775.7 9913.0 10264.5 10430.4 10678.7 8986.3 9248.9 9267.8 9356.4 9535.7 8386.7 8488.2 8688.0 8691.9 8825.6 8215.5 8343.5 8345.2 8425.8 8463.4 22:13:27 INFO - PROCESS | 5406 | -------- Summary: end -------- 22:13:27 INFO - PROCESS | 5406 | 22:13:28 INFO - TEST-INFO | 5406: exit 0 22:13:28 INFO - TEST-OK | dromaeo_css | took 1120194ms 22:13:28 INFO - TEST-START | kraken 22:13:28 INFO - operating with platform_type : linux_ 22:13:28 INFO - Initialising browser for kraken test... 22:13:28 INFO - TEST-INFO | started process 5595 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcrKYQx/profile http://localhost:50122/getInfo.html) 22:13:32 INFO - PROCESS | 5595 | __metrics Screen width/height:1600/1200 22:13:32 INFO - PROCESS | 5595 | colorDepth:24 22:13:32 INFO - PROCESS | 5595 | Browser inner width/height: 1024/697 22:13:32 INFO - PROCESS | 5595 | __metrics 22:13:33 INFO - PROCESS | 5595 | 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:13:33 INFO - PROCESS | 5595 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:13:34 INFO - TEST-INFO | 5595: exit 0 22:13:34 INFO - Browser initialized. 22:13:34 INFO - Running cycle 1/1 for kraken test... 22:13:34 INFO - TEST-INFO | started process 5676 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcrKYQx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:13:46 INFO - PROCESS | 5676 | RSS: Main: 159072256 22:13:46 INFO - PROCESS | 5676 | 22:13:48 INFO - PROCESS | 5676 | Cycle 1(1): loaded http://localhost:50122/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50122/tests/kraken/driver.html?testName=audio-beat-detection) 22:13:49 INFO - PROCESS | 5676 | RSS: Main: 215482368 22:13:49 INFO - PROCESS | 5676 | 22:13:52 INFO - PROCESS | 5676 | Cycle 1(1): loaded http://localhost:50122/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50122/tests/kraken/driver.html?testName=audio-dft) 22:13:53 INFO - PROCESS | 5676 | RSS: Main: 192540672 22:13:53 INFO - PROCESS | 5676 | 22:13:56 INFO - PROCESS | 5676 | Cycle 1(1): loaded http://localhost:50122/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50122/tests/kraken/driver.html?testName=audio-fft) 22:13:57 INFO - PROCESS | 5676 | RSS: Main: 273498112 22:13:57 INFO - PROCESS | 5676 | 22:13:59 INFO - PROCESS | 5676 | Cycle 1(1): loaded http://localhost:50122/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50122/tests/kraken/driver.html?testName=audio-oscillator) 22:13:59 INFO - PROCESS | 5676 | RSS: Main: 181874688 22:13:59 INFO - PROCESS | 5676 | 22:14:02 INFO - PROCESS | 5676 | Cycle 1(1): loaded http://localhost:50122/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50122/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:14:02 INFO - PROCESS | 5676 | RSS: Main: 186167296 22:14:02 INFO - PROCESS | 5676 | 22:14:05 INFO - PROCESS | 5676 | Cycle 1(1): loaded http://localhost:50122/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50122/tests/kraken/driver.html?testName=imaging-darkroom) 22:14:06 INFO - PROCESS | 5676 | RSS: Main: 223019008 22:14:06 INFO - PROCESS | 5676 | 22:14:10 INFO - PROCESS | 5676 | Cycle 1(1): loaded http://localhost:50122/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50122/tests/kraken/driver.html?testName=imaging-desaturate) 22:14:10 INFO - PROCESS | 5676 | RSS: Main: 217219072 22:14:10 INFO - PROCESS | 5676 | 22:14:13 INFO - PROCESS | 5676 | Cycle 1(1): loaded http://localhost:50122/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50122/tests/kraken/driver.html?testName=json-parse-financial) 22:14:14 INFO - PROCESS | 5676 | RSS: Main: 219971584 22:14:14 INFO - PROCESS | 5676 | 22:14:15 INFO - PROCESS | 5676 | Cycle 1(1): loaded http://localhost:50122/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50122/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:14:16 INFO - PROCESS | 5676 | RSS: Main: 185561088 22:14:16 INFO - PROCESS | 5676 | 22:14:18 INFO - PROCESS | 5676 | Cycle 1(1): loaded http://localhost:50122/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50122/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:14:18 INFO - PROCESS | 5676 | RSS: Main: 191528960 22:14:18 INFO - PROCESS | 5676 | 22:14:21 INFO - PROCESS | 5676 | Cycle 1(1): loaded http://localhost:50122/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50122/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:14:21 INFO - PROCESS | 5676 | RSS: Main: 195469312 22:14:21 INFO - PROCESS | 5676 | 22:14:24 INFO - PROCESS | 5676 | Cycle 1(1): loaded http://localhost:50122/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50122/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:14:25 INFO - PROCESS | 5676 | RSS: Main: 190644224 22:14:25 INFO - PROCESS | 5676 | 22:14:28 INFO - PROCESS | 5676 | Cycle 1(1): loaded http://localhost:50122/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50122/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:14:28 INFO - PROCESS | 5676 | RSS: Main: 193818624 22:14:28 INFO - PROCESS | 5676 | 22:14:30 INFO - PROCESS | 5676 | Cycle 1(1): loaded http://localhost:50122/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50122/tests/kraken/driver.html?testName=ai-astar) 22:14:30 INFO - PROCESS | 5676 | RSS: Main: 244215808 22:14:30 INFO - PROCESS | 5676 | 22:14:30 INFO - PROCESS | 5676 | __start_tp_report 22:14:30 INFO - PROCESS | 5676 | _x_x_mozilla_page_load 22:14:30 INFO - PROCESS | 5676 | _x_x_mozilla_page_load_details 22:14:30 INFO - PROCESS | 5676 | |i|pagename|runs| 22:14:30 INFO - PROCESS | 5676 | |0;ai-astar;103;114;114;113;114;114;113;114;114;114 22:14:30 INFO - PROCESS | 5676 | |1;audio-beat-detection;145;123;123;175;125;124;151;123;164;122 22:14:30 INFO - PROCESS | 5676 | |2;audio-dft;163;162;159;160;161;161;161;161;160;159 22:14:30 INFO - PROCESS | 5676 | |3;audio-fft;75;74;75;109;77;77;77;75;110;76 22:14:30 INFO - PROCESS | 5676 | |4;audio-oscillator;85;86;120;85;86;86;119;86;84;132 22:14:30 INFO - PROCESS | 5676 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;118;116;117 22:14:30 INFO - PROCESS | 5676 | |6;imaging-darkroom;146;146;146;148;146;146;147;147;147;146 22:14:30 INFO - PROCESS | 5676 | |7;imaging-desaturate;97;96;96;96;95;95;95;95;95;95 22:14:30 INFO - PROCESS | 5676 | |8;json-parse-financial;64;61;62;61;62;65;63;63;62;63 22:14:30 INFO - PROCESS | 5676 | |9;json-stringify-tinderbox;66;66;65;66;66;66;75;65;65;66 22:14:30 INFO - PROCESS | 5676 | |10;stanford-crypto-aes;89;82;85;79;80;80;89;78;78;85 22:14:30 INFO - PROCESS | 5676 | |11;stanford-crypto-ccm;130;128;128;130;129;129;128;129;178;134 22:14:30 INFO - PROCESS | 5676 | |12;stanford-crypto-pbkdf2;171;166;163;164;168;167;168;169;166;167 22:14:30 INFO - PROCESS | 5676 | |13;stanford-crypto-sha256-iterative;68;66;72;66;67;66;66;65;65;67 22:14:30 INFO - PROCESS | 5676 | __end_tp_report 22:14:30 INFO - PROCESS | 5676 | __start_cc_report 22:14:30 INFO - PROCESS | 5676 | _x_x_mozilla_cycle_collect,1955 22:14:30 INFO - PROCESS | 5676 | __end_cc_report 22:14:30 INFO - PROCESS | 5676 | __startTimestamp1469078070727__endTimestamp 22:14:30 INFO - PROCESS | 5676 | 22:14:30 INFO - PROCESS | 5676 | ------- Summary: start ------- 22:14:30 INFO - PROCESS | 5676 | Number of tests: 14 22:14:30 INFO - PROCESS | 5676 | 22:14:30 INFO - PROCESS | 5676 | [#0] ai-astar Cycles:10 Average:112.70 Median:114.00 stddev:3.43 (3.0%) stddev-sans-first:0.44 22:14:30 INFO - PROCESS | 5676 | Values: 103.0 114.0 114.0 113.0 114.0 114.0 113.0 114.0 114.0 114.0 22:14:30 INFO - PROCESS | 5676 | 22:14:30 INFO - PROCESS | 5676 | [#1] audio-beat-detection Cycles:10 Average:137.50 Median:135.00 stddev:19.88 (14.7%) stddev-sans-first:20.90 22:14:30 INFO - PROCESS | 5676 | Values: 145.0 123.0 123.0 175.0 125.0 124.0 151.0 123.0 164.0 122.0 22:14:30 INFO - PROCESS | 5676 | 22:14:30 INFO - PROCESS | 5676 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:1.25 (0.8%) stddev-sans-first:1.01 22:14:30 INFO - PROCESS | 5676 | Values: 163.0 162.0 159.0 160.0 161.0 161.0 161.0 161.0 160.0 159.0 22:14:30 INFO - PROCESS | 5676 | 22:14:30 INFO - PROCESS | 5676 | [#3] audio-fft Cycles:10 Average:82.50 Median:75.50 stddev:14.27 (18.9%) stddev-sans-first:14.87 22:14:30 INFO - PROCESS | 5676 | Values: 75.0 74.0 75.0 109.0 77.0 77.0 77.0 75.0 110.0 76.0 22:14:30 INFO - PROCESS | 5676 | 22:14:30 INFO - PROCESS | 5676 | [#4] audio-oscillator Cycles:10 Average:96.90 Median:85.50 stddev:18.79 (22.0%) stddev-sans-first:19.43 22:14:30 INFO - PROCESS | 5676 | Values: 85.0 86.0 120.0 85.0 86.0 86.0 119.0 86.0 84.0 132.0 22:14:30 INFO - PROCESS | 5676 | 22:14:30 INFO - PROCESS | 5676 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 22:14:30 INFO - PROCESS | 5676 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 118.0 116.0 117.0 22:14:30 INFO - PROCESS | 5676 | 22:14:30 INFO - PROCESS | 5676 | [#6] imaging-darkroom Cycles:10 Average:146.50 Median:146.50 stddev:0.71 (0.5%) stddev-sans-first:0.73 22:14:30 INFO - PROCESS | 5676 | Values: 146.0 146.0 146.0 148.0 146.0 146.0 147.0 147.0 147.0 146.0 22:14:30 INFO - PROCESS | 5676 | 22:14:30 INFO - PROCESS | 5676 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:95.50 stddev:0.71 (0.7%) stddev-sans-first:0.50 22:14:30 INFO - PROCESS | 5676 | Values: 97.0 96.0 96.0 96.0 95.0 95.0 95.0 95.0 95.0 95.0 22:14:30 INFO - PROCESS | 5676 | 22:14:30 INFO - PROCESS | 5676 | [#8] json-parse-financial Cycles:10 Average:62.60 Median:63.00 stddev:1.26 (2.0%) stddev-sans-first:1.24 22:14:30 INFO - PROCESS | 5676 | Values: 64.0 61.0 62.0 61.0 62.0 65.0 63.0 63.0 62.0 63.0 22:14:30 INFO - PROCESS | 5676 | 22:14:30 INFO - PROCESS | 5676 | [#9] json-stringify-tinderbox Cycles:10 Average:66.60 Median:66.00 stddev:2.99 (4.5%) stddev-sans-first:3.16 22:14:30 INFO - PROCESS | 5676 | Values: 66.0 66.0 65.0 66.0 66.0 66.0 75.0 65.0 65.0 66.0 22:14:30 INFO - PROCESS | 5676 | 22:14:30 INFO - PROCESS | 5676 | [#10] stanford-crypto-aes Cycles:10 Average:82.50 Median:83.50 stddev:4.25 (5.1%) stddev-sans-first:3.80 22:14:30 INFO - PROCESS | 5676 | Values: 89.0 82.0 85.0 79.0 80.0 80.0 89.0 78.0 78.0 85.0 22:14:30 INFO - PROCESS | 5676 | 22:14:30 INFO - PROCESS | 5676 | [#11] stanford-crypto-ccm Cycles:10 Average:134.30 Median:129.50 stddev:15.46 (11.9%) stddev-sans-first:16.32 22:14:30 INFO - PROCESS | 5676 | Values: 130.0 128.0 128.0 130.0 129.0 129.0 128.0 129.0 178.0 134.0 22:14:30 INFO - PROCESS | 5676 | 22:14:30 INFO - PROCESS | 5676 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.90 Median:167.50 stddev:2.33 (1.4%) stddev-sans-first:1.94 22:14:30 INFO - PROCESS | 5676 | Values: 171.0 166.0 163.0 164.0 168.0 167.0 168.0 169.0 166.0 167.0 22:14:30 INFO - PROCESS | 5676 | 22:14:30 INFO - PROCESS | 5676 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:66.50 stddev:2.04 (3.1%) stddev-sans-first:2.12 22:14:30 INFO - PROCESS | 5676 | Values: 68.0 66.0 72.0 66.0 67.0 66.0 66.0 65.0 65.0 67.0 22:14:30 INFO - PROCESS | 5676 | -------- Summary: end -------- 22:14:30 INFO - PROCESS | 5676 | 22:14:31 INFO - TEST-INFO | 5676: exit 0 22:14:31 INFO - TEST-OK | kraken | took 63021ms 22:14:31 INFO - SUITE-END | took 1183s 22:14:31 INFO - Completed test suite (00:19:43) 22:14:31 INFO - javascript benchmark 22:14:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2575.4849030193964, 2599.220233929821, 2634.1965700461355, 2649.920502384928, 2652.164568161029, 4652.278896770998, 4655.278431843066, 4696.154692155411, 4697.318888761128, 4718.914649630587, 4630.259158534635, 4672.626189904808, 4686.156491831477, 4695.600873925712, 4739.194336962716, 4291.570842915715, 4319.71921825082, 4358.5423530529315, 4404.845830395937, 4513.909721805562, 4437.156940181371, 4484.66365022623, 4495.348174514694, 4500.909981800362, 4514.932276015862, 4204.453421055274, 4240.130773191519, 4242.936355954663, 4286.592773686495, 4396.758178300192, 4249.2563801334645, 4260.510041345254, 4316.633086187678, 4316.784160791979, 4381.890452738675, 4320.0, 4329.7618630975285, 4335.891602709926, 4365.017870979036, 4377.671674624416, 4555.567221113989, 4627.213373726475, 4709.011107667394, 4709.411323584552, 4761.666683332168, 4988.077205716918, 5045.621578381636, 5046.2178362353525, 5100.081985242655, 5112.207607820794, 5060.924086138711, 5087.465816089331, 5121.487851214886, 5131.820386286462, 5139.974300128475, 4593.081383723269, 4715.74063426508, 4740.715557066587, 4747.881302967419, 4789.281607758864, 5215.739213039333, 5233.685978841282, 5268.315119034501, 5287.4976877196605, 5288.65623734456, 5033.421156567042, 5039.3448851649055, 5072.54347108762, 5080.847574572768, 5085.364329458817, 4934.062528119646, 4965.453800081988, 5001.824936127218, 5152.124138896396, 5161.0, 5006.4743201964, 5008.84973450797, 5014.222795466709, 5050.090983622983, 5060.544550990428, 4950.356453661073, 4956.306117143603, 4964.801407943714, 4966.90066198674, 5010.123228435009, 4238.554951730086, 4263.936040959388, 4307.763073031013, 4309.784510774449, 4350.912981740411, 3737.028372623138, 3740.831662575191, 3784.1863999240295, 3820.2932457495453, 3842.8078596070086, 4352.521222665504, 4359.585839345257, 4360.454943132108, 4362.0185458271635, 4410.16206920684, 4928.457869634337, 4960.751962401865, 5034.496550344937, 5040.747962601855, 5084.957583695334, 4309.310510318334, 4348.347747837786, 4361.956380436155, 4370.2352088384405, 4416.227160246944, 4568.771561421916, 4569.497355290915, 4574.382458368144, 4579.244424669892, 4621.514740952151, 4642.837500687459, 4708.552687494683, 4746.620270378361, 4750.168720473835, 4790.616750659939, 4979.0, 4999.375078115236, 5002.774875130701, 5046.823361182341, 5062.898742025212, 4722.952770472251, 4728.361671174347, 4745.928811067905, 4760.78576464053, 4798.592119669867, 4881.90236195267, 4946.08497427977, 4960.131976904099, 4970.502949705001, 5005.924911126263, 4962.727050012284, 5005.34930459038, 5035.219540971156, 5053.74731263428, 5155.639105262596, 4844.806207751792, 4845.75771211445, 4848.737452676195, 4850.1269771441475, 4909.26361045846, 4842.927356089591, 4858.246971719389, 4868.2940973557925, 4870.147724148331, 4925.113479573711, 4755.191617424977, 4761.142994261067, 4836.105320515716, 4837.7097374157665, 4918.016396720599, 4920.803167873245, 4935.901281974412, 4938.037082768969, 4938.259261110862, 4980.576650984707, 4719.0, 4832.589229915497, 4848.9757551212015, 4852.2479015752615, 4894.265860120868], "value": 4633.208788086629, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [558.7346010644944, 576.9942300576994, 577.2121054760254, 582.0832189301848, 582.9562782791289, 27561.58657620135, 40124.79937600312, 41671.58328416715, 41677.37483937739, 46038.76980615092, 42347.788261058726, 42602.14795704084, 42633.0, 42656.146877062514, 42681.57318426823, 7501.174870764212, 7823.921760782405, 8015.7194498192575, 8026.478278911881, 8027.638756255953, 7681.539107653531, 7807.141214466405, 7843.0, 10164.491775411238, 10400.479976001208, 10388.63639772608, 10439.164866810637, 10455.58177672892, 10455.895441045568, 10464.319819211727, 10340.534675939602, 10396.220283478693, 10411.687649370531, 10420.791584168312, 10422.583096676124, 7512.173660897297, 9981.251406144496, 10163.898361016443, 10371.844422333596, 10377.429241391721, 40153.19693606112, 40431.7978410109, 40536.75705943517, 40575.0, 40604.0, 2753.765929895961, 2829.3983810765403, 2908.4473949949443, 3004.2038859702197, 3145.9370812583848, 29930.70069299323, 30018.699813002026, 30047.248818779488, 30298.84850575733, 31233.0, 3264.069740124053, 3289.3750187464307, 3363.8930178270493, 3397.5583174187445, 3456.9308613827584, 267023.0, 269121.0, 269537.0, 269629.0, 270016.0, 54873.176902346495, 55093.44906550883, 55096.72451637716, 55141.72429137828, 55166.44833551613, 58553.41446585565, 58844.0, 58893.0, 59046.70476647675, 59048.40951590515, 15136.54590362269, 15427.382904683911, 15597.142157181464, 15615.141167235684, 15646.217689115727, 11403.42982850854, 11424.42877856104, 11445.599404020986, 11462.140339474407, 11471.311721296748, 15208.163551004802, 17870.91064544669, 18196.45410637683, 18396.540086497946, 18478.445646630622, 5848.181254624356, 5860.384659610765, 5861.238039054895, 5914.172015917775, 5997.940020599738, 780.7384526183679, 884.0805562215224, 922.1331947968888, 930.8231436027133, 1002.5438425516372, 28588.428231435253, 28992.565111523345, 29209.0, 29282.414351712847, 29585.704142958726, 912.648630277348, 988.619381538113, 998.6255154317131, 1043.4261156363968, 1049.7060822969581, 14868.553943381716, 15587.220638968007, 15595.0, 15864.0, 15912.443064492687, 397204.0, 400093.0, 400267.0, 400884.0, 401190.0, 50429.49570504321, 50450.49549504458, 50511.24233136506, 50551.49448505468, 50732.0, 1021.4075836015094, 1130.9830352544718, 1192.3501691577937, 1211.2792888231488, 1220.7558488302552, 28292.575611366257, 29771.255718607208, 30296.242593935327, 30424.543631845983, 30777.538336924517, 43128.56871431245, 43782.12435751203, 43801.56198438102, 43807.34288985732, 43814.56185438232, 326954.0, 329056.0, 329229.0, 329352.0, 329688.0, 32274.838625806722, 37584.812075939444, 37932.81033594815, 38379.2324153521, 41675.16649667051, 42789.78605106955, 42925.78537107295, 43009.56990430056, 43429.0, 44259.0, 37604.623953760114, 37660.57544693728, 38051.61948380481, 38112.237755245296, 39310.80344598373, 36609.0, 36646.0, 37162.0, 37444.43833342448, 37785.81107094557, 36033.81983090068, 36537.451938220416, 36610.26779464449, 36691.0, 36696.63303367039, 32087.358252834347, 33216.0, 33345.16637083995, 33491.83254083714, 33665.15837104092, 25421.491570168124, 25574.872125639253, 25775.742242578086, 25795.484090318463, 26274.08040718566], "value": 18484.845059310563, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.8723972130312, 354.969827564657, 365.95425571803526, 369.7060836634875, 375.72628788407303, 13005.479780808768, 16370.83629163708, 16597.25312360945, 17381.91309043453, 18293.451196464095, 17103.82896171041, 17555.122243887818, 17604.207810648553, 17667.73498397525, 17813.7327940081, 5090.720010399426, 5174.818881339153, 5444.727763611823, 5454.7272636368025, 5528.06022976093, 3533.734969877257, 5156.0, 5450.482204190615, 5494.780208791643, 5496.6701997880255, 5433.972830135864, 5445.237666726662, 5480.0, 5482.39693633702, 5485.204645326426, 5485.01269771441, 5491.478309560605, 5502.614816962814, 5994.6403215807195, 6026.9095963560585, 5362.839114826562, 5370.516653501165, 5371.677699338053, 5372.086745253317, 5374.355077390699, 20024.499387515283, 20946.476338091517, 20969.68545471819, 21108.366748997556, 21173.153073877027, 1755.7015307397774, 1774.2459454731816, 1807.764990551233, 1810.9094545272683, 1836.4674244469088, 9038.774030649221, 9160.816783664355, 9215.078492150798, 9658.0, 9687.27345449087, 1650.6038550747853, 1696.7539706742577, 1751.868609854266, 1774.1838754173095, 1806.846418054467, 64914.67542662256, 67647.66176169137, 68791.3120868795, 70135.64932175358, 70944.0, 8532.8720069199, 8537.871931921027, 8679.26226270774, 9778.16885564735, 10312.639057632947, 13767.518136865161, 13774.65563360904, 13789.448422063006, 13809.723805523834, 13811.861881381057, 6835.282295359015, 7349.04462419882, 7363.374113200438, 7501.28737769911, 7623.275788800055, 6885.208201056943, 6992.860142797115, 7004.0544526488275, 7057.6471176442, 7342.192358840523, 4155.898686847988, 4252.043290259667, 4310.965368311646, 4364.759938203366, 4411.823527058882, 1723.6552689461907, 1749.7200447928526, 1757.0599729144847, 1817.0551313317, 1845.7600511933363, 627.5230824573357, 821.1706176761395, 835.3776436554806, 911.9908800912038, 917.4816228830689, 16692.165391730367, 16908.746368804477, 17301.0, 17519.64960700779, 17737.37919172823, 839.8110425154291, 845.3533047218882, 853.6628031927354, 881.625309243569, 933.883264591926, 9478.763030924281, 9709.174720148725, 9862.704118876445, 10007.483063992662, 10031.147352474976, 390688.0, 397618.0, 407436.0, 409384.0, 410299.0, 80996.0, 81126.0, 81133.0, 81267.99999999882, 83793.58103209567, 892.2103938015033, 916.4180745226696, 941.8445956417252, 943.2077055273603, 944.5938246554048, 15011.0, 15355.769663455281, 15573.844261557693, 15708.136052516764, 16073.75889361684, 37619.0, 38006.619933800306, 38575.807120964215, 39311.41032884452, 39611.405828913164, 52078.73960630173, 52224.21663674972, 52288.47711522836, 52290.0, 52327.476725233784, 6896.724131034903, 6929.411000065051, 7196.712131514681, 7301.817454563678, 7423.257674232534, 10768.892311076788, 11377.374244416636, 12225.816612750637, 12260.75478490443, 12401.25592464455, 9530.857037144588, 9723.805523889341, 9772.364796288219, 9886.604535818487, 9950.402975821184, 10403.167746580104, 10552.894471055499, 10644.787104258026, 10646.391634049707, 10887.455627218766, 9566.0, 9861.408315500807, 9894.109530142321, 9938.10557049869, 9963.950180249052, 6814.591124532544, 7103.396211322096, 7140.46446516503, 7308.780736577911, 7502.774916752506, 9521.19069879068, 9730.75673108178, 9813.803723925625, 9888.555015024485, 10015.449150296803], "value": 8168.7984532861165, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1366.0096430088188, 1417.773156294993, 1469.3902030657277, 1693.864490840733, 1699.4816580942816, 7569.810754731135, 7906.762797116081, 7952.085510166333, 7952.244536769005, 7960.721374751877, 6595.208574970999, 6746.257911629717, 6752.628605426699, 6960.54756440831, 7732.845343093135, 1896.2983696032488, 2134.7331583552054, 2220.6891035255076, 2222.48882756966, 2234.0393630738777, 2189.430748005522, 2201.1305534313938, 2204.096320508592, 2208.503086805472, 2212.1151539384214, 2193.396815875639, 2240.8767517786514, 2241.540484200735, 2242.439390152462, 2243.6298010828277, 1904.2192700992596, 2130.5312831177116, 2191.769864164272, 2192.495725983033, 2207.9558408831736, 2162.1135334512774, 2167.5448155887284, 2171.4354267890462, 2177.2162021672343, 2177.368563116694, 7726.150123486412, 7752.3410510106705, 7777.02787151606, 7778.338841198448, 7787.0, 4508.075844451912, 4623.768811559408, 4632.7683615819415, 4647.465541462741, 4647.721136731807, 2814.4089741154175, 2829.9858500707364, 2911.7088291170926, 2942.7204415580486, 2943.3083225442006, 964.5081008685551, 967.6129548180713, 970.815545046446, 984.714432814483, 988.6193815381058, 10639.095676867475, 10642.0, 10653.147748180127, 10655.360678359246, 10663.946680266627, 4186.958130418657, 4210.789460526961, 4303.698741088094, 4306.741595504217, 4413.16149931512, 8258.628361723617, 8385.538795366192, 8385.95807020961, 8386.203310685476, 8396.664133434717, 4643.187442197602, 4657.464391594942, 4659.58063774262, 4667.206574882211, 4671.0, 4204.8528301509305, 4264.955086003949, 4342.392065110887, 4343.869683909487, 4349.043210493686, 3202.8718851246153, 3216.533602627606, 3264.542963985044, 3270.1824543864036, 3276.541284220211, 990.8662330585279, 998.3311181508407, 1000.8448690452846, 1001.62439085343, 1010.4139599032543, 645.2998496631208, 769.9307062364414, 774.8527779721835, 792.1919641965163, 802.5144787403653, 4824.734639594786, 4892.730899800546, 4976.776045077981, 5011.373578302712, 5164.48355164488, 403.3586597310291, 407.90618157823866, 409.76643313311126, 415.43085972217654, 415.70277251765134, 3121.8923318210504, 3321.0368992992244, 3338.315645292709, 3356.7985920844826, 3403.3873902697255, 9091.31815113856, 9382.061793820563, 9544.284178686627, 9614.086661767122, 9614.134727874525, 9423.199028082552, 9437.952810235905, 9527.809443811224, 9557.713268601952, 9568.904310956941, 7136.429085673134, 7154.39187669054, 7258.165310989199, 7347.448941329315, 7415.555066696015, 4852.296417019583, 4873.342098816614, 4879.170541007966, 4886.486918873609, 4991.750412479289, 8814.955925220333, 8816.471011739317, 8829.602667879832, 8836.293096552388, 8910.643574257216, 8659.874216351835, 9075.092490751136, 9167.541622918694, 9169.724908252763, 9177.08229177077, 5651.943480565142, 5964.761409543569, 6146.078088286793, 6243.157173781481, 6311.0, 7528.774136775905, 7586.924130758622, 7595.240475952361, 7596.392288617008, 7601.543907365576, 6525.64108974011, 6556.442702370352, 6567.704453299709, 6581.14445122131, 6726.990951357336, 6425.228972523327, 6440.0, 6466.288708242184, 6491.870162596816, 6701.698423571049, 6189.133521307111, 6252.0, 6257.155284036597, 6264.21697287839, 6265.718042687999, 4181.121964387513, 4332.935005974976, 4371.978140109279, 4413.3159360299205, 4420.535843736361, 4206.579342065769, 4262.616364527207, 4262.616364527207, 4362.432883725095, 4456.777161141995], "value": 3952.8857524753753, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.28918817807704, 299.3563837748841, 305.38007844076526, 306.4132186862159, 306.76379188025237, 4872.098661747575, 4874.78063487143, 4898.608111351093, 4929.4824043475455, 4939.234418665108, 4829.7585120744, 4835.782389792459, 4843.46721860596, 4849.10291596055, 4862.683925544837, 3858.6720128789016, 3861.1891502784447, 3871.477350557681, 3893.0462036801023, 3898.142408670102, 3587.121155316951, 3804.733668643182, 3890.2414029264305, 3918.8628398006076, 3919.5492518360325, 3761.849526018956, 3779.39529675252, 3790.9620903791024, 3794.392897136459, 3795.9430608540756, 3759.575074077205, 3760.623937606245, 3762.9247415051545, 3774.1885494618787, 3774.905627359324, 3100.945678469309, 3178.666240044808, 3178.8728450861936, 3308.7353011759, 3323.900282991514, 4647.140279048387, 4686.9765651171865, 4702.952970470285, 4710.764461776897, 4714.339992401066, 4292.205941900758, 4300.569943005706, 4310.741355518648, 4316.223079845627, 4335.479742430897, 4717.787699553528, 4822.445418776851, 5073.162928116856, 5092.319534553589, 5115.0, 3605.3510368133725, 3608.0979755061235, 3618.8009659468717, 3623.746337756358, 3625.981870090659, 5226.73866306687, 5227.163653815372, 5268.288781014552, 5274.367075950872, 5276.12943864262, 3645.7265705072227, 3686.64976827201, 3697.4638677391627, 3722.106694393326, 3732.0483276764794, 3713.9814300928865, 3722.181120153562, 3727.6086010969, 3764.6047165047275, 3765.3787125124595, 4286.507051689034, 4286.914261714748, 4293.635041021485, 4338.414314067623, 4346.1525002624185, 5030.314844527051, 5056.974715126401, 5063.367079115111, 5063.670861393997, 5065.189569668909, 1234.1422711215798, 1298.1367390685277, 1298.4935875008755, 1298.6298904812083, 1306.3664122900575, 805.1988271669726, 816.5957850863741, 830.543201239316, 830.8089139497949, 841.2680967558264, 4031.5565287818317, 4040.8585699500377, 4057.797110144481, 4094.959050409517, 4161.979190104091, 4633.443986721548, 4651.511591282867, 4689.484156742756, 4699.9085406994245, 4700.952990470051, 4087.346024636044, 4096.6313031826685, 4104.876853694334, 4153.460050193516, 4160.460629567019, 2328.6157783965796, 2456.7788898999174, 2463.8398504097177, 2475.8266921315694, 2490.788282995966, 4369.257226271541, 4704.364910737075, 4801.927971080437, 4984.127777638899, 5002.449730529639, 5425.321834770653, 5435.619506634576, 5436.565074794007, 5458.972705136449, 5463.453654634584, 5015.3730783652045, 5023.8492845214705, 5029.547340739351, 5031.84904452867, 5033.0, 5033.697978121324, 5247.9737601311745, 5252.579793616578, 5278.97360513195, 5281.260623512634, 5166.2767212589515, 5181.2746215529105, 5181.326427564392, 5203.19350500673, 5221.929504451502, 4785.59322457595, 5015.22264049073, 5016.197408414782, 5018.974905125451, 5027.622928280321, 3699.8335074921156, 3709.0356507307756, 3711.3319602471815, 3716.3310604090443, 3716.4239542870896, 5233.947660523499, 5252.343457067866, 5255.710935898563, 5318.654287471302, 5329.413764485981, 5041.596672266137, 5045.268436076822, 5057.494250574913, 5058.519440653132, 5093.0, 4766.189747742823, 5008.298838162587, 5016.247562865599, 5017.422996355466, 5021.397432308147, 5038.294638750651, 5051.267566202807, 5069.0, 5073.18828987375, 5129.102407078821, 5011.899762004667, 5184.0, 5207.140821764518, 5221.843344699665, 5241.397239317527, 5021.924671129863, 5045.747712614428, 5048.798048078036, 5060.8987820242655, 5064.29099926018], "value": 3787.2771174604595, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [604.6368709546325, 690.4303949241873, 695.15538620576, 713.126420135334, 718.8274814044631, 12385.876141238585, 12538.623841284765, 12581.93709031456, 12649.494020239192, 12677.112602117855, 12688.682782930431, 13110.803337949937, 13287.069905106646, 13646.317684115804, 13667.248301343423, 787.1852632525338, 804.0311424733183, 805.6374631415857, 806.6450761664848, 806.7499075286651, 620.9720562574695, 780.2080887898787, 803.0203152154362, 807.2855522862251, 810.5055915891331, 847.3094428041127, 848.367965865428, 848.4866655673321, 849.1932663969224, 850.1371108325025, 843.2958479669484, 843.6372359885247, 844.2022288936957, 845.0, 845.1379592815325, 481.7157876852674, 589.4047012517345, 606.8118883146238, 611.2237458427816, 615.8214117905802, 13264.204147751167, 13274.867251327556, 13280.86719132806, 13298.0, 13337.066405351628, 12119.636410907688, 12181.23372111039, 12469.127161098726, 12602.68493287666, 13133.934330328382, 11083.168762342775, 11264.267822591506, 11474.082073434105, 11980.940095299555, 12079.214851034654, 4010.197960407901, 4063.9796801015805, 4084.326086195774, 4123.340265557496, 4156.5635608261, 61680.38319616791, 61835.69082154606, 62363.68818155926, 62615.37384626141, 62826.0, 10032.0, 10086.697399078039, 10126.05230497766, 10382.844257336146, 10757.085647720032, 17849.643007139784, 17874.374396896306, 17904.731429028576, 17905.641887162183, 17908.373206937693, 6627.635480048547, 6633.4693224541925, 6640.634765087966, 6641.169853768279, 6641.535092543476, 3599.5500562429697, 3685.6130106338983, 3705.814709264526, 3771.113788259757, 3771.132639492932, 2524.1922584773065, 2608.595667671514, 2619.2796980830426, 2640.8943642254094, 2647.3116989582845, 490.7668857292829, 494.57960733377064, 547.4252035362853, 568.590614757374, 580.0516156084832, 525.136151031551, 535.563515734675, 538.8410418926426, 547.2120146988403, 549.9202615620712, 7906.209379062047, 7908.655528560159, 8896.510691912006, 9086.091390860862, 9107.544622768835, 446.2191165460444, 496.31012892080037, 513.7713717395798, 515.3712470785636, 536.419820662614, 3609.8375573099274, 3651.726120540917, 3658.835352409148, 3793.4878791363367, 3814.9237015260096, 57212.0, 57383.42616573781, 57481.71259143761, 96460.0353996451, 98169.0, 87292.56353718191, 90147.54926225326, 91381.0, 91793.0, 91988.10089243199, 55868.0, 74509.6274518624, 75071.24928750751, 76447.61776191083, 80060.0, 6911.5853048817235, 6992.720291188499, 7003.6848341823725, 7068.010478532907, 7108.0, 44105.33841992437, 44469.77765111154, 46001.0, 46009.07981840277, 46427.0, 59242.40757592541, 59786.70106649439, 66366.33633663795, 67493.3250667487, 67797.3220267791, 9242.399244049115, 9317.39436936597, 9321.72034838956, 9348.672796452092, 9469.573869175765, 10187.541560629641, 10415.218858585788, 10440.269181157246, 10555.41945192991, 11112.0, 9994.300398972293, 10162.339447935861, 10218.0, 10239.180865530889, 10248.180145588483, 9775.657851975147, 9913.0, 10264.538095785558, 10430.37417754937, 10678.679639610824, 8986.281097512052, 9248.861267080865, 9267.814643706954, 9356.4386136832, 9535.666251681158, 8386.748397548083, 8488.151184881463, 8688.044315125457, 8691.91308086911, 8825.64697412122, 8215.507069575844, 8343.54110523927, 8345.248927596545, 8425.83148337042, 8463.449875758057], "value": 6278.129170411058, "unit": "score"}], "name": "dromaeo_css", "value": 6353.263988303226, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 114.0, 114.0, 113.0, 114.0, 114.0, 113.0, 114.0, 114.0, 114.0], "value": 112.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 123.0, 123.0, 175.0, 125.0, 124.0, 151.0, 123.0, 164.0, 122.0], "value": 137.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 159.0, 160.0, 161.0, 161.0, 161.0, 161.0, 160.0, 159.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 75.0, 109.0, 77.0, 77.0, 77.0, 75.0, 110.0, 76.0], "value": 82.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 120.0, 85.0, 86.0, 86.0, 119.0, 86.0, 84.0, 132.0], "value": 96.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 118.0, 116.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 148.0, 146.0, 146.0, 147.0, 147.0, 147.0, 146.0], "value": 146.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 96.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 61.0, 62.0, 61.0, 62.0, 65.0, 63.0, 63.0, 62.0, 63.0], "value": 62.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 65.0, 66.0, 66.0, 66.0, 75.0, 65.0, 65.0, 66.0], "value": 66.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 82.0, 85.0, 79.0, 80.0, 80.0, 89.0, 78.0, 78.0, 85.0], "value": 82.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 128.0, 128.0, 130.0, 129.0, 129.0, 128.0, 129.0, 178.0, 134.0], "value": 134.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 166.0, 163.0, 164.0, 168.0, 167.0, 168.0, 169.0, 166.0, 167.0], "value": 166.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 72.0, 66.0, 67.0, 66.0, 66.0, 65.0, 65.0, 67.0], "value": 66.8, "unit": "score"}], "name": "kraken", "value": 1529.0, "alertThreshold": 2.0}]} 22:14:31 INFO - Return code: 0 22:14:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:14:32 INFO - # TBPL SUCCESS # 22:14:32 INFO - Running post-action listener: _resource_record_post_action 22:14:32 INFO - [mozharness: 2016-07-21 05:14:32.052327Z] Finished run-tests step (success) 22:14:32 INFO - Running post-run listener: _resource_record_post_run 22:14:32 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 495616; Write bytes: 290656256; Read time: 56; Write time: 208112 22:14:32 INFO - TinderboxPrint: CPU usage
13.0% 22:14:32 INFO - TinderboxPrint: I/O read bytes / time
495,616 / 56 22:14:32 INFO - TinderboxPrint: I/O write bytes / time
290,656,256 / 208,112 22:14:32 INFO - TinderboxPrint: CPU idle
8,333.6 (87.0%) 22:14:32 INFO - TinderboxPrint: CPU user
1,201.7 (12.5%) 22:14:32 INFO - TinderboxPrint: Swap in / out
0 / 0 22:14:32 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 5242880; Read time: 0; Write time: 284 22:14:32 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 491520; Write bytes: 285413376; Read time: 40; Write time: 207828 22:14:32 INFO - Running post-run listener: _upload_blobber_files 22:14:32 INFO - Blob upload gear active. 22:14:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:14:32 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:14:32 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', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:14:32 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 Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:14:33 INFO - (blobuploader) - INFO - Open directory for files ... 22:14:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:14:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:14:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:14:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:14:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:14:58 INFO - (blobuploader) - INFO - Done attempting. 22:14:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:14:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:14:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:14:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:14:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:14:59 INFO - (blobuploader) - INFO - Done attempting. 22:14:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:14:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:14:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:15:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:15:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:15:00 INFO - (blobuploader) - INFO - Done attempting. 22:15:00 INFO - (blobuploader) - INFO - Iteration through files over. 22:15:00 INFO - Return code: 0 22:15:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:15:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:15:00 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/5a3a8412d6f2cc9ef50d0cab68ec9ea3cbc60a945fb7ab82452b595010deebb3b5949a1f7c766bf6dd7433881d38819da96bdceeb3dd331a5e0fcb7afa138880", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/ca3c8f0b83bc5a4cd5e6d0c157a3c18b6f16be9b11289d93e983bd01c53f1457fd0411d27dab8da2e4509a3f265094911f6d98d9479bdef9855c7f15596ce7af", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/08caf844596eb5a3b2e1a6b5a6e8441d5e4b0694ed8ecfd3d3522c07d3e65a4bd0d3edc6a7bc089ccea7f9c9356452059513b91d0a21e4ff5e7f47437a0bad97"} 22:15:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:15:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:15:00 INFO - Contents: 22:15:00 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/5a3a8412d6f2cc9ef50d0cab68ec9ea3cbc60a945fb7ab82452b595010deebb3b5949a1f7c766bf6dd7433881d38819da96bdceeb3dd331a5e0fcb7afa138880", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/ca3c8f0b83bc5a4cd5e6d0c157a3c18b6f16be9b11289d93e983bd01c53f1457fd0411d27dab8da2e4509a3f265094911f6d98d9479bdef9855c7f15596ce7af", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/08caf844596eb5a3b2e1a6b5a6e8441d5e4b0694ed8ecfd3d3522c07d3e65a4bd0d3edc6a7bc089ccea7f9c9356452059513b91d0a21e4ff5e7f47437a0bad97"} 22:15:00 INFO - Running post-run listener: copy_logs_to_upload_dir 22:15:00 INFO - Copying logs to upload dir... 22:15:00 INFO - mkdir: /builds/slave/test/build/upload/logs 22:15:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.854463 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2016-07-20 22:15:00.556725) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-20 22:15:00.559750) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1469076790.561368-759460913 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/5a3a8412d6f2cc9ef50d0cab68ec9ea3cbc60a945fb7ab82452b595010deebb3b5949a1f7c766bf6dd7433881d38819da96bdceeb3dd331a5e0fcb7afa138880", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/ca3c8f0b83bc5a4cd5e6d0c157a3c18b6f16be9b11289d93e983bd01c53f1457fd0411d27dab8da2e4509a3f265094911f6d98d9479bdef9855c7f15596ce7af", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/08caf844596eb5a3b2e1a6b5a6e8441d5e4b0694ed8ecfd3d3522c07d3e65a4bd0d3edc6a7bc089ccea7f9c9356452059513b91d0a21e4ff5e7f47437a0bad97"} build_url:https://queue.taskcluster.net/v1/task/WU40-pKsSU2-tXtDsUp6KA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011314 build_url: 'https://queue.taskcluster.net/v1/task/WU40-pKsSU2-tXtDsUp6KA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/5a3a8412d6f2cc9ef50d0cab68ec9ea3cbc60a945fb7ab82452b595010deebb3b5949a1f7c766bf6dd7433881d38819da96bdceeb3dd331a5e0fcb7afa138880", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/ca3c8f0b83bc5a4cd5e6d0c157a3c18b6f16be9b11289d93e983bd01c53f1457fd0411d27dab8da2e4509a3f265094911f6d98d9479bdef9855c7f15596ce7af", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/08caf844596eb5a3b2e1a6b5a6e8441d5e4b0694ed8ecfd3d3522c07d3e65a4bd0d3edc6a7bc089ccea7f9c9356452059513b91d0a21e4ff5e7f47437a0bad97"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-20 22:15:00.584671) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 22:15:00.585014) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1469076790.561368-759460913 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004467 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 22:15:00.670145) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-20 22:15:00.670494) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-20 22:15:00.670758) ========= ========= Total master_lag: 0.16 =========