builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-036 starttime: 1482213720.23 results: success (0) buildid: 20161219202253 builduid: ddce2ca76a1d460dac2d69122129be1d revision: 171be6aaa16ee1d241ffa4bcdabc95b28372c28b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-19 22:02:00.227833) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-19 22:02:00.228304) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-19 22:02:00.228610) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-19 22:02:00.243371) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-19 22:02:00.243705) ========= 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=14ef55db36f7623abb31b9cf000001d5-1482213504.472085-960774253 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-19 22:02:00-- 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: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 37.6M=0s 2016-12-19 22:02:00 (37.6 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.355970 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-19 22:02:00.613402) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-19 22:02:00.613750) ========= 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=14ef55db36f7623abb31b9cf000001d5-1482213504.472085-960774253 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015029 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-19 22:02:00.663555) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-19 22:02:00.663878) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 171be6aaa16ee1d241ffa4bcdabc95b28372c28b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 171be6aaa16ee1d241ffa4bcdabc95b28372c28b --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=14ef55db36f7623abb31b9cf000001d5-1482213504.472085-960774253 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-19 22:02:01,020 truncating revision to first 12 chars 2016-12-19 22:02:01,020 Setting DEBUG logging. 2016-12-19 22:02:01,020 attempt 1/10 2016-12-19 22:02:01,020 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/171be6aaa16e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-19 22:02:01,321 unpacking tar archive at: mozilla-aurora-171be6aaa16e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.599250 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-19 22:02:01.276045) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-19 22:02:01.276357) ========= script_repo_revision: 171be6aaa16ee1d241ffa4bcdabc95b28372c28b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-19 22:02:01.276806) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-19 22:02:01.277092) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-19 22:02:01.285725) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-12-19 22:02:01.286059) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=14ef55db36f7623abb31b9cf000001d5-1482213504.472085-960774253 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:02:01 INFO - MultiFileLogger online at 20161219 22:02:01 in /builds/slave/test 22:02:01 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 22:02:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:02:01 INFO - {'append_to_log': False, 22:02:01 INFO - 'base_work_dir': '/builds/slave/test', 22:02:01 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 22:02:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:02:01 INFO - 'branch': 'Mozilla-Aurora', 22:02:01 INFO - 'buildbot_json_path': 'buildprops.json', 22:02:01 INFO - 'config_files': ('talos/linux_config.py',), 22:02:01 INFO - 'default_actions': ('clobber', 22:02:01 INFO - 'read-buildbot-config', 22:02:01 INFO - 'download-and-extract', 22:02:01 INFO - 'populate-webroot', 22:02:01 INFO - 'create-virtualenv', 22:02:01 INFO - 'install', 22:02:01 INFO - 'run-tests'), 22:02:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:02:01 INFO - 'download_minidump_stackwalk': True, 22:02:01 INFO - 'download_symbols': 'ondemand', 22:02:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:02:01 INFO - 'tooltool.py': '/tools/tooltool.py', 22:02:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:02:01 INFO - '/tools/misc-python/virtualenv.py')}, 22:02:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:02:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:02:01 INFO - 'installer_path': 'installer.exe', 22:02:01 INFO - 'log_level': 'info', 22:02:01 INFO - 'log_name': 'talos', 22:02:01 INFO - 'log_to_console': True, 22:02:01 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:02:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:02:01 INFO - 'opt_config_files': (), 22:02:01 INFO - 'pip_index': False, 22:02:01 INFO - 'sps_profile': False, 22:02:01 INFO - 'sps_profile_interval': 0, 22:02:01 INFO - 'suite': 'dromaeojs', 22:02:01 INFO - 'system_bits': '32', 22:02:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:02:01 INFO - 'title': 'talos-linux64-ix-036', 22:02:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:02:01 INFO - 'use_talos_json': True, 22:02:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:02:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:02:01 INFO - 'work_dir': 'build'} 22:02:01 INFO - [mozharness: 2016-12-20 06:02:01.734670Z] Running clobber step. 22:02:01 INFO - Running pre-action listener: _resource_record_pre_action 22:02:01 INFO - Running main action method: clobber 22:02:01 INFO - rmtree: /builds/slave/test/build 22:02:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:02:02 INFO - Running post-action listener: _resource_record_post_action 22:02:02 INFO - [mozharness: 2016-12-20 06:02:02.199007Z] Finished clobber step (success) 22:02:02 INFO - [mozharness: 2016-12-20 06:02:02.199108Z] Running read-buildbot-config step. 22:02:02 INFO - Running pre-action listener: _resource_record_pre_action 22:02:02 INFO - Running main action method: read_buildbot_config 22:02:02 INFO - Using buildbot properties: 22:02:02 INFO - { 22:02:02 INFO - "project": "", 22:02:02 INFO - "product": "firefox", 22:02:02 INFO - "script_repo_revision": "production", 22:02:02 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 22:02:02 INFO - "repository": "", 22:02:02 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 22:02:02 INFO - "buildid": "20161219202253", 22:02:02 INFO - "pgo_build": "True", 22:02:02 INFO - "basedir": "/builds/slave/test", 22:02:02 INFO - "buildnumber": 650, 22:02:02 INFO - "slavename": "talos-linux64-ix-036", 22:02:02 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 22:02:02 INFO - "platform": "ubuntu64_hw", 22:02:02 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 22:02:02 INFO - "branch": "mozilla-aurora", 22:02:02 INFO - "repo_path": "releases/mozilla-aurora", 22:02:02 INFO - "revision": "171be6aaa16ee1d241ffa4bcdabc95b28372c28b", 22:02:02 INFO - "stage_platform": "linux64-pgo", 22:02:02 INFO - "builduid": "ddce2ca76a1d460dac2d69122129be1d", 22:02:02 INFO - "slavebuilddir": "test" 22:02:02 INFO - } 22:02:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/eHzeF_F7QtqJx1Jdri-D4g/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2. 22:02:02 INFO - Running post-action listener: _resource_record_post_action 22:02:02 INFO - [mozharness: 2016-12-20 06:02:02.201535Z] Finished read-buildbot-config step (success) 22:02:02 INFO - [mozharness: 2016-12-20 06:02:02.201624Z] Running download-and-extract step. 22:02:02 INFO - Running pre-action listener: _resource_record_pre_action 22:02:02 INFO - Running main action method: download_and_extract 22:02:02 INFO - mkdir: /builds/slave/test/build/tests 22:02:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:02:02 INFO - trying https://queue.taskcluster.net/v1/task/eHzeF_F7QtqJx1Jdri-D4g/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.test_packages.json 22:02:02 INFO - Downloading https://queue.taskcluster.net/v1/task/eHzeF_F7QtqJx1Jdri-D4g/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.test_packages.json 22:02:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eHzeF_F7QtqJx1Jdri-D4g/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 22:02:02 INFO - Downloaded 1361 bytes. 22:02:02 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.test_packages.json 22:02:02 INFO - Using the following test package requirements: 22:02:02 INFO - {u'common': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip'], 22:02:02 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 22:02:02 INFO - u'firefox-52.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 22:02:02 INFO - u'gtest': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 22:02:02 INFO - u'firefox-52.0a2.en-US.linux-x86_64.gtest.tests.zip'], 22:02:02 INFO - u'jittest': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 22:02:02 INFO - u'jsshell-linux-x86_64.zip'], 22:02:02 INFO - u'mochitest': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 22:02:02 INFO - u'firefox-52.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 22:02:02 INFO - u'mozbase': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip'], 22:02:02 INFO - u'reftest': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 22:02:02 INFO - u'firefox-52.0a2.en-US.linux-x86_64.reftest.tests.zip'], 22:02:02 INFO - u'talos': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 22:02:02 INFO - u'firefox-52.0a2.en-US.linux-x86_64.talos.tests.zip'], 22:02:02 INFO - u'web-platform': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 22:02:02 INFO - u'firefox-52.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 22:02:02 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 22:02:02 INFO - u'firefox-52.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 22:02:02 INFO - Downloading packages: [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:02:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eHzeF_F7QtqJx1Jdri-D4g/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.common.tests.zip 22:02:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eHzeF_F7QtqJx1Jdri-D4g/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:02:02 INFO - Fetch https://queue.taskcluster.net/v1/task/eHzeF_F7QtqJx1Jdri-D4g/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.common.tests.zip into memory 22:02:03 INFO - Http code: 200 22:02:03 INFO - via: 1.1 66ed69e8b47ad05050331602c798132f.cloudfront.net (CloudFront) 22:02:03 INFO - x-amz-cf-id: 7jxB1qQ4fnTsnKglBRc5-QeJaOUtJX-ZPAPHXTNUCnDyhCItC-hUWw== 22:02:03 INFO - x-amz-version-id: cI27WYliMsyIssiW30XONLZoUXG1UdSD 22:02:03 INFO - Expected file size: 21940786 22:02:03 INFO - Obtained file size: 21940786 22:02:07 INFO - Downloading packages: [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-52.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 22:02:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eHzeF_F7QtqJx1Jdri-D4g/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.common.tests.zip 22:02:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eHzeF_F7QtqJx1Jdri-D4g/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:02:07 INFO - Fetch https://queue.taskcluster.net/v1/task/eHzeF_F7QtqJx1Jdri-D4g/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.common.tests.zip into memory 22:02:08 INFO - Http code: 200 22:02:08 INFO - via: 1.1 e1eb7447ab46f530f6009f1d93917c54.cloudfront.net (CloudFront) 22:02:08 INFO - x-amz-cf-id: VChqkN9pfdEtdUIsnWkGsWtT_j3qLBx2DkW8WxB1MkvXPsXixQug1w== 22:02:08 INFO - x-amz-version-id: cI27WYliMsyIssiW30XONLZoUXG1UdSD 22:02:09 INFO - Expected file size: 21940786 22:02:09 INFO - Obtained file size: 21940786 22:02:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eHzeF_F7QtqJx1Jdri-D4g/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.talos.tests.zip 22:02:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eHzeF_F7QtqJx1Jdri-D4g/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 22:02:13 INFO - Fetch https://queue.taskcluster.net/v1/task/eHzeF_F7QtqJx1Jdri-D4g/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.talos.tests.zip into memory 22:02:14 INFO - Http code: 200 22:02:14 INFO - via: 1.1 ae162f6796e551002447afd7c07ec67a.cloudfront.net (CloudFront) 22:02:14 INFO - x-amz-cf-id: mZ-fSb84qgR3nLztfFMSau4q0NVmZrYgP-D6Gs_NARztuStcr7yxfw== 22:02:14 INFO - x-amz-version-id: t2wR8W8BfwPNfnoNqHTHmsiECpAa5N.Y 22:02:15 INFO - Expected file size: 14409038 22:02:15 INFO - Obtained file size: 14409038 22:02:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:02:15 INFO - trying https://queue.taskcluster.net/v1/task/eHzeF_F7QtqJx1Jdri-D4g/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 22:02:15 INFO - Downloading https://queue.taskcluster.net/v1/task/eHzeF_F7QtqJx1Jdri-D4g/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 22:02:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eHzeF_F7QtqJx1Jdri-D4g/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 22:02:17 INFO - Downloaded 63942889 bytes. 22:02:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eHzeF_F7QtqJx1Jdri-D4g/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 22:02:17 INFO - mkdir: /builds/slave/test/properties 22:02:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:02:17 INFO - Writing to file /builds/slave/test/properties/build_url 22:02:17 INFO - Contents: 22:02:17 INFO - build_url:https://queue.taskcluster.net/v1/task/eHzeF_F7QtqJx1Jdri-D4g/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 22:02:18 INFO - Running post-action listener: _resource_record_post_action 22:02:18 INFO - Running post-action listener: set_extra_try_arguments 22:02:18 INFO - [mozharness: 2016-12-20 06:02:18.611481Z] Finished download-and-extract step (success) 22:02:18 INFO - [mozharness: 2016-12-20 06:02:18.611707Z] Running populate-webroot step. 22:02:18 INFO - Running pre-action listener: _resource_record_pre_action 22:02:18 INFO - Running main action method: populate_webroot 22:02:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:02:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:02:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:02:18 INFO - '--tppagecycles', 22:02:18 INFO - '7'], 22:02:18 INFO - 'tests': ['tsvgm']}}, 22:02:18 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:02:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:02:18 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:02:18 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:02:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:02:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:02:18 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:02:18 INFO - 'talos_options': ['--disable-e10s'], 22:02:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:02:18 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:02:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:02:18 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:02:18 INFO - 'talos_options': ['--disable-e10s'], 22:02:18 INFO - 'tests': ['damp', 'tps']}, 22:02:18 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:02:18 INFO - 'tests': ['damp', 'tps']}, 22:02:18 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:02:18 INFO - 'tests': ['dromaeo_dom']}, 22:02:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:02:18 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:02:18 INFO - 'tests': ['basic_compositor_video']}, 22:02:18 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:02:18 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:02:18 INFO - 'tests': ['a11yr', 22:02:18 INFO - 'ts_paint', 22:02:18 INFO - 'tpaint', 22:02:18 INFO - 'sessionrestore', 22:02:18 INFO - 'sessionrestore_no_auto_restore', 22:02:18 INFO - 'tabpaint']}, 22:02:18 INFO - 'other-e10s': {'tests': ['a11yr', 22:02:18 INFO - 'ts_paint', 22:02:18 INFO - 'tpaint', 22:02:18 INFO - 'sessionrestore', 22:02:18 INFO - 'sessionrestore_no_auto_restore', 22:02:18 INFO - 'tabpaint']}, 22:02:18 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:02:18 INFO - 'ts_paint', 22:02:18 INFO - 'tpaint', 22:02:18 INFO - 'sessionrestore', 22:02:18 INFO - 'sessionrestore_no_auto_restore', 22:02:18 INFO - 'tabpaint']}, 22:02:18 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:02:18 INFO - 'ts_paint', 22:02:18 INFO - 'tpaint', 22:02:18 INFO - 'sessionrestore', 22:02:18 INFO - 'sessionrestore_no_auto_restore', 22:02:18 INFO - 'tabpaint']}, 22:02:18 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:02:18 INFO - 'tests': ['a11yr', 22:02:18 INFO - 'ts_paint', 22:02:18 INFO - 'tpaint', 22:02:18 INFO - 'sessionrestore', 22:02:18 INFO - 'sessionrestore_no_auto_restore', 22:02:18 INFO - 'tabpaint']}, 22:02:18 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:02:18 INFO - 'tests': ['a11yr', 22:02:18 INFO - 'ts_paint', 22:02:18 INFO - 'tpaint', 22:02:18 INFO - 'sessionrestore', 22:02:18 INFO - 'sessionrestore_no_auto_restore', 22:02:18 INFO - 'tabpaint']}, 22:02:18 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:02:18 INFO - 'tests': ['tsvgx', 22:02:18 INFO - 'tsvgr_opacity', 22:02:18 INFO - 'tart', 22:02:18 INFO - 'tscrollx', 22:02:18 INFO - 'cart']}, 22:02:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:02:18 INFO - 'tsvgr_opacity', 22:02:18 INFO - 'tart', 22:02:18 INFO - 'tscrollx', 22:02:18 INFO - 'cart']}, 22:02:18 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:02:18 INFO - 'talos_options': ['--disable-e10s'], 22:02:18 INFO - 'tests': ['tp5o']}, 22:02:18 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:02:18 INFO - 'tests': ['tp5o']}, 22:02:18 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:02:18 INFO - 'talos_options': ['--disable-e10s', 22:02:18 INFO - '--xperf_path', 22:02:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:02:18 INFO - 'tests': ['tp5n']}, 22:02:18 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:02:18 INFO - 'talos_options': ['--xperf_path', 22:02:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:02:18 INFO - 'tests': ['tp5n']}}, 22:02:18 INFO - 'talos.zip': {'path': '', 22:02:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:02:18 INFO - Running post-action listener: _resource_record_post_action 22:02:18 INFO - [mozharness: 2016-12-20 06:02:18.634108Z] Finished populate-webroot step (success) 22:02:18 INFO - [mozharness: 2016-12-20 06:02:18.634201Z] Running create-virtualenv step. 22:02:18 INFO - Running pre-action listener: _resource_record_pre_action 22:02:18 INFO - Running main action method: create_virtualenv 22:02:18 INFO - Creating virtualenv /builds/slave/test/build/venv 22:02:18 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 22:02:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:02:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:02:18 INFO - Using real prefix '/usr' 22:02:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:02:19 INFO - Installing distribute.............................................................................................................................................................................................done. 22:02:21 INFO - Installing pip.................done. 22:02:21 INFO - Return code: 0 22:02:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 22:02:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 22:02:21 INFO - Reading from file tmpfile_stdout 22:02:21 INFO - Output received: 22:02:21 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 22:02:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:02:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:02:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:02:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:02:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:02:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:02:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:02:21 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 0x7f248746ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27db8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28a8ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a5f60>, '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 0x28a6d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28a71d0>, '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': '14ef55db36f7623abb31b9cf000001d5-1482213504.472085-960774253', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:02:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:02:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:02:21 INFO - Using env: {'DISPLAY': ':0', 22:02:21 INFO - 'HOME': '/home/cltbld', 22:02:21 INFO - 'LANG': 'en_US.UTF-8', 22:02:21 INFO - 'LANGUAGE': 'en_US:en', 22:02:21 INFO - 'LOGNAME': 'cltbld', 22:02:21 INFO - 'MAIL': '/var/mail/cltbld', 22:02:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:02:21 INFO - 'MOZ_NO_REMOTE': '1', 22:02:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:02:21 INFO - 'NO_EM_RESTART': '1', 22:02:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:02:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:02:21 INFO - 'PWD': '/builds/slave/test', 22:02:21 INFO - 'SHELL': '/bin/bash', 22:02:21 INFO - 'SHLVL': '1', 22:02:21 INFO - 'TERM': 'linux', 22:02:21 INFO - 'TMOUT': '86400', 22:02:21 INFO - 'USER': 'cltbld', 22:02:21 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1482213504.472085-960774253', 22:02:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:02:21 INFO - '_': '/tools/buildbot/bin/python'} 22:02:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:02:21 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 22:02:21 INFO - Cleaning up... 22:02:21 INFO - Return code: 0 22:02:21 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:02:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:02:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:02:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:02:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:02:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:02:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:02:21 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 0x7f248746ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27db8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28a8ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a5f60>, '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 0x28a6d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28a71d0>, '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': '14ef55db36f7623abb31b9cf000001d5-1482213504.472085-960774253', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:02:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 22:02:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 22:02:21 INFO - Using env: {'DISPLAY': ':0', 22:02:21 INFO - 'HOME': '/home/cltbld', 22:02:21 INFO - 'LANG': 'en_US.UTF-8', 22:02:21 INFO - 'LANGUAGE': 'en_US:en', 22:02:21 INFO - 'LOGNAME': 'cltbld', 22:02:21 INFO - 'MAIL': '/var/mail/cltbld', 22:02:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:02:21 INFO - 'MOZ_NO_REMOTE': '1', 22:02:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:02:21 INFO - 'NO_EM_RESTART': '1', 22:02:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:02:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:02:21 INFO - 'PWD': '/builds/slave/test', 22:02:21 INFO - 'SHELL': '/bin/bash', 22:02:21 INFO - 'SHLVL': '1', 22:02:21 INFO - 'TERM': 'linux', 22:02:21 INFO - 'TMOUT': '86400', 22:02:21 INFO - 'USER': 'cltbld', 22:02:21 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1482213504.472085-960774253', 22:02:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:02:21 INFO - '_': '/tools/buildbot/bin/python'} 22:02:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:02:21 INFO - Downloading/unpacking psutil>=3.1.1 22:02:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:02:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:02:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:02:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:02:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:02:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:02:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:02:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:02:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:02:23 INFO - Installing collected packages: psutil 22:02:23 INFO - Running setup.py install for psutil 22:02:24 INFO - building 'psutil._psutil_linux' extension 22:02:24 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 22:02:24 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 22:02:24 INFO - building 'psutil._psutil_posix' extension 22:02:24 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 22:02:24 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 22:02:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:02:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:02:24 INFO - Successfully installed psutil 22:02:24 INFO - Cleaning up... 22:02:24 INFO - Return code: 0 22:02:24 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:02:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:02:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:02:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:02:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:02:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:02:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:02:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f248746ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27db8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28a8ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a5f60>, '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 0x28a6d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28a71d0>, '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': '14ef55db36f7623abb31b9cf000001d5-1482213504.472085-960774253', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:02:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 22:02:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 22:02:24 INFO - Using env: {'DISPLAY': ':0', 22:02:24 INFO - 'HOME': '/home/cltbld', 22:02:24 INFO - 'LANG': 'en_US.UTF-8', 22:02:24 INFO - 'LANGUAGE': 'en_US:en', 22:02:24 INFO - 'LOGNAME': 'cltbld', 22:02:24 INFO - 'MAIL': '/var/mail/cltbld', 22:02:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:02:24 INFO - 'MOZ_NO_REMOTE': '1', 22:02:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:02:24 INFO - 'NO_EM_RESTART': '1', 22:02:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:02:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:02:24 INFO - 'PWD': '/builds/slave/test', 22:02:24 INFO - 'SHELL': '/bin/bash', 22:02:24 INFO - 'SHLVL': '1', 22:02:24 INFO - 'TERM': 'linux', 22:02:24 INFO - 'TMOUT': '86400', 22:02:24 INFO - 'USER': 'cltbld', 22:02:24 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1482213504.472085-960774253', 22:02:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:02:24 INFO - '_': '/tools/buildbot/bin/python'} 22:02:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:02:24 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:02:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:02:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:02:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:02:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:02:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:02:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:02:26 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:02:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:02:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:02:26 INFO - Installing collected packages: mozsystemmonitor 22:02:26 INFO - Running setup.py install for mozsystemmonitor 22:02:26 INFO - Successfully installed mozsystemmonitor 22:02:26 INFO - Cleaning up... 22:02:26 INFO - Return code: 0 22:02:26 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:02:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:02:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:02:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:02:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:02:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:02:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:02:26 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 0x7f248746ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27db8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28a8ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a5f60>, '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 0x28a6d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28a71d0>, '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': '14ef55db36f7623abb31b9cf000001d5-1482213504.472085-960774253', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:02:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 22:02:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 22:02:26 INFO - Using env: {'DISPLAY': ':0', 22:02:26 INFO - 'HOME': '/home/cltbld', 22:02:26 INFO - 'LANG': 'en_US.UTF-8', 22:02:26 INFO - 'LANGUAGE': 'en_US:en', 22:02:26 INFO - 'LOGNAME': 'cltbld', 22:02:26 INFO - 'MAIL': '/var/mail/cltbld', 22:02:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:02:26 INFO - 'MOZ_NO_REMOTE': '1', 22:02:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:02:26 INFO - 'NO_EM_RESTART': '1', 22:02:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:02:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:02:26 INFO - 'PWD': '/builds/slave/test', 22:02:26 INFO - 'SHELL': '/bin/bash', 22:02:26 INFO - 'SHLVL': '1', 22:02:26 INFO - 'TERM': 'linux', 22:02:26 INFO - 'TMOUT': '86400', 22:02:26 INFO - 'USER': 'cltbld', 22:02:26 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1482213504.472085-960774253', 22:02:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:02:26 INFO - '_': '/tools/buildbot/bin/python'} 22:02:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:02:27 INFO - Downloading/unpacking jsonschema==2.5.1 22:02:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:02:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:02:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:02:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:02:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:02:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:02:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:02:29 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:02:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:02:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:02:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:02:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:02:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:02:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:02:29 INFO - Downloading functools32-3.2.3-2.tar.gz 22:02:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:02:29 INFO - warning: no files found matching '*.txt' 22:02:29 INFO - no previously-included directories found matching 'build' 22:02:29 INFO - no previously-included directories found matching 'dist' 22:02:29 INFO - no previously-included directories found matching '.git*' 22:02:29 INFO - Installing collected packages: jsonschema, functools32 22:02:29 INFO - Running setup.py install for functools32 22:02:29 INFO - warning: no files found matching '*.txt' 22:02:29 INFO - no previously-included directories found matching 'build' 22:02:29 INFO - no previously-included directories found matching 'dist' 22:02:29 INFO - no previously-included directories found matching '.git*' 22:02:29 INFO - Successfully installed jsonschema functools32 22:02:29 INFO - Cleaning up... 22:02:29 INFO - Return code: 0 22:02:29 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:02:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:02:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:02:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:02:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:02:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:02:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:02:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f248746ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27db8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28a8ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a5f60>, '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 0x28a6d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28a71d0>, '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': '14ef55db36f7623abb31b9cf000001d5-1482213504.472085-960774253', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:02:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 22:02:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 22:02:29 INFO - Using env: {'DISPLAY': ':0', 22:02:29 INFO - 'HOME': '/home/cltbld', 22:02:29 INFO - 'LANG': 'en_US.UTF-8', 22:02:29 INFO - 'LANGUAGE': 'en_US:en', 22:02:29 INFO - 'LOGNAME': 'cltbld', 22:02:29 INFO - 'MAIL': '/var/mail/cltbld', 22:02:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:02:29 INFO - 'MOZ_NO_REMOTE': '1', 22:02:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:02:29 INFO - 'NO_EM_RESTART': '1', 22:02:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:02:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:02:29 INFO - 'PWD': '/builds/slave/test', 22:02:29 INFO - 'SHELL': '/bin/bash', 22:02:29 INFO - 'SHLVL': '1', 22:02:29 INFO - 'TERM': 'linux', 22:02:29 INFO - 'TMOUT': '86400', 22:02:29 INFO - 'USER': 'cltbld', 22:02:29 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1482213504.472085-960774253', 22:02:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:02:29 INFO - '_': '/tools/buildbot/bin/python'} 22:02:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:02:29 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:02:29 INFO - Cleaning up... 22:02:29 INFO - Return code: 0 22:02:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:02:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:02:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:02:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:02:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:02:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:02:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:02:29 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 0x7f248746ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27db8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28a8ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a5f60>, '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 0x28a6d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28a71d0>, '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': '14ef55db36f7623abb31b9cf000001d5-1482213504.472085-960774253', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:02:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:02:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:02:29 INFO - Using env: {'DISPLAY': ':0', 22:02:29 INFO - 'HOME': '/home/cltbld', 22:02:29 INFO - 'LANG': 'en_US.UTF-8', 22:02:29 INFO - 'LANGUAGE': 'en_US:en', 22:02:29 INFO - 'LOGNAME': 'cltbld', 22:02:29 INFO - 'MAIL': '/var/mail/cltbld', 22:02:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:02:29 INFO - 'MOZ_NO_REMOTE': '1', 22:02:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:02:29 INFO - 'NO_EM_RESTART': '1', 22:02:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:02:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:02:29 INFO - 'PWD': '/builds/slave/test', 22:02:29 INFO - 'SHELL': '/bin/bash', 22:02:29 INFO - 'SHLVL': '1', 22:02:29 INFO - 'TERM': 'linux', 22:02:29 INFO - 'TMOUT': '86400', 22:02:29 INFO - 'USER': 'cltbld', 22:02:29 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1482213504.472085-960774253', 22:02:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:02:29 INFO - '_': '/tools/buildbot/bin/python'} 22:02:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:02:30 INFO - Downloading/unpacking blobuploader==1.2.4 22:02:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:02:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:02:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:02:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:02:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:02:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:02:32 INFO - Downloading blobuploader-1.2.4.tar.gz 22:02:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:02:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:02:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:02:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:02:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:02:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:02:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:02:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:02:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:02:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:02:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:02:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:02:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:02:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:02:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:02:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:02:32 INFO - Downloading docopt-0.6.1.tar.gz 22:02:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:02:32 INFO - Installing collected packages: blobuploader, requests, docopt 22:02:32 INFO - Running setup.py install for blobuploader 22:02:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:02:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:02:32 INFO - Running setup.py install for requests 22:02:33 INFO - Running setup.py install for docopt 22:02:33 INFO - Successfully installed blobuploader requests docopt 22:02:33 INFO - Cleaning up... 22:02:33 INFO - Return code: 0 22:02:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:02:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:02:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:02:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:02:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:02:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:02:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:02:33 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 0x7f248746ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27db8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28a8ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a5f60>, '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 0x28a6d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28a71d0>, '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': '14ef55db36f7623abb31b9cf000001d5-1482213504.472085-960774253', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:02:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:02:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:02:33 INFO - Using env: {'DISPLAY': ':0', 22:02:33 INFO - 'HOME': '/home/cltbld', 22:02:33 INFO - 'LANG': 'en_US.UTF-8', 22:02:33 INFO - 'LANGUAGE': 'en_US:en', 22:02:33 INFO - 'LOGNAME': 'cltbld', 22:02:33 INFO - 'MAIL': '/var/mail/cltbld', 22:02:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:02:33 INFO - 'MOZ_NO_REMOTE': '1', 22:02:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:02:33 INFO - 'NO_EM_RESTART': '1', 22:02:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:02:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:02:33 INFO - 'PWD': '/builds/slave/test', 22:02:33 INFO - 'SHELL': '/bin/bash', 22:02:33 INFO - 'SHLVL': '1', 22:02:33 INFO - 'TERM': 'linux', 22:02:33 INFO - 'TMOUT': '86400', 22:02:33 INFO - 'USER': 'cltbld', 22:02:33 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1482213504.472085-960774253', 22:02:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:02:33 INFO - '_': '/tools/buildbot/bin/python'} 22:02:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:02:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:02:33 INFO - Running setup.py (path:/tmp/pip-OjuGHG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:02:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:02:33 INFO - Running setup.py (path:/tmp/pip-ocebAW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:02:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:02:33 INFO - Running setup.py (path:/tmp/pip-IxDZWC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:02:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:02:33 INFO - Running setup.py (path:/tmp/pip-IQdqCh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:02:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:02:33 INFO - Running setup.py (path:/tmp/pip-aaGKlo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:02:34 INFO - Running setup.py (path:/tmp/pip-DZNaxr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:02:34 INFO - Running setup.py (path:/tmp/pip-WvwWUR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:02:34 INFO - Running setup.py (path:/tmp/pip-oSYqt0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:02:34 INFO - Running setup.py (path:/tmp/pip-4FvWJh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:02:34 INFO - Running setup.py (path:/tmp/pip-3HOUeH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:02:34 INFO - Running setup.py (path:/tmp/pip-EQVwZ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:02:34 INFO - Running setup.py (path:/tmp/pip-akjj4N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:02:34 INFO - Running setup.py (path:/tmp/pip-hOtiMT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:02:34 INFO - Running setup.py (path:/tmp/pip-eN0CsJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:02:34 INFO - Running setup.py (path:/tmp/pip-RfXnBJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:02:34 INFO - Running setup.py (path:/tmp/pip-8wej1t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:02:34 INFO - Running setup.py (path:/tmp/pip-l92MJx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:02:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:02:34 INFO - Running setup.py install for manifestparser 22:02:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:02:35 INFO - Running setup.py install for mozcrash 22:02:35 INFO - Running setup.py install for mozdebug 22:02:35 INFO - Running setup.py install for mozdevice 22:02:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:02:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:02:35 INFO - Running setup.py install for mozfile 22:02:35 INFO - Running setup.py install for mozhttpd 22:02:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:02:35 INFO - Running setup.py install for mozinfo 22:02:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:02:35 INFO - Running setup.py install for mozInstall 22:02:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:02:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:02:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:02:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:02:35 INFO - Running setup.py install for mozleak 22:02:35 INFO - Running setup.py install for mozlog 22:02:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:02:35 INFO - Running setup.py install for moznetwork 22:02:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:02:36 INFO - Running setup.py install for mozprocess 22:02:36 INFO - Running setup.py install for mozprofile 22:02:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:02:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:02:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:02:36 INFO - Running setup.py install for mozrunner 22:02:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:02:36 INFO - Running setup.py install for mozscreenshot 22:02:36 INFO - Running setup.py install for moztest 22:02:36 INFO - Running setup.py install for mozversion 22:02:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:02:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:02:36 INFO - Cleaning up... 22:02:36 INFO - Return code: 0 22:02:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:02: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:02:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:02:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:02: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:02:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:02:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:02:36 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 0x7f248746ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27db8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28a8ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a5f60>, '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 0x28a6d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28a71d0>, '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': '14ef55db36f7623abb31b9cf000001d5-1482213504.472085-960774253', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:02:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:02:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:02:36 INFO - Using env: {'DISPLAY': ':0', 22:02:36 INFO - 'HOME': '/home/cltbld', 22:02:36 INFO - 'LANG': 'en_US.UTF-8', 22:02:36 INFO - 'LANGUAGE': 'en_US:en', 22:02:36 INFO - 'LOGNAME': 'cltbld', 22:02:36 INFO - 'MAIL': '/var/mail/cltbld', 22:02:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:02:36 INFO - 'MOZ_NO_REMOTE': '1', 22:02:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:02:36 INFO - 'NO_EM_RESTART': '1', 22:02:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:02:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:02:36 INFO - 'PWD': '/builds/slave/test', 22:02:36 INFO - 'SHELL': '/bin/bash', 22:02:36 INFO - 'SHLVL': '1', 22:02:36 INFO - 'TERM': 'linux', 22:02:36 INFO - 'TMOUT': '86400', 22:02:36 INFO - 'USER': 'cltbld', 22:02:36 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1482213504.472085-960774253', 22:02:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:02:36 INFO - '_': '/tools/buildbot/bin/python'} 22:02:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:02:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:02:36 INFO - Running setup.py (path:/tmp/pip-EKHfcE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:02:37 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 22:02:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:02:37 INFO - Running setup.py (path:/tmp/pip-7f2Ta_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:02:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:02:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:02:37 INFO - Running setup.py (path:/tmp/pip-IZB7sn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:02:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:02:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:02:37 INFO - Running setup.py (path:/tmp/pip-2pvJF2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:02:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:02:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:02:37 INFO - Running setup.py (path:/tmp/pip-1cdlO6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:02:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 22:02:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:02:37 INFO - Running setup.py (path:/tmp/pip-gwfqGw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:02:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 22:02:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:02:37 INFO - Running setup.py (path:/tmp/pip-ZuTV2W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:02:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 22:02:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:02:37 INFO - Running setup.py (path:/tmp/pip-QQ7IYp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:02:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 22:02:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:02:37 INFO - Running setup.py (path:/tmp/pip-ME8MiZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:02:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 22:02:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:02:37 INFO - Running setup.py (path:/tmp/pip-S65Hoe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:02:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:02:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:02:37 INFO - Running setup.py (path:/tmp/pip-Sa8jX7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:02:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 22:02:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:02:37 INFO - Running setup.py (path:/tmp/pip-RUHQX8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:02:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 22:02:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:02:37 INFO - Running setup.py (path:/tmp/pip-pw99N6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:02:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 22:02:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:02:37 INFO - Running setup.py (path:/tmp/pip-5Vos9M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:02:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 22:02:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:02:37 INFO - Running setup.py (path:/tmp/pip-aXQK9k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:02:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 22:02:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:02:38 INFO - Running setup.py (path:/tmp/pip-pl2TvZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:02:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 22:02:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:02:38 INFO - Running setup.py (path:/tmp/pip-m_XgUh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:02:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 22:02:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:02:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:02:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:02:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:02:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:02:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:02:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:02:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:02:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:02:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:02:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:02:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:02:40 INFO - Downloading blessings-1.6.tar.gz 22:02:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:02:40 INFO - Installing collected packages: blessings 22:02:40 INFO - Running setup.py install for blessings 22:02:40 INFO - Successfully installed blessings 22:02:40 INFO - Cleaning up... 22:02:40 INFO - Return code: 0 22:02:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:02:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:02:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:02:40 INFO - Reading from file tmpfile_stdout 22:02:40 INFO - Current package versions: 22:02:40 INFO - argparse == 1.2.1 22:02:40 INFO - blessings == 1.6 22:02:40 INFO - blobuploader == 1.2.4 22:02:40 INFO - docopt == 0.6.1 22:02:40 INFO - functools32 == 3.2.3-2 22:02:40 INFO - jsonschema == 2.5.1 22:02:40 INFO - manifestparser == 1.1 22:02:40 INFO - mozInstall == 1.12 22:02:40 INFO - mozcrash == 1.0 22:02:40 INFO - mozdebug == 0.1 22:02:40 INFO - mozdevice == 0.48 22:02:40 INFO - mozfile == 1.2 22:02:40 INFO - mozhttpd == 0.7 22:02:40 INFO - mozinfo == 0.9 22:02:40 INFO - mozleak == 0.1 22:02:40 INFO - mozlog == 3.4 22:02:40 INFO - moznetwork == 0.27 22:02:40 INFO - mozprocess == 0.23 22:02:40 INFO - mozprofile == 0.28 22:02:40 INFO - mozrunner == 6.13 22:02:40 INFO - mozscreenshot == 0.1 22:02:40 INFO - mozsystemmonitor == 0.3 22:02:40 INFO - moztest == 0.7 22:02:40 INFO - mozversion == 1.4 22:02:40 INFO - psutil == 3.1.1 22:02:40 INFO - requests == 1.2.3 22:02:40 INFO - wsgiref == 0.1.2 22:02:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:02:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:02:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:02:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:02:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:02:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:02:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:02:40 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 0x7f248746ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27db8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28a8ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a5f60>, '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 0x28a6d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28a71d0>, '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': '14ef55db36f7623abb31b9cf000001d5-1482213504.472085-960774253', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:02:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 22:02:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:02:40 INFO - Using env: {'DISPLAY': ':0', 22:02:40 INFO - 'HOME': '/home/cltbld', 22:02:40 INFO - 'LANG': 'en_US.UTF-8', 22:02:40 INFO - 'LANGUAGE': 'en_US:en', 22:02:40 INFO - 'LOGNAME': 'cltbld', 22:02:40 INFO - 'MAIL': '/var/mail/cltbld', 22:02:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:02:40 INFO - 'MOZ_NO_REMOTE': '1', 22:02:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:02:40 INFO - 'NO_EM_RESTART': '1', 22:02:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:02:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:02:40 INFO - 'PWD': '/builds/slave/test', 22:02:40 INFO - 'SHELL': '/bin/bash', 22:02:40 INFO - 'SHLVL': '1', 22:02:40 INFO - 'TERM': 'linux', 22:02:40 INFO - 'TMOUT': '86400', 22:02:40 INFO - 'USER': 'cltbld', 22:02:40 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1482213504.472085-960774253', 22:02:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:02:40 INFO - '_': '/tools/buildbot/bin/python'} 22:02:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:02:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:02:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 22:02:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 22:02:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:02:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 22:02:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 22:02:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 22:02:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 22:02:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 22:02:40 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:02:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:02:40 INFO - Cleaning up... 22:02:40 INFO - Return code: 0 22:02:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:02:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:02:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:02:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:02:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:02:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:02:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:02:40 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 0x7f248746ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27db8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28a8ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a5f60>, '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 0x28a6d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28a71d0>, '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': '14ef55db36f7623abb31b9cf000001d5-1482213504.472085-960774253', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:02:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:02:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:02:40 INFO - Using env: {'DISPLAY': ':0', 22:02:40 INFO - 'HOME': '/home/cltbld', 22:02:40 INFO - 'LANG': 'en_US.UTF-8', 22:02:40 INFO - 'LANGUAGE': 'en_US:en', 22:02:40 INFO - 'LOGNAME': 'cltbld', 22:02:40 INFO - 'MAIL': '/var/mail/cltbld', 22:02:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:02:40 INFO - 'MOZ_NO_REMOTE': '1', 22:02:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:02:40 INFO - 'NO_EM_RESTART': '1', 22:02:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:02:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:02:40 INFO - 'PWD': '/builds/slave/test', 22:02:40 INFO - 'SHELL': '/bin/bash', 22:02:40 INFO - 'SHLVL': '1', 22:02:40 INFO - 'TERM': 'linux', 22:02:40 INFO - 'TMOUT': '86400', 22:02:40 INFO - 'USER': 'cltbld', 22:02:40 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1482213504.472085-960774253', 22:02:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:02:40 INFO - '_': '/tools/buildbot/bin/python'} 22:02:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:02:41 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:02:41 INFO - Cleaning up... 22:02:41 INFO - Return code: 0 22:02:41 INFO - Running post-action listener: _resource_record_post_action 22:02:41 INFO - Running post-action listener: _start_resource_monitoring 22:02:41 INFO - Starting resource monitoring. 22:02:41 INFO - [mozharness: 2016-12-20 06:02:41.203446Z] Finished create-virtualenv step (success) 22:02:41 INFO - [mozharness: 2016-12-20 06:02:41.203949Z] Running install step. 22:02:41 INFO - Running pre-action listener: _resource_record_pre_action 22:02:41 INFO - Running main action method: install 22:02:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:02:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:02:41 INFO - Reading from file tmpfile_stdout 22:02:41 INFO - Detecting whether we're running mozinstall >=1.0... 22:02:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:02:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:02:41 INFO - Reading from file tmpfile_stdout 22:02:41 INFO - Output received: 22:02:41 INFO - Usage: mozinstall [options] installer 22:02:41 INFO - Options: 22:02:41 INFO - -h, --help show this help message and exit 22:02:41 INFO - -d DEST, --destination=DEST 22:02:41 INFO - Directory to install application into. [default: 22:02:41 INFO - "/builds/slave/test"] 22:02:41 INFO - --app=APP Application being installed. [default: firefox] 22:02:41 INFO - mkdir: /builds/slave/test/build/application 22:02:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 22:02:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 22:02:58 INFO - Reading from file tmpfile_stdout 22:02:58 INFO - Output received: 22:02:58 INFO - /builds/slave/test/build/application/firefox/firefox 22:02:58 INFO - Running post-action listener: _resource_record_post_action 22:02:58 INFO - [mozharness: 2016-12-20 06:02:58.128328Z] Finished install step (success) 22:02:58 INFO - [mozharness: 2016-12-20 06:02:58.128570Z] Running run-tests step. 22:02:58 INFO - Running pre-action listener: _resource_record_pre_action 22:02:58 INFO - Running main action method: run_tests 22:02:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:02:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:02:58 INFO - Python 2.7.3 22:02:58 INFO - Return code: 0 22:02:58 INFO - grabbing minidump binary from tooltool 22:02:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:02:58 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 0x28a5f60>, '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 0x28a6d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28a71d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:02:58 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 22:02:58 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 22:02:58 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 22:02:58 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:02:58 INFO - Return code: 0 22:02:58 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:02:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:02:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:02:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:02:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:02:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eHzeF_F7QtqJx1Jdri-D4g/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-036', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 22:02:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/eHzeF_F7QtqJx1Jdri-D4g/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-036 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 22:02:58 INFO - Using env: {'DISPLAY': ':0', 22:02:58 INFO - 'HOME': '/home/cltbld', 22:02:58 INFO - 'LANG': 'en_US.UTF-8', 22:02:58 INFO - 'LANGUAGE': 'en_US:en', 22:02:58 INFO - 'LOGNAME': 'cltbld', 22:02:58 INFO - 'MAIL': '/var/mail/cltbld', 22:02:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:02:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:02:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:02:58 INFO - 'MOZ_NO_REMOTE': '1', 22:02:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:02:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:02:58 INFO - 'NO_EM_RESTART': '1', 22:02:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:02:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:02:58 INFO - 'PWD': '/builds/slave/test', 22:02:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:02:58 INFO - 'SHELL': '/bin/bash', 22:02:58 INFO - 'SHLVL': '1', 22:02:58 INFO - 'TERM': 'linux', 22:02:58 INFO - 'TMOUT': '86400', 22:02:58 INFO - 'USER': 'cltbld', 22:02:58 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1482213504.472085-960774253', 22:02:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:02:58 INFO - '_': '/tools/buildbot/bin/python'} 22:02:58 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eHzeF_F7QtqJx1Jdri-D4g/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-036', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 22:02:58 INFO - mozversion application_buildid: 20161219202253 22:02:58 INFO - mozversion application_changeset: 171be6aaa16ee1d241ffa4bcdabc95b28372c28b 22:02:58 INFO - mozversion application_display_name: FirefoxDeveloperEdition 22:02:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:02:58 INFO - mozversion application_name: Firefox 22:02:58 INFO - mozversion application_remotingname: firefox-dev 22:02:58 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 22:02:58 INFO - mozversion application_vendor: Mozilla 22:02:58 INFO - mozversion application_version: 52.0a2 22:02:58 INFO - mozversion platform_buildid: 20161219202253 22:02:58 INFO - mozversion platform_changeset: 171be6aaa16ee1d241ffa4bcdabc95b28372c28b 22:02:58 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 22:02:58 INFO - mozversion platform_version: 52.0a2 22:02:58 INFO - using testdate: 1482213778 22:02:58 INFO - actual date: 1482213778 22:02:58 INFO - starting webserver on 'localhost:37182' 22:02:58 INFO - SUITE-START | Running 2 tests 22:02:58 INFO - TEST-START | dromaeo_css 22:02:58 INFO - operating with platform_type : linux_ 22:02:58 INFO - Initialising browser for dromaeo_css test... 22:02:58 INFO - TEST-INFO | started process 8471 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOk8NSr/profile http://localhost:37182/getInfo.html) 22:03:02 INFO - PROCESS | 8471 | __metrics Screen width/height:1600/1200 22:03:02 INFO - PROCESS | 8471 | colorDepth:24 22:03:02 INFO - PROCESS | 8471 | Browser inner width/height: 1024/697 22:03:02 INFO - PROCESS | 8471 | __metrics 22:03:02 INFO - PROCESS | 8471 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 22:03:02 INFO - PROCESS | 8471 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:03:03 INFO - PROCESS | 8471 | console.error: PushService: 22:03:03 INFO - PROCESS | 8471 | stateChangeProcessEnqueue: Error transitioning state 22:03:03 INFO - PROCESS | 8471 | UnknownError 22:03:04 INFO - TEST-INFO | 8471: exit 0 22:03:04 INFO - Browser initialized. 22:03:04 INFO - Running cycle 1/1 for dromaeo_css test... 22:03:04 INFO - TEST-INFO | started process 8562 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOk8NSr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:03:15 INFO - PROCESS | 8562 | RSS: Main: 144642048 22:03:15 INFO - PROCESS | 8562 | 22:04:07 INFO - PROCESS | 8562 | 1482213847727 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 22:06:17 INFO - PROCESS | 8562 | Cycle 1(1): loaded http://localhost:37182/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37182/tests/dromaeo/cssquery-ext.html) 22:06:18 INFO - PROCESS | 8562 | RSS: Main: 237760512 22:06:18 INFO - PROCESS | 8562 | 22:09:21 INFO - PROCESS | 8562 | Cycle 1(1): loaded http://localhost:37182/tests/dromaeo/cssquery-ext.html (next: http://localhost:37182/tests/dromaeo/cssquery-jquery.html) 22:09:21 INFO - PROCESS | 8562 | RSS: Main: 230772736 22:09:21 INFO - PROCESS | 8562 | 22:12:23 INFO - PROCESS | 8562 | Cycle 1(1): loaded http://localhost:37182/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37182/tests/dromaeo/cssquery-mootools.html) 22:12:24 INFO - PROCESS | 8562 | RSS: Main: 224944128 22:12:24 INFO - PROCESS | 8562 | 22:15:26 INFO - PROCESS | 8562 | Cycle 1(1): loaded http://localhost:37182/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37182/tests/dromaeo/cssquery-prototype.html) 22:15:26 INFO - PROCESS | 8562 | RSS: Main: 220819456 22:15:26 INFO - PROCESS | 8562 | 22:18:29 INFO - PROCESS | 8562 | Cycle 1(1): loaded http://localhost:37182/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37182/tests/dromaeo/cssquery-yui.html) 22:18:30 INFO - PROCESS | 8562 | RSS: Main: 175136768 22:18:30 INFO - PROCESS | 8562 | 22:21:32 INFO - PROCESS | 8562 | Cycle 1(1): loaded http://localhost:37182/tests/dromaeo/cssquery-yui.html (next: http://localhost:37182/tests/dromaeo/cssquery-dojo.html) 22:21:32 INFO - PROCESS | 8562 | RSS: Main: 267063296 22:21:32 INFO - PROCESS | 8562 | 22:21:32 INFO - PROCESS | 8562 | __start_tp_report 22:21:32 INFO - PROCESS | 8562 | _x_x_mozilla_page_load 22:21:32 INFO - PROCESS | 8562 | _x_x_mozilla_page_load_details 22:21:32 INFO - PROCESS | 8562 | |i|pagename|runs| 22:21:32 INFO - PROCESS | 8562 | |0;dojo.html;3210.9197270068253;3225.854836532356;3256.120847371209;3259.462188738858;3259.8207098609582;5456.7544460499275;5555.083411237141;5558.360788509322;5567.86080347991;5572.832815015556;5508.6970216638065;5512.476314750103;5530.640508366953;5536.058869992101;5540.695261760601;4971.527704868041;4982.57648099912;5008.824691135811;5036.748162591855;5086.79652813887;4884.829030983917;4895.657303988723;5046.192609182532;5099.133147364957;5100.642954993152;4953.9504604953845;4960.032793605248;4976.552110310071;4999.900001999958;5031.11955407804;4995.725235112085;5019.824306149304;5074.720890351029;5082.390113186404;5102.617303702237;4765.976170119162;4979.402471703419;4987.15218412867;5024.84925452237;5054.1407960646775;5269.6311258211945;5338.386085600167;5346.545543628797;5357.1696387060065;5369.704666243355;5738.713064346808;5766.538676905838;5876.059830427154;5877.970610146922;5883.499902508292;5700.059490184115;5902.144189092557;5913;5930.199423077873;5932.19915311436;5297.602679798992;5363.865903352447;5364.463553644604;5373.38206106295;5420.891582168335;5836.06622940336;5862.501687356537;5863.501602363762;5864.648121112747;5866.23738913947;5655.123455864347;5657.094864821691;5665.291838520185;5666.348369937427;5668.744906479218;5633.605647604711;5678.261825962599;5722.656640601577;5806.82579522615;5816.651000939873;5867.266591676041;5871.32479764824;5872.383399743051;5895.882082358329;5907.793227237112;5814.941850581525;5860.794872179454;5873.295204575478;5876.206712093898;5880.882382352329;4413.6248418884115;4451.332300154938;4512.887177820515;4574.954250457453;4581.656375771764;4097.385392191195;4112;4135.565765594569;4141.130362623882;4166.500019997559;5674.574406919498;5695.231143795618;5699.287589051369;5704.572157088235;5706.743196556165;5673.432656734293;5718.399568045378;5728.427157284238;5971.970140149271;5980.0431930890845;5553.8333849984565;5609.467100625434;5620.100783874642;5639.351474580394;5650.660960342392;5022.070916880389;5047.899042019212;5055.747212639353;5056.418511871108;5059.139946209153;5699.173619825185;5915.526757859275;5975.193348897843;5988.371221021817;5992.940070599326;5629.6903670298525;5765.56758243125;5786.971065144648;5885.323187833454;5966.940330596639;6047.183630210041;6052.455279024909;6054.515638748978;6059.818205453844;6061.302950160788;5794.594378393641;5803.245578074823;5811.593188476766;5812.82561523155;5839.649621022752;5593.692346920796;5599.104143337209;5770.451807078321;5833.095870140135;5852.4440178183;5801.6519008859605;5803.042497987784;5807.767689292381;5817;5827.5337972961215;5730;5795.362510123968;5795.594308398372;5799.82600521985;5800.187973683772;5419.593530485151;5422;5599.552035837205;5679.630823996427;5742.368339482737;5850.12248162779;5850.53195744348;5855.970720146372;5856.267966504187;5889.381614930542;5332.173513105475;5382.757775900172;5713.485786279255;5720.2849643794525;5721.141828725725 22:21:32 INFO - PROCESS | 8562 | |1;ext.html;559.8432438917102;589.6196952965338;625.087372436243;633.86371930035;659.5745743995126;33891.66108338919;44261.114777704424;45428.091438171214;45516.544834551685;49146.26280605794;46225.7688711556;46562.53437465624;46619;46745.53254467454;46791.76604116983;8256.25693687568;8923.732288031353;9072.36493445459;9080.0919908009;9083.182513573776;8297.336213102937;10697.19771017173;10952.178586605996;10954.616588419407;11297.943510282437;10615.203859710515;10663.84004239937;10664.253502254845;10666.839997400006;10667.466626668675;10953.83569246462;10979.121670266359;11011.449427528672;11022.614208502744;11025.724356891142;10811.027007569357;10873.401962892038;10886.836697449544;10895.291806032583;10905.291156074909;43476.3478547822;43566.78216608897;43720.78139609282;43763;43784.78107609473;3702.740808143431;3760.7931563764255;3820.465134881119;4013.678905687538;4347.413099231595;32306.676933230603;32409.67590324114;32617.347653047043;32922.6707732922;32935.50596741051;4450.2434586120435;4549.555355071538;4571.839985600522;4594.609458196024;4689.601383882374;355663;356361;357420;357615.9999999974;357953;59407.10889336663;59723.10415343774;60135;60174.09738853921;60243.39756602466;39183.804080979986;40284;40414.59585404167;42913;63765.36234637687;17099.23053462597;17111.058891760822;17161.570960726083;17171.141442927805;17196.398126065527;13298.268595227162;13499.730005399837;14182.432702691984;14592.270386480634;14734.115953042852;19655.705164422547;20654.896725516577;20662.690059649118;20722.896385518277;20787.79212207889;7374.557526548424;7386.409087273005;7516.549007059594;7595.620218989028;7690.577018264049;935.4854829843695;1015.6851376073441;1061.0927656853435;1071.5927947379946;1104.9779004420027;33530.32939341248;33773.83113084419;34095.659043409745;34234.82882585571;34539.82730086333;1139.601139601133;1160.3502038858267;1189.149757923089;1203.6930582701355;1259.9118061735774;17885.195166217552;18017.19828017214;18447.771639962953;18671.90664046671;19364.70952935707;511775;516219;519314;520527;520773;60783.39216607866;61425;61595;61672.69163654153;61689.69155154195;1273.9171953822972;1323.6624660711457;1376.6558360409897;1386.8682475164844;1432.534426311453;33069.83465082659;35161.29677406489;35499.82250088733;35619.28761424809;35640.28719425545;51440.22839657333;52101.21848172205;52139.47860521346;52192;52268.21597675962;422810;426753;427278;427711;428250;27429.17712468629;27794.305142370795;28042;28139.748820093933;32597.044177349435;49186;49441;49553.25670115023;49631.00737985148;49693;41014;41712.58287417208;41827;42000;43489.565104349815;42536.57463425326;43145;43191.35212971871;43931;44012.559874400846;41585.58414415817;42605.78697106495;42646.36030459483;42677.57322426736;42816.14367712691;36421;38718.419223711106;39001.414978774774;39038;39476.21047578975;29032.225810644482;29069;29178.270543236587;29474.852625737592;29623.25941851471 22:21:32 INFO - PROCESS | 8562 | |2;jquery.html;398.4222478983226;417.1323646814626;421.9620234178924;426.68638550665264;443.6916343141516;15275.465358712447;19198.52003699908;20012.19951201952;20185.09167087481;20378.69431958522;19420.22319107234;20709.06809193586;20712.792872071273;20714.964251787427;20932.58134837306;5818.5926985110955;5973.641581505123;6063.939360606404;6066.302375226839;6067.908981365283;5759.308882934033;5937.584369094165;5957.6127551709;5959.0763431668165;5964.761409543613;5728.112142617901;5925.081612350093;5927.792527261547;5933.199018132562;5936.940630593704;5957.344692083867;6114.174586430864;6126.26484821822;6127.417895299939;6129.448349648553;5821.621594596338;5877.588568800185;5899.469047785677;5902.085176797603;5945.435183657545;14993.250337483192;15237.085774853544;15269.541913742605;22211.22260720883;24310.635340469908;2267.5691618592414;2361.090979972723;2385.522895420923;2392.1268736911074;2519.407939134302;11663.183577149603;11714.882851171464;13185;15230.086194828345;16080.758788618181;1968.7145363922293;2003.8497112716605;2044.1210279579775;2054.4555692741437;2059.5571952030386;84375.578122109;86852.56573717154;88735.11264887398;89195.10804891998;89976.55011724899;10577.894221057844;11879.346635934933;11894.524219031142;12145.392730363623;12367.752644947052;15491.845081549265;15573.377064917502;16389.426370077228;16414.589635259214;17144.314227430765;8930.732078037669;9046.81906361869;9088.54557272147;9095.863562046574;9135.95432022849;8721.389502734748;8727.869081963776;8736.519491428033;8865.734027979172;9052;5111.514406131411;5190;5221.086309895753;5330.520253177233;5339.546138578265;2048.8463365247667;2074.481379655086;2106.915723371048;2111.3243761996014;2143.9142434302453;940.290080988849;985.2364417576435;1001.9499025048718;1020.5968642386216;1069.4866464097277;19937.60124797496;20554.07506662174;20670.483237919172;20679.689804652942;20693.137617614488;998.0418797954045;1034.6585626743156;1039.0596510158318;1046.638909576195;1059.4490864750287;10899;11502.53989840397;11843.763124737628;11850.762984740257;11882.871127242897;446270;460264;468144;477164;479501;93902;96572.51713741386;96842;97708.51145744226;99198;990.5938565188238;1016.2378216337746;1027.7276521721749;1033.565902321027;1037.0459177556666;17761.911190444484;17859.73210401871;18281.81718182801;18483.353082642043;18568.25726970906;43127.78436107925;43548.782256089784;43674.12651747011;43792.78103609462;44895;59138.70430647819;59820.40179598148;60232.69883650554;60896.69551652214;61248.69375653272;5934.376890426443;7502.36229920441;8594.226519613265;9364.063593640582;9601.519924003633;11911;13815.549367316287;13998;14107.576772696835;14281.785773213202;12118.575849845212;12161.33112678811;12328.445219965302;12579.74840503203;12870.22778633285;11858.347790871585;12132.393380331125;12474.251544907334;12569.1201615886;12757.170783899017;12295.200811947196;12429;12471.376431178587;12478.25130492137;12534;9320.207782338306;9361.297902657465;9393.060693930553;9551.570179342085;9554.426734395958;11929.033571509954;12337.876621233672;12407;12498.875011249771;12849.100562960877 22:21:32 INFO - PROCESS | 8562 | |3;mootools.html;1355.3629793996818;1498.6702925356421;1504.022385449458;1669.4240487031977;1855.8051404602516;7720.266574675404;8545.188207120322;8913.108689131084;8918.375713700027;9183.77040573986;8956.10438956104;8982.416142950704;8992.325575581825;9027.368084234105;9037.231835293991;2466.383404148967;2483.950320993579;2491.8006559475198;2491.9750802492017;2495.3886297857052;2445.9510809783883;2525.0657256815;2535.1253817432957;2537.200781753751;2539;2415.5168966206743;2423.987880060606;2482.950340993179;2485.1674688979215;2495.15164843953;2423.0550085466684;2431.659567660529;2440.7925326347104;2457.680501534807;2462.4090218347646;2407.482391285882;2410.9397265068337;2432.8905199266073;2438.146648672968;2467.321486591202;8657.307415406753;8883.067277935854;8950;8958.238549723215;9011.909880901238;5497.312835895513;5652.434756524356;5657.830265092054;5664.546836253131;5672.5178359839465;3387.1193489692855;3529.5235143255845;3547.1486843157713;3599.2981368633127;3609.9819500902327;1059.1685526861377;1078.449990504848;1083.3175099687146;1115.1357697784201;1121.534563156289;12532.624021279285;12568.874311256863;12603.873961260362;12616.873831261662;12661.683457913441;4520.254158063883;4715.386999690087;4777.904441911142;4824.6863953842885;4931.087748766489;9791.167750741266;9791.216702663769;9792.16766574835;9792.804143917225;9798.951005244928;5511.58663100269;5514.696691681916;5516.172574113834;5519.724013799294;5520.751566179451;4273;4469.754163521037;4688.085823264499;5039.571636410946;5161.122609156452;3947.6644485219076;3963.801809909493;4065.166640838621;4074.144429669743;4082.81627326771;1186.691460220349;1218.945147468366;1222.480445810527;1233.1491271022965;1240.3240234072273;953.4803532075035;984.7390441532999;991.3704797453524;994.1053052252886;1003.0771690045176;5915.733791979371;5928.258967629046;6076.483498902643;6090.817275481742;6101.206843110367;453.6212262760566;458.63767623577;462.81024779840106;464.8884267775777;466.8062753957138;4122.2579935611875;4130.339145736728;4149.875503734893;4165.12532368206;4220.978895105505;10998.450077496092;11163.665090047312;11323.94338028321;11330.263532870336;11336.886631133582;9934.602615895283;10365.533550990223;10369.792604147875;11219.270747401393;11233.38216398106;8271.379646526415;8476.406651534457;8476.745697629081;8478.279346255515;8479.279261262862;6074.1633826394045;6089.391060893875;6100.969495152496;6223.159873417031;6320.336364681642;10301.484925753532;10304.639337623148;10308.793824123626;10336.431496267778;10388.532516036645;8942.552872356486;9104.04407537199;10453.895461045597;10454.790904181722;10466.215033872339;7202.855942881218;7973;8018.238267364591;8135.837283254184;8257.83484330322;8778.341624378325;8795.25240354577;8836.690715824916;8840.690575829814;8840.823183536422;7757.534547927143;7859.371250300077;7862.213778622092;8015.83968320642;8171.387145963958;7713.7300194495165;7810.765677029698;7819.648115834915;7929.841403171788;7991.72028978983;7090.574565526085;7134.643267836691;7197.640117994184;7205.567665940061;7207.71169153249;5167.689938603696;5239.528442440199;5242.633015688865;5246.160614301846;5437.184422336682;5293.920591191212;5305.098133317269;5322.920156197582;5331.893362132813;5364.14173732201 22:21:32 INFO - PROCESS | 8562 | |4;prototype.html;347.09062256886943;359.3675131768088;359.5721091900638;364.16060979442375;364.1933118143312;5322.7072511011875;5360.9463905360935;5372.8448383597515;5381.542568881645;5458.4541545845395;5022.447530771612;5164.431912489633;5177.870553236162;5295.920561191585;5300.761465734042;4205.369194620816;4214.431051808013;4219.303814870543;4228.957710422887;4234.30134027885;4197.622214000739;4212.55768144345;4216.852410165645;4224;4232.301670224425;3959.5644479107277;3989.52125744911;4164.416981622575;4178.853740119096;4216.494020717503;4162.666986641092;4175.895602609929;4181.853635122787;4186.518550366701;4189.958100418988;3749.3063783199923;3750.587435382106;3755.0800053986677;3757.3236817372863;3766.227923275722;5049.924251136236;5141.760690764831;5173.120569503193;5176.352955880515;5205.401378841444;4772.284157376386;4824.372831531879;4831.685940413862;4843.878903027417;4843.934334446416;5548.91676624851;5550.27846379972;5550.611457197998;5552.194931734876;5573.6098473106495;3983.7609743415487;4097.631213190797;4121.649659778923;4164.541900390955;4165;5725.770969161229;5731.914021289684;5734.197212390269;5734.885302293931;5744.195812586241;4219.367094935723;4223.458437670228;4227.3447615619625;4231.30183519722;4238.639715624145;4188.518320393133;4251.766987573631;4262.403263543107;4288.335308027261;4300.795777182394;4777.976110119427;4793.784279707422;4803.495632958559;4815.807367705253;4820.6143508519235;5493.148561972901;5546;5550.91673624896;5560.527355174774;5563.332400112013;1401.978970315446;1426.6076828872144;1431.8926080544002;1436.1598464898152;1441.3802065112102;951.5242378810594;978.8825340958988;1004.5379125602304;1010.7523656704154;1021.2187676427538;5209.583233341324;5213.949839344026;5214;5238.76425560843;5251.741751047087;5413.702246376409;5422.810201642924;5434.538064264502;5470.617056806065;5472.825761058576;5199.050356116456;5264.710440925785;5489.258950041693;5516.668999860021;5522.834314970557;2918.2558447596152;2937.427201695649;2955.88176472943;2971.465136275491;2972.390659914712;5524.530414914697;5632.986062508706;5651.343868381854;5712.171735098387;5716.885662286731;5574.804881829116;5619.887602247933;5623.634463759924;5637.633553818989;5679.176519404663;5591.41290164535;5621.49406553404;5632.352279487911;5658.292713410824;5659.717014149359;5385.2191432241325;5472.72636368188;5525.309336332958;5538.529225015919;5552.472515111058;5438.912217556425;5454.209139674805;5469.671819690831;5475.67145971243;5482.533984611353;5440.646357986719;5563.666180029211;5635.887282254414;5636.464535869086;5659.858503537444;3988.8603898863403;4009.278329900646;4045.5549889512718;4048.1701251244012;4063.309237429703;5409.702466364388;5472.91790623149;5473;5488.280079132023;5523.7238138093735;5179.585633149414;5346.679199248058;5357.1696387060065;5374.704391258519;5382.892342153214;5364.4786950133375;5392.193615138973;5415.620906536505;5440.211169380497;5450.400455949922;5278.181881808326;5302.337207849019;5316.787328506971;5343.198520221842;5461.754221060141;5487.094629386266;5560.749766260609;5567.55459563242;5573.219749235192;5573.470520300565;5372.919406208832;5409.729513524229;5420.9186862197885;5442.727863606883;5447.202423200255 22:21:32 INFO - PROCESS | 8562 | |5;yui.html;741.9962900185499;744.2297222374841;818.5661599352345;828.9046759622642;909.6634245329229;14183;14812.185329806856;15290.46483373082;15372.538823835288;15374.69250614987;12706.555270565532;13816.101953373023;13917.23455209963;13925.094868833545;13952.093113947587;836.7238811192292;857.2199298638241;858.4291446188278;859.5401460218784;860.5267103093273;857.7812657772259;861.2593169873903;862.1895418306772;862.4307956748515;862.8360611483829;898.1512470715177;898.9910100898973;899.4153800029967;900.0954041188596;901.3870568013748;889.5685592487638;891.5364010714457;892.848215803315;893.2095095840164;893.2407453664366;657.6251536624126;658.3613894522306;658.6739563915844;658.8155316511385;659.6734616364882;12650.74698506034;12685.112042157052;12739.171953822972;13685.384157712926;14125.505607303696;14071.577852664437;14659;14812.777808332883;14816.185109818956;15083.170425626586;12268.631941041784;12305.26168429897;12336.198147120498;12357.876421235762;12363.814542781865;3722.9441558376648;3767.566729826078;3806.4670946067295;3854.2869569129803;3861;66032.66983665052;66054.3394566053;67243.3275667242;67688;67815.66092169557;9839.950800246095;10268.127209187154;10405.53175107122;10658.467076646137;10845.186611004206;19764;21070.47323816917;21097.578048438947;21109.68335474969;21114.577708445744;8294.917050829414;8496.235338819411;8552;8662.566871656392;8699.782505437415;4807.591354734816;4826.61387089032;4833.540813622701;4846.224604063333;5084.313617661643;3460.9826950865086;3539.94690079649;3571.8928432147077;3589.587197472272;3602.171500554887;597.6503745308956;604.9364816694272;622.819382379105;628.3998781164069;632.206580741176;691.6576294734139;712.0814149746872;733.5012191709582;756.8902509136254;770.7263921307967;9486;9879.703608891745;9952.751181220383;10510.579576816994;10549.419781912069;664.7540410048313;690.9723611055501;738.3576288628927;780.5277806926841;783.2793829676712;4568;4894.951050489521;4925.335079764258;4958.8760280993265;4972.726500042532;56940.71529642325;57470.712646437336;57635.71182144062;57757.42242577521;58671.11993320104;51418.74290628523;51483.48516514862;63668.68165659142;80784.15295082313;83190.58404708059;62475.37524624696;67054.32945670481;70115.29884701088;71524;71640;9714.902850971683;10162.74593135148;10188.847167292348;10189.976730455272;10264.127549158107;48717.5128248713;48999.75500122619;49183;50054;50681;58392;62173;63947;64323.678381607795;65502.344976549626;11965.40482605655;11995.82006269924;12823.102382833486;12857.485700571873;13227.272500012206;13236.13965092266;13249.668758281121;13293.933530332286;13328.466861325438;14366.066205696596;12723.109382343517;13139.60581182566;13448.731025379633;13511.446948230297;13656.248906310248;12995;13062.869371306166;13081.345932703518;13358.599242022754;13611.11527750693;13314.93342533281;13390.598282051555;13461.663458413617;13516.662083447993;13528.39122239482;10763;11173.809865888674;11273.323600583992;11566.248193867372;11777.823332650189;11151.83272250933;11310.886891130984;11497.1377146716;11611.709707257387;11661.35862527569 22:21:32 INFO - PROCESS | 8562 | __end_tp_report 22:21:32 INFO - PROCESS | 8562 | __start_cc_report 22:21:32 INFO - PROCESS | 8562 | _x_x_mozilla_cycle_collect,1015 22:21:32 INFO - PROCESS | 8562 | __end_cc_report 22:21:32 INFO - PROCESS | 8562 | __startTimestamp1482214892624__endTimestamp 22:21:32 INFO - PROCESS | 8562 | 22:21:32 INFO - PROCESS | 8562 | ------- Summary: start ------- 22:21:32 INFO - PROCESS | 8562 | Number of tests: 6 22:21:32 INFO - PROCESS | 8562 | 22:21:32 INFO - PROCESS | 8562 | [#0] dojo.html Cycles:165 Average:5438.42 Median:5666.35 stddev:589.61 (10.4%) stddev-sans-first:564.94 22:21:32 INFO - PROCESS | 8562 | Values: 3210.9 3225.9 3256.1 3259.5 3259.8 5456.8 5555.1 5558.4 5567.9 5572.8 5508.7 5512.5 5530.6 5536.1 5540.7 4971.5 4982.6 5008.8 5036.7 5086.8 4884.8 4895.7 5046.2 5099.1 5100.6 4954.0 4960.0 4976.6 4999.9 5031.1 4995.7 5019.8 5074.7 5082.4 5102.6 4766.0 4979.4 4987.2 5024.8 5054.1 5269.6 5338.4 5346.5 5357.2 5369.7 5738.7 5766.5 5876.1 5878.0 5883.5 5700.1 5902.1 5913.0 5930.2 5932.2 5297.6 5363.9 5364.5 5373.4 5420.9 5836.1 5862.5 5863.5 5864.6 5866.2 5655.1 5657.1 5665.3 5666.3 5668.7 5633.6 5678.3 5722.7 5806.8 5816.7 5867.3 5871.3 5872.4 5895.9 5907.8 5814.9 5860.8 5873.3 5876.2 5880.9 4413.6 4451.3 4512.9 4575.0 4581.7 4097.4 4112.0 4135.6 4141.1 4166.5 5674.6 5695.2 5699.3 5704.6 5706.7 5673.4 5718.4 5728.4 5972.0 5980.0 5553.8 5609.5 5620.1 5639.4 5650.7 5022.1 5047.9 5055.7 5056.4 5059.1 5699.2 5915.5 5975.2 5988.4 5992.9 5629.7 5765.6 5787.0 5885.3 5966.9 6047.2 6052.5 6054.5 6059.8 6061.3 5794.6 5803.2 5811.6 5812.8 5839.6 5593.7 5599.1 5770.5 5833.1 5852.4 5801.7 5803.0 5807.8 5817.0 5827.5 5730.0 5795.4 5795.6 5799.8 5800.2 5419.6 5422.0 5599.6 5679.6 5742.4 5850.1 5850.5 5856.0 5856.3 5889.4 5332.2 5382.8 5713.5 5720.3 5721.1 22:21:32 INFO - PROCESS | 8562 | 22:21:32 INFO - PROCESS | 8562 | [#1] ext.html Cycles:180 Average:60326.68 Median:39110.90 stddev:115922.66 (296.4%) stddev-sans-first:116161.00 22:21:32 INFO - PROCESS | 8562 | Values: 559.8 589.6 625.1 633.9 659.6 33891.7 44261.1 45428.1 45516.5 49146.3 46225.8 46562.5 46619.0 46745.5 46791.8 8256.3 8923.7 9072.4 9080.1 9083.2 8297.3 10697.2 10952.2 10954.6 11297.9 10615.2 10663.8 10664.3 10666.8 10667.5 10953.8 10979.1 11011.4 11022.6 11025.7 10811.0 10873.4 10886.8 10895.3 10905.3 43476.3 43566.8 43720.8 43763.0 43784.8 3702.7 3760.8 3820.5 4013.7 4347.4 32306.7 32409.7 32617.3 32922.7 32935.5 4450.2 4549.6 4571.8 4594.6 4689.6 355663.0 356361.0 357420.0 357616.0 357953.0 59407.1 59723.1 60135.0 60174.1 60243.4 39183.8 40284.0 40414.6 42913.0 63765.4 17099.2 17111.1 17161.6 17171.1 17196.4 13298.3 13499.7 14182.4 14592.3 14734.1 19655.7 20654.9 20662.7 20722.9 20787.8 7374.6 7386.4 7516.5 7595.6 7690.6 935.5 1015.7 1061.1 1071.6 1105.0 33530.3 33773.8 34095.7 34234.8 34539.8 1139.6 1160.4 1189.1 1203.7 1259.9 17885.2 18017.2 18447.8 18671.9 19364.7 511775.0 516219.0 519314.0 520527.0 520773.0 60783.4 61425.0 61595.0 61672.7 61689.7 1273.9 1323.7 1376.7 1386.9 1432.5 33069.8 35161.3 35499.8 35619.3 35640.3 51440.2 52101.2 52139.5 52192.0 52268.2 422810.0 426753.0 427278.0 427711.0 428250.0 27429.2 27794.3 28042.0 28139.7 32597.0 49186.0 49441.0 49553.3 49631.0 49693.0 41014.0 41712.6 41827.0 42000.0 43489.6 42536.6 43145.0 43191.4 43931.0 44012.6 41585.6 42605.8 42646.4 42677.6 42816.1 36421.0 38718.4 39001.4 39038.0 39476.2 29032.2 29069.0 29178.3 29474.9 29623.3 22:21:32 INFO - PROCESS | 8562 | 22:21:32 INFO - PROCESS | 8562 | [#2] jquery.html Cycles:180 Average:29126.08 Median:2314.33 stddev:77247.24 (3337.8%) stddev-sans-first:77433.82 22:21:32 INFO - PROCESS | 8562 | Values: 398.4 417.1 422.0 426.7 443.7 15275.5 19198.5 20012.2 20185.1 20378.7 19420.2 20709.1 20712.8 20715.0 20932.6 5818.6 5973.6 6063.9 6066.3 6067.9 5759.3 5937.6 5957.6 5959.1 5964.8 5728.1 5925.1 5927.8 5933.2 5936.9 5957.3 6114.2 6126.3 6127.4 6129.4 5821.6 5877.6 5899.5 5902.1 5945.4 14993.3 15237.1 15269.5 22211.2 24310.6 2267.6 2361.1 2385.5 2392.1 2519.4 11663.2 11714.9 13185.0 15230.1 16080.8 1968.7 2003.8 2044.1 2054.5 2059.6 84375.6 86852.6 88735.1 89195.1 89976.6 10577.9 11879.3 11894.5 12145.4 12367.8 15491.8 15573.4 16389.4 16414.6 17144.3 8930.7 9046.8 9088.5 9095.9 9136.0 8721.4 8727.9 8736.5 8865.7 9052.0 5111.5 5190.0 5221.1 5330.5 5339.5 2048.8 2074.5 2106.9 2111.3 2143.9 940.3 985.2 1001.9 1020.6 1069.5 19937.6 20554.1 20670.5 20679.7 20693.1 998.0 1034.7 1039.1 1046.6 1059.4 10899.0 11502.5 11843.8 11850.8 11882.9 446270.0 460264.0 468144.0 477164.0 479501.0 93902.0 96572.5 96842.0 97708.5 99198.0 990.6 1016.2 1027.7 1033.6 1037.0 17761.9 17859.7 18281.8 18483.4 18568.3 43127.8 43548.8 43674.1 43792.8 44895.0 59138.7 59820.4 60232.7 60896.7 61248.7 5934.4 7502.4 8594.2 9364.1 9601.5 11911.0 13815.5 13998.0 14107.6 14281.8 12118.6 12161.3 12328.4 12579.7 12870.2 11858.3 12132.4 12474.3 12569.1 12757.2 12295.2 12429.0 12471.4 12478.3 12534.0 9320.2 9361.3 9393.1 9551.6 9554.4 11929.0 12337.9 12407.0 12498.9 12849.1 22:21:32 INFO - PROCESS | 8562 | 22:21:32 INFO - PROCESS | 8562 | [#3] mootools.html Cycles:180 Average:5886.74 Median:4985.33 stddev:3320.06 (66.6%) stddev-sans-first:3311.91 22:21:32 INFO - PROCESS | 8562 | Values: 1355.4 1498.7 1504.0 1669.4 1855.8 7720.3 8545.2 8913.1 8918.4 9183.8 8956.1 8982.4 8992.3 9027.4 9037.2 2466.4 2484.0 2491.8 2492.0 2495.4 2446.0 2525.1 2535.1 2537.2 2539.0 2415.5 2424.0 2483.0 2485.2 2495.2 2423.1 2431.7 2440.8 2457.7 2462.4 2407.5 2410.9 2432.9 2438.1 2467.3 8657.3 8883.1 8950.0 8958.2 9011.9 5497.3 5652.4 5657.8 5664.5 5672.5 3387.1 3529.5 3547.1 3599.3 3610.0 1059.2 1078.4 1083.3 1115.1 1121.5 12532.6 12568.9 12603.9 12616.9 12661.7 4520.3 4715.4 4777.9 4824.7 4931.1 9791.2 9791.2 9792.2 9792.8 9799.0 5511.6 5514.7 5516.2 5519.7 5520.8 4273.0 4469.8 4688.1 5039.6 5161.1 3947.7 3963.8 4065.2 4074.1 4082.8 1186.7 1218.9 1222.5 1233.1 1240.3 953.5 984.7 991.4 994.1 1003.1 5915.7 5928.3 6076.5 6090.8 6101.2 453.6 458.6 462.8 464.9 466.8 4122.3 4130.3 4149.9 4165.1 4221.0 10998.5 11163.7 11323.9 11330.3 11336.9 9934.6 10365.5 10369.8 11219.3 11233.4 8271.4 8476.4 8476.7 8478.3 8479.3 6074.2 6089.4 6101.0 6223.2 6320.3 10301.5 10304.6 10308.8 10336.4 10388.5 8942.6 9104.0 10453.9 10454.8 10466.2 7202.9 7973.0 8018.2 8135.8 8257.8 8778.3 8795.3 8836.7 8840.7 8840.8 7757.5 7859.4 7862.2 8015.8 8171.4 7713.7 7810.8 7819.6 7929.8 7991.7 7090.6 7134.6 7197.6 7205.6 7207.7 5167.7 5239.5 5242.6 5246.2 5437.2 5293.9 5305.1 5322.9 5331.9 5364.1 22:21:32 INFO - PROCESS | 8562 | 22:21:32 INFO - PROCESS | 8562 | [#4] prototype.html Cycles:180 Average:4640.39 Median:5298.34 stddev:1316.59 (24.8%) stddev-sans-first:1280.24 22:21:32 INFO - PROCESS | 8562 | Values: 347.1 359.4 359.6 364.2 364.2 5322.7 5360.9 5372.8 5381.5 5458.5 5022.4 5164.4 5177.9 5295.9 5300.8 4205.4 4214.4 4219.3 4229.0 4234.3 4197.6 4212.6 4216.9 4224.0 4232.3 3959.6 3989.5 4164.4 4178.9 4216.5 4162.7 4175.9 4181.9 4186.5 4190.0 3749.3 3750.6 3755.1 3757.3 3766.2 5049.9 5141.8 5173.1 5176.4 5205.4 4772.3 4824.4 4831.7 4843.9 4843.9 5548.9 5550.3 5550.6 5552.2 5573.6 3983.8 4097.6 4121.6 4164.5 4165.0 5725.8 5731.9 5734.2 5734.9 5744.2 4219.4 4223.5 4227.3 4231.3 4238.6 4188.5 4251.8 4262.4 4288.3 4300.8 4778.0 4793.8 4803.5 4815.8 4820.6 5493.1 5546.0 5550.9 5560.5 5563.3 1402.0 1426.6 1431.9 1436.2 1441.4 951.5 978.9 1004.5 1010.8 1021.2 5209.6 5213.9 5214.0 5238.8 5251.7 5413.7 5422.8 5434.5 5470.6 5472.8 5199.1 5264.7 5489.3 5516.7 5522.8 2918.3 2937.4 2955.9 2971.5 2972.4 5524.5 5633.0 5651.3 5712.2 5716.9 5574.8 5619.9 5623.6 5637.6 5679.2 5591.4 5621.5 5632.4 5658.3 5659.7 5385.2 5472.7 5525.3 5538.5 5552.5 5438.9 5454.2 5469.7 5475.7 5482.5 5440.6 5563.7 5635.9 5636.5 5659.9 3988.9 4009.3 4045.6 4048.2 4063.3 5409.7 5472.9 5473.0 5488.3 5523.7 5179.6 5346.7 5357.2 5374.7 5382.9 5364.5 5392.2 5415.6 5440.2 5450.4 5278.2 5302.3 5316.8 5343.2 5461.8 5487.1 5560.7 5567.6 5573.2 5573.5 5372.9 5409.7 5420.9 5442.7 5447.2 22:21:32 INFO - PROCESS | 8562 | 22:21:32 INFO - PROCESS | 8562 | [#5] yui.html Cycles:180 Average:17041.30 Median:26806.23 stddev:21159.93 (78.9%) stddev-sans-first:21183.89 22:21:32 INFO - PROCESS | 8562 | Values: 742.0 744.2 818.6 828.9 909.7 14183.0 14812.2 15290.5 15372.5 15374.7 12706.6 13816.1 13917.2 13925.1 13952.1 836.7 857.2 858.4 859.5 860.5 857.8 861.3 862.2 862.4 862.8 898.2 899.0 899.4 900.1 901.4 889.6 891.5 892.8 893.2 893.2 657.6 658.4 658.7 658.8 659.7 12650.7 12685.1 12739.2 13685.4 14125.5 14071.6 14659.0 14812.8 14816.2 15083.2 12268.6 12305.3 12336.2 12357.9 12363.8 3722.9 3767.6 3806.5 3854.3 3861.0 66032.7 66054.3 67243.3 67688.0 67815.7 9840.0 10268.1 10405.5 10658.5 10845.2 19764.0 21070.5 21097.6 21109.7 21114.6 8294.9 8496.2 8552.0 8662.6 8699.8 4807.6 4826.6 4833.5 4846.2 5084.3 3461.0 3539.9 3571.9 3589.6 3602.2 597.7 604.9 622.8 628.4 632.2 691.7 712.1 733.5 756.9 770.7 9486.0 9879.7 9952.8 10510.6 10549.4 664.8 691.0 738.4 780.5 783.3 4568.0 4895.0 4925.3 4958.9 4972.7 56940.7 57470.7 57635.7 57757.4 58671.1 51418.7 51483.5 63668.7 80784.2 83190.6 62475.4 67054.3 70115.3 71524.0 71640.0 9714.9 10162.7 10188.8 10190.0 10264.1 48717.5 48999.8 49183.0 50054.0 50681.0 58392.0 62173.0 63947.0 64323.7 65502.3 11965.4 11995.8 12823.1 12857.5 13227.3 13236.1 13249.7 13293.9 13328.5 14366.1 12723.1 13139.6 13448.7 13511.4 13656.2 12995.0 13062.9 13081.3 13358.6 13611.1 13314.9 13390.6 13461.7 13516.7 13528.4 10763.0 11173.8 11273.3 11566.2 11777.8 11151.8 11310.9 11497.1 11611.7 11661.4 22:21:32 INFO - PROCESS | 8562 | -------- Summary: end -------- 22:21:32 INFO - PROCESS | 8562 | 22:21:33 INFO - TEST-INFO | 8562: exit 0 22:21:33 INFO - TEST-OK | dromaeo_css | took 1114988ms 22:21:33 INFO - TEST-START | kraken 22:21:33 INFO - operating with platform_type : linux_ 22:21:33 INFO - Initialising browser for kraken test... 22:21:33 INFO - TEST-INFO | started process 8890 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5Wo1fE/profile http://localhost:37182/getInfo.html) 22:21:37 INFO - PROCESS | 8890 | __metrics Screen width/height:1600/1200 22:21:37 INFO - PROCESS | 8890 | colorDepth:24 22:21:37 INFO - PROCESS | 8890 | Browser inner width/height: 1024/697 22:21:37 INFO - PROCESS | 8890 | __metrics 22:21:37 INFO - PROCESS | 8890 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 22:21:37 INFO - PROCESS | 8890 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:21:38 INFO - PROCESS | 8890 | console.error: PushService: 22:21:38 INFO - PROCESS | 8890 | stateChangeProcessEnqueue: Error transitioning state 22:21:38 INFO - PROCESS | 8890 | UnknownError 22:21:38 INFO - TEST-INFO | 8890: exit 0 22:21:38 INFO - Browser initialized. 22:21:38 INFO - Running cycle 1/1 for kraken test... 22:21:38 INFO - TEST-INFO | started process 8984 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5Wo1fE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:21:50 INFO - PROCESS | 8984 | RSS: Main: 147300352 22:21:50 INFO - PROCESS | 8984 | 22:21:53 INFO - PROCESS | 8984 | Cycle 1(1): loaded http://localhost:37182/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37182/tests/kraken/driver.html?testName=audio-beat-detection) 22:21:54 INFO - PROCESS | 8984 | RSS: Main: 188768256 22:21:54 INFO - PROCESS | 8984 | 22:21:56 INFO - PROCESS | 8984 | Cycle 1(1): loaded http://localhost:37182/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37182/tests/kraken/driver.html?testName=audio-dft) 22:21:57 INFO - PROCESS | 8984 | RSS: Main: 178372608 22:21:57 INFO - PROCESS | 8984 | 22:21:59 INFO - PROCESS | 8984 | Cycle 1(1): loaded http://localhost:37182/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37182/tests/kraken/driver.html?testName=audio-fft) 22:22:00 INFO - PROCESS | 8984 | RSS: Main: 269918208 22:22:00 INFO - PROCESS | 8984 | 22:22:02 INFO - PROCESS | 8984 | Cycle 1(1): loaded http://localhost:37182/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37182/tests/kraken/driver.html?testName=audio-oscillator) 22:22:03 INFO - PROCESS | 8984 | RSS: Main: 176750592 22:22:03 INFO - PROCESS | 8984 | 22:22:05 INFO - PROCESS | 8984 | Cycle 1(1): loaded http://localhost:37182/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37182/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:22:05 INFO - PROCESS | 8984 | RSS: Main: 179646464 22:22:05 INFO - PROCESS | 8984 | 22:22:08 INFO - PROCESS | 8984 | Cycle 1(1): loaded http://localhost:37182/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37182/tests/kraken/driver.html?testName=imaging-darkroom) 22:22:09 INFO - PROCESS | 8984 | RSS: Main: 217710592 22:22:09 INFO - PROCESS | 8984 | 22:22:12 INFO - PROCESS | 8984 | Cycle 1(1): loaded http://localhost:37182/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37182/tests/kraken/driver.html?testName=imaging-desaturate) 22:22:13 INFO - PROCESS | 8984 | RSS: Main: 215875584 22:22:13 INFO - PROCESS | 8984 | 22:22:16 INFO - PROCESS | 8984 | Cycle 1(1): loaded http://localhost:37182/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37182/tests/kraken/driver.html?testName=json-parse-financial) 22:22:16 INFO - PROCESS | 8984 | RSS: Main: 214921216 22:22:16 INFO - PROCESS | 8984 | 22:22:18 INFO - PROCESS | 8984 | Cycle 1(1): loaded http://localhost:37182/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37182/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:22:18 INFO - PROCESS | 8984 | RSS: Main: 176889856 22:22:18 INFO - PROCESS | 8984 | 22:22:20 INFO - PROCESS | 8984 | Cycle 1(1): loaded http://localhost:37182/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37182/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:22:21 INFO - PROCESS | 8984 | RSS: Main: 183996416 22:22:21 INFO - PROCESS | 8984 | 22:22:23 INFO - PROCESS | 8984 | Cycle 1(1): loaded http://localhost:37182/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37182/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:22:23 INFO - PROCESS | 8984 | RSS: Main: 190324736 22:22:23 INFO - PROCESS | 8984 | 22:22:26 INFO - PROCESS | 8984 | Cycle 1(1): loaded http://localhost:37182/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37182/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:22:26 INFO - PROCESS | 8984 | RSS: Main: 198848512 22:22:26 INFO - PROCESS | 8984 | 22:22:29 INFO - PROCESS | 8984 | Cycle 1(1): loaded http://localhost:37182/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37182/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:22:30 INFO - PROCESS | 8984 | RSS: Main: 195493888 22:22:30 INFO - PROCESS | 8984 | 22:22:31 INFO - PROCESS | 8984 | Cycle 1(1): loaded http://localhost:37182/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37182/tests/kraken/driver.html?testName=ai-astar) 22:22:32 INFO - PROCESS | 8984 | RSS: Main: 246312960 22:22:32 INFO - PROCESS | 8984 | 22:22:32 INFO - PROCESS | 8984 | __start_tp_report 22:22:32 INFO - PROCESS | 8984 | _x_x_mozilla_page_load 22:22:32 INFO - PROCESS | 8984 | _x_x_mozilla_page_load_details 22:22:32 INFO - PROCESS | 8984 | |i|pagename|runs| 22:22:32 INFO - PROCESS | 8984 | |0;ai-astar;100;111;112;110;111;111;111;111;110;110 22:22:32 INFO - PROCESS | 8984 | |1;audio-beat-detection;147;118;120;154;120;118;120;118;154;119 22:22:32 INFO - PROCESS | 8984 | |2;audio-dft;160;160;159;159;159;162;161;161;163;162 22:22:32 INFO - PROCESS | 8984 | |3;audio-fft;75;75;74;112;76;76;75;76;99;76 22:22:32 INFO - PROCESS | 8984 | |4;audio-oscillator;82;82;112;82;82;83;98;82;82;108 22:22:32 INFO - PROCESS | 8984 | |5;imaging-gaussian-blur;117;117;117;116;117;117;117;117;116;116 22:22:32 INFO - PROCESS | 8984 | |6;imaging-darkroom;163;148;148;149;149;158;147;149;147;152 22:22:32 INFO - PROCESS | 8984 | |7;imaging-desaturate;85;85;84;85;84;84;85;85;84;84 22:22:32 INFO - PROCESS | 8984 | |8;json-parse-financial;54;50;52;50;50;52;52;51;50;51 22:22:32 INFO - PROCESS | 8984 | |9;json-stringify-tinderbox;58;58;58;58;60;58;59;59;59;59 22:22:32 INFO - PROCESS | 8984 | |10;stanford-crypto-aes;75;75;83;75;79;76;74;74;82;77 22:22:32 INFO - PROCESS | 8984 | |11;stanford-crypto-ccm;122;120;122;123;130;125;132;127;191;122 22:22:32 INFO - PROCESS | 8984 | |12;stanford-crypto-pbkdf2;159;159;154;156;158;155;157;154;155;156 22:22:32 INFO - PROCESS | 8984 | |13;stanford-crypto-sha256-iterative;63;63;63;66;65;63;61;62;62;62 22:22:32 INFO - PROCESS | 8984 | __end_tp_report 22:22:32 INFO - PROCESS | 8984 | __start_cc_report 22:22:32 INFO - PROCESS | 8984 | _x_x_mozilla_cycle_collect,1620 22:22:32 INFO - PROCESS | 8984 | __end_cc_report 22:22:32 INFO - PROCESS | 8984 | __startTimestamp1482214952041__endTimestamp 22:22:32 INFO - PROCESS | 8984 | 22:22:32 INFO - PROCESS | 8984 | ------- Summary: start ------- 22:22:32 INFO - PROCESS | 8984 | Number of tests: 14 22:22:32 INFO - PROCESS | 8984 | 22:22:32 INFO - PROCESS | 8984 | [#0] ai-astar Cycles:10 Average:109.70 Median:111.00 stddev:3.47 (3.1%) stddev-sans-first:0.67 22:22:32 INFO - PROCESS | 8984 | Values: 100.0 111.0 112.0 110.0 111.0 111.0 111.0 111.0 110.0 110.0 22:22:32 INFO - PROCESS | 8984 | 22:22:32 INFO - PROCESS | 8984 | [#1] audio-beat-detection Cycles:10 Average:128.80 Median:120.00 stddev:15.92 (13.3%) stddev-sans-first:15.46 22:22:32 INFO - PROCESS | 8984 | Values: 147.0 118.0 120.0 154.0 120.0 118.0 120.0 118.0 154.0 119.0 22:22:32 INFO - PROCESS | 8984 | 22:22:32 INFO - PROCESS | 8984 | [#2] audio-dft Cycles:10 Average:160.60 Median:161.00 stddev:1.43 (0.9%) stddev-sans-first:1.50 22:22:32 INFO - PROCESS | 8984 | Values: 160.0 160.0 159.0 159.0 159.0 162.0 161.0 161.0 163.0 162.0 22:22:32 INFO - PROCESS | 8984 | 22:22:32 INFO - PROCESS | 8984 | [#3] audio-fft Cycles:10 Average:81.40 Median:76.00 stddev:13.08 (17.2%) stddev-sans-first:13.67 22:22:32 INFO - PROCESS | 8984 | Values: 75.0 75.0 74.0 112.0 76.0 76.0 75.0 76.0 99.0 76.0 22:22:32 INFO - PROCESS | 8984 | 22:22:32 INFO - PROCESS | 8984 | [#4] audio-oscillator Cycles:10 Average:89.30 Median:82.00 stddev:12.02 (14.7%) stddev-sans-first:12.45 22:22:32 INFO - PROCESS | 8984 | Values: 82.0 82.0 112.0 82.0 82.0 83.0 98.0 82.0 82.0 108.0 22:22:32 INFO - PROCESS | 8984 | 22:22:32 INFO - PROCESS | 8984 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 22:22:32 INFO - PROCESS | 8984 | Values: 117.0 117.0 117.0 116.0 117.0 117.0 117.0 117.0 116.0 116.0 22:22:32 INFO - PROCESS | 8984 | 22:22:32 INFO - PROCESS | 8984 | [#6] imaging-darkroom Cycles:10 Average:151.00 Median:149.00 stddev:5.33 (3.6%) stddev-sans-first:3.46 22:22:32 INFO - PROCESS | 8984 | Values: 163.0 148.0 148.0 149.0 149.0 158.0 147.0 149.0 147.0 152.0 22:22:32 INFO - PROCESS | 8984 | 22:22:32 INFO - PROCESS | 8984 | [#7] imaging-desaturate Cycles:10 Average:84.50 Median:85.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 22:22:32 INFO - PROCESS | 8984 | Values: 85.0 85.0 84.0 85.0 84.0 84.0 85.0 85.0 84.0 84.0 22:22:32 INFO - PROCESS | 8984 | 22:22:32 INFO - PROCESS | 8984 | [#8] json-parse-financial Cycles:10 Average:51.20 Median:51.50 stddev:1.32 (2.6%) stddev-sans-first:0.93 22:22:32 INFO - PROCESS | 8984 | Values: 54.0 50.0 52.0 50.0 50.0 52.0 52.0 51.0 50.0 51.0 22:22:32 INFO - PROCESS | 8984 | 22:22:32 INFO - PROCESS | 8984 | [#9] json-stringify-tinderbox Cycles:10 Average:58.60 Median:59.00 stddev:0.70 (1.2%) stddev-sans-first:0.71 22:22:32 INFO - PROCESS | 8984 | Values: 58.0 58.0 58.0 58.0 60.0 58.0 59.0 59.0 59.0 59.0 22:22:32 INFO - PROCESS | 8984 | 22:22:32 INFO - PROCESS | 8984 | [#10] stanford-crypto-aes Cycles:10 Average:77.00 Median:76.50 stddev:3.27 (4.3%) stddev-sans-first:3.38 22:22:32 INFO - PROCESS | 8984 | Values: 75.0 75.0 83.0 75.0 79.0 76.0 74.0 74.0 82.0 77.0 22:22:32 INFO - PROCESS | 8984 | 22:22:32 INFO - PROCESS | 8984 | [#11] stanford-crypto-ccm Cycles:10 Average:131.40 Median:126.00 stddev:21.29 (16.9%) stddev-sans-first:22.31 22:22:32 INFO - PROCESS | 8984 | Values: 122.0 120.0 122.0 123.0 130.0 125.0 132.0 127.0 191.0 122.0 22:22:32 INFO - PROCESS | 8984 | 22:22:32 INFO - PROCESS | 8984 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:156.30 Median:156.50 stddev:1.89 (1.2%) stddev-sans-first:1.73 22:22:32 INFO - PROCESS | 8984 | Values: 159.0 159.0 154.0 156.0 158.0 155.0 157.0 154.0 155.0 156.0 22:22:32 INFO - PROCESS | 8984 | 22:22:32 INFO - PROCESS | 8984 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.00 Median:63.00 stddev:1.49 (2.4%) stddev-sans-first:1.58 22:22:32 INFO - PROCESS | 8984 | Values: 63.0 63.0 63.0 66.0 65.0 63.0 61.0 62.0 62.0 62.0 22:22:32 INFO - PROCESS | 8984 | -------- Summary: end -------- 22:22:32 INFO - PROCESS | 8984 | 22:22:33 INFO - TEST-INFO | 8984: exit 0 22:22:33 INFO - TEST-OK | kraken | took 59779ms 22:22:33 INFO - SUITE-END | took 1174s 22:22:33 INFO - Completed test suite (00:19:34) 22:22:33 INFO - javascript benchmark 22:22:33 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3210.9197270068253, 3225.854836532356, 3256.120847371209, 3259.462188738858, 3259.8207098609582, 5456.7544460499275, 5555.083411237141, 5558.360788509322, 5567.86080347991, 5572.832815015556, 5508.6970216638065, 5512.476314750103, 5530.640508366953, 5536.058869992101, 5540.695261760601, 4971.527704868041, 4982.57648099912, 5008.824691135811, 5036.748162591855, 5086.79652813887, 4884.829030983917, 4895.657303988723, 5046.192609182532, 5099.133147364957, 5100.642954993152, 4953.9504604953845, 4960.032793605248, 4976.552110310071, 4999.900001999958, 5031.11955407804, 4995.725235112085, 5019.824306149304, 5074.720890351029, 5082.390113186404, 5102.617303702237, 4765.976170119162, 4979.402471703419, 4987.15218412867, 5024.84925452237, 5054.1407960646775, 5269.6311258211945, 5338.386085600167, 5346.545543628797, 5357.1696387060065, 5369.704666243355, 5738.713064346808, 5766.538676905838, 5876.059830427154, 5877.970610146922, 5883.499902508292, 5700.059490184115, 5902.144189092557, 5913.0, 5930.199423077873, 5932.19915311436, 5297.602679798992, 5363.865903352447, 5364.463553644604, 5373.38206106295, 5420.891582168335, 5836.06622940336, 5862.501687356537, 5863.501602363762, 5864.648121112747, 5866.23738913947, 5655.123455864347, 5657.094864821691, 5665.291838520185, 5666.348369937427, 5668.744906479218, 5633.605647604711, 5678.261825962599, 5722.656640601577, 5806.82579522615, 5816.651000939873, 5867.266591676041, 5871.32479764824, 5872.383399743051, 5895.882082358329, 5907.793227237112, 5814.941850581525, 5860.794872179454, 5873.295204575478, 5876.206712093898, 5880.882382352329, 4413.6248418884115, 4451.332300154938, 4512.887177820515, 4574.954250457453, 4581.656375771764, 4097.385392191195, 4112.0, 4135.565765594569, 4141.130362623882, 4166.500019997559, 5674.574406919498, 5695.231143795618, 5699.287589051369, 5704.572157088235, 5706.743196556165, 5673.432656734293, 5718.399568045378, 5728.427157284238, 5971.970140149271, 5980.0431930890845, 5553.8333849984565, 5609.467100625434, 5620.100783874642, 5639.351474580394, 5650.660960342392, 5022.070916880389, 5047.899042019212, 5055.747212639353, 5056.418511871108, 5059.139946209153, 5699.173619825185, 5915.526757859275, 5975.193348897843, 5988.371221021817, 5992.940070599326, 5629.6903670298525, 5765.56758243125, 5786.971065144648, 5885.323187833454, 5966.940330596639, 6047.183630210041, 6052.455279024909, 6054.515638748978, 6059.818205453844, 6061.302950160788, 5794.594378393641, 5803.245578074823, 5811.593188476766, 5812.82561523155, 5839.649621022752, 5593.692346920796, 5599.104143337209, 5770.451807078321, 5833.095870140135, 5852.4440178183, 5801.6519008859605, 5803.042497987784, 5807.767689292381, 5817.0, 5827.5337972961215, 5730.0, 5795.362510123968, 5795.594308398372, 5799.82600521985, 5800.187973683772, 5419.593530485151, 5422.0, 5599.552035837205, 5679.630823996427, 5742.368339482737, 5850.12248162779, 5850.53195744348, 5855.970720146372, 5856.267966504187, 5889.381614930542, 5332.173513105475, 5382.757775900172, 5713.485786279255, 5720.2849643794525, 5721.141828725725], "value": 5400.921719576344, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [559.8432438917102, 589.6196952965338, 625.087372436243, 633.86371930035, 659.5745743995126, 33891.66108338919, 44261.114777704424, 45428.091438171214, 45516.544834551685, 49146.26280605794, 46225.7688711556, 46562.53437465624, 46619.0, 46745.53254467454, 46791.76604116983, 8256.25693687568, 8923.732288031353, 9072.36493445459, 9080.0919908009, 9083.182513573776, 8297.336213102937, 10697.19771017173, 10952.178586605996, 10954.616588419407, 11297.943510282437, 10615.203859710515, 10663.84004239937, 10664.253502254845, 10666.839997400006, 10667.466626668675, 10953.83569246462, 10979.121670266359, 11011.449427528672, 11022.614208502744, 11025.724356891142, 10811.027007569357, 10873.401962892038, 10886.836697449544, 10895.291806032583, 10905.291156074909, 43476.3478547822, 43566.78216608897, 43720.78139609282, 43763.0, 43784.78107609473, 3702.740808143431, 3760.7931563764255, 3820.465134881119, 4013.678905687538, 4347.413099231595, 32306.676933230603, 32409.67590324114, 32617.347653047043, 32922.6707732922, 32935.50596741051, 4450.2434586120435, 4549.555355071538, 4571.839985600522, 4594.609458196024, 4689.601383882374, 355663.0, 356361.0, 357420.0, 357615.9999999974, 357953.0, 59407.10889336663, 59723.10415343774, 60135.0, 60174.09738853921, 60243.39756602466, 39183.804080979986, 40284.0, 40414.59585404167, 42913.0, 63765.36234637687, 17099.23053462597, 17111.058891760822, 17161.570960726083, 17171.141442927805, 17196.398126065527, 13298.268595227162, 13499.730005399837, 14182.432702691984, 14592.270386480634, 14734.115953042852, 19655.705164422547, 20654.896725516577, 20662.690059649118, 20722.896385518277, 20787.79212207889, 7374.557526548424, 7386.409087273005, 7516.549007059594, 7595.620218989028, 7690.577018264049, 935.4854829843695, 1015.6851376073441, 1061.0927656853435, 1071.5927947379946, 1104.9779004420027, 33530.32939341248, 33773.83113084419, 34095.659043409745, 34234.82882585571, 34539.82730086333, 1139.601139601133, 1160.3502038858267, 1189.149757923089, 1203.6930582701355, 1259.9118061735774, 17885.195166217552, 18017.19828017214, 18447.771639962953, 18671.90664046671, 19364.70952935707, 511775.0, 516219.0, 519314.0, 520527.0, 520773.0, 60783.39216607866, 61425.0, 61595.0, 61672.69163654153, 61689.69155154195, 1273.9171953822972, 1323.6624660711457, 1376.6558360409897, 1386.8682475164844, 1432.534426311453, 33069.83465082659, 35161.29677406489, 35499.82250088733, 35619.28761424809, 35640.28719425545, 51440.22839657333, 52101.21848172205, 52139.47860521346, 52192.0, 52268.21597675962, 422810.0, 426753.0, 427278.0, 427711.0, 428250.0, 27429.17712468629, 27794.305142370795, 28042.0, 28139.748820093933, 32597.044177349435, 49186.0, 49441.0, 49553.25670115023, 49631.00737985148, 49693.0, 41014.0, 41712.58287417208, 41827.0, 42000.0, 43489.565104349815, 42536.57463425326, 43145.0, 43191.35212971871, 43931.0, 44012.559874400846, 41585.58414415817, 42605.78697106495, 42646.36030459483, 42677.57322426736, 42816.14367712691, 36421.0, 38718.419223711106, 39001.414978774774, 39038.0, 39476.21047578975, 29032.225810644482, 29069.0, 29178.270543236587, 29474.852625737592, 29623.25941851471], "value": 21062.182775261863, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [398.4222478983226, 417.1323646814626, 421.9620234178924, 426.68638550665264, 443.6916343141516, 15275.465358712447, 19198.52003699908, 20012.19951201952, 20185.09167087481, 20378.69431958522, 19420.22319107234, 20709.06809193586, 20712.792872071273, 20714.964251787427, 20932.58134837306, 5818.5926985110955, 5973.641581505123, 6063.939360606404, 6066.302375226839, 6067.908981365283, 5759.308882934033, 5937.584369094165, 5957.6127551709, 5959.0763431668165, 5964.761409543613, 5728.112142617901, 5925.081612350093, 5927.792527261547, 5933.199018132562, 5936.940630593704, 5957.344692083867, 6114.174586430864, 6126.26484821822, 6127.417895299939, 6129.448349648553, 5821.621594596338, 5877.588568800185, 5899.469047785677, 5902.085176797603, 5945.435183657545, 14993.250337483192, 15237.085774853544, 15269.541913742605, 22211.22260720883, 24310.635340469908, 2267.5691618592414, 2361.090979972723, 2385.522895420923, 2392.1268736911074, 2519.407939134302, 11663.183577149603, 11714.882851171464, 13185.0, 15230.086194828345, 16080.758788618181, 1968.7145363922293, 2003.8497112716605, 2044.1210279579775, 2054.4555692741437, 2059.5571952030386, 84375.578122109, 86852.56573717154, 88735.11264887398, 89195.10804891998, 89976.55011724899, 10577.894221057844, 11879.346635934933, 11894.524219031142, 12145.392730363623, 12367.752644947052, 15491.845081549265, 15573.377064917502, 16389.426370077228, 16414.589635259214, 17144.314227430765, 8930.732078037669, 9046.81906361869, 9088.54557272147, 9095.863562046574, 9135.95432022849, 8721.389502734748, 8727.869081963776, 8736.519491428033, 8865.734027979172, 9052.0, 5111.514406131411, 5190.0, 5221.086309895753, 5330.520253177233, 5339.546138578265, 2048.8463365247667, 2074.481379655086, 2106.915723371048, 2111.3243761996014, 2143.9142434302453, 940.290080988849, 985.2364417576435, 1001.9499025048718, 1020.5968642386216, 1069.4866464097277, 19937.60124797496, 20554.07506662174, 20670.483237919172, 20679.689804652942, 20693.137617614488, 998.0418797954045, 1034.6585626743156, 1039.0596510158318, 1046.638909576195, 1059.4490864750287, 10899.0, 11502.53989840397, 11843.763124737628, 11850.762984740257, 11882.871127242897, 446270.0, 460264.0, 468144.0, 477164.0, 479501.0, 93902.0, 96572.51713741386, 96842.0, 97708.51145744226, 99198.0, 990.5938565188238, 1016.2378216337746, 1027.7276521721749, 1033.565902321027, 1037.0459177556666, 17761.911190444484, 17859.73210401871, 18281.81718182801, 18483.353082642043, 18568.25726970906, 43127.78436107925, 43548.782256089784, 43674.12651747011, 43792.78103609462, 44895.0, 59138.70430647819, 59820.40179598148, 60232.69883650554, 60896.69551652214, 61248.69375653272, 5934.376890426443, 7502.36229920441, 8594.226519613265, 9364.063593640582, 9601.519924003633, 11911.0, 13815.549367316287, 13998.0, 14107.576772696835, 14281.785773213202, 12118.575849845212, 12161.33112678811, 12328.445219965302, 12579.74840503203, 12870.22778633285, 11858.347790871585, 12132.393380331125, 12474.251544907334, 12569.1201615886, 12757.170783899017, 12295.200811947196, 12429.0, 12471.376431178587, 12478.25130492137, 12534.0, 9320.207782338306, 9361.297902657465, 9393.060693930553, 9551.570179342085, 9554.426734395958, 11929.033571509954, 12337.876621233672, 12407.0, 12498.875011249771, 12849.100562960877], "value": 9671.291728177808, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1355.3629793996818, 1498.6702925356421, 1504.022385449458, 1669.4240487031977, 1855.8051404602516, 7720.266574675404, 8545.188207120322, 8913.108689131084, 8918.375713700027, 9183.77040573986, 8956.10438956104, 8982.416142950704, 8992.325575581825, 9027.368084234105, 9037.231835293991, 2466.383404148967, 2483.950320993579, 2491.8006559475198, 2491.9750802492017, 2495.3886297857052, 2445.9510809783883, 2525.0657256815, 2535.1253817432957, 2537.200781753751, 2539.0, 2415.5168966206743, 2423.987880060606, 2482.950340993179, 2485.1674688979215, 2495.15164843953, 2423.0550085466684, 2431.659567660529, 2440.7925326347104, 2457.680501534807, 2462.4090218347646, 2407.482391285882, 2410.9397265068337, 2432.8905199266073, 2438.146648672968, 2467.321486591202, 8657.307415406753, 8883.067277935854, 8950.0, 8958.238549723215, 9011.909880901238, 5497.312835895513, 5652.434756524356, 5657.830265092054, 5664.546836253131, 5672.5178359839465, 3387.1193489692855, 3529.5235143255845, 3547.1486843157713, 3599.2981368633127, 3609.9819500902327, 1059.1685526861377, 1078.449990504848, 1083.3175099687146, 1115.1357697784201, 1121.534563156289, 12532.624021279285, 12568.874311256863, 12603.873961260362, 12616.873831261662, 12661.683457913441, 4520.254158063883, 4715.386999690087, 4777.904441911142, 4824.6863953842885, 4931.087748766489, 9791.167750741266, 9791.216702663769, 9792.16766574835, 9792.804143917225, 9798.951005244928, 5511.58663100269, 5514.696691681916, 5516.172574113834, 5519.724013799294, 5520.751566179451, 4273.0, 4469.754163521037, 4688.085823264499, 5039.571636410946, 5161.122609156452, 3947.6644485219076, 3963.801809909493, 4065.166640838621, 4074.144429669743, 4082.81627326771, 1186.691460220349, 1218.945147468366, 1222.480445810527, 1233.1491271022965, 1240.3240234072273, 953.4803532075035, 984.7390441532999, 991.3704797453524, 994.1053052252886, 1003.0771690045176, 5915.733791979371, 5928.258967629046, 6076.483498902643, 6090.817275481742, 6101.206843110367, 453.6212262760566, 458.63767623577, 462.81024779840106, 464.8884267775777, 466.8062753957138, 4122.2579935611875, 4130.339145736728, 4149.875503734893, 4165.12532368206, 4220.978895105505, 10998.450077496092, 11163.665090047312, 11323.94338028321, 11330.263532870336, 11336.886631133582, 9934.602615895283, 10365.533550990223, 10369.792604147875, 11219.270747401393, 11233.38216398106, 8271.379646526415, 8476.406651534457, 8476.745697629081, 8478.279346255515, 8479.279261262862, 6074.1633826394045, 6089.391060893875, 6100.969495152496, 6223.159873417031, 6320.336364681642, 10301.484925753532, 10304.639337623148, 10308.793824123626, 10336.431496267778, 10388.532516036645, 8942.552872356486, 9104.04407537199, 10453.895461045597, 10454.790904181722, 10466.215033872339, 7202.855942881218, 7973.0, 8018.238267364591, 8135.837283254184, 8257.83484330322, 8778.341624378325, 8795.25240354577, 8836.690715824916, 8840.690575829814, 8840.823183536422, 7757.534547927143, 7859.371250300077, 7862.213778622092, 8015.83968320642, 8171.387145963958, 7713.7300194495165, 7810.765677029698, 7819.648115834915, 7929.841403171788, 7991.72028978983, 7090.574565526085, 7134.643267836691, 7197.640117994184, 7205.567665940061, 7207.71169153249, 5167.689938603696, 5239.528442440199, 5242.633015688865, 5246.160614301846, 5437.184422336682, 5293.920591191212, 5305.098133317269, 5322.920156197582, 5331.893362132813, 5364.14173732201], "value": 4645.446240085793, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.09062256886943, 359.3675131768088, 359.5721091900638, 364.16060979442375, 364.1933118143312, 5322.7072511011875, 5360.9463905360935, 5372.8448383597515, 5381.542568881645, 5458.4541545845395, 5022.447530771612, 5164.431912489633, 5177.870553236162, 5295.920561191585, 5300.761465734042, 4205.369194620816, 4214.431051808013, 4219.303814870543, 4228.957710422887, 4234.30134027885, 4197.622214000739, 4212.55768144345, 4216.852410165645, 4224.0, 4232.301670224425, 3959.5644479107277, 3989.52125744911, 4164.416981622575, 4178.853740119096, 4216.494020717503, 4162.666986641092, 4175.895602609929, 4181.853635122787, 4186.518550366701, 4189.958100418988, 3749.3063783199923, 3750.587435382106, 3755.0800053986677, 3757.3236817372863, 3766.227923275722, 5049.924251136236, 5141.760690764831, 5173.120569503193, 5176.352955880515, 5205.401378841444, 4772.284157376386, 4824.372831531879, 4831.685940413862, 4843.878903027417, 4843.934334446416, 5548.91676624851, 5550.27846379972, 5550.611457197998, 5552.194931734876, 5573.6098473106495, 3983.7609743415487, 4097.631213190797, 4121.649659778923, 4164.541900390955, 4165.0, 5725.770969161229, 5731.914021289684, 5734.197212390269, 5734.885302293931, 5744.195812586241, 4219.367094935723, 4223.458437670228, 4227.3447615619625, 4231.30183519722, 4238.639715624145, 4188.518320393133, 4251.766987573631, 4262.403263543107, 4288.335308027261, 4300.795777182394, 4777.976110119427, 4793.784279707422, 4803.495632958559, 4815.807367705253, 4820.6143508519235, 5493.148561972901, 5546.0, 5550.91673624896, 5560.527355174774, 5563.332400112013, 1401.978970315446, 1426.6076828872144, 1431.8926080544002, 1436.1598464898152, 1441.3802065112102, 951.5242378810594, 978.8825340958988, 1004.5379125602304, 1010.7523656704154, 1021.2187676427538, 5209.583233341324, 5213.949839344026, 5214.0, 5238.76425560843, 5251.741751047087, 5413.702246376409, 5422.810201642924, 5434.538064264502, 5470.617056806065, 5472.825761058576, 5199.050356116456, 5264.710440925785, 5489.258950041693, 5516.668999860021, 5522.834314970557, 2918.2558447596152, 2937.427201695649, 2955.88176472943, 2971.465136275491, 2972.390659914712, 5524.530414914697, 5632.986062508706, 5651.343868381854, 5712.171735098387, 5716.885662286731, 5574.804881829116, 5619.887602247933, 5623.634463759924, 5637.633553818989, 5679.176519404663, 5591.41290164535, 5621.49406553404, 5632.352279487911, 5658.292713410824, 5659.717014149359, 5385.2191432241325, 5472.72636368188, 5525.309336332958, 5538.529225015919, 5552.472515111058, 5438.912217556425, 5454.209139674805, 5469.671819690831, 5475.67145971243, 5482.533984611353, 5440.646357986719, 5563.666180029211, 5635.887282254414, 5636.464535869086, 5659.858503537444, 3988.8603898863403, 4009.278329900646, 4045.5549889512718, 4048.1701251244012, 4063.309237429703, 5409.702466364388, 5472.91790623149, 5473.0, 5488.280079132023, 5523.7238138093735, 5179.585633149414, 5346.679199248058, 5357.1696387060065, 5374.704391258519, 5382.892342153214, 5364.4786950133375, 5392.193615138973, 5415.620906536505, 5440.211169380497, 5450.400455949922, 5278.181881808326, 5302.337207849019, 5316.787328506971, 5343.198520221842, 5461.754221060141, 5487.094629386266, 5560.749766260609, 5567.55459563242, 5573.219749235192, 5573.470520300565, 5372.919406208832, 5409.729513524229, 5420.9186862197885, 5442.727863606883, 5447.202423200255], "value": 4230.130588091323, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [741.9962900185499, 744.2297222374841, 818.5661599352345, 828.9046759622642, 909.6634245329229, 14183.0, 14812.185329806856, 15290.46483373082, 15372.538823835288, 15374.69250614987, 12706.555270565532, 13816.101953373023, 13917.23455209963, 13925.094868833545, 13952.093113947587, 836.7238811192292, 857.2199298638241, 858.4291446188278, 859.5401460218784, 860.5267103093273, 857.7812657772259, 861.2593169873903, 862.1895418306772, 862.4307956748515, 862.8360611483829, 898.1512470715177, 898.9910100898973, 899.4153800029967, 900.0954041188596, 901.3870568013748, 889.5685592487638, 891.5364010714457, 892.848215803315, 893.2095095840164, 893.2407453664366, 657.6251536624126, 658.3613894522306, 658.6739563915844, 658.8155316511385, 659.6734616364882, 12650.74698506034, 12685.112042157052, 12739.171953822972, 13685.384157712926, 14125.505607303696, 14071.577852664437, 14659.0, 14812.777808332883, 14816.185109818956, 15083.170425626586, 12268.631941041784, 12305.26168429897, 12336.198147120498, 12357.876421235762, 12363.814542781865, 3722.9441558376648, 3767.566729826078, 3806.4670946067295, 3854.2869569129803, 3861.0, 66032.66983665052, 66054.3394566053, 67243.3275667242, 67688.0, 67815.66092169557, 9839.950800246095, 10268.127209187154, 10405.53175107122, 10658.467076646137, 10845.186611004206, 19764.0, 21070.47323816917, 21097.578048438947, 21109.68335474969, 21114.577708445744, 8294.917050829414, 8496.235338819411, 8552.0, 8662.566871656392, 8699.782505437415, 4807.591354734816, 4826.61387089032, 4833.540813622701, 4846.224604063333, 5084.313617661643, 3460.9826950865086, 3539.94690079649, 3571.8928432147077, 3589.587197472272, 3602.171500554887, 597.6503745308956, 604.9364816694272, 622.819382379105, 628.3998781164069, 632.206580741176, 691.6576294734139, 712.0814149746872, 733.5012191709582, 756.8902509136254, 770.7263921307967, 9486.0, 9879.703608891745, 9952.751181220383, 10510.579576816994, 10549.419781912069, 664.7540410048313, 690.9723611055501, 738.3576288628927, 780.5277806926841, 783.2793829676712, 4568.0, 4894.951050489521, 4925.335079764258, 4958.8760280993265, 4972.726500042532, 56940.71529642325, 57470.712646437336, 57635.71182144062, 57757.42242577521, 58671.11993320104, 51418.74290628523, 51483.48516514862, 63668.68165659142, 80784.15295082313, 83190.58404708059, 62475.37524624696, 67054.32945670481, 70115.29884701088, 71524.0, 71640.0, 9714.902850971683, 10162.74593135148, 10188.847167292348, 10189.976730455272, 10264.127549158107, 48717.5128248713, 48999.75500122619, 49183.0, 50054.0, 50681.0, 58392.0, 62173.0, 63947.0, 64323.678381607795, 65502.344976549626, 11965.40482605655, 11995.82006269924, 12823.102382833486, 12857.485700571873, 13227.272500012206, 13236.13965092266, 13249.668758281121, 13293.933530332286, 13328.466861325438, 14366.066205696596, 12723.109382343517, 13139.60581182566, 13448.731025379633, 13511.446948230297, 13656.248906310248, 12995.0, 13062.869371306166, 13081.345932703518, 13358.599242022754, 13611.11527750693, 13314.93342533281, 13390.598282051555, 13461.663458413617, 13516.662083447993, 13528.39122239482, 10763.0, 11173.809865888674, 11273.323600583992, 11566.248193867372, 11777.823332650189, 11151.83272250933, 11310.886891130984, 11497.1377146716, 11611.709707257387, 11661.35862527569], "value": 7236.975941324179, "unit": "score"}], "name": "dromaeo_css", "value": 7340.746054448321, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 111.0, 112.0, 110.0, 111.0, 111.0, 111.0, 111.0, 110.0, 110.0], "value": 109.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 118.0, 120.0, 154.0, 120.0, 118.0, 120.0, 118.0, 154.0, 119.0], "value": 128.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 159.0, 159.0, 159.0, 162.0, 161.0, 161.0, 163.0, 162.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 74.0, 112.0, 76.0, 76.0, 75.0, 76.0, 99.0, 76.0], "value": 81.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 112.0, 82.0, 82.0, 83.0, 98.0, 82.0, 82.0, 108.0], "value": 89.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 116.0, 116.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 148.0, 148.0, 149.0, 149.0, 158.0, 147.0, 149.0, 147.0, 152.0], "value": 151.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 84.0, 85.0, 84.0, 84.0, 85.0, 85.0, 84.0, 84.0], "value": 84.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 50.0, 52.0, 50.0, 50.0, 52.0, 52.0, 51.0, 50.0, 51.0], "value": 51.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 58.0, 58.0, 60.0, 58.0, 59.0, 59.0, 59.0, 59.0], "value": 58.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 83.0, 75.0, 79.0, 76.0, 74.0, 74.0, 82.0, 77.0], "value": 77.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 120.0, 122.0, 123.0, 130.0, 125.0, 132.0, 127.0, 191.0, 122.0], "value": 131.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 159.0, 154.0, 156.0, 158.0, 155.0, 157.0, 154.0, 155.0, 156.0], "value": 156.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 63.0, 66.0, 65.0, 63.0, 61.0, 62.0, 62.0, 62.0], "value": 63.0, "unit": "score"}], "name": "kraken", "value": 1459.5, "alertThreshold": 2.0}]} 22:22:33 INFO - Return code: 0 22:22:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:22:33 INFO - # TBPL SUCCESS # 22:22:33 INFO - Running post-action listener: _resource_record_post_action 22:22:33 INFO - [mozharness: 2016-12-20 06:22:33.332366Z] Finished run-tests step (success) 22:22:33 INFO - Running post-run listener: _resource_record_post_run 22:22:33 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 262144; Write bytes: 356384768; Read time: 12; Write time: 257628 22:22:33 INFO - TinderboxPrint: CPU usage
13.0% 22:22:33 INFO - TinderboxPrint: I/O read bytes / time
262,144 / 12 22:22:33 INFO - TinderboxPrint: I/O write bytes / time
356,384,768 / 257,628 22:22:33 INFO - TinderboxPrint: CPU idle
8,274.0 (87.0%) 22:22:33 INFO - TinderboxPrint: CPU user
1,194.2 (12.6%) 22:22:33 INFO - TinderboxPrint: Swap in / out
0 / 0 22:22:33 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 107757568; Read time: 0; Write time: 105960 22:22:33 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 262144; Write bytes: 248229888; Read time: 12; Write time: 151668 22:22:33 INFO - Running post-run listener: _upload_blobber_files 22:22:33 INFO - Blob upload gear active. 22:22:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:22:33 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 22:22:33 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:22:33 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:22:34 INFO - (blobuploader) - INFO - Open directory for files ... 22:22:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:22:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:22:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:22:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:22:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:22:35 INFO - (blobuploader) - INFO - Done attempting. 22:22:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:22:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:22:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:22:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:22:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:22:36 INFO - (blobuploader) - INFO - Done attempting. 22:22:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:22:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:22:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:22:39 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:22:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:22:39 INFO - (blobuploader) - INFO - Done attempting. 22:22:39 INFO - (blobuploader) - INFO - Iteration through files over. 22:22:39 INFO - Return code: 0 22:22:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:22:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:22:39 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b3a70b685bd87f349b8bbce8e3b6362e722c503e62950b91be069aab22295322c884f5518898fec302fbec6f504a9e91c4ce699291087126bf8605a2d6e5b043", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/42265449d373fcfbbca7705a10e334e601b1841bf49d99d0afc36417c90472d34863eac7ad18ba1ba84ee236620158f45cf42014b5d55239a94cb62847fe60ce", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fc971cda39116121b6dbc9849b892b19dae23565e50975b8c11a66814caf0bf0af5a039ae85a4ddcc6d9942915e979228098623b778187431ac4d602e634ea9d"} 22:22:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:22:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:22:39 INFO - Contents: 22:22:39 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b3a70b685bd87f349b8bbce8e3b6362e722c503e62950b91be069aab22295322c884f5518898fec302fbec6f504a9e91c4ce699291087126bf8605a2d6e5b043", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/42265449d373fcfbbca7705a10e334e601b1841bf49d99d0afc36417c90472d34863eac7ad18ba1ba84ee236620158f45cf42014b5d55239a94cb62847fe60ce", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fc971cda39116121b6dbc9849b892b19dae23565e50975b8c11a66814caf0bf0af5a039ae85a4ddcc6d9942915e979228098623b778187431ac4d602e634ea9d"} 22:22:39 INFO - Running post-run listener: copy_logs_to_upload_dir 22:22:39 INFO - Copying logs to upload dir... 22:22:39 INFO - mkdir: /builds/slave/test/build/upload/logs 22:22:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1237.718081 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-12-19 22:22:39.053835) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-19 22:22:39.062951) ========= 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=14ef55db36f7623abb31b9cf000001d5-1482213504.472085-960774253 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b3a70b685bd87f349b8bbce8e3b6362e722c503e62950b91be069aab22295322c884f5518898fec302fbec6f504a9e91c4ce699291087126bf8605a2d6e5b043", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/42265449d373fcfbbca7705a10e334e601b1841bf49d99d0afc36417c90472d34863eac7ad18ba1ba84ee236620158f45cf42014b5d55239a94cb62847fe60ce", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fc971cda39116121b6dbc9849b892b19dae23565e50975b8c11a66814caf0bf0af5a039ae85a4ddcc6d9942915e979228098623b778187431ac4d602e634ea9d"} build_url:https://queue.taskcluster.net/v1/task/eHzeF_F7QtqJx1Jdri-D4g/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011319 build_url: 'https://queue.taskcluster.net/v1/task/eHzeF_F7QtqJx1Jdri-D4g/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b3a70b685bd87f349b8bbce8e3b6362e722c503e62950b91be069aab22295322c884f5518898fec302fbec6f504a9e91c4ce699291087126bf8605a2d6e5b043", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/42265449d373fcfbbca7705a10e334e601b1841bf49d99d0afc36417c90472d34863eac7ad18ba1ba84ee236620158f45cf42014b5d55239a94cb62847fe60ce", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fc971cda39116121b6dbc9849b892b19dae23565e50975b8c11a66814caf0bf0af5a039ae85a4ddcc6d9942915e979228098623b778187431ac4d602e634ea9d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-19 22:22:39.093998) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-19 22:22:39.094344) ========= 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=14ef55db36f7623abb31b9cf000001d5-1482213504.472085-960774253 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004738 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-19 22:22:39.140033) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-19 22:22:39.140361) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-19 22:22:39.140650) ========= ========= Total master_lag: 0.17 =========