builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-060 starttime: 1480441072.71 results: success (0) buildid: 20161129062926 builduid: 048a0259962446d380020565c40f4e96 revision: 130bb29013a1d089dd533e50e170eabedce157d8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-29 09:37:52.714459) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-29 09:37:52.714917) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-29 09:37:52.715189) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-29 09:37:52.729464) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 09:37:52.729739) ========= 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=337fe3f4b1febe37b85fccc40000019e-1480441072.182730-152408469 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-11-29 09:37:52-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.0M=0s 2016-11-29 09:37:52 (46.0 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.114991 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 09:37:52.858326) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 09:37:52.858639) ========= 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=337fe3f4b1febe37b85fccc40000019e-1480441072.182730-152408469 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017128 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 09:37:52.908094) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 09:37:52.908450) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 130bb29013a1d089dd533e50e170eabedce157d8 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 130bb29013a1d089dd533e50e170eabedce157d8 --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=337fe3f4b1febe37b85fccc40000019e-1480441072.182730-152408469 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-11-29 09:37:53,093 truncating revision to first 12 chars 2016-11-29 09:37:53,093 Setting DEBUG logging. 2016-11-29 09:37:53,093 attempt 1/10 2016-11-29 09:37:53,093 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/130bb29013a1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-29 09:37:53,397 unpacking tar archive at: mozilla-central-130bb29013a1/testing/mozharness/ program finished with exit code 0 elapsedTime=0.600257 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 09:37:53.522144) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-29 09:37:53.522457) ========= script_repo_revision: 130bb29013a1d089dd533e50e170eabedce157d8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-29 09:37:53.522831) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-29 09:37:53.523091) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-29 09:37:53.533709) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-11-29 09:37:53.534096) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=337fe3f4b1febe37b85fccc40000019e-1480441072.182730-152408469 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:37:53 INFO - MultiFileLogger online at 20161129 09:37:53 in /builds/slave/test 09:37:53 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 09:37:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:37:53 INFO - {'append_to_log': False, 09:37:53 INFO - 'base_work_dir': '/builds/slave/test', 09:37:53 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 09:37:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:37:53 INFO - 'branch': 'Firefox-Non-PGO', 09:37:53 INFO - 'buildbot_json_path': 'buildprops.json', 09:37:53 INFO - 'config_files': ('talos/linux_config.py',), 09:37:53 INFO - 'default_actions': ('clobber', 09:37:53 INFO - 'read-buildbot-config', 09:37:53 INFO - 'download-and-extract', 09:37:53 INFO - 'populate-webroot', 09:37:53 INFO - 'create-virtualenv', 09:37:53 INFO - 'install', 09:37:53 INFO - 'run-tests'), 09:37:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:37:53 INFO - 'download_minidump_stackwalk': True, 09:37:53 INFO - 'download_symbols': 'ondemand', 09:37:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:37:53 INFO - 'tooltool.py': '/tools/tooltool.py', 09:37:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:37:53 INFO - '/tools/misc-python/virtualenv.py')}, 09:37:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:37:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:37:53 INFO - 'installer_path': 'installer.exe', 09:37:53 INFO - 'log_level': 'info', 09:37:53 INFO - 'log_name': 'talos', 09:37:53 INFO - 'log_to_console': True, 09:37:53 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:37:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:37:53 INFO - 'opt_config_files': (), 09:37:53 INFO - 'pip_index': False, 09:37:53 INFO - 'sps_profile': False, 09:37:53 INFO - 'sps_profile_interval': 0, 09:37:53 INFO - 'suite': 'dromaeojs', 09:37:53 INFO - 'system_bits': '32', 09:37:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:37:53 INFO - 'title': 'talos-linux64-ix-060', 09:37:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:37:53 INFO - 'use_talos_json': True, 09:37:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:37:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:37:53 INFO - 'work_dir': 'build'} 09:37:53 INFO - [mozharness: 2016-11-29 17:37:53.816931Z] Running clobber step. 09:37:53 INFO - Running pre-action listener: _resource_record_pre_action 09:37:53 INFO - Running main action method: clobber 09:37:53 INFO - rmtree: /builds/slave/test/build 09:37:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:37:54 INFO - Running post-action listener: _resource_record_post_action 09:37:54 INFO - [mozharness: 2016-11-29 17:37:54.268915Z] Finished clobber step (success) 09:37:54 INFO - [mozharness: 2016-11-29 17:37:54.269016Z] Running read-buildbot-config step. 09:37:54 INFO - Running pre-action listener: _resource_record_pre_action 09:37:54 INFO - Running main action method: read_buildbot_config 09:37:54 INFO - Using buildbot properties: 09:37:54 INFO - { 09:37:54 INFO - "project": "", 09:37:54 INFO - "product": "firefox", 09:37:54 INFO - "script_repo_revision": "production", 09:37:54 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 09:37:54 INFO - "repository": "", 09:37:54 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 09:37:54 INFO - "buildid": "20161129062926", 09:37:54 INFO - "pgo_build": "False", 09:37:54 INFO - "basedir": "/builds/slave/test", 09:37:54 INFO - "buildnumber": 731, 09:37:54 INFO - "slavename": "talos-linux64-ix-060", 09:37:54 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 09:37:54 INFO - "platform": "ubuntu64_hw", 09:37:54 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 09:37:54 INFO - "branch": "mozilla-central", 09:37:54 INFO - "repo_path": "mozilla-central", 09:37:54 INFO - "revision": "130bb29013a1d089dd533e50e170eabedce157d8", 09:37:54 INFO - "stage_platform": "linux64", 09:37:54 INFO - "builduid": "048a0259962446d380020565c40f4e96", 09:37:54 INFO - "slavebuilddir": "test" 09:37:54 INFO - } 09:37:54 INFO - Found installer url https://queue.taskcluster.net/v1/task/b86Ic-esQi2JZLx9uVoKyA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 09:37:54 INFO - Running post-action listener: _resource_record_post_action 09:37:54 INFO - [mozharness: 2016-11-29 17:37:54.271442Z] Finished read-buildbot-config step (success) 09:37:54 INFO - [mozharness: 2016-11-29 17:37:54.271529Z] Running download-and-extract step. 09:37:54 INFO - Running pre-action listener: _resource_record_pre_action 09:37:54 INFO - Running main action method: download_and_extract 09:37:54 INFO - mkdir: /builds/slave/test/build/tests 09:37:54 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')]} 09:37:54 INFO - trying https://queue.taskcluster.net/v1/task/b86Ic-esQi2JZLx9uVoKyA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 09:37:54 INFO - Downloading https://queue.taskcluster.net/v1/task/b86Ic-esQi2JZLx9uVoKyA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 09:37:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b86Ic-esQi2JZLx9uVoKyA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 09:37:54 INFO - Downloaded 1361 bytes. 09:37:54 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 09:37:54 INFO - Using the following test package requirements: 09:37:54 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 09:37:54 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:37:54 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:37:54 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:37:54 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:37:54 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:37:54 INFO - u'jsshell-linux-x86_64.zip'], 09:37:54 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:37:54 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:37:54 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 09:37:54 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:37:54 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:37:54 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:37:54 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:37:54 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:37:54 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:37:54 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:37:54 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:37:54 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:37:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b86Ic-esQi2JZLx9uVoKyA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 09:37:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b86Ic-esQi2JZLx9uVoKyA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:37:54 INFO - Fetch https://queue.taskcluster.net/v1/task/b86Ic-esQi2JZLx9uVoKyA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:37:55 INFO - Http code: 200 09:37:55 INFO - via: 1.1 73fa5a1ee49cf827e596b502b5927eab.cloudfront.net (CloudFront) 09:37:55 INFO - x-amz-cf-id: i7uV0PKy7PCdsU5hMyoIueFkhGZlNkgAqzoSEAKYDOnYqnJUKZQHtA== 09:37:55 INFO - x-amz-version-id: 2J1HmNntOSvCEzYZbyXYRTza8awk1Noc 09:37:55 INFO - Expected file size: 20219554 09:37:55 INFO - Obtained file size: 20219554 09:37:59 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:37:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b86Ic-esQi2JZLx9uVoKyA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 09:37:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b86Ic-esQi2JZLx9uVoKyA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:37:59 INFO - Fetch https://queue.taskcluster.net/v1/task/b86Ic-esQi2JZLx9uVoKyA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:38:00 INFO - Http code: 200 09:38:00 INFO - via: 1.1 b3bccc0e4c2cc0a33e7e8308d88cacdd.cloudfront.net (CloudFront) 09:38:00 INFO - x-amz-cf-id: WZR9nLnhu6T409XHUsSEjEp1fZl2tq1PXlCR4cJVBP64bheKfd5woA== 09:38:00 INFO - x-amz-version-id: 2J1HmNntOSvCEzYZbyXYRTza8awk1Noc 09:38:00 INFO - Expected file size: 20219554 09:38:00 INFO - Obtained file size: 20219554 09:38:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b86Ic-esQi2JZLx9uVoKyA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 09:38:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b86Ic-esQi2JZLx9uVoKyA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:38:04 INFO - Fetch https://queue.taskcluster.net/v1/task/b86Ic-esQi2JZLx9uVoKyA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 09:38:05 INFO - Http code: 200 09:38:05 INFO - via: 1.1 e1eb7447ab46f530f6009f1d93917c54.cloudfront.net (CloudFront) 09:38:05 INFO - x-amz-cf-id: ARokm94Ae6WILctNEf56YbLC-pRuX3p8IHxqmOmPSrJ4wW-zpXRqOw== 09:38:05 INFO - x-amz-version-id: DAmwo9JuYUDXe06mQVt4BX1fQHCurGm8 09:38:05 INFO - Expected file size: 14409702 09:38:05 INFO - Obtained file size: 14409702 09:38:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:05 INFO - trying https://queue.taskcluster.net/v1/task/b86Ic-esQi2JZLx9uVoKyA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:38:05 INFO - Downloading https://queue.taskcluster.net/v1/task/b86Ic-esQi2JZLx9uVoKyA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:38:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b86Ic-esQi2JZLx9uVoKyA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:38:06 INFO - Downloaded 55671425 bytes. 09:38:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/b86Ic-esQi2JZLx9uVoKyA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:38:06 INFO - mkdir: /builds/slave/test/properties 09:38:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:38:06 INFO - Writing to file /builds/slave/test/properties/build_url 09:38:06 INFO - Contents: 09:38:06 INFO - build_url:https://queue.taskcluster.net/v1/task/b86Ic-esQi2JZLx9uVoKyA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:38:07 INFO - Running post-action listener: _resource_record_post_action 09:38:07 INFO - Running post-action listener: set_extra_try_arguments 09:38:07 INFO - [mozharness: 2016-11-29 17:38:07.567916Z] Finished download-and-extract step (success) 09:38:07 INFO - [mozharness: 2016-11-29 17:38:07.568143Z] Running populate-webroot step. 09:38:07 INFO - Running pre-action listener: _resource_record_pre_action 09:38:07 INFO - Running main action method: populate_webroot 09:38:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:38:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:38:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:38:07 INFO - '--tppagecycles', 09:38:07 INFO - '7'], 09:38:07 INFO - 'tests': ['tsvgm']}}, 09:38:07 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:38:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:38:07 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:38:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:38:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:38:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:38:07 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:38:07 INFO - 'talos_options': ['--disable-e10s'], 09:38:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:38:07 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:38:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:38:07 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:38:07 INFO - 'talos_options': ['--disable-e10s'], 09:38:07 INFO - 'tests': ['damp', 'tps']}, 09:38:07 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:38:07 INFO - 'tests': ['damp', 'tps']}, 09:38:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:38:07 INFO - 'tests': ['dromaeo_dom']}, 09:38:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:38:07 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:38:07 INFO - 'tests': ['basic_compositor_video']}, 09:38:07 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:38:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:38:07 INFO - 'tests': ['a11yr', 09:38:07 INFO - 'ts_paint', 09:38:07 INFO - 'tpaint', 09:38:07 INFO - 'sessionrestore', 09:38:07 INFO - 'sessionrestore_no_auto_restore', 09:38:07 INFO - 'tabpaint']}, 09:38:07 INFO - 'other-e10s': {'tests': ['a11yr', 09:38:07 INFO - 'ts_paint', 09:38:07 INFO - 'tpaint', 09:38:07 INFO - 'sessionrestore', 09:38:07 INFO - 'sessionrestore_no_auto_restore', 09:38:07 INFO - 'tabpaint']}, 09:38:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:38:07 INFO - 'ts_paint', 09:38:07 INFO - 'tpaint', 09:38:07 INFO - 'sessionrestore', 09:38:07 INFO - 'sessionrestore_no_auto_restore', 09:38:07 INFO - 'tabpaint']}, 09:38:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:38:07 INFO - 'ts_paint', 09:38:07 INFO - 'tpaint', 09:38:07 INFO - 'sessionrestore', 09:38:07 INFO - 'sessionrestore_no_auto_restore', 09:38:07 INFO - 'tabpaint']}, 09:38:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:38:07 INFO - 'tests': ['a11yr', 09:38:07 INFO - 'ts_paint', 09:38:07 INFO - 'tpaint', 09:38:07 INFO - 'sessionrestore', 09:38:07 INFO - 'sessionrestore_no_auto_restore', 09:38:07 INFO - 'tabpaint']}, 09:38:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:38:07 INFO - 'tests': ['a11yr', 09:38:07 INFO - 'ts_paint', 09:38:07 INFO - 'tpaint', 09:38:07 INFO - 'sessionrestore', 09:38:07 INFO - 'sessionrestore_no_auto_restore', 09:38:07 INFO - 'tabpaint']}, 09:38:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:38:07 INFO - 'tests': ['tsvgx', 09:38:07 INFO - 'tsvgr_opacity', 09:38:07 INFO - 'tart', 09:38:07 INFO - 'tscrollx', 09:38:07 INFO - 'cart', 09:38:07 INFO - 'tsvg_static']}, 09:38:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:38:07 INFO - 'tsvgr_opacity', 09:38:07 INFO - 'tart', 09:38:07 INFO - 'tscrollx', 09:38:07 INFO - 'cart', 09:38:07 INFO - 'tsvg_static']}, 09:38:07 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:38:07 INFO - 'talos_options': ['--disable-e10s'], 09:38:07 INFO - 'tests': ['tp5o']}, 09:38:07 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:38:07 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:38:07 INFO - 'talos_options': ['--disable-e10s', 09:38:07 INFO - '--xperf_path', 09:38:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:38:07 INFO - 'tests': ['tp5n']}, 09:38:07 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:38:07 INFO - 'talos_options': ['--xperf_path', 09:38:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:38:07 INFO - 'tests': ['tp5n']}}, 09:38:07 INFO - 'talos.zip': {'path': '', 09:38:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:38:07 INFO - Running post-action listener: _resource_record_post_action 09:38:07 INFO - [mozharness: 2016-11-29 17:38:07.586518Z] Finished populate-webroot step (success) 09:38:07 INFO - [mozharness: 2016-11-29 17:38:07.586606Z] Running create-virtualenv step. 09:38:07 INFO - Running pre-action listener: _resource_record_pre_action 09:38:07 INFO - Running main action method: create_virtualenv 09:38:07 INFO - Creating virtualenv /builds/slave/test/build/venv 09:38:07 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 09:38:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:38:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:38:07 INFO - Using real prefix '/usr' 09:38:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:38:08 INFO - Installing distribute.............................................................................................................................................................................................done. 09:38:10 INFO - Installing pip.................done. 09:38:10 INFO - Return code: 0 09:38:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:38:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:38:10 INFO - Reading from file tmpfile_stdout 09:38:10 INFO - Output received: 09:38:10 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) 09:38:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:38:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f59da925e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1731b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18cd130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195bfc0>, '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 0x195cec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x195d350>, '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': '337fe3f4b1febe37b85fccc40000019e-1480441072.182730-152408469', '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 09:38:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:38:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:38:10 INFO - Using env: {'DISPLAY': ':0', 09:38:10 INFO - 'HOME': '/home/cltbld', 09:38:10 INFO - 'LANG': 'en_US.UTF-8', 09:38:10 INFO - 'LANGUAGE': 'en_US:en', 09:38:10 INFO - 'LOGNAME': 'cltbld', 09:38:10 INFO - 'MAIL': '/var/mail/cltbld', 09:38:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:10 INFO - 'MOZ_NO_REMOTE': '1', 09:38:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:38:10 INFO - 'NO_EM_RESTART': '1', 09:38:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:38:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:10 INFO - 'PWD': '/builds/slave/test', 09:38:10 INFO - 'SHELL': '/bin/bash', 09:38:10 INFO - 'SHLVL': '1', 09:38:10 INFO - 'TERM': 'linux', 09:38:10 INFO - 'TMOUT': '86400', 09:38:10 INFO - 'USER': 'cltbld', 09:38:10 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1480441072.182730-152408469', 09:38:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:10 INFO - '_': '/tools/buildbot/bin/python'} 09:38:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:10 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 09:38:10 INFO - Cleaning up... 09:38:10 INFO - Return code: 0 09:38:10 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:38:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f59da925e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1731b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18cd130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195bfc0>, '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 0x195cec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x195d350>, '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': '337fe3f4b1febe37b85fccc40000019e-1480441072.182730-152408469', '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 09:38:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:38:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:38:10 INFO - Using env: {'DISPLAY': ':0', 09:38:10 INFO - 'HOME': '/home/cltbld', 09:38:10 INFO - 'LANG': 'en_US.UTF-8', 09:38:10 INFO - 'LANGUAGE': 'en_US:en', 09:38:10 INFO - 'LOGNAME': 'cltbld', 09:38:10 INFO - 'MAIL': '/var/mail/cltbld', 09:38:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:10 INFO - 'MOZ_NO_REMOTE': '1', 09:38:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:38:10 INFO - 'NO_EM_RESTART': '1', 09:38:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:38:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:10 INFO - 'PWD': '/builds/slave/test', 09:38:10 INFO - 'SHELL': '/bin/bash', 09:38:10 INFO - 'SHLVL': '1', 09:38:10 INFO - 'TERM': 'linux', 09:38:10 INFO - 'TMOUT': '86400', 09:38:10 INFO - 'USER': 'cltbld', 09:38:10 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1480441072.182730-152408469', 09:38:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:10 INFO - '_': '/tools/buildbot/bin/python'} 09:38:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:10 INFO - Downloading/unpacking psutil>=3.1.1 09:38:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:38:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:38:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:38:12 INFO - Installing collected packages: psutil 09:38:12 INFO - Running setup.py install for psutil 09:38:12 INFO - building 'psutil._psutil_linux' extension 09:38:12 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 09:38:13 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 09:38:13 INFO - building 'psutil._psutil_posix' extension 09:38:13 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 09:38:13 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 09:38:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:38:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:38:13 INFO - Successfully installed psutil 09:38:13 INFO - Cleaning up... 09:38:13 INFO - Return code: 0 09:38:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:38:13 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')]} 09:38:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:13 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')]} 09:38:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:13 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 0x7f59da925e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1731b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18cd130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195bfc0>, '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 0x195cec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x195d350>, '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': '337fe3f4b1febe37b85fccc40000019e-1480441072.182730-152408469', '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 09:38:13 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 09:38:13 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 09:38:13 INFO - Using env: {'DISPLAY': ':0', 09:38:13 INFO - 'HOME': '/home/cltbld', 09:38:13 INFO - 'LANG': 'en_US.UTF-8', 09:38:13 INFO - 'LANGUAGE': 'en_US:en', 09:38:13 INFO - 'LOGNAME': 'cltbld', 09:38:13 INFO - 'MAIL': '/var/mail/cltbld', 09:38:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:13 INFO - 'MOZ_NO_REMOTE': '1', 09:38:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:38:13 INFO - 'NO_EM_RESTART': '1', 09:38:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:38:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:13 INFO - 'PWD': '/builds/slave/test', 09:38:13 INFO - 'SHELL': '/bin/bash', 09:38:13 INFO - 'SHLVL': '1', 09:38:13 INFO - 'TERM': 'linux', 09:38:13 INFO - 'TMOUT': '86400', 09:38:13 INFO - 'USER': 'cltbld', 09:38:13 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1480441072.182730-152408469', 09:38:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:13 INFO - '_': '/tools/buildbot/bin/python'} 09:38:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:38:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:38:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:38:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:38:15 INFO - Installing collected packages: mozsystemmonitor 09:38:15 INFO - Running setup.py install for mozsystemmonitor 09:38:15 INFO - Successfully installed mozsystemmonitor 09:38:15 INFO - Cleaning up... 09:38:15 INFO - Return code: 0 09:38:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:38: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')]} 09:38:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38: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')]} 09:38:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f59da925e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1731b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18cd130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195bfc0>, '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 0x195cec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x195d350>, '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': '337fe3f4b1febe37b85fccc40000019e-1480441072.182730-152408469', '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 09:38:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:38:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:38:15 INFO - Using env: {'DISPLAY': ':0', 09:38:15 INFO - 'HOME': '/home/cltbld', 09:38:15 INFO - 'LANG': 'en_US.UTF-8', 09:38:15 INFO - 'LANGUAGE': 'en_US:en', 09:38:15 INFO - 'LOGNAME': 'cltbld', 09:38:15 INFO - 'MAIL': '/var/mail/cltbld', 09:38:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:15 INFO - 'MOZ_NO_REMOTE': '1', 09:38:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:38:15 INFO - 'NO_EM_RESTART': '1', 09:38:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:38:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:15 INFO - 'PWD': '/builds/slave/test', 09:38:15 INFO - 'SHELL': '/bin/bash', 09:38:15 INFO - 'SHLVL': '1', 09:38:15 INFO - 'TERM': 'linux', 09:38:15 INFO - 'TMOUT': '86400', 09:38:15 INFO - 'USER': 'cltbld', 09:38:15 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1480441072.182730-152408469', 09:38:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:15 INFO - '_': '/tools/buildbot/bin/python'} 09:38:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:16 INFO - Downloading/unpacking jsonschema==2.5.1 09:38:16 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 09:38:16 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 09:38:16 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 09:38:16 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 09:38:16 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 09:38:16 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 09:38:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:38:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:38:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:18 INFO - Downloading functools32-3.2.3-2.tar.gz 09:38:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:38:18 INFO - warning: no files found matching '*.txt' 09:38:18 INFO - no previously-included directories found matching 'build' 09:38:18 INFO - no previously-included directories found matching 'dist' 09:38:18 INFO - no previously-included directories found matching '.git*' 09:38:18 INFO - Installing collected packages: jsonschema, functools32 09:38:18 INFO - Running setup.py install for functools32 09:38:18 INFO - warning: no files found matching '*.txt' 09:38:18 INFO - no previously-included directories found matching 'build' 09:38:18 INFO - no previously-included directories found matching 'dist' 09:38:18 INFO - no previously-included directories found matching '.git*' 09:38:18 INFO - Successfully installed jsonschema functools32 09:38:18 INFO - Cleaning up... 09:38:18 INFO - Return code: 0 09:38:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:38:18 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')]} 09:38:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:18 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')]} 09:38:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:18 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 0x7f59da925e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1731b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18cd130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195bfc0>, '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 0x195cec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x195d350>, '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': '337fe3f4b1febe37b85fccc40000019e-1480441072.182730-152408469', '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 09:38:18 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 09:38:18 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 09:38:18 INFO - Using env: {'DISPLAY': ':0', 09:38:18 INFO - 'HOME': '/home/cltbld', 09:38:18 INFO - 'LANG': 'en_US.UTF-8', 09:38:18 INFO - 'LANGUAGE': 'en_US:en', 09:38:18 INFO - 'LOGNAME': 'cltbld', 09:38:18 INFO - 'MAIL': '/var/mail/cltbld', 09:38:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:18 INFO - 'MOZ_NO_REMOTE': '1', 09:38:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:38:18 INFO - 'NO_EM_RESTART': '1', 09:38:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:38:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:18 INFO - 'PWD': '/builds/slave/test', 09:38:18 INFO - 'SHELL': '/bin/bash', 09:38:18 INFO - 'SHLVL': '1', 09:38:18 INFO - 'TERM': 'linux', 09:38:18 INFO - 'TMOUT': '86400', 09:38:18 INFO - 'USER': 'cltbld', 09:38:18 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1480441072.182730-152408469', 09:38:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:18 INFO - '_': '/tools/buildbot/bin/python'} 09:38:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:18 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:38:18 INFO - Cleaning up... 09:38:18 INFO - Return code: 0 09:38:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:38:18 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')]} 09:38:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:18 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')]} 09:38:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:18 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 0x7f59da925e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1731b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18cd130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195bfc0>, '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 0x195cec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x195d350>, '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': '337fe3f4b1febe37b85fccc40000019e-1480441072.182730-152408469', '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 09:38:18 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 09:38:18 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 09:38:18 INFO - Using env: {'DISPLAY': ':0', 09:38:18 INFO - 'HOME': '/home/cltbld', 09:38:18 INFO - 'LANG': 'en_US.UTF-8', 09:38:18 INFO - 'LANGUAGE': 'en_US:en', 09:38:18 INFO - 'LOGNAME': 'cltbld', 09:38:18 INFO - 'MAIL': '/var/mail/cltbld', 09:38:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:18 INFO - 'MOZ_NO_REMOTE': '1', 09:38:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:38:18 INFO - 'NO_EM_RESTART': '1', 09:38:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:38:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:18 INFO - 'PWD': '/builds/slave/test', 09:38:18 INFO - 'SHELL': '/bin/bash', 09:38:18 INFO - 'SHLVL': '1', 09:38:18 INFO - 'TERM': 'linux', 09:38:18 INFO - 'TMOUT': '86400', 09:38:18 INFO - 'USER': 'cltbld', 09:38:18 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1480441072.182730-152408469', 09:38:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:18 INFO - '_': '/tools/buildbot/bin/python'} 09:38:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:18 INFO - Downloading/unpacking blobuploader==1.2.4 09:38:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:20 INFO - Downloading blobuploader-1.2.4.tar.gz 09:38:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:38:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:38: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 09:38: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 09:38: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 09:38: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 09:38: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 09:38: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 09:38:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:38:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:38: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 09:38: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 09:38: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 09:38: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 09:38: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 09:38: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 09:38:21 INFO - Downloading docopt-0.6.1.tar.gz 09:38:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:38:21 INFO - Installing collected packages: blobuploader, requests, docopt 09:38:21 INFO - Running setup.py install for blobuploader 09:38:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:38:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:38:21 INFO - Running setup.py install for requests 09:38:22 INFO - Running setup.py install for docopt 09:38:22 INFO - Successfully installed blobuploader requests docopt 09:38:22 INFO - Cleaning up... 09:38:22 INFO - Return code: 0 09:38:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:38:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:22 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 0x7f59da925e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1731b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18cd130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195bfc0>, '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 0x195cec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x195d350>, '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': '337fe3f4b1febe37b85fccc40000019e-1480441072.182730-152408469', '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 09:38:22 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 09:38:22 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 09:38:22 INFO - Using env: {'DISPLAY': ':0', 09:38:22 INFO - 'HOME': '/home/cltbld', 09:38:22 INFO - 'LANG': 'en_US.UTF-8', 09:38:22 INFO - 'LANGUAGE': 'en_US:en', 09:38:22 INFO - 'LOGNAME': 'cltbld', 09:38:22 INFO - 'MAIL': '/var/mail/cltbld', 09:38:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:22 INFO - 'MOZ_NO_REMOTE': '1', 09:38:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:38:22 INFO - 'NO_EM_RESTART': '1', 09:38:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:38:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:22 INFO - 'PWD': '/builds/slave/test', 09:38:22 INFO - 'SHELL': '/bin/bash', 09:38:22 INFO - 'SHLVL': '1', 09:38:22 INFO - 'TERM': 'linux', 09:38:22 INFO - 'TMOUT': '86400', 09:38:22 INFO - 'USER': 'cltbld', 09:38:22 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1480441072.182730-152408469', 09:38:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:22 INFO - '_': '/tools/buildbot/bin/python'} 09:38:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:38:22 INFO - Running setup.py (path:/tmp/pip-fM985l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:38:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:38:22 INFO - Running setup.py (path:/tmp/pip-66suIz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:38:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:38:22 INFO - Running setup.py (path:/tmp/pip-Sws7QU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:38:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:38:22 INFO - Running setup.py (path:/tmp/pip-3lhdhW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:38:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:38:22 INFO - Running setup.py (path:/tmp/pip-36lxK1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:38:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:38:22 INFO - Running setup.py (path:/tmp/pip-SP7dzF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:38:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:38:23 INFO - Running setup.py (path:/tmp/pip-37a_Wx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:38:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:38:23 INFO - Running setup.py (path:/tmp/pip-g3iX9x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:38:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:38:23 INFO - Running setup.py (path:/tmp/pip-HQIUGJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:38:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:38:23 INFO - Running setup.py (path:/tmp/pip-bbF8zn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:38:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:38:23 INFO - Running setup.py (path:/tmp/pip-tzfGiG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:38:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:38:23 INFO - Running setup.py (path:/tmp/pip-vH0oNp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:38:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:38:23 INFO - Running setup.py (path:/tmp/pip-ETApoe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:38:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:38:23 INFO - Running setup.py (path:/tmp/pip-UKDwrT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:38:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:38:23 INFO - Running setup.py (path:/tmp/pip-PvgYhm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:38:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:38:23 INFO - Running setup.py (path:/tmp/pip-XgAjpG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:38:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:38:23 INFO - Running setup.py (path:/tmp/pip-GhWTP6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:38:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:38:23 INFO - Running setup.py install for manifestparser 09:38:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:38:23 INFO - Running setup.py install for mozcrash 09:38:24 INFO - Running setup.py install for mozdebug 09:38:24 INFO - Running setup.py install for mozdevice 09:38:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:38:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:38:24 INFO - Running setup.py install for mozfile 09:38:24 INFO - Running setup.py install for mozhttpd 09:38:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:38:24 INFO - Running setup.py install for mozinfo 09:38:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:38:24 INFO - Running setup.py install for mozInstall 09:38:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:38:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:38:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:38:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:38:24 INFO - Running setup.py install for mozleak 09:38:24 INFO - Running setup.py install for mozlog 09:38:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:38:24 INFO - Running setup.py install for moznetwork 09:38:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:38:24 INFO - Running setup.py install for mozprocess 09:38:25 INFO - Running setup.py install for mozprofile 09:38:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:38:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:38:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:38:25 INFO - Running setup.py install for mozrunner 09:38:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:38:25 INFO - Running setup.py install for mozscreenshot 09:38:25 INFO - Running setup.py install for moztest 09:38:25 INFO - Running setup.py install for mozversion 09:38:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:38:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:38:25 INFO - Cleaning up... 09:38:25 INFO - Return code: 0 09:38:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:38:25 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')]} 09:38:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:25 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')]} 09:38:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:25 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 0x7f59da925e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1731b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18cd130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195bfc0>, '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 0x195cec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x195d350>, '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': '337fe3f4b1febe37b85fccc40000019e-1480441072.182730-152408469', '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 09:38:25 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 09:38:25 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 09:38:25 INFO - Using env: {'DISPLAY': ':0', 09:38:25 INFO - 'HOME': '/home/cltbld', 09:38:25 INFO - 'LANG': 'en_US.UTF-8', 09:38:25 INFO - 'LANGUAGE': 'en_US:en', 09:38:25 INFO - 'LOGNAME': 'cltbld', 09:38:25 INFO - 'MAIL': '/var/mail/cltbld', 09:38:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:25 INFO - 'MOZ_NO_REMOTE': '1', 09:38:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:38:25 INFO - 'NO_EM_RESTART': '1', 09:38:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:38:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:25 INFO - 'PWD': '/builds/slave/test', 09:38:25 INFO - 'SHELL': '/bin/bash', 09:38:25 INFO - 'SHLVL': '1', 09:38:25 INFO - 'TERM': 'linux', 09:38:25 INFO - 'TMOUT': '86400', 09:38:25 INFO - 'USER': 'cltbld', 09:38:25 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1480441072.182730-152408469', 09:38:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:25 INFO - '_': '/tools/buildbot/bin/python'} 09:38:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:38:25 INFO - Running setup.py (path:/tmp/pip-kwjPHa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:38:25 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)) 09:38:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:38:25 INFO - Running setup.py (path:/tmp/pip-a2RMRu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:38:25 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)) 09:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:38:26 INFO - Running setup.py (path:/tmp/pip-44JzqX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:38:26 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)) 09:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:38:26 INFO - Running setup.py (path:/tmp/pip-Xy3NRH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:38:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 09:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:38:26 INFO - Running setup.py (path:/tmp/pip-CoIowT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:38:26 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)) 09:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:38:26 INFO - Running setup.py (path:/tmp/pip-5G4guP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:38:26 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)) 09:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:38:26 INFO - Running setup.py (path:/tmp/pip-IPYhOq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:38:26 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)) 09:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:38:26 INFO - Running setup.py (path:/tmp/pip-w7g3Ds-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:38:26 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)) 09:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:38:26 INFO - Running setup.py (path:/tmp/pip-zhz_Wx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:38:26 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)) 09:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:38:26 INFO - Running setup.py (path:/tmp/pip-BHAVHz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:38:26 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)) 09:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:38:26 INFO - Running setup.py (path:/tmp/pip-reTv4c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:38:26 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)) 09:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:38:26 INFO - Running setup.py (path:/tmp/pip-yuyhq_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:38:26 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)) 09:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:38:26 INFO - Running setup.py (path:/tmp/pip-Hb3qEv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:38:26 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)) 09:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:38:26 INFO - Running setup.py (path:/tmp/pip-RbeiBW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:38:26 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)) 09:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:38:26 INFO - Running setup.py (path:/tmp/pip-0d1zaO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:38:26 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)) 09:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:38:26 INFO - Running setup.py (path:/tmp/pip-wO5Psm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:38:27 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)) 09:38:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:38:27 INFO - Running setup.py (path:/tmp/pip-cTBFeD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:38:27 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)) 09:38:27 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)) 09:38:27 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)) 09:38:27 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)) 09:38:27 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:38:27 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:38:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:38: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 09:38: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 09:38: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 09:38: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 09:38: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 09:38: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 09:38:29 INFO - Downloading blessings-1.6.tar.gz 09:38:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:38:29 INFO - Installing collected packages: blessings 09:38:29 INFO - Running setup.py install for blessings 09:38:29 INFO - Successfully installed blessings 09:38:29 INFO - Cleaning up... 09:38:29 INFO - Return code: 0 09:38:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:38:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:38:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:38:29 INFO - Reading from file tmpfile_stdout 09:38:29 INFO - Current package versions: 09:38:29 INFO - argparse == 1.2.1 09:38:29 INFO - blessings == 1.6 09:38:29 INFO - blobuploader == 1.2.4 09:38:29 INFO - docopt == 0.6.1 09:38:29 INFO - functools32 == 3.2.3-2 09:38:29 INFO - jsonschema == 2.5.1 09:38:29 INFO - manifestparser == 1.1 09:38:29 INFO - mozInstall == 1.12 09:38:29 INFO - mozcrash == 1.0 09:38:29 INFO - mozdebug == 0.1 09:38:29 INFO - mozdevice == 0.49 09:38:29 INFO - mozfile == 1.2 09:38:29 INFO - mozhttpd == 0.7 09:38:29 INFO - mozinfo == 0.9 09:38:29 INFO - mozleak == 0.1 09:38:29 INFO - mozlog == 3.4 09:38:29 INFO - moznetwork == 0.27 09:38:29 INFO - mozprocess == 0.23 09:38:29 INFO - mozprofile == 0.28 09:38:29 INFO - mozrunner == 6.13 09:38:29 INFO - mozscreenshot == 0.1 09:38:29 INFO - mozsystemmonitor == 0.3 09:38:29 INFO - moztest == 0.7 09:38:29 INFO - mozversion == 1.4 09:38:29 INFO - psutil == 3.1.1 09:38:29 INFO - requests == 1.2.3 09:38:29 INFO - wsgiref == 0.1.2 09:38:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:38: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')]} 09:38:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38: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')]} 09:38:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:29 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 0x7f59da925e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1731b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18cd130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195bfc0>, '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 0x195cec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x195d350>, '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': '337fe3f4b1febe37b85fccc40000019e-1480441072.182730-152408469', '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 09:38:29 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 09:38:29 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 09:38:29 INFO - Using env: {'DISPLAY': ':0', 09:38:29 INFO - 'HOME': '/home/cltbld', 09:38:29 INFO - 'LANG': 'en_US.UTF-8', 09:38:29 INFO - 'LANGUAGE': 'en_US:en', 09:38:29 INFO - 'LOGNAME': 'cltbld', 09:38:29 INFO - 'MAIL': '/var/mail/cltbld', 09:38:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:29 INFO - 'MOZ_NO_REMOTE': '1', 09:38:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:38:29 INFO - 'NO_EM_RESTART': '1', 09:38:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:38:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:29 INFO - 'PWD': '/builds/slave/test', 09:38:29 INFO - 'SHELL': '/bin/bash', 09:38:29 INFO - 'SHLVL': '1', 09:38:29 INFO - 'TERM': 'linux', 09:38:29 INFO - 'TMOUT': '86400', 09:38:29 INFO - 'USER': 'cltbld', 09:38:29 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1480441072.182730-152408469', 09:38:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:29 INFO - '_': '/tools/buildbot/bin/python'} 09:38:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:29 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)) 09:38:29 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)) 09:38:29 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)) 09:38:29 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)) 09:38:29 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)) 09:38:29 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)) 09:38:29 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)) 09:38:29 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)) 09:38:29 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)) 09:38:29 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)) 09:38:29 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)) 09:38:29 INFO - Cleaning up... 09:38:29 INFO - Return code: 0 09:38:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:38: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')]} 09:38:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38: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')]} 09:38:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f59da925e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1731b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18cd130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195bfc0>, '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 0x195cec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x195d350>, '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': '337fe3f4b1febe37b85fccc40000019e-1480441072.182730-152408469', '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 09:38: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', 'jsonschema'] in /builds/slave/test/build 09:38: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 jsonschema 09:38:29 INFO - Using env: {'DISPLAY': ':0', 09:38:29 INFO - 'HOME': '/home/cltbld', 09:38:29 INFO - 'LANG': 'en_US.UTF-8', 09:38:29 INFO - 'LANGUAGE': 'en_US:en', 09:38:29 INFO - 'LOGNAME': 'cltbld', 09:38:29 INFO - 'MAIL': '/var/mail/cltbld', 09:38:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:29 INFO - 'MOZ_NO_REMOTE': '1', 09:38:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:38:29 INFO - 'NO_EM_RESTART': '1', 09:38:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:38:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:29 INFO - 'PWD': '/builds/slave/test', 09:38:29 INFO - 'SHELL': '/bin/bash', 09:38:29 INFO - 'SHLVL': '1', 09:38:29 INFO - 'TERM': 'linux', 09:38:29 INFO - 'TMOUT': '86400', 09:38:29 INFO - 'USER': 'cltbld', 09:38:29 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1480441072.182730-152408469', 09:38:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:29 INFO - '_': '/tools/buildbot/bin/python'} 09:38:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:29 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:38:29 INFO - Cleaning up... 09:38:30 INFO - Return code: 0 09:38:30 INFO - Running post-action listener: _resource_record_post_action 09:38:30 INFO - Running post-action listener: _start_resource_monitoring 09:38:30 INFO - Starting resource monitoring. 09:38:30 INFO - [mozharness: 2016-11-29 17:38:30.042038Z] Finished create-virtualenv step (success) 09:38:30 INFO - [mozharness: 2016-11-29 17:38:30.042510Z] Running install step. 09:38:30 INFO - Running pre-action listener: _resource_record_pre_action 09:38:30 INFO - Running main action method: install 09:38:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:38:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:38:30 INFO - Reading from file tmpfile_stdout 09:38:30 INFO - Detecting whether we're running mozinstall >=1.0... 09:38:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:38:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:38:30 INFO - Reading from file tmpfile_stdout 09:38:30 INFO - Output received: 09:38:30 INFO - Usage: mozinstall [options] installer 09:38:30 INFO - Options: 09:38:30 INFO - -h, --help show this help message and exit 09:38:30 INFO - -d DEST, --destination=DEST 09:38:30 INFO - Directory to install application into. [default: 09:38:30 INFO - "/builds/slave/test"] 09:38:30 INFO - --app=APP Application being installed. [default: firefox] 09:38:30 INFO - mkdir: /builds/slave/test/build/application 09:38:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:38:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:38:46 INFO - Reading from file tmpfile_stdout 09:38:46 INFO - Output received: 09:38:46 INFO - /builds/slave/test/build/application/firefox/firefox 09:38:46 INFO - Running post-action listener: _resource_record_post_action 09:38:46 INFO - [mozharness: 2016-11-29 17:38:46.375301Z] Finished install step (success) 09:38:46 INFO - [mozharness: 2016-11-29 17:38:46.375544Z] Running run-tests step. 09:38:46 INFO - Running pre-action listener: _resource_record_pre_action 09:38:46 INFO - Running main action method: run_tests 09:38:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:38:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:38:46 INFO - Python 2.7.3 09:38:46 INFO - Return code: 0 09:38:46 INFO - grabbing minidump binary from tooltool 09:38:46 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')]} 09:38:46 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 0x195bfc0>, '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 0x195cec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x195d350>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:38:46 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 09:38:46 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 09:38:46 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 09:38:46 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:38:46 INFO - Return code: 0 09:38:46 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:38:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:38:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:38:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:38:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:38:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/b86Ic-esQi2JZLx9uVoKyA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-060', '--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 09:38:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/b86Ic-esQi2JZLx9uVoKyA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-060 --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 09:38:46 INFO - Using env: {'DISPLAY': ':0', 09:38:46 INFO - 'HOME': '/home/cltbld', 09:38:46 INFO - 'LANG': 'en_US.UTF-8', 09:38:46 INFO - 'LANGUAGE': 'en_US:en', 09:38:46 INFO - 'LOGNAME': 'cltbld', 09:38:46 INFO - 'MAIL': '/var/mail/cltbld', 09:38:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:38:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:38:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:46 INFO - 'MOZ_NO_REMOTE': '1', 09:38:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:38:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:38:46 INFO - 'NO_EM_RESTART': '1', 09:38:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:38:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:46 INFO - 'PWD': '/builds/slave/test', 09:38:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:38:46 INFO - 'SHELL': '/bin/bash', 09:38:46 INFO - 'SHLVL': '1', 09:38:46 INFO - 'TERM': 'linux', 09:38:46 INFO - 'TMOUT': '86400', 09:38:46 INFO - 'USER': 'cltbld', 09:38:46 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1480441072.182730-152408469', 09:38:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:46 INFO - '_': '/tools/buildbot/bin/python'} 09:38:46 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/b86Ic-esQi2JZLx9uVoKyA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-060', '--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 09:38:46 INFO - mozversion application_buildid: 20161129062926 09:38:46 INFO - mozversion application_changeset: 130bb29013a1d089dd533e50e170eabedce157d8 09:38:46 INFO - mozversion application_display_name: Nightly 09:38:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:38:46 INFO - mozversion application_name: Firefox 09:38:46 INFO - mozversion application_remotingname: firefox 09:38:46 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:38:46 INFO - mozversion application_vendor: Mozilla 09:38:46 INFO - mozversion application_version: 53.0a1 09:38:46 INFO - mozversion platform_buildid: 20161129062926 09:38:46 INFO - mozversion platform_changeset: 130bb29013a1d089dd533e50e170eabedce157d8 09:38:46 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:38:46 INFO - mozversion platform_version: 53.0a1 09:38:46 INFO - using testdate: 1480441126 09:38:46 INFO - actual date: 1480441126 09:38:46 INFO - starting webserver on 'localhost:37929' 09:38:46 INFO - SUITE-START | Running 2 tests 09:38:46 INFO - TEST-START | dromaeo_css 09:38:46 INFO - operating with platform_type : linux_ 09:38:46 INFO - Initialising browser for dromaeo_css test... 09:38:46 INFO - TEST-INFO | started process 6260 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHy0fNs/profile http://localhost:37929/getInfo.html) 09:38:51 INFO - PROCESS | 6260 | __metrics Screen width/height:1600/1200 09:38:51 INFO - PROCESS | 6260 | colorDepth:24 09:38:51 INFO - PROCESS | 6260 | Browser inner width/height: 1024/697 09:38:51 INFO - PROCESS | 6260 | __metrics 09:38:51 INFO - PROCESS | 6260 | 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" 09:38:51 INFO - PROCESS | 6260 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:38:52 INFO - PROCESS | 6260 | console.error: PushService: 09:38:52 INFO - PROCESS | 6260 | stateChangeProcessEnqueue: Error transitioning state 09:38:52 INFO - PROCESS | 6260 | UnknownError 09:38:53 INFO - TEST-INFO | 6260: exit 0 09:38:53 INFO - Browser initialized. 09:38:53 INFO - Running cycle 1/1 for dromaeo_css test... 09:38:53 INFO - TEST-INFO | started process 6345 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHy0fNs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:39:05 INFO - PROCESS | 6345 | RSS: Main: 175263744 09:39:05 INFO - PROCESS | 6345 | 09:39:57 INFO - PROCESS | 6345 | 1480441197127 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] 09:42:10 INFO - PROCESS | 6345 | Cycle 1(1): loaded http://localhost:37929/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37929/tests/dromaeo/cssquery-ext.html) 09:42:11 INFO - PROCESS | 6345 | RSS: Main: 317562880 09:42:11 INFO - PROCESS | 6345 | 09:45:14 INFO - PROCESS | 6345 | Cycle 1(1): loaded http://localhost:37929/tests/dromaeo/cssquery-ext.html (next: http://localhost:37929/tests/dromaeo/cssquery-jquery.html) 09:45:14 INFO - PROCESS | 6345 | RSS: Main: 278822912 09:45:14 INFO - PROCESS | 6345 | 09:48:17 INFO - PROCESS | 6345 | Cycle 1(1): loaded http://localhost:37929/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37929/tests/dromaeo/cssquery-mootools.html) 09:48:17 INFO - PROCESS | 6345 | RSS: Main: 264413184 09:48:17 INFO - PROCESS | 6345 | 09:51:19 INFO - PROCESS | 6345 | Cycle 1(1): loaded http://localhost:37929/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37929/tests/dromaeo/cssquery-prototype.html) 09:51:20 INFO - PROCESS | 6345 | RSS: Main: 221700096 09:51:20 INFO - PROCESS | 6345 | 09:54:23 INFO - PROCESS | 6345 | Cycle 1(1): loaded http://localhost:37929/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37929/tests/dromaeo/cssquery-yui.html) 09:54:24 INFO - PROCESS | 6345 | RSS: Main: 222441472 09:54:24 INFO - PROCESS | 6345 | 09:57:27 INFO - PROCESS | 6345 | Cycle 1(1): loaded http://localhost:37929/tests/dromaeo/cssquery-yui.html (next: http://localhost:37929/tests/dromaeo/cssquery-dojo.html) 09:57:27 INFO - PROCESS | 6345 | RSS: Main: 317562880 09:57:27 INFO - PROCESS | 6345 | 09:57:27 INFO - PROCESS | 6345 | __start_tp_report 09:57:27 INFO - PROCESS | 6345 | _x_x_mozilla_page_load 09:57:27 INFO - PROCESS | 6345 | _x_x_mozilla_page_load_details 09:57:27 INFO - PROCESS | 6345 | |i|pagename|runs| 09:57:27 INFO - PROCESS | 6345 | |0;dojo.html;2564.8717564121794;2577.445849142434;2604.9348766280846;2613.621024951382;2621.1743300860226;4548.272276435771;4591.334256532809;4669.322948172513;4684.695494792836;4687.671862969593;4630.583247507732;4658.953410465894;4684.8126074957045;4727.810887564502;4785.353977213066;4262.595053469915;4274.0383413731815;4312.805923733439;4318.719283246594;4409.206342858284;3924.0869662516807;4238.300680387732;4353.380306992158;4412.84555040572;4432.290833466646;4213.9578604213875;4296.5273819879785;4317.913641727179;4384.430024096863;4402.669799765014;4216.936745948813;4263.360495925605;4274.273373526508;4279.165562715271;4287.228298906196;4250.511191213019;4255.63827074699;4267.509236437819;4271.423357846682;4273.188094262111;4654.697444666086;4685.109829132454;4686.133065382915;4747.786349614275;4754.7147171169845;4977.7262250576205;4983.651144419892;4988.077205716954;4988.775505102279;5044.319016932704;4877.975610121962;4926.2118061110405;4943.134951383529;5018.573421259161;5020.799168033274;4590.74750888701;4626.699264547794;4627.514111018362;4628.166929952641;4665.066986602693;5055.797768089309;5060.671056381397;5061.569766569809;5067.417247016567;5108.310378098983;4822.855314340575;4860.975695121501;4862.927056094161;4870.123377791961;4898.804047838046;4839.467658557556;4864.416270047617;4875.1224779540025;4892.094962431962;4982.90034199314;4844.15227335222;4937.530934561211;4946.629002824802;4975.726335051607;4988.176950803149;4894.828680996148;4895.975520122377;4911.582515486152;4912.435069966999;4951.826686066042;4153.086321009373;4187.5184353799095;4209.2633789086785;4226.197022565764;4253.149370125987;3655.067957670777;3675.3568125578454;3686.9631303687156;3690.7970061646865;3747.475353450519;4271.487421509439;4276.5082015568605;4277.422547955986;4278.764667943293;4312.741235525816;5015.398152221756;5018;5021.372328458942;5026.396832380138;5058.797648094035;4227.112306415687;4235.80938857746;4254.127903779718;4273.081287523198;4278.764667943293;4536.115457485825;4546.704464209815;4552.180607490617;4582.312653102062;4588.7935042923145;4699.671023028423;4712.764361781966;4713.882152946204;4719.622430205575;4757.976210118927;4871.171900776805;4903;4905.607551395951;5058.772355243949;5079.314292570456;4597.655175861757;4610.561996610317;4633.953660463352;4666.3700400445505;4709.788059537261;4899.461059283405;4904.852854414374;4906.190478570996;4906.6320025997475;4952.479989601185;4895.632827537877;4970.527799859007;4980.978899325702;4985.803407182323;5028.195488721787;4801.663883528119;4865.1242776300605;4872.926906096341;4888.902221955612;4889.679786457707;4854.708717476963;4857.514248575114;4861.781219845045;4865.829695960624;4904.043711476228;4777.808887644455;4787.042591481787;4789.042191561772;4792.424909010941;4842.806287748451;4785.330053792402;4812.590929770869;4817.78319975609;4824.927626085681;4862.197737373293;4786.688865223749;4804;4822.373091498082;4828.37231159947;4848.7090774553635 09:57:27 INFO - PROCESS | 6345 | |1;ext.html;566.2412367427647;568.1846550200463;571.0834111251442;589.7817807411253;592.6621825559433;27369.726302736966;38027.429588556195;38701.61298387015;38841.84321292525;42228.36657450141;39482.802585987105;39821.20357592846;39940.60059399405;39955.60044399555;39963;7545.924540754604;7730.574818384986;7730.961345193266;7736.535807851547;7740.148583655821;7398.519096258726;7597.772066838003;9755.853662195073;10002.449865257408;10019.949900250489;10034.84947725781;10104.69685909424;10118.291719579633;10119.544620492059;10139.746506337364;10007.54966026527;10072.899271007269;10074.445905475195;10078.748031299203;10081.596736130547;9750.26872984529;9755;9814.312998090136;9820.558074886576;9829.754256143582;36665.81667091647;36944.44583331252;36963.81518092392;36991.445128323096;37073.07317317062;3104.2394613319657;3159.810411375302;3184.1561986073707;3294.4399452093203;3652.2330310634798;27396.31509212266;28137.57793633097;28237.576436353265;28251.85874070637;28378.148655540368;3258.266889949766;3313.966860331414;3344.4481660525985;3344.5986481622117;3388.102152929476;171006;171207.99999999875;171535;171652;171652;50086.749566251936;51659.741701292005;51685.22472162921;51708.741456292475;51767.22349164765;53094.73452632789;53320;53391.194792468865;53396;53492.46507534875;12560.685982850318;12663.683407914692;12806.167599106027;12806.167599106215;12817.935910320388;8850.026497085315;9162.129597688208;9188.8621670675;9248.16766491019;9261.44431334122;18398.632027359377;18422.35521756732;18588.25646974133;18695.71956420655;18711.81288187128;6708.329167083252;7103.89344159838;7104.928950710427;7115.786526404217;7124.903180671135;743.8437928035173;823.1069289820492;888.8577827547691;898.3127907151035;926.6154545863391;26407.33981650474;27754.86122569374;27842.2296926591;28116.7665718445;28220.43559128847;897.2328658996595;934.2666007184328;984.6504490905769;999.5302207962246;1013.7364285285731;13792.724145517233;14698.230750062297;14731.218565208135;14771.113733176044;14977;365915;370115;370836;370866;370991;48379.0498757731;48773.75613121912;48887.26669099966;48939.51060489421;48962.75518622455;1118.7762447510531;1129.9209055366046;1147.3058799426394;1155.8728539860776;1155.8959693627614;25908.96364145413;27021.459570808867;27163.72836271691;27734.167974960783;27770.72229277762;42606;42933.35599966066;42948.57051429446;42954.35568466413;42963;304435;305350;305922;305931;306222;36748.81625591855;37849.99775041626;37972.430413544374;38011;38041.239175215786;34273.485897711056;40220;42280;43746.34380484232;45051.0989780196;34778.47879775613;35193.29613407666;35239.47140792839;35644.82177589095;36096.27807443889;34935;35001.64998349984;35108.473372899934;35211.47182792312;35221.29557408786;33737.49393759145;34260.48609270813;34384.65615343815;34522;35048.64951350454;29942.700572993992;32299.838500807346;32393;32450.675493244766;32537.511937320487;23938.521229574962;24606.261812145665;24775.25674229776;24845.130420435198;24975.750242497343 09:57:27 INFO - PROCESS | 6345 | |2;jquery.html;316.8542470463585;322.1286420233269;342.4418198336711;346.4681713569671;362.3948006828596;15445.845541544595;15481.535553933372;16102.516924492269;16704.248308826103;16777.24502397392;15355;15693.529194124167;16198.676026479463;16303.83696163038;16353.182340882939;4742.834000809972;4851.8059277628945;4869.70781753096;4943.901121977558;4981.651284410074;4918.508149185071;4990.451050384473;5002.77487513061;5005.0990821652085;5005.1491246488;4730.219513780222;4826.879328016793;4960.3303554020285;4962.379702537183;4978.278149668296;4922.80308787648;4983.6760610560195;4986.152354099811;4986.376702912136;4988.301637770715;4287.356896465524;4356.716813407118;4412.227860124465;4413.249747542894;4413.955860441387;20068.297609583595;20146.79853201464;20152.29466968649;20226.494337641678;20521.076551555216;1908.0745838268567;1968.7539057617798;2165.783421657837;2270.2962081754545;2458.9180760465338;12293.508259669603;12653.36733163338;12909.806352904714;12945.676358091028;13533.593992180251;1662.5760431089996;1663.9084850333227;1704.2501299428336;1723.6552689462033;1767.849732772716;66259.66870165618;67789;68043.31956680419;68258.65870670664;68347.3165268341;8396.832063358699;8432.53621050848;8608.182222688834;8665.35009874262;8796.208341249318;11781.52873885035;11798;11812.40937953116;11868.703282418008;11869.931706146488;6459.547831651739;6495.610263384117;6639.236487803868;6672.399484046459;6748.392644662004;6294.968525157345;6306.3378345274;6478.805635830932;6538.248101468392;6539.411452969161;5481.616286859881;5576.804811831566;5645.1532270159805;5748.780682817635;5816.302043754777;1563.1324614838873;1585.7066442707905;1615.4588212948559;1625.8780591455688;1695.9745603815952;755.1240560949382;827.6192951242375;851.2466467176594;889.4707648948865;939.2204470289644;16149.35402583907;16431.671366572602;17087.487375378758;17115;18000.729989050174;854.6837670062117;863.7020228021322;864.786147462498;868.639514601446;883.2492381475694;9345;9865.556049977768;9949.850752238723;10008.64969726056;10068.899311006942;335385;345043;345684;346105;359984;64463.67768161129;71053.2894671057;80817.59591202006;81130;81964.18035819684;874.73320637205;891.5319457284847;923.9399439036441;927.355487935878;931.0968360690119;15254.237288135771;15301.540953771404;15908.840911590736;16019.759703604688;16109.838901610834;33303.50044749282;34210.31579368449;36432;37760.43359349557;39100.6089939097;50038.74980625074;53349.73325133349;53918;55563.16655250094;55595.16607250975;7015.824604384931;7144.928550714426;7166.390856777232;7181.425485961215;7238.710451581877;9525.710834834752;11353.262037967836;11842.46708898119;11854.94072529632;12227.327496987751;9763.951180244338;9919.256055795988;10131.392116473035;10357.844632330673;10492.947535262274;9436.245100392089;10123.746906327106;10214.08073273409;10558.894411055791;10806.513706883366;10203.591856325664;10618.256722029384;10740.818509963741;10841.349519028883;11015.173861960226;7137.321954414322;7203.603801790742;7239.855202895807;7376.4836461447185;7571.962140189264;10280.177585793268;10408;10515.57937682484;10550.419726915094;10972.067374273276 09:57:27 INFO - PROCESS | 6345 | |3;mootools.html;1148.0757989818194;1289.6711338608654;1346.4614154338267;1358.9660258493536;1594.8803839712025;5727.942720572793;6286.779962701301;6397.872042559146;6467.191601049869;6589.703463344149;6447.194100737408;6511.934880651192;6513.6743162841785;6518.250401203863;6520.054592084157;1711.0503670462888;1981.4154824326786;2005.358285348689;2006.2075480185317;2008.6886532587473;1767.1252729898717;1910.6369789739974;1978.2581531925528;1979.475439008664;1988.0159321135986;1891.4420246027455;1897.9145938432803;1898.136347961681;1900.5818719881672;1905.6665083610383;1817.2367605605612;1882.491727233646;1883.6421079994893;1883.8116188381189;1884.7738271407381;1949.210569719266;1956.1490751523133;1974.1116497576147;1984.087319832864;1988.09541658545;6387.648679322635;6527.93472065278;6536.477081833489;6538.444232240266;6541.378569035982;3785.8107094645434;3908.120672848615;3910.354791459406;3910.8240129194255;3914.275858966072;2461.9876900615563;2577.342777591702;2631.6184153297663;2632.105084271357;2667.9866600667065;827.3422629009951;829;833.2542374574782;839.5256679975794;857.4640849469082;8425.662973481054;8444.662213511514;8445.239928406472;8445.83108337836;8456.8731469028;3360.19355354718;3514.1741690702665;3575.2313252650806;3581.659742324475;3584.802835844054;7343.853122937511;7345.485815992829;7346.485745997729;7350.595717235603;7351.081114860643;3847.153626202231;3849.480320156743;3854.903627409281;3855.055511399669;3860.0735823402742;3181.2046988252937;3275.3776782411264;3530.3821831179953;3531.2760884018717;3535.1338921964284;2987.551867219891;3054.5570892220503;3116.2053676312394;3137.5607414961923;3219.8873039443974;1023.1047833145997;1050.8003479338902;1050.8949105089582;1065.094669530908;1067.594314160614;796.0685997383076;811.5374236685033;812.118851046621;815.6125840225845;831.2020460358124;4512.097580483916;4715.811367545329;4761.381020467386;4828.95171048285;4971.279164521125;797.103258833812;806.8305655812228;867.3364875869964;869.2567854484452;871.1549796697194;3197.2006998250436;3231;3251.7398608111293;3269.4441944869427;3387;7393.55638661682;7595.354394876497;7679.0785105786545;7703.768886933401;7709.151993280847;7623;7625.504342217738;7632.427567932427;7635.503692259986;7636.427267954815;6089.512838972872;6108.053251745987;6139.539534534928;6145.324014358506;6146.5390095742105;4371.497277812965;4427.490838553607;4483.14820184177;4493.303537951622;4539.228331183641;6849.657517124224;6935.930640693528;7004.789856304319;7071.080759501232;7077.681504332214;6949.583025018313;7131.500794944512;7132.714691412285;7136.179339376038;7152.034475345762;5648.858778530405;5712.828615141552;5715.8571035724435;5720.227769251264;5725.16985037159;5669.744861481161;5713.457221563945;6027.276726792813;6085.269767627913;6146.969265153645;5580.86047848807;5610.102383618601;5611.915821262766;5619.353774315842;5706.600537962303;5561.110222364402;5628.521575666115;5630.268065151504;5675.148727690879;5812.534997200299;5553.8333849984565;5645.012122878562;5650.63270887391;5651.745671444713;5652.689102099424;4208.821529971613;4222.387753775624;4304.213384157953;4333.9349909750745;4351.151525452507;4115.094679170577;4191.580841915784;4231.936520952249;4246.532881383107;4314.892127696833 09:57:27 INFO - PROCESS | 6345 | |4;prototype.html;257.3193902128869;262.27219465981887;264.19815858868327;265.0365919881232;268.13927293388224;4664.160451118797;4695.436547614283;4785.688930219538;4793.568578827909;4828.299896515012;4599.494055653875;4708.835190768324;4744.600342898844;4744.69159504632;4770.976145119278;3521.38375784238;3610.5667319921513;3628.2380700053022;3701.796401197933;3703.7962912039825;3563.8396272167815;3627.4740162676394;3656.542932133483;3675.981620091896;3681.6134305897895;3634.945475817865;3636.4908912752103;3641.107928557494;3643.744937854351;3652.7443078984484;3628.455731640249;3629.4192929131214;3632.4551317302353;3636.4545318202217;3639.4176931691068;3094.984525077383;3146.3078122812944;3189.952150717741;3196.96803031969;3198.4722520784044;4582.266837306015;4586.72479651222;4622.399088118556;4639.860804175846;4645.744484053375;4146.274401979642;4155;4171.228322760299;4183.184279065584;4184.958150418518;4784.330193772875;4915.164422048225;4937.950620493785;4938.283948827436;4940.209566469348;3467.1505481157023;3477.4783782432582;3477.669621385964;3479.9826000869834;3483;5158.148905430599;5170.767315470813;5176.844694659167;5181.533661970403;5189.832287735229;3545.6099829018785;3547.130952916552;3552.2007548301426;3567.6977903065713;3570.946435803465;3496.8426420811124;3567.322208780375;3601.585817631006;3621.8189090545366;3626.7279954003557;4089.5297040840683;4102.876913692594;4138.8551400700835;4139.296319625671;4151.3772934059525;4935.555799978019;4942.555170034714;4968.354113965233;4969.527894849984;4972.5276098770555;1146.8623765148236;1163.4996951311018;1186.9465874035689;1193.4868006757179;1209.7520008398258;806.0488623424299;821.8356328734277;831.4886344897965;836.8159005019007;840.8528507511161;4221.915561688749;4223.909164963363;4237.894052648647;4268.893277668021;4278.7004909656025;4932.432770231398;4956.843772958702;4967.205247160437;4971.428285747185;4974.228994505857;4222.514410842731;4241.603494025865;4246.575342465729;4255.170241802881;4267.765272909957;2552.859592722381;2564.4101856573093;2593.3775893785355;2635.8665773717485;2643.788496920242;4560.95439045612;4572.839950601713;4581.244094724333;4622.768861556909;4639.652026098056;4991.376077990251;5034.546890779848;5041.949580504222;5074.06129865976;5079.923801142986;4428.756418397019;4631.58315751584;4648.790804413742;4654.604358629555;4665.743384113906;4845.594777514481;4873.269009648581;4920.286558448934;4962.727050012284;4966.807966088185;4807.254875494304;4829.806807727792;4834.178189707688;4844.709317440965;4873.51264873524;4448.5773851484055;4510.1656193604285;4618.475902952085;4622.260438329851;4625.93061104077;3446.741494387981;3450.3789317923015;3464.2032332564154;3466.289410670857;3468.5490886184634;4945.109880221451;4952.4304704959395;4963.454020057863;4975.955049439658;4980.086649310194;4619.284010978304;4815.616269946399;4844.2007068833245;4943.627059011623;4960.77676504551;4986.700797952134;5004.522761233213;5008.84973450797;5014.197728363444;5049.722265275298;4822.71063736177;4920.6555541111775;4929.556339929423;4952.579030782424;4966.478519755374;4966.602671786176;4989.301497790239;5018.723970181675;5030.170021946485;5035.420926593342;4902;4902.63230257725;4907.77914993819;4923.384576927884;4945.653804233669 09:57:27 INFO - PROCESS | 6345 | |5;yui.html;596.8328867916982;645.3578689204243;677.2042849651659;703.5391818358974;704.8177478683741;14676.266186690662;15217.619559511018;15297.847021529782;15331.080135191896;15347.76978345323;13728.244946527937;13826.585202443917;13839;14465.059771114897;15018.399264029453;618.3383779356091;734.4014628078099;757.9848403031936;758.5903612049486;759.3431681595436;622.5704264057808;737.2627372627372;750.5009168902673;757.1028331427276;757.5303311946601;802.8554860125175;804.1636697834246;804.8671969125087;805.6414895371562;806.0206848678854;639.9232092148925;796.4026979765176;796.8087658961865;797.2386371015666;797.3063434811722;476.29270533258085;561.6658088437373;570.8373113662627;575.1832397994857;576.691470063514;14113.576592702235;14149.504767333197;14164.362603682755;14164.92917535416;14178.929105354511;11999.580014699532;12085.93957030218;12374.319412432313;12435.44040518179;12588.622341329774;11643.708907277301;11688.883111168949;11720.531178752839;11777.64667059981;11876.703082422922;3986.032023174156;4038.434619153321;4053.5541090480024;4085.0604360997136;4128.793560321972;64596.67701661509;65610;66173.99999999952;67122;67370.66314668444;10077.949610251902;10110.140638045701;10227.130693890953;10282.845757313646;10369.740756480998;18502.907485462485;18701.34545290908;18709.90645046766;18732.752069751445;18961.905190474234;6560.606363618198;6603.273639899608;6707.295733947963;6746.224184218878;6860.588364698035;3583.6416358363954;3667.1382225177063;3743.644353786386;3774.584795672474;3778.792166430818;2560.590305551103;2659.4415172813538;2670.092168662625;2672.6926403463463;2673.8262012969094;453.5283305362452;457.1953362082758;461.92147334953137;462.1565642702082;464.13438936384074;481.508860961817;518.7613697699026;527.5120513524975;535.3067777228498;567.8296511046669;7140.214576396592;7417.940278620236;7446.553206807501;7447.88828167578;7566.810829729301;473.9004808990082;520.6121439527501;524.7005247005217;560.4087687489738;568.1534513574826;3553.7867727936405;3581.6955558777277;3630.854765809338;3691.6123807000417;3777.225668737902;73831.63084184544;73896.39225698727;74185.72039503197;74352;75768;62004.68997654983;64104;64300;65486;66788.33211667919;58108.41891581115;58562.12156817736;58581.70709146427;59089.70455147783;59237.40762592319;6905.447564194953;7071.575705457689;7117.4661900358715;7179.856402871809;8539.658413663385;35552.46671299984;36482.08794780152;36593.45109823408;36994.26011479701;38439.807800961935;49007.754961224964;49667.50332496629;50324.49675503198;50704.23943640775;53367.46632533625;11151.832722509007;11456.312621242752;11533.250338727956;11676.766464670829;11689.766204676029;11818;12857.549985750455;13068.80396794068;13459.3943272551;13507.527236546674;12550.874491255336;12905.612831615066;12970.156939798882;13220.867791321964;13320.93339533296;11740.354280514654;12270.386480676108;12318.75362492763;12340.629781106582;12557.87442125567;12355.196912200678;12411.255324680547;12574.49702011946;12723.936380318039;13071.921556476284;9554.378965367228;9851.704448866545;9985.201183905416;10140.695779126638;10244.846327304947;10128;10182.541785619813;10192;10213.182945364204;10345.224108191765 09:57:27 INFO - PROCESS | 6345 | __end_tp_report 09:57:27 INFO - PROCESS | 6345 | __start_cc_report 09:57:27 INFO - PROCESS | 6345 | _x_x_mozilla_cycle_collect,1407 09:57:27 INFO - PROCESS | 6345 | __end_cc_report 09:57:27 INFO - PROCESS | 6345 | __startTimestamp1480442247616__endTimestamp 09:57:27 INFO - PROCESS | 6345 | 09:57:27 INFO - PROCESS | 6345 | ------- Summary: start ------- 09:57:27 INFO - PROCESS | 6345 | Number of tests: 6 09:57:27 INFO - PROCESS | 6345 | 09:57:27 INFO - PROCESS | 6345 | [#0] dojo.html Cycles:165 Average:4598.15 Median:4754.71 stddev:479.13 (10.1%) stddev-sans-first:453.27 09:57:27 INFO - PROCESS | 6345 | Values: 2564.9 2577.4 2604.9 2613.6 2621.2 4548.3 4591.3 4669.3 4684.7 4687.7 4630.6 4659.0 4684.8 4727.8 4785.4 4262.6 4274.0 4312.8 4318.7 4409.2 3924.1 4238.3 4353.4 4412.8 4432.3 4214.0 4296.5 4317.9 4384.4 4402.7 4216.9 4263.4 4274.3 4279.2 4287.2 4250.5 4255.6 4267.5 4271.4 4273.2 4654.7 4685.1 4686.1 4747.8 4754.7 4977.7 4983.7 4988.1 4988.8 5044.3 4878.0 4926.2 4943.1 5018.6 5020.8 4590.7 4626.7 4627.5 4628.2 4665.1 5055.8 5060.7 5061.6 5067.4 5108.3 4822.9 4861.0 4862.9 4870.1 4898.8 4839.5 4864.4 4875.1 4892.1 4982.9 4844.2 4937.5 4946.6 4975.7 4988.2 4894.8 4896.0 4911.6 4912.4 4951.8 4153.1 4187.5 4209.3 4226.2 4253.1 3655.1 3675.4 3687.0 3690.8 3747.5 4271.5 4276.5 4277.4 4278.8 4312.7 5015.4 5018.0 5021.4 5026.4 5058.8 4227.1 4235.8 4254.1 4273.1 4278.8 4536.1 4546.7 4552.2 4582.3 4588.8 4699.7 4712.8 4713.9 4719.6 4758.0 4871.2 4903.0 4905.6 5058.8 5079.3 4597.7 4610.6 4634.0 4666.4 4709.8 4899.5 4904.9 4906.2 4906.6 4952.5 4895.6 4970.5 4981.0 4985.8 5028.2 4801.7 4865.1 4872.9 4888.9 4889.7 4854.7 4857.5 4861.8 4865.8 4904.0 4777.8 4787.0 4789.0 4792.4 4842.8 4785.3 4812.6 4817.8 4824.9 4862.2 4786.7 4804.0 4822.4 4828.4 4848.7 09:57:27 INFO - PROCESS | 6345 | 09:57:27 INFO - PROCESS | 6345 | [#1] ext.html Cycles:180 Average:44413.12 Median:35230.38 stddev:77413.36 (219.7%) stddev-sans-first:77560.52 09:57:27 INFO - PROCESS | 6345 | Values: 566.2 568.2 571.1 589.8 592.7 27369.7 38027.4 38701.6 38841.8 42228.4 39482.8 39821.2 39940.6 39955.6 39963.0 7545.9 7730.6 7731.0 7736.5 7740.1 7398.5 7597.8 9755.9 10002.4 10019.9 10034.8 10104.7 10118.3 10119.5 10139.7 10007.5 10072.9 10074.4 10078.7 10081.6 9750.3 9755.0 9814.3 9820.6 9829.8 36665.8 36944.4 36963.8 36991.4 37073.1 3104.2 3159.8 3184.2 3294.4 3652.2 27396.3 28137.6 28237.6 28251.9 28378.1 3258.3 3314.0 3344.4 3344.6 3388.1 171006.0 171208.0 171535.0 171652.0 171652.0 50086.7 51659.7 51685.2 51708.7 51767.2 53094.7 53320.0 53391.2 53396.0 53492.5 12560.7 12663.7 12806.2 12806.2 12817.9 8850.0 9162.1 9188.9 9248.2 9261.4 18398.6 18422.4 18588.3 18695.7 18711.8 6708.3 7103.9 7104.9 7115.8 7124.9 743.8 823.1 888.9 898.3 926.6 26407.3 27754.9 27842.2 28116.8 28220.4 897.2 934.3 984.7 999.5 1013.7 13792.7 14698.2 14731.2 14771.1 14977.0 365915.0 370115.0 370836.0 370866.0 370991.0 48379.0 48773.8 48887.3 48939.5 48962.8 1118.8 1129.9 1147.3 1155.9 1155.9 25909.0 27021.5 27163.7 27734.2 27770.7 42606.0 42933.4 42948.6 42954.4 42963.0 304435.0 305350.0 305922.0 305931.0 306222.0 36748.8 37850.0 37972.4 38011.0 38041.2 34273.5 40220.0 42280.0 43746.3 45051.1 34778.5 35193.3 35239.5 35644.8 36096.3 34935.0 35001.6 35108.5 35211.5 35221.3 33737.5 34260.5 34384.7 34522.0 35048.6 29942.7 32299.8 32393.0 32450.7 32537.5 23938.5 24606.3 24775.3 24845.1 24975.8 09:57:27 INFO - PROCESS | 6345 | 09:57:27 INFO - PROCESS | 6345 | [#2] jquery.html Cycles:180 Average:22962.90 Median:4412.74 stddev:57543.97 (1304.0%) stddev-sans-first:57680.27 09:57:27 INFO - PROCESS | 6345 | Values: 316.9 322.1 342.4 346.5 362.4 15445.8 15481.5 16102.5 16704.2 16777.2 15355.0 15693.5 16198.7 16303.8 16353.2 4742.8 4851.8 4869.7 4943.9 4981.7 4918.5 4990.5 5002.8 5005.1 5005.1 4730.2 4826.9 4960.3 4962.4 4978.3 4922.8 4983.7 4986.2 4986.4 4988.3 4287.4 4356.7 4412.2 4413.2 4414.0 20068.3 20146.8 20152.3 20226.5 20521.1 1908.1 1968.8 2165.8 2270.3 2458.9 12293.5 12653.4 12909.8 12945.7 13533.6 1662.6 1663.9 1704.3 1723.7 1767.8 66259.7 67789.0 68043.3 68258.7 68347.3 8396.8 8432.5 8608.2 8665.4 8796.2 11781.5 11798.0 11812.4 11868.7 11869.9 6459.5 6495.6 6639.2 6672.4 6748.4 6295.0 6306.3 6478.8 6538.2 6539.4 5481.6 5576.8 5645.2 5748.8 5816.3 1563.1 1585.7 1615.5 1625.9 1696.0 755.1 827.6 851.2 889.5 939.2 16149.4 16431.7 17087.5 17115.0 18000.7 854.7 863.7 864.8 868.6 883.2 9345.0 9865.6 9949.9 10008.6 10068.9 335385.0 345043.0 345684.0 346105.0 359984.0 64463.7 71053.3 80817.6 81130.0 81964.2 874.7 891.5 923.9 927.4 931.1 15254.2 15301.5 15908.8 16019.8 16109.8 33303.5 34210.3 36432.0 37760.4 39100.6 50038.7 53349.7 53918.0 55563.2 55595.2 7015.8 7144.9 7166.4 7181.4 7238.7 9525.7 11353.3 11842.5 11854.9 12227.3 9764.0 9919.3 10131.4 10357.8 10492.9 9436.2 10123.7 10214.1 10558.9 10806.5 10203.6 10618.3 10740.8 10841.3 11015.2 7137.3 7203.6 7239.9 7376.5 7572.0 10280.2 10408.0 10515.6 10550.4 10972.1 09:57:27 INFO - PROCESS | 6345 | 09:57:27 INFO - PROCESS | 6345 | [#3] mootools.html Cycles:180 Average:4304.64 Median:5557.47 stddev:2254.91 (40.6%) stddev-sans-first:2248.76 09:57:27 INFO - PROCESS | 6345 | Values: 1148.1 1289.7 1346.5 1359.0 1594.9 5727.9 6286.8 6397.9 6467.2 6589.7 6447.2 6511.9 6513.7 6518.3 6520.1 1711.1 1981.4 2005.4 2006.2 2008.7 1767.1 1910.6 1978.3 1979.5 1988.0 1891.4 1897.9 1898.1 1900.6 1905.7 1817.2 1882.5 1883.6 1883.8 1884.8 1949.2 1956.1 1974.1 1984.1 1988.1 6387.6 6527.9 6536.5 6538.4 6541.4 3785.8 3908.1 3910.4 3910.8 3914.3 2462.0 2577.3 2631.6 2632.1 2668.0 827.3 829.0 833.3 839.5 857.5 8425.7 8444.7 8445.2 8445.8 8456.9 3360.2 3514.2 3575.2 3581.7 3584.8 7343.9 7345.5 7346.5 7350.6 7351.1 3847.2 3849.5 3854.9 3855.1 3860.1 3181.2 3275.4 3530.4 3531.3 3535.1 2987.6 3054.6 3116.2 3137.6 3219.9 1023.1 1050.8 1050.9 1065.1 1067.6 796.1 811.5 812.1 815.6 831.2 4512.1 4715.8 4761.4 4829.0 4971.3 797.1 806.8 867.3 869.3 871.2 3197.2 3231.0 3251.7 3269.4 3387.0 7393.6 7595.4 7679.1 7703.8 7709.2 7623.0 7625.5 7632.4 7635.5 7636.4 6089.5 6108.1 6139.5 6145.3 6146.5 4371.5 4427.5 4483.1 4493.3 4539.2 6849.7 6935.9 7004.8 7071.1 7077.7 6949.6 7131.5 7132.7 7136.2 7152.0 5648.9 5712.8 5715.9 5720.2 5725.2 5669.7 5713.5 6027.3 6085.3 6147.0 5580.9 5610.1 5611.9 5619.4 5706.6 5561.1 5628.5 5630.3 5675.1 5812.5 5553.8 5645.0 5650.6 5651.7 5652.7 4208.8 4222.4 4304.2 4333.9 4351.2 4115.1 4191.6 4231.9 4246.5 4314.9 09:57:27 INFO - PROCESS | 6345 | 09:57:27 INFO - PROCESS | 6345 | [#4] prototype.html Cycles:180 Average:4048.40 Median:4620.77 stddev:1190.78 (25.8%) stddev-sans-first:1159.63 09:57:27 INFO - PROCESS | 6345 | Values: 257.3 262.3 264.2 265.0 268.1 4664.2 4695.4 4785.7 4793.6 4828.3 4599.5 4708.8 4744.6 4744.7 4771.0 3521.4 3610.6 3628.2 3701.8 3703.8 3563.8 3627.5 3656.5 3676.0 3681.6 3634.9 3636.5 3641.1 3643.7 3652.7 3628.5 3629.4 3632.5 3636.5 3639.4 3095.0 3146.3 3190.0 3197.0 3198.5 4582.3 4586.7 4622.4 4639.9 4645.7 4146.3 4155.0 4171.2 4183.2 4185.0 4784.3 4915.2 4938.0 4938.3 4940.2 3467.2 3477.5 3477.7 3480.0 3483.0 5158.1 5170.8 5176.8 5181.5 5189.8 3545.6 3547.1 3552.2 3567.7 3570.9 3496.8 3567.3 3601.6 3621.8 3626.7 4089.5 4102.9 4138.9 4139.3 4151.4 4935.6 4942.6 4968.4 4969.5 4972.5 1146.9 1163.5 1186.9 1193.5 1209.8 806.0 821.8 831.5 836.8 840.9 4221.9 4223.9 4237.9 4268.9 4278.7 4932.4 4956.8 4967.2 4971.4 4974.2 4222.5 4241.6 4246.6 4255.2 4267.8 2552.9 2564.4 2593.4 2635.9 2643.8 4561.0 4572.8 4581.2 4622.8 4639.7 4991.4 5034.5 5041.9 5074.1 5079.9 4428.8 4631.6 4648.8 4654.6 4665.7 4845.6 4873.3 4920.3 4962.7 4966.8 4807.3 4829.8 4834.2 4844.7 4873.5 4448.6 4510.2 4618.5 4622.3 4625.9 3446.7 3450.4 3464.2 3466.3 3468.5 4945.1 4952.4 4963.5 4976.0 4980.1 4619.3 4815.6 4844.2 4943.6 4960.8 4986.7 5004.5 5008.8 5014.2 5049.7 4822.7 4920.7 4929.6 4952.6 4966.5 4966.6 4989.3 5018.7 5030.2 5035.4 4902.0 4902.6 4907.8 4923.4 4945.7 09:57:27 INFO - PROCESS | 6345 | 09:57:27 INFO - PROCESS | 6345 | [#5] yui.html Cycles:180 Average:15874.86 Median:4106.93 stddev:20432.78 (497.5%) stddev-sans-first:20457.89 09:57:27 INFO - PROCESS | 6345 | Values: 596.8 645.4 677.2 703.5 704.8 14676.3 15217.6 15297.8 15331.1 15347.8 13728.2 13826.6 13839.0 14465.1 15018.4 618.3 734.4 758.0 758.6 759.3 622.6 737.3 750.5 757.1 757.5 802.9 804.2 804.9 805.6 806.0 639.9 796.4 796.8 797.2 797.3 476.3 561.7 570.8 575.2 576.7 14113.6 14149.5 14164.4 14164.9 14178.9 11999.6 12085.9 12374.3 12435.4 12588.6 11643.7 11688.9 11720.5 11777.6 11876.7 3986.0 4038.4 4053.6 4085.1 4128.8 64596.7 65610.0 66174.0 67122.0 67370.7 10077.9 10110.1 10227.1 10282.8 10369.7 18502.9 18701.3 18709.9 18732.8 18961.9 6560.6 6603.3 6707.3 6746.2 6860.6 3583.6 3667.1 3743.6 3774.6 3778.8 2560.6 2659.4 2670.1 2672.7 2673.8 453.5 457.2 461.9 462.2 464.1 481.5 518.8 527.5 535.3 567.8 7140.2 7417.9 7446.6 7447.9 7566.8 473.9 520.6 524.7 560.4 568.2 3553.8 3581.7 3630.9 3691.6 3777.2 73831.6 73896.4 74185.7 74352.0 75768.0 62004.7 64104.0 64300.0 65486.0 66788.3 58108.4 58562.1 58581.7 59089.7 59237.4 6905.4 7071.6 7117.5 7179.9 8539.7 35552.5 36482.1 36593.5 36994.3 38439.8 49007.8 49667.5 50324.5 50704.2 53367.5 11151.8 11456.3 11533.3 11676.8 11689.8 11818.0 12857.5 13068.8 13459.4 13507.5 12550.9 12905.6 12970.2 13220.9 13320.9 11740.4 12270.4 12318.8 12340.6 12557.9 12355.2 12411.3 12574.5 12723.9 13071.9 9554.4 9851.7 9985.2 10140.7 10244.8 10128.0 10182.5 10192.0 10213.2 10345.2 09:57:27 INFO - PROCESS | 6345 | -------- Summary: end -------- 09:57:27 INFO - PROCESS | 6345 | 09:57:28 INFO - TEST-INFO | 6345: exit 0 09:57:28 INFO - TEST-OK | dromaeo_css | took 1122017ms 09:57:28 INFO - TEST-START | kraken 09:57:28 INFO - operating with platform_type : linux_ 09:57:28 INFO - Initialising browser for kraken test... 09:57:28 INFO - TEST-INFO | started process 6651 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJirIM_/profile http://localhost:37929/getInfo.html) 09:57:32 INFO - PROCESS | 6651 | __metrics Screen width/height:1600/1200 09:57:32 INFO - PROCESS | 6651 | colorDepth:24 09:57:32 INFO - PROCESS | 6651 | Browser inner width/height: 1024/697 09:57:32 INFO - PROCESS | 6651 | __metrics 09:57:33 INFO - PROCESS | 6651 | 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" 09:57:33 INFO - PROCESS | 6651 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:57:34 INFO - PROCESS | 6651 | console.error: PushService: 09:57:34 INFO - PROCESS | 6651 | stateChangeProcessEnqueue: Error transitioning state 09:57:34 INFO - PROCESS | 6651 | UnknownError 09:57:34 INFO - TEST-INFO | 6651: exit 0 09:57:34 INFO - Browser initialized. 09:57:34 INFO - Running cycle 1/1 for kraken test... 09:57:34 INFO - TEST-INFO | started process 6732 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJirIM_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:57:47 INFO - PROCESS | 6732 | RSS: Main: 174870528 09:57:47 INFO - PROCESS | 6732 | 09:57:50 INFO - PROCESS | 6732 | Cycle 1(1): loaded http://localhost:37929/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37929/tests/kraken/driver.html?testName=audio-beat-detection) 09:57:51 INFO - PROCESS | 6732 | RSS: Main: 240058368 09:57:51 INFO - PROCESS | 6732 | 09:57:53 INFO - PROCESS | 6732 | Cycle 1(1): loaded http://localhost:37929/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37929/tests/kraken/driver.html?testName=audio-dft) 09:57:54 INFO - PROCESS | 6732 | RSS: Main: 218439680 09:57:54 INFO - PROCESS | 6732 | 09:57:57 INFO - PROCESS | 6732 | Cycle 1(1): loaded http://localhost:37929/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37929/tests/kraken/driver.html?testName=audio-fft) 09:57:58 INFO - PROCESS | 6732 | RSS: Main: 300408832 09:57:58 INFO - PROCESS | 6732 | 09:58:00 INFO - PROCESS | 6732 | Cycle 1(1): loaded http://localhost:37929/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37929/tests/kraken/driver.html?testName=audio-oscillator) 09:58:00 INFO - PROCESS | 6732 | RSS: Main: 211628032 09:58:00 INFO - PROCESS | 6732 | 09:58:03 INFO - PROCESS | 6732 | Cycle 1(1): loaded http://localhost:37929/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37929/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:58:03 INFO - PROCESS | 6732 | RSS: Main: 215519232 09:58:03 INFO - PROCESS | 6732 | 09:58:06 INFO - PROCESS | 6732 | Cycle 1(1): loaded http://localhost:37929/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37929/tests/kraken/driver.html?testName=imaging-darkroom) 09:58:07 INFO - PROCESS | 6732 | RSS: Main: 254050304 09:58:07 INFO - PROCESS | 6732 | 09:58:11 INFO - PROCESS | 6732 | Cycle 1(1): loaded http://localhost:37929/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37929/tests/kraken/driver.html?testName=imaging-desaturate) 09:58:11 INFO - PROCESS | 6732 | RSS: Main: 248807424 09:58:11 INFO - PROCESS | 6732 | 09:58:14 INFO - PROCESS | 6732 | Cycle 1(1): loaded http://localhost:37929/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37929/tests/kraken/driver.html?testName=json-parse-financial) 09:58:15 INFO - PROCESS | 6732 | RSS: Main: 249917440 09:58:15 INFO - PROCESS | 6732 | 09:58:16 INFO - PROCESS | 6732 | Cycle 1(1): loaded http://localhost:37929/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37929/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:58:17 INFO - PROCESS | 6732 | RSS: Main: 213348352 09:58:17 INFO - PROCESS | 6732 | 09:58:19 INFO - PROCESS | 6732 | Cycle 1(1): loaded http://localhost:37929/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37929/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:58:20 INFO - PROCESS | 6732 | RSS: Main: 220938240 09:58:20 INFO - PROCESS | 6732 | 09:58:22 INFO - PROCESS | 6732 | Cycle 1(1): loaded http://localhost:37929/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37929/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:58:22 INFO - PROCESS | 6732 | RSS: Main: 229470208 09:58:22 INFO - PROCESS | 6732 | 09:58:25 INFO - PROCESS | 6732 | Cycle 1(1): loaded http://localhost:37929/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37929/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:58:26 INFO - PROCESS | 6732 | RSS: Main: 237772800 09:58:26 INFO - PROCESS | 6732 | 09:58:29 INFO - PROCESS | 6732 | Cycle 1(1): loaded http://localhost:37929/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37929/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:58:30 INFO - PROCESS | 6732 | RSS: Main: 235421696 09:58:30 INFO - PROCESS | 6732 | 09:58:31 INFO - PROCESS | 6732 | Cycle 1(1): loaded http://localhost:37929/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37929/tests/kraken/driver.html?testName=ai-astar) 09:58:32 INFO - PROCESS | 6732 | RSS: Main: 285958144 09:58:32 INFO - PROCESS | 6732 | 09:58:32 INFO - PROCESS | 6732 | __start_tp_report 09:58:32 INFO - PROCESS | 6732 | _x_x_mozilla_page_load 09:58:32 INFO - PROCESS | 6732 | _x_x_mozilla_page_load_details 09:58:32 INFO - PROCESS | 6732 | |i|pagename|runs| 09:58:32 INFO - PROCESS | 6732 | |0;ai-astar;114;118;116;117;118;116;116;117;115;115 09:58:32 INFO - PROCESS | 6732 | |1;audio-beat-detection;150;130;128;162;134;135;135;128;152;135 09:58:32 INFO - PROCESS | 6732 | |2;audio-dft;160;161;162;163;162;162;161;160;161;163 09:58:32 INFO - PROCESS | 6732 | |3;audio-fft;77;76;77;107;76;77;77;76;92;76 09:58:32 INFO - PROCESS | 6732 | |4;audio-oscillator;84;84;115;83;84;84;103;85;84;117 09:58:32 INFO - PROCESS | 6732 | |5;imaging-gaussian-blur;117;117;117;117;117;117;119;118;117;117 09:58:32 INFO - PROCESS | 6732 | |6;imaging-darkroom;147;147;147;146;147;147;147;146;147;147 09:58:32 INFO - PROCESS | 6732 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;95;95 09:58:32 INFO - PROCESS | 6732 | |8;json-parse-financial;64;63;63;63;64;64;64;64;64;66 09:58:32 INFO - PROCESS | 6732 | |9;json-stringify-tinderbox;65;66;65;66;66;65;65;66;66;66 09:58:32 INFO - PROCESS | 6732 | |10;stanford-crypto-aes;84;87;92;86;85;84;84;84;86;85 09:58:32 INFO - PROCESS | 6732 | |11;stanford-crypto-ccm;146;146;147;150;148;146;143;140;188;141 09:58:32 INFO - PROCESS | 6732 | |12;stanford-crypto-pbkdf2;179;181;178;179;178;178;177;188;185;182 09:58:32 INFO - PROCESS | 6732 | |13;stanford-crypto-sha256-iterative;75;71;71;75;71;72;72;72;72;70 09:58:32 INFO - PROCESS | 6732 | __end_tp_report 09:58:32 INFO - PROCESS | 6732 | __start_cc_report 09:58:32 INFO - PROCESS | 6732 | _x_x_mozilla_cycle_collect,1988 09:58:32 INFO - PROCESS | 6732 | __end_cc_report 09:58:32 INFO - PROCESS | 6732 | __startTimestamp1480442312241__endTimestamp 09:58:32 INFO - PROCESS | 6732 | 09:58:32 INFO - PROCESS | 6732 | ------- Summary: start ------- 09:58:32 INFO - PROCESS | 6732 | Number of tests: 14 09:58:32 INFO - PROCESS | 6732 | 09:58:32 INFO - PROCESS | 6732 | [#0] ai-astar Cycles:10 Average:116.20 Median:116.50 stddev:1.32 (1.1%) stddev-sans-first:1.13 09:58:32 INFO - PROCESS | 6732 | Values: 114.0 118.0 116.0 117.0 118.0 116.0 116.0 117.0 115.0 115.0 09:58:32 INFO - PROCESS | 6732 | 09:58:32 INFO - PROCESS | 6732 | [#1] audio-beat-detection Cycles:10 Average:138.90 Median:135.00 stddev:11.62 (8.6%) stddev-sans-first:11.61 09:58:32 INFO - PROCESS | 6732 | Values: 150.0 130.0 128.0 162.0 134.0 135.0 135.0 128.0 152.0 135.0 09:58:32 INFO - PROCESS | 6732 | 09:58:32 INFO - PROCESS | 6732 | [#2] audio-dft Cycles:10 Average:161.50 Median:162.00 stddev:1.08 (0.7%) stddev-sans-first:1.00 09:58:32 INFO - PROCESS | 6732 | Values: 160.0 161.0 162.0 163.0 162.0 162.0 161.0 160.0 161.0 163.0 09:58:32 INFO - PROCESS | 6732 | 09:58:32 INFO - PROCESS | 6732 | [#3] audio-fft Cycles:10 Average:81.10 Median:77.00 stddev:10.33 (13.4%) stddev-sans-first:10.85 09:58:32 INFO - PROCESS | 6732 | Values: 77.0 76.0 77.0 107.0 76.0 77.0 77.0 76.0 92.0 76.0 09:58:32 INFO - PROCESS | 6732 | 09:58:32 INFO - PROCESS | 6732 | [#4] audio-oscillator Cycles:10 Average:92.30 Median:84.00 stddev:13.84 (16.5%) stddev-sans-first:14.35 09:58:32 INFO - PROCESS | 6732 | Values: 84.0 84.0 115.0 83.0 84.0 84.0 103.0 85.0 84.0 117.0 09:58:32 INFO - PROCESS | 6732 | 09:58:32 INFO - PROCESS | 6732 | [#5] imaging-gaussian-blur Cycles:10 Average:117.30 Median:117.00 stddev:0.67 (0.6%) stddev-sans-first:0.71 09:58:32 INFO - PROCESS | 6732 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 119.0 118.0 117.0 117.0 09:58:32 INFO - PROCESS | 6732 | 09:58:32 INFO - PROCESS | 6732 | [#6] imaging-darkroom Cycles:10 Average:146.80 Median:147.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 09:58:32 INFO - PROCESS | 6732 | Values: 147.0 147.0 147.0 146.0 147.0 147.0 147.0 146.0 147.0 147.0 09:58:32 INFO - PROCESS | 6732 | 09:58:32 INFO - PROCESS | 6732 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 09:58:32 INFO - PROCESS | 6732 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 09:58:32 INFO - PROCESS | 6732 | 09:58:32 INFO - PROCESS | 6732 | [#8] json-parse-financial Cycles:10 Average:63.90 Median:64.00 stddev:0.88 (1.4%) stddev-sans-first:0.93 09:58:32 INFO - PROCESS | 6732 | Values: 64.0 63.0 63.0 63.0 64.0 64.0 64.0 64.0 64.0 66.0 09:58:32 INFO - PROCESS | 6732 | 09:58:32 INFO - PROCESS | 6732 | [#9] json-stringify-tinderbox Cycles:10 Average:65.60 Median:66.00 stddev:0.52 (0.8%) stddev-sans-first:0.50 09:58:32 INFO - PROCESS | 6732 | Values: 65.0 66.0 65.0 66.0 66.0 65.0 65.0 66.0 66.0 66.0 09:58:32 INFO - PROCESS | 6732 | 09:58:32 INFO - PROCESS | 6732 | [#10] stanford-crypto-aes Cycles:10 Average:85.70 Median:85.50 stddev:2.45 (2.9%) stddev-sans-first:2.52 09:58:32 INFO - PROCESS | 6732 | Values: 84.0 87.0 92.0 86.0 85.0 84.0 84.0 84.0 86.0 85.0 09:58:32 INFO - PROCESS | 6732 | 09:58:32 INFO - PROCESS | 6732 | [#11] stanford-crypto-ccm Cycles:10 Average:149.50 Median:146.50 stddev:13.87 (9.5%) stddev-sans-first:14.66 09:58:32 INFO - PROCESS | 6732 | Values: 146.0 146.0 147.0 150.0 148.0 146.0 143.0 140.0 188.0 141.0 09:58:32 INFO - PROCESS | 6732 | 09:58:32 INFO - PROCESS | 6732 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.50 Median:180.00 stddev:3.57 (2.0%) stddev-sans-first:3.74 09:58:32 INFO - PROCESS | 6732 | Values: 179.0 181.0 178.0 179.0 178.0 178.0 177.0 188.0 185.0 182.0 09:58:32 INFO - PROCESS | 6732 | 09:58:32 INFO - PROCESS | 6732 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.10 Median:72.00 stddev:1.66 (2.3%) stddev-sans-first:1.39 09:58:32 INFO - PROCESS | 6732 | Values: 75.0 71.0 71.0 75.0 71.0 72.0 72.0 72.0 72.0 70.0 09:58:32 INFO - PROCESS | 6732 | -------- Summary: end -------- 09:58:32 INFO - PROCESS | 6732 | 09:58:33 INFO - TEST-INFO | 6732: exit 0 09:58:33 INFO - TEST-OK | kraken | took 64822ms 09:58:33 INFO - SUITE-END | took 1186s 09:58:33 INFO - Completed test suite (00:19:47) 09:58:33 INFO - javascript benchmark 09:58:33 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2564.8717564121794, 2577.445849142434, 2604.9348766280846, 2613.621024951382, 2621.1743300860226, 4548.272276435771, 4591.334256532809, 4669.322948172513, 4684.695494792836, 4687.671862969593, 4630.583247507732, 4658.953410465894, 4684.8126074957045, 4727.810887564502, 4785.353977213066, 4262.595053469915, 4274.0383413731815, 4312.805923733439, 4318.719283246594, 4409.206342858284, 3924.0869662516807, 4238.300680387732, 4353.380306992158, 4412.84555040572, 4432.290833466646, 4213.9578604213875, 4296.5273819879785, 4317.913641727179, 4384.430024096863, 4402.669799765014, 4216.936745948813, 4263.360495925605, 4274.273373526508, 4279.165562715271, 4287.228298906196, 4250.511191213019, 4255.63827074699, 4267.509236437819, 4271.423357846682, 4273.188094262111, 4654.697444666086, 4685.109829132454, 4686.133065382915, 4747.786349614275, 4754.7147171169845, 4977.7262250576205, 4983.651144419892, 4988.077205716954, 4988.775505102279, 5044.319016932704, 4877.975610121962, 4926.2118061110405, 4943.134951383529, 5018.573421259161, 5020.799168033274, 4590.74750888701, 4626.699264547794, 4627.514111018362, 4628.166929952641, 4665.066986602693, 5055.797768089309, 5060.671056381397, 5061.569766569809, 5067.417247016567, 5108.310378098983, 4822.855314340575, 4860.975695121501, 4862.927056094161, 4870.123377791961, 4898.804047838046, 4839.467658557556, 4864.416270047617, 4875.1224779540025, 4892.094962431962, 4982.90034199314, 4844.15227335222, 4937.530934561211, 4946.629002824802, 4975.726335051607, 4988.176950803149, 4894.828680996148, 4895.975520122377, 4911.582515486152, 4912.435069966999, 4951.826686066042, 4153.086321009373, 4187.5184353799095, 4209.2633789086785, 4226.197022565764, 4253.149370125987, 3655.067957670777, 3675.3568125578454, 3686.9631303687156, 3690.7970061646865, 3747.475353450519, 4271.487421509439, 4276.5082015568605, 4277.422547955986, 4278.764667943293, 4312.741235525816, 5015.398152221756, 5018.0, 5021.372328458942, 5026.396832380138, 5058.797648094035, 4227.112306415687, 4235.80938857746, 4254.127903779718, 4273.081287523198, 4278.764667943293, 4536.115457485825, 4546.704464209815, 4552.180607490617, 4582.312653102062, 4588.7935042923145, 4699.671023028423, 4712.764361781966, 4713.882152946204, 4719.622430205575, 4757.976210118927, 4871.171900776805, 4903.0, 4905.607551395951, 5058.772355243949, 5079.314292570456, 4597.655175861757, 4610.561996610317, 4633.953660463352, 4666.3700400445505, 4709.788059537261, 4899.461059283405, 4904.852854414374, 4906.190478570996, 4906.6320025997475, 4952.479989601185, 4895.632827537877, 4970.527799859007, 4980.978899325702, 4985.803407182323, 5028.195488721787, 4801.663883528119, 4865.1242776300605, 4872.926906096341, 4888.902221955612, 4889.679786457707, 4854.708717476963, 4857.514248575114, 4861.781219845045, 4865.829695960624, 4904.043711476228, 4777.808887644455, 4787.042591481787, 4789.042191561772, 4792.424909010941, 4842.806287748451, 4785.330053792402, 4812.590929770869, 4817.78319975609, 4824.927626085681, 4862.197737373293, 4786.688865223749, 4804.0, 4822.373091498082, 4828.37231159947, 4848.7090774553635], "value": 4567.647081179257, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [566.2412367427647, 568.1846550200463, 571.0834111251442, 589.7817807411253, 592.6621825559433, 27369.726302736966, 38027.429588556195, 38701.61298387015, 38841.84321292525, 42228.36657450141, 39482.802585987105, 39821.20357592846, 39940.60059399405, 39955.60044399555, 39963.0, 7545.924540754604, 7730.574818384986, 7730.961345193266, 7736.535807851547, 7740.148583655821, 7398.519096258726, 7597.772066838003, 9755.853662195073, 10002.449865257408, 10019.949900250489, 10034.84947725781, 10104.69685909424, 10118.291719579633, 10119.544620492059, 10139.746506337364, 10007.54966026527, 10072.899271007269, 10074.445905475195, 10078.748031299203, 10081.596736130547, 9750.26872984529, 9755.0, 9814.312998090136, 9820.558074886576, 9829.754256143582, 36665.81667091647, 36944.44583331252, 36963.81518092392, 36991.445128323096, 37073.07317317062, 3104.2394613319657, 3159.810411375302, 3184.1561986073707, 3294.4399452093203, 3652.2330310634798, 27396.31509212266, 28137.57793633097, 28237.576436353265, 28251.85874070637, 28378.148655540368, 3258.266889949766, 3313.966860331414, 3344.4481660525985, 3344.5986481622117, 3388.102152929476, 171006.0, 171207.99999999875, 171535.0, 171652.0, 171652.0, 50086.749566251936, 51659.741701292005, 51685.22472162921, 51708.741456292475, 51767.22349164765, 53094.73452632789, 53320.0, 53391.194792468865, 53396.0, 53492.46507534875, 12560.685982850318, 12663.683407914692, 12806.167599106027, 12806.167599106215, 12817.935910320388, 8850.026497085315, 9162.129597688208, 9188.8621670675, 9248.16766491019, 9261.44431334122, 18398.632027359377, 18422.35521756732, 18588.25646974133, 18695.71956420655, 18711.81288187128, 6708.329167083252, 7103.89344159838, 7104.928950710427, 7115.786526404217, 7124.903180671135, 743.8437928035173, 823.1069289820492, 888.8577827547691, 898.3127907151035, 926.6154545863391, 26407.33981650474, 27754.86122569374, 27842.2296926591, 28116.7665718445, 28220.43559128847, 897.2328658996595, 934.2666007184328, 984.6504490905769, 999.5302207962246, 1013.7364285285731, 13792.724145517233, 14698.230750062297, 14731.218565208135, 14771.113733176044, 14977.0, 365915.0, 370115.0, 370836.0, 370866.0, 370991.0, 48379.0498757731, 48773.75613121912, 48887.26669099966, 48939.51060489421, 48962.75518622455, 1118.7762447510531, 1129.9209055366046, 1147.3058799426394, 1155.8728539860776, 1155.8959693627614, 25908.96364145413, 27021.459570808867, 27163.72836271691, 27734.167974960783, 27770.72229277762, 42606.0, 42933.35599966066, 42948.57051429446, 42954.35568466413, 42963.0, 304435.0, 305350.0, 305922.0, 305931.0, 306222.0, 36748.81625591855, 37849.99775041626, 37972.430413544374, 38011.0, 38041.239175215786, 34273.485897711056, 40220.0, 42280.0, 43746.34380484232, 45051.0989780196, 34778.47879775613, 35193.29613407666, 35239.47140792839, 35644.82177589095, 36096.27807443889, 34935.0, 35001.64998349984, 35108.473372899934, 35211.47182792312, 35221.29557408786, 33737.49393759145, 34260.48609270813, 34384.65615343815, 34522.0, 35048.64951350454, 29942.700572993992, 32299.838500807346, 32393.0, 32450.675493244766, 32537.511937320487, 23938.521229574962, 24606.261812145665, 24775.25674229776, 24845.130420435198, 24975.750242497343], "value": 17535.76165667736, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.8542470463585, 322.1286420233269, 342.4418198336711, 346.4681713569671, 362.3948006828596, 15445.845541544595, 15481.535553933372, 16102.516924492269, 16704.248308826103, 16777.24502397392, 15355.0, 15693.529194124167, 16198.676026479463, 16303.83696163038, 16353.182340882939, 4742.834000809972, 4851.8059277628945, 4869.70781753096, 4943.901121977558, 4981.651284410074, 4918.508149185071, 4990.451050384473, 5002.77487513061, 5005.0990821652085, 5005.1491246488, 4730.219513780222, 4826.879328016793, 4960.3303554020285, 4962.379702537183, 4978.278149668296, 4922.80308787648, 4983.6760610560195, 4986.152354099811, 4986.376702912136, 4988.301637770715, 4287.356896465524, 4356.716813407118, 4412.227860124465, 4413.249747542894, 4413.955860441387, 20068.297609583595, 20146.79853201464, 20152.29466968649, 20226.494337641678, 20521.076551555216, 1908.0745838268567, 1968.7539057617798, 2165.783421657837, 2270.2962081754545, 2458.9180760465338, 12293.508259669603, 12653.36733163338, 12909.806352904714, 12945.676358091028, 13533.593992180251, 1662.5760431089996, 1663.9084850333227, 1704.2501299428336, 1723.6552689462033, 1767.849732772716, 66259.66870165618, 67789.0, 68043.31956680419, 68258.65870670664, 68347.3165268341, 8396.832063358699, 8432.53621050848, 8608.182222688834, 8665.35009874262, 8796.208341249318, 11781.52873885035, 11798.0, 11812.40937953116, 11868.703282418008, 11869.931706146488, 6459.547831651739, 6495.610263384117, 6639.236487803868, 6672.399484046459, 6748.392644662004, 6294.968525157345, 6306.3378345274, 6478.805635830932, 6538.248101468392, 6539.411452969161, 5481.616286859881, 5576.804811831566, 5645.1532270159805, 5748.780682817635, 5816.302043754777, 1563.1324614838873, 1585.7066442707905, 1615.4588212948559, 1625.8780591455688, 1695.9745603815952, 755.1240560949382, 827.6192951242375, 851.2466467176594, 889.4707648948865, 939.2204470289644, 16149.35402583907, 16431.671366572602, 17087.487375378758, 17115.0, 18000.729989050174, 854.6837670062117, 863.7020228021322, 864.786147462498, 868.639514601446, 883.2492381475694, 9345.0, 9865.556049977768, 9949.850752238723, 10008.64969726056, 10068.899311006942, 335385.0, 345043.0, 345684.0, 346105.0, 359984.0, 64463.67768161129, 71053.2894671057, 80817.59591202006, 81130.0, 81964.18035819684, 874.73320637205, 891.5319457284847, 923.9399439036441, 927.355487935878, 931.0968360690119, 15254.237288135771, 15301.540953771404, 15908.840911590736, 16019.759703604688, 16109.838901610834, 33303.50044749282, 34210.31579368449, 36432.0, 37760.43359349557, 39100.6089939097, 50038.74980625074, 53349.73325133349, 53918.0, 55563.16655250094, 55595.16607250975, 7015.824604384931, 7144.928550714426, 7166.390856777232, 7181.425485961215, 7238.710451581877, 9525.710834834752, 11353.262037967836, 11842.46708898119, 11854.94072529632, 12227.327496987751, 9763.951180244338, 9919.256055795988, 10131.392116473035, 10357.844632330673, 10492.947535262274, 9436.245100392089, 10123.746906327106, 10214.08073273409, 10558.894411055791, 10806.513706883366, 10203.591856325664, 10618.256722029384, 10740.818509963741, 10841.349519028883, 11015.173861960226, 7137.321954414322, 7203.603801790742, 7239.855202895807, 7376.4836461447185, 7571.962140189264, 10280.177585793268, 10408.0, 10515.57937682484, 10550.419726915094, 10972.067374273276], "value": 8023.6394810502225, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1148.0757989818194, 1289.6711338608654, 1346.4614154338267, 1358.9660258493536, 1594.8803839712025, 5727.942720572793, 6286.779962701301, 6397.872042559146, 6467.191601049869, 6589.703463344149, 6447.194100737408, 6511.934880651192, 6513.6743162841785, 6518.250401203863, 6520.054592084157, 1711.0503670462888, 1981.4154824326786, 2005.358285348689, 2006.2075480185317, 2008.6886532587473, 1767.1252729898717, 1910.6369789739974, 1978.2581531925528, 1979.475439008664, 1988.0159321135986, 1891.4420246027455, 1897.9145938432803, 1898.136347961681, 1900.5818719881672, 1905.6665083610383, 1817.2367605605612, 1882.491727233646, 1883.6421079994893, 1883.8116188381189, 1884.7738271407381, 1949.210569719266, 1956.1490751523133, 1974.1116497576147, 1984.087319832864, 1988.09541658545, 6387.648679322635, 6527.93472065278, 6536.477081833489, 6538.444232240266, 6541.378569035982, 3785.8107094645434, 3908.120672848615, 3910.354791459406, 3910.8240129194255, 3914.275858966072, 2461.9876900615563, 2577.342777591702, 2631.6184153297663, 2632.105084271357, 2667.9866600667065, 827.3422629009951, 829.0, 833.2542374574782, 839.5256679975794, 857.4640849469082, 8425.662973481054, 8444.662213511514, 8445.239928406472, 8445.83108337836, 8456.8731469028, 3360.19355354718, 3514.1741690702665, 3575.2313252650806, 3581.659742324475, 3584.802835844054, 7343.853122937511, 7345.485815992829, 7346.485745997729, 7350.595717235603, 7351.081114860643, 3847.153626202231, 3849.480320156743, 3854.903627409281, 3855.055511399669, 3860.0735823402742, 3181.2046988252937, 3275.3776782411264, 3530.3821831179953, 3531.2760884018717, 3535.1338921964284, 2987.551867219891, 3054.5570892220503, 3116.2053676312394, 3137.5607414961923, 3219.8873039443974, 1023.1047833145997, 1050.8003479338902, 1050.8949105089582, 1065.094669530908, 1067.594314160614, 796.0685997383076, 811.5374236685033, 812.118851046621, 815.6125840225845, 831.2020460358124, 4512.097580483916, 4715.811367545329, 4761.381020467386, 4828.95171048285, 4971.279164521125, 797.103258833812, 806.8305655812228, 867.3364875869964, 869.2567854484452, 871.1549796697194, 3197.2006998250436, 3231.0, 3251.7398608111293, 3269.4441944869427, 3387.0, 7393.55638661682, 7595.354394876497, 7679.0785105786545, 7703.768886933401, 7709.151993280847, 7623.0, 7625.504342217738, 7632.427567932427, 7635.503692259986, 7636.427267954815, 6089.512838972872, 6108.053251745987, 6139.539534534928, 6145.324014358506, 6146.5390095742105, 4371.497277812965, 4427.490838553607, 4483.14820184177, 4493.303537951622, 4539.228331183641, 6849.657517124224, 6935.930640693528, 7004.789856304319, 7071.080759501232, 7077.681504332214, 6949.583025018313, 7131.500794944512, 7132.714691412285, 7136.179339376038, 7152.034475345762, 5648.858778530405, 5712.828615141552, 5715.8571035724435, 5720.227769251264, 5725.16985037159, 5669.744861481161, 5713.457221563945, 6027.276726792813, 6085.269767627913, 6146.969265153645, 5580.86047848807, 5610.102383618601, 5611.915821262766, 5619.353774315842, 5706.600537962303, 5561.110222364402, 5628.521575666115, 5630.268065151504, 5675.148727690879, 5812.534997200299, 5553.8333849984565, 5645.012122878562, 5650.63270887391, 5651.745671444713, 5652.689102099424, 4208.821529971613, 4222.387753775624, 4304.213384157953, 4333.9349909750745, 4351.151525452507, 4115.094679170577, 4191.580841915784, 4231.936520952249, 4246.532881383107, 4314.892127696833], "value": 3556.3722339148903, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [257.3193902128869, 262.27219465981887, 264.19815858868327, 265.0365919881232, 268.13927293388224, 4664.160451118797, 4695.436547614283, 4785.688930219538, 4793.568578827909, 4828.299896515012, 4599.494055653875, 4708.835190768324, 4744.600342898844, 4744.69159504632, 4770.976145119278, 3521.38375784238, 3610.5667319921513, 3628.2380700053022, 3701.796401197933, 3703.7962912039825, 3563.8396272167815, 3627.4740162676394, 3656.542932133483, 3675.981620091896, 3681.6134305897895, 3634.945475817865, 3636.4908912752103, 3641.107928557494, 3643.744937854351, 3652.7443078984484, 3628.455731640249, 3629.4192929131214, 3632.4551317302353, 3636.4545318202217, 3639.4176931691068, 3094.984525077383, 3146.3078122812944, 3189.952150717741, 3196.96803031969, 3198.4722520784044, 4582.266837306015, 4586.72479651222, 4622.399088118556, 4639.860804175846, 4645.744484053375, 4146.274401979642, 4155.0, 4171.228322760299, 4183.184279065584, 4184.958150418518, 4784.330193772875, 4915.164422048225, 4937.950620493785, 4938.283948827436, 4940.209566469348, 3467.1505481157023, 3477.4783782432582, 3477.669621385964, 3479.9826000869834, 3483.0, 5158.148905430599, 5170.767315470813, 5176.844694659167, 5181.533661970403, 5189.832287735229, 3545.6099829018785, 3547.130952916552, 3552.2007548301426, 3567.6977903065713, 3570.946435803465, 3496.8426420811124, 3567.322208780375, 3601.585817631006, 3621.8189090545366, 3626.7279954003557, 4089.5297040840683, 4102.876913692594, 4138.8551400700835, 4139.296319625671, 4151.3772934059525, 4935.555799978019, 4942.555170034714, 4968.354113965233, 4969.527894849984, 4972.5276098770555, 1146.8623765148236, 1163.4996951311018, 1186.9465874035689, 1193.4868006757179, 1209.7520008398258, 806.0488623424299, 821.8356328734277, 831.4886344897965, 836.8159005019007, 840.8528507511161, 4221.915561688749, 4223.909164963363, 4237.894052648647, 4268.893277668021, 4278.7004909656025, 4932.432770231398, 4956.843772958702, 4967.205247160437, 4971.428285747185, 4974.228994505857, 4222.514410842731, 4241.603494025865, 4246.575342465729, 4255.170241802881, 4267.765272909957, 2552.859592722381, 2564.4101856573093, 2593.3775893785355, 2635.8665773717485, 2643.788496920242, 4560.95439045612, 4572.839950601713, 4581.244094724333, 4622.768861556909, 4639.652026098056, 4991.376077990251, 5034.546890779848, 5041.949580504222, 5074.06129865976, 5079.923801142986, 4428.756418397019, 4631.58315751584, 4648.790804413742, 4654.604358629555, 4665.743384113906, 4845.594777514481, 4873.269009648581, 4920.286558448934, 4962.727050012284, 4966.807966088185, 4807.254875494304, 4829.806807727792, 4834.178189707688, 4844.709317440965, 4873.51264873524, 4448.5773851484055, 4510.1656193604285, 4618.475902952085, 4622.260438329851, 4625.93061104077, 3446.741494387981, 3450.3789317923015, 3464.2032332564154, 3466.289410670857, 3468.5490886184634, 4945.109880221451, 4952.4304704959395, 4963.454020057863, 4975.955049439658, 4980.086649310194, 4619.284010978304, 4815.616269946399, 4844.2007068833245, 4943.627059011623, 4960.77676504551, 4986.700797952134, 5004.522761233213, 5008.84973450797, 5014.197728363444, 5049.722265275298, 4822.71063736177, 4920.6555541111775, 4929.556339929423, 4952.579030782424, 4966.478519755374, 4966.602671786176, 4989.301497790239, 5018.723970181675, 5030.170021946485, 5035.420926593342, 4902.0, 4902.63230257725, 4907.77914993819, 4923.384576927884, 4945.653804233669], "value": 3659.7483895255095, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [596.8328867916982, 645.3578689204243, 677.2042849651659, 703.5391818358974, 704.8177478683741, 14676.266186690662, 15217.619559511018, 15297.847021529782, 15331.080135191896, 15347.76978345323, 13728.244946527937, 13826.585202443917, 13839.0, 14465.059771114897, 15018.399264029453, 618.3383779356091, 734.4014628078099, 757.9848403031936, 758.5903612049486, 759.3431681595436, 622.5704264057808, 737.2627372627372, 750.5009168902673, 757.1028331427276, 757.5303311946601, 802.8554860125175, 804.1636697834246, 804.8671969125087, 805.6414895371562, 806.0206848678854, 639.9232092148925, 796.4026979765176, 796.8087658961865, 797.2386371015666, 797.3063434811722, 476.29270533258085, 561.6658088437373, 570.8373113662627, 575.1832397994857, 576.691470063514, 14113.576592702235, 14149.504767333197, 14164.362603682755, 14164.92917535416, 14178.929105354511, 11999.580014699532, 12085.93957030218, 12374.319412432313, 12435.44040518179, 12588.622341329774, 11643.708907277301, 11688.883111168949, 11720.531178752839, 11777.64667059981, 11876.703082422922, 3986.032023174156, 4038.434619153321, 4053.5541090480024, 4085.0604360997136, 4128.793560321972, 64596.67701661509, 65610.0, 66173.99999999952, 67122.0, 67370.66314668444, 10077.949610251902, 10110.140638045701, 10227.130693890953, 10282.845757313646, 10369.740756480998, 18502.907485462485, 18701.34545290908, 18709.90645046766, 18732.752069751445, 18961.905190474234, 6560.606363618198, 6603.273639899608, 6707.295733947963, 6746.224184218878, 6860.588364698035, 3583.6416358363954, 3667.1382225177063, 3743.644353786386, 3774.584795672474, 3778.792166430818, 2560.590305551103, 2659.4415172813538, 2670.092168662625, 2672.6926403463463, 2673.8262012969094, 453.5283305362452, 457.1953362082758, 461.92147334953137, 462.1565642702082, 464.13438936384074, 481.508860961817, 518.7613697699026, 527.5120513524975, 535.3067777228498, 567.8296511046669, 7140.214576396592, 7417.940278620236, 7446.553206807501, 7447.88828167578, 7566.810829729301, 473.9004808990082, 520.6121439527501, 524.7005247005217, 560.4087687489738, 568.1534513574826, 3553.7867727936405, 3581.6955558777277, 3630.854765809338, 3691.6123807000417, 3777.225668737902, 73831.63084184544, 73896.39225698727, 74185.72039503197, 74352.0, 75768.0, 62004.68997654983, 64104.0, 64300.0, 65486.0, 66788.33211667919, 58108.41891581115, 58562.12156817736, 58581.70709146427, 59089.70455147783, 59237.40762592319, 6905.447564194953, 7071.575705457689, 7117.4661900358715, 7179.856402871809, 8539.658413663385, 35552.46671299984, 36482.08794780152, 36593.45109823408, 36994.26011479701, 38439.807800961935, 49007.754961224964, 49667.50332496629, 50324.49675503198, 50704.23943640775, 53367.46632533625, 11151.832722509007, 11456.312621242752, 11533.250338727956, 11676.766464670829, 11689.766204676029, 11818.0, 12857.549985750455, 13068.80396794068, 13459.3943272551, 13507.527236546674, 12550.874491255336, 12905.612831615066, 12970.156939798882, 13220.867791321964, 13320.93339533296, 11740.354280514654, 12270.386480676108, 12318.75362492763, 12340.629781106582, 12557.87442125567, 12355.196912200678, 12411.255324680547, 12574.49702011946, 12723.936380318039, 13071.921556476284, 9554.378965367228, 9851.704448866545, 9985.201183905416, 10140.695779126638, 10244.846327304947, 10128.0, 10182.541785619813, 10192.0, 10213.182945364204, 10345.224108191765], "value": 6334.649933108895, "unit": "score"}], "name": "dromaeo_css", "value": 6128.741368434303, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 118.0, 116.0, 117.0, 118.0, 116.0, 116.0, 117.0, 115.0, 115.0], "value": 116.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 130.0, 128.0, 162.0, 134.0, 135.0, 135.0, 128.0, 152.0, 135.0], "value": 138.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 162.0, 163.0, 162.0, 162.0, 161.0, 160.0, 161.0, 163.0], "value": 161.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 77.0, 107.0, 76.0, 77.0, 77.0, 76.0, 92.0, 76.0], "value": 81.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 115.0, 83.0, 84.0, 84.0, 103.0, 85.0, 84.0, 117.0], "value": 92.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 119.0, 118.0, 117.0, 117.0], "value": 117.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 147.0, 146.0, 147.0, 147.0, 147.0, 146.0, 147.0, 147.0], "value": 146.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 63.0, 63.0, 64.0, 64.0, 64.0, 64.0, 64.0, 66.0], "value": 63.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 65.0, 66.0, 66.0, 65.0, 65.0, 66.0, 66.0, 66.0], "value": 65.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 87.0, 92.0, 86.0, 85.0, 84.0, 84.0, 84.0, 86.0, 85.0], "value": 85.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 147.0, 150.0, 148.0, 146.0, 143.0, 140.0, 188.0, 141.0], "value": 149.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 181.0, 178.0, 179.0, 178.0, 178.0, 177.0, 188.0, 185.0, 182.0], "value": 180.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 71.0, 71.0, 75.0, 71.0, 72.0, 72.0, 72.0, 72.0, 70.0], "value": 72.1, "unit": "score"}], "name": "kraken", "value": 1566.3999999999999, "alertThreshold": 2.0}]} 09:58:33 INFO - Return code: 0 09:58:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:58:34 INFO - # TBPL SUCCESS # 09:58:34 INFO - Running post-action listener: _resource_record_post_action 09:58:34 INFO - [mozharness: 2016-11-29 17:58:34.027503Z] Finished run-tests step (success) 09:58:34 INFO - Running post-run listener: _resource_record_post_run 09:58:34 INFO - Total resource usage - Wall time: 1203s; CPU: 13.0%; Read bytes: 4096; Write bytes: 402776064; Read time: 8; Write time: 274756 09:58:34 INFO - TinderboxPrint: CPU usage
13.1% 09:58:34 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 09:58:34 INFO - TinderboxPrint: I/O write bytes / time
402,776,064 / 274,756 09:58:34 INFO - TinderboxPrint: CPU idle
8,339.5 (86.9%) 09:58:34 INFO - TinderboxPrint: CPU user
1,209.8 (12.6%) 09:58:34 INFO - TinderboxPrint: Swap in / out
0 / 0 09:58:34 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 110583808; Read time: 0; Write time: 56436 09:58:34 INFO - run-tests - Wall time: 1188s; CPU: 13.0%; Read bytes: 0; Write bytes: 292192256; Read time: 0; Write time: 218320 09:58:34 INFO - Running post-run listener: _upload_blobber_files 09:58:34 INFO - Blob upload gear active. 09:58:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:58:34 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 09:58:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:58:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:58:35 INFO - (blobuploader) - INFO - Open directory for files ... 09:58:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:58:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:58:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:58:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:58:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:58:35 INFO - (blobuploader) - INFO - Done attempting. 09:58:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:58:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:58:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:58:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:58:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:58:36 INFO - (blobuploader) - INFO - Done attempting. 09:58:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:58:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:58:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:58:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:58:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:58:40 INFO - (blobuploader) - INFO - Done attempting. 09:58:40 INFO - (blobuploader) - INFO - Iteration through files over. 09:58:40 INFO - Return code: 0 09:58:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:58:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:58:40 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5aa126132613ef059520f91e605446f0a980b1a9b658948b9f0adeb5ccef088b0cab6cdff59ad8031200d7a5abc6979232648a23b07e2ec8024a3dd442c40c41", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a2db72aabba792600ed93e90b5c2c9e0b0ba14b8b28b006b180c7f77d24023a047a47c7e4a5a9a19c24a754597e3918dce8a2b2ce2b6c436b851e798d22bc6cd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c4d2c8e649602359d517dd62d85a66941138687cf474449fd991bab3e893dc619852454cc336c4262a3ca0f6954d0c28b9e1e712387df2a09677a087311cb2c3"} 09:58:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:58:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:58:40 INFO - Contents: 09:58:40 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5aa126132613ef059520f91e605446f0a980b1a9b658948b9f0adeb5ccef088b0cab6cdff59ad8031200d7a5abc6979232648a23b07e2ec8024a3dd442c40c41", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a2db72aabba792600ed93e90b5c2c9e0b0ba14b8b28b006b180c7f77d24023a047a47c7e4a5a9a19c24a754597e3918dce8a2b2ce2b6c436b851e798d22bc6cd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c4d2c8e649602359d517dd62d85a66941138687cf474449fd991bab3e893dc619852454cc336c4262a3ca0f6954d0c28b9e1e712387df2a09677a087311cb2c3"} 09:58:40 INFO - Running post-run listener: copy_logs_to_upload_dir 09:58:40 INFO - Copying logs to upload dir... 09:58:40 INFO - mkdir: /builds/slave/test/build/upload/logs 09:58:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.006164 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-11-29 09:58:40.561192) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-29 09:58:40.564725) ========= 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=337fe3f4b1febe37b85fccc40000019e-1480441072.182730-152408469 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5aa126132613ef059520f91e605446f0a980b1a9b658948b9f0adeb5ccef088b0cab6cdff59ad8031200d7a5abc6979232648a23b07e2ec8024a3dd442c40c41", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a2db72aabba792600ed93e90b5c2c9e0b0ba14b8b28b006b180c7f77d24023a047a47c7e4a5a9a19c24a754597e3918dce8a2b2ce2b6c436b851e798d22bc6cd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c4d2c8e649602359d517dd62d85a66941138687cf474449fd991bab3e893dc619852454cc336c4262a3ca0f6954d0c28b9e1e712387df2a09677a087311cb2c3"} build_url:https://queue.taskcluster.net/v1/task/b86Ic-esQi2JZLx9uVoKyA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011791 build_url: 'https://queue.taskcluster.net/v1/task/b86Ic-esQi2JZLx9uVoKyA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5aa126132613ef059520f91e605446f0a980b1a9b658948b9f0adeb5ccef088b0cab6cdff59ad8031200d7a5abc6979232648a23b07e2ec8024a3dd442c40c41", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a2db72aabba792600ed93e90b5c2c9e0b0ba14b8b28b006b180c7f77d24023a047a47c7e4a5a9a19c24a754597e3918dce8a2b2ce2b6c436b851e798d22bc6cd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c4d2c8e649602359d517dd62d85a66941138687cf474449fd991bab3e893dc619852454cc336c4262a3ca0f6954d0c28b9e1e712387df2a09677a087311cb2c3"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-29 09:58:40.591167) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 09:58:40.591473) ========= 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=337fe3f4b1febe37b85fccc40000019e-1480441072.182730-152408469 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004481 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 09:58:40.677003) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-29 09:58:40.677533) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-29 09:58:40.678078) ========= ========= Total master_lag: 0.18 =========