builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-012 starttime: 1467858752.92 results: success (0) buildid: 20160706181624 builduid: a7275eb20cf749aa9d5ace23bfe39601 revision: 39ec98bf2580a236471714c28b336d8c974cc365 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-06 19:32:32.924531) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-06 19:32:32.925039) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-06 19:32:32.925385) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-06 19:32:32.968399) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 19:32:32.968809) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1467857098.738187-1065211122 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-06 19:32:33-- 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% 67.0M=0s 2016-07-06 19:32:33 (67.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.140158 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 19:32:33.124069) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 19:32:33.124449) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1467857098.738187-1065211122 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015708 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 19:32:33.177565) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 19:32:33.177929) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 39ec98bf2580a236471714c28b336d8c974cc365 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 39ec98bf2580a236471714c28b336d8c974cc365 --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=2c5dd2b0a310ecf02fd87158000001b4-1467857098.738187-1065211122 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-06 19:32:33,921 truncating revision to first 12 chars 2016-07-06 19:32:33,921 Setting DEBUG logging. 2016-07-06 19:32:33,921 attempt 1/10 2016-07-06 19:32:33,921 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/39ec98bf2580?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-06 19:32:34,225 unpacking tar archive at: mozilla-central-39ec98bf2580/testing/mozharness/ program finished with exit code 0 elapsedTime=0.619476 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 19:32:33.811152) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-06 19:32:33.811467) ========= script_repo_revision: 39ec98bf2580a236471714c28b336d8c974cc365 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-06 19:32:33.811855) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-06 19:32:33.812137) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-06 19:32:33.821092) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-07-06 19:32:33.821366) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=2c5dd2b0a310ecf02fd87158000001b4-1467857098.738187-1065211122 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:32:34 INFO - MultiFileLogger online at 20160706 19:32:34 in /builds/slave/test 19:32:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 19:32:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:32:34 INFO - {'append_to_log': False, 19:32:34 INFO - 'base_work_dir': '/builds/slave/test', 19:32:34 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 19:32:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:32:34 INFO - 'branch': 'Firefox-Non-PGO', 19:32:34 INFO - 'buildbot_json_path': 'buildprops.json', 19:32:34 INFO - 'config_files': ('talos/linux_config.py',), 19:32:34 INFO - 'default_actions': ('clobber', 19:32:34 INFO - 'read-buildbot-config', 19:32:34 INFO - 'download-and-extract', 19:32:34 INFO - 'populate-webroot', 19:32:34 INFO - 'create-virtualenv', 19:32:34 INFO - 'install', 19:32:34 INFO - 'run-tests'), 19:32:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:32:34 INFO - 'download_minidump_stackwalk': True, 19:32:34 INFO - 'download_symbols': 'ondemand', 19:32:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:32:34 INFO - 'tooltool.py': '/tools/tooltool.py', 19:32:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:32:34 INFO - '/tools/misc-python/virtualenv.py')}, 19:32:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:32:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:32:34 INFO - 'installer_path': 'installer.exe', 19:32:34 INFO - 'log_level': 'info', 19:32:34 INFO - 'log_name': 'talos', 19:32:34 INFO - 'log_to_console': True, 19:32:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:32:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:32:34 INFO - 'opt_config_files': (), 19:32:34 INFO - 'pip_index': False, 19:32:34 INFO - 'sps_profile': False, 19:32:34 INFO - 'sps_profile_interval': 0, 19:32:34 INFO - 'suite': 'dromaeojs', 19:32:34 INFO - 'system_bits': '32', 19:32:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:32:34 INFO - 'title': 'talos-linux64-ix-012', 19:32:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:32:34 INFO - 'use_talos_json': True, 19:32:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:32:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:32:34 INFO - 'work_dir': 'build'} 19:32:34 INFO - ##### 19:32:34 INFO - ##### Running clobber step. 19:32:34 INFO - ##### 19:32:34 INFO - Running pre-action listener: _resource_record_pre_action 19:32:34 INFO - Running main action method: clobber 19:32:34 INFO - rmtree: /builds/slave/test/build 19:32:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:32:35 INFO - Running post-action listener: _resource_record_post_action 19:32:35 INFO - ##### 19:32:35 INFO - ##### Finished clobber step (success) 19:32:35 INFO - ##### 19:32:35 INFO - ##### 19:32:35 INFO - ##### Running read-buildbot-config step. 19:32:35 INFO - ##### 19:32:35 INFO - Running pre-action listener: _resource_record_pre_action 19:32:35 INFO - Running main action method: read_buildbot_config 19:32:35 INFO - Using buildbot properties: 19:32:35 INFO - { 19:32:35 INFO - "project": "", 19:32:35 INFO - "product": "firefox", 19:32:35 INFO - "script_repo_revision": "production", 19:32:35 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 19:32:35 INFO - "repository": "", 19:32:35 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 19:32:35 INFO - "buildid": "20160706181624", 19:32:35 INFO - "pgo_build": "False", 19:32:35 INFO - "basedir": "/builds/slave/test", 19:32:35 INFO - "buildnumber": 637, 19:32:35 INFO - "slavename": "talos-linux64-ix-012", 19:32:35 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 19:32:35 INFO - "platform": "ubuntu64_hw", 19:32:35 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 19:32:35 INFO - "branch": "mozilla-central", 19:32:35 INFO - "repo_path": "mozilla-central", 19:32:35 INFO - "revision": "39ec98bf2580a236471714c28b336d8c974cc365", 19:32:35 INFO - "stage_platform": "linux64", 19:32:35 INFO - "builduid": "a7275eb20cf749aa9d5ace23bfe39601", 19:32:35 INFO - "slavebuilddir": "test" 19:32:35 INFO - } 19:32:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/dUsf8kqGRySJpX9iCJshYA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 19:32:35 INFO - Running post-action listener: _resource_record_post_action 19:32:35 INFO - ##### 19:32:35 INFO - ##### Finished read-buildbot-config step (success) 19:32:35 INFO - ##### 19:32:35 INFO - ##### 19:32:35 INFO - ##### Running download-and-extract step. 19:32:35 INFO - ##### 19:32:35 INFO - Running pre-action listener: _resource_record_pre_action 19:32:35 INFO - Running main action method: download_and_extract 19:32:35 INFO - mkdir: /builds/slave/test/build/tests 19:32: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')]} 19:32:35 INFO - https://queue.taskcluster.net/v1/task/dUsf8kqGRySJpX9iCJshYA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 19:32:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dUsf8kqGRySJpX9iCJshYA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 19:32:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dUsf8kqGRySJpX9iCJshYA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 19:32:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dUsf8kqGRySJpX9iCJshYA/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 19:32:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dUsf8kqGRySJpX9iCJshYA/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 19:32:36 INFO - Downloaded 1361 bytes. 19:32:36 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 19:32:36 INFO - Using the following test package requirements: 19:32:36 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 19:32:36 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 19:32:36 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 19:32:36 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 19:32:36 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 19:32:36 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 19:32:36 INFO - u'jsshell-linux-x86_64.zip'], 19:32:36 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 19:32:36 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 19:32:36 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 19:32:36 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 19:32:36 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 19:32:36 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 19:32:36 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 19:32:36 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 19:32:36 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 19:32:36 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 19:32:36 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 19:32:36 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 19:32:36 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')]} 19:32:36 INFO - https://queue.taskcluster.net/v1/task/dUsf8kqGRySJpX9iCJshYA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 19:32:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dUsf8kqGRySJpX9iCJshYA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 19:32:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dUsf8kqGRySJpX9iCJshYA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 19:32:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dUsf8kqGRySJpX9iCJshYA/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 19:32:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dUsf8kqGRySJpX9iCJshYA/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 19:32:37 INFO - Downloaded 22733225 bytes. 19:32:37 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'] 19:32:37 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 19:32:38 INFO - Return code: 0 19:32:38 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 19:32:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 19:32:38 INFO - https://queue.taskcluster.net/v1/task/dUsf8kqGRySJpX9iCJshYA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 19:32:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dUsf8kqGRySJpX9iCJshYA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 19:32:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dUsf8kqGRySJpX9iCJshYA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 19:32:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dUsf8kqGRySJpX9iCJshYA/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 19:32:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dUsf8kqGRySJpX9iCJshYA/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 19:32:39 INFO - Downloaded 22733225 bytes. 19:32:39 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'] 19:32:39 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 19:32:41 INFO - Return code: 0 19:32:41 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')]} 19:32:41 INFO - https://queue.taskcluster.net/v1/task/dUsf8kqGRySJpX9iCJshYA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 19:32:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dUsf8kqGRySJpX9iCJshYA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 19:32:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dUsf8kqGRySJpX9iCJshYA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 19:32:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dUsf8kqGRySJpX9iCJshYA/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 19:32:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dUsf8kqGRySJpX9iCJshYA/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 19:32:42 INFO - Downloaded 14403333 bytes. 19:32:42 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'] 19:32:42 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 19:32:42 INFO - Return code: 0 19:32:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 19:32:42 INFO - https://queue.taskcluster.net/v1/task/dUsf8kqGRySJpX9iCJshYA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 19:32:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dUsf8kqGRySJpX9iCJshYA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 19:32:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dUsf8kqGRySJpX9iCJshYA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 19:32:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dUsf8kqGRySJpX9iCJshYA/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 19:32:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dUsf8kqGRySJpX9iCJshYA/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 19:32:44 INFO - Downloaded 56589682 bytes. 19:32:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dUsf8kqGRySJpX9iCJshYA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 19:32:44 INFO - mkdir: /builds/slave/test/properties 19:32:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:32:44 INFO - Writing to file /builds/slave/test/properties/build_url 19:32:44 INFO - Contents: 19:32:44 INFO - build_url:https://queue.taskcluster.net/v1/task/dUsf8kqGRySJpX9iCJshYA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 19:32:44 INFO - Running post-action listener: _resource_record_post_action 19:32:44 INFO - Running post-action listener: set_extra_try_arguments 19:32:44 INFO - ##### 19:32:44 INFO - ##### Finished download-and-extract step (success) 19:32:44 INFO - ##### 19:32:44 INFO - ##### 19:32:44 INFO - ##### Running populate-webroot step. 19:32:44 INFO - ##### 19:32:44 INFO - Running pre-action listener: _resource_record_pre_action 19:32:44 INFO - Running main action method: populate_webroot 19:32:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:32:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:32:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:32:44 INFO - '--tppagecycles', 19:32:44 INFO - '7'], 19:32:44 INFO - 'tests': ['tsvgm']}}, 19:32:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:32:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:32:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:32:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:32:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:32:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:32:44 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:32:44 INFO - 'talos_options': ['--disable-e10s'], 19:32:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:32:44 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:32:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:32:44 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:32:44 INFO - 'talos_options': ['--disable-e10s'], 19:32:44 INFO - 'tests': ['damp', 'tps']}, 19:32:44 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:32:44 INFO - 'tests': ['damp', 'tps']}, 19:32:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:32:44 INFO - 'tests': ['dromaeo_dom']}, 19:32:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:32:44 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:32:44 INFO - 'tests': ['basic_compositor_video']}, 19:32:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:32:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:32:44 INFO - 'tests': ['a11yr', 19:32:44 INFO - 'ts_paint', 19:32:44 INFO - 'tpaint', 19:32:44 INFO - 'sessionrestore', 19:32:44 INFO - 'sessionrestore_no_auto_restore', 19:32:44 INFO - 'tabpaint']}, 19:32:44 INFO - 'other-e10s': {'tests': ['a11yr', 19:32:44 INFO - 'ts_paint', 19:32:44 INFO - 'tpaint', 19:32:44 INFO - 'sessionrestore', 19:32:44 INFO - 'sessionrestore_no_auto_restore', 19:32:44 INFO - 'tabpaint']}, 19:32:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:32:44 INFO - 'ts_paint', 19:32:44 INFO - 'tpaint', 19:32:44 INFO - 'sessionrestore', 19:32:44 INFO - 'sessionrestore_no_auto_restore', 19:32:44 INFO - 'tabpaint']}, 19:32:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:32:44 INFO - 'ts_paint', 19:32:44 INFO - 'tpaint', 19:32:44 INFO - 'sessionrestore', 19:32:44 INFO - 'sessionrestore_no_auto_restore', 19:32:44 INFO - 'tabpaint']}, 19:32:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:32:44 INFO - 'tests': ['a11yr', 19:32:44 INFO - 'ts_paint', 19:32:44 INFO - 'tpaint', 19:32:44 INFO - 'sessionrestore', 19:32:44 INFO - 'sessionrestore_no_auto_restore', 19:32:44 INFO - 'tabpaint']}, 19:32:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:32:44 INFO - 'tests': ['a11yr', 19:32:44 INFO - 'ts_paint', 19:32:44 INFO - 'tpaint', 19:32:44 INFO - 'sessionrestore', 19:32:44 INFO - 'sessionrestore_no_auto_restore', 19:32:44 INFO - 'tabpaint']}, 19:32:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:32:44 INFO - 'tests': ['tsvgx', 19:32:44 INFO - 'tsvgr_opacity', 19:32:44 INFO - 'tart', 19:32:44 INFO - 'tscrollx', 19:32:44 INFO - 'cart']}, 19:32:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:32:44 INFO - 'tsvgr_opacity', 19:32:44 INFO - 'tart', 19:32:44 INFO - 'tscrollx', 19:32:44 INFO - 'cart']}, 19:32:44 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:32:44 INFO - 'talos_options': ['--disable-e10s'], 19:32:44 INFO - 'tests': ['tp5o']}, 19:32:44 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:32:44 INFO - 'tests': ['tp5o']}, 19:32:44 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:32:44 INFO - 'talos_options': ['--disable-e10s', 19:32:44 INFO - '--xperf_path', 19:32:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:32:44 INFO - 'tests': ['tp5n']}, 19:32:44 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:32:44 INFO - 'talos_options': ['--xperf_path', 19:32:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:32:44 INFO - 'tests': ['tp5n']}}, 19:32:44 INFO - 'talos.zip': {'path': '', 19:32:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:32:44 INFO - Running post-action listener: _resource_record_post_action 19:32:44 INFO - ##### 19:32:44 INFO - ##### Finished populate-webroot step (success) 19:32:44 INFO - ##### 19:32:44 INFO - ##### 19:32:44 INFO - ##### Running create-virtualenv step. 19:32:44 INFO - ##### 19:32:44 INFO - Running pre-action listener: _resource_record_pre_action 19:32:44 INFO - Running main action method: create_virtualenv 19:32:44 INFO - Creating virtualenv /builds/slave/test/build/venv 19:32:44 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 19:32:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:32:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:32:44 INFO - Using real prefix '/usr' 19:32:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:32:45 INFO - Installing distribute.............................................................................................................................................................................................done. 19:32:47 INFO - Installing pip.................done. 19:32:47 INFO - Return code: 0 19:32:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:32:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 19:32:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:32:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:32:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 19:32:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:32:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:32:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cb5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e188a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f76180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f743b0>, '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 0x1f752b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f75740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1467857098.738187-1065211122', '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 19:32:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 19:32:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:32:47 INFO - Using env: {'DISPLAY': ':0', 19:32:47 INFO - 'HOME': '/home/cltbld', 19:32:47 INFO - 'LANG': 'en_US.UTF-8', 19:32:47 INFO - 'LANGUAGE': 'en_US:en', 19:32:47 INFO - 'LOGNAME': 'cltbld', 19:32:47 INFO - 'MAIL': '/var/mail/cltbld', 19:32:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:32:47 INFO - 'MOZ_NO_REMOTE': '1', 19:32:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:32:47 INFO - 'NO_EM_RESTART': '1', 19:32:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:32:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:32:47 INFO - 'PWD': '/builds/slave/test', 19:32:47 INFO - 'SHELL': '/bin/bash', 19:32:47 INFO - 'SHLVL': '1', 19:32:47 INFO - 'TERM': 'linux', 19:32:47 INFO - 'TMOUT': '86400', 19:32:47 INFO - 'USER': 'cltbld', 19:32:47 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1467857098.738187-1065211122', 19:32:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:32:47 INFO - '_': '/tools/buildbot/bin/python'} 19:32:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:32:47 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 19:32:47 INFO - Cleaning up... 19:32:47 INFO - Return code: 0 19:32:47 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:32:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 19:32:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:32:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:32:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 19:32:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:32:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:32:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1cb5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e188a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f76180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f743b0>, '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 0x1f752b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f75740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1467857098.738187-1065211122', '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 19:32:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 19:32:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 19:32:47 INFO - Using env: {'DISPLAY': ':0', 19:32:47 INFO - 'HOME': '/home/cltbld', 19:32:47 INFO - 'LANG': 'en_US.UTF-8', 19:32:47 INFO - 'LANGUAGE': 'en_US:en', 19:32:47 INFO - 'LOGNAME': 'cltbld', 19:32:47 INFO - 'MAIL': '/var/mail/cltbld', 19:32:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:32:47 INFO - 'MOZ_NO_REMOTE': '1', 19:32:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:32:47 INFO - 'NO_EM_RESTART': '1', 19:32:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:32:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:32:47 INFO - 'PWD': '/builds/slave/test', 19:32:47 INFO - 'SHELL': '/bin/bash', 19:32:47 INFO - 'SHLVL': '1', 19:32:47 INFO - 'TERM': 'linux', 19:32:47 INFO - 'TMOUT': '86400', 19:32:47 INFO - 'USER': 'cltbld', 19:32:47 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1467857098.738187-1065211122', 19:32:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:32:47 INFO - '_': '/tools/buildbot/bin/python'} 19:32:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:32:47 INFO - Downloading/unpacking psutil>=3.1.1 19:32:47 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 19:32:47 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 19:32:47 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 19:32:47 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 19:32:47 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 19:32:47 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 19:32:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:32:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:32:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:32:49 INFO - Installing collected packages: psutil 19:32:49 INFO - Running setup.py install for psutil 19:32:49 INFO - building 'psutil._psutil_linux' extension 19:32:49 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 19:32:50 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 19:32:50 INFO - building 'psutil._psutil_posix' extension 19:32:50 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 19:32:50 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 19:32:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:32:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:32:50 INFO - Successfully installed psutil 19:32:50 INFO - Cleaning up... 19:32:50 INFO - Return code: 0 19:32:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:32:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 19:32:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:32:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:32:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 19:32:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:32:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:32:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cb5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e188a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f76180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f743b0>, '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 0x1f752b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f75740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1467857098.738187-1065211122', '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 19:32:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:32:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 19:32:50 INFO - Using env: {'DISPLAY': ':0', 19:32:50 INFO - 'HOME': '/home/cltbld', 19:32:50 INFO - 'LANG': 'en_US.UTF-8', 19:32:50 INFO - 'LANGUAGE': 'en_US:en', 19:32:50 INFO - 'LOGNAME': 'cltbld', 19:32:50 INFO - 'MAIL': '/var/mail/cltbld', 19:32:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:32:50 INFO - 'MOZ_NO_REMOTE': '1', 19:32:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:32:50 INFO - 'NO_EM_RESTART': '1', 19:32:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:32:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:32:50 INFO - 'PWD': '/builds/slave/test', 19:32:50 INFO - 'SHELL': '/bin/bash', 19:32:50 INFO - 'SHLVL': '1', 19:32:50 INFO - 'TERM': 'linux', 19:32:50 INFO - 'TMOUT': '86400', 19:32:50 INFO - 'USER': 'cltbld', 19:32:50 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1467857098.738187-1065211122', 19:32:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:32:50 INFO - '_': '/tools/buildbot/bin/python'} 19:32:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:32:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:32:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:32:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:32:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:32:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:32:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:32:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:32:52 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:32:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:32:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:32:52 INFO - Installing collected packages: mozsystemmonitor 19:32:52 INFO - Running setup.py install for mozsystemmonitor 19:32:52 INFO - Successfully installed mozsystemmonitor 19:32:52 INFO - Cleaning up... 19:32:52 INFO - Return code: 0 19:32:52 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:32: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')]} 19:32:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:32:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:32: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')]} 19:32:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:32:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:32:52 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 0x1cb5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e188a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f76180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f743b0>, '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 0x1f752b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f75740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1467857098.738187-1065211122', '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 19:32:52 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 19:32:52 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 19:32:52 INFO - Using env: {'DISPLAY': ':0', 19:32:52 INFO - 'HOME': '/home/cltbld', 19:32:52 INFO - 'LANG': 'en_US.UTF-8', 19:32:52 INFO - 'LANGUAGE': 'en_US:en', 19:32:52 INFO - 'LOGNAME': 'cltbld', 19:32:52 INFO - 'MAIL': '/var/mail/cltbld', 19:32:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:32:52 INFO - 'MOZ_NO_REMOTE': '1', 19:32:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:32:52 INFO - 'NO_EM_RESTART': '1', 19:32:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:32:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:32:52 INFO - 'PWD': '/builds/slave/test', 19:32:52 INFO - 'SHELL': '/bin/bash', 19:32:52 INFO - 'SHLVL': '1', 19:32:52 INFO - 'TERM': 'linux', 19:32:52 INFO - 'TMOUT': '86400', 19:32:52 INFO - 'USER': 'cltbld', 19:32:52 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1467857098.738187-1065211122', 19:32:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:32:52 INFO - '_': '/tools/buildbot/bin/python'} 19:32:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:32:52 INFO - Downloading/unpacking jsonschema==2.5.1 19:32:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:32:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:32:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:32:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:32:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:32:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:32:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:32:54 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:32:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:32:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:32:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:32:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:32:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:32:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:32:55 INFO - Downloading functools32-3.2.3-2.tar.gz 19:32:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:32:55 INFO - warning: no files found matching '*.txt' 19:32:55 INFO - no previously-included directories found matching 'build' 19:32:55 INFO - no previously-included directories found matching 'dist' 19:32:55 INFO - no previously-included directories found matching '.git*' 19:32:55 INFO - Installing collected packages: jsonschema, functools32 19:32:55 INFO - Running setup.py install for functools32 19:32:55 INFO - warning: no files found matching '*.txt' 19:32:55 INFO - no previously-included directories found matching 'build' 19:32:55 INFO - no previously-included directories found matching 'dist' 19:32:55 INFO - no previously-included directories found matching '.git*' 19:32:55 INFO - Successfully installed jsonschema functools32 19:32:55 INFO - Cleaning up... 19:32:55 INFO - Return code: 0 19:32:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:32:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 19:32:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:32:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:32:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 19:32:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:32:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:32:55 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 0x1cb5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e188a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f76180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f743b0>, '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 0x1f752b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f75740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1467857098.738187-1065211122', '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 19:32:55 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 19:32:55 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 19:32:55 INFO - Using env: {'DISPLAY': ':0', 19:32:55 INFO - 'HOME': '/home/cltbld', 19:32:55 INFO - 'LANG': 'en_US.UTF-8', 19:32:55 INFO - 'LANGUAGE': 'en_US:en', 19:32:55 INFO - 'LOGNAME': 'cltbld', 19:32:55 INFO - 'MAIL': '/var/mail/cltbld', 19:32:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:32:55 INFO - 'MOZ_NO_REMOTE': '1', 19:32:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:32:55 INFO - 'NO_EM_RESTART': '1', 19:32:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:32:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:32:55 INFO - 'PWD': '/builds/slave/test', 19:32:55 INFO - 'SHELL': '/bin/bash', 19:32:55 INFO - 'SHLVL': '1', 19:32:55 INFO - 'TERM': 'linux', 19:32:55 INFO - 'TMOUT': '86400', 19:32:55 INFO - 'USER': 'cltbld', 19:32:55 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1467857098.738187-1065211122', 19:32:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:32:55 INFO - '_': '/tools/buildbot/bin/python'} 19:32:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:32:55 INFO - Downloading/unpacking blobuploader==1.2.4 19:32:55 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 19:32:55 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 19:32:55 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 19:32:55 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 19:32:55 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 19:32:55 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 19:32:57 INFO - Downloading blobuploader-1.2.4.tar.gz 19:32:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:32:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:32:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:32:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:32:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:32:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:32:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:32:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:32:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:32:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:32:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:32:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:32:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:32:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:32:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:32:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:32:58 INFO - Downloading docopt-0.6.1.tar.gz 19:32:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:32:58 INFO - Installing collected packages: blobuploader, requests, docopt 19:32:58 INFO - Running setup.py install for blobuploader 19:32:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:32:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:32:58 INFO - Running setup.py install for requests 19:32:58 INFO - Running setup.py install for docopt 19:32:58 INFO - Successfully installed blobuploader requests docopt 19:32:58 INFO - Cleaning up... 19:32:58 INFO - Return code: 0 19:32:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:32:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:32:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:32:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:32:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:32:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:32:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:32:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cb5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e188a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f76180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f743b0>, '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 0x1f752b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f75740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1467857098.738187-1065211122', '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 19:32:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:32:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:32:58 INFO - Using env: {'DISPLAY': ':0', 19:32:58 INFO - 'HOME': '/home/cltbld', 19:32:58 INFO - 'LANG': 'en_US.UTF-8', 19:32:58 INFO - 'LANGUAGE': 'en_US:en', 19:32:58 INFO - 'LOGNAME': 'cltbld', 19:32:58 INFO - 'MAIL': '/var/mail/cltbld', 19:32:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:32:58 INFO - 'MOZ_NO_REMOTE': '1', 19:32:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:32:58 INFO - 'NO_EM_RESTART': '1', 19:32:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:32:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:32:58 INFO - 'PWD': '/builds/slave/test', 19:32:58 INFO - 'SHELL': '/bin/bash', 19:32:58 INFO - 'SHLVL': '1', 19:32:58 INFO - 'TERM': 'linux', 19:32:58 INFO - 'TMOUT': '86400', 19:32:58 INFO - 'USER': 'cltbld', 19:32:58 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1467857098.738187-1065211122', 19:32:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:32:58 INFO - '_': '/tools/buildbot/bin/python'} 19:32:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:32:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:32:59 INFO - Running setup.py (path:/tmp/pip-9ZJEiH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:32:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:32:59 INFO - Running setup.py (path:/tmp/pip-560Uqc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:32:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:32:59 INFO - Running setup.py (path:/tmp/pip-J4IbTa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:32:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:32:59 INFO - Running setup.py (path:/tmp/pip-4u3nb_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:32:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:32:59 INFO - Running setup.py (path:/tmp/pip-mkwHhd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:32:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:32:59 INFO - Running setup.py (path:/tmp/pip-9XZsdN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:32:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:32:59 INFO - Running setup.py (path:/tmp/pip-yvfbMA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:32:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:32:59 INFO - Running setup.py (path:/tmp/pip-ivb_Xn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:32:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:32:59 INFO - Running setup.py (path:/tmp/pip-ROH3gF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:32:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:32:59 INFO - Running setup.py (path:/tmp/pip-qU2H5D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:32:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:32:59 INFO - Running setup.py (path:/tmp/pip-jYTECt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:32:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:32:59 INFO - Running setup.py (path:/tmp/pip-CIfBIK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:33:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:33:00 INFO - Running setup.py (path:/tmp/pip-RKzUbp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:33:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:33:00 INFO - Running setup.py (path:/tmp/pip-nduFhK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:33:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:33:00 INFO - Running setup.py (path:/tmp/pip-_cx17f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:33:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:33:00 INFO - Running setup.py (path:/tmp/pip-9yvJNY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:33:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:33:00 INFO - Running setup.py (path:/tmp/pip-4kyo6_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:33:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:33:00 INFO - Running setup.py install for manifestparser 19:33:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:33:00 INFO - Running setup.py install for mozcrash 19:33:00 INFO - Running setup.py install for mozdebug 19:33:00 INFO - Running setup.py install for mozdevice 19:33:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:33:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:33:00 INFO - Running setup.py install for mozfile 19:33:00 INFO - Running setup.py install for mozhttpd 19:33:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:33:00 INFO - Running setup.py install for mozinfo 19:33:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:33:01 INFO - Running setup.py install for mozInstall 19:33:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:33:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:33:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:33:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:33:01 INFO - Running setup.py install for mozleak 19:33:01 INFO - Running setup.py install for mozlog 19:33:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:33:01 INFO - Running setup.py install for moznetwork 19:33:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:33:01 INFO - Running setup.py install for mozprocess 19:33:01 INFO - Running setup.py install for mozprofile 19:33:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:33:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:33:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:33:01 INFO - Running setup.py install for mozrunner 19:33:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:33:01 INFO - Running setup.py install for mozscreenshot 19:33:01 INFO - Running setup.py install for moztest 19:33:02 INFO - Running setup.py install for mozversion 19:33:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:33:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:33:02 INFO - Cleaning up... 19:33:02 INFO - Return code: 0 19:33:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:33: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')]} 19:33:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:33:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:33: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')]} 19:33:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:33:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:33:02 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 0x1cb5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e188a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f76180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f743b0>, '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 0x1f752b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f75740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1467857098.738187-1065211122', '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 19:33:02 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 19:33:02 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 19:33:02 INFO - Using env: {'DISPLAY': ':0', 19:33:02 INFO - 'HOME': '/home/cltbld', 19:33:02 INFO - 'LANG': 'en_US.UTF-8', 19:33:02 INFO - 'LANGUAGE': 'en_US:en', 19:33:02 INFO - 'LOGNAME': 'cltbld', 19:33:02 INFO - 'MAIL': '/var/mail/cltbld', 19:33:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:33:02 INFO - 'MOZ_NO_REMOTE': '1', 19:33:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:33:02 INFO - 'NO_EM_RESTART': '1', 19:33:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:33:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:33:02 INFO - 'PWD': '/builds/slave/test', 19:33:02 INFO - 'SHELL': '/bin/bash', 19:33:02 INFO - 'SHLVL': '1', 19:33:02 INFO - 'TERM': 'linux', 19:33:02 INFO - 'TMOUT': '86400', 19:33:02 INFO - 'USER': 'cltbld', 19:33:02 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1467857098.738187-1065211122', 19:33:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:33:02 INFO - '_': '/tools/buildbot/bin/python'} 19:33:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:33:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:33:02 INFO - Running setup.py (path:/tmp/pip-s3aEmi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:33:02 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)) 19:33:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:33:02 INFO - Running setup.py (path:/tmp/pip-eImaMM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:33:02 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)) 19:33:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:33:02 INFO - Running setup.py (path:/tmp/pip-txPebG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:33:02 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)) 19:33:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:33:02 INFO - Running setup.py (path:/tmp/pip-cbNesz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:33:02 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)) 19:33:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:33:02 INFO - Running setup.py (path:/tmp/pip-jdCKcq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:33:02 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)) 19:33:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:33:02 INFO - Running setup.py (path:/tmp/pip-ooiDZ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:33:02 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)) 19:33:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:33:02 INFO - Running setup.py (path:/tmp/pip-cVyogK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:33:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 19:33:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:33:02 INFO - Running setup.py (path:/tmp/pip-mGfpwR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:33:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 19:33:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:33:02 INFO - Running setup.py (path:/tmp/pip-eI3P8G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:33:03 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)) 19:33:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:33:03 INFO - Running setup.py (path:/tmp/pip-S3BOGh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:33:03 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)) 19:33:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:33:03 INFO - Running setup.py (path:/tmp/pip-Tzn8dq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:33:03 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)) 19:33:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:33:03 INFO - Running setup.py (path:/tmp/pip-UhAZlr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:33:03 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)) 19:33:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:33:03 INFO - Running setup.py (path:/tmp/pip-wULxRp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:33:03 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)) 19:33:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:33:03 INFO - Running setup.py (path:/tmp/pip-DDhpYf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:33:03 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)) 19:33:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:33:03 INFO - Running setup.py (path:/tmp/pip-bqM5CO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:33:03 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)) 19:33:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:33:03 INFO - Running setup.py (path:/tmp/pip-f_KrC6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:33:03 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)) 19:33:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:33:03 INFO - Running setup.py (path:/tmp/pip-5gwWhd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:33:03 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)) 19:33:03 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)) 19:33:03 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)) 19:33:03 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)) 19:33:03 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)) 19:33:03 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)) 19:33:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:33:03 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 19:33:03 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 19:33:03 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 19:33:03 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 19:33:03 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 19:33:03 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 19:33:05 INFO - Downloading blessings-1.6.tar.gz 19:33:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:33:05 INFO - Installing collected packages: blessings 19:33:05 INFO - Running setup.py install for blessings 19:33:05 INFO - Successfully installed blessings 19:33:05 INFO - Cleaning up... 19:33:05 INFO - Return code: 0 19:33:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:33:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:33:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:33:06 INFO - Reading from file tmpfile_stdout 19:33:06 INFO - Current package versions: 19:33:06 INFO - argparse == 1.2.1 19:33:06 INFO - blessings == 1.6 19:33:06 INFO - blobuploader == 1.2.4 19:33:06 INFO - docopt == 0.6.1 19:33:06 INFO - functools32 == 3.2.3-2 19:33:06 INFO - jsonschema == 2.5.1 19:33:06 INFO - manifestparser == 1.1 19:33:06 INFO - mozInstall == 1.12 19:33:06 INFO - mozcrash == 0.17 19:33:06 INFO - mozdebug == 0.1 19:33:06 INFO - mozdevice == 0.48 19:33:06 INFO - mozfile == 1.2 19:33:06 INFO - mozhttpd == 0.7 19:33:06 INFO - mozinfo == 0.9 19:33:06 INFO - mozleak == 0.1 19:33:06 INFO - mozlog == 3.2 19:33:06 INFO - moznetwork == 0.27 19:33:06 INFO - mozprocess == 0.23 19:33:06 INFO - mozprofile == 0.28 19:33:06 INFO - mozrunner == 6.12 19:33:06 INFO - mozscreenshot == 0.1 19:33:06 INFO - mozsystemmonitor == 0.3 19:33:06 INFO - moztest == 0.7 19:33:06 INFO - mozversion == 1.4 19:33:06 INFO - psutil == 3.1.1 19:33:06 INFO - requests == 1.2.3 19:33:06 INFO - wsgiref == 0.1.2 19:33:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:33: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')]} 19:33:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:33:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:33: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')]} 19:33:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:33:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:33:06 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 0x1cb5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e188a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f76180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f743b0>, '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 0x1f752b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f75740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1467857098.738187-1065211122', '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 19:33:06 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 19:33:06 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 19:33:06 INFO - Using env: {'DISPLAY': ':0', 19:33:06 INFO - 'HOME': '/home/cltbld', 19:33:06 INFO - 'LANG': 'en_US.UTF-8', 19:33:06 INFO - 'LANGUAGE': 'en_US:en', 19:33:06 INFO - 'LOGNAME': 'cltbld', 19:33:06 INFO - 'MAIL': '/var/mail/cltbld', 19:33:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:33:06 INFO - 'MOZ_NO_REMOTE': '1', 19:33:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:33:06 INFO - 'NO_EM_RESTART': '1', 19:33:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:33:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:33:06 INFO - 'PWD': '/builds/slave/test', 19:33:06 INFO - 'SHELL': '/bin/bash', 19:33:06 INFO - 'SHLVL': '1', 19:33:06 INFO - 'TERM': 'linux', 19:33:06 INFO - 'TMOUT': '86400', 19:33:06 INFO - 'USER': 'cltbld', 19:33:06 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1467857098.738187-1065211122', 19:33:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:33:06 INFO - '_': '/tools/buildbot/bin/python'} 19:33:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:33:06 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)) 19:33:06 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)) 19:33:06 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)) 19:33:06 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)) 19:33:06 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)) 19:33:06 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)) 19:33:06 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)) 19:33:06 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)) 19:33:06 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)) 19:33:06 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)) 19:33:06 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)) 19:33:06 INFO - Cleaning up... 19:33:06 INFO - Return code: 0 19:33:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:33: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')]} 19:33:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:33:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:33: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')]} 19:33:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:33:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:33:06 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 0x1cb5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e188a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f76180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f743b0>, '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 0x1f752b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f75740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1467857098.738187-1065211122', '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 19:33:06 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 19:33:06 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 19:33:06 INFO - Using env: {'DISPLAY': ':0', 19:33:06 INFO - 'HOME': '/home/cltbld', 19:33:06 INFO - 'LANG': 'en_US.UTF-8', 19:33:06 INFO - 'LANGUAGE': 'en_US:en', 19:33:06 INFO - 'LOGNAME': 'cltbld', 19:33:06 INFO - 'MAIL': '/var/mail/cltbld', 19:33:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:33:06 INFO - 'MOZ_NO_REMOTE': '1', 19:33:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:33:06 INFO - 'NO_EM_RESTART': '1', 19:33:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:33:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:33:06 INFO - 'PWD': '/builds/slave/test', 19:33:06 INFO - 'SHELL': '/bin/bash', 19:33:06 INFO - 'SHLVL': '1', 19:33:06 INFO - 'TERM': 'linux', 19:33:06 INFO - 'TMOUT': '86400', 19:33:06 INFO - 'USER': 'cltbld', 19:33:06 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1467857098.738187-1065211122', 19:33:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:33:06 INFO - '_': '/tools/buildbot/bin/python'} 19:33:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:33:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:33:06 INFO - Cleaning up... 19:33:06 INFO - Return code: 0 19:33:06 INFO - Running post-action listener: _resource_record_post_action 19:33:06 INFO - Running post-action listener: _start_resource_monitoring 19:33:06 INFO - Starting resource monitoring. 19:33:06 INFO - ##### 19:33:06 INFO - ##### Finished create-virtualenv step (success) 19:33:06 INFO - ##### 19:33:06 INFO - ##### 19:33:06 INFO - ##### Running install step. 19:33:06 INFO - ##### 19:33:06 INFO - Running pre-action listener: _resource_record_pre_action 19:33:06 INFO - Running main action method: install 19:33:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:33:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:33:06 INFO - Reading from file tmpfile_stdout 19:33:06 INFO - Detecting whether we're running mozinstall >=1.0... 19:33:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:33:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:33:06 INFO - Reading from file tmpfile_stdout 19:33:06 INFO - Output received: 19:33:06 INFO - Usage: mozinstall [options] installer 19:33:06 INFO - Options: 19:33:06 INFO - -h, --help show this help message and exit 19:33:06 INFO - -d DEST, --destination=DEST 19:33:06 INFO - Directory to install application into. [default: 19:33:06 INFO - "/builds/slave/test"] 19:33:06 INFO - --app=APP Application being installed. [default: firefox] 19:33:06 INFO - mkdir: /builds/slave/test/build/application 19:33:06 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'] 19:33:06 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 19:33:23 INFO - Reading from file tmpfile_stdout 19:33:23 INFO - Output received: 19:33:23 INFO - /builds/slave/test/build/application/firefox/firefox 19:33:23 INFO - Running post-action listener: _resource_record_post_action 19:33:23 INFO - ##### 19:33:23 INFO - ##### Finished install step (success) 19:33:23 INFO - ##### 19:33:23 INFO - ##### 19:33:23 INFO - ##### Running run-tests step. 19:33:23 INFO - ##### 19:33:23 INFO - Running pre-action listener: _resource_record_pre_action 19:33:23 INFO - Running main action method: run_tests 19:33:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:33:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:33:23 INFO - Python 2.7.3 19:33:23 INFO - Return code: 0 19:33:23 INFO - grabbing minidump binary from tooltool 19:33:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 19:33:23 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 0x1f743b0>, '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 0x1f752b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f75740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:33:23 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 19:33:23 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 19:33:23 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 19:33:23 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:33:23 INFO - Return code: 0 19:33:23 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:33:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:33:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:33:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:33:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:33:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dUsf8kqGRySJpX9iCJshYA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-012', '--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 19:33:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/dUsf8kqGRySJpX9iCJshYA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-012 --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 19:33:23 INFO - Using env: {'DISPLAY': ':0', 19:33:23 INFO - 'HOME': '/home/cltbld', 19:33:23 INFO - 'LANG': 'en_US.UTF-8', 19:33:23 INFO - 'LANGUAGE': 'en_US:en', 19:33:23 INFO - 'LOGNAME': 'cltbld', 19:33:23 INFO - 'MAIL': '/var/mail/cltbld', 19:33:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:33:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:33:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:33:23 INFO - 'MOZ_NO_REMOTE': '1', 19:33:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:33:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:33:23 INFO - 'NO_EM_RESTART': '1', 19:33:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:33:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:33:23 INFO - 'PWD': '/builds/slave/test', 19:33:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:33:23 INFO - 'SHELL': '/bin/bash', 19:33:23 INFO - 'SHLVL': '1', 19:33:23 INFO - 'TERM': 'linux', 19:33:23 INFO - 'TMOUT': '86400', 19:33:23 INFO - 'USER': 'cltbld', 19:33:23 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1467857098.738187-1065211122', 19:33:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:33:23 INFO - '_': '/tools/buildbot/bin/python'} 19:33:23 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dUsf8kqGRySJpX9iCJshYA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-012', '--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 19:33:23 INFO - mozversion application_buildid: 20160706181624 19:33:23 INFO - mozversion application_changeset: 39ec98bf2580a236471714c28b336d8c974cc365 19:33:23 INFO - mozversion application_display_name: Nightly 19:33:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:33:23 INFO - mozversion application_name: Firefox 19:33:23 INFO - mozversion application_remotingname: firefox 19:33:23 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:33:23 INFO - mozversion application_vendor: Mozilla 19:33:23 INFO - mozversion application_version: 50.0a1 19:33:23 INFO - mozversion platform_buildid: 20160706181624 19:33:23 INFO - mozversion platform_changeset: 39ec98bf2580a236471714c28b336d8c974cc365 19:33:23 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:33:23 INFO - mozversion platform_version: 50.0a1 19:33:23 INFO - using testdate: 1467858803 19:33:23 INFO - actual date: 1467858803 19:33:23 INFO - starting webserver on 'localhost:54925' 19:33:23 INFO - SUITE-START | Running 2 tests 19:33:23 INFO - TEST-START | dromaeo_css 19:33:23 INFO - operating with platform_type : linux_ 19:33:23 INFO - Initialising browser for dromaeo_css test... 19:33:23 INFO - TEST-INFO | started process 28848 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpG_DAS9/profile http://localhost:54925/getInfo.html) 19:33:27 INFO - PROCESS | 28848 | __metrics Screen width/height:1600/1200 19:33:27 INFO - PROCESS | 28848 | colorDepth:24 19:33:27 INFO - PROCESS | 28848 | Browser inner width/height: 1024/697 19:33:27 INFO - PROCESS | 28848 | __metrics 19:33:28 INFO - PROCESS | 28848 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:33:28 INFO - TEST-INFO | 28848: exit 0 19:33:28 INFO - Browser initialized. 19:33:28 INFO - Running cycle 1/1 for dromaeo_css test... 19:33:29 INFO - TEST-INFO | started process 28928 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpG_DAS9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:33:40 INFO - PROCESS | 28928 | RSS: Main: 158961664 19:33:40 INFO - PROCESS | 28928 | 19:34:32 INFO - PROCESS | 28928 | 1467858872585 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] 19:36:44 INFO - PROCESS | 28928 | Cycle 1(1): loaded http://localhost:54925/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54925/tests/dromaeo/cssquery-ext.html) 19:36:45 INFO - PROCESS | 28928 | RSS: Main: 263356416 19:36:45 INFO - PROCESS | 28928 | 19:39:48 INFO - PROCESS | 28928 | Cycle 1(1): loaded http://localhost:54925/tests/dromaeo/cssquery-ext.html (next: http://localhost:54925/tests/dromaeo/cssquery-jquery.html) 19:39:49 INFO - PROCESS | 28928 | RSS: Main: 245784576 19:39:49 INFO - PROCESS | 28928 | 19:42:51 INFO - PROCESS | 28928 | Cycle 1(1): loaded http://localhost:54925/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54925/tests/dromaeo/cssquery-mootools.html) 19:42:51 INFO - PROCESS | 28928 | RSS: Main: 240967680 19:42:51 INFO - PROCESS | 28928 | 19:45:53 INFO - PROCESS | 28928 | Cycle 1(1): loaded http://localhost:54925/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54925/tests/dromaeo/cssquery-prototype.html) 19:45:54 INFO - PROCESS | 28928 | RSS: Main: 237834240 19:45:54 INFO - PROCESS | 28928 | 19:48:57 INFO - PROCESS | 28928 | Cycle 1(1): loaded http://localhost:54925/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54925/tests/dromaeo/cssquery-yui.html) 19:48:57 INFO - PROCESS | 28928 | RSS: Main: 230756352 19:48:57 INFO - PROCESS | 28928 | 19:52:00 INFO - PROCESS | 28928 | Cycle 1(1): loaded http://localhost:54925/tests/dromaeo/cssquery-yui.html (next: http://localhost:54925/tests/dromaeo/cssquery-dojo.html) 19:52:00 INFO - PROCESS | 28928 | RSS: Main: 297508864 19:52:00 INFO - PROCESS | 28928 | 19:52:00 INFO - PROCESS | 28928 | __start_tp_report 19:52:00 INFO - PROCESS | 28928 | _x_x_mozilla_page_load 19:52:00 INFO - PROCESS | 28928 | _x_x_mozilla_page_load_details 19:52:00 INFO - PROCESS | 28928 | |i|pagename|runs| 19:52:00 INFO - PROCESS | 28928 | |0;dojo.html;2611.438540713746;2620.711721710612;2663.6803583569967;2671.9465610687785;2673.278214881982;4699;4722.811087556503;4737.929832424493;4805.90388192236;4856.53862883026;4797.0885531748945;4798.136335459616;4804.591609713169;4828.855134345975;4833.419989601244;4433.556644335565;4449.332600109993;4512.503624601291;4512.774361281923;4531.909361812762;4440.356148358486;4481.753503557303;4524.411826462555;4526.4794548627;4564.931526027096;4421.911561768763;4537.2740361542155;4567;4573.679842411033;4579.656525760564;4360.21516127097;4401.757903315316;4404.779761011937;4432.578905004052;4471.6199123074575;4315.244832154392;4321.351797230409;4372.366006929009;4407.515173330931;4409.272470042439;4736.265878788793;4747.240441529373;4765.1660959331975;4788.401449818773;4821.180399332122;5031.748412579393;5182.378114626231;5221.138512145491;5229.790808367661;5231.424543300234;5152.098382782998;5247.790088396459;5264.789408423659;5266.210068489719;5268.157094864803;4847.830325938575;4890.168671325883;4890.657653964259;4891.877703057452;4903.87740306488;5196.090684130262;5258.421573626898;5381.27352807366;5385.353757549041;5385.7576409061685;5078.720670363092;5123.436421993578;5160.7419629018395;5172.689638621695;5172.715500647425;5205.713685747321;5222.582193424517;5222.746540830173;5272.130098533776;5279;5004.599632029429;5084.49155084496;5134.974325128425;5149.330587023738;5151.665141765773;5116.499289790396;5130.281760553526;5143.434222235551;5147.9189370231925;5180.145276029489;4315.848945286963;4317.481902171759;4317.56824317572;4353.499347575006;4365.345198220229;3736.5142531470733;3739.1586892949413;3759.285735710261;3770.5852356240794;3795.2409518096492;4431.468223813163;4448.377227188196;4458.375827384169;4494.4606647202545;4502.887427814331;5218.086834803894;5218.2955301034635;5230.08473517133;5243.685378877203;5280.339957505312;4354.477462704433;4399.032212913218;4405.713628614194;4424.0709451015;4443.888902777392;4621;4627.329037289642;4627.791749371219;4632.073585282957;4659.184642687516;4893.192623217201;4912.508749125059;4920.6555541111775;4925.975370123269;4965.553100220997;5105.948940510547;5157.638965272395;5162.922556161736;5202;5221.738913054408;4898.804047838046;4902.730349830651;4906.411230652345;4920.458749537476;4971.726555039363;4956.752162391938;5061.8734531637;5100.897982040413;5101.79592816298;5111.361079865017;5053.67151135175;5142.1258386073705;5150;5153.974230128825;5163.586913047023;5012.924806127984;5018.799248030038;5024.371953505812;5028.84913452597;5070.771815268248;4812.037592481448;4945.1346014448045;5022.221555658879;5024.145895197898;5031;4915.483874193301;4920;4993.1012417763695;5005.274235235943;5009.148444764326;5067.391912970467;5080.212567052113;5082.491750824888;5083.847484575469;5139.640225184351;4777.474477807508;4827.155247831545;4850.708957462563;4934.210526315772;4942.481039490802 19:52:00 INFO - PROCESS | 28928 | |1;ext.html;580.1066357808975;580.9825705228843;583.3379114704809;590.8759160576279;592.2803793391029;27331.726682733166;39268.80365598172;41025.179496410055;41138.58861411385;45622.77188614052;41695.791521042425;42083.78958105213;42259;42293.788531057304;42310.788446057806;7529.32236098751;7906.367490600753;8081.515109093473;8082.919170808304;8092.433529652927;7487.138979017401;7720.227977202263;7722.768316950501;10139.138173255282;10422.739431514197;10442.791144177112;10445.059944604984;10492.108170805452;10553.102986246178;10554.577816887315;10372.066514013737;10474.214433917487;10474.528646210976;10480.737981550446;10482.685519434353;7481;7689.423293252972;7693.038370203724;10135.695929122137;10555.47222638865;39223.41164882529;39281;39315.41026884599;39561.20877582432;39565.604343956766;3041.1180757580496;3172.6192856857324;3683.981580092109;3789.3747531657245;3891.727579069466;29605;30108.397832043236;30693.31186784726;30811.38377232465;30850.228744281347;3450.3931111943093;3627.2564124354444;3666.651668091527;3688.686461650763;3722.5160729105314;264567.80309280026;265323;265529;265568;266440.9999999981;56026.15960760592;56205.71897140488;56271;56530.43469565252;56610.43389566051;58544.7072764642;58613.70693146507;58634.12048819271;58691.11963320553;58720;14442.783358249635;15068.171250581323;15105.3202605883;15108.244587770567;15121.470748523969;10518.316309439862;10596.523156457977;10602.151827853753;10603.469826508644;10657.467126643636;16697.91651041737;17227.741583876254;17313.91343043302;17518.21168047441;17595.56011099707;5815.273090863642;5972.343042265347;5984.670843103671;6001.549883758736;6087.29996050451;842.2966822703112;927.8097989912052;948.6110694615174;970.9417434953925;993.458565082032;28573.28566785847;29326.560101598065;29402.264943376587;29537.409251814843;29584.408311833642;928.6888892221046;930.7207837648679;956.3496822160997;957.4590356448584;1046.727850758793;14354.569362919128;15058.32237549313;15251;15253.729554850042;15419.151946642818;386824;387509;387757;390327;390418;49823.25265121026;49905.50094499081;49936;50070.499295007314;50079;1100.785346857355;1148.6381789736206;1237.8143278508296;1244.2907542700755;1252.2236213547658;29451.263718407212;30210;30213.395732084795;30350;30675.53986690246;43538.34692479679;44047;44077.77961110174;44107.55892441035;44132.558674412845;300021;306420;306449;308086;308490;22336.441588960406;24879.253622391356;25116.49767004686;25279.494410112064;25593.616095758953;42380;42632.147357053305;42897.571024289355;42994.78502607467;43657.56342436535;35809;36411.45382819207;36972.26055478821;37062.814685926394;37092.44361334636;36532;36597.08507287339;36682;37393.43909841409;37426.625733742316;36539.63460365362;36785.81607091947;36798.63201368059;37035.62964370322;37558.8122059388;31338.843305783324;33800.1549961253;34097.14757131092;34291.69469985816;34361.31277374489;25868.22395328143;26005.739942600332;26043.739562604133;26179;26275.474490510464 19:52:00 INFO - PROCESS | 28928 | |2;jquery.html;355.3585777671302;360.34597206070964;370.13757943990504;377.3547234229467;393.70668851705466;16489.670206595874;18201.544961375956;18730.53173670659;18804.905975470105;18822.717659235106;17894.910525447354;18107.818921810776;18742;18759;19372.90313548434;5685.431456854323;5956.195913551658;5965.955957707405;6020.759169633209;6049.727762250687;5580.107182850745;5885.08781138922;5895.64626122434;5898.380670029628;5903.026000709878;5670.4896559309655;5852.414758524156;5862.677552734619;5862.9413705863035;5870.090136028901;5931.881362372772;5971.880562388749;5993.580449368525;6009.699515024231;6016.308124565665;5627.943720562783;5784.4504772046585;5795;5817.650940943557;5853.736581853785;24019.639705404432;24061;24531.877340613362;24700.752992470025;24702.13542526021;1955.3938279133515;1966.2921348314596;2011.939641810748;2152.6340522111277;2559.833610815291;6691.698873550702;6705.966470167667;6727.529072964894;14437.783433248509;15389.374834714657;1799.9460016199534;1879.1825555883236;1892.4606487151095;1934.719465677483;1934.8161924617139;70928.29071709268;80937.59531202365;82050;82684.58657706733;83179.16820831836;16424;16610.335586576643;16939.407120750715;16964.406245781338;18641.90679046596;29070.563941540895;29121;29277.56083658747;29363.706362936526;29417.55873661897;8467.788305292293;8885.333599980027;8928.642854285887;9172.908270917338;9188.632454701738;7314.487985841047;7331.596762178025;7362.411007119417;7498.962505187439;7508.962455187799;8312.667493300321;8364.996200455984;8583.755355473422;8594.398392112493;8712.647585151974;3075.2926826829953;3197.6322722887166;3260.233845046412;3330.6344398796377;3365.158710322419;767.9769606911801;819.7458787775809;845.7082306604211;916.9587368568431;932.6316105138433;16789.41237056697;17386.913065434594;17633.911830440764;17652.558686032695;17983.64032719338;857.4255248983196;869.3132425383876;883.9779005524784;949.7435692363023;953.1993125774383;9316.161545460805;9717.6598819041;9775.804483910282;9817.281975654232;9822.508874556244;395715;405313;408685;411666;413721;80235.19764802277;82077;82380;82524;83624.16375836164;821.5850995247362;844.5566077809076;844.6283635200615;848;848.7156802471242;15122.319495622503;15824.762628560811;15901.920490397473;16014.27935742871;16080.758788618414;38923.416148758355;39202.80398597989;39214;39825;40138.99652508711;53037;53908.460915391915;54063.45936540584;54417.45582544282;54455;7427.925720742724;7540.245975402416;7583.886241706268;7677.692892284246;7751.41864360164;12142.514299428278;12286.938565307117;12289.754204915673;12487.750244995232;12539.435725392197;10745.677629671123;10763.730906727395;10820;10972.835407468734;10996.395198264172;10725.854829034068;10850.348979061282;10970.835437468604;11067.114630829354;11122.61070862516;10765.138788897026;10842.295250808673;10852.348859068481;10914.727131821768;11093.778124437627;7585.924140758521;7602.657880395506;7704.114026886979;7711.614419278902;7803.102643196105;9928.354656947275;10122.848157277795;10270.07569318765;10690.518926648476;11055.0603198729 19:52:00 INFO - PROCESS | 28928 | |3;mootools.html;1236.462138969549;1330.753810545049;1401.530487286759;1413.6677880698035;1733.349993752343;6891.896621550681;7811.570363629998;7989.640466179021;8023.959880200606;8184.959075204631;7969.6015199239955;8091.069527004396;8103.675852965875;8105.432619716622;8134.796630084236;2216.5899308627877;2240.8543444676043;2244.2033078257227;2249.403907964391;2252.0203711385598;2204.856684315522;2222.6666000100035;2250.5948929192737;2256.9887150564223;2259.5480903819303;2220.31170337196;2223.9332820015425;2227.8106360959337;2228.3649159989404;2257.2099765082253;2166.837487188467;2174.315090746418;2177.346795961213;2183.148572056899;2187.223535644839;2193.429708275844;2197.1870407949;2199.747029091659;2199.912003519857;2201.6587428948537;7967.163447838009;8142.348612111017;8188.181181881824;8189.754307370788;8194.385421093442;4586.334981427708;4761.785719642625;4766.618670506351;4780.163471392492;4789.688670236458;2756.6140740296373;2827.533456979596;2855.7572606328486;2869.5552189410464;2879.0930856780046;875.6059773102066;880.0231742765525;895.6865097215988;898.4250079948838;900.5812297281796;10838.620648277272;10881.183911206692;10912.454377281183;10923.235373523856;10944.726381840437;3964.8810535683974;4051.2707712611423;4066.6543343815515;4089.5092588889524;4272.743635381825;8578;8625.223729864218;8638.049814520398;8645.91354086451;8651.134886511298;4497.437820272466;4497.752623605667;4499.167653984024;4499.59503644667;4501.279795232747;3843.1545060086737;3903.043754280163;4026.57720939303;4109.239790638681;4164.5210800758305;3464.082018265162;3531.94702079469;3567.625399333033;3625.492431059654;3629.1108678373967;1016.7051555049101;1050.7425680708122;1054.920880933933;1055.3562326980536;1062.2670357453333;772.4974923773319;846.8687353460224;853.3429259470297;868.7133246028795;880.4409200157818;5129.384473863161;5362.91955620666;5398.865028374322;5468.93355795624;5478.369987451414;383.3119550407026;386.7196282695024;387.76733959624;396.33613697057314;407.74515927545286;3658.378075727133;3709.239605880788;3725.2363265530503;3750.9684836670135;3815.5802861685193;9671.758206044764;10002.49987500622;10089.041541053588;10101.444420556792;10107.595696172219;9918.30571859963;10005;10007.449590272605;10014.198864090855;10018.599256029676;7534.434917381109;7584.051993500812;7603.315701586884;7615.200403957505;7625.466217364842;5305.098133317422;5306.522412982696;5356.758945847524;5476.479734425223;5496.6701997880255;8699.13008699125;9060.275177985613;9410.717678469657;9428.481433521221;9466.715998520056;8495.915040849512;9170.312226582899;9304.208737378623;9413.199878010446;9632.9036709632;6911.7926462206215;7104.218535960937;7125.429965602843;7306.561606303639;7312.81717957034;7379.520331178456;7604.8479030417975;7740.612969351622;7828.217178282126;7839.490433121829;7160.355567998905;7166.498345115792;7269.563826170447;7321.707131714672;7353;6870.896936545856;6934.063901373452;6974.58152510851;7032.964835175791;7035.894461583182;6477.514186435942;6479.5788273762055;6525;6549.443297319781;6561.737530498726;4536.682432229844;4853.199222128309;4868.537488938687;4879.438864530624;4899.216125419915;4742.810287588458;4747.477777444409;4800.543948324904;4842.4673285939225;4881.633877459134 19:52:00 INFO - PROCESS | 28928 | |4;prototype.html;297.64431504352297;297.8614944051016;309.10976388002575;309.46926021872486;311.34151270063813;4879.341288925994;4881.292212629167;4892.412910450742;4919.680220785646;4938.777755001024;4644.976775116128;4818.3736114305175;4838.129136755382;4850.526231621871;4852.830150944717;3854.2098869731776;3861.9227615447676;3887.8056097195167;3889.1638297765976;3889.883303500885;3863.787491687956;3882.2429626222897;3884.475595794574;3889.105505733683;3895.2404281165186;3722.7394082414107;3819.694424446038;3840.3087444260022;3865.1496670732536;3876.4379165021055;3800.277947190025;3811.4473401356927;3818.923621527582;3856.2480316338047;3857.9421308680394;3380.5436266104007;3382.2220889195623;3394.2871996880685;3396.1849156202334;3420.990807711706;4756.7859446325;4799.608113647038;4805.1590971579835;4808.3989501312335;4810.687305325143;4310.719803212813;4388.275934470808;4392.824287028515;4412.977935110336;4427.535108813592;5082.771275292621;5089.465606111342;5114.846554603368;5119.513646203604;5140.3060586820675;3729.7202709796875;3746.9063273418383;3752.1745216052423;3758.3986562149926;3760.905977350561;4989.90020199594;5059.696418214882;5234.7120908350025;5373.543248823855;5391.460853914617;3791.905202369908;3792.6776224020714;3805.60041195676;3812.694984401241;3822.4075268333454;3884.8446062157764;3885.55316138642;3886.1256217351433;3886.358750806142;3890.3386424307937;4132.793360331972;4179.310413781692;4296.935545966813;4308.67684923632;4347.543507931685;4980.800767969313;4988.201887697951;4991.650584459053;5012.072766538202;5101.209312556485;1321.3327269728725;1330.348129416579;1339.0158233698223;1339.8057281694294;1345.724126554054;844.4806444036996;873.9257163141205;876.9123087691307;877.2368039805283;885.1325700813111;4448.466184057869;4463.419755431773;4469.888252793641;4489.887752806206;4503.256962601135;5163.896722065613;5190.8961820764125;5210.270562121306;5225.608079394061;5233.738313084331;4391.560843915583;4456.420665313489;4469.575390337913;4484.573965473275;4484.932726009112;2784.7911406644585;2786.275568352243;2811.789115816281;2819.4361127774528;2819.4502072095743;4919.188333924933;4986.008394962993;4990.750462476862;4994;4997.250412438163;5184.818531351385;5269.1832765921345;5294.311739473844;5389.299391079135;5392.946070539323;4897.583705385011;4919.286703428054;4951.356323677899;4958.231474121517;4969.229769385751;5203.973980130227;5209.791608335776;5223.7910483580235;5224.2947202127225;5240.554552862897;5002.424721157114;5095.464976177596;5134;5191.404297851074;5221.2690223368;4976.353074100344;5015.598752099896;5016.598672106295;5018.272350509229;5019.84940451787;3715.9038083764813;3753.5683396409763;3753.981230093832;3756.286305601927;3756.9336864724837;5036.6726162799305;5221.582273418041;5237.397699264481;5281.287026251407;5286.259923610774;5043.9243411348125;5055.191169412877;5063.3417655704525;5084.41529224144;5085.753990272407;5049.57078648319;5144.202648589475;5150.459201783909;5153.3815942087185;5155.510226528474;4931.186354251508;4991.850244492671;5040.218766091261;5103.897922041464;5104.3619547556555;5106.7957281709805;5109.795608175631;5120.6159538035545;5124.231365295086;5130;4993.575546078624;5061.772220250171;5064.46823083571;5073.797048118182;5077.009983053269 19:52:00 INFO - PROCESS | 28928 | |5;yui.html;604.7006731667825;612.1216054961128;642.5149012495566;716.6380977606308;727.505296398449;11724.296542207474;12603.495860165605;12618.810717839227;12632;12635;12662.43019064142;13148.802767958488;13207.141535800167;13220.6694832629;13278.46886124554;761.402299195132;766.0883548577203;766.3409467857638;770.6955752477768;774.0247288416607;766.478794419797;778.8247644280049;779.12737733738;781.5584194929875;781.6951388958311;804.9275565199131;808.514891065359;808.9352851771844;809.3929552835374;809.8015986083418;802.3541049455215;802.4823988527426;802.9799255018613;811.4968719393954;811.6347643560422;604.6190899733184;605.4157737783016;605.6456972670993;605.6578033411153;606.0545548943662;11857.347845868473;11857.644270671894;11897.464614092387;11904.464299106561;11906;9511.191548718367;9850.753731156707;9942.751431214205;9974.9501252494;9993.80012399748;9706.75733106678;9819.165370943478;9892.109710126051;9957.701268961942;9997.555000024893;3162.7311678507353;3201.2477067889026;3210.5184222366597;3296.4396052671104;3321.4187517184637;42223.788881055705;52475.73762131203;55142.9999999996;55180.44819551793;60437.39562604362;10424.322419042737;10682.51928663212;10990.120790336754;11039.172062095375;11137.721556960978;19382.70925936112;19517.8048219516;19522.414327570197;19531.90234048849;19702.423806095478;7037.19072306681;7109.964450177819;7135.89296160558;7257.745978890714;7307.086614173229;3592.084018575247;3643.635636436335;3664.853405863789;3676.3750162472443;3777.150141218259;2954.3057381515328;3095.659477457478;3097.4579448596405;3100.3179300553843;3100.6589275179713;534.5563182558467;540.8161225183457;544.4501053935475;547.1683041776478;548.2242626683278;543.8721900353414;570.9115087161497;582.9854253643692;613.2212090644945;619.5074915442234;8788.384813063152;8805.471671699719;8876.689315873913;8954.194122529003;8956.731298061068;601.8736065426222;607.1984979826675;612.091044798474;612.9448349648553;614.0788816774838;4007.278689835799;4269.231538323132;4297.441332626739;4392.846250381222;4453.109378124388;107986.380204297;108841;110253;111422.44288778504;113347.43326283316;98003.50998244963;99082;99855.50072249738;100678.4966075165;101012.49493752631;47982.520174798505;50983.49016509787;57839.710801445726;75119.62440187763;78995.60502197567;8231.711890083818;8235.29999949987;8250.092489826235;8367.707130250412;8462;47603;48273.5172648269;48600.75699621479;49068;50093;64389.678051609444;65041;69149;69513.30486695068;73663;9934.950325248617;9973.401595904268;10060.496975151067;10197.541110650187;10316.123129534073;11245.662630121109;11347.943260283975;11647.41762911868;11679;11920.52317907274;10011.149052330633;10225.437600931722;10521.105706014769;10737.785244294913;11141.387223702774;10807.891921080689;11016.11871050298;11031.613893513688;11449.59926402572;12021.038316934799;11753;11763.117766167743;12074.818877716665;12122.696932576757;12504.499820007099;10027.147692446224;10358.482075896325;10490.527926243185;10511.684649460529;10899.891001090205;10070.949645251727;10444.529996150039;10641.787164256517;10814;10860.945695271474 19:52:00 INFO - PROCESS | 28928 | __end_tp_report 19:52:00 INFO - PROCESS | 28928 | __start_cc_report 19:52:00 INFO - PROCESS | 28928 | _x_x_mozilla_cycle_collect,1018 19:52:00 INFO - PROCESS | 28928 | __end_cc_report 19:52:00 INFO - PROCESS | 28928 | __startTimestamp1467859920707__endTimestamp 19:52:00 INFO - PROCESS | 28928 | 19:52:00 INFO - PROCESS | 28928 | ------- Summary: start ------- 19:52:00 INFO - PROCESS | 28928 | Number of tests: 6 19:52:00 INFO - PROCESS | 28928 | 19:52:00 INFO - PROCESS | 28928 | [#0] dojo.html Cycles:165 Average:4773.90 Median:4906.41 stddev:515.91 (10.5%) stddev-sans-first:488.81 19:52:00 INFO - PROCESS | 28928 | Values: 2611.4 2620.7 2663.7 2671.9 2673.3 4699.0 4722.8 4737.9 4805.9 4856.5 4797.1 4798.1 4804.6 4828.9 4833.4 4433.6 4449.3 4512.5 4512.8 4531.9 4440.4 4481.8 4524.4 4526.5 4564.9 4421.9 4537.3 4567.0 4573.7 4579.7 4360.2 4401.8 4404.8 4432.6 4471.6 4315.2 4321.4 4372.4 4407.5 4409.3 4736.3 4747.2 4765.2 4788.4 4821.2 5031.7 5182.4 5221.1 5229.8 5231.4 5152.1 5247.8 5264.8 5266.2 5268.2 4847.8 4890.2 4890.7 4891.9 4903.9 5196.1 5258.4 5381.3 5385.4 5385.8 5078.7 5123.4 5160.7 5172.7 5172.7 5205.7 5222.6 5222.7 5272.1 5279.0 5004.6 5084.5 5135.0 5149.3 5151.7 5116.5 5130.3 5143.4 5147.9 5180.1 4315.8 4317.5 4317.6 4353.5 4365.3 3736.5 3739.2 3759.3 3770.6 3795.2 4431.5 4448.4 4458.4 4494.5 4502.9 5218.1 5218.3 5230.1 5243.7 5280.3 4354.5 4399.0 4405.7 4424.1 4443.9 4621.0 4627.3 4627.8 4632.1 4659.2 4893.2 4912.5 4920.7 4926.0 4965.6 5105.9 5157.6 5162.9 5202.0 5221.7 4898.8 4902.7 4906.4 4920.5 4971.7 4956.8 5061.9 5100.9 5101.8 5111.4 5053.7 5142.1 5150.0 5154.0 5163.6 5012.9 5018.8 5024.4 5028.8 5070.8 4812.0 4945.1 5022.2 5024.1 5031.0 4915.5 4920.0 4993.1 5005.3 5009.1 5067.4 5080.2 5082.5 5083.8 5139.6 4777.5 4827.2 4850.7 4934.2 4942.5 19:52:00 INFO - PROCESS | 28928 | 19:52:00 INFO - PROCESS | 28928 | [#1] ext.html Cycles:180 Average:48274.59 Median:20174.33 stddev:85132.63 (422.0%) stddev-sans-first:85296.14 19:52:00 INFO - PROCESS | 28928 | Values: 580.1 581.0 583.3 590.9 592.3 27331.7 39268.8 41025.2 41138.6 45622.8 41695.8 42083.8 42259.0 42293.8 42310.8 7529.3 7906.4 8081.5 8082.9 8092.4 7487.1 7720.2 7722.8 10139.1 10422.7 10442.8 10445.1 10492.1 10553.1 10554.6 10372.1 10474.2 10474.5 10480.7 10482.7 7481.0 7689.4 7693.0 10135.7 10555.5 39223.4 39281.0 39315.4 39561.2 39565.6 3041.1 3172.6 3684.0 3789.4 3891.7 29605.0 30108.4 30693.3 30811.4 30850.2 3450.4 3627.3 3666.7 3688.7 3722.5 264567.8 265323.0 265529.0 265568.0 266441.0 56026.2 56205.7 56271.0 56530.4 56610.4 58544.7 58613.7 58634.1 58691.1 58720.0 14442.8 15068.2 15105.3 15108.2 15121.5 10518.3 10596.5 10602.2 10603.5 10657.5 16697.9 17227.7 17313.9 17518.2 17595.6 5815.3 5972.3 5984.7 6001.5 6087.3 842.3 927.8 948.6 970.9 993.5 28573.3 29326.6 29402.3 29537.4 29584.4 928.7 930.7 956.3 957.5 1046.7 14354.6 15058.3 15251.0 15253.7 15419.2 386824.0 387509.0 387757.0 390327.0 390418.0 49823.3 49905.5 49936.0 50070.5 50079.0 1100.8 1148.6 1237.8 1244.3 1252.2 29451.3 30210.0 30213.4 30350.0 30675.5 43538.3 44047.0 44077.8 44107.6 44132.6 300021.0 306420.0 306449.0 308086.0 308490.0 22336.4 24879.3 25116.5 25279.5 25593.6 42380.0 42632.1 42897.6 42994.8 43657.6 35809.0 36411.5 36972.3 37062.8 37092.4 36532.0 36597.1 36682.0 37393.4 37426.6 36539.6 36785.8 36798.6 37035.6 37558.8 31338.8 33800.2 34097.1 34291.7 34361.3 25868.2 26005.7 26043.7 26179.0 26275.5 19:52:00 INFO - PROCESS | 28928 | 19:52:00 INFO - PROCESS | 28928 | [#2] jquery.html Cycles:180 Average:26618.57 Median:233379.00 stddev:67301.99 (28.8%) stddev-sans-first:67461.90 19:52:00 INFO - PROCESS | 28928 | Values: 355.4 360.3 370.1 377.4 393.7 16489.7 18201.5 18730.5 18804.9 18822.7 17894.9 18107.8 18742.0 18759.0 19372.9 5685.4 5956.2 5966.0 6020.8 6049.7 5580.1 5885.1 5895.6 5898.4 5903.0 5670.5 5852.4 5862.7 5862.9 5870.1 5931.9 5971.9 5993.6 6009.7 6016.3 5627.9 5784.5 5795.0 5817.7 5853.7 24019.6 24061.0 24531.9 24700.8 24702.1 1955.4 1966.3 2011.9 2152.6 2559.8 6691.7 6706.0 6727.5 14437.8 15389.4 1799.9 1879.2 1892.5 1934.7 1934.8 70928.3 80937.6 82050.0 82684.6 83179.2 16424.0 16610.3 16939.4 16964.4 18641.9 29070.6 29121.0 29277.6 29363.7 29417.6 8467.8 8885.3 8928.6 9172.9 9188.6 7314.5 7331.6 7362.4 7499.0 7509.0 8312.7 8365.0 8583.8 8594.4 8712.6 3075.3 3197.6 3260.2 3330.6 3365.2 768.0 819.7 845.7 917.0 932.6 16789.4 17386.9 17633.9 17652.6 17983.6 857.4 869.3 884.0 949.7 953.2 9316.2 9717.7 9775.8 9817.3 9822.5 395715.0 405313.0 408685.0 411666.0 413721.0 80235.2 82077.0 82380.0 82524.0 83624.2 821.6 844.6 844.6 848.0 848.7 15122.3 15824.8 15901.9 16014.3 16080.8 38923.4 39202.8 39214.0 39825.0 40139.0 53037.0 53908.5 54063.5 54417.5 54455.0 7427.9 7540.2 7583.9 7677.7 7751.4 12142.5 12286.9 12289.8 12487.8 12539.4 10745.7 10763.7 10820.0 10972.8 10996.4 10725.9 10850.3 10970.8 11067.1 11122.6 10765.1 10842.3 10852.3 10914.7 11093.8 7585.9 7602.7 7704.1 7711.6 7803.1 9928.4 10122.8 10270.1 10690.5 11055.1 19:52:00 INFO - PROCESS | 28928 | 19:52:00 INFO - PROCESS | 28928 | [#3] mootools.html Cycles:180 Average:5239.27 Median:4795.12 stddev:2999.44 (62.6%) stddev-sans-first:2992.77 19:52:00 INFO - PROCESS | 28928 | Values: 1236.5 1330.8 1401.5 1413.7 1733.3 6891.9 7811.6 7989.6 8024.0 8185.0 7969.6 8091.1 8103.7 8105.4 8134.8 2216.6 2240.9 2244.2 2249.4 2252.0 2204.9 2222.7 2250.6 2257.0 2259.5 2220.3 2223.9 2227.8 2228.4 2257.2 2166.8 2174.3 2177.3 2183.1 2187.2 2193.4 2197.2 2199.7 2199.9 2201.7 7967.2 8142.3 8188.2 8189.8 8194.4 4586.3 4761.8 4766.6 4780.2 4789.7 2756.6 2827.5 2855.8 2869.6 2879.1 875.6 880.0 895.7 898.4 900.6 10838.6 10881.2 10912.5 10923.2 10944.7 3964.9 4051.3 4066.7 4089.5 4272.7 8578.0 8625.2 8638.0 8645.9 8651.1 4497.4 4497.8 4499.2 4499.6 4501.3 3843.2 3903.0 4026.6 4109.2 4164.5 3464.1 3531.9 3567.6 3625.5 3629.1 1016.7 1050.7 1054.9 1055.4 1062.3 772.5 846.9 853.3 868.7 880.4 5129.4 5362.9 5398.9 5468.9 5478.4 383.3 386.7 387.8 396.3 407.7 3658.4 3709.2 3725.2 3751.0 3815.6 9671.8 10002.5 10089.0 10101.4 10107.6 9918.3 10005.0 10007.4 10014.2 10018.6 7534.4 7584.1 7603.3 7615.2 7625.5 5305.1 5306.5 5356.8 5476.5 5496.7 8699.1 9060.3 9410.7 9428.5 9466.7 8495.9 9170.3 9304.2 9413.2 9632.9 6911.8 7104.2 7125.4 7306.6 7312.8 7379.5 7604.8 7740.6 7828.2 7839.5 7160.4 7166.5 7269.6 7321.7 7353.0 6870.9 6934.1 6974.6 7033.0 7035.9 6477.5 6479.6 6525.0 6549.4 6561.7 4536.7 4853.2 4868.5 4879.4 4899.2 4742.8 4747.5 4800.5 4842.5 4881.6 19:52:00 INFO - PROCESS | 28928 | 19:52:00 INFO - PROCESS | 28928 | [#4] prototype.html Cycles:180 Average:4253.37 Median:4880.32 stddev:1215.94 (24.9%) stddev-sans-first:1182.55 19:52:00 INFO - PROCESS | 28928 | Values: 297.6 297.9 309.1 309.5 311.3 4879.3 4881.3 4892.4 4919.7 4938.8 4645.0 4818.4 4838.1 4850.5 4852.8 3854.2 3861.9 3887.8 3889.2 3889.9 3863.8 3882.2 3884.5 3889.1 3895.2 3722.7 3819.7 3840.3 3865.1 3876.4 3800.3 3811.4 3818.9 3856.2 3857.9 3380.5 3382.2 3394.3 3396.2 3421.0 4756.8 4799.6 4805.2 4808.4 4810.7 4310.7 4388.3 4392.8 4413.0 4427.5 5082.8 5089.5 5114.8 5119.5 5140.3 3729.7 3746.9 3752.2 3758.4 3760.9 4989.9 5059.7 5234.7 5373.5 5391.5 3791.9 3792.7 3805.6 3812.7 3822.4 3884.8 3885.6 3886.1 3886.4 3890.3 4132.8 4179.3 4296.9 4308.7 4347.5 4980.8 4988.2 4991.7 5012.1 5101.2 1321.3 1330.3 1339.0 1339.8 1345.7 844.5 873.9 876.9 877.2 885.1 4448.5 4463.4 4469.9 4489.9 4503.3 5163.9 5190.9 5210.3 5225.6 5233.7 4391.6 4456.4 4469.6 4484.6 4484.9 2784.8 2786.3 2811.8 2819.4 2819.5 4919.2 4986.0 4990.8 4994.0 4997.3 5184.8 5269.2 5294.3 5389.3 5392.9 4897.6 4919.3 4951.4 4958.2 4969.2 5204.0 5209.8 5223.8 5224.3 5240.6 5002.4 5095.5 5134.0 5191.4 5221.3 4976.4 5015.6 5016.6 5018.3 5019.8 3715.9 3753.6 3754.0 3756.3 3756.9 5036.7 5221.6 5237.4 5281.3 5286.3 5043.9 5055.2 5063.3 5084.4 5085.8 5049.6 5144.2 5150.5 5153.4 5155.5 4931.2 4991.9 5040.2 5103.9 5104.4 5106.8 5109.8 5120.6 5124.2 5130.0 4993.6 5061.8 5064.5 5073.8 5077.0 19:52:00 INFO - PROCESS | 28928 | 19:52:00 INFO - PROCESS | 28928 | [#5] yui.html Cycles:180 Average:18091.82 Median:49580.50 stddev:27230.30 (54.9%) stddev-sans-first:27275.03 19:52:00 INFO - PROCESS | 28928 | Values: 604.7 612.1 642.5 716.6 727.5 11724.3 12603.5 12618.8 12632.0 12635.0 12662.4 13148.8 13207.1 13220.7 13278.5 761.4 766.1 766.3 770.7 774.0 766.5 778.8 779.1 781.6 781.7 804.9 808.5 808.9 809.4 809.8 802.4 802.5 803.0 811.5 811.6 604.6 605.4 605.6 605.7 606.1 11857.3 11857.6 11897.5 11904.5 11906.0 9511.2 9850.8 9942.8 9975.0 9993.8 9706.8 9819.2 9892.1 9957.7 9997.6 3162.7 3201.2 3210.5 3296.4 3321.4 42223.8 52475.7 55143.0 55180.4 60437.4 10424.3 10682.5 10990.1 11039.2 11137.7 19382.7 19517.8 19522.4 19531.9 19702.4 7037.2 7110.0 7135.9 7257.7 7307.1 3592.1 3643.6 3664.9 3676.4 3777.2 2954.3 3095.7 3097.5 3100.3 3100.7 534.6 540.8 544.5 547.2 548.2 543.9 570.9 583.0 613.2 619.5 8788.4 8805.5 8876.7 8954.2 8956.7 601.9 607.2 612.1 612.9 614.1 4007.3 4269.2 4297.4 4392.8 4453.1 107986.4 108841.0 110253.0 111422.4 113347.4 98003.5 99082.0 99855.5 100678.5 101012.5 47982.5 50983.5 57839.7 75119.6 78995.6 8231.7 8235.3 8250.1 8367.7 8462.0 47603.0 48273.5 48600.8 49068.0 50093.0 64389.7 65041.0 69149.0 69513.3 73663.0 9935.0 9973.4 10060.5 10197.5 10316.1 11245.7 11347.9 11647.4 11679.0 11920.5 10011.1 10225.4 10521.1 10737.8 11141.4 10807.9 11016.1 11031.6 11449.6 12021.0 11753.0 11763.1 12074.8 12122.7 12504.5 10027.1 10358.5 10490.5 10511.7 10899.9 10070.9 10444.5 10641.8 10814.0 10860.9 19:52:00 INFO - PROCESS | 28928 | -------- Summary: end -------- 19:52:00 INFO - PROCESS | 28928 | 19:52:01 INFO - TEST-INFO | 28928: exit 0 19:52:01 INFO - TEST-OK | dromaeo_css | took 1117779ms 19:52:01 INFO - TEST-START | kraken 19:52:01 INFO - operating with platform_type : linux_ 19:52:01 INFO - Initialising browser for kraken test... 19:52:01 INFO - TEST-INFO | started process 29118 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcePfzS/profile http://localhost:54925/getInfo.html) 19:52:05 INFO - PROCESS | 29118 | __metrics Screen width/height:1600/1200 19:52:05 INFO - PROCESS | 29118 | colorDepth:24 19:52:05 INFO - PROCESS | 29118 | Browser inner width/height: 1024/697 19:52:05 INFO - PROCESS | 29118 | __metrics 19:52:05 INFO - PROCESS | 29118 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:52:06 INFO - TEST-INFO | 29118: exit 0 19:52:06 INFO - Browser initialized. 19:52:06 INFO - Running cycle 1/1 for kraken test... 19:52:06 INFO - TEST-INFO | started process 29200 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcePfzS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:52:18 INFO - PROCESS | 29200 | RSS: Main: 159240192 19:52:18 INFO - PROCESS | 29200 | 19:52:21 INFO - PROCESS | 29200 | Cycle 1(1): loaded http://localhost:54925/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54925/tests/kraken/driver.html?testName=audio-beat-detection) 19:52:22 INFO - PROCESS | 29200 | RSS: Main: 217579520 19:52:22 INFO - PROCESS | 29200 | 19:52:25 INFO - PROCESS | 29200 | Cycle 1(1): loaded http://localhost:54925/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54925/tests/kraken/driver.html?testName=audio-dft) 19:52:25 INFO - PROCESS | 29200 | RSS: Main: 204320768 19:52:25 INFO - PROCESS | 29200 | 19:52:29 INFO - PROCESS | 29200 | Cycle 1(1): loaded http://localhost:54925/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54925/tests/kraken/driver.html?testName=audio-fft) 19:52:29 INFO - PROCESS | 29200 | RSS: Main: 271347712 19:52:29 INFO - PROCESS | 29200 | 19:52:31 INFO - PROCESS | 29200 | Cycle 1(1): loaded http://localhost:54925/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54925/tests/kraken/driver.html?testName=audio-oscillator) 19:52:32 INFO - PROCESS | 29200 | RSS: Main: 181841920 19:52:32 INFO - PROCESS | 29200 | 19:52:34 INFO - PROCESS | 29200 | Cycle 1(1): loaded http://localhost:54925/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54925/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:52:35 INFO - PROCESS | 29200 | RSS: Main: 185593856 19:52:35 INFO - PROCESS | 29200 | 19:52:38 INFO - PROCESS | 29200 | Cycle 1(1): loaded http://localhost:54925/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54925/tests/kraken/driver.html?testName=imaging-darkroom) 19:52:39 INFO - PROCESS | 29200 | RSS: Main: 222154752 19:52:39 INFO - PROCESS | 29200 | 19:52:42 INFO - PROCESS | 29200 | Cycle 1(1): loaded http://localhost:54925/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54925/tests/kraken/driver.html?testName=imaging-desaturate) 19:52:43 INFO - PROCESS | 29200 | RSS: Main: 223068160 19:52:43 INFO - PROCESS | 29200 | 19:52:46 INFO - PROCESS | 29200 | Cycle 1(1): loaded http://localhost:54925/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54925/tests/kraken/driver.html?testName=json-parse-financial) 19:52:46 INFO - PROCESS | 29200 | RSS: Main: 221929472 19:52:46 INFO - PROCESS | 29200 | 19:52:48 INFO - PROCESS | 29200 | Cycle 1(1): loaded http://localhost:54925/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54925/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:52:49 INFO - PROCESS | 29200 | RSS: Main: 210436096 19:52:49 INFO - PROCESS | 29200 | 19:52:50 INFO - PROCESS | 29200 | Cycle 1(1): loaded http://localhost:54925/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54925/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:52:51 INFO - PROCESS | 29200 | RSS: Main: 213737472 19:52:51 INFO - PROCESS | 29200 | 19:52:53 INFO - PROCESS | 29200 | Cycle 1(1): loaded http://localhost:54925/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54925/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:52:54 INFO - PROCESS | 29200 | RSS: Main: 213417984 19:52:54 INFO - PROCESS | 29200 | 19:52:57 INFO - PROCESS | 29200 | Cycle 1(1): loaded http://localhost:54925/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54925/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:52:57 INFO - PROCESS | 29200 | RSS: Main: 227459072 19:52:57 INFO - PROCESS | 29200 | 19:53:00 INFO - PROCESS | 29200 | Cycle 1(1): loaded http://localhost:54925/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54925/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:53:01 INFO - PROCESS | 29200 | RSS: Main: 225869824 19:53:01 INFO - PROCESS | 29200 | 19:53:02 INFO - PROCESS | 29200 | Cycle 1(1): loaded http://localhost:54925/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54925/tests/kraken/driver.html?testName=ai-astar) 19:53:03 INFO - PROCESS | 29200 | RSS: Main: 268173312 19:53:03 INFO - PROCESS | 29200 | 19:53:03 INFO - PROCESS | 29200 | __start_tp_report 19:53:03 INFO - PROCESS | 29200 | _x_x_mozilla_page_load 19:53:03 INFO - PROCESS | 29200 | _x_x_mozilla_page_load_details 19:53:03 INFO - PROCESS | 29200 | |i|pagename|runs| 19:53:03 INFO - PROCESS | 29200 | |0;ai-astar;103;113;111;113;114;112;112;114;112;113 19:53:03 INFO - PROCESS | 29200 | |1;audio-beat-detection;158;125;122;175;122;128;150;123;157;122 19:53:03 INFO - PROCESS | 29200 | |2;audio-dft;162;162;162;161;161;161;161;162;161;161 19:53:03 INFO - PROCESS | 29200 | |3;audio-fft;76;76;78;127;76;76;76;76;109;76 19:53:03 INFO - PROCESS | 29200 | |4;audio-oscillator;85;85;119;85;87;86;119;85;85;132 19:53:03 INFO - PROCESS | 29200 | |5;imaging-gaussian-blur;117;116;117;116;117;117;117;117;117;118 19:53:03 INFO - PROCESS | 29200 | |6;imaging-darkroom;146;146;147;146;146;145;146;146;146;146 19:53:03 INFO - PROCESS | 29200 | |7;imaging-desaturate;95;95;95;95;95;95;96;97;95;95 19:53:03 INFO - PROCESS | 29200 | |8;json-parse-financial;64;64;64;64;65;69;64;65;65;65 19:53:03 INFO - PROCESS | 29200 | |9;json-stringify-tinderbox;64;64;63;65;64;65;65;65;64;64 19:53:03 INFO - PROCESS | 29200 | |10;stanford-crypto-aes;79;78;77;84;81;80;76;77;86;81 19:53:03 INFO - PROCESS | 29200 | |11;stanford-crypto-ccm;136;128;136;131;129;131;137;135;177;133 19:53:03 INFO - PROCESS | 29200 | |12;stanford-crypto-pbkdf2;165;165;165;166;168;167;167;169;166;165 19:53:03 INFO - PROCESS | 29200 | |13;stanford-crypto-sha256-iterative;71;68;67;73;74;67;67;69;66;66 19:53:03 INFO - PROCESS | 29200 | __end_tp_report 19:53:03 INFO - PROCESS | 29200 | __start_cc_report 19:53:03 INFO - PROCESS | 29200 | _x_x_mozilla_cycle_collect,1912 19:53:03 INFO - PROCESS | 29200 | __end_cc_report 19:53:03 INFO - PROCESS | 29200 | __startTimestamp1467859983226__endTimestamp 19:53:03 INFO - PROCESS | 29200 | 19:53:03 INFO - PROCESS | 29200 | ------- Summary: start ------- 19:53:03 INFO - PROCESS | 29200 | Number of tests: 14 19:53:03 INFO - PROCESS | 29200 | 19:53:03 INFO - PROCESS | 29200 | [#0] ai-astar Cycles:10 Average:111.70 Median:113.00 stddev:3.20 (2.8%) stddev-sans-first:1.00 19:53:03 INFO - PROCESS | 29200 | Values: 103.0 113.0 111.0 113.0 114.0 112.0 112.0 114.0 112.0 113.0 19:53:03 INFO - PROCESS | 29200 | 19:53:03 INFO - PROCESS | 29200 | [#1] audio-beat-detection Cycles:10 Average:138.20 Median:139.00 stddev:19.82 (14.3%) stddev-sans-first:19.69 19:53:03 INFO - PROCESS | 29200 | Values: 158.0 125.0 122.0 175.0 122.0 128.0 150.0 123.0 157.0 122.0 19:53:03 INFO - PROCESS | 29200 | 19:53:03 INFO - PROCESS | 29200 | [#2] audio-dft Cycles:10 Average:161.40 Median:161.50 stddev:0.52 (0.3%) stddev-sans-first:0.50 19:53:03 INFO - PROCESS | 29200 | Values: 162.0 162.0 162.0 161.0 161.0 161.0 161.0 162.0 161.0 161.0 19:53:03 INFO - PROCESS | 29200 | 19:53:03 INFO - PROCESS | 29200 | [#3] audio-fft Cycles:10 Average:84.60 Median:76.00 stddev:18.12 (23.8%) stddev-sans-first:18.95 19:53:03 INFO - PROCESS | 29200 | Values: 76.0 76.0 78.0 127.0 76.0 76.0 76.0 76.0 109.0 76.0 19:53:03 INFO - PROCESS | 29200 | 19:53:03 INFO - PROCESS | 29200 | [#4] audio-oscillator Cycles:10 Average:96.80 Median:85.00 stddev:18.66 (22.0%) stddev-sans-first:19.30 19:53:03 INFO - PROCESS | 29200 | Values: 85.0 85.0 119.0 85.0 87.0 86.0 119.0 85.0 85.0 132.0 19:53:03 INFO - PROCESS | 29200 | 19:53:03 INFO - PROCESS | 29200 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 19:53:03 INFO - PROCESS | 29200 | Values: 117.0 116.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 118.0 19:53:03 INFO - PROCESS | 29200 | 19:53:03 INFO - PROCESS | 29200 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.47 (0.3%) stddev-sans-first:0.50 19:53:03 INFO - PROCESS | 29200 | Values: 146.0 146.0 147.0 146.0 146.0 145.0 146.0 146.0 146.0 146.0 19:53:03 INFO - PROCESS | 29200 | 19:53:03 INFO - PROCESS | 29200 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 19:53:03 INFO - PROCESS | 29200 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 96.0 97.0 95.0 95.0 19:53:03 INFO - PROCESS | 29200 | 19:53:03 INFO - PROCESS | 29200 | [#8] json-parse-financial Cycles:10 Average:64.90 Median:65.00 stddev:1.52 (2.3%) stddev-sans-first:1.58 19:53:03 INFO - PROCESS | 29200 | Values: 64.0 64.0 64.0 64.0 65.0 69.0 64.0 65.0 65.0 65.0 19:53:03 INFO - PROCESS | 29200 | 19:53:03 INFO - PROCESS | 29200 | [#9] json-stringify-tinderbox Cycles:10 Average:64.30 Median:64.50 stddev:0.67 (1.0%) stddev-sans-first:0.71 19:53:03 INFO - PROCESS | 29200 | Values: 64.0 64.0 63.0 65.0 64.0 65.0 65.0 65.0 64.0 64.0 19:53:03 INFO - PROCESS | 29200 | 19:53:03 INFO - PROCESS | 29200 | [#10] stanford-crypto-aes Cycles:10 Average:79.90 Median:80.50 stddev:3.21 (4.0%) stddev-sans-first:3.39 19:53:03 INFO - PROCESS | 29200 | Values: 79.0 78.0 77.0 84.0 81.0 80.0 76.0 77.0 86.0 81.0 19:53:03 INFO - PROCESS | 29200 | 19:53:03 INFO - PROCESS | 29200 | [#11] stanford-crypto-ccm Cycles:10 Average:137.30 Median:135.50 stddev:14.29 (10.5%) stddev-sans-first:15.15 19:53:03 INFO - PROCESS | 29200 | Values: 136.0 128.0 136.0 131.0 129.0 131.0 137.0 135.0 177.0 133.0 19:53:03 INFO - PROCESS | 29200 | 19:53:03 INFO - PROCESS | 29200 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.30 Median:166.50 stddev:1.42 (0.9%) stddev-sans-first:1.42 19:53:03 INFO - PROCESS | 29200 | Values: 165.0 165.0 165.0 166.0 168.0 167.0 167.0 169.0 166.0 165.0 19:53:03 INFO - PROCESS | 29200 | 19:53:03 INFO - PROCESS | 29200 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.80 Median:68.50 stddev:2.90 (4.2%) stddev-sans-first:2.96 19:53:03 INFO - PROCESS | 29200 | Values: 71.0 68.0 67.0 73.0 74.0 67.0 67.0 69.0 66.0 66.0 19:53:03 INFO - PROCESS | 29200 | -------- Summary: end -------- 19:53:03 INFO - PROCESS | 29200 | 19:53:04 INFO - TEST-INFO | 29200: exit 0 19:53:04 INFO - TEST-OK | kraken | took 62783ms 19:53:04 INFO - SUITE-END | took 1180s 19:53:04 INFO - Completed test suite (00:19:40) 19:53:04 INFO - javascript benchmark 19:53:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2611.438540713746, 2620.711721710612, 2663.6803583569967, 2671.9465610687785, 2673.278214881982, 4699.0, 4722.811087556503, 4737.929832424493, 4805.90388192236, 4856.53862883026, 4797.0885531748945, 4798.136335459616, 4804.591609713169, 4828.855134345975, 4833.419989601244, 4433.556644335565, 4449.332600109993, 4512.503624601291, 4512.774361281923, 4531.909361812762, 4440.356148358486, 4481.753503557303, 4524.411826462555, 4526.4794548627, 4564.931526027096, 4421.911561768763, 4537.2740361542155, 4567.0, 4573.679842411033, 4579.656525760564, 4360.21516127097, 4401.757903315316, 4404.779761011937, 4432.578905004052, 4471.6199123074575, 4315.244832154392, 4321.351797230409, 4372.366006929009, 4407.515173330931, 4409.272470042439, 4736.265878788793, 4747.240441529373, 4765.1660959331975, 4788.401449818773, 4821.180399332122, 5031.748412579393, 5182.378114626231, 5221.138512145491, 5229.790808367661, 5231.424543300234, 5152.098382782998, 5247.790088396459, 5264.789408423659, 5266.210068489719, 5268.157094864803, 4847.830325938575, 4890.168671325883, 4890.657653964259, 4891.877703057452, 4903.87740306488, 5196.090684130262, 5258.421573626898, 5381.27352807366, 5385.353757549041, 5385.7576409061685, 5078.720670363092, 5123.436421993578, 5160.7419629018395, 5172.689638621695, 5172.715500647425, 5205.713685747321, 5222.582193424517, 5222.746540830173, 5272.130098533776, 5279.0, 5004.599632029429, 5084.49155084496, 5134.974325128425, 5149.330587023738, 5151.665141765773, 5116.499289790396, 5130.281760553526, 5143.434222235551, 5147.9189370231925, 5180.145276029489, 4315.848945286963, 4317.481902171759, 4317.56824317572, 4353.499347575006, 4365.345198220229, 3736.5142531470733, 3739.1586892949413, 3759.285735710261, 3770.5852356240794, 3795.2409518096492, 4431.468223813163, 4448.377227188196, 4458.375827384169, 4494.4606647202545, 4502.887427814331, 5218.086834803894, 5218.2955301034635, 5230.08473517133, 5243.685378877203, 5280.339957505312, 4354.477462704433, 4399.032212913218, 4405.713628614194, 4424.0709451015, 4443.888902777392, 4621.0, 4627.329037289642, 4627.791749371219, 4632.073585282957, 4659.184642687516, 4893.192623217201, 4912.508749125059, 4920.6555541111775, 4925.975370123269, 4965.553100220997, 5105.948940510547, 5157.638965272395, 5162.922556161736, 5202.0, 5221.738913054408, 4898.804047838046, 4902.730349830651, 4906.411230652345, 4920.458749537476, 4971.726555039363, 4956.752162391938, 5061.8734531637, 5100.897982040413, 5101.79592816298, 5111.361079865017, 5053.67151135175, 5142.1258386073705, 5150.0, 5153.974230128825, 5163.586913047023, 5012.924806127984, 5018.799248030038, 5024.371953505812, 5028.84913452597, 5070.771815268248, 4812.037592481448, 4945.1346014448045, 5022.221555658879, 5024.145895197898, 5031.0, 4915.483874193301, 4920.0, 4993.1012417763695, 5005.274235235943, 5009.148444764326, 5067.391912970467, 5080.212567052113, 5082.491750824888, 5083.847484575469, 5139.640225184351, 4777.474477807508, 4827.155247831545, 4850.708957462563, 4934.210526315772, 4942.481039490802], "value": 4739.673768974814, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [580.1066357808975, 580.9825705228843, 583.3379114704809, 590.8759160576279, 592.2803793391029, 27331.726682733166, 39268.80365598172, 41025.179496410055, 41138.58861411385, 45622.77188614052, 41695.791521042425, 42083.78958105213, 42259.0, 42293.788531057304, 42310.788446057806, 7529.32236098751, 7906.367490600753, 8081.515109093473, 8082.919170808304, 8092.433529652927, 7487.138979017401, 7720.227977202263, 7722.768316950501, 10139.138173255282, 10422.739431514197, 10442.791144177112, 10445.059944604984, 10492.108170805452, 10553.102986246178, 10554.577816887315, 10372.066514013737, 10474.214433917487, 10474.528646210976, 10480.737981550446, 10482.685519434353, 7481.0, 7689.423293252972, 7693.038370203724, 10135.695929122137, 10555.47222638865, 39223.41164882529, 39281.0, 39315.41026884599, 39561.20877582432, 39565.604343956766, 3041.1180757580496, 3172.6192856857324, 3683.981580092109, 3789.3747531657245, 3891.727579069466, 29605.0, 30108.397832043236, 30693.31186784726, 30811.38377232465, 30850.228744281347, 3450.3931111943093, 3627.2564124354444, 3666.651668091527, 3688.686461650763, 3722.5160729105314, 264567.80309280026, 265323.0, 265529.0, 265568.0, 266440.9999999981, 56026.15960760592, 56205.71897140488, 56271.0, 56530.43469565252, 56610.43389566051, 58544.7072764642, 58613.70693146507, 58634.12048819271, 58691.11963320553, 58720.0, 14442.783358249635, 15068.171250581323, 15105.3202605883, 15108.244587770567, 15121.470748523969, 10518.316309439862, 10596.523156457977, 10602.151827853753, 10603.469826508644, 10657.467126643636, 16697.91651041737, 17227.741583876254, 17313.91343043302, 17518.21168047441, 17595.56011099707, 5815.273090863642, 5972.343042265347, 5984.670843103671, 6001.549883758736, 6087.29996050451, 842.2966822703112, 927.8097989912052, 948.6110694615174, 970.9417434953925, 993.458565082032, 28573.28566785847, 29326.560101598065, 29402.264943376587, 29537.409251814843, 29584.408311833642, 928.6888892221046, 930.7207837648679, 956.3496822160997, 957.4590356448584, 1046.727850758793, 14354.569362919128, 15058.32237549313, 15251.0, 15253.729554850042, 15419.151946642818, 386824.0, 387509.0, 387757.0, 390327.0, 390418.0, 49823.25265121026, 49905.50094499081, 49936.0, 50070.499295007314, 50079.0, 1100.785346857355, 1148.6381789736206, 1237.8143278508296, 1244.2907542700755, 1252.2236213547658, 29451.263718407212, 30210.0, 30213.395732084795, 30350.0, 30675.53986690246, 43538.34692479679, 44047.0, 44077.77961110174, 44107.55892441035, 44132.558674412845, 300021.0, 306420.0, 306449.0, 308086.0, 308490.0, 22336.441588960406, 24879.253622391356, 25116.49767004686, 25279.494410112064, 25593.616095758953, 42380.0, 42632.147357053305, 42897.571024289355, 42994.78502607467, 43657.56342436535, 35809.0, 36411.45382819207, 36972.26055478821, 37062.814685926394, 37092.44361334636, 36532.0, 36597.08507287339, 36682.0, 37393.43909841409, 37426.625733742316, 36539.63460365362, 36785.81607091947, 36798.63201368059, 37035.62964370322, 37558.8122059388, 31338.843305783324, 33800.1549961253, 34097.14757131092, 34291.69469985816, 34361.31277374489, 25868.22395328143, 26005.739942600332, 26043.739562604133, 26179.0, 26275.474490510464], "value": 18226.722553998068, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [355.3585777671302, 360.34597206070964, 370.13757943990504, 377.3547234229467, 393.70668851705466, 16489.670206595874, 18201.544961375956, 18730.53173670659, 18804.905975470105, 18822.717659235106, 17894.910525447354, 18107.818921810776, 18742.0, 18759.0, 19372.90313548434, 5685.431456854323, 5956.195913551658, 5965.955957707405, 6020.759169633209, 6049.727762250687, 5580.107182850745, 5885.08781138922, 5895.64626122434, 5898.380670029628, 5903.026000709878, 5670.4896559309655, 5852.414758524156, 5862.677552734619, 5862.9413705863035, 5870.090136028901, 5931.881362372772, 5971.880562388749, 5993.580449368525, 6009.699515024231, 6016.308124565665, 5627.943720562783, 5784.4504772046585, 5795.0, 5817.650940943557, 5853.736581853785, 24019.639705404432, 24061.0, 24531.877340613362, 24700.752992470025, 24702.13542526021, 1955.3938279133515, 1966.2921348314596, 2011.939641810748, 2152.6340522111277, 2559.833610815291, 6691.698873550702, 6705.966470167667, 6727.529072964894, 14437.783433248509, 15389.374834714657, 1799.9460016199534, 1879.1825555883236, 1892.4606487151095, 1934.719465677483, 1934.8161924617139, 70928.29071709268, 80937.59531202365, 82050.0, 82684.58657706733, 83179.16820831836, 16424.0, 16610.335586576643, 16939.407120750715, 16964.406245781338, 18641.90679046596, 29070.563941540895, 29121.0, 29277.56083658747, 29363.706362936526, 29417.55873661897, 8467.788305292293, 8885.333599980027, 8928.642854285887, 9172.908270917338, 9188.632454701738, 7314.487985841047, 7331.596762178025, 7362.411007119417, 7498.962505187439, 7508.962455187799, 8312.667493300321, 8364.996200455984, 8583.755355473422, 8594.398392112493, 8712.647585151974, 3075.2926826829953, 3197.6322722887166, 3260.233845046412, 3330.6344398796377, 3365.158710322419, 767.9769606911801, 819.7458787775809, 845.7082306604211, 916.9587368568431, 932.6316105138433, 16789.41237056697, 17386.913065434594, 17633.911830440764, 17652.558686032695, 17983.64032719338, 857.4255248983196, 869.3132425383876, 883.9779005524784, 949.7435692363023, 953.1993125774383, 9316.161545460805, 9717.6598819041, 9775.804483910282, 9817.281975654232, 9822.508874556244, 395715.0, 405313.0, 408685.0, 411666.0, 413721.0, 80235.19764802277, 82077.0, 82380.0, 82524.0, 83624.16375836164, 821.5850995247362, 844.5566077809076, 844.6283635200615, 848.0, 848.7156802471242, 15122.319495622503, 15824.762628560811, 15901.920490397473, 16014.27935742871, 16080.758788618414, 38923.416148758355, 39202.80398597989, 39214.0, 39825.0, 40138.99652508711, 53037.0, 53908.460915391915, 54063.45936540584, 54417.45582544282, 54455.0, 7427.925720742724, 7540.245975402416, 7583.886241706268, 7677.692892284246, 7751.41864360164, 12142.514299428278, 12286.938565307117, 12289.754204915673, 12487.750244995232, 12539.435725392197, 10745.677629671123, 10763.730906727395, 10820.0, 10972.835407468734, 10996.395198264172, 10725.854829034068, 10850.348979061282, 10970.835437468604, 11067.114630829354, 11122.61070862516, 10765.138788897026, 10842.295250808673, 10852.348859068481, 10914.727131821768, 11093.778124437627, 7585.924140758521, 7602.657880395506, 7704.114026886979, 7711.614419278902, 7803.102643196105, 9928.354656947275, 10122.848157277795, 10270.07569318765, 10690.518926648476, 11055.0603198729], "value": 9309.492152051009, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1236.462138969549, 1330.753810545049, 1401.530487286759, 1413.6677880698035, 1733.349993752343, 6891.896621550681, 7811.570363629998, 7989.640466179021, 8023.959880200606, 8184.959075204631, 7969.6015199239955, 8091.069527004396, 8103.675852965875, 8105.432619716622, 8134.796630084236, 2216.5899308627877, 2240.8543444676043, 2244.2033078257227, 2249.403907964391, 2252.0203711385598, 2204.856684315522, 2222.6666000100035, 2250.5948929192737, 2256.9887150564223, 2259.5480903819303, 2220.31170337196, 2223.9332820015425, 2227.8106360959337, 2228.3649159989404, 2257.2099765082253, 2166.837487188467, 2174.315090746418, 2177.346795961213, 2183.148572056899, 2187.223535644839, 2193.429708275844, 2197.1870407949, 2199.747029091659, 2199.912003519857, 2201.6587428948537, 7967.163447838009, 8142.348612111017, 8188.181181881824, 8189.754307370788, 8194.385421093442, 4586.334981427708, 4761.785719642625, 4766.618670506351, 4780.163471392492, 4789.688670236458, 2756.6140740296373, 2827.533456979596, 2855.7572606328486, 2869.5552189410464, 2879.0930856780046, 875.6059773102066, 880.0231742765525, 895.6865097215988, 898.4250079948838, 900.5812297281796, 10838.620648277272, 10881.183911206692, 10912.454377281183, 10923.235373523856, 10944.726381840437, 3964.8810535683974, 4051.2707712611423, 4066.6543343815515, 4089.5092588889524, 4272.743635381825, 8578.0, 8625.223729864218, 8638.049814520398, 8645.91354086451, 8651.134886511298, 4497.437820272466, 4497.752623605667, 4499.167653984024, 4499.59503644667, 4501.279795232747, 3843.1545060086737, 3903.043754280163, 4026.57720939303, 4109.239790638681, 4164.5210800758305, 3464.082018265162, 3531.94702079469, 3567.625399333033, 3625.492431059654, 3629.1108678373967, 1016.7051555049101, 1050.7425680708122, 1054.920880933933, 1055.3562326980536, 1062.2670357453333, 772.4974923773319, 846.8687353460224, 853.3429259470297, 868.7133246028795, 880.4409200157818, 5129.384473863161, 5362.91955620666, 5398.865028374322, 5468.93355795624, 5478.369987451414, 383.3119550407026, 386.7196282695024, 387.76733959624, 396.33613697057314, 407.74515927545286, 3658.378075727133, 3709.239605880788, 3725.2363265530503, 3750.9684836670135, 3815.5802861685193, 9671.758206044764, 10002.49987500622, 10089.041541053588, 10101.444420556792, 10107.595696172219, 9918.30571859963, 10005.0, 10007.449590272605, 10014.198864090855, 10018.599256029676, 7534.434917381109, 7584.051993500812, 7603.315701586884, 7615.200403957505, 7625.466217364842, 5305.098133317422, 5306.522412982696, 5356.758945847524, 5476.479734425223, 5496.6701997880255, 8699.13008699125, 9060.275177985613, 9410.717678469657, 9428.481433521221, 9466.715998520056, 8495.915040849512, 9170.312226582899, 9304.208737378623, 9413.199878010446, 9632.9036709632, 6911.7926462206215, 7104.218535960937, 7125.429965602843, 7306.561606303639, 7312.81717957034, 7379.520331178456, 7604.8479030417975, 7740.612969351622, 7828.217178282126, 7839.490433121829, 7160.355567998905, 7166.498345115792, 7269.563826170447, 7321.707131714672, 7353.0, 6870.896936545856, 6934.063901373452, 6974.58152510851, 7032.964835175791, 7035.894461583182, 6477.514186435942, 6479.5788273762055, 6525.0, 6549.443297319781, 6561.737530498726, 4536.682432229844, 4853.199222128309, 4868.537488938687, 4879.438864530624, 4899.216125419915, 4742.810287588458, 4747.477777444409, 4800.543948324904, 4842.4673285939225, 4881.633877459134], "value": 4101.818406320987, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.64431504352297, 297.8614944051016, 309.10976388002575, 309.46926021872486, 311.34151270063813, 4879.341288925994, 4881.292212629167, 4892.412910450742, 4919.680220785646, 4938.777755001024, 4644.976775116128, 4818.3736114305175, 4838.129136755382, 4850.526231621871, 4852.830150944717, 3854.2098869731776, 3861.9227615447676, 3887.8056097195167, 3889.1638297765976, 3889.883303500885, 3863.787491687956, 3882.2429626222897, 3884.475595794574, 3889.105505733683, 3895.2404281165186, 3722.7394082414107, 3819.694424446038, 3840.3087444260022, 3865.1496670732536, 3876.4379165021055, 3800.277947190025, 3811.4473401356927, 3818.923621527582, 3856.2480316338047, 3857.9421308680394, 3380.5436266104007, 3382.2220889195623, 3394.2871996880685, 3396.1849156202334, 3420.990807711706, 4756.7859446325, 4799.608113647038, 4805.1590971579835, 4808.3989501312335, 4810.687305325143, 4310.719803212813, 4388.275934470808, 4392.824287028515, 4412.977935110336, 4427.535108813592, 5082.771275292621, 5089.465606111342, 5114.846554603368, 5119.513646203604, 5140.3060586820675, 3729.7202709796875, 3746.9063273418383, 3752.1745216052423, 3758.3986562149926, 3760.905977350561, 4989.90020199594, 5059.696418214882, 5234.7120908350025, 5373.543248823855, 5391.460853914617, 3791.905202369908, 3792.6776224020714, 3805.60041195676, 3812.694984401241, 3822.4075268333454, 3884.8446062157764, 3885.55316138642, 3886.1256217351433, 3886.358750806142, 3890.3386424307937, 4132.793360331972, 4179.310413781692, 4296.935545966813, 4308.67684923632, 4347.543507931685, 4980.800767969313, 4988.201887697951, 4991.650584459053, 5012.072766538202, 5101.209312556485, 1321.3327269728725, 1330.348129416579, 1339.0158233698223, 1339.8057281694294, 1345.724126554054, 844.4806444036996, 873.9257163141205, 876.9123087691307, 877.2368039805283, 885.1325700813111, 4448.466184057869, 4463.419755431773, 4469.888252793641, 4489.887752806206, 4503.256962601135, 5163.896722065613, 5190.8961820764125, 5210.270562121306, 5225.608079394061, 5233.738313084331, 4391.560843915583, 4456.420665313489, 4469.575390337913, 4484.573965473275, 4484.932726009112, 2784.7911406644585, 2786.275568352243, 2811.789115816281, 2819.4361127774528, 2819.4502072095743, 4919.188333924933, 4986.008394962993, 4990.750462476862, 4994.0, 4997.250412438163, 5184.818531351385, 5269.1832765921345, 5294.311739473844, 5389.299391079135, 5392.946070539323, 4897.583705385011, 4919.286703428054, 4951.356323677899, 4958.231474121517, 4969.229769385751, 5203.973980130227, 5209.791608335776, 5223.7910483580235, 5224.2947202127225, 5240.554552862897, 5002.424721157114, 5095.464976177596, 5134.0, 5191.404297851074, 5221.2690223368, 4976.353074100344, 5015.598752099896, 5016.598672106295, 5018.272350509229, 5019.84940451787, 3715.9038083764813, 3753.5683396409763, 3753.981230093832, 3756.286305601927, 3756.9336864724837, 5036.6726162799305, 5221.582273418041, 5237.397699264481, 5281.287026251407, 5286.259923610774, 5043.9243411348125, 5055.191169412877, 5063.3417655704525, 5084.41529224144, 5085.753990272407, 5049.57078648319, 5144.202648589475, 5150.459201783909, 5153.3815942087185, 5155.510226528474, 4931.186354251508, 4991.850244492671, 5040.218766091261, 5103.897922041464, 5104.3619547556555, 5106.7957281709805, 5109.795608175631, 5120.6159538035545, 5124.231365295086, 5130.0, 4993.575546078624, 5061.772220250171, 5064.46823083571, 5073.797048118182, 5077.009983053269], "value": 3867.1464917433887, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [604.7006731667825, 612.1216054961128, 642.5149012495566, 716.6380977606308, 727.505296398449, 11724.296542207474, 12603.495860165605, 12618.810717839227, 12632.0, 12635.0, 12662.43019064142, 13148.802767958488, 13207.141535800167, 13220.6694832629, 13278.46886124554, 761.402299195132, 766.0883548577203, 766.3409467857638, 770.6955752477768, 774.0247288416607, 766.478794419797, 778.8247644280049, 779.12737733738, 781.5584194929875, 781.6951388958311, 804.9275565199131, 808.514891065359, 808.9352851771844, 809.3929552835374, 809.8015986083418, 802.3541049455215, 802.4823988527426, 802.9799255018613, 811.4968719393954, 811.6347643560422, 604.6190899733184, 605.4157737783016, 605.6456972670993, 605.6578033411153, 606.0545548943662, 11857.347845868473, 11857.644270671894, 11897.464614092387, 11904.464299106561, 11906.0, 9511.191548718367, 9850.753731156707, 9942.751431214205, 9974.9501252494, 9993.80012399748, 9706.75733106678, 9819.165370943478, 9892.109710126051, 9957.701268961942, 9997.555000024893, 3162.7311678507353, 3201.2477067889026, 3210.5184222366597, 3296.4396052671104, 3321.4187517184637, 42223.788881055705, 52475.73762131203, 55142.9999999996, 55180.44819551793, 60437.39562604362, 10424.322419042737, 10682.51928663212, 10990.120790336754, 11039.172062095375, 11137.721556960978, 19382.70925936112, 19517.8048219516, 19522.414327570197, 19531.90234048849, 19702.423806095478, 7037.19072306681, 7109.964450177819, 7135.89296160558, 7257.745978890714, 7307.086614173229, 3592.084018575247, 3643.635636436335, 3664.853405863789, 3676.3750162472443, 3777.150141218259, 2954.3057381515328, 3095.659477457478, 3097.4579448596405, 3100.3179300553843, 3100.6589275179713, 534.5563182558467, 540.8161225183457, 544.4501053935475, 547.1683041776478, 548.2242626683278, 543.8721900353414, 570.9115087161497, 582.9854253643692, 613.2212090644945, 619.5074915442234, 8788.384813063152, 8805.471671699719, 8876.689315873913, 8954.194122529003, 8956.731298061068, 601.8736065426222, 607.1984979826675, 612.091044798474, 612.9448349648553, 614.0788816774838, 4007.278689835799, 4269.231538323132, 4297.441332626739, 4392.846250381222, 4453.109378124388, 107986.380204297, 108841.0, 110253.0, 111422.44288778504, 113347.43326283316, 98003.50998244963, 99082.0, 99855.50072249738, 100678.4966075165, 101012.49493752631, 47982.520174798505, 50983.49016509787, 57839.710801445726, 75119.62440187763, 78995.60502197567, 8231.711890083818, 8235.29999949987, 8250.092489826235, 8367.707130250412, 8462.0, 47603.0, 48273.5172648269, 48600.75699621479, 49068.0, 50093.0, 64389.678051609444, 65041.0, 69149.0, 69513.30486695068, 73663.0, 9934.950325248617, 9973.401595904268, 10060.496975151067, 10197.541110650187, 10316.123129534073, 11245.662630121109, 11347.943260283975, 11647.41762911868, 11679.0, 11920.52317907274, 10011.149052330633, 10225.437600931722, 10521.105706014769, 10737.785244294913, 11141.387223702774, 10807.891921080689, 11016.11871050298, 11031.613893513688, 11449.59926402572, 12021.038316934799, 11753.0, 11763.117766167743, 12074.818877716665, 12122.696932576757, 12504.499820007099, 10027.147692446224, 10358.482075896325, 10490.527926243185, 10511.684649460529, 10899.891001090205, 10070.949645251727, 10444.529996150039, 10641.787164256517, 10814.0, 10860.945695271474], "value": 6536.413020119124, "unit": "score"}], "name": "dromaeo_css", "value": 6609.830797770613, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 113.0, 111.0, 113.0, 114.0, 112.0, 112.0, 114.0, 112.0, 113.0], "value": 111.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 125.0, 122.0, 175.0, 122.0, 128.0, 150.0, 123.0, 157.0, 122.0], "value": 138.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 162.0, 161.0, 161.0, 161.0, 161.0, 162.0, 161.0, 161.0], "value": 161.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 78.0, 127.0, 76.0, 76.0, 76.0, 76.0, 109.0, 76.0], "value": 84.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 119.0, 85.0, 87.0, 86.0, 119.0, 85.0, 85.0, 132.0], "value": 96.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 118.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 147.0, 146.0, 146.0, 145.0, 146.0, 146.0, 146.0, 146.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 97.0, 95.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 64.0, 64.0, 65.0, 69.0, 64.0, 65.0, 65.0, 65.0], "value": 64.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 63.0, 65.0, 64.0, 65.0, 65.0, 65.0, 64.0, 64.0], "value": 64.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 77.0, 84.0, 81.0, 80.0, 76.0, 77.0, 86.0, 81.0], "value": 79.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 128.0, 136.0, 131.0, 129.0, 131.0, 137.0, 135.0, 177.0, 133.0], "value": 137.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 165.0, 165.0, 166.0, 168.0, 167.0, 167.0, 169.0, 166.0, 165.0], "value": 166.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 68.0, 67.0, 73.0, 74.0, 67.0, 67.0, 69.0, 66.0, 66.0], "value": 68.8, "unit": "score"}], "name": "kraken", "value": 1532.3999999999999, "alertThreshold": 2.0}]} 19:53:04 INFO - Return code: 0 19:53:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:53:04 INFO - # TBPL SUCCESS # 19:53:04 INFO - Running post-action listener: _resource_record_post_action 19:53:04 INFO - ##### 19:53:04 INFO - ##### Finished run-tests step (success) 19:53:04 INFO - ##### 19:53:04 INFO - Running post-run listener: _resource_record_post_run 19:53:04 INFO - Total resource usage - Wall time: 1197s; CPU: 13.0%; Read bytes: 4096; Write bytes: 552996864; Read time: 8; Write time: 509072 19:53:04 INFO - TinderboxPrint: CPU usage
13.0% 19:53:04 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 19:53:04 INFO - TinderboxPrint: I/O write bytes / time
552,996,864 / 509,072 19:53:04 INFO - TinderboxPrint: CPU idle
8,314.3 (87.0%) 19:53:04 INFO - TinderboxPrint: CPU user
1,199.2 (12.6%) 19:53:04 INFO - TinderboxPrint: Swap in / out
0 / 0 19:53:04 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 265293824; Read time: 0; Write time: 289536 19:53:04 INFO - run-tests - Wall time: 1181s; CPU: 13.0%; Read bytes: 4096; Write bytes: 287703040; Read time: 8; Write time: 219536 19:53:05 INFO - Running post-run listener: _upload_blobber_files 19:53:05 INFO - Blob upload gear active. 19:53:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:53:05 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 19:53:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:53:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:53:05 INFO - (blobuploader) - INFO - Open directory for files ... 19:53:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:53:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:53:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:53:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:53:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:53:06 INFO - (blobuploader) - INFO - Done attempting. 19:53:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:53:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:53:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:53:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:53:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:53:10 INFO - (blobuploader) - INFO - Done attempting. 19:53:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:53:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:53:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:53:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:53:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:53:11 INFO - (blobuploader) - INFO - Done attempting. 19:53:11 INFO - (blobuploader) - INFO - Iteration through files over. 19:53:11 INFO - Return code: 0 19:53:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:53:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:53:11 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4ea5884944bbc8d66ae50570edd3c8aa99eb0aa3d1c7d4337caf30c0ffb47d7eadb3ee087c6666a67c19a9357fd75b6ead80ad31fb7fe054888385aebdcef3f6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/26dad62d66563e44c0d1b20aeb7032081817f0a16b2cdcfc76d9fc3d94534582ceb64dff9320d9ff412a85bf73b58b4d7ac94cd35d83fbacb0dfb70372b44282", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dbe2739aa4e0a14faf5529f0c560c14f32a01034e651ec54e5dc1a6e55f14c96c27bb633981b73d0084d5bf608e6eff588fa8d76be0e017977a3b9ef3a0a1aa9"} 19:53:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:53:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:53:11 INFO - Contents: 19:53:11 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4ea5884944bbc8d66ae50570edd3c8aa99eb0aa3d1c7d4337caf30c0ffb47d7eadb3ee087c6666a67c19a9357fd75b6ead80ad31fb7fe054888385aebdcef3f6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/26dad62d66563e44c0d1b20aeb7032081817f0a16b2cdcfc76d9fc3d94534582ceb64dff9320d9ff412a85bf73b58b4d7ac94cd35d83fbacb0dfb70372b44282", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dbe2739aa4e0a14faf5529f0c560c14f32a01034e651ec54e5dc1a6e55f14c96c27bb633981b73d0084d5bf608e6eff588fa8d76be0e017977a3b9ef3a0a1aa9"} 19:53:11 INFO - Running post-run listener: copy_logs_to_upload_dir 19:53:11 INFO - Copying logs to upload dir... 19:53:11 INFO - mkdir: /builds/slave/test/build/upload/logs 19:53:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1236.754917 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-07-06 19:53:10.592212) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-06 19:53:10.595480) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1467857098.738187-1065211122 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4ea5884944bbc8d66ae50570edd3c8aa99eb0aa3d1c7d4337caf30c0ffb47d7eadb3ee087c6666a67c19a9357fd75b6ead80ad31fb7fe054888385aebdcef3f6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/26dad62d66563e44c0d1b20aeb7032081817f0a16b2cdcfc76d9fc3d94534582ceb64dff9320d9ff412a85bf73b58b4d7ac94cd35d83fbacb0dfb70372b44282", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dbe2739aa4e0a14faf5529f0c560c14f32a01034e651ec54e5dc1a6e55f14c96c27bb633981b73d0084d5bf608e6eff588fa8d76be0e017977a3b9ef3a0a1aa9"} build_url:https://queue.taskcluster.net/v1/task/dUsf8kqGRySJpX9iCJshYA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011423 build_url: 'https://queue.taskcluster.net/v1/task/dUsf8kqGRySJpX9iCJshYA/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/Firefox-Non-PGO/sha512/4ea5884944bbc8d66ae50570edd3c8aa99eb0aa3d1c7d4337caf30c0ffb47d7eadb3ee087c6666a67c19a9357fd75b6ead80ad31fb7fe054888385aebdcef3f6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/26dad62d66563e44c0d1b20aeb7032081817f0a16b2cdcfc76d9fc3d94534582ceb64dff9320d9ff412a85bf73b58b4d7ac94cd35d83fbacb0dfb70372b44282", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dbe2739aa4e0a14faf5529f0c560c14f32a01034e651ec54e5dc1a6e55f14c96c27bb633981b73d0084d5bf608e6eff588fa8d76be0e017977a3b9ef3a0a1aa9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-06 19:53:10.623163) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 19:53:10.623671) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1467857098.738187-1065211122 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004357 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 19:53:10.705200) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-06 19:53:10.705597) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-06 19:53:10.705897) ========= ========= Total master_lag: 0.18 =========