builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-039 starttime: 1468360920.64 results: success (0) buildid: 20160712142126 builduid: ef2131f292014f9ebc4be8ea160a4fe3 revision: 9fb3a5f8b892765c7b877aa8541228fd0b5a1928 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-12 15:02:00.644903) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-12 15:02:00.645397) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-12 15:02:00.645728) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-12 15:02:00.699972) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 15:02:00.700238) ========= 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=5bd038846534b2fdfa68eeae000001e2-1468360872.253528-239280511 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-12 15:02:01-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 222M=0s 2016-07-12 15:02:01 (222 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.095369 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 15:02:00.820985) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 15:02:00.821309) ========= 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=5bd038846534b2fdfa68eeae000001e2-1468360872.253528-239280511 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019868 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 15:02:00.881135) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 15:02:00.881473) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 9fb3a5f8b892765c7b877aa8541228fd0b5a1928 --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 9fb3a5f8b892765c7b877aa8541228fd0b5a1928 --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=5bd038846534b2fdfa68eeae000001e2-1468360872.253528-239280511 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-12 15:02:01,501 truncating revision to first 12 chars 2016-07-12 15:02:01,501 Setting DEBUG logging. 2016-07-12 15:02:01,501 attempt 1/10 2016-07-12 15:02:01,501 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/9fb3a5f8b892?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-12 15:02:01,824 unpacking tar archive at: fx-team-9fb3a5f8b892/testing/mozharness/ program finished with exit code 0 elapsedTime=0.627486 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 15:02:01.526821) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-12 15:02:01.527145) ========= script_repo_revision: 9fb3a5f8b892765c7b877aa8541228fd0b5a1928 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-12 15:02:01.527559) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-12 15:02:01.527860) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-12 15:02:01.537944) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-07-12 15:02:01.538372) ========= /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=5bd038846534b2fdfa68eeae000001e2-1468360872.253528-239280511 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:02:02 INFO - MultiFileLogger online at 20160712 15:02:02 in /builds/slave/test 15:02:02 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 15:02:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:02:02 INFO - {'append_to_log': False, 15:02:02 INFO - 'base_work_dir': '/builds/slave/test', 15:02:02 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 15:02:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:02:02 INFO - 'branch': 'Fx-Team-Non-PGO', 15:02:02 INFO - 'buildbot_json_path': 'buildprops.json', 15:02:02 INFO - 'config_files': ('talos/linux_config.py',), 15:02:02 INFO - 'default_actions': ('clobber', 15:02:02 INFO - 'read-buildbot-config', 15:02:02 INFO - 'download-and-extract', 15:02:02 INFO - 'populate-webroot', 15:02:02 INFO - 'create-virtualenv', 15:02:02 INFO - 'install', 15:02:02 INFO - 'run-tests'), 15:02:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:02:02 INFO - 'download_minidump_stackwalk': True, 15:02:02 INFO - 'download_symbols': 'ondemand', 15:02:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:02:02 INFO - 'tooltool.py': '/tools/tooltool.py', 15:02:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:02:02 INFO - '/tools/misc-python/virtualenv.py')}, 15:02:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:02:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:02:02 INFO - 'installer_path': 'installer.exe', 15:02:02 INFO - 'log_level': 'info', 15:02:02 INFO - 'log_name': 'talos', 15:02:02 INFO - 'log_to_console': True, 15:02:02 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:02:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:02:02 INFO - 'opt_config_files': (), 15:02:02 INFO - 'pip_index': False, 15:02:02 INFO - 'sps_profile': False, 15:02:02 INFO - 'sps_profile_interval': 0, 15:02:02 INFO - 'suite': 'dromaeojs', 15:02:02 INFO - 'system_bits': '32', 15:02:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:02:02 INFO - 'title': 'talos-linux64-ix-039', 15:02:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:02:02 INFO - 'use_talos_json': True, 15:02:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:02:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:02:02 INFO - 'work_dir': 'build'} 15:02:02 INFO - ##### 15:02:02 INFO - ##### Running clobber step. 15:02:02 INFO - ##### 15:02:02 INFO - Running pre-action listener: _resource_record_pre_action 15:02:02 INFO - Running main action method: clobber 15:02:02 INFO - rmtree: /builds/slave/test/build 15:02:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:02:02 INFO - Running post-action listener: _resource_record_post_action 15:02:02 INFO - ##### 15:02:02 INFO - ##### Finished clobber step (success) 15:02:02 INFO - ##### 15:02:02 INFO - ##### 15:02:02 INFO - ##### Running read-buildbot-config step. 15:02:02 INFO - ##### 15:02:02 INFO - Running pre-action listener: _resource_record_pre_action 15:02:02 INFO - Running main action method: read_buildbot_config 15:02:02 INFO - Using buildbot properties: 15:02:02 INFO - { 15:02:02 INFO - "project": "", 15:02:02 INFO - "product": "firefox", 15:02:02 INFO - "script_repo_revision": "production", 15:02:02 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 15:02:02 INFO - "repository": "", 15:02:02 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 15:02:02 INFO - "buildid": "20160712142126", 15:02:02 INFO - "pgo_build": "False", 15:02:02 INFO - "basedir": "/builds/slave/test", 15:02:02 INFO - "buildnumber": 1981, 15:02:02 INFO - "slavename": "talos-linux64-ix-039", 15:02:02 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 15:02:02 INFO - "platform": "ubuntu64_hw", 15:02:02 INFO - "scheduler": "tests-fx-team-linux64-talos", 15:02:02 INFO - "branch": "fx-team", 15:02:02 INFO - "repo_path": "integration/fx-team", 15:02:02 INFO - "revision": "9fb3a5f8b892765c7b877aa8541228fd0b5a1928", 15:02:02 INFO - "stage_platform": "linux64", 15:02:02 INFO - "builduid": "ef2131f292014f9ebc4be8ea160a4fe3", 15:02:02 INFO - "slavebuilddir": "test" 15:02:02 INFO - } 15:02:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/ICxnFLgER4ChM_-iieoMRw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 15:02:02 INFO - Running post-action listener: _resource_record_post_action 15:02:02 INFO - ##### 15:02:02 INFO - ##### Finished read-buildbot-config step (success) 15:02:02 INFO - ##### 15:02:02 INFO - ##### 15:02:02 INFO - ##### Running download-and-extract step. 15:02:02 INFO - ##### 15:02:02 INFO - Running pre-action listener: _resource_record_pre_action 15:02:02 INFO - Running main action method: download_and_extract 15:02:02 INFO - mkdir: /builds/slave/test/build/tests 15:02:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:02:02 INFO - https://queue.taskcluster.net/v1/task/ICxnFLgER4ChM_-iieoMRw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 15:02:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ICxnFLgER4ChM_-iieoMRw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 15:02:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ICxnFLgER4ChM_-iieoMRw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 15:02:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ICxnFLgER4ChM_-iieoMRw/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 15:02:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ICxnFLgER4ChM_-iieoMRw/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 15:02:03 INFO - Downloaded 1361 bytes. 15:02:03 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 15:02:03 INFO - Using the following test package requirements: 15:02:03 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 15:02:03 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:02:03 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 15:02:03 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:02:03 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 15:02:03 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:02:03 INFO - u'jsshell-linux-x86_64.zip'], 15:02:03 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:02:03 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 15:02:03 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 15:02:03 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:02:03 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 15:02:03 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:02:03 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 15:02:03 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:02:03 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 15:02:03 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:02:03 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 15:02:03 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:02:03 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')]} 15:02:03 INFO - https://queue.taskcluster.net/v1/task/ICxnFLgER4ChM_-iieoMRw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:02:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ICxnFLgER4ChM_-iieoMRw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:02:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ICxnFLgER4ChM_-iieoMRw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:02:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ICxnFLgER4ChM_-iieoMRw/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 15:02:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ICxnFLgER4ChM_-iieoMRw/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 15:02:05 INFO - Downloaded 22750521 bytes. 15:02:05 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'] 15:02:05 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 15:02:06 INFO - Return code: 0 15:02:06 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 15:02: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')]} 15:02:06 INFO - https://queue.taskcluster.net/v1/task/ICxnFLgER4ChM_-iieoMRw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:02:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ICxnFLgER4ChM_-iieoMRw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:02:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ICxnFLgER4ChM_-iieoMRw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:02:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ICxnFLgER4ChM_-iieoMRw/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 15:02:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ICxnFLgER4ChM_-iieoMRw/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 15:02:08 INFO - Downloaded 22750521 bytes. 15:02:08 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'] 15:02:08 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 15:02:09 INFO - Return code: 0 15:02:09 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')]} 15:02:09 INFO - https://queue.taskcluster.net/v1/task/ICxnFLgER4ChM_-iieoMRw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 15:02:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ICxnFLgER4ChM_-iieoMRw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 15:02:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ICxnFLgER4ChM_-iieoMRw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 15:02:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ICxnFLgER4ChM_-iieoMRw/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 15:02:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ICxnFLgER4ChM_-iieoMRw/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 15:02:11 INFO - Downloaded 14403317 bytes. 15:02:11 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'] 15:02:11 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 15:02:11 INFO - Return code: 0 15:02:11 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')]} 15:02:11 INFO - https://queue.taskcluster.net/v1/task/ICxnFLgER4ChM_-iieoMRw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 15:02:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ICxnFLgER4ChM_-iieoMRw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 15:02:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ICxnFLgER4ChM_-iieoMRw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 15:02:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ICxnFLgER4ChM_-iieoMRw/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 15:02:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ICxnFLgER4ChM_-iieoMRw/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 15:02:13 INFO - Downloaded 56665710 bytes. 15:02:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ICxnFLgER4ChM_-iieoMRw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 15:02:13 INFO - mkdir: /builds/slave/test/properties 15:02:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:02:13 INFO - Writing to file /builds/slave/test/properties/build_url 15:02:13 INFO - Contents: 15:02:13 INFO - build_url:https://queue.taskcluster.net/v1/task/ICxnFLgER4ChM_-iieoMRw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 15:02:14 INFO - Running post-action listener: _resource_record_post_action 15:02:14 INFO - Running post-action listener: set_extra_try_arguments 15:02:14 INFO - ##### 15:02:14 INFO - ##### Finished download-and-extract step (success) 15:02:14 INFO - ##### 15:02:14 INFO - ##### 15:02:14 INFO - ##### Running populate-webroot step. 15:02:14 INFO - ##### 15:02:14 INFO - Running pre-action listener: _resource_record_pre_action 15:02:14 INFO - Running main action method: populate_webroot 15:02:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:02:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:02:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:02:14 INFO - '--tppagecycles', 15:02:14 INFO - '7'], 15:02:14 INFO - 'tests': ['tsvgm']}}, 15:02:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:02:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:02:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:02:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:02:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:02:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:02:14 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:02:14 INFO - 'talos_options': ['--disable-e10s'], 15:02:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:02:14 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:02:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:02:14 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:02:14 INFO - 'talos_options': ['--disable-e10s'], 15:02:14 INFO - 'tests': ['damp', 'tps']}, 15:02:14 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:02:14 INFO - 'tests': ['damp', 'tps']}, 15:02:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:02:14 INFO - 'tests': ['dromaeo_dom']}, 15:02:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:02:14 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:02:14 INFO - 'tests': ['basic_compositor_video']}, 15:02:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:02:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:02:14 INFO - 'tests': ['a11yr', 15:02:14 INFO - 'ts_paint', 15:02:14 INFO - 'tpaint', 15:02:14 INFO - 'sessionrestore', 15:02:14 INFO - 'sessionrestore_no_auto_restore', 15:02:14 INFO - 'tabpaint']}, 15:02:14 INFO - 'other-e10s': {'tests': ['a11yr', 15:02:14 INFO - 'ts_paint', 15:02:14 INFO - 'tpaint', 15:02:14 INFO - 'sessionrestore', 15:02:14 INFO - 'sessionrestore_no_auto_restore', 15:02:14 INFO - 'tabpaint']}, 15:02:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:02:14 INFO - 'ts_paint', 15:02:14 INFO - 'tpaint', 15:02:14 INFO - 'sessionrestore', 15:02:14 INFO - 'sessionrestore_no_auto_restore', 15:02:14 INFO - 'tabpaint']}, 15:02:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:02:14 INFO - 'ts_paint', 15:02:14 INFO - 'tpaint', 15:02:14 INFO - 'sessionrestore', 15:02:14 INFO - 'sessionrestore_no_auto_restore', 15:02:14 INFO - 'tabpaint']}, 15:02:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:02:14 INFO - 'tests': ['a11yr', 15:02:14 INFO - 'ts_paint', 15:02:14 INFO - 'tpaint', 15:02:14 INFO - 'sessionrestore', 15:02:14 INFO - 'sessionrestore_no_auto_restore', 15:02:14 INFO - 'tabpaint']}, 15:02:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:02:14 INFO - 'tests': ['a11yr', 15:02:14 INFO - 'ts_paint', 15:02:14 INFO - 'tpaint', 15:02:14 INFO - 'sessionrestore', 15:02:14 INFO - 'sessionrestore_no_auto_restore', 15:02:14 INFO - 'tabpaint']}, 15:02:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:02:14 INFO - 'tests': ['tsvgx', 15:02:14 INFO - 'tsvgr_opacity', 15:02:14 INFO - 'tart', 15:02:14 INFO - 'tscrollx', 15:02:14 INFO - 'cart']}, 15:02:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:02:14 INFO - 'tsvgr_opacity', 15:02:14 INFO - 'tart', 15:02:14 INFO - 'tscrollx', 15:02:14 INFO - 'cart']}, 15:02:14 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:02:14 INFO - 'talos_options': ['--disable-e10s'], 15:02:14 INFO - 'tests': ['tp5o']}, 15:02:14 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:02:14 INFO - 'tests': ['tp5o']}, 15:02:14 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:02:14 INFO - 'talos_options': ['--disable-e10s', 15:02:14 INFO - '--xperf_path', 15:02:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:02:14 INFO - 'tests': ['tp5n']}, 15:02:14 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:02:14 INFO - 'talos_options': ['--xperf_path', 15:02:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:02:14 INFO - 'tests': ['tp5n']}}, 15:02:14 INFO - 'talos.zip': {'path': '', 15:02:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:02:14 INFO - Running post-action listener: _resource_record_post_action 15:02:14 INFO - ##### 15:02:14 INFO - ##### Finished populate-webroot step (success) 15:02:14 INFO - ##### 15:02:14 INFO - ##### 15:02:14 INFO - ##### Running create-virtualenv step. 15:02:14 INFO - ##### 15:02:14 INFO - Running pre-action listener: _resource_record_pre_action 15:02:14 INFO - Running main action method: create_virtualenv 15:02:14 INFO - Creating virtualenv /builds/slave/test/build/venv 15:02:14 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 15:02:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:02:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:02:14 INFO - Using real prefix '/usr' 15:02:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:02:14 INFO - Installing distribute.............................................................................................................................................................................................done. 15:02:16 INFO - Installing pip.................done. 15:02:16 INFO - Return code: 0 15:02:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:02:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:02:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:02:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:02:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:02:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:16 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 0x20bc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x237d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x237b3b0>, '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 0x237c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x237c740>, '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': '5bd038846534b2fdfa68eeae000001e2-1468360872.253528-239280511', '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 15:02:16 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 15:02:16 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 15:02:16 INFO - Using env: {'DISPLAY': ':0', 15:02:16 INFO - 'HOME': '/home/cltbld', 15:02:16 INFO - 'LANG': 'en_US.UTF-8', 15:02:16 INFO - 'LANGUAGE': 'en_US:en', 15:02:16 INFO - 'LOGNAME': 'cltbld', 15:02:16 INFO - 'MAIL': '/var/mail/cltbld', 15:02:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:02:16 INFO - 'MOZ_NO_REMOTE': '1', 15:02:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:02:16 INFO - 'NO_EM_RESTART': '1', 15:02:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:02:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:02:16 INFO - 'PWD': '/builds/slave/test', 15:02:16 INFO - 'SHELL': '/bin/bash', 15:02:16 INFO - 'SHLVL': '1', 15:02:16 INFO - 'TERM': 'linux', 15:02:16 INFO - 'TMOUT': '86400', 15:02:16 INFO - 'USER': 'cltbld', 15:02:16 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1468360872.253528-239280511', 15:02:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:02:16 INFO - '_': '/tools/buildbot/bin/python'} 15:02:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:02:17 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 15:02:17 INFO - Cleaning up... 15:02:17 INFO - Return code: 0 15:02:17 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:02: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')]} 15:02:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:02:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02: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')]} 15:02:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:02:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02: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', '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 0x20bc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x237d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x237b3b0>, '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 0x237c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x237c740>, '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': '5bd038846534b2fdfa68eeae000001e2-1468360872.253528-239280511', '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 15:02: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', 'psutil>=3.1.1'] in /builds/slave/test/build 15:02: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 psutil>=3.1.1 15:02:17 INFO - Using env: {'DISPLAY': ':0', 15:02:17 INFO - 'HOME': '/home/cltbld', 15:02:17 INFO - 'LANG': 'en_US.UTF-8', 15:02:17 INFO - 'LANGUAGE': 'en_US:en', 15:02:17 INFO - 'LOGNAME': 'cltbld', 15:02:17 INFO - 'MAIL': '/var/mail/cltbld', 15:02:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:02:17 INFO - 'MOZ_NO_REMOTE': '1', 15:02:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:02:17 INFO - 'NO_EM_RESTART': '1', 15:02:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:02:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:02:17 INFO - 'PWD': '/builds/slave/test', 15:02:17 INFO - 'SHELL': '/bin/bash', 15:02:17 INFO - 'SHLVL': '1', 15:02:17 INFO - 'TERM': 'linux', 15:02:17 INFO - 'TMOUT': '86400', 15:02:17 INFO - 'USER': 'cltbld', 15:02:17 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1468360872.253528-239280511', 15:02:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:02:17 INFO - '_': '/tools/buildbot/bin/python'} 15:02:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:02:17 INFO - Downloading/unpacking psutil>=3.1.1 15:02:17 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 15:02:17 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 15:02:17 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 15:02:17 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 15:02:17 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 15:02:17 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 15:02:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:02:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:02:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:02:19 INFO - Installing collected packages: psutil 15:02:19 INFO - Running setup.py install for psutil 15:02:19 INFO - building 'psutil._psutil_linux' extension 15:02:19 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 15:02:19 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 15:02:19 INFO - building 'psutil._psutil_posix' extension 15:02:19 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 15:02:19 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 15:02:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:02:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:02:19 INFO - Successfully installed psutil 15:02:19 INFO - Cleaning up... 15:02:19 INFO - Return code: 0 15:02:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:02:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:02:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:02:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:02:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:02:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:19 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 0x20bc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x237d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x237b3b0>, '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 0x237c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x237c740>, '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': '5bd038846534b2fdfa68eeae000001e2-1468360872.253528-239280511', '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 15:02:19 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 15:02:19 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 15:02:19 INFO - Using env: {'DISPLAY': ':0', 15:02:19 INFO - 'HOME': '/home/cltbld', 15:02:19 INFO - 'LANG': 'en_US.UTF-8', 15:02:19 INFO - 'LANGUAGE': 'en_US:en', 15:02:19 INFO - 'LOGNAME': 'cltbld', 15:02:19 INFO - 'MAIL': '/var/mail/cltbld', 15:02:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:02:19 INFO - 'MOZ_NO_REMOTE': '1', 15:02:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:02:19 INFO - 'NO_EM_RESTART': '1', 15:02:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:02:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:02:19 INFO - 'PWD': '/builds/slave/test', 15:02:19 INFO - 'SHELL': '/bin/bash', 15:02:19 INFO - 'SHLVL': '1', 15:02:19 INFO - 'TERM': 'linux', 15:02:19 INFO - 'TMOUT': '86400', 15:02:19 INFO - 'USER': 'cltbld', 15:02:19 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1468360872.253528-239280511', 15:02:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:02:19 INFO - '_': '/tools/buildbot/bin/python'} 15:02:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:02:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:02: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 15:02: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 15:02: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 15:02: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 15:02: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 15:02: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 15:02:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:02:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:02:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:02:21 INFO - Installing collected packages: mozsystemmonitor 15:02:21 INFO - Running setup.py install for mozsystemmonitor 15:02:22 INFO - Successfully installed mozsystemmonitor 15:02:22 INFO - Cleaning up... 15:02:22 INFO - Return code: 0 15:02:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:02:22 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')]} 15:02:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:02:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:22 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')]} 15:02:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:02:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:22 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 0x20bc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x237d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x237b3b0>, '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 0x237c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x237c740>, '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': '5bd038846534b2fdfa68eeae000001e2-1468360872.253528-239280511', '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 15:02:22 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 15:02:22 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 15:02:22 INFO - Using env: {'DISPLAY': ':0', 15:02:22 INFO - 'HOME': '/home/cltbld', 15:02:22 INFO - 'LANG': 'en_US.UTF-8', 15:02:22 INFO - 'LANGUAGE': 'en_US:en', 15:02:22 INFO - 'LOGNAME': 'cltbld', 15:02:22 INFO - 'MAIL': '/var/mail/cltbld', 15:02:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:02:22 INFO - 'MOZ_NO_REMOTE': '1', 15:02:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:02:22 INFO - 'NO_EM_RESTART': '1', 15:02:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:02:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:02:22 INFO - 'PWD': '/builds/slave/test', 15:02:22 INFO - 'SHELL': '/bin/bash', 15:02:22 INFO - 'SHLVL': '1', 15:02:22 INFO - 'TERM': 'linux', 15:02:22 INFO - 'TMOUT': '86400', 15:02:22 INFO - 'USER': 'cltbld', 15:02:22 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1468360872.253528-239280511', 15:02:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:02:22 INFO - '_': '/tools/buildbot/bin/python'} 15:02:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:02:22 INFO - Downloading/unpacking jsonschema==2.5.1 15:02: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 15:02: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 15:02: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 15:02: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 15:02: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 15:02: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 15:02:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:02:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:02:24 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 15:02:24 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 15:02:24 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 15:02:24 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 15:02:24 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 15:02:24 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 15:02:24 INFO - Downloading functools32-3.2.3-2.tar.gz 15:02:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:02:24 INFO - warning: no files found matching '*.txt' 15:02:24 INFO - no previously-included directories found matching 'build' 15:02:24 INFO - no previously-included directories found matching 'dist' 15:02:24 INFO - no previously-included directories found matching '.git*' 15:02:24 INFO - Installing collected packages: jsonschema, functools32 15:02:24 INFO - Running setup.py install for functools32 15:02:24 INFO - warning: no files found matching '*.txt' 15:02:24 INFO - no previously-included directories found matching 'build' 15:02:24 INFO - no previously-included directories found matching 'dist' 15:02:24 INFO - no previously-included directories found matching '.git*' 15:02:24 INFO - Successfully installed jsonschema functools32 15:02:24 INFO - Cleaning up... 15:02:24 INFO - Return code: 0 15:02:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:02: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')]} 15:02:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:02:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02: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')]} 15:02:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:02:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:24 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 0x20bc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x237d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x237b3b0>, '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 0x237c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x237c740>, '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': '5bd038846534b2fdfa68eeae000001e2-1468360872.253528-239280511', '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 15:02:24 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 15:02:24 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 15:02:24 INFO - Using env: {'DISPLAY': ':0', 15:02:24 INFO - 'HOME': '/home/cltbld', 15:02:24 INFO - 'LANG': 'en_US.UTF-8', 15:02:24 INFO - 'LANGUAGE': 'en_US:en', 15:02:24 INFO - 'LOGNAME': 'cltbld', 15:02:24 INFO - 'MAIL': '/var/mail/cltbld', 15:02:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:02:24 INFO - 'MOZ_NO_REMOTE': '1', 15:02:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:02:24 INFO - 'NO_EM_RESTART': '1', 15:02:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:02:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:02:24 INFO - 'PWD': '/builds/slave/test', 15:02:24 INFO - 'SHELL': '/bin/bash', 15:02:24 INFO - 'SHLVL': '1', 15:02:24 INFO - 'TERM': 'linux', 15:02:24 INFO - 'TMOUT': '86400', 15:02:24 INFO - 'USER': 'cltbld', 15:02:24 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1468360872.253528-239280511', 15:02:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:02:24 INFO - '_': '/tools/buildbot/bin/python'} 15:02:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:02:25 INFO - Downloading/unpacking blobuploader==1.2.4 15:02:25 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 15:02:25 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 15:02:25 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 15:02:25 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 15:02:25 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 15:02:25 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 15:02:26 INFO - Downloading blobuploader-1.2.4.tar.gz 15:02:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:02:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:02:27 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 15:02:27 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 15:02:27 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 15:02:27 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 15:02:27 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 15:02:27 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 15:02:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:02:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:02:27 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 15:02:27 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 15:02:27 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 15:02:27 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 15:02:27 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 15:02:27 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 15:02:27 INFO - Downloading docopt-0.6.1.tar.gz 15:02:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:02:27 INFO - Installing collected packages: blobuploader, requests, docopt 15:02:27 INFO - Running setup.py install for blobuploader 15:02:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:02:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:02:27 INFO - Running setup.py install for requests 15:02:28 INFO - Running setup.py install for docopt 15:02:28 INFO - Successfully installed blobuploader requests docopt 15:02:28 INFO - Cleaning up... 15:02:28 INFO - Return code: 0 15:02:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:02:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:02:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:02:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:02:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:02:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:28 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 0x20bc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x237d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x237b3b0>, '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 0x237c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x237c740>, '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': '5bd038846534b2fdfa68eeae000001e2-1468360872.253528-239280511', '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 15:02:28 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 15:02:28 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 15:02:28 INFO - Using env: {'DISPLAY': ':0', 15:02:28 INFO - 'HOME': '/home/cltbld', 15:02:28 INFO - 'LANG': 'en_US.UTF-8', 15:02:28 INFO - 'LANGUAGE': 'en_US:en', 15:02:28 INFO - 'LOGNAME': 'cltbld', 15:02:28 INFO - 'MAIL': '/var/mail/cltbld', 15:02:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:02:28 INFO - 'MOZ_NO_REMOTE': '1', 15:02:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:02:28 INFO - 'NO_EM_RESTART': '1', 15:02:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:02:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:02:28 INFO - 'PWD': '/builds/slave/test', 15:02:28 INFO - 'SHELL': '/bin/bash', 15:02:28 INFO - 'SHLVL': '1', 15:02:28 INFO - 'TERM': 'linux', 15:02:28 INFO - 'TMOUT': '86400', 15:02:28 INFO - 'USER': 'cltbld', 15:02:28 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1468360872.253528-239280511', 15:02:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:02:28 INFO - '_': '/tools/buildbot/bin/python'} 15:02:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:02:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:02:28 INFO - Running setup.py (path:/tmp/pip-3w9OP7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:02:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:02:28 INFO - Running setup.py (path:/tmp/pip-lxbeJO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:02:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:02:28 INFO - Running setup.py (path:/tmp/pip-FhFCpc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:02:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:02:28 INFO - Running setup.py (path:/tmp/pip-0NJgOG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:02:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:02:28 INFO - Running setup.py (path:/tmp/pip-vS1V9Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:02:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:02:28 INFO - Running setup.py (path:/tmp/pip-hXlWQT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:02:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:02:29 INFO - Running setup.py (path:/tmp/pip-Hr7Hgi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:02:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:02:29 INFO - Running setup.py (path:/tmp/pip-1JlwVo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:02:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:02:29 INFO - Running setup.py (path:/tmp/pip-IK1eHV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:02:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:02:29 INFO - Running setup.py (path:/tmp/pip-vcUrff-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:02:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:02:29 INFO - Running setup.py (path:/tmp/pip-32ucll-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:02:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:02:29 INFO - Running setup.py (path:/tmp/pip-A8V_MH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:02:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:02:29 INFO - Running setup.py (path:/tmp/pip-p6Oqkc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:02:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:02:29 INFO - Running setup.py (path:/tmp/pip-ksMNY_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:02:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:02:29 INFO - Running setup.py (path:/tmp/pip-6Ofvi2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:02:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:02:29 INFO - Running setup.py (path:/tmp/pip-bAngzH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:02:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:02:29 INFO - Running setup.py (path:/tmp/pip-P3_WSs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:02:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:02:29 INFO - Running setup.py install for manifestparser 15:02:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:02:29 INFO - Running setup.py install for mozcrash 15:02:29 INFO - Running setup.py install for mozdebug 15:02:30 INFO - Running setup.py install for mozdevice 15:02:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:02:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:02:30 INFO - Running setup.py install for mozfile 15:02:30 INFO - Running setup.py install for mozhttpd 15:02:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:02:30 INFO - Running setup.py install for mozinfo 15:02:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:02:30 INFO - Running setup.py install for mozInstall 15:02:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:02:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:02:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:02:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:02:30 INFO - Running setup.py install for mozleak 15:02:30 INFO - Running setup.py install for mozlog 15:02:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:02:30 INFO - Running setup.py install for moznetwork 15:02:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:02:30 INFO - Running setup.py install for mozprocess 15:02:31 INFO - Running setup.py install for mozprofile 15:02:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:02:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:02:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:02:31 INFO - Running setup.py install for mozrunner 15:02:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:02:31 INFO - Running setup.py install for mozscreenshot 15:02:31 INFO - Running setup.py install for moztest 15:02:31 INFO - Running setup.py install for mozversion 15:02:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:02:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:02:31 INFO - Cleaning up... 15:02:31 INFO - Return code: 0 15:02:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:02: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')]} 15:02:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:02:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02: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')]} 15:02:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:02:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:31 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 0x20bc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x237d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x237b3b0>, '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 0x237c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x237c740>, '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': '5bd038846534b2fdfa68eeae000001e2-1468360872.253528-239280511', '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 15:02:31 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 15:02:31 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 15:02:31 INFO - Using env: {'DISPLAY': ':0', 15:02:31 INFO - 'HOME': '/home/cltbld', 15:02:31 INFO - 'LANG': 'en_US.UTF-8', 15:02:31 INFO - 'LANGUAGE': 'en_US:en', 15:02:31 INFO - 'LOGNAME': 'cltbld', 15:02:31 INFO - 'MAIL': '/var/mail/cltbld', 15:02:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:02:31 INFO - 'MOZ_NO_REMOTE': '1', 15:02:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:02:31 INFO - 'NO_EM_RESTART': '1', 15:02:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:02:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:02:31 INFO - 'PWD': '/builds/slave/test', 15:02:31 INFO - 'SHELL': '/bin/bash', 15:02:31 INFO - 'SHLVL': '1', 15:02:31 INFO - 'TERM': 'linux', 15:02:31 INFO - 'TMOUT': '86400', 15:02:31 INFO - 'USER': 'cltbld', 15:02:31 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1468360872.253528-239280511', 15:02:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:02:31 INFO - '_': '/tools/buildbot/bin/python'} 15:02:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:02:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:02:31 INFO - Running setup.py (path:/tmp/pip-qYpXrX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:02:31 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)) 15:02:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:02:31 INFO - Running setup.py (path:/tmp/pip-fvDa_h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:02:31 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)) 15:02:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:02:31 INFO - Running setup.py (path:/tmp/pip-lvY1T5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:02:31 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)) 15:02:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:02:32 INFO - Running setup.py (path:/tmp/pip-5jr53P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:02:32 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)) 15:02:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:02:32 INFO - Running setup.py (path:/tmp/pip-ZLVX9W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:02:32 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)) 15:02:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:02:32 INFO - Running setup.py (path:/tmp/pip-ZKrPVy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:02:32 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)) 15:02:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:02:32 INFO - Running setup.py (path:/tmp/pip-3WX8i8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:02:32 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)) 15:02:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:02:32 INFO - Running setup.py (path:/tmp/pip-1f8U2Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:02:32 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)) 15:02:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:02:32 INFO - Running setup.py (path:/tmp/pip-x9Eacf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:02:32 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)) 15:02:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:02:32 INFO - Running setup.py (path:/tmp/pip-NJsonO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:02:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 15:02:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:02:32 INFO - Running setup.py (path:/tmp/pip-GlFlwS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:02:32 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)) 15:02:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:02:32 INFO - Running setup.py (path:/tmp/pip-PmWSWO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:02:32 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)) 15:02:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:02:32 INFO - Running setup.py (path:/tmp/pip-MqcUwS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:02:32 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)) 15:02:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:02:32 INFO - Running setup.py (path:/tmp/pip-kOJQVz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:02:32 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)) 15:02:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:02:32 INFO - Running setup.py (path:/tmp/pip-hJxP_U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:02:32 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)) 15:02:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:02:32 INFO - Running setup.py (path:/tmp/pip-5zamnN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:02:32 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)) 15:02:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:02:32 INFO - Running setup.py (path:/tmp/pip-5StGFQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:02:33 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)) 15:02:33 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)) 15:02:33 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)) 15:02:33 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)) 15:02:33 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)) 15:02:33 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)) 15:02:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:02:33 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 15:02:33 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 15:02:33 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 15:02:33 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 15:02:33 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 15:02:33 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 15:02:34 INFO - Downloading blessings-1.6.tar.gz 15:02:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:02:34 INFO - Installing collected packages: blessings 15:02:34 INFO - Running setup.py install for blessings 15:02:35 INFO - Successfully installed blessings 15:02:35 INFO - Cleaning up... 15:02:35 INFO - Return code: 0 15:02:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:02:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:02:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:02:35 INFO - Reading from file tmpfile_stdout 15:02:35 INFO - Current package versions: 15:02:35 INFO - argparse == 1.2.1 15:02:35 INFO - blessings == 1.6 15:02:35 INFO - blobuploader == 1.2.4 15:02:35 INFO - docopt == 0.6.1 15:02:35 INFO - functools32 == 3.2.3-2 15:02:35 INFO - jsonschema == 2.5.1 15:02:35 INFO - manifestparser == 1.1 15:02:35 INFO - mozInstall == 1.12 15:02:35 INFO - mozcrash == 0.17 15:02:35 INFO - mozdebug == 0.1 15:02:35 INFO - mozdevice == 0.48 15:02:35 INFO - mozfile == 1.2 15:02:35 INFO - mozhttpd == 0.7 15:02:35 INFO - mozinfo == 0.9 15:02:35 INFO - mozleak == 0.1 15:02:35 INFO - mozlog == 3.2 15:02:35 INFO - moznetwork == 0.27 15:02:35 INFO - mozprocess == 0.23 15:02:35 INFO - mozprofile == 0.28 15:02:35 INFO - mozrunner == 6.12 15:02:35 INFO - mozscreenshot == 0.1 15:02:35 INFO - mozsystemmonitor == 0.3 15:02:35 INFO - moztest == 0.7 15:02:35 INFO - mozversion == 1.4 15:02:35 INFO - psutil == 3.1.1 15:02:35 INFO - requests == 1.2.3 15:02:35 INFO - wsgiref == 0.1.2 15:02:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:02:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:02:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:02:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:02:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:02:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:35 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 0x20bc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x237d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x237b3b0>, '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 0x237c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x237c740>, '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': '5bd038846534b2fdfa68eeae000001e2-1468360872.253528-239280511', '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 15:02:35 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 15:02:35 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 15:02:35 INFO - Using env: {'DISPLAY': ':0', 15:02:35 INFO - 'HOME': '/home/cltbld', 15:02:35 INFO - 'LANG': 'en_US.UTF-8', 15:02:35 INFO - 'LANGUAGE': 'en_US:en', 15:02:35 INFO - 'LOGNAME': 'cltbld', 15:02:35 INFO - 'MAIL': '/var/mail/cltbld', 15:02:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:02:35 INFO - 'MOZ_NO_REMOTE': '1', 15:02:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:02:35 INFO - 'NO_EM_RESTART': '1', 15:02:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:02:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:02:35 INFO - 'PWD': '/builds/slave/test', 15:02:35 INFO - 'SHELL': '/bin/bash', 15:02:35 INFO - 'SHLVL': '1', 15:02:35 INFO - 'TERM': 'linux', 15:02:35 INFO - 'TMOUT': '86400', 15:02:35 INFO - 'USER': 'cltbld', 15:02:35 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1468360872.253528-239280511', 15:02:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:02:35 INFO - '_': '/tools/buildbot/bin/python'} 15:02:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:02:35 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)) 15:02:35 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)) 15:02:35 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)) 15:02:35 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)) 15:02:35 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)) 15:02:35 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)) 15:02:35 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)) 15:02:35 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)) 15:02:35 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)) 15:02:35 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)) 15:02:35 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)) 15:02:35 INFO - Cleaning up... 15:02:35 INFO - Return code: 0 15:02:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:02:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:02:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:02:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:02:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:02:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:35 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 0x20bc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x237d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x237b3b0>, '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 0x237c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x237c740>, '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': '5bd038846534b2fdfa68eeae000001e2-1468360872.253528-239280511', '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 15:02:35 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 15:02:35 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 15:02:35 INFO - Using env: {'DISPLAY': ':0', 15:02:35 INFO - 'HOME': '/home/cltbld', 15:02:35 INFO - 'LANG': 'en_US.UTF-8', 15:02:35 INFO - 'LANGUAGE': 'en_US:en', 15:02:35 INFO - 'LOGNAME': 'cltbld', 15:02:35 INFO - 'MAIL': '/var/mail/cltbld', 15:02:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:02:35 INFO - 'MOZ_NO_REMOTE': '1', 15:02:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:02:35 INFO - 'NO_EM_RESTART': '1', 15:02:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:02:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:02:35 INFO - 'PWD': '/builds/slave/test', 15:02:35 INFO - 'SHELL': '/bin/bash', 15:02:35 INFO - 'SHLVL': '1', 15:02:35 INFO - 'TERM': 'linux', 15:02:35 INFO - 'TMOUT': '86400', 15:02:35 INFO - 'USER': 'cltbld', 15:02:35 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1468360872.253528-239280511', 15:02:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:02:35 INFO - '_': '/tools/buildbot/bin/python'} 15:02:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:02:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:02:35 INFO - Cleaning up... 15:02:35 INFO - Return code: 0 15:02:35 INFO - Running post-action listener: _resource_record_post_action 15:02:35 INFO - Running post-action listener: _start_resource_monitoring 15:02:35 INFO - Starting resource monitoring. 15:02:35 INFO - ##### 15:02:35 INFO - ##### Finished create-virtualenv step (success) 15:02:35 INFO - ##### 15:02:35 INFO - ##### 15:02:35 INFO - ##### Running install step. 15:02:35 INFO - ##### 15:02:35 INFO - Running pre-action listener: _resource_record_pre_action 15:02:35 INFO - Running main action method: install 15:02:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:02:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:02:36 INFO - Reading from file tmpfile_stdout 15:02:36 INFO - Detecting whether we're running mozinstall >=1.0... 15:02:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:02:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:02:36 INFO - Reading from file tmpfile_stdout 15:02:36 INFO - Output received: 15:02:36 INFO - Usage: mozinstall [options] installer 15:02:36 INFO - Options: 15:02:36 INFO - -h, --help show this help message and exit 15:02:36 INFO - -d DEST, --destination=DEST 15:02:36 INFO - Directory to install application into. [default: 15:02:36 INFO - "/builds/slave/test"] 15:02:36 INFO - --app=APP Application being installed. [default: firefox] 15:02:36 INFO - mkdir: /builds/slave/test/build/application 15:02:36 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'] 15:02:36 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 15:02:52 INFO - Reading from file tmpfile_stdout 15:02:52 INFO - Output received: 15:02:52 INFO - /builds/slave/test/build/application/firefox/firefox 15:02:52 INFO - Running post-action listener: _resource_record_post_action 15:02:52 INFO - ##### 15:02:52 INFO - ##### Finished install step (success) 15:02:52 INFO - ##### 15:02:52 INFO - ##### 15:02:52 INFO - ##### Running run-tests step. 15:02:52 INFO - ##### 15:02:52 INFO - Running pre-action listener: _resource_record_pre_action 15:02:52 INFO - Running main action method: run_tests 15:02:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:02:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:02:52 INFO - Python 2.7.3 15:02:52 INFO - Return code: 0 15:02:52 INFO - grabbing minidump binary from tooltool 15:02:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:02:52 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 0x237b3b0>, '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 0x237c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x237c740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:02:52 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 15:02:52 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 15:02:52 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 15:02:52 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:02:52 INFO - Return code: 0 15:02:52 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:02:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:02:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:02:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:02:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:02:52 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/ICxnFLgER4ChM_-iieoMRw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-039', '--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 15:02:52 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/ICxnFLgER4ChM_-iieoMRw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-039 --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 15:02:52 INFO - Using env: {'DISPLAY': ':0', 15:02:52 INFO - 'HOME': '/home/cltbld', 15:02:52 INFO - 'LANG': 'en_US.UTF-8', 15:02:52 INFO - 'LANGUAGE': 'en_US:en', 15:02:52 INFO - 'LOGNAME': 'cltbld', 15:02:52 INFO - 'MAIL': '/var/mail/cltbld', 15:02:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:02:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:02:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:02:52 INFO - 'MOZ_NO_REMOTE': '1', 15:02:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:02:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:02:52 INFO - 'NO_EM_RESTART': '1', 15:02:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:02:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:02:52 INFO - 'PWD': '/builds/slave/test', 15:02:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:02:52 INFO - 'SHELL': '/bin/bash', 15:02:52 INFO - 'SHLVL': '1', 15:02:52 INFO - 'TERM': 'linux', 15:02:52 INFO - 'TMOUT': '86400', 15:02:52 INFO - 'USER': 'cltbld', 15:02:52 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1468360872.253528-239280511', 15:02:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:02:52 INFO - '_': '/tools/buildbot/bin/python'} 15:02:52 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/ICxnFLgER4ChM_-iieoMRw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-039', '--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 15:02:52 INFO - mozversion application_buildid: 20160712142126 15:02:52 INFO - mozversion application_changeset: 9fb3a5f8b892765c7b877aa8541228fd0b5a1928 15:02:52 INFO - mozversion application_display_name: Nightly 15:02:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:02:52 INFO - mozversion application_name: Firefox 15:02:52 INFO - mozversion application_remotingname: firefox 15:02:52 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 15:02:52 INFO - mozversion application_vendor: Mozilla 15:02:52 INFO - mozversion application_version: 50.0a1 15:02:52 INFO - mozversion platform_buildid: 20160712142126 15:02:52 INFO - mozversion platform_changeset: 9fb3a5f8b892765c7b877aa8541228fd0b5a1928 15:02:52 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 15:02:52 INFO - mozversion platform_version: 50.0a1 15:02:52 INFO - using testdate: 1468360972 15:02:52 INFO - actual date: 1468360972 15:02:52 INFO - starting webserver on 'localhost:56948' 15:02:52 INFO - SUITE-START | Running 2 tests 15:02:52 INFO - TEST-START | dromaeo_css 15:02:52 INFO - operating with platform_type : linux_ 15:02:52 INFO - Initialising browser for dromaeo_css test... 15:02:52 INFO - TEST-INFO | started process 887 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTUq3Oc/profile http://localhost:56948/getInfo.html) 15:02:57 INFO - PROCESS | 887 | __metrics Screen width/height:1600/1200 15:02:57 INFO - PROCESS | 887 | colorDepth:24 15:02:57 INFO - PROCESS | 887 | Browser inner width/height: 1024/697 15:02:57 INFO - PROCESS | 887 | __metrics 15:02:57 INFO - PROCESS | 887 | 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" 15:02:57 INFO - PROCESS | 887 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:02:58 INFO - PROCESS | 887 | console.error: PushService: 15:02:58 INFO - PROCESS | 887 | stateChangeProcessEnqueue: Error transitioning state 15:02:58 INFO - PROCESS | 887 | UnknownError 15:02:58 INFO - TEST-INFO | 887: exit 0 15:02:58 INFO - Browser initialized. 15:02:58 INFO - Running cycle 1/1 for dromaeo_css test... 15:02:58 INFO - TEST-INFO | started process 971 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTUq3Oc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:03:10 INFO - PROCESS | 971 | RSS: Main: 159727616 15:03:10 INFO - PROCESS | 971 | 15:04:01 INFO - PROCESS | 971 | 1468361041727 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] 15:06:15 INFO - PROCESS | 971 | Cycle 1(1): loaded http://localhost:56948/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56948/tests/dromaeo/cssquery-ext.html) 15:06:16 INFO - PROCESS | 971 | RSS: Main: 261435392 15:06:16 INFO - PROCESS | 971 | 15:09:18 INFO - PROCESS | 971 | Cycle 1(1): loaded http://localhost:56948/tests/dromaeo/cssquery-ext.html (next: http://localhost:56948/tests/dromaeo/cssquery-jquery.html) 15:09:19 INFO - PROCESS | 971 | RSS: Main: 241147904 15:09:19 INFO - PROCESS | 971 | 15:12:21 INFO - PROCESS | 971 | Cycle 1(1): loaded http://localhost:56948/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56948/tests/dromaeo/cssquery-mootools.html) 15:12:22 INFO - PROCESS | 971 | RSS: Main: 238796800 15:12:22 INFO - PROCESS | 971 | 15:15:24 INFO - PROCESS | 971 | Cycle 1(1): loaded http://localhost:56948/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56948/tests/dromaeo/cssquery-prototype.html) 15:15:25 INFO - PROCESS | 971 | RSS: Main: 239235072 15:15:25 INFO - PROCESS | 971 | 15:18:28 INFO - PROCESS | 971 | Cycle 1(1): loaded http://localhost:56948/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56948/tests/dromaeo/cssquery-yui.html) 15:18:29 INFO - PROCESS | 971 | RSS: Main: 221429760 15:18:29 INFO - PROCESS | 971 | 15:21:31 INFO - PROCESS | 971 | Cycle 1(1): loaded http://localhost:56948/tests/dromaeo/cssquery-yui.html (next: http://localhost:56948/tests/dromaeo/cssquery-dojo.html) 15:21:32 INFO - PROCESS | 971 | RSS: Main: 326152192 15:21:32 INFO - PROCESS | 971 | 15:21:32 INFO - PROCESS | 971 | __start_tp_report 15:21:32 INFO - PROCESS | 971 | _x_x_mozilla_page_load 15:21:32 INFO - PROCESS | 971 | _x_x_mozilla_page_load_details 15:21:32 INFO - PROCESS | 971 | |i|pagename|runs| 15:21:32 INFO - PROCESS | 971 | |0;dojo.html;2641.973580264197;2645.828021178622;2684.0471632570425;2684.986575067124;2685.154176434423;4606.930896036562;4680.5787479126875;4729.668923175379;4758.976205118978;4828.058528586923;4681.368015317931;4741.881452963669;4748.453927798304;4748.976255118729;4763.285507173926;4386.210482113226;4391.01202229499;4406.272964960777;4430.667699922518;4490.1019796040755;4385.101054283864;4467.441569803775;4481.596656300932;4483.439570053743;4507.4365704286965;4427.667924905645;4489.618382437497;4518.051209246046;4532.342810292505;4536.841210557631;4268.786560671954;4297.656187504992;4312.978435107804;4325.243082460588;4436.401085853433;4317.69776115672;4342.392065110887;4351.543087975749;4362.018545827195;4380.255356589388;4818.084563932876;4835.6131509479155;4857.8299759508245;4862.513748625144;4880.170371036932;4860.854174374775;4999.9500004999845;5025.472325405853;5075.0611136939415;5192.558632516203;5131.640785145041;5136.383633963911;5144.6655967362;5147.176451767699;5155.53600175986;4785.3300537924715;4820.325154478376;4820.68665536739;4821.4696383397795;4822.493638168012;5078;5173.1464308388695;5240.213967904845;5255.921161182585;5329.040772660881;5083.2375143727995;5094.133997220407;5100.132977393852;5105.591552675777;5117.744112794346;4973.975130124326;5139.306193663882;5143.639945203875;5149.588032957355;5185.274061631375;4980.228064649985;5049.722265275445;5061.974690126526;5069.31564238823;5139.511746384088;5030.748462576857;5044.646874718808;5050.141475949097;5052.8484145475695;5102.132637451642;4194.2450358935685;4283.571642835692;4297.269464191095;4297.376880352332;4343.891402714958;3708.4622729704042;3727.1613886875234;3762.0218743126984;3775.2260786538695;3796.772193668389;4351.891202719894;4361.498427680857;4371.103923695635;4375.431193944767;4422.690411671152;5092.821751238689;5098.5411312982005;5099.898002039939;5103.413107492686;5145.76844042019;4248.129133527619;4387.166438376698;4402.691811573223;4441.933370999438;4453.398791163217;4621.976890115528;4629.166749984967;4634.026854360622;4634.907301853944;4659.743714095689;4629.722216667011;4853.9029219415415;4866;4868.196747536616;4914.508549145128;4952.059108769467;4980.526849949249;4980.975095124501;4982.775775090202;4984.800607975785;4666.323383109498;4726.787294571684;4733.124371991193;4740.454847692422;4770.928436073392;4920.21276595743;4924.285978533164;4980.203167493184;5019.824306149268;5021.472745361831;5148.073346797612;5180.404253510895;5199.48005199477;5200.63595548308;5251.291075704731;5043.44522102561;5048.368953880765;5053.469385714447;5056.721880296619;5107.157319042316;4801.063792560417;4919.901601967869;4949.455559888482;5056.317397151338;5065.189569668835;4906.63200259989;4946.480619534897;4962.354893863775;4973.701577905337;4981.277714731271;5026.070177017264;5035.295058691713;5039.445660977216;5044.344235249394;5083.186690129562;4738.028704115717;4817.108834865561;4861.878453038702;4915.705057696549;4931.408231012301 15:21:32 INFO - PROCESS | 971 | |1;ext.html;568.8094488346404;576.3055518100689;578.8813293274877;582.6474982635506;583.4398976982097;27401.862990685044;40493;41627.79186104069;41972.790136049276;45809.77095114528;41510.169796604125;41913.37129943053;41920;41960.790196049056;41976.79011604938;7807.804804879866;7921.762347129596;7947.245011723906;7948.602569871512;7952.244536768998;7731.574763388011;7808.882866757003;7823.608819559027;7826.217378262185;7827.569483678395;7838.372930165601;7842.137364889886;7875.369970402354;7920.48516846406;7923.247291507298;7730.922690773076;7775.222477752236;7783.299503044725;7805.804854878616;7856.960715196387;7625.771226863148;7683.884741728879;7689.653965571507;7697.576633285167;7697.807554811118;39472.40791388103;39761.40357894634;39828.20343593112;39829;39837.80081099576;3059.4034163338156;3072.6620071792086;3072.9231769205726;3083.5066389377594;3362.5964884213804;29811.10566683003;30078.24804379886;30474.085777426713;30523.389532209232;30806.53790193149;3352.8323583820957;3425.2806910548816;3476.9652303476896;3503.8248087595516;3608.440691692792;174209;176157;176679.11660441745;176915;177367.11316443465;32021.359572808415;55564.72217638886;55934.44065559374;56348.71825640846;56447.435525645036;33116.503252451235;34651.30697386038;34785;34816;34864;13215.66960825987;13382.732345353234;13395.397207125508;13399.53101641438;13431.12697674648;10913.83629245562;11008.449577521093;11034.834477482844;11037.668869933916;11039.83440248397;7935.880961785578;8057.5165490070785;8067.475614085066;8114.3102836258395;8128.309093727099;3079.784415090967;3080.753539716862;3096.427160975227;3108.564800927872;3140.2463408781728;866.9739907802776;871.6600525794945;888.8577827547562;935.4808081514689;956.0630582029511;28603.28491787722;28696.85651571729;28993.42013159725;29018;29585.55621665677;972.4214092614883;1017.2624846985876;1031.9742006449749;1034.896510348959;1041.8124737547314;14963.40146394132;15350.155741434328;15434.38262469511;15496.380144794308;15639.843601563838;439140;444382;444569;445714;445903.3114503285;49749.75125124351;50272.497275026784;50340.49659503431;50425;50457.74771126121;1081.4592703648175;1172.876847930972;1192.6600918738118;1199.400299850075;1205.3852535207106;28863.5670464939;29578.260543486584;29924.401511970074;30330.696693032787;30466;42299.365509518;47092.7645361771;48635.27047094367;48672;52893.7355313221;395303;395610;397768;398027;399701;31530.842345788125;37133.25733485369;38172.618273818014;38783.41824872573;39298.410523841594;42203.57796421996;42366.78816606021;42853.57146428621;43206.56793432026;43422.78288608537;36239.275214495035;36766;36902.261954760215;37102.44346334861;37334.813325933195;36546.45180322351;36677.63322366742;36898.26203475969;37177.25645487129;37252;35888.4616730744;36296;36466.635333647384;36712.81643591872;37037.07407314731;31542.684573154893;33186.66813331933;33350.49974250437;33480;33797.66202337945;26217;26276.605850912634;26354.341141471617;26366;26608.733912660624 15:21:32 INFO - PROCESS | 971 | |2;jquery.html;340.2107111501318;365.8079508258164;367.15737382706686;369.72270796902325;382.85451528419196;12831.101822872391;16139.596510087238;16839.915800420997;18574;18611.255549778027;17095.48713538596;17531.12344382779;17761.111944402794;17763.64472710545;17800.219978002195;5121.820736274231;5157.045946499909;5252.290940723011;5268.262443257928;5287.629865909388;3723.646253605903;5224.92162617561;5776.855578610526;6110.08348747687;6125.203723515937;6095.08573713942;6161.137440758297;6184;6190.257169139687;6199.101130336098;6140.785072522485;6244.531660125486;6252.124702541648;6259.467945224661;6262.96868515659;3369.79781213128;5026.924596131061;5344.919826202571;5347.919781203285;5357.946420535784;21119.683204751942;21791.23730669419;21799.128034878588;21940.89029554842;21947.231846885283;2021.2723419568943;2093.005822234442;2163.056588351378;2295.653907496003;2389.6535002424553;13092.410841512155;13262.071654984155;13377.799333010013;13385.66535836602;13709.931450342785;1735.1237624999294;1746.956326091845;1750;1759.6392739488374;1807.1145138881852;69234.3076569233;69421.30578694199;70516;71725.64137179281;72518;8476.321894248445;9104.817903642022;9150.176484116462;9317.813643727088;9356.157945784777;12113.939430302793;13061.608151755463;13099.738005239842;13529.255890926093;13571.660708482366;6946;7237.312455316736;7298.160711518137;7303.196648368676;7486.625668716542;6771;6916.377526022581;6981.930180698128;7047.823804404931;7197.4601904857345;6035.336113027563;6106.297775755845;6210.9068363974575;6228.190335256388;6242.438180563771;1758.6131051168722;1782.9019403932648;1804.3684710351536;1818.7180986947044;1831.4414103698234;883.8718385834018;918.0131358789329;930.4882314727008;939.7321763297427;945.7682867697458;16892.239849206817;17136.657266854592;17734.822651773575;18203.36288229932;18987.810121898605;873.5021038008401;875.2647775868174;881.1188811188811;894.1103601916135;926.3746970794687;9599.136077752895;9886;10125.949370253102;10128;10176.694699159038;404272;406541;416009;417026;418622;84752;84941.57529212438;87093.56453217694;90739;91173.54413227891;899.2266650680409;926.18495723764;943.698016634684;945.5130607737021;946.0303189231093;15528.22358882074;15664;16038.518844434686;16216.702663787104;16383.344666213694;25848.483030339663;34477.482837757954;36317.63682363142;38612.034699132746;38973.610263897;46037.76981115073;50448.747756260986;52387.73806130945;53000.46999530109;53513;7429.479936404616;7459.477836551607;7678.769636910902;7699.037620297463;7737.381009519112;9727.708168754949;11251.774964500828;12583.68540786451;12722.681932951751;12989.4154763034;10615.256932014685;10711;10777.568897244337;10879.728006799893;11287.492062857027;10229.28395012342;10581.576736930436;10679.626213082505;10835.295705779099;11164.88835111671;9993.502274203971;10129.544170512198;10240.027197416233;10569.947150264199;11173.776524469627;7967.601619919097;8066.032076150899;8100.311473524581;8126.390520710852;8148.470349427268;9239.888249729074;10131.64539241123;10501.684949451528;10800.49755435189;10884.510197040994 15:21:32 INFO - PROCESS | 971 | |3;mootools.html;1359.8028285898547;1410.6402867268846;1469.1625773309217;1660.9003459792414;1675.765392845001;7058.894116588249;7457.328840404362;7511.586862722548;7517.323440890319;7523.13483949346;6252.562320637558;7036.085308909847;7321.963390183042;7327.26727327267;7365.631718414084;2167.0898222746446;2192.068370942351;2195.560887822442;2196.3081629286803;2198.1756841184556;1789.1233295685151;2193.122750899637;2199.197292988056;2235.1282999630075;2238.0712004518105;2117.9682304765442;2183.072194317409;2189.7481789594162;2197.890105494727;2199.692043113965;2207.2716003718733;2225.276785044859;2233.564454931289;2236.2955668964387;2241.0587553227683;2118.046878904499;2119.586680597284;2122.755883073451;2124.5007423255524;2126.096409026173;7510.8122296942465;7548.5848278344665;7555.7355492558045;7559.1684914659345;7564.546127232328;4359.498657654379;4434.17967675981;4481.753503557303;4482.820687172509;4488.05750792334;2750.2986738381783;2778.1526634376505;2876.0652787844033;2882.207392966951;2891.4506243813607;865.1089377940668;865.8831057807179;867.119873328572;876.8728534362546;899.9010108888017;9817.852732209023;9819.509024548815;9839.409635421825;9858.90141098587;9886.456244906527;4003.499562554681;4093;4219.07180420307;4240.639545638655;4242.384854196124;8450.704225352083;8482.279006284412;8482.660693572312;8487.363447741443;8489.278411334983;4419.292913133948;4456.3538286948215;4460.531644177379;4470.552944705569;4473.507914129443;3990.780507072081;4046.0896298333228;4049.493813273341;4054.3715724062818;4055;3530.435130379127;3543.20277937462;3615.1323682316056;3620.6922411594783;3629.473726309723;1006.8540061691014;1156.2599936040772;1167.8948894599528;1174.3893175548837;1186.1815347410259;756.3797685897621;776.7902666280073;815.9020917489939;828.511178404732;831.3266254333888;5033.421156566968;5052.242163675404;5114.795408183705;5135.6918584885025;5141.100307446182;835.8453686068096;840.0465471689677;853.5262929074423;881.2377293641083;887.6138879587401;3547.5742910850863;3585.8027808470265;3606.6032736398975;3656.1590834107774;3683.318586061587;8816.294696424271;9242.21441177494;9316.44101353921;9317.021712720203;9317.34778565494;9217.032211617683;9219.95390023059;9232.353735238603;9232.953835230916;9249.075092490832;5979.701014949235;6676.065350850885;6942.028116063755;6942.895856562156;7104.183018952886;5203.8178663746585;5268.1834315681135;5298.2052692096495;5405.081136206775;5442.700651464049;8334.708285209988;8516.914830851612;8520.829583408422;8541;8542.44474109181;8548.957255213683;8578.957105214684;8595;8607.139286071593;8662.393632445668;6316.589421687576;6321.747130114744;6432.3567643237175;6530.869382612226;6536.379043990813;7019;7028.191757947901;7056.894146587908;7061.329173728487;7081.539699919489;6417.26201486835;6531.150950376421;6557.868842623217;6655.334466553306;6666;6219.626822390671;6487.3836985486305;6494.6103233806125;6510.804675859732;6571.112899758471;6109.389061093855;6258.937410625836;6283.685815709105;6284.24589049317;6290.370962903673;4445.599896009375;4487.304467807495;4488.820447182076;4591.47198072226;4635.466921304093;4449.28811390176;4503.211937910787;4520.706154099973;4607.861764147081;4649.674522783373 15:21:32 INFO - PROCESS | 971 | |4;prototype.html;296.5522061686853;300.1460843899107;300.2149379372939;306.54018971542683;306.7990466244609;4862.61099112071;4867.269909513566;4880.243562247849;4897.828575999841;4911.901761964758;4741.193997020506;4777.498362671921;4825.348577941985;4841.491643377448;4873.926891096637;3562.2875424915055;3709.814509274539;3720.8325625346924;3817.5186129016083;3842.711796615251;3786.602406747293;3809.5999920008417;3826.4260360945805;3829.7127715421457;3838.251540949516;3581.3553560359123;3788.3938569828833;3794.5256842894637;3794.6964242860645;3796.1079146400575;3644.835982380786;3771.3400154972906;3773.905652358713;3785.432185172219;3796.2217745362136;3381.5797365106714;3382.594088709346;3389.847456864447;3393.1686736749652;3402.9659703402895;4750.073735621555;4754.168020596382;4754.95245047552;4758.500357462486;4769.689970151963;4282.8715138545895;4333.934990975138;4340.305551111838;4364.323529852878;4389.517153113155;4982.551570358685;5000.124978128849;5000.74996250186;5016.469976657118;5019.874503137451;3701.555813302421;3705.370087085202;3712.814359282052;3729.291434627412;3730.1420673245034;5131.7434128293435;5229.254641313341;5258.763355648966;5301.920471192935;5304.787808487656;3781.1303400218103;3790.8294126764363;3799.297130030953;3802.3535998880257;3831.310364134427;3865.1496670732395;3873.7288389812443;3875.767453952772;3878.886007126123;3883.37865941448;4316.611504964569;4342.131573685276;4353.172897149531;4353.978230108829;4361.760103194355;4820;4973.52751488608;4981.327520784647;5004.924926126111;5009.44896061433;1316.2628927800463;1319.3798914510164;1321.85459599444;1326.0916272353468;1326.6882282663566;855.5422848775875;857.8455877941906;887.9378443508892;888.4136469929815;890.5369208011798;4341.327094300388;4375.78121093944;4476.597106260387;4521.186186486399;4529.886752831206;5153.6907785532985;5166.3800343958965;5169.7932082717025;5175.887184255404;5184.351956005499;4300.8709738707885;4462.709923854939;4464.17412778637;4483.663725220622;4613.492515823256;2764.032588594016;2764.8064635475325;2768.7646550043473;2786.665600127957;2805.9158225253277;4896.094222568765;4927.90144197114;4940.308356830046;4940.456549779521;4953.23224900141;5062.82280120194;5346;5351.117065684196;5352.136606915282;5358.651687640291;4928.457869634337;4942.4316203637045;4943.3573635427165;4947.54047555979;4948.678335908155;5202.297689811827;5205;5224.138017227115;5226.216067589892;5265.763040663256;4855.247436647325;4999.900001999867;5017.448080711192;5020.246962955586;5085.211792172219;4875.780589873393;4877.975610121926;4896.730679812645;4897.853064408074;4903;3671.3758661027155;3675.338439080991;3685.428758542323;3688.834002469842;3701.11173318407;5152.948470515247;5181.145111056633;5219.608529360237;5220.1647736362;5296.947030529799;5068.859506610983;5084.923726144037;5085.160948443466;5085.8728531787;5093.388793344822;5059.848204553869;5090.796368145233;5102.387713474258;5121.769520371667;5123;4689.413823272091;4727.7163370197895;4982.701037937735;4986.32684587576;5058.1401161803315;4949.406071271471;5071.13790655582;5072.315237443046;5073.340465739578;5084.135696931605;4941.382327209099;4975.10448119342;4982.576480999156;5013.824516142064;5013.924791128209 15:21:32 INFO - PROCESS | 971 | |5;yui.html;647.1166857239867;674.0731494195478;689.1798759476226;698.441247002398;756.4704706705307;12508.687282817922;13357.33213339334;13358.398872050757;13362.13146145501;13382.330883455837;12300.323482208474;12397.62807115788;12447.875521244785;12875.098743087987;13003.414846331914;784.3411534311177;801.3028665061405;802.6067227058758;802.6789284286274;804.1275216390223;610.2920612089977;768.5043147170072;802.4062193976445;806.5201205282847;807.2008711375754;839.5550358310105;840.017179899516;840.9873851892227;841.6885752271668;842.1115722912356;638.3361304243581;831.8793774902636;832.4630613254477;832.7168762620678;833.4291010657688;591.8549955260988;608.8873558391712;609.9481544068759;612.2255346986066;612.653850574424;13541.796873046913;13551.186928784304;13566.864331356659;13570.864291357058;13600.979926505453;12486.438110284967;13012.60962171127;13124.212547247196;13143.86856131436;13485.865141348657;12044.277343359427;12298.262104273772;12298.754024919452;12336.198147120498;12461.93769031158;3268.117608245784;3304.653011433789;3379.9324013519836;4261.829526818923;4395.274779661352;64943.75281235919;65233;65445;65871.341286587;66297.66851165713;11133.220674552866;11149.16381271408;11158.442077896072;11164.888351116384;11168.106551475863;19976.3008294709;20023.199072036954;20046.198152074045;20059.39821805348;20397;6963.1316612228875;7067.787966361017;7094.396976257076;7146.606936618432;7188.5327453715345;3689.0777305673582;3778.131029863147;3786.8295926683363;3796.7532110413285;3807.695384369244;2644.5504264274737;2737.5893615957766;2740.4245108527034;2865.097494289334;2867.5125228711167;489.89222371078307;494.4165884256612;501.1230346892937;502.4924825925841;503.8513638476585;594.1651978969554;596.5794115148809;599.3946114424463;612.4885720423407;620.8447888027993;8637.611307491177;8693.913060869438;8696.13038696138;8709.303255739653;8858.601362938683;581.1689284323373;591.3235976007979;602.8553147244718;628.1080865171467;638.9552731308763;4069.654079403225;4210.326347784401;4262.083652014831;4269.188854117708;4280.315149576053;90043;93712;96310;96339.51830240944;96912;48996.755016225405;50173.73175816487;67651;79639.60180199062;82175.589122054;71813;77170.61414693003;77438.61280693673;77715;77720.61139694265;7253.8186545336785;7678.385729141767;7902.881456778268;8014.278714915686;8203.917960820316;29878;30531;36680;44309.33535996898;46357;40604.59395406008;52038.219426707874;58023;60428;61598;10772.515236814519;11170.553177872795;11276.041536469163;11345.149113816662;11711.297322160697;13191.20454151062;13455.259960702255;13697.931510342385;13858.099223550436;13858.584242472742;11565.421728913689;12127.696807579528;12391.62825115248;12469.5012199511;12620.36898155107;11514.88485115138;11763.47064382122;12049.578264760692;12532.37338133108;12555.435005424595;12452.875471245172;12615;12847.871521284667;12947.676308092374;13335.133216340906;10753.73115672083;10971.286866353661;10996.560137594726;11086.778264434828;11318.830217546909;9543.313536760055;10710.965846052342;11045.723856903642;11122.388268645302;11416.429178540873 15:21:32 INFO - PROCESS | 971 | __end_tp_report 15:21:32 INFO - PROCESS | 971 | __start_cc_report 15:21:32 INFO - PROCESS | 971 | _x_x_mozilla_cycle_collect,1060 15:21:32 INFO - PROCESS | 971 | __end_cc_report 15:21:32 INFO - PROCESS | 971 | __startTimestamp1468362092120__endTimestamp 15:21:32 INFO - PROCESS | 971 | 15:21:32 INFO - PROCESS | 971 | ------- Summary: start ------- 15:21:32 INFO - PROCESS | 971 | Number of tests: 6 15:21:32 INFO - PROCESS | 971 | 15:21:32 INFO - PROCESS | 971 | [#0] dojo.html Cycles:165 Average:4718.10 Median:4857.83 stddev:496.79 (10.2%) stddev-sans-first:470.86 15:21:32 INFO - PROCESS | 971 | Values: 2642.0 2645.8 2684.0 2685.0 2685.2 4606.9 4680.6 4729.7 4759.0 4828.1 4681.4 4741.9 4748.5 4749.0 4763.3 4386.2 4391.0 4406.3 4430.7 4490.1 4385.1 4467.4 4481.6 4483.4 4507.4 4427.7 4489.6 4518.1 4532.3 4536.8 4268.8 4297.7 4313.0 4325.2 4436.4 4317.7 4342.4 4351.5 4362.0 4380.3 4818.1 4835.6 4857.8 4862.5 4880.2 4860.9 5000.0 5025.5 5075.1 5192.6 5131.6 5136.4 5144.7 5147.2 5155.5 4785.3 4820.3 4820.7 4821.5 4822.5 5078.0 5173.1 5240.2 5255.9 5329.0 5083.2 5094.1 5100.1 5105.6 5117.7 4974.0 5139.3 5143.6 5149.6 5185.3 4980.2 5049.7 5062.0 5069.3 5139.5 5030.7 5044.6 5050.1 5052.8 5102.1 4194.2 4283.6 4297.3 4297.4 4343.9 3708.5 3727.2 3762.0 3775.2 3796.8 4351.9 4361.5 4371.1 4375.4 4422.7 5092.8 5098.5 5099.9 5103.4 5145.8 4248.1 4387.2 4402.7 4441.9 4453.4 4622.0 4629.2 4634.0 4634.9 4659.7 4629.7 4853.9 4866.0 4868.2 4914.5 4952.1 4980.5 4981.0 4982.8 4984.8 4666.3 4726.8 4733.1 4740.5 4770.9 4920.2 4924.3 4980.2 5019.8 5021.5 5148.1 5180.4 5199.5 5200.6 5251.3 5043.4 5048.4 5053.5 5056.7 5107.2 4801.1 4919.9 4949.5 5056.3 5065.2 4906.6 4946.5 4962.4 4973.7 4981.3 5026.1 5035.3 5039.4 5044.3 5083.2 4738.0 4817.1 4861.9 4915.7 4931.4 15:21:32 INFO - PROCESS | 971 | 15:21:32 INFO - PROCESS | 971 | [#1] ext.html Cycles:180 Average:48925.68 Median:37214.63 stddev:95445.87 (256.5%) stddev-sans-first:95644.56 15:21:32 INFO - PROCESS | 971 | Values: 568.8 576.3 578.9 582.6 583.4 27401.9 40493.0 41627.8 41972.8 45809.8 41510.2 41913.4 41920.0 41960.8 41976.8 7807.8 7921.8 7947.2 7948.6 7952.2 7731.6 7808.9 7823.6 7826.2 7827.6 7838.4 7842.1 7875.4 7920.5 7923.2 7730.9 7775.2 7783.3 7805.8 7857.0 7625.8 7683.9 7689.7 7697.6 7697.8 39472.4 39761.4 39828.2 39829.0 39837.8 3059.4 3072.7 3072.9 3083.5 3362.6 29811.1 30078.2 30474.1 30523.4 30806.5 3352.8 3425.3 3477.0 3503.8 3608.4 174209.0 176157.0 176679.1 176915.0 177367.1 32021.4 55564.7 55934.4 56348.7 56447.4 33116.5 34651.3 34785.0 34816.0 34864.0 13215.7 13382.7 13395.4 13399.5 13431.1 10913.8 11008.4 11034.8 11037.7 11039.8 7935.9 8057.5 8067.5 8114.3 8128.3 3079.8 3080.8 3096.4 3108.6 3140.2 867.0 871.7 888.9 935.5 956.1 28603.3 28696.9 28993.4 29018.0 29585.6 972.4 1017.3 1032.0 1034.9 1041.8 14963.4 15350.2 15434.4 15496.4 15639.8 439140.0 444382.0 444569.0 445714.0 445903.3 49749.8 50272.5 50340.5 50425.0 50457.7 1081.5 1172.9 1192.7 1199.4 1205.4 28863.6 29578.3 29924.4 30330.7 30466.0 42299.4 47092.8 48635.3 48672.0 52893.7 395303.0 395610.0 397768.0 398027.0 399701.0 31530.8 37133.3 38172.6 38783.4 39298.4 42203.6 42366.8 42853.6 43206.6 43422.8 36239.3 36766.0 36902.3 37102.4 37334.8 36546.5 36677.6 36898.3 37177.3 37252.0 35888.5 36296.0 36466.6 36712.8 37037.1 31542.7 33186.7 33350.5 33480.0 33797.7 26217.0 26276.6 26354.3 26366.0 26608.7 15:21:32 INFO - PROCESS | 971 | 15:21:32 INFO - PROCESS | 971 | [#2] jquery.html Cycles:180 Average:25527.66 Median:417824.00 stddev:68191.66 (16.3%) stddev-sans-first:68356.73 15:21:32 INFO - PROCESS | 971 | Values: 340.2 365.8 367.2 369.7 382.9 12831.1 16139.6 16839.9 18574.0 18611.3 17095.5 17531.1 17761.1 17763.6 17800.2 5121.8 5157.0 5252.3 5268.3 5287.6 3723.6 5224.9 5776.9 6110.1 6125.2 6095.1 6161.1 6184.0 6190.3 6199.1 6140.8 6244.5 6252.1 6259.5 6263.0 3369.8 5026.9 5344.9 5347.9 5357.9 21119.7 21791.2 21799.1 21940.9 21947.2 2021.3 2093.0 2163.1 2295.7 2389.7 13092.4 13262.1 13377.8 13385.7 13709.9 1735.1 1747.0 1750.0 1759.6 1807.1 69234.3 69421.3 70516.0 71725.6 72518.0 8476.3 9104.8 9150.2 9317.8 9356.2 12113.9 13061.6 13099.7 13529.3 13571.7 6946.0 7237.3 7298.2 7303.2 7486.6 6771.0 6916.4 6981.9 7047.8 7197.5 6035.3 6106.3 6210.9 6228.2 6242.4 1758.6 1782.9 1804.4 1818.7 1831.4 883.9 918.0 930.5 939.7 945.8 16892.2 17136.7 17734.8 18203.4 18987.8 873.5 875.3 881.1 894.1 926.4 9599.1 9886.0 10125.9 10128.0 10176.7 404272.0 406541.0 416009.0 417026.0 418622.0 84752.0 84941.6 87093.6 90739.0 91173.5 899.2 926.2 943.7 945.5 946.0 15528.2 15664.0 16038.5 16216.7 16383.3 25848.5 34477.5 36317.6 38612.0 38973.6 46037.8 50448.7 52387.7 53000.5 53513.0 7429.5 7459.5 7678.8 7699.0 7737.4 9727.7 11251.8 12583.7 12722.7 12989.4 10615.3 10711.0 10777.6 10879.7 11287.5 10229.3 10581.6 10679.6 10835.3 11164.9 9993.5 10129.5 10240.0 10569.9 11173.8 7967.6 8066.0 8100.3 8126.4 8148.5 9239.9 10131.6 10501.7 10800.5 10884.5 15:21:32 INFO - PROCESS | 971 | 15:21:32 INFO - PROCESS | 971 | [#3] mootools.html Cycles:180 Average:4944.95 Median:6044.55 stddev:2689.04 (44.5%) stddev-sans-first:2683.09 15:21:32 INFO - PROCESS | 971 | Values: 1359.8 1410.6 1469.2 1660.9 1675.8 7058.9 7457.3 7511.6 7517.3 7523.1 6252.6 7036.1 7322.0 7327.3 7365.6 2167.1 2192.1 2195.6 2196.3 2198.2 1789.1 2193.1 2199.2 2235.1 2238.1 2118.0 2183.1 2189.7 2197.9 2199.7 2207.3 2225.3 2233.6 2236.3 2241.1 2118.0 2119.6 2122.8 2124.5 2126.1 7510.8 7548.6 7555.7 7559.2 7564.5 4359.5 4434.2 4481.8 4482.8 4488.1 2750.3 2778.2 2876.1 2882.2 2891.5 865.1 865.9 867.1 876.9 899.9 9817.9 9819.5 9839.4 9858.9 9886.5 4003.5 4093.0 4219.1 4240.6 4242.4 8450.7 8482.3 8482.7 8487.4 8489.3 4419.3 4456.4 4460.5 4470.6 4473.5 3990.8 4046.1 4049.5 4054.4 4055.0 3530.4 3543.2 3615.1 3620.7 3629.5 1006.9 1156.3 1167.9 1174.4 1186.2 756.4 776.8 815.9 828.5 831.3 5033.4 5052.2 5114.8 5135.7 5141.1 835.8 840.0 853.5 881.2 887.6 3547.6 3585.8 3606.6 3656.2 3683.3 8816.3 9242.2 9316.4 9317.0 9317.3 9217.0 9220.0 9232.4 9233.0 9249.1 5979.7 6676.1 6942.0 6942.9 7104.2 5203.8 5268.2 5298.2 5405.1 5442.7 8334.7 8516.9 8520.8 8541.0 8542.4 8549.0 8579.0 8595.0 8607.1 8662.4 6316.6 6321.7 6432.4 6530.9 6536.4 7019.0 7028.2 7056.9 7061.3 7081.5 6417.3 6531.2 6557.9 6655.3 6666.0 6219.6 6487.4 6494.6 6510.8 6571.1 6109.4 6258.9 6283.7 6284.2 6290.4 4445.6 4487.3 4488.8 4591.5 4635.5 4449.3 4503.2 4520.7 4607.9 4649.7 15:21:32 INFO - PROCESS | 971 | 15:21:32 INFO - PROCESS | 971 | [#4] prototype.html Cycles:180 Average:4217.53 Median:4833.42 stddev:1208.43 (25.0%) stddev-sans-first:1175.44 15:21:32 INFO - PROCESS | 971 | Values: 296.6 300.1 300.2 306.5 306.8 4862.6 4867.3 4880.2 4897.8 4911.9 4741.2 4777.5 4825.3 4841.5 4873.9 3562.3 3709.8 3720.8 3817.5 3842.7 3786.6 3809.6 3826.4 3829.7 3838.3 3581.4 3788.4 3794.5 3794.7 3796.1 3644.8 3771.3 3773.9 3785.4 3796.2 3381.6 3382.6 3389.8 3393.2 3403.0 4750.1 4754.2 4755.0 4758.5 4769.7 4282.9 4333.9 4340.3 4364.3 4389.5 4982.6 5000.1 5000.7 5016.5 5019.9 3701.6 3705.4 3712.8 3729.3 3730.1 5131.7 5229.3 5258.8 5301.9 5304.8 3781.1 3790.8 3799.3 3802.4 3831.3 3865.1 3873.7 3875.8 3878.9 3883.4 4316.6 4342.1 4353.2 4354.0 4361.8 4820.0 4973.5 4981.3 5004.9 5009.4 1316.3 1319.4 1321.9 1326.1 1326.7 855.5 857.8 887.9 888.4 890.5 4341.3 4375.8 4476.6 4521.2 4529.9 5153.7 5166.4 5169.8 5175.9 5184.4 4300.9 4462.7 4464.2 4483.7 4613.5 2764.0 2764.8 2768.8 2786.7 2805.9 4896.1 4927.9 4940.3 4940.5 4953.2 5062.8 5346.0 5351.1 5352.1 5358.7 4928.5 4942.4 4943.4 4947.5 4948.7 5202.3 5205.0 5224.1 5226.2 5265.8 4855.2 4999.9 5017.4 5020.2 5085.2 4875.8 4878.0 4896.7 4897.9 4903.0 3671.4 3675.3 3685.4 3688.8 3701.1 5152.9 5181.1 5219.6 5220.2 5296.9 5068.9 5084.9 5085.2 5085.9 5093.4 5059.8 5090.8 5102.4 5121.8 5123.0 4689.4 4727.7 4982.7 4986.3 5058.1 4949.4 5071.1 5072.3 5073.3 5084.1 4941.4 4975.1 4982.6 5013.8 5013.9 15:21:32 INFO - PROCESS | 971 | 15:21:32 INFO - PROCESS | 971 | [#5] yui.html Cycles:180 Average:17180.44 Median:4274.75 stddev:23700.77 (554.4%) stddev-sans-first:23734.74 15:21:32 INFO - PROCESS | 971 | Values: 647.1 674.1 689.2 698.4 756.5 12508.7 13357.3 13358.4 13362.1 13382.3 12300.3 12397.6 12447.9 12875.1 13003.4 784.3 801.3 802.6 802.7 804.1 610.3 768.5 802.4 806.5 807.2 839.6 840.0 841.0 841.7 842.1 638.3 831.9 832.5 832.7 833.4 591.9 608.9 609.9 612.2 612.7 13541.8 13551.2 13566.9 13570.9 13601.0 12486.4 13012.6 13124.2 13143.9 13485.9 12044.3 12298.3 12298.8 12336.2 12461.9 3268.1 3304.7 3379.9 4261.8 4395.3 64943.8 65233.0 65445.0 65871.3 66297.7 11133.2 11149.2 11158.4 11164.9 11168.1 19976.3 20023.2 20046.2 20059.4 20397.0 6963.1 7067.8 7094.4 7146.6 7188.5 3689.1 3778.1 3786.8 3796.8 3807.7 2644.6 2737.6 2740.4 2865.1 2867.5 489.9 494.4 501.1 502.5 503.9 594.2 596.6 599.4 612.5 620.8 8637.6 8693.9 8696.1 8709.3 8858.6 581.2 591.3 602.9 628.1 639.0 4069.7 4210.3 4262.1 4269.2 4280.3 90043.0 93712.0 96310.0 96339.5 96912.0 48996.8 50173.7 67651.0 79639.6 82175.6 71813.0 77170.6 77438.6 77715.0 77720.6 7253.8 7678.4 7902.9 8014.3 8203.9 29878.0 30531.0 36680.0 44309.3 46357.0 40604.6 52038.2 58023.0 60428.0 61598.0 10772.5 11170.6 11276.0 11345.1 11711.3 13191.2 13455.3 13697.9 13858.1 13858.6 11565.4 12127.7 12391.6 12469.5 12620.4 11514.9 11763.5 12049.6 12532.4 12555.4 12452.9 12615.0 12847.9 12947.7 13335.1 10753.7 10971.3 10996.6 11086.8 11318.8 9543.3 10711.0 11045.7 11122.4 11416.4 15:21:32 INFO - PROCESS | 971 | -------- Summary: end -------- 15:21:32 INFO - PROCESS | 971 | 15:21:32 INFO - TEST-INFO | 971: exit 0 15:21:32 INFO - TEST-OK | dromaeo_css | took 1120267ms 15:21:32 INFO - TEST-START | kraken 15:21:32 INFO - operating with platform_type : linux_ 15:21:32 INFO - Initialising browser for kraken test... 15:21:32 INFO - TEST-INFO | started process 1247 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptKWPs0/profile http://localhost:56948/getInfo.html) 15:21:37 INFO - PROCESS | 1247 | __metrics Screen width/height:1600/1200 15:21:37 INFO - PROCESS | 1247 | colorDepth:24 15:21:37 INFO - PROCESS | 1247 | Browser inner width/height: 1024/697 15:21:37 INFO - PROCESS | 1247 | __metrics 15:21:38 INFO - PROCESS | 1247 | 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" 15:21:38 INFO - PROCESS | 1247 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:21:39 INFO - TEST-INFO | 1247: exit 0 15:21:39 INFO - Browser initialized. 15:21:39 INFO - Running cycle 1/1 for kraken test... 15:21:39 INFO - TEST-INFO | started process 1333 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptKWPs0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:21:51 INFO - PROCESS | 1333 | RSS: Main: 158789632 15:21:51 INFO - PROCESS | 1333 | 15:21:53 INFO - PROCESS | 1333 | Cycle 1(1): loaded http://localhost:56948/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56948/tests/kraken/driver.html?testName=audio-beat-detection) 15:21:54 INFO - PROCESS | 1333 | RSS: Main: 217296896 15:21:54 INFO - PROCESS | 1333 | 15:21:57 INFO - PROCESS | 1333 | Cycle 1(1): loaded http://localhost:56948/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56948/tests/kraken/driver.html?testName=audio-dft) 15:21:58 INFO - PROCESS | 1333 | RSS: Main: 204038144 15:21:58 INFO - PROCESS | 1333 | 15:22:01 INFO - PROCESS | 1333 | Cycle 1(1): loaded http://localhost:56948/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56948/tests/kraken/driver.html?testName=audio-fft) 15:22:01 INFO - PROCESS | 1333 | RSS: Main: 271028224 15:22:01 INFO - PROCESS | 1333 | 15:22:04 INFO - PROCESS | 1333 | Cycle 1(1): loaded http://localhost:56948/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56948/tests/kraken/driver.html?testName=audio-oscillator) 15:22:04 INFO - PROCESS | 1333 | RSS: Main: 181989376 15:22:04 INFO - PROCESS | 1333 | 15:22:06 INFO - PROCESS | 1333 | Cycle 1(1): loaded http://localhost:56948/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56948/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:22:07 INFO - PROCESS | 1333 | RSS: Main: 185778176 15:22:07 INFO - PROCESS | 1333 | 15:22:10 INFO - PROCESS | 1333 | Cycle 1(1): loaded http://localhost:56948/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56948/tests/kraken/driver.html?testName=imaging-darkroom) 15:22:11 INFO - PROCESS | 1333 | RSS: Main: 221982720 15:22:11 INFO - PROCESS | 1333 | 15:22:14 INFO - PROCESS | 1333 | Cycle 1(1): loaded http://localhost:56948/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56948/tests/kraken/driver.html?testName=imaging-desaturate) 15:22:15 INFO - PROCESS | 1333 | RSS: Main: 217919488 15:22:15 INFO - PROCESS | 1333 | 15:22:18 INFO - PROCESS | 1333 | Cycle 1(1): loaded http://localhost:56948/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56948/tests/kraken/driver.html?testName=json-parse-financial) 15:22:19 INFO - PROCESS | 1333 | RSS: Main: 220561408 15:22:19 INFO - PROCESS | 1333 | 15:22:20 INFO - PROCESS | 1333 | Cycle 1(1): loaded http://localhost:56948/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56948/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:22:21 INFO - PROCESS | 1333 | RSS: Main: 206635008 15:22:21 INFO - PROCESS | 1333 | 15:22:23 INFO - PROCESS | 1333 | Cycle 1(1): loaded http://localhost:56948/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56948/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:22:23 INFO - PROCESS | 1333 | RSS: Main: 211202048 15:22:23 INFO - PROCESS | 1333 | 15:22:25 INFO - PROCESS | 1333 | Cycle 1(1): loaded http://localhost:56948/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56948/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:22:26 INFO - PROCESS | 1333 | RSS: Main: 208654336 15:22:26 INFO - PROCESS | 1333 | 15:22:29 INFO - PROCESS | 1333 | Cycle 1(1): loaded http://localhost:56948/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56948/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:22:30 INFO - PROCESS | 1333 | RSS: Main: 222801920 15:22:30 INFO - PROCESS | 1333 | 15:22:32 INFO - PROCESS | 1333 | Cycle 1(1): loaded http://localhost:56948/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56948/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:22:33 INFO - PROCESS | 1333 | RSS: Main: 223809536 15:22:33 INFO - PROCESS | 1333 | 15:22:35 INFO - PROCESS | 1333 | Cycle 1(1): loaded http://localhost:56948/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56948/tests/kraken/driver.html?testName=ai-astar) 15:22:35 INFO - PROCESS | 1333 | RSS: Main: 265269248 15:22:35 INFO - PROCESS | 1333 | 15:22:35 INFO - PROCESS | 1333 | __start_tp_report 15:22:35 INFO - PROCESS | 1333 | _x_x_mozilla_page_load 15:22:35 INFO - PROCESS | 1333 | _x_x_mozilla_page_load_details 15:22:35 INFO - PROCESS | 1333 | |i|pagename|runs| 15:22:35 INFO - PROCESS | 1333 | |0;ai-astar;104;114;115;114;115;113;113;113;113;113 15:22:35 INFO - PROCESS | 1333 | |1;audio-beat-detection;152;126;122;170;124;127;144;130;159;121 15:22:35 INFO - PROCESS | 1333 | |2;audio-dft;162;161;161;161;161;163;161;163;161;162 15:22:35 INFO - PROCESS | 1333 | |3;audio-fft;76;77;78;130;76;78;74;75;98;75 15:22:35 INFO - PROCESS | 1333 | |4;audio-oscillator;85;84;118;85;85;85;130;85;86;128 15:22:35 INFO - PROCESS | 1333 | |5;imaging-gaussian-blur;117;118;117;117;116;117;117;118;116;116 15:22:35 INFO - PROCESS | 1333 | |6;imaging-darkroom;146;147;146;146;145;145;146;146;147;146 15:22:35 INFO - PROCESS | 1333 | |7;imaging-desaturate;94;95;95;95;95;95;94;95;94;95 15:22:35 INFO - PROCESS | 1333 | |8;json-parse-financial;64;64;64;64;64;64;63;65;65;64 15:22:35 INFO - PROCESS | 1333 | |9;json-stringify-tinderbox;64;65;64;64;64;64;65;64;65;65 15:22:35 INFO - PROCESS | 1333 | |10;stanford-crypto-aes;80;80;79;77;78;79;81;79;81;78 15:22:35 INFO - PROCESS | 1333 | |11;stanford-crypto-ccm;146;128;128;127;128;131;130;131;182;140 15:22:35 INFO - PROCESS | 1333 | |12;stanford-crypto-pbkdf2;174;170;171;173;168;169;174;166;175;173 15:22:35 INFO - PROCESS | 1333 | |13;stanford-crypto-sha256-iterative;68;67;72;74;67;67;66;67;68;70 15:22:35 INFO - PROCESS | 1333 | __end_tp_report 15:22:35 INFO - PROCESS | 1333 | __start_cc_report 15:22:35 INFO - PROCESS | 1333 | _x_x_mozilla_cycle_collect,1959 15:22:35 INFO - PROCESS | 1333 | __end_cc_report 15:22:35 INFO - PROCESS | 1333 | __startTimestamp1468362155587__endTimestamp 15:22:35 INFO - PROCESS | 1333 | 15:22:35 INFO - PROCESS | 1333 | ------- Summary: start ------- 15:22:35 INFO - PROCESS | 1333 | Number of tests: 14 15:22:35 INFO - PROCESS | 1333 | 15:22:35 INFO - PROCESS | 1333 | [#0] ai-astar Cycles:10 Average:112.70 Median:113.50 stddev:3.16 (2.8%) stddev-sans-first:0.87 15:22:35 INFO - PROCESS | 1333 | Values: 104.0 114.0 115.0 114.0 115.0 113.0 113.0 113.0 113.0 113.0 15:22:35 INFO - PROCESS | 1333 | 15:22:35 INFO - PROCESS | 1333 | [#1] audio-beat-detection Cycles:10 Average:137.50 Median:137.00 stddev:17.53 (12.8%) stddev-sans-first:17.79 15:22:35 INFO - PROCESS | 1333 | Values: 152.0 126.0 122.0 170.0 124.0 127.0 144.0 130.0 159.0 121.0 15:22:35 INFO - PROCESS | 1333 | 15:22:35 INFO - PROCESS | 1333 | [#2] audio-dft Cycles:10 Average:161.60 Median:161.50 stddev:0.84 (0.5%) stddev-sans-first:0.88 15:22:35 INFO - PROCESS | 1333 | Values: 162.0 161.0 161.0 161.0 161.0 163.0 161.0 163.0 161.0 162.0 15:22:35 INFO - PROCESS | 1333 | 15:22:35 INFO - PROCESS | 1333 | [#3] audio-fft Cycles:10 Average:83.70 Median:76.50 stddev:17.71 (23.1%) stddev-sans-first:18.56 15:22:35 INFO - PROCESS | 1333 | Values: 76.0 77.0 78.0 130.0 76.0 78.0 74.0 75.0 98.0 75.0 15:22:35 INFO - PROCESS | 1333 | 15:22:35 INFO - PROCESS | 1333 | [#4] audio-oscillator Cycles:10 Average:97.10 Median:85.00 stddev:19.72 (23.2%) stddev-sans-first:20.43 15:22:35 INFO - PROCESS | 1333 | Values: 85.0 84.0 118.0 85.0 85.0 85.0 130.0 85.0 86.0 128.0 15:22:35 INFO - PROCESS | 1333 | 15:22:35 INFO - PROCESS | 1333 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.74 (0.6%) stddev-sans-first:0.78 15:22:35 INFO - PROCESS | 1333 | Values: 117.0 118.0 117.0 117.0 116.0 117.0 117.0 118.0 116.0 116.0 15:22:35 INFO - PROCESS | 1333 | 15:22:35 INFO - PROCESS | 1333 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 15:22:35 INFO - PROCESS | 1333 | Values: 146.0 147.0 146.0 146.0 145.0 145.0 146.0 146.0 147.0 146.0 15:22:35 INFO - PROCESS | 1333 | 15:22:35 INFO - PROCESS | 1333 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 15:22:35 INFO - PROCESS | 1333 | Values: 94.0 95.0 95.0 95.0 95.0 95.0 94.0 95.0 94.0 95.0 15:22:35 INFO - PROCESS | 1333 | 15:22:35 INFO - PROCESS | 1333 | [#8] json-parse-financial Cycles:10 Average:64.10 Median:64.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 15:22:35 INFO - PROCESS | 1333 | Values: 64.0 64.0 64.0 64.0 64.0 64.0 63.0 65.0 65.0 64.0 15:22:35 INFO - PROCESS | 1333 | 15:22:35 INFO - PROCESS | 1333 | [#9] json-stringify-tinderbox Cycles:10 Average:64.40 Median:64.50 stddev:0.52 (0.8%) stddev-sans-first:0.53 15:22:35 INFO - PROCESS | 1333 | Values: 64.0 65.0 64.0 64.0 64.0 64.0 65.0 64.0 65.0 65.0 15:22:35 INFO - PROCESS | 1333 | 15:22:35 INFO - PROCESS | 1333 | [#10] stanford-crypto-aes Cycles:10 Average:79.20 Median:79.50 stddev:1.32 (1.7%) stddev-sans-first:1.36 15:22:35 INFO - PROCESS | 1333 | Values: 80.0 80.0 79.0 77.0 78.0 79.0 81.0 79.0 81.0 78.0 15:22:35 INFO - PROCESS | 1333 | 15:22:35 INFO - PROCESS | 1333 | [#11] stanford-crypto-ccm Cycles:10 Average:137.10 Median:131.00 stddev:16.93 (12.9%) stddev-sans-first:17.65 15:22:35 INFO - PROCESS | 1333 | Values: 146.0 128.0 128.0 127.0 128.0 131.0 130.0 131.0 182.0 140.0 15:22:35 INFO - PROCESS | 1333 | 15:22:35 INFO - PROCESS | 1333 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.30 Median:173.00 stddev:2.98 (1.7%) stddev-sans-first:3.00 15:22:35 INFO - PROCESS | 1333 | Values: 174.0 170.0 171.0 173.0 168.0 169.0 174.0 166.0 175.0 173.0 15:22:35 INFO - PROCESS | 1333 | 15:22:35 INFO - PROCESS | 1333 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.60 Median:68.00 stddev:2.59 (3.8%) stddev-sans-first:2.74 15:22:35 INFO - PROCESS | 1333 | Values: 68.0 67.0 72.0 74.0 67.0 67.0 66.0 67.0 68.0 70.0 15:22:35 INFO - PROCESS | 1333 | -------- Summary: end -------- 15:22:35 INFO - PROCESS | 1333 | 15:22:36 INFO - TEST-INFO | 1333: exit 0 15:22:36 INFO - TEST-OK | kraken | took 63730ms 15:22:36 INFO - SUITE-END | took 1183s 15:22:36 INFO - Completed test suite (00:19:44) 15:22:36 INFO - javascript benchmark 15:22:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2641.973580264197, 2645.828021178622, 2684.0471632570425, 2684.986575067124, 2685.154176434423, 4606.930896036562, 4680.5787479126875, 4729.668923175379, 4758.976205118978, 4828.058528586923, 4681.368015317931, 4741.881452963669, 4748.453927798304, 4748.976255118729, 4763.285507173926, 4386.210482113226, 4391.01202229499, 4406.272964960777, 4430.667699922518, 4490.1019796040755, 4385.101054283864, 4467.441569803775, 4481.596656300932, 4483.439570053743, 4507.4365704286965, 4427.667924905645, 4489.618382437497, 4518.051209246046, 4532.342810292505, 4536.841210557631, 4268.786560671954, 4297.656187504992, 4312.978435107804, 4325.243082460588, 4436.401085853433, 4317.69776115672, 4342.392065110887, 4351.543087975749, 4362.018545827195, 4380.255356589388, 4818.084563932876, 4835.6131509479155, 4857.8299759508245, 4862.513748625144, 4880.170371036932, 4860.854174374775, 4999.9500004999845, 5025.472325405853, 5075.0611136939415, 5192.558632516203, 5131.640785145041, 5136.383633963911, 5144.6655967362, 5147.176451767699, 5155.53600175986, 4785.3300537924715, 4820.325154478376, 4820.68665536739, 4821.4696383397795, 4822.493638168012, 5078.0, 5173.1464308388695, 5240.213967904845, 5255.921161182585, 5329.040772660881, 5083.2375143727995, 5094.133997220407, 5100.132977393852, 5105.591552675777, 5117.744112794346, 4973.975130124326, 5139.306193663882, 5143.639945203875, 5149.588032957355, 5185.274061631375, 4980.228064649985, 5049.722265275445, 5061.974690126526, 5069.31564238823, 5139.511746384088, 5030.748462576857, 5044.646874718808, 5050.141475949097, 5052.8484145475695, 5102.132637451642, 4194.2450358935685, 4283.571642835692, 4297.269464191095, 4297.376880352332, 4343.891402714958, 3708.4622729704042, 3727.1613886875234, 3762.0218743126984, 3775.2260786538695, 3796.772193668389, 4351.891202719894, 4361.498427680857, 4371.103923695635, 4375.431193944767, 4422.690411671152, 5092.821751238689, 5098.5411312982005, 5099.898002039939, 5103.413107492686, 5145.76844042019, 4248.129133527619, 4387.166438376698, 4402.691811573223, 4441.933370999438, 4453.398791163217, 4621.976890115528, 4629.166749984967, 4634.026854360622, 4634.907301853944, 4659.743714095689, 4629.722216667011, 4853.9029219415415, 4866.0, 4868.196747536616, 4914.508549145128, 4952.059108769467, 4980.526849949249, 4980.975095124501, 4982.775775090202, 4984.800607975785, 4666.323383109498, 4726.787294571684, 4733.124371991193, 4740.454847692422, 4770.928436073392, 4920.21276595743, 4924.285978533164, 4980.203167493184, 5019.824306149268, 5021.472745361831, 5148.073346797612, 5180.404253510895, 5199.48005199477, 5200.63595548308, 5251.291075704731, 5043.44522102561, 5048.368953880765, 5053.469385714447, 5056.721880296619, 5107.157319042316, 4801.063792560417, 4919.901601967869, 4949.455559888482, 5056.317397151338, 5065.189569668835, 4906.63200259989, 4946.480619534897, 4962.354893863775, 4973.701577905337, 4981.277714731271, 5026.070177017264, 5035.295058691713, 5039.445660977216, 5044.344235249394, 5083.186690129562, 4738.028704115717, 4817.108834865561, 4861.878453038702, 4915.705057696549, 4931.408231012301], "value": 4686.28857788713, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [568.8094488346404, 576.3055518100689, 578.8813293274877, 582.6474982635506, 583.4398976982097, 27401.862990685044, 40493.0, 41627.79186104069, 41972.790136049276, 45809.77095114528, 41510.169796604125, 41913.37129943053, 41920.0, 41960.790196049056, 41976.79011604938, 7807.804804879866, 7921.762347129596, 7947.245011723906, 7948.602569871512, 7952.244536768998, 7731.574763388011, 7808.882866757003, 7823.608819559027, 7826.217378262185, 7827.569483678395, 7838.372930165601, 7842.137364889886, 7875.369970402354, 7920.48516846406, 7923.247291507298, 7730.922690773076, 7775.222477752236, 7783.299503044725, 7805.804854878616, 7856.960715196387, 7625.771226863148, 7683.884741728879, 7689.653965571507, 7697.576633285167, 7697.807554811118, 39472.40791388103, 39761.40357894634, 39828.20343593112, 39829.0, 39837.80081099576, 3059.4034163338156, 3072.6620071792086, 3072.9231769205726, 3083.5066389377594, 3362.5964884213804, 29811.10566683003, 30078.24804379886, 30474.085777426713, 30523.389532209232, 30806.53790193149, 3352.8323583820957, 3425.2806910548816, 3476.9652303476896, 3503.8248087595516, 3608.440691692792, 174209.0, 176157.0, 176679.11660441745, 176915.0, 177367.11316443465, 32021.359572808415, 55564.72217638886, 55934.44065559374, 56348.71825640846, 56447.435525645036, 33116.503252451235, 34651.30697386038, 34785.0, 34816.0, 34864.0, 13215.66960825987, 13382.732345353234, 13395.397207125508, 13399.53101641438, 13431.12697674648, 10913.83629245562, 11008.449577521093, 11034.834477482844, 11037.668869933916, 11039.83440248397, 7935.880961785578, 8057.5165490070785, 8067.475614085066, 8114.3102836258395, 8128.309093727099, 3079.784415090967, 3080.753539716862, 3096.427160975227, 3108.564800927872, 3140.2463408781728, 866.9739907802776, 871.6600525794945, 888.8577827547562, 935.4808081514689, 956.0630582029511, 28603.28491787722, 28696.85651571729, 28993.42013159725, 29018.0, 29585.55621665677, 972.4214092614883, 1017.2624846985876, 1031.9742006449749, 1034.896510348959, 1041.8124737547314, 14963.40146394132, 15350.155741434328, 15434.38262469511, 15496.380144794308, 15639.843601563838, 439140.0, 444382.0, 444569.0, 445714.0, 445903.3114503285, 49749.75125124351, 50272.497275026784, 50340.49659503431, 50425.0, 50457.74771126121, 1081.4592703648175, 1172.876847930972, 1192.6600918738118, 1199.400299850075, 1205.3852535207106, 28863.5670464939, 29578.260543486584, 29924.401511970074, 30330.696693032787, 30466.0, 42299.365509518, 47092.7645361771, 48635.27047094367, 48672.0, 52893.7355313221, 395303.0, 395610.0, 397768.0, 398027.0, 399701.0, 31530.842345788125, 37133.25733485369, 38172.618273818014, 38783.41824872573, 39298.410523841594, 42203.57796421996, 42366.78816606021, 42853.57146428621, 43206.56793432026, 43422.78288608537, 36239.275214495035, 36766.0, 36902.261954760215, 37102.44346334861, 37334.813325933195, 36546.45180322351, 36677.63322366742, 36898.26203475969, 37177.25645487129, 37252.0, 35888.4616730744, 36296.0, 36466.635333647384, 36712.81643591872, 37037.07407314731, 31542.684573154893, 33186.66813331933, 33350.49974250437, 33480.0, 33797.66202337945, 26217.0, 26276.605850912634, 26354.341141471617, 26366.0, 26608.733912660624], "value": 16942.1965176927, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.2107111501318, 365.8079508258164, 367.15737382706686, 369.72270796902325, 382.85451528419196, 12831.101822872391, 16139.596510087238, 16839.915800420997, 18574.0, 18611.255549778027, 17095.48713538596, 17531.12344382779, 17761.111944402794, 17763.64472710545, 17800.219978002195, 5121.820736274231, 5157.045946499909, 5252.290940723011, 5268.262443257928, 5287.629865909388, 3723.646253605903, 5224.92162617561, 5776.855578610526, 6110.08348747687, 6125.203723515937, 6095.08573713942, 6161.137440758297, 6184.0, 6190.257169139687, 6199.101130336098, 6140.785072522485, 6244.531660125486, 6252.124702541648, 6259.467945224661, 6262.96868515659, 3369.79781213128, 5026.924596131061, 5344.919826202571, 5347.919781203285, 5357.946420535784, 21119.683204751942, 21791.23730669419, 21799.128034878588, 21940.89029554842, 21947.231846885283, 2021.2723419568943, 2093.005822234442, 2163.056588351378, 2295.653907496003, 2389.6535002424553, 13092.410841512155, 13262.071654984155, 13377.799333010013, 13385.66535836602, 13709.931450342785, 1735.1237624999294, 1746.956326091845, 1750.0, 1759.6392739488374, 1807.1145138881852, 69234.3076569233, 69421.30578694199, 70516.0, 71725.64137179281, 72518.0, 8476.321894248445, 9104.817903642022, 9150.176484116462, 9317.813643727088, 9356.157945784777, 12113.939430302793, 13061.608151755463, 13099.738005239842, 13529.255890926093, 13571.660708482366, 6946.0, 7237.312455316736, 7298.160711518137, 7303.196648368676, 7486.625668716542, 6771.0, 6916.377526022581, 6981.930180698128, 7047.823804404931, 7197.4601904857345, 6035.336113027563, 6106.297775755845, 6210.9068363974575, 6228.190335256388, 6242.438180563771, 1758.6131051168722, 1782.9019403932648, 1804.3684710351536, 1818.7180986947044, 1831.4414103698234, 883.8718385834018, 918.0131358789329, 930.4882314727008, 939.7321763297427, 945.7682867697458, 16892.239849206817, 17136.657266854592, 17734.822651773575, 18203.36288229932, 18987.810121898605, 873.5021038008401, 875.2647775868174, 881.1188811188811, 894.1103601916135, 926.3746970794687, 9599.136077752895, 9886.0, 10125.949370253102, 10128.0, 10176.694699159038, 404272.0, 406541.0, 416009.0, 417026.0, 418622.0, 84752.0, 84941.57529212438, 87093.56453217694, 90739.0, 91173.54413227891, 899.2266650680409, 926.18495723764, 943.698016634684, 945.5130607737021, 946.0303189231093, 15528.22358882074, 15664.0, 16038.518844434686, 16216.702663787104, 16383.344666213694, 25848.483030339663, 34477.482837757954, 36317.63682363142, 38612.034699132746, 38973.610263897, 46037.76981115073, 50448.747756260986, 52387.73806130945, 53000.46999530109, 53513.0, 7429.479936404616, 7459.477836551607, 7678.769636910902, 7699.037620297463, 7737.381009519112, 9727.708168754949, 11251.774964500828, 12583.68540786451, 12722.681932951751, 12989.4154763034, 10615.256932014685, 10711.0, 10777.568897244337, 10879.728006799893, 11287.492062857027, 10229.28395012342, 10581.576736930436, 10679.626213082505, 10835.295705779099, 11164.88835111671, 9993.502274203971, 10129.544170512198, 10240.027197416233, 10569.947150264199, 11173.776524469627, 7967.601619919097, 8066.032076150899, 8100.311473524581, 8126.390520710852, 8148.470349427268, 9239.888249729074, 10131.64539241123, 10501.684949451528, 10800.49755435189, 10884.510197040994], "value": 8566.458447453653, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1359.8028285898547, 1410.6402867268846, 1469.1625773309217, 1660.9003459792414, 1675.765392845001, 7058.894116588249, 7457.328840404362, 7511.586862722548, 7517.323440890319, 7523.13483949346, 6252.562320637558, 7036.085308909847, 7321.963390183042, 7327.26727327267, 7365.631718414084, 2167.0898222746446, 2192.068370942351, 2195.560887822442, 2196.3081629286803, 2198.1756841184556, 1789.1233295685151, 2193.122750899637, 2199.197292988056, 2235.1282999630075, 2238.0712004518105, 2117.9682304765442, 2183.072194317409, 2189.7481789594162, 2197.890105494727, 2199.692043113965, 2207.2716003718733, 2225.276785044859, 2233.564454931289, 2236.2955668964387, 2241.0587553227683, 2118.046878904499, 2119.586680597284, 2122.755883073451, 2124.5007423255524, 2126.096409026173, 7510.8122296942465, 7548.5848278344665, 7555.7355492558045, 7559.1684914659345, 7564.546127232328, 4359.498657654379, 4434.17967675981, 4481.753503557303, 4482.820687172509, 4488.05750792334, 2750.2986738381783, 2778.1526634376505, 2876.0652787844033, 2882.207392966951, 2891.4506243813607, 865.1089377940668, 865.8831057807179, 867.119873328572, 876.8728534362546, 899.9010108888017, 9817.852732209023, 9819.509024548815, 9839.409635421825, 9858.90141098587, 9886.456244906527, 4003.499562554681, 4093.0, 4219.07180420307, 4240.639545638655, 4242.384854196124, 8450.704225352083, 8482.279006284412, 8482.660693572312, 8487.363447741443, 8489.278411334983, 4419.292913133948, 4456.3538286948215, 4460.531644177379, 4470.552944705569, 4473.507914129443, 3990.780507072081, 4046.0896298333228, 4049.493813273341, 4054.3715724062818, 4055.0, 3530.435130379127, 3543.20277937462, 3615.1323682316056, 3620.6922411594783, 3629.473726309723, 1006.8540061691014, 1156.2599936040772, 1167.8948894599528, 1174.3893175548837, 1186.1815347410259, 756.3797685897621, 776.7902666280073, 815.9020917489939, 828.511178404732, 831.3266254333888, 5033.421156566968, 5052.242163675404, 5114.795408183705, 5135.6918584885025, 5141.100307446182, 835.8453686068096, 840.0465471689677, 853.5262929074423, 881.2377293641083, 887.6138879587401, 3547.5742910850863, 3585.8027808470265, 3606.6032736398975, 3656.1590834107774, 3683.318586061587, 8816.294696424271, 9242.21441177494, 9316.44101353921, 9317.021712720203, 9317.34778565494, 9217.032211617683, 9219.95390023059, 9232.353735238603, 9232.953835230916, 9249.075092490832, 5979.701014949235, 6676.065350850885, 6942.028116063755, 6942.895856562156, 7104.183018952886, 5203.8178663746585, 5268.1834315681135, 5298.2052692096495, 5405.081136206775, 5442.700651464049, 8334.708285209988, 8516.914830851612, 8520.829583408422, 8541.0, 8542.44474109181, 8548.957255213683, 8578.957105214684, 8595.0, 8607.139286071593, 8662.393632445668, 6316.589421687576, 6321.747130114744, 6432.3567643237175, 6530.869382612226, 6536.379043990813, 7019.0, 7028.191757947901, 7056.894146587908, 7061.329173728487, 7081.539699919489, 6417.26201486835, 6531.150950376421, 6557.868842623217, 6655.334466553306, 6666.0, 6219.626822390671, 6487.3836985486305, 6494.6103233806125, 6510.804675859732, 6571.112899758471, 6109.389061093855, 6258.937410625836, 6283.685815709105, 6284.24589049317, 6290.370962903673, 4445.599896009375, 4487.304467807495, 4488.820447182076, 4591.47198072226, 4635.466921304093, 4449.28811390176, 4503.211937910787, 4520.706154099973, 4607.861764147081, 4649.674522783373], "value": 4020.309467692686, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [296.5522061686853, 300.1460843899107, 300.2149379372939, 306.54018971542683, 306.7990466244609, 4862.61099112071, 4867.269909513566, 4880.243562247849, 4897.828575999841, 4911.901761964758, 4741.193997020506, 4777.498362671921, 4825.348577941985, 4841.491643377448, 4873.926891096637, 3562.2875424915055, 3709.814509274539, 3720.8325625346924, 3817.5186129016083, 3842.711796615251, 3786.602406747293, 3809.5999920008417, 3826.4260360945805, 3829.7127715421457, 3838.251540949516, 3581.3553560359123, 3788.3938569828833, 3794.5256842894637, 3794.6964242860645, 3796.1079146400575, 3644.835982380786, 3771.3400154972906, 3773.905652358713, 3785.432185172219, 3796.2217745362136, 3381.5797365106714, 3382.594088709346, 3389.847456864447, 3393.1686736749652, 3402.9659703402895, 4750.073735621555, 4754.168020596382, 4754.95245047552, 4758.500357462486, 4769.689970151963, 4282.8715138545895, 4333.934990975138, 4340.305551111838, 4364.323529852878, 4389.517153113155, 4982.551570358685, 5000.124978128849, 5000.74996250186, 5016.469976657118, 5019.874503137451, 3701.555813302421, 3705.370087085202, 3712.814359282052, 3729.291434627412, 3730.1420673245034, 5131.7434128293435, 5229.254641313341, 5258.763355648966, 5301.920471192935, 5304.787808487656, 3781.1303400218103, 3790.8294126764363, 3799.297130030953, 3802.3535998880257, 3831.310364134427, 3865.1496670732395, 3873.7288389812443, 3875.767453952772, 3878.886007126123, 3883.37865941448, 4316.611504964569, 4342.131573685276, 4353.172897149531, 4353.978230108829, 4361.760103194355, 4820.0, 4973.52751488608, 4981.327520784647, 5004.924926126111, 5009.44896061433, 1316.2628927800463, 1319.3798914510164, 1321.85459599444, 1326.0916272353468, 1326.6882282663566, 855.5422848775875, 857.8455877941906, 887.9378443508892, 888.4136469929815, 890.5369208011798, 4341.327094300388, 4375.78121093944, 4476.597106260387, 4521.186186486399, 4529.886752831206, 5153.6907785532985, 5166.3800343958965, 5169.7932082717025, 5175.887184255404, 5184.351956005499, 4300.8709738707885, 4462.709923854939, 4464.17412778637, 4483.663725220622, 4613.492515823256, 2764.032588594016, 2764.8064635475325, 2768.7646550043473, 2786.665600127957, 2805.9158225253277, 4896.094222568765, 4927.90144197114, 4940.308356830046, 4940.456549779521, 4953.23224900141, 5062.82280120194, 5346.0, 5351.117065684196, 5352.136606915282, 5358.651687640291, 4928.457869634337, 4942.4316203637045, 4943.3573635427165, 4947.54047555979, 4948.678335908155, 5202.297689811827, 5205.0, 5224.138017227115, 5226.216067589892, 5265.763040663256, 4855.247436647325, 4999.900001999867, 5017.448080711192, 5020.246962955586, 5085.211792172219, 4875.780589873393, 4877.975610121926, 4896.730679812645, 4897.853064408074, 4903.0, 3671.3758661027155, 3675.338439080991, 3685.428758542323, 3688.834002469842, 3701.11173318407, 5152.948470515247, 5181.145111056633, 5219.608529360237, 5220.1647736362, 5296.947030529799, 5068.859506610983, 5084.923726144037, 5085.160948443466, 5085.8728531787, 5093.388793344822, 5059.848204553869, 5090.796368145233, 5102.387713474258, 5121.769520371667, 5123.0, 4689.413823272091, 4727.7163370197895, 4982.701037937735, 4986.32684587576, 5058.1401161803315, 4949.406071271471, 5071.13790655582, 5072.315237443046, 5073.340465739578, 5084.135696931605, 4941.382327209099, 4975.10448119342, 4982.576480999156, 5013.824516142064, 5013.924791128209], "value": 3834.647247116393, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [647.1166857239867, 674.0731494195478, 689.1798759476226, 698.441247002398, 756.4704706705307, 12508.687282817922, 13357.33213339334, 13358.398872050757, 13362.13146145501, 13382.330883455837, 12300.323482208474, 12397.62807115788, 12447.875521244785, 12875.098743087987, 13003.414846331914, 784.3411534311177, 801.3028665061405, 802.6067227058758, 802.6789284286274, 804.1275216390223, 610.2920612089977, 768.5043147170072, 802.4062193976445, 806.5201205282847, 807.2008711375754, 839.5550358310105, 840.017179899516, 840.9873851892227, 841.6885752271668, 842.1115722912356, 638.3361304243581, 831.8793774902636, 832.4630613254477, 832.7168762620678, 833.4291010657688, 591.8549955260988, 608.8873558391712, 609.9481544068759, 612.2255346986066, 612.653850574424, 13541.796873046913, 13551.186928784304, 13566.864331356659, 13570.864291357058, 13600.979926505453, 12486.438110284967, 13012.60962171127, 13124.212547247196, 13143.86856131436, 13485.865141348657, 12044.277343359427, 12298.262104273772, 12298.754024919452, 12336.198147120498, 12461.93769031158, 3268.117608245784, 3304.653011433789, 3379.9324013519836, 4261.829526818923, 4395.274779661352, 64943.75281235919, 65233.0, 65445.0, 65871.341286587, 66297.66851165713, 11133.220674552866, 11149.16381271408, 11158.442077896072, 11164.888351116384, 11168.106551475863, 19976.3008294709, 20023.199072036954, 20046.198152074045, 20059.39821805348, 20397.0, 6963.1316612228875, 7067.787966361017, 7094.396976257076, 7146.606936618432, 7188.5327453715345, 3689.0777305673582, 3778.131029863147, 3786.8295926683363, 3796.7532110413285, 3807.695384369244, 2644.5504264274737, 2737.5893615957766, 2740.4245108527034, 2865.097494289334, 2867.5125228711167, 489.89222371078307, 494.4165884256612, 501.1230346892937, 502.4924825925841, 503.8513638476585, 594.1651978969554, 596.5794115148809, 599.3946114424463, 612.4885720423407, 620.8447888027993, 8637.611307491177, 8693.913060869438, 8696.13038696138, 8709.303255739653, 8858.601362938683, 581.1689284323373, 591.3235976007979, 602.8553147244718, 628.1080865171467, 638.9552731308763, 4069.654079403225, 4210.326347784401, 4262.083652014831, 4269.188854117708, 4280.315149576053, 90043.0, 93712.0, 96310.0, 96339.51830240944, 96912.0, 48996.755016225405, 50173.73175816487, 67651.0, 79639.60180199062, 82175.589122054, 71813.0, 77170.61414693003, 77438.61280693673, 77715.0, 77720.61139694265, 7253.8186545336785, 7678.385729141767, 7902.881456778268, 8014.278714915686, 8203.917960820316, 29878.0, 30531.0, 36680.0, 44309.33535996898, 46357.0, 40604.59395406008, 52038.219426707874, 58023.0, 60428.0, 61598.0, 10772.515236814519, 11170.553177872795, 11276.041536469163, 11345.149113816662, 11711.297322160697, 13191.20454151062, 13455.259960702255, 13697.931510342385, 13858.099223550436, 13858.584242472742, 11565.421728913689, 12127.696807579528, 12391.62825115248, 12469.5012199511, 12620.36898155107, 11514.88485115138, 11763.47064382122, 12049.578264760692, 12532.37338133108, 12555.435005424595, 12452.875471245172, 12615.0, 12847.871521284667, 12947.676308092374, 13335.133216340906, 10753.73115672083, 10971.286866353661, 10996.560137594726, 11086.778264434828, 11318.830217546909, 9543.313536760055, 10710.965846052342, 11045.723856903642, 11122.388268645302, 11416.429178540873], "value": 6662.245244807564, "unit": "score"}], "name": "dromaeo_css", "value": 6417.647122476216, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 114.0, 115.0, 114.0, 115.0, 113.0, 113.0, 113.0, 113.0, 113.0], "value": 112.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 126.0, 122.0, 170.0, 124.0, 127.0, 144.0, 130.0, 159.0, 121.0], "value": 137.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 161.0, 161.0, 161.0, 163.0, 161.0, 163.0, 161.0, 162.0], "value": 161.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 78.0, 130.0, 76.0, 78.0, 74.0, 75.0, 98.0, 75.0], "value": 83.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 118.0, 85.0, 85.0, 85.0, 130.0, 85.0, 86.0, 128.0], "value": 97.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 117.0, 117.0, 116.0, 117.0, 117.0, 118.0, 116.0, 116.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 146.0, 145.0, 145.0, 146.0, 146.0, 147.0, 146.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 94.0, 95.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 64.0, 64.0, 64.0, 64.0, 63.0, 65.0, 65.0, 64.0], "value": 64.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 64.0, 64.0, 64.0, 64.0, 65.0, 64.0, 65.0, 65.0], "value": 64.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 79.0, 77.0, 78.0, 79.0, 81.0, 79.0, 81.0, 78.0], "value": 79.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 128.0, 128.0, 127.0, 128.0, 131.0, 130.0, 131.0, 182.0, 140.0], "value": 137.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 170.0, 171.0, 173.0, 168.0, 169.0, 174.0, 166.0, 175.0, 173.0], "value": 171.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 72.0, 74.0, 67.0, 67.0, 66.0, 67.0, 68.0, 70.0], "value": 68.6, "unit": "score"}], "name": "kraken", "value": 1534.8999999999999, "alertThreshold": 2.0}]} 15:22:36 INFO - Return code: 0 15:22:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:22:36 INFO - # TBPL SUCCESS # 15:22:36 INFO - Running post-action listener: _resource_record_post_action 15:22:36 INFO - ##### 15:22:36 INFO - ##### Finished run-tests step (success) 15:22:36 INFO - ##### 15:22:36 INFO - Running post-run listener: _resource_record_post_run 15:22:36 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 0; Write bytes: 537354240; Read time: 0; Write time: 675632 15:22:36 INFO - TinderboxPrint: CPU usage
13.0% 15:22:36 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 15:22:36 INFO - TinderboxPrint: I/O write bytes / time
537,354,240 / 675,632 15:22:36 INFO - TinderboxPrint: CPU idle
8,333.4 (87.0%) 15:22:36 INFO - TinderboxPrint: CPU user
1,200.4 (12.5%) 15:22:36 INFO - TinderboxPrint: Swap in / out
0 / 0 15:22:36 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 247181312; Read time: 0; Write time: 308232 15:22:36 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 0; Write bytes: 289890304; Read time: 0; Write time: 367376 15:22:37 INFO - Running post-run listener: _upload_blobber_files 15:22:37 INFO - Blob upload gear active. 15:22:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:22:37 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 15:22:37 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'] 15:22:37 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 15:22:37 INFO - (blobuploader) - INFO - Open directory for files ... 15:22:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:22:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:22:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:22:41 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:22:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:22:41 INFO - (blobuploader) - INFO - Done attempting. 15:22:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:22:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:22:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:22:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:22:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:22:42 INFO - (blobuploader) - INFO - Done attempting. 15:22:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:22:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:22:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:22:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:22:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:22:42 INFO - (blobuploader) - INFO - Done attempting. 15:22:42 INFO - (blobuploader) - INFO - Iteration through files over. 15:22:42 INFO - Return code: 0 15:22:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:22:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:22:42 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/c0ab7df8d7d2d7a60b101e2cc92459f133c8539b83b5d6acc50ff0d986233fb4f514fbed2b036112b60c971597b5059664992618d16b3b35ef0a212850b89026", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/aad0a376ad0869802df05d0b7d2ce52450969bebcca37694c6c4b18e3416d8c706d40bd4724bfa95ebc70525f5a3939ff8cc02e41cd941765cf246e05d23de72", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/ef5b2563d87e0a4b9da94aa1396c59e0e637c48023d2a59aa8a2d4763458434551d7956902e5abec5cac3068c1f4a13ee6e902134018ae7056c3ffa5b3cb1e0b"} 15:22:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:22:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:22:42 INFO - Contents: 15:22:42 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/c0ab7df8d7d2d7a60b101e2cc92459f133c8539b83b5d6acc50ff0d986233fb4f514fbed2b036112b60c971597b5059664992618d16b3b35ef0a212850b89026", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/aad0a376ad0869802df05d0b7d2ce52450969bebcca37694c6c4b18e3416d8c706d40bd4724bfa95ebc70525f5a3939ff8cc02e41cd941765cf246e05d23de72", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/ef5b2563d87e0a4b9da94aa1396c59e0e637c48023d2a59aa8a2d4763458434551d7956902e5abec5cac3068c1f4a13ee6e902134018ae7056c3ffa5b3cb1e0b"} 15:22:42 INFO - Running post-run listener: copy_logs_to_upload_dir 15:22:42 INFO - Copying logs to upload dir... 15:22:42 INFO - mkdir: /builds/slave/test/build/upload/logs 15:22:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1240.757575 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-07-12 15:22:42.311136) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-12 15:22:42.315000) ========= 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=5bd038846534b2fdfa68eeae000001e2-1468360872.253528-239280511 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/c0ab7df8d7d2d7a60b101e2cc92459f133c8539b83b5d6acc50ff0d986233fb4f514fbed2b036112b60c971597b5059664992618d16b3b35ef0a212850b89026", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/aad0a376ad0869802df05d0b7d2ce52450969bebcca37694c6c4b18e3416d8c706d40bd4724bfa95ebc70525f5a3939ff8cc02e41cd941765cf246e05d23de72", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/ef5b2563d87e0a4b9da94aa1396c59e0e637c48023d2a59aa8a2d4763458434551d7956902e5abec5cac3068c1f4a13ee6e902134018ae7056c3ffa5b3cb1e0b"} build_url:https://queue.taskcluster.net/v1/task/ICxnFLgER4ChM_-iieoMRw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011231 build_url: 'https://queue.taskcluster.net/v1/task/ICxnFLgER4ChM_-iieoMRw/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/c0ab7df8d7d2d7a60b101e2cc92459f133c8539b83b5d6acc50ff0d986233fb4f514fbed2b036112b60c971597b5059664992618d16b3b35ef0a212850b89026", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/aad0a376ad0869802df05d0b7d2ce52450969bebcca37694c6c4b18e3416d8c706d40bd4724bfa95ebc70525f5a3939ff8cc02e41cd941765cf246e05d23de72", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/ef5b2563d87e0a4b9da94aa1396c59e0e637c48023d2a59aa8a2d4763458434551d7956902e5abec5cac3068c1f4a13ee6e902134018ae7056c3ffa5b3cb1e0b"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-12 15:22:42.339970) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 15:22:42.340293) ========= 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=5bd038846534b2fdfa68eeae000001e2-1468360872.253528-239280511 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004441 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 15:22:42.425661) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-12 15:22:42.425976) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-12 15:22:42.426309) ========= ========= Total master_lag: 0.19 =========