builder: cedar_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-009 starttime: 1479994262.44 results: success (0) buildid: 20161124041439 builduid: 1889b03501f745b8a7f8adfcf97619b1 revision: 84062375a88ecbdece745115683fb0eef10e1dd0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-24 05:31:02.438017) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-24 05:31:02.443308) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-24 05:31:02.443634) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-24 05:31:02.556806) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-24 05:31:02.557070) ========= 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=17051fcd5208b27b50bedfeb000001dd-1479993684.831020-144256127 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-11-24 05:31:02-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 18.8M=0.001s 2016-11-24 05:31:02 (18.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.089193 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-24 05:31:02.668027) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-24 05:31:02.668376) ========= 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=17051fcd5208b27b50bedfeb000001dd-1479993684.831020-144256127 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015429 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-24 05:31:02.719086) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-24 05:31:02.719450) ========= bash -c 'python archiver_client.py mozharness --repo projects/cedar --rev 84062375a88ecbdece745115683fb0eef10e1dd0 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/cedar --rev 84062375a88ecbdece745115683fb0eef10e1dd0 --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=17051fcd5208b27b50bedfeb000001dd-1479993684.831020-144256127 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-11-24 05:31:02,770 truncating revision to first 12 chars 2016-11-24 05:31:02,770 Setting DEBUG logging. 2016-11-24 05:31:02,770 attempt 1/10 2016-11-24 05:31:02,770 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/cedar/84062375a88e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-24 05:31:03,101 unpacking tar archive at: cedar-84062375a88e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.657137 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-24 05:31:03.391617) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-24 05:31:03.392200) ========= script_repo_revision: 84062375a88ecbdece745115683fb0eef10e1dd0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-24 05:31:03.392860) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-24 05:31:03.393361) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-24 05:31:03.404723) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2016-11-24 05:31:03.405044) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Cedar --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Cedar 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', 'Cedar', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Cedar'] 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=17051fcd5208b27b50bedfeb000001dd-1479993684.831020-144256127 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:31:03 INFO - MultiFileLogger online at 20161124 05:31:03 in /builds/slave/test 05:31:03 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Cedar --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Cedar 05:31:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:31:03 INFO - {'append_to_log': False, 05:31:03 INFO - 'base_work_dir': '/builds/slave/test', 05:31:03 INFO - 'blob_upload_branch': 'Cedar', 05:31:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:31:03 INFO - 'branch': 'Cedar', 05:31:03 INFO - 'buildbot_json_path': 'buildprops.json', 05:31:03 INFO - 'config_files': ('talos/linux_config.py',), 05:31:03 INFO - 'default_actions': ('clobber', 05:31:03 INFO - 'read-buildbot-config', 05:31:03 INFO - 'download-and-extract', 05:31:03 INFO - 'populate-webroot', 05:31:03 INFO - 'create-virtualenv', 05:31:03 INFO - 'install', 05:31:03 INFO - 'run-tests'), 05:31:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:31:03 INFO - 'download_minidump_stackwalk': True, 05:31:03 INFO - 'download_symbols': 'ondemand', 05:31:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:31:03 INFO - 'tooltool.py': '/tools/tooltool.py', 05:31:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:31:03 INFO - '/tools/misc-python/virtualenv.py')}, 05:31:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:31:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:31:03 INFO - 'installer_path': 'installer.exe', 05:31:03 INFO - 'log_level': 'info', 05:31:03 INFO - 'log_name': 'talos', 05:31:03 INFO - 'log_to_console': True, 05:31:03 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:31:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:31:03 INFO - 'opt_config_files': (), 05:31:03 INFO - 'pip_index': False, 05:31:03 INFO - 'sps_profile': False, 05:31:03 INFO - 'sps_profile_interval': 0, 05:31:03 INFO - 'suite': 'dromaeojs', 05:31:03 INFO - 'system_bits': '32', 05:31:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:31:03 INFO - 'title': 'talos-linux64-ix-009', 05:31:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:31:03 INFO - 'use_talos_json': True, 05:31:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:31:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:31:03 INFO - 'work_dir': 'build'} 05:31:03 INFO - [mozharness: 2016-11-24 13:31:03.555481Z] Running clobber step. 05:31:03 INFO - Running pre-action listener: _resource_record_pre_action 05:31:03 INFO - Running main action method: clobber 05:31:03 INFO - rmtree: /builds/slave/test/build 05:31:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:31:04 INFO - Running post-action listener: _resource_record_post_action 05:31:04 INFO - [mozharness: 2016-11-24 13:31:04.530877Z] Finished clobber step (success) 05:31:04 INFO - [mozharness: 2016-11-24 13:31:04.530976Z] Running read-buildbot-config step. 05:31:04 INFO - Running pre-action listener: _resource_record_pre_action 05:31:04 INFO - Running main action method: read_buildbot_config 05:31:04 INFO - Using buildbot properties: 05:31:04 INFO - { 05:31:04 INFO - "project": "", 05:31:04 INFO - "product": "firefox", 05:31:04 INFO - "script_repo_revision": "default", 05:31:04 INFO - "builddir": "cedar_ubuntu64_hw_test-dromaeojs", 05:31:04 INFO - "repository": "", 05:31:04 INFO - "buildername": "Ubuntu HW 12.04 x64 cedar talos dromaeojs", 05:31:04 INFO - "buildid": "20161124041439", 05:31:04 INFO - "pgo_build": "False", 05:31:04 INFO - "basedir": "/builds/slave/test", 05:31:04 INFO - "buildnumber": 7, 05:31:04 INFO - "slavename": "talos-linux64-ix-009", 05:31:04 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 05:31:04 INFO - "platform": "ubuntu64_hw", 05:31:04 INFO - "scheduler": "tests-cedar-linux64-talos", 05:31:04 INFO - "branch": "cedar", 05:31:04 INFO - "repo_path": "projects/cedar", 05:31:04 INFO - "revision": "84062375a88ecbdece745115683fb0eef10e1dd0", 05:31:04 INFO - "stage_platform": "linux64", 05:31:04 INFO - "builduid": "1889b03501f745b8a7f8adfcf97619b1", 05:31:04 INFO - "slavebuilddir": "test" 05:31:04 INFO - } 05:31:04 INFO - Found installer url https://queue.taskcluster.net/v1/task/Z6wAUFeBQG-DUotjTFAx0Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 05:31:04 INFO - Running post-action listener: _resource_record_post_action 05:31:04 INFO - [mozharness: 2016-11-24 13:31:04.533419Z] Finished read-buildbot-config step (success) 05:31:04 INFO - [mozharness: 2016-11-24 13:31:04.533507Z] Running download-and-extract step. 05:31:04 INFO - Running pre-action listener: _resource_record_pre_action 05:31:04 INFO - Running main action method: download_and_extract 05:31:04 INFO - mkdir: /builds/slave/test/build/tests 05:31:04 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')]} 05:31:04 INFO - trying https://queue.taskcluster.net/v1/task/Z6wAUFeBQG-DUotjTFAx0Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 05:31:04 INFO - Downloading https://queue.taskcluster.net/v1/task/Z6wAUFeBQG-DUotjTFAx0Q/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 05:31:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Z6wAUFeBQG-DUotjTFAx0Q/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 05:31:05 INFO - Downloaded 1361 bytes. 05:31:05 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 05:31:05 INFO - Using the following test package requirements: 05:31:05 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 05:31:05 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 05:31:05 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:31:05 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 05:31:05 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 05:31:05 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 05:31:05 INFO - u'jsshell-linux-x86_64.zip'], 05:31:05 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 05:31:05 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:31:05 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 05:31:05 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 05:31:05 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:31:05 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 05:31:05 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:31:05 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 05:31:05 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:31:05 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 05:31:05 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:31:05 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:31:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Z6wAUFeBQG-DUotjTFAx0Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 05:31:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Z6wAUFeBQG-DUotjTFAx0Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:31:05 INFO - Fetch https://queue.taskcluster.net/v1/task/Z6wAUFeBQG-DUotjTFAx0Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 05:31:05 INFO - Http code: 200 05:31:05 INFO - via: 1.1 e30ae5b3d9f6779a9b8bc992faad0b09.cloudfront.net (CloudFront) 05:31:05 INFO - x-amz-cf-id: CwxGP7cts3asyEwWMcmfY1nA90c-V5wD_xt1cklv26a8oXCc5WvThg== 05:31:05 INFO - x-amz-version-id: KdOhuPabzsplHybjginXh.2PV62ZGXCp 05:31:06 INFO - Expected file size: 20202762 05:31:06 INFO - Obtained file size: 20202762 05:31:10 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 05:31:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Z6wAUFeBQG-DUotjTFAx0Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 05:31:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Z6wAUFeBQG-DUotjTFAx0Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:31:10 INFO - Fetch https://queue.taskcluster.net/v1/task/Z6wAUFeBQG-DUotjTFAx0Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 05:31:10 INFO - Http code: 200 05:31:10 INFO - via: 1.1 0cf1d7257c633ae75623c5e75bf3805e.cloudfront.net (CloudFront) 05:31:10 INFO - x-amz-cf-id: 0ZodyRriQpsnnr-YnSM8NKUKpi5yQmV5NzG9A6ONW1tiQzE7TNn5Cg== 05:31:10 INFO - x-amz-version-id: KdOhuPabzsplHybjginXh.2PV62ZGXCp 05:31:11 INFO - Expected file size: 20202762 05:31:11 INFO - Obtained file size: 20202762 05:31:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Z6wAUFeBQG-DUotjTFAx0Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 05:31:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Z6wAUFeBQG-DUotjTFAx0Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 05:31:15 INFO - Fetch https://queue.taskcluster.net/v1/task/Z6wAUFeBQG-DUotjTFAx0Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 05:31:16 INFO - Http code: 200 05:31:16 INFO - via: 1.1 41b2ee7cbe95749816a7586d9ab4629d.cloudfront.net (CloudFront) 05:31:16 INFO - x-amz-cf-id: 3kUHDoN571qPWJdlYSBQIYyVuv0CIu4OyfWkn--YHPA6uDKYOWDs0w== 05:31:16 INFO - x-amz-version-id: fw6wkqoRIzk5NuKlmRYtCLZsXAjEklpn 05:31:17 INFO - Expected file size: 14409749 05:31:17 INFO - Obtained file size: 14409749 05:31:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:31:17 INFO - trying https://queue.taskcluster.net/v1/task/Z6wAUFeBQG-DUotjTFAx0Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 05:31:17 INFO - Downloading https://queue.taskcluster.net/v1/task/Z6wAUFeBQG-DUotjTFAx0Q/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 05:31:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Z6wAUFeBQG-DUotjTFAx0Q/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 05:31:19 INFO - Downloaded 55615216 bytes. 05:31:19 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Z6wAUFeBQG-DUotjTFAx0Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 05:31:19 INFO - mkdir: /builds/slave/test/properties 05:31:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:31:19 INFO - Writing to file /builds/slave/test/properties/build_url 05:31:19 INFO - Contents: 05:31:19 INFO - build_url:https://queue.taskcluster.net/v1/task/Z6wAUFeBQG-DUotjTFAx0Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 05:31:20 INFO - Running post-action listener: _resource_record_post_action 05:31:20 INFO - Running post-action listener: set_extra_try_arguments 05:31:20 INFO - [mozharness: 2016-11-24 13:31:20.281260Z] Finished download-and-extract step (success) 05:31:20 INFO - [mozharness: 2016-11-24 13:31:20.281491Z] Running populate-webroot step. 05:31:20 INFO - Running pre-action listener: _resource_record_pre_action 05:31:20 INFO - Running main action method: populate_webroot 05:31:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:31:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:31:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:31:20 INFO - '--tppagecycles', 05:31:20 INFO - '7'], 05:31:20 INFO - 'tests': ['tsvgm']}}, 05:31:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:31:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:31:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:31:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:31:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:31:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:31:20 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:31:20 INFO - 'talos_options': ['--disable-e10s'], 05:31:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:31:20 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:31:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:31:20 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:31:20 INFO - 'talos_options': ['--disable-e10s'], 05:31:20 INFO - 'tests': ['damp', 'tps']}, 05:31:20 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:31:20 INFO - 'tests': ['damp', 'tps']}, 05:31:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:31:20 INFO - 'tests': ['dromaeo_dom']}, 05:31:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:31:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:31:20 INFO - 'tests': ['basic_compositor_video']}, 05:31:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:31:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:31:20 INFO - 'tests': ['a11yr', 05:31:20 INFO - 'ts_paint', 05:31:20 INFO - 'tpaint', 05:31:20 INFO - 'sessionrestore', 05:31:20 INFO - 'sessionrestore_no_auto_restore', 05:31:20 INFO - 'tabpaint']}, 05:31:20 INFO - 'other-e10s': {'tests': ['a11yr', 05:31:20 INFO - 'ts_paint', 05:31:20 INFO - 'tpaint', 05:31:20 INFO - 'sessionrestore', 05:31:20 INFO - 'sessionrestore_no_auto_restore', 05:31:20 INFO - 'tabpaint']}, 05:31:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:31:20 INFO - 'ts_paint', 05:31:20 INFO - 'tpaint', 05:31:20 INFO - 'sessionrestore', 05:31:20 INFO - 'sessionrestore_no_auto_restore', 05:31:20 INFO - 'tabpaint']}, 05:31:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:31:20 INFO - 'ts_paint', 05:31:20 INFO - 'tpaint', 05:31:20 INFO - 'sessionrestore', 05:31:20 INFO - 'sessionrestore_no_auto_restore', 05:31:20 INFO - 'tabpaint']}, 05:31:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:31:20 INFO - 'tests': ['a11yr', 05:31:20 INFO - 'ts_paint', 05:31:20 INFO - 'tpaint', 05:31:20 INFO - 'sessionrestore', 05:31:20 INFO - 'sessionrestore_no_auto_restore', 05:31:20 INFO - 'tabpaint']}, 05:31:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:31:20 INFO - 'tests': ['a11yr', 05:31:20 INFO - 'ts_paint', 05:31:20 INFO - 'tpaint', 05:31:20 INFO - 'sessionrestore', 05:31:20 INFO - 'sessionrestore_no_auto_restore', 05:31:20 INFO - 'tabpaint']}, 05:31:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:31:20 INFO - 'tests': ['tsvgx', 05:31:20 INFO - 'tsvgr_opacity', 05:31:20 INFO - 'tart', 05:31:20 INFO - 'tscrollx', 05:31:20 INFO - 'cart', 05:31:20 INFO - 'tsvg_static']}, 05:31:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:31:20 INFO - 'tsvgr_opacity', 05:31:20 INFO - 'tart', 05:31:20 INFO - 'tscrollx', 05:31:20 INFO - 'cart', 05:31:20 INFO - 'tsvg_static']}, 05:31:20 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:31:20 INFO - 'talos_options': ['--disable-e10s'], 05:31:20 INFO - 'tests': ['tp5o']}, 05:31:20 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:31:20 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:31:20 INFO - 'talos_options': ['--disable-e10s', 05:31:20 INFO - '--xperf_path', 05:31:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:31:20 INFO - 'tests': ['tp5n']}, 05:31:20 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:31:20 INFO - 'talos_options': ['--xperf_path', 05:31:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:31:20 INFO - 'tests': ['tp5n']}}, 05:31:20 INFO - 'talos.zip': {'path': '', 05:31:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:31:20 INFO - Running post-action listener: _resource_record_post_action 05:31:20 INFO - [mozharness: 2016-11-24 13:31:20.301848Z] Finished populate-webroot step (success) 05:31:20 INFO - [mozharness: 2016-11-24 13:31:20.301937Z] Running create-virtualenv step. 05:31:20 INFO - Running pre-action listener: _resource_record_pre_action 05:31:20 INFO - Running main action method: create_virtualenv 05:31:20 INFO - Creating virtualenv /builds/slave/test/build/venv 05:31:20 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 05:31:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:31:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:31:20 INFO - Using real prefix '/usr' 05:31:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:31:20 INFO - Installing distribute.............................................................................................................................................................................................done. 05:31:22 INFO - Installing pip.................done. 05:31:22 INFO - Return code: 0 05:31:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:31:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:31:23 INFO - Reading from file tmpfile_stdout 05:31:23 INFO - Output received: 05:31:23 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) 05:31:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:31:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:31:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:31:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:23 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 0x7f6ff1c92e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18b9b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a55130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae3fc0>, '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 0x1ae4ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ae5350>, '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': '17051fcd5208b27b50bedfeb000001dd-1479993684.831020-144256127', '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 05:31:23 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 05:31:23 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 05:31:23 INFO - Using env: {'DISPLAY': ':0', 05:31:23 INFO - 'HOME': '/home/cltbld', 05:31:23 INFO - 'LANG': 'en_US.UTF-8', 05:31:23 INFO - 'LANGUAGE': 'en_US:en', 05:31:23 INFO - 'LOGNAME': 'cltbld', 05:31:23 INFO - 'MAIL': '/var/mail/cltbld', 05:31:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:23 INFO - 'MOZ_NO_REMOTE': '1', 05:31:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:31:23 INFO - 'NO_EM_RESTART': '1', 05:31:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:31:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:23 INFO - 'PWD': '/builds/slave/test', 05:31:23 INFO - 'SHELL': '/bin/bash', 05:31:23 INFO - 'SHLVL': '1', 05:31:23 INFO - 'TERM': 'linux', 05:31:23 INFO - 'TMOUT': '86400', 05:31:23 INFO - 'USER': 'cltbld', 05:31:23 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1479993684.831020-144256127', 05:31:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:23 INFO - '_': '/tools/buildbot/bin/python'} 05:31:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:23 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 05:31:23 INFO - Cleaning up... 05:31:23 INFO - Return code: 0 05:31:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:31:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:31:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:31:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:23 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 0x7f6ff1c92e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18b9b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a55130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae3fc0>, '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 0x1ae4ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ae5350>, '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': '17051fcd5208b27b50bedfeb000001dd-1479993684.831020-144256127', '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 05:31:23 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 05:31:23 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 05:31:23 INFO - Using env: {'DISPLAY': ':0', 05:31:23 INFO - 'HOME': '/home/cltbld', 05:31:23 INFO - 'LANG': 'en_US.UTF-8', 05:31:23 INFO - 'LANGUAGE': 'en_US:en', 05:31:23 INFO - 'LOGNAME': 'cltbld', 05:31:23 INFO - 'MAIL': '/var/mail/cltbld', 05:31:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:23 INFO - 'MOZ_NO_REMOTE': '1', 05:31:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:31:23 INFO - 'NO_EM_RESTART': '1', 05:31:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:31:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:23 INFO - 'PWD': '/builds/slave/test', 05:31:23 INFO - 'SHELL': '/bin/bash', 05:31:23 INFO - 'SHLVL': '1', 05:31:23 INFO - 'TERM': 'linux', 05:31:23 INFO - 'TMOUT': '86400', 05:31:23 INFO - 'USER': 'cltbld', 05:31:23 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1479993684.831020-144256127', 05:31:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:23 INFO - '_': '/tools/buildbot/bin/python'} 05:31:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:23 INFO - Downloading/unpacking psutil>=3.1.1 05:31:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:31:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:31:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:31:26 INFO - Installing collected packages: psutil 05:31:26 INFO - Running setup.py install for psutil 05:31:26 INFO - building 'psutil._psutil_linux' extension 05:31:26 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 05:31:26 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 05:31:26 INFO - building 'psutil._psutil_posix' extension 05:31:26 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 05:31:26 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 05:31:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:31:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:31:27 INFO - Successfully installed psutil 05:31:27 INFO - Cleaning up... 05:31:27 INFO - Return code: 0 05:31:27 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:31:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:31:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:31:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:27 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 0x7f6ff1c92e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18b9b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a55130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae3fc0>, '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 0x1ae4ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ae5350>, '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': '17051fcd5208b27b50bedfeb000001dd-1479993684.831020-144256127', '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 05:31:27 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 05:31:27 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 05:31:27 INFO - Using env: {'DISPLAY': ':0', 05:31:27 INFO - 'HOME': '/home/cltbld', 05:31:27 INFO - 'LANG': 'en_US.UTF-8', 05:31:27 INFO - 'LANGUAGE': 'en_US:en', 05:31:27 INFO - 'LOGNAME': 'cltbld', 05:31:27 INFO - 'MAIL': '/var/mail/cltbld', 05:31:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:27 INFO - 'MOZ_NO_REMOTE': '1', 05:31:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:31:27 INFO - 'NO_EM_RESTART': '1', 05:31:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:31:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:27 INFO - 'PWD': '/builds/slave/test', 05:31:27 INFO - 'SHELL': '/bin/bash', 05:31:27 INFO - 'SHLVL': '1', 05:31:27 INFO - 'TERM': 'linux', 05:31:27 INFO - 'TMOUT': '86400', 05:31:27 INFO - 'USER': 'cltbld', 05:31:27 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1479993684.831020-144256127', 05:31:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:27 INFO - '_': '/tools/buildbot/bin/python'} 05:31:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:27 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:31: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 05:31: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 05:31: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 05:31: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 05:31: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 05:31: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 05:31:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:31:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:31:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:31:29 INFO - Installing collected packages: mozsystemmonitor 05:31:29 INFO - Running setup.py install for mozsystemmonitor 05:31:29 INFO - Successfully installed mozsystemmonitor 05:31:29 INFO - Cleaning up... 05:31:29 INFO - Return code: 0 05:31:29 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:31: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')]} 05:31:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31: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')]} 05:31:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31: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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6ff1c92e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18b9b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a55130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae3fc0>, '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 0x1ae4ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ae5350>, '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': '17051fcd5208b27b50bedfeb000001dd-1479993684.831020-144256127', '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 05:31: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==2.5.1'] in /builds/slave/test/build 05:31: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==2.5.1 05:31:29 INFO - Using env: {'DISPLAY': ':0', 05:31:29 INFO - 'HOME': '/home/cltbld', 05:31:29 INFO - 'LANG': 'en_US.UTF-8', 05:31:29 INFO - 'LANGUAGE': 'en_US:en', 05:31:29 INFO - 'LOGNAME': 'cltbld', 05:31:29 INFO - 'MAIL': '/var/mail/cltbld', 05:31:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:29 INFO - 'MOZ_NO_REMOTE': '1', 05:31:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:31:29 INFO - 'NO_EM_RESTART': '1', 05:31:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:31:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:29 INFO - 'PWD': '/builds/slave/test', 05:31:29 INFO - 'SHELL': '/bin/bash', 05:31:29 INFO - 'SHLVL': '1', 05:31:29 INFO - 'TERM': 'linux', 05:31:29 INFO - 'TMOUT': '86400', 05:31:29 INFO - 'USER': 'cltbld', 05:31:29 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1479993684.831020-144256127', 05:31:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:29 INFO - '_': '/tools/buildbot/bin/python'} 05:31:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:29 INFO - Downloading/unpacking jsonschema==2.5.1 05:31:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:31:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:31:31 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 05:31:31 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 05:31:31 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 05:31:31 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 05:31:31 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 05:31:31 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 05:31:31 INFO - Downloading functools32-3.2.3-2.tar.gz 05:31:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:31:31 INFO - warning: no files found matching '*.txt' 05:31:31 INFO - no previously-included directories found matching 'build' 05:31:31 INFO - no previously-included directories found matching 'dist' 05:31:31 INFO - no previously-included directories found matching '.git*' 05:31:31 INFO - Installing collected packages: jsonschema, functools32 05:31:31 INFO - Running setup.py install for functools32 05:31:32 INFO - warning: no files found matching '*.txt' 05:31:32 INFO - no previously-included directories found matching 'build' 05:31:32 INFO - no previously-included directories found matching 'dist' 05:31:32 INFO - no previously-included directories found matching '.git*' 05:31:32 INFO - Successfully installed jsonschema functools32 05:31:32 INFO - Cleaning up... 05:31:32 INFO - Return code: 0 05:31:32 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:31:32 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')]} 05:31:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:32 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')]} 05:31:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:32 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 0x7f6ff1c92e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18b9b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a55130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae3fc0>, '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 0x1ae4ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ae5350>, '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': '17051fcd5208b27b50bedfeb000001dd-1479993684.831020-144256127', '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 05:31:32 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 05:31:32 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 05:31:32 INFO - Using env: {'DISPLAY': ':0', 05:31:32 INFO - 'HOME': '/home/cltbld', 05:31:32 INFO - 'LANG': 'en_US.UTF-8', 05:31:32 INFO - 'LANGUAGE': 'en_US:en', 05:31:32 INFO - 'LOGNAME': 'cltbld', 05:31:32 INFO - 'MAIL': '/var/mail/cltbld', 05:31:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:32 INFO - 'MOZ_NO_REMOTE': '1', 05:31:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:31:32 INFO - 'NO_EM_RESTART': '1', 05:31:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:31:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:32 INFO - 'PWD': '/builds/slave/test', 05:31:32 INFO - 'SHELL': '/bin/bash', 05:31:32 INFO - 'SHLVL': '1', 05:31:32 INFO - 'TERM': 'linux', 05:31:32 INFO - 'TMOUT': '86400', 05:31:32 INFO - 'USER': 'cltbld', 05:31:32 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1479993684.831020-144256127', 05:31:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:32 INFO - '_': '/tools/buildbot/bin/python'} 05:31:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:31:32 INFO - Cleaning up... 05:31:32 INFO - Return code: 0 05:31:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:31:32 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')]} 05:31:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:32 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')]} 05:31:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:32 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 0x7f6ff1c92e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18b9b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a55130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae3fc0>, '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 0x1ae4ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ae5350>, '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': '17051fcd5208b27b50bedfeb000001dd-1479993684.831020-144256127', '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 05:31:32 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 05:31:32 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 05:31:32 INFO - Using env: {'DISPLAY': ':0', 05:31:32 INFO - 'HOME': '/home/cltbld', 05:31:32 INFO - 'LANG': 'en_US.UTF-8', 05:31:32 INFO - 'LANGUAGE': 'en_US:en', 05:31:32 INFO - 'LOGNAME': 'cltbld', 05:31:32 INFO - 'MAIL': '/var/mail/cltbld', 05:31:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:32 INFO - 'MOZ_NO_REMOTE': '1', 05:31:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:31:32 INFO - 'NO_EM_RESTART': '1', 05:31:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:31:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:32 INFO - 'PWD': '/builds/slave/test', 05:31:32 INFO - 'SHELL': '/bin/bash', 05:31:32 INFO - 'SHLVL': '1', 05:31:32 INFO - 'TERM': 'linux', 05:31:32 INFO - 'TMOUT': '86400', 05:31:32 INFO - 'USER': 'cltbld', 05:31:32 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1479993684.831020-144256127', 05:31:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:32 INFO - '_': '/tools/buildbot/bin/python'} 05:31:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:32 INFO - Downloading/unpacking blobuploader==1.2.4 05:31:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:34 INFO - Downloading blobuploader-1.2.4.tar.gz 05:31:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:31:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:31:34 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 05:31:34 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 05:31:34 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 05:31:34 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 05:31:34 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 05:31:34 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 05:31:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:31:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:31:35 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 05:31:35 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 05:31:35 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 05:31:35 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 05:31:35 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 05:31:35 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 05:31:35 INFO - Downloading docopt-0.6.1.tar.gz 05:31:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:31:35 INFO - Installing collected packages: blobuploader, requests, docopt 05:31:35 INFO - Running setup.py install for blobuploader 05:31:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:31:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:31:35 INFO - Running setup.py install for requests 05:31:35 INFO - Running setup.py install for docopt 05:31:35 INFO - Successfully installed blobuploader requests docopt 05:31:35 INFO - Cleaning up... 05:31:35 INFO - Return code: 0 05:31:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:31:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:31:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:31:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:35 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 0x7f6ff1c92e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18b9b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a55130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae3fc0>, '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 0x1ae4ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ae5350>, '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': '17051fcd5208b27b50bedfeb000001dd-1479993684.831020-144256127', '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 05:31:35 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 05:31:35 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 05:31:35 INFO - Using env: {'DISPLAY': ':0', 05:31:35 INFO - 'HOME': '/home/cltbld', 05:31:35 INFO - 'LANG': 'en_US.UTF-8', 05:31:35 INFO - 'LANGUAGE': 'en_US:en', 05:31:35 INFO - 'LOGNAME': 'cltbld', 05:31:35 INFO - 'MAIL': '/var/mail/cltbld', 05:31:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:35 INFO - 'MOZ_NO_REMOTE': '1', 05:31:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:31:35 INFO - 'NO_EM_RESTART': '1', 05:31:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:31:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:35 INFO - 'PWD': '/builds/slave/test', 05:31:35 INFO - 'SHELL': '/bin/bash', 05:31:35 INFO - 'SHLVL': '1', 05:31:35 INFO - 'TERM': 'linux', 05:31:35 INFO - 'TMOUT': '86400', 05:31:35 INFO - 'USER': 'cltbld', 05:31:35 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1479993684.831020-144256127', 05:31:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:35 INFO - '_': '/tools/buildbot/bin/python'} 05:31:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:31:36 INFO - Running setup.py (path:/tmp/pip-fkNHzw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:31:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:31:36 INFO - Running setup.py (path:/tmp/pip-KKhshf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:31:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:31:36 INFO - Running setup.py (path:/tmp/pip-geoUDa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:31:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:31:36 INFO - Running setup.py (path:/tmp/pip-r_SHJa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:31:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:31:36 INFO - Running setup.py (path:/tmp/pip-rd5wJS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:31:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:31:36 INFO - Running setup.py (path:/tmp/pip-dc1VT9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:31:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:31:36 INFO - Running setup.py (path:/tmp/pip-nLU8QS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:31:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:31:36 INFO - Running setup.py (path:/tmp/pip-Cds8v8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:31:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:31:36 INFO - Running setup.py (path:/tmp/pip-606Y1F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:31:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:31:36 INFO - Running setup.py (path:/tmp/pip-1uOiFw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:31:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:31:36 INFO - Running setup.py (path:/tmp/pip-QzrvwU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:31:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:31:36 INFO - Running setup.py (path:/tmp/pip-i7LgX0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:31:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:31:37 INFO - Running setup.py (path:/tmp/pip-fQr30J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:31:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:31:37 INFO - Running setup.py (path:/tmp/pip-DsbyKe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:31:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:31:37 INFO - Running setup.py (path:/tmp/pip-14Fhzq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:31:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:31:37 INFO - Running setup.py (path:/tmp/pip-uEZqJA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:31:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:31:37 INFO - Running setup.py (path:/tmp/pip-Azragx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:31:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:31:37 INFO - Running setup.py install for manifestparser 05:31:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:31:37 INFO - Running setup.py install for mozcrash 05:31:37 INFO - Running setup.py install for mozdebug 05:31:37 INFO - Running setup.py install for mozdevice 05:31:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:31:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:31:37 INFO - Running setup.py install for mozfile 05:31:37 INFO - Running setup.py install for mozhttpd 05:31:38 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:31:38 INFO - Running setup.py install for mozinfo 05:31:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:31:38 INFO - Running setup.py install for mozInstall 05:31:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:31:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:31:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:31:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:31:38 INFO - Running setup.py install for mozleak 05:31:38 INFO - Running setup.py install for mozlog 05:31:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:31:38 INFO - Running setup.py install for moznetwork 05:31:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:31:38 INFO - Running setup.py install for mozprocess 05:31:38 INFO - Running setup.py install for mozprofile 05:31:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:31:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:31:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:31:38 INFO - Running setup.py install for mozrunner 05:31:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:31:38 INFO - Running setup.py install for mozscreenshot 05:31:38 INFO - Running setup.py install for moztest 05:31:39 INFO - Running setup.py install for mozversion 05:31:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:31:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:31:39 INFO - Cleaning up... 05:31:39 INFO - Return code: 0 05:31:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:31:39 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')]} 05:31:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:39 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')]} 05:31:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:39 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 0x7f6ff1c92e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18b9b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a55130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae3fc0>, '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 0x1ae4ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ae5350>, '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': '17051fcd5208b27b50bedfeb000001dd-1479993684.831020-144256127', '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 05:31:39 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 05:31:39 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 05:31:39 INFO - Using env: {'DISPLAY': ':0', 05:31:39 INFO - 'HOME': '/home/cltbld', 05:31:39 INFO - 'LANG': 'en_US.UTF-8', 05:31:39 INFO - 'LANGUAGE': 'en_US:en', 05:31:39 INFO - 'LOGNAME': 'cltbld', 05:31:39 INFO - 'MAIL': '/var/mail/cltbld', 05:31:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:39 INFO - 'MOZ_NO_REMOTE': '1', 05:31:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:31:39 INFO - 'NO_EM_RESTART': '1', 05:31:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:31:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:39 INFO - 'PWD': '/builds/slave/test', 05:31:39 INFO - 'SHELL': '/bin/bash', 05:31:39 INFO - 'SHLVL': '1', 05:31:39 INFO - 'TERM': 'linux', 05:31:39 INFO - 'TMOUT': '86400', 05:31:39 INFO - 'USER': 'cltbld', 05:31:39 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1479993684.831020-144256127', 05:31:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:39 INFO - '_': '/tools/buildbot/bin/python'} 05:31:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:31:39 INFO - Running setup.py (path:/tmp/pip-gzZnJ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:31:39 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)) 05:31:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:31:39 INFO - Running setup.py (path:/tmp/pip-VM5Fqn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:31:39 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)) 05:31:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:31:39 INFO - Running setup.py (path:/tmp/pip-0impvN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:31:39 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)) 05:31:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:31:39 INFO - Running setup.py (path:/tmp/pip-Gn_v8J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:31:39 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)) 05:31:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:31:39 INFO - Running setup.py (path:/tmp/pip-zGxyk4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:31:39 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)) 05:31:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:31:39 INFO - Running setup.py (path:/tmp/pip-7udT9D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:31:39 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)) 05:31:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:31:39 INFO - Running setup.py (path:/tmp/pip-EcM17n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:31:39 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)) 05:31:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:31:39 INFO - Running setup.py (path:/tmp/pip-e6Zie3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:31:40 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)) 05:31:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:31:40 INFO - Running setup.py (path:/tmp/pip-9miE6x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:31:40 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)) 05:31:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:31:40 INFO - Running setup.py (path:/tmp/pip-6yiJcj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:31:40 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)) 05:31:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:31:40 INFO - Running setup.py (path:/tmp/pip-vvvMcf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:31:40 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)) 05:31:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:31:40 INFO - Running setup.py (path:/tmp/pip-mHjy5_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:31:40 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)) 05:31:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:31:40 INFO - Running setup.py (path:/tmp/pip-yw14UN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:31:40 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)) 05:31:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:31:40 INFO - Running setup.py (path:/tmp/pip-Vpd8Z8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:31:40 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)) 05:31:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:31:40 INFO - Running setup.py (path:/tmp/pip-LEuG5B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:31:40 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)) 05:31:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:31:40 INFO - Running setup.py (path:/tmp/pip-806Mya-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:31:40 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)) 05:31:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:31:40 INFO - Running setup.py (path:/tmp/pip-CAenvp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:31:40 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)) 05:31:40 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)) 05:31:40 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)) 05:31:40 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)) 05:31:40 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)) 05:31:40 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)) 05:31:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:31:40 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 05:31:40 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 05:31:40 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 05:31:40 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 05:31:40 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 05:31:40 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 05:31:42 INFO - Downloading blessings-1.6.tar.gz 05:31:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:31:42 INFO - Installing collected packages: blessings 05:31:42 INFO - Running setup.py install for blessings 05:31:42 INFO - Successfully installed blessings 05:31:42 INFO - Cleaning up... 05:31:42 INFO - Return code: 0 05:31:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:31:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:31:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:31:43 INFO - Reading from file tmpfile_stdout 05:31:43 INFO - Current package versions: 05:31:43 INFO - argparse == 1.2.1 05:31:43 INFO - blessings == 1.6 05:31:43 INFO - blobuploader == 1.2.4 05:31:43 INFO - docopt == 0.6.1 05:31:43 INFO - functools32 == 3.2.3-2 05:31:43 INFO - jsonschema == 2.5.1 05:31:43 INFO - manifestparser == 1.1 05:31:43 INFO - mozInstall == 1.12 05:31:43 INFO - mozcrash == 1.0 05:31:43 INFO - mozdebug == 0.1 05:31:43 INFO - mozdevice == 0.49 05:31:43 INFO - mozfile == 1.2 05:31:43 INFO - mozhttpd == 0.7 05:31:43 INFO - mozinfo == 0.9 05:31:43 INFO - mozleak == 0.1 05:31:43 INFO - mozlog == 3.4 05:31:43 INFO - moznetwork == 0.27 05:31:43 INFO - mozprocess == 0.23 05:31:43 INFO - mozprofile == 0.28 05:31:43 INFO - mozrunner == 6.13 05:31:43 INFO - mozscreenshot == 0.1 05:31:43 INFO - mozsystemmonitor == 0.3 05:31:43 INFO - moztest == 0.7 05:31:43 INFO - mozversion == 1.4 05:31:43 INFO - psutil == 3.1.1 05:31:43 INFO - requests == 1.2.3 05:31:43 INFO - wsgiref == 0.1.2 05:31:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:31:43 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')]} 05:31:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:43 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')]} 05:31:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:43 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 0x7f6ff1c92e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18b9b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a55130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae3fc0>, '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 0x1ae4ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ae5350>, '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': '17051fcd5208b27b50bedfeb000001dd-1479993684.831020-144256127', '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 05:31:43 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 05:31:43 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 05:31:43 INFO - Using env: {'DISPLAY': ':0', 05:31:43 INFO - 'HOME': '/home/cltbld', 05:31:43 INFO - 'LANG': 'en_US.UTF-8', 05:31:43 INFO - 'LANGUAGE': 'en_US:en', 05:31:43 INFO - 'LOGNAME': 'cltbld', 05:31:43 INFO - 'MAIL': '/var/mail/cltbld', 05:31:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:43 INFO - 'MOZ_NO_REMOTE': '1', 05:31:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:31:43 INFO - 'NO_EM_RESTART': '1', 05:31:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:31:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:43 INFO - 'PWD': '/builds/slave/test', 05:31:43 INFO - 'SHELL': '/bin/bash', 05:31:43 INFO - 'SHLVL': '1', 05:31:43 INFO - 'TERM': 'linux', 05:31:43 INFO - 'TMOUT': '86400', 05:31:43 INFO - 'USER': 'cltbld', 05:31:43 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1479993684.831020-144256127', 05:31:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:43 INFO - '_': '/tools/buildbot/bin/python'} 05:31:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:43 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)) 05:31:43 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)) 05:31:43 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)) 05:31:43 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)) 05:31:43 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)) 05:31:43 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)) 05:31:43 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)) 05:31:43 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)) 05:31:43 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)) 05:31:43 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)) 05:31:43 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)) 05:31:43 INFO - Cleaning up... 05:31:43 INFO - Return code: 0 05:31:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:31:43 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')]} 05:31:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:43 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')]} 05:31:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:43 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 0x7f6ff1c92e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18b9b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a55130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae3fc0>, '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 0x1ae4ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ae5350>, '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': '17051fcd5208b27b50bedfeb000001dd-1479993684.831020-144256127', '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 05:31:43 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 05:31:43 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 05:31:43 INFO - Using env: {'DISPLAY': ':0', 05:31:43 INFO - 'HOME': '/home/cltbld', 05:31:43 INFO - 'LANG': 'en_US.UTF-8', 05:31:43 INFO - 'LANGUAGE': 'en_US:en', 05:31:43 INFO - 'LOGNAME': 'cltbld', 05:31:43 INFO - 'MAIL': '/var/mail/cltbld', 05:31:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:43 INFO - 'MOZ_NO_REMOTE': '1', 05:31:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:31:43 INFO - 'NO_EM_RESTART': '1', 05:31:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:31:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:43 INFO - 'PWD': '/builds/slave/test', 05:31:43 INFO - 'SHELL': '/bin/bash', 05:31:43 INFO - 'SHLVL': '1', 05:31:43 INFO - 'TERM': 'linux', 05:31:43 INFO - 'TMOUT': '86400', 05:31:43 INFO - 'USER': 'cltbld', 05:31:43 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1479993684.831020-144256127', 05:31:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:43 INFO - '_': '/tools/buildbot/bin/python'} 05:31:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:43 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:31:43 INFO - Cleaning up... 05:31:43 INFO - Return code: 0 05:31:43 INFO - Running post-action listener: _resource_record_post_action 05:31:43 INFO - Running post-action listener: _start_resource_monitoring 05:31:43 INFO - Starting resource monitoring. 05:31:43 INFO - [mozharness: 2016-11-24 13:31:43.613012Z] Finished create-virtualenv step (success) 05:31:43 INFO - [mozharness: 2016-11-24 13:31:43.613577Z] Running install step. 05:31:43 INFO - Running pre-action listener: _resource_record_pre_action 05:31:43 INFO - Running main action method: install 05:31:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:31:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:31:43 INFO - Reading from file tmpfile_stdout 05:31:43 INFO - Detecting whether we're running mozinstall >=1.0... 05:31:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:31:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:31:43 INFO - Reading from file tmpfile_stdout 05:31:43 INFO - Output received: 05:31:43 INFO - Usage: mozinstall [options] installer 05:31:43 INFO - Options: 05:31:43 INFO - -h, --help show this help message and exit 05:31:43 INFO - -d DEST, --destination=DEST 05:31:43 INFO - Directory to install application into. [default: 05:31:43 INFO - "/builds/slave/test"] 05:31:43 INFO - --app=APP Application being installed. [default: firefox] 05:31:43 INFO - mkdir: /builds/slave/test/build/application 05:31:43 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'] 05:31:43 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 05:32:00 INFO - Reading from file tmpfile_stdout 05:32:00 INFO - Output received: 05:32:00 INFO - /builds/slave/test/build/application/firefox/firefox 05:32:00 INFO - Running post-action listener: _resource_record_post_action 05:32:00 INFO - [mozharness: 2016-11-24 13:32:00.719374Z] Finished install step (success) 05:32:00 INFO - [mozharness: 2016-11-24 13:32:00.719616Z] Running run-tests step. 05:32:00 INFO - Running pre-action listener: _resource_record_pre_action 05:32:00 INFO - Running main action method: run_tests 05:32:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:32:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:32:00 INFO - Python 2.7.3 05:32:00 INFO - Return code: 0 05:32:00 INFO - grabbing minidump binary from tooltool 05:32:00 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')]} 05:32:00 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 0x1ae3fc0>, '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 0x1ae4ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ae5350>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:32:00 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 05:32:00 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 05:32:00 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 05:32:00 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:32:00 INFO - Return code: 0 05:32:00 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:32:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:32:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:32:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:32:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:32:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Cedar', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Z6wAUFeBQG-DUotjTFAx0Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-009', '--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 05:32:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Cedar --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Z6wAUFeBQG-DUotjTFAx0Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-009 --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 05:32:00 INFO - Using env: {'DISPLAY': ':0', 05:32:00 INFO - 'HOME': '/home/cltbld', 05:32:00 INFO - 'LANG': 'en_US.UTF-8', 05:32:00 INFO - 'LANGUAGE': 'en_US:en', 05:32:00 INFO - 'LOGNAME': 'cltbld', 05:32:00 INFO - 'MAIL': '/var/mail/cltbld', 05:32:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:32:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:32:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:00 INFO - 'MOZ_NO_REMOTE': '1', 05:32:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:32:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:32:00 INFO - 'NO_EM_RESTART': '1', 05:32:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:32:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:00 INFO - 'PWD': '/builds/slave/test', 05:32:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:32:00 INFO - 'SHELL': '/bin/bash', 05:32:00 INFO - 'SHLVL': '1', 05:32:00 INFO - 'TERM': 'linux', 05:32:00 INFO - 'TMOUT': '86400', 05:32:00 INFO - 'USER': 'cltbld', 05:32:00 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1479993684.831020-144256127', 05:32:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:00 INFO - '_': '/tools/buildbot/bin/python'} 05:32:00 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Cedar', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Z6wAUFeBQG-DUotjTFAx0Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-009', '--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 05:32:00 INFO - mozversion application_buildid: 20161124041439 05:32:00 INFO - mozversion application_changeset: 84062375a88ecbdece745115683fb0eef10e1dd0 05:32:00 INFO - mozversion application_display_name: Nightly 05:32:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:32:00 INFO - mozversion application_name: Firefox 05:32:00 INFO - mozversion application_remotingname: firefox 05:32:00 INFO - mozversion application_repository: https://hg.mozilla.org/projects/cedar 05:32:00 INFO - mozversion application_vendor: Mozilla 05:32:00 INFO - mozversion application_version: 53.0a1 05:32:00 INFO - mozversion platform_buildid: 20161124041439 05:32:00 INFO - mozversion platform_changeset: 84062375a88ecbdece745115683fb0eef10e1dd0 05:32:00 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/cedar 05:32:00 INFO - mozversion platform_version: 53.0a1 05:32:00 INFO - using testdate: 1479994320 05:32:00 INFO - actual date: 1479994320 05:32:00 INFO - starting webserver on 'localhost:56676' 05:32:00 INFO - SUITE-START | Running 2 tests 05:32:00 INFO - TEST-START | dromaeo_css 05:32:00 INFO - operating with platform_type : linux_ 05:32:00 INFO - Initialising browser for dromaeo_css test... 05:32:01 INFO - TEST-INFO | started process 19724 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWiGmqy/profile http://localhost:56676/getInfo.html) 05:32:05 INFO - PROCESS | 19724 | __metrics Screen width/height:1600/1200 05:32:05 INFO - PROCESS | 19724 | colorDepth:24 05:32:05 INFO - PROCESS | 19724 | Browser inner width/height: 1024/697 05:32:05 INFO - PROCESS | 19724 | __metrics 05:32:06 INFO - PROCESS | 19724 | 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" 05:32:06 INFO - PROCESS | 19724 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:32:06 INFO - PROCESS | 19724 | console.error: PushService: 05:32:06 INFO - PROCESS | 19724 | stateChangeProcessEnqueue: Error transitioning state 05:32:06 INFO - PROCESS | 19724 | UnknownError 05:32:07 INFO - TEST-INFO | 19724: exit 0 05:32:07 INFO - Browser initialized. 05:32:07 INFO - Running cycle 1/1 for dromaeo_css test... 05:32:07 INFO - TEST-INFO | started process 19808 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWiGmqy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:32:19 INFO - PROCESS | 19808 | RSS: Main: 174075904 05:32:19 INFO - PROCESS | 19808 | 05:33:11 INFO - PROCESS | 19808 | 1479994391447 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] 05:35:24 INFO - PROCESS | 19808 | Cycle 1(1): loaded http://localhost:56676/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56676/tests/dromaeo/cssquery-ext.html) 05:35:26 INFO - PROCESS | 19808 | RSS: Main: 299307008 05:35:26 INFO - PROCESS | 19808 | 05:38:28 INFO - PROCESS | 19808 | Cycle 1(1): loaded http://localhost:56676/tests/dromaeo/cssquery-ext.html (next: http://localhost:56676/tests/dromaeo/cssquery-jquery.html) 05:38:29 INFO - PROCESS | 19808 | RSS: Main: 276017152 05:38:29 INFO - PROCESS | 19808 | 05:41:31 INFO - PROCESS | 19808 | Cycle 1(1): loaded http://localhost:56676/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56676/tests/dromaeo/cssquery-mootools.html) 05:41:32 INFO - PROCESS | 19808 | RSS: Main: 219754496 05:41:32 INFO - PROCESS | 19808 | 05:44:34 INFO - PROCESS | 19808 | Cycle 1(1): loaded http://localhost:56676/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56676/tests/dromaeo/cssquery-prototype.html) 05:44:35 INFO - PROCESS | 19808 | RSS: Main: 260145152 05:44:35 INFO - PROCESS | 19808 | 05:47:38 INFO - PROCESS | 19808 | Cycle 1(1): loaded http://localhost:56676/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56676/tests/dromaeo/cssquery-yui.html) 05:47:38 INFO - PROCESS | 19808 | RSS: Main: 223272960 05:47:38 INFO - PROCESS | 19808 | 05:50:41 INFO - PROCESS | 19808 | Cycle 1(1): loaded http://localhost:56676/tests/dromaeo/cssquery-yui.html (next: http://localhost:56676/tests/dromaeo/cssquery-dojo.html) 05:50:42 INFO - PROCESS | 19808 | RSS: Main: 311402496 05:50:42 INFO - PROCESS | 19808 | 05:50:42 INFO - PROCESS | 19808 | __start_tp_report 05:50:42 INFO - PROCESS | 19808 | _x_x_mozilla_page_load 05:50:42 INFO - PROCESS | 19808 | _x_x_mozilla_page_load_details 05:50:42 INFO - PROCESS | 19808 | |i|pagename|runs| 05:50:42 INFO - PROCESS | 19808 | |0;dojo.html;2562.8718564071796;2588.4952434275324;2597.974020259797;2612.4775044990993;2624.042224588025;4581.289900065487;4634.745089020107;4639.721616703;4661.044485880395;4669.6731228814;4646.25659894417;4673.672842900998;4678.53214678532;4718.882027949303;4776.761161941907;4251.298535741599;4275.25183092959;4310.482742070956;4343.391925130469;4416.24923762961;3959.335520212691;4268.210381079494;4387.495438024636;4407.669424793137;4469.262571675669;4226.91546169078;4275.743455392655;4375.562443755631;4408.515063343029;4451.065276291983;4301.225779359714;4305.892352691177;4308.289132293168;4315.417418648473;4316.848910288156;4295.312749960022;4298.828046878121;4305.461817272841;4305.5694430557005;4305.590968857953;4700.482946875841;4738.3603213566075;4778.713277203379;4782.0914026334885;4793.424789025339;5079.390473143209;5085.440601533828;5086.084504789136;5089.185730283137;5140.1004824155625;4878.780454879539;5082.923756143661;5091.898162036776;5106.872328191787;5109.054824857413;4601.746903920283;4623.144718227071;4668.253079507296;4698.859034228979;4704.341392205094;5137.897242055138;5149.665271757323;5151.2273159026445;5185.611079169077;5198.818041368534;4940.851774446773;4945.7774400152075;4951.851444456672;4959.454460009396;4962.8759281018265;4817.325574419584;4873;5028.396592408862;5035.345405097314;5036.992601479718;4859.65982381237;4860.878478038077;4881.511848815161;4900.485449027872;4980.477049909779;4902.4362198346935;4908.509149085135;4908.705477671351;4912.6452167137995;4941.061198372297;4253.298205796071;4260.531341552427;4265.296226122655;4302.376155457441;4311.568843115663;3624.3657359961903;3669.9816500917323;3690.944635830465;3697.22358304759;3748.3440397930253;4298.699091063658;4329.0259691569145;4356.4336636237085;4369.584889435499;4369.584889435499;5022.547970682583;5065.594752419871;5068.797248110108;5074.771635276422;5121.667091639106;4182.853600123981;4322.805473753689;4327.7186982846015;4329.393884856123;4372.234858899744;4420.31485119807;4513.954860451354;4516.480604730498;4520.412346394947;4520.909581808345;4680.882977925511;4710.858674239778;4746.098241334135;4762.023785123972;4765.404324459512;5064.772085256246;5071.416787069535;5074.289599456153;5082.034413461431;5094.057599344133;4534.59188673021;4697.882552936204;4699.694519856198;4704.5765881070865;4745.905081898273;4810.927836082392;4890.535399137077;4913.926291105565;4916.950830491649;5010.273510340907;4997.625178111729;5040;5042.042012017705;5047.444781074152;5080.415752188545;4890.755462226945;4901.068796928572;4902.583280421061;4905.092557876804;4905.852824415273;4735.100330937111;4773.04539092176;4834.129856625841;4851.733154676526;4859.125357435695;4845.830395936126;4852.73309967941;4852.975735121302;4894.4616092230535;4899.387576552931;4951.5048495150195;4952.628552858478;4961.7519124044375;4968.658691906143;4969.900601988013;4677.134730074947;4708.623310135249;4770.189067858541;4813.879653008703;4893.975530122327 05:50:42 INFO - PROCESS | 19808 | |1;ext.html;542.5388419843133;572.1932075773163;572.5934586443625;573.1145380387301;580.2775544447161;27829.165125046256;30612.84693576532;37608.43587346189;38702.61297387025;38741;38846.41730374047;39315.803420982855;39350;39409.99999999993;39411.80294098525;7377.262273772633;7487.850242995137;7591.127020392642;7594.544327340377;7612.124605670336;7342.265773422669;7583.5070720403;7616.847663046736;7619.847603047936;9558.713238602853;9672.129508344247;9993.300468967174;10015.39907605546;10032;10033.297669163125;9791.216702663805;9839.754006149831;9840.21278297738;9847.950760246225;9850.21198304134;9559.569819358076;9646.372985755903;9649.083337082966;9651.635349339456;9653.806923861554;36098.81950590257;36334.81832590847;36356.454653180226;36398.8180059098;36702.265954681025;2607.58278675411;2700.7569318761407;2862.2128914548666;2902.6516817981765;3402.404579198655;26958;27258.45483090347;27313;27702.5844612331;28041.29896752577;3382.96617033829;3441.8451169697173;3465.5841299044278;3467.133216695826;3485.8670931946963;240800;241051;241086;241383;241398;50900.745496272284;51156;51237;51322;51358.74320628373;53246.73376633092;53494.19758703622;53694.73152634212;53901.1914821278;53920.460795392326;12354.938225308995;12590.496380144692;12598.244105353524;12622.116451848466;12633.810492842615;10557;10601.045905868508;10603.469826508644;10605.09856662177;10607.363558186533;14221.14673119616;14588.34352454142;16101.27544260511;16297.511074667778;16437.671246575002;5547.944520554824;5610.13042978339;5610.4670056344585;5683.857903552362;5734.419511219968;746.6528064450057;773.0684525147184;847.817719190377;921.5254144115786;924.6393906376519;25982.87008564945;27140.728592713822;27565.17304480869;27601.723982760315;28012;909.5952301225891;915.6932427636816;925.4169872979979;953.947532885684;1000.4143432659171;14104.788428173586;14475.855241447662;14492.94145796766;14493;14598.270086495633;331043;331207;332393;333566.6538340366;334638.51657059265;47056.5294347059;47102.76448617735;47258.29112563314;47271.76364118158;47320;1002.2082554781787;1067.4716015572187;1118.3848883114092;1120.7310245541012;1143.742657901982;25064.874675626506;28567.714322856504;28623.856880716296;28627.570586440805;29218;40919.795401022806;41421.378679319234;41481;41553.376699348926;41560.584394155674;279849;281224;282227;282794;282898;23672.763272367058;24284.27147185587;24507.264782056565;25337.366565836;35303.11742206466;39490.40764388479;39922.800385997885;39988.60011399965;40058.799706001286;40361.798191010035;34723.82638086793;34725.82637086798;34768.47847282344;35121.473177902866;35196.47205291974;34009.829950850086;34449;34569.654303457646;34707.65292347044;34840.47739283963;33943.151421214665;34653.13367165841;34918.30163396768;35102.12244693903;35411.291774164885;29903.850480747456;31939.6806031946;32062.6793732069;32236.35527289488;32476.512852307707;23694.407639808454;24623.753762462864;26873.492720397368;26893.193204203904;27239.72760272372 05:50:42 INFO - PROCESS | 19808 | |2;jquery.html;341.0348713141809;344.29247895574593;345.99667922727394;353.7965669739899;377.65067312735715;12591.559295424662;15597.14215718135;15655;16439.178041097955;17191;15234.162121083336;15413.306401211945;15428.69142617147;16693.165341732896;17038.659226815456;4577.656675749316;4868.902621947559;4880.0727861706255;5072.543471087602;5114.462981386957;5000.97499512502;5103.566196873252;5105.336306280178;5109.156989096795;5112.590992720573;4879.268109783526;5049;5053.519915608012;5053.5451809337155;5055.292259083732;4981.975090124544;5017.297578339036;5026.145555255615;5027.849164525033;5032.723200223986;4302.161078589676;4435.711678740871;4492.61812745917;4494.9325760113625;4496.752678602676;19924.900375498175;20011.599768004704;20025.499362515908;20096.196152153898;20101.597968040558;1842.9354972575895;1876.5308672831793;1877.1365172020887;1945.863789534733;2470.9839026775676;8842.911570884273;9001.954990225007;9188.40275382098;9252;9606.75983100421;1679.6472740724462;1710.6151115998925;1743.8779285450023;1756.130715295924;1816.6185101128647;68542;70345;71055;71293.64353178305;72433.63783181104;9507.429554226768;9809.027343925722;9844.803103938024;9858.605655773832;9955.303128780915;12850.357482125857;12877.291748953898;12929.806052909214;12938.547150849676;12942.805857912139;6696.799096027127;6786.626735529593;6971.6862741176765;6977.511574189758;7070.469714771412;6585.86828263432;6625.4037136657935;6809.591424514545;6809.795706128824;6815.284395138439;3932.587078356731;4062.289099407651;4073.9388909166387;4175.749455032707;4339.284018137035;1811.25738447236;1817.1277786662213;1826.1417133947023;1845.4832646858902;1859.9721004184948;644.716324817079;763.393102483527;817.6198067898456;819.3608984991716;863.9481631102028;15969.121698306704;16310.510684679479;16603.501894943172;16961.151942403078;17341;797.6370751308141;819.1767273889822;830.1449507007792;831.7089018843357;850.1116333431579;8906.376553641312;9472.621095156255;9489.240860731124;9492.95253523728;9656.179224765974;368087;368781;371955;372272;385856;72829;77681;77925;78592.60703696444;80627;900.9954950225207;906.8231694819447;922.4188761080476;929.9163075323254;932.3054324528134;14201.28993550339;14271.14373137615;14523.854761452672;14585.489507867174;15073.321700523284;33800.854079773955;34353.484697730055;34357.312853743286;35213.823930881204;35792.46311305385;45973;47325.763371182926;47763.76118119387;48853.02293954172;50318.49681503138;7082.681279342545;7213.098362704662;7306.671199796128;7362.411007119311;7743.264389882952;10546.68359949172;10990.890091099307;11375.033122184706;11700.414979250832;12145.14983951115;9990.350627209207;10097.848532271875;10143.34068285559;10927.071198948179;10961.397123158304;9918.504074796376;9931.900680993098;10051.346662466916;10191.28660993753;10610.469476526298;9999.450030248407;10232.181425485795;10508.684739457829;10933.289336193122;11136.276142050741;7218.747343842941;7241.855162896607;7306.159791624032;7397.334239918433;7593.734219302298;8936.955315223382;10048.196144308584;10060.94969525177;10138.898611013796;10511.737206569896 05:50:42 INFO - PROCESS | 19808 | |3;mootools.html;1311.0887932886642;1351.925644089575;1372.8489866114733;1606.3574570171936;1629.087710881907;6368.554201205906;6589;6889.345512176342;7390.076240469942;7395.075615548063;6797.0144329072255;7021.929780702191;7027.051348068009;7097.396721278685;7195.460340474472;2080.3862860456165;2104.6001259760674;2110.6833974903734;2111.9049642766113;2119.73503312086;1837.6784062789025;2075.584883023394;2114.555943251919;2140.3792900058966;2142.7107340508987;1836.1370156026646;2039.8980050997466;2109.4093653776968;2117.269542008005;2121.9787802122014;1996.331229038274;2004.2684420186672;2009.176237742526;2012.7383440152687;2019.0207749241606;2052.4766184622827;2073.6474799284156;2079.2514694709894;2085.197199078366;2086.8017538333834;6873.450123990069;6986.126734158231;7005.369516743466;7007.194172670157;7008.92991070093;4212.978935105336;4300.462442194726;4321.416608757809;4322.654187664979;4324.675649326282;2597.9350516237055;2885.5094633912286;2893.088677066717;2904.956425653617;2910.9272268193254;829.9128591497866;874.9606267717968;909.1408618855185;910.6721580231111;915.697819719491;9639.662611808553;9659.46872921989;9683.806323873554;9695.418274903528;9708.563114659857;3678.5401824771902;3946.1121247719607;3983.46223259862;4029.5164580250557;4049.271131196354;8353.039400468902;8365.07984121746;8371.623276952552;8378.622961966727;8393.748187554384;4430.490493593214;4435.6229720473475;4436.578525040116;4447.977760111179;4452.48796388413;3988.6011398859882;4030.9922519370157;4033.8789836304372;4037.5356833963883;4041.535223449282;3322.983385083059;3401.6258211596705;3437.8796742113905;3448.3620530201993;3456.4296891013205;973.7516933182135;1093.8031154392286;1116.547798141754;1119.143854950947;1119.9496022679;686.8694947959872;795.9960200199077;824.7938015496126;830.2983978538126;835.6866175184306;4988.700677959334;5080.441151473335;5118.61610379223;5135.897282054338;5201.349831271091;839.0770152831836;858.3047731337512;901.7825934987715;912.3886995712888;939.7321763297564;3393.3382990317145;3394.0666316763086;3400.268942177444;3400.3879301725433;3506.281212351462;8671;8864.64541418336;8982.68560600389;8982.775430614287;8988.910110898807;8897.777555561033;8924;8924.509151996574;8925.687600933936;8927.553622318857;6331.556791024676;6470.450011749054;6509.804705858832;6607.2071351438135;6762.425193858478;5012.6240531960975;5064.468230835857;5128.487151284841;5145.382554093533;5171.095058364846;7957.244061814118;8020.879686804586;8110.107888132418;8111.634976426193;8222.17778222173;8124.431289809657;8124.55314957683;8155.755327340189;8180.509169449852;8235.670573177005;6129;6256.593321434092;6278.591891527036;6279.372062793684;6281.12064311006;6892.655367231719;6908.48186386013;6923.7576684817805;6938.340857618518;6955;4946.777395017305;6333.619982801047;6352.142460767922;6372.96813515948;6467.902981455188;6282.340354262919;6341.7780377688405;6347.71435285404;6371.171747672773;6381.617102973836;5988.28140623128;6086.178365920544;6091.847703807441;6094.786682466092;6098.725557350019;4170;4208.726432781859;4228.661707063489;4252.510961239497;4342.41377414045;4347.195768782785;4362.607365337135;4389.122175564836;4482.75344856036;4641.535846415331 05:50:42 INFO - PROCESS | 19808 | |4;prototype.html;277.8832890186122;282.40130922444416;286.1344433089904;288.17725394000126;289.3186545684911;4651.372064771255;4752.857414277573;4805.255185446257;4809.9519004809945;4868.707877527347;4568.954310456895;4700.367794035343;4706.294055891618;4720.90558188836;4741.0991911536785;3703.5185425894597;3719.5164628598313;3731.533558305212;3739.420389839566;3740.5873211778385;3743.270062337845;3746.4898517992874;3756.530433695788;3758.6241375862332;3762.398016317376;3448.4137696591515;3479.7042251408534;3667.1382225177063;3698.1679122197424;3704.481372607837;3657.414813629833;3709.758865673736;3711.4226453757046;3713.2573485302914;3714.5542534895717;3253.8047717137047;3256.837158142107;3261.8858339957987;3262.1681471224924;3266.1018219989455;4474.127545128701;4629;4645.837395691168;4671.103915572333;4680.742559159245;4147.336426171798;4156.8129434175535;4160.54234034256;4166.354215096665;4168.520620128694;5008.398992120932;5018.498150184989;5020.146575082208;5022.443042656788;5025.221090730943;3457.8388507183013;3458.6714262145056;3463.1688394785324;3467.791932484059;3486.4944583035317;4940.258961155855;5189.948100518946;5200.531952124289;5205.973970130163;5251.842444726626;3509.596396414394;3524.4360902255517;3527.6825085742407;3547.1909326243804;3549.0594992327055;3587.7980876406696;3592.1738000259566;3597.8560857565926;3598.4242521196484;3602.693771029492;3948.9210215795524;4147.937780933288;4156.106437116035;4156.293430116887;4159.396887451363;4959.628027897922;4962.80892585784;4983.377077865267;4985.9501404985485;5003.249512573143;1154.3420250457327;1184.1888306510068;1207.84047314579;1208.8307636930838;1210.5097435538444;777.7899967008876;809.7773112394021;824.221111050052;826.5454000299932;829.5934991854069;4258.403823464718;4261.21167583998;4266.253405653998;4268.189044081614;4273.209456250604;4936.456989731127;4957.652964292537;4967.627427942919;4980.402351717817;4991.5258050485145;4107.139289068266;4171.937420938689;4187.958120418818;4243.490781106287;4289.528151903288;2645.7829398476533;2654.4160284737327;2657.7873770098345;2659.6010598410007;2661.4810112027967;4630.467496237909;4647.303734137015;4648.767561621906;4651.953480465219;4674.228752255908;4911.533404326583;5003.54968052877;5055.51972562606;5068.771905264272;5075.365579302587;4609.087228800077;4618.538146185355;4623.306504024423;4627.120847039119;4627.375304333939;4857.562819346276;4858.222684370484;4887.926681099858;4952.529509696573;4962.851114466572;4620.330052142488;4809.350737650372;4824.758762061953;4840.298156767179;4846;4602.7468489233415;4611.884702882455;4614.700044497231;4615.861524154281;4626.814927403001;3449.6550344965303;3455.8963231103107;3458.9827050864583;3460.4290292101523;3470.618231994529;4779.545943135397;4872.270344027819;4952.752362381939;4974.8756281093265;4990.326305948651;4766.284137710352;4768.976155119202;4788.449328327287;4793.496682848251;4818.927716084191;4670.929936050894;4701.294805779161;4749.556571485646;4755.549557384892;4791.9281210782565;4455.487618923865;4685.250359942393;4690.484046754923;4692.296155576691;4728.6050615069325;4775.689580177277;4816.662833601615;4824.6863953842885;4831.541003604652;4839.177339852163;4664.93002604968;4692.554207350297;4693.653173413293;4697.6007039400665;4710.69380490267 05:50:42 INFO - PROCESS | 19808 | |5;yui.html;578.7887421091303;600.3396264109479;603.4327731931984;692.3595674001549;696.9895451568224;10621.57513699453;11059.336439813616;11699.941500292498;12751.362431878404;12802.295873726942;12116.151869369147;12424.316662583555;12506.437210325534;12846.871531284683;12951.611451656441;738.4129616954534;760.042346643228;760.0727112922226;761.2045412543905;761.8209720715655;609.1471939284992;625.6746491824249;731.3235257386923;757.413004921185;760.4562737642584;798.744401791427;808.9676412943504;809.6761295481825;809.6923169195727;810.1452967119686;806.2058872011064;807.289585165051;807.3783186946018;807.5074204735107;807.870740681491;591.5563327504372;592.3602509289999;592.7599322274442;593.1192179613271;593.1399470767361;12126.090543209295;12205.20666156697;12226.327551984727;12241.632751017394;12249.387530623433;11581.942090289494;11655.358955257458;11758.764824703458;11982.340971246578;12446;9412;9421.952890235574;9537.856932146024;9578.329516933818;9904.554295056667;3202.7277681396877;3215.3087086276564;3230.5154226865925;3261.5596894419423;3273.2798784267425;51254;52416.73791631056;52457.99999999962;52611.47388526104;53425.1986220207;9327.440353578808;9378.249740020781;9566;9608.327417080738;9714.125728684452;16604.916975415046;16731.581710457092;16861;16961.321547138225;17022.40421585263;6347.873042539123;6437.645929473924;6479.838004049842;6500.674966251669;6572.211334639874;3398.425824449497;3505.4566542186003;3546.219831637036;3571.6606922342335;3607.1703508193264;2782.8469434181316;2873.6264285642733;2880.8271503709425;2884.8846046158337;2885.567164925278;471.75940270461626;475.7549861821165;477.23880410744806;481.03552377483624;486.1055657590051;473.8838984448763;566.6345207341324;601.1673831743067;602.5571205164201;634.2104080419907;7320.633968301564;7673.309402153833;7736.6518506667335;7986.880196797053;8190.754277371689;498.5911552526966;579.209379197401;581.6800759582297;582.2081968522806;618.7494064903722;3434.6221915589267;3457.930841383158;3466.376052310558;3490.8254587270535;3533.293341331744;40927.59072409238;41439;41553.37669934954;41557.79221103875;47116.76441617838;59682;59793.40206597965;60990.73064885504;61028.69485652632;61289;55056.17415738767;57397.42602573921;57945.420545794004;58042.70978645164;58597.70701146552;6663.267040625624;7147.912948987653;7192.136943566806;7210.603416812126;7251.709931602677;38797.6120238794;39021.2195756089;39433.211335773696;39704.404433932934;40266.59733402746;31589.8420507896;34378.65621343755;34406.483902740976;50378;50499.74750126226;8339.53462819692;9114.589843456928;9516.143547080796;10147.391156530632;10373.377597343882;11683;12172;12182;12223.511059557519;13099.148555343872;11047.72380690489;11243.594600522427;11282.830757538808;11304.208705390873;11663.88336116628;10993.725156871142;11460.656180314603;11508.36703981289;11583.710407239887;11694.824577631172;10850.620228291971;11192.776144476902;11982.161248712506;12022.63932082039;12046.51813927433;9097.863532047157;9299.953500232456;9330.533473326443;9798.608055677978;9944.35361701487;9312.255019598551;9363.344565880323;9394;9433.811323773623;9518.23854091657 05:50:42 INFO - PROCESS | 19808 | __end_tp_report 05:50:42 INFO - PROCESS | 19808 | __start_cc_report 05:50:42 INFO - PROCESS | 19808 | _x_x_mozilla_cycle_collect,1241 05:50:42 INFO - PROCESS | 19808 | __end_cc_report 05:50:42 INFO - PROCESS | 19808 | __startTimestamp1479995442231__endTimestamp 05:50:42 INFO - PROCESS | 19808 | 05:50:42 INFO - PROCESS | 19808 | ------- Summary: start ------- 05:50:42 INFO - PROCESS | 19808 | Number of tests: 6 05:50:42 INFO - PROCESS | 19808 | 05:50:42 INFO - PROCESS | 19808 | [#0] dojo.html Cycles:165 Average:4633.37 Median:4762.02 stddev:492.65 (10.3%) stddev-sans-first:466.62 05:50:42 INFO - PROCESS | 19808 | Values: 2562.9 2588.5 2598.0 2612.5 2624.0 4581.3 4634.7 4639.7 4661.0 4669.7 4646.3 4673.7 4678.5 4718.9 4776.8 4251.3 4275.3 4310.5 4343.4 4416.2 3959.3 4268.2 4387.5 4407.7 4469.3 4226.9 4275.7 4375.6 4408.5 4451.1 4301.2 4305.9 4308.3 4315.4 4316.8 4295.3 4298.8 4305.5 4305.6 4305.6 4700.5 4738.4 4778.7 4782.1 4793.4 5079.4 5085.4 5086.1 5089.2 5140.1 4878.8 5082.9 5091.9 5106.9 5109.1 4601.7 4623.1 4668.3 4698.9 4704.3 5137.9 5149.7 5151.2 5185.6 5198.8 4940.9 4945.8 4951.9 4959.5 4962.9 4817.3 4873.0 5028.4 5035.3 5037.0 4859.7 4860.9 4881.5 4900.5 4980.5 4902.4 4908.5 4908.7 4912.6 4941.1 4253.3 4260.5 4265.3 4302.4 4311.6 3624.4 3670.0 3690.9 3697.2 3748.3 4298.7 4329.0 4356.4 4369.6 4369.6 5022.5 5065.6 5068.8 5074.8 5121.7 4182.9 4322.8 4327.7 4329.4 4372.2 4420.3 4514.0 4516.5 4520.4 4520.9 4680.9 4710.9 4746.1 4762.0 4765.4 5064.8 5071.4 5074.3 5082.0 5094.1 4534.6 4697.9 4699.7 4704.6 4745.9 4810.9 4890.5 4913.9 4917.0 5010.3 4997.6 5040.0 5042.0 5047.4 5080.4 4890.8 4901.1 4902.6 4905.1 4905.9 4735.1 4773.0 4834.1 4851.7 4859.1 4845.8 4852.7 4853.0 4894.5 4899.4 4951.5 4952.6 4961.8 4968.7 4969.9 4677.1 4708.6 4770.2 4813.9 4894.0 05:50:42 INFO - PROCESS | 19808 | 05:50:42 INFO - PROCESS | 19808 | [#1] ext.html Cycles:180 Average:43996.15 Median:34724.83 stddev:75322.00 (216.9%) stddev-sans-first:75462.64 05:50:42 INFO - PROCESS | 19808 | Values: 542.5 572.2 572.6 573.1 580.3 27829.2 30612.8 37608.4 38702.6 38741.0 38846.4 39315.8 39350.0 39410.0 39411.8 7377.3 7487.9 7591.1 7594.5 7612.1 7342.3 7583.5 7616.8 7619.8 9558.7 9672.1 9993.3 10015.4 10032.0 10033.3 9791.2 9839.8 9840.2 9848.0 9850.2 9559.6 9646.4 9649.1 9651.6 9653.8 36098.8 36334.8 36356.5 36398.8 36702.3 2607.6 2700.8 2862.2 2902.7 3402.4 26958.0 27258.5 27313.0 27702.6 28041.3 3383.0 3441.8 3465.6 3467.1 3485.9 240800.0 241051.0 241086.0 241383.0 241398.0 50900.7 51156.0 51237.0 51322.0 51358.7 53246.7 53494.2 53694.7 53901.2 53920.5 12354.9 12590.5 12598.2 12622.1 12633.8 10557.0 10601.0 10603.5 10605.1 10607.4 14221.1 14588.3 16101.3 16297.5 16437.7 5547.9 5610.1 5610.5 5683.9 5734.4 746.7 773.1 847.8 921.5 924.6 25982.9 27140.7 27565.2 27601.7 28012.0 909.6 915.7 925.4 953.9 1000.4 14104.8 14475.9 14492.9 14493.0 14598.3 331043.0 331207.0 332393.0 333566.7 334638.5 47056.5 47102.8 47258.3 47271.8 47320.0 1002.2 1067.5 1118.4 1120.7 1143.7 25064.9 28567.7 28623.9 28627.6 29218.0 40919.8 41421.4 41481.0 41553.4 41560.6 279849.0 281224.0 282227.0 282794.0 282898.0 23672.8 24284.3 24507.3 25337.4 35303.1 39490.4 39922.8 39988.6 40058.8 40361.8 34723.8 34725.8 34768.5 35121.5 35196.5 34009.8 34449.0 34569.7 34707.7 34840.5 33943.2 34653.1 34918.3 35102.1 35411.3 29903.9 31939.7 32062.7 32236.4 32476.5 23694.4 24623.8 26873.5 26893.2 27239.7 05:50:42 INFO - PROCESS | 19808 | 05:50:42 INFO - PROCESS | 19808 | [#2] jquery.html Cycles:180 Average:23503.54 Median:25275.33 stddev:61824.70 (244.6%) stddev-sans-first:61973.68 05:50:42 INFO - PROCESS | 19808 | Values: 341.0 344.3 346.0 353.8 377.7 12591.6 15597.1 15655.0 16439.2 17191.0 15234.2 15413.3 15428.7 16693.2 17038.7 4577.7 4868.9 4880.1 5072.5 5114.5 5001.0 5103.6 5105.3 5109.2 5112.6 4879.3 5049.0 5053.5 5053.5 5055.3 4982.0 5017.3 5026.1 5027.8 5032.7 4302.2 4435.7 4492.6 4494.9 4496.8 19924.9 20011.6 20025.5 20096.2 20101.6 1842.9 1876.5 1877.1 1945.9 2471.0 8842.9 9002.0 9188.4 9252.0 9606.8 1679.6 1710.6 1743.9 1756.1 1816.6 68542.0 70345.0 71055.0 71293.6 72433.6 9507.4 9809.0 9844.8 9858.6 9955.3 12850.4 12877.3 12929.8 12938.5 12942.8 6696.8 6786.6 6971.7 6977.5 7070.5 6585.9 6625.4 6809.6 6809.8 6815.3 3932.6 4062.3 4073.9 4175.7 4339.3 1811.3 1817.1 1826.1 1845.5 1860.0 644.7 763.4 817.6 819.4 863.9 15969.1 16310.5 16603.5 16961.2 17341.0 797.6 819.2 830.1 831.7 850.1 8906.4 9472.6 9489.2 9493.0 9656.2 368087.0 368781.0 371955.0 372272.0 385856.0 72829.0 77681.0 77925.0 78592.6 80627.0 901.0 906.8 922.4 929.9 932.3 14201.3 14271.1 14523.9 14585.5 15073.3 33800.9 34353.5 34357.3 35213.8 35792.5 45973.0 47325.8 47763.8 48853.0 50318.5 7082.7 7213.1 7306.7 7362.4 7743.3 10546.7 10990.9 11375.0 11700.4 12145.1 9990.4 10097.8 10143.3 10927.1 10961.4 9918.5 9931.9 10051.3 10191.3 10610.5 9999.5 10232.2 10508.7 10933.3 11136.3 7218.7 7241.9 7306.2 7397.3 7593.7 8937.0 10048.2 10060.9 10138.9 10511.7 05:50:42 INFO - PROCESS | 19808 | 05:50:42 INFO - PROCESS | 19808 | [#3] mootools.html Cycles:180 Average:4776.05 Median:6037.23 stddev:2599.04 (43.1%) stddev-sans-first:2593.28 05:50:42 INFO - PROCESS | 19808 | Values: 1311.1 1351.9 1372.8 1606.4 1629.1 6368.6 6589.0 6889.3 7390.1 7395.1 6797.0 7021.9 7027.1 7097.4 7195.5 2080.4 2104.6 2110.7 2111.9 2119.7 1837.7 2075.6 2114.6 2140.4 2142.7 1836.1 2039.9 2109.4 2117.3 2122.0 1996.3 2004.3 2009.2 2012.7 2019.0 2052.5 2073.6 2079.3 2085.2 2086.8 6873.5 6986.1 7005.4 7007.2 7008.9 4213.0 4300.5 4321.4 4322.7 4324.7 2597.9 2885.5 2893.1 2905.0 2910.9 829.9 875.0 909.1 910.7 915.7 9639.7 9659.5 9683.8 9695.4 9708.6 3678.5 3946.1 3983.5 4029.5 4049.3 8353.0 8365.1 8371.6 8378.6 8393.7 4430.5 4435.6 4436.6 4448.0 4452.5 3988.6 4031.0 4033.9 4037.5 4041.5 3323.0 3401.6 3437.9 3448.4 3456.4 973.8 1093.8 1116.5 1119.1 1119.9 686.9 796.0 824.8 830.3 835.7 4988.7 5080.4 5118.6 5135.9 5201.3 839.1 858.3 901.8 912.4 939.7 3393.3 3394.1 3400.3 3400.4 3506.3 8671.0 8864.6 8982.7 8982.8 8988.9 8897.8 8924.0 8924.5 8925.7 8927.6 6331.6 6470.5 6509.8 6607.2 6762.4 5012.6 5064.5 5128.5 5145.4 5171.1 7957.2 8020.9 8110.1 8111.6 8222.2 8124.4 8124.6 8155.8 8180.5 8235.7 6129.0 6256.6 6278.6 6279.4 6281.1 6892.7 6908.5 6923.8 6938.3 6955.0 4946.8 6333.6 6352.1 6373.0 6467.9 6282.3 6341.8 6347.7 6371.2 6381.6 5988.3 6086.2 6091.8 6094.8 6098.7 4170.0 4208.7 4228.7 4252.5 4342.4 4347.2 4362.6 4389.1 4482.8 4641.5 05:50:42 INFO - PROCESS | 19808 | 05:50:42 INFO - PROCESS | 19808 | [#4] prototype.html Cycles:180 Average:4032.91 Median:4625.06 stddev:1166.29 (25.2%) stddev-sans-first:1135.00 05:50:42 INFO - PROCESS | 19808 | Values: 277.9 282.4 286.1 288.2 289.3 4651.4 4752.9 4805.3 4810.0 4868.7 4569.0 4700.4 4706.3 4720.9 4741.1 3703.5 3719.5 3731.5 3739.4 3740.6 3743.3 3746.5 3756.5 3758.6 3762.4 3448.4 3479.7 3667.1 3698.2 3704.5 3657.4 3709.8 3711.4 3713.3 3714.6 3253.8 3256.8 3261.9 3262.2 3266.1 4474.1 4629.0 4645.8 4671.1 4680.7 4147.3 4156.8 4160.5 4166.4 4168.5 5008.4 5018.5 5020.1 5022.4 5025.2 3457.8 3458.7 3463.2 3467.8 3486.5 4940.3 5189.9 5200.5 5206.0 5251.8 3509.6 3524.4 3527.7 3547.2 3549.1 3587.8 3592.2 3597.9 3598.4 3602.7 3948.9 4147.9 4156.1 4156.3 4159.4 4959.6 4962.8 4983.4 4986.0 5003.2 1154.3 1184.2 1207.8 1208.8 1210.5 777.8 809.8 824.2 826.5 829.6 4258.4 4261.2 4266.3 4268.2 4273.2 4936.5 4957.7 4967.6 4980.4 4991.5 4107.1 4171.9 4188.0 4243.5 4289.5 2645.8 2654.4 2657.8 2659.6 2661.5 4630.5 4647.3 4648.8 4652.0 4674.2 4911.5 5003.5 5055.5 5068.8 5075.4 4609.1 4618.5 4623.3 4627.1 4627.4 4857.6 4858.2 4887.9 4952.5 4962.9 4620.3 4809.4 4824.8 4840.3 4846.0 4602.7 4611.9 4614.7 4615.9 4626.8 3449.7 3455.9 3459.0 3460.4 3470.6 4779.5 4872.3 4952.8 4974.9 4990.3 4766.3 4769.0 4788.4 4793.5 4818.9 4670.9 4701.3 4749.6 4755.5 4791.9 4455.5 4685.3 4690.5 4692.3 4728.6 4775.7 4816.7 4824.7 4831.5 4839.2 4664.9 4692.6 4693.7 4697.6 4710.7 05:50:42 INFO - PROCESS | 19808 | 05:50:42 INFO - PROCESS | 19808 | [#5] yui.html Cycles:180 Average:13677.69 Median:52437.37 stddev:16803.05 (32.0%) stddev-sans-first:16821.40 05:50:42 INFO - PROCESS | 19808 | Values: 578.8 600.3 603.4 692.4 697.0 10621.6 11059.3 11699.9 12751.4 12802.3 12116.2 12424.3 12506.4 12846.9 12951.6 738.4 760.0 760.1 761.2 761.8 609.1 625.7 731.3 757.4 760.5 798.7 809.0 809.7 809.7 810.1 806.2 807.3 807.4 807.5 807.9 591.6 592.4 592.8 593.1 593.1 12126.1 12205.2 12226.3 12241.6 12249.4 11581.9 11655.4 11758.8 11982.3 12446.0 9412.0 9422.0 9537.9 9578.3 9904.6 3202.7 3215.3 3230.5 3261.6 3273.3 51254.0 52416.7 52458.0 52611.5 53425.2 9327.4 9378.2 9566.0 9608.3 9714.1 16604.9 16731.6 16861.0 16961.3 17022.4 6347.9 6437.6 6479.8 6500.7 6572.2 3398.4 3505.5 3546.2 3571.7 3607.2 2782.8 2873.6 2880.8 2884.9 2885.6 471.8 475.8 477.2 481.0 486.1 473.9 566.6 601.2 602.6 634.2 7320.6 7673.3 7736.7 7986.9 8190.8 498.6 579.2 581.7 582.2 618.7 3434.6 3457.9 3466.4 3490.8 3533.3 40927.6 41439.0 41553.4 41557.8 47116.8 59682.0 59793.4 60990.7 61028.7 61289.0 55056.2 57397.4 57945.4 58042.7 58597.7 6663.3 7147.9 7192.1 7210.6 7251.7 38797.6 39021.2 39433.2 39704.4 40266.6 31589.8 34378.7 34406.5 50378.0 50499.7 8339.5 9114.6 9516.1 10147.4 10373.4 11683.0 12172.0 12182.0 12223.5 13099.1 11047.7 11243.6 11282.8 11304.2 11663.9 10993.7 11460.7 11508.4 11583.7 11694.8 10850.6 11192.8 11982.2 12022.6 12046.5 9097.9 9300.0 9330.5 9798.6 9944.4 9312.3 9363.3 9394.0 9433.8 9518.2 05:50:42 INFO - PROCESS | 19808 | -------- Summary: end -------- 05:50:42 INFO - PROCESS | 19808 | 05:50:43 INFO - TEST-INFO | 19808: exit 0 05:50:43 INFO - TEST-OK | dromaeo_css | took 1122212ms 05:50:43 INFO - TEST-START | kraken 05:50:43 INFO - operating with platform_type : linux_ 05:50:43 INFO - Initialising browser for kraken test... 05:50:43 INFO - TEST-INFO | started process 20115 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDRTSyu/profile http://localhost:56676/getInfo.html) 05:50:47 INFO - PROCESS | 20115 | __metrics Screen width/height:1600/1200 05:50:47 INFO - PROCESS | 20115 | colorDepth:24 05:50:47 INFO - PROCESS | 20115 | Browser inner width/height: 1024/697 05:50:47 INFO - PROCESS | 20115 | __metrics 05:50:48 INFO - PROCESS | 20115 | 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" 05:50:48 INFO - PROCESS | 20115 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:50:48 INFO - PROCESS | 20115 | console.error: PushService: 05:50:48 INFO - PROCESS | 20115 | stateChangeProcessEnqueue: Error transitioning state 05:50:48 INFO - PROCESS | 20115 | UnknownError 05:50:49 INFO - TEST-INFO | 20115: exit 0 05:50:49 INFO - Browser initialized. 05:50:49 INFO - Running cycle 1/1 for kraken test... 05:50:49 INFO - TEST-INFO | started process 20195 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDRTSyu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:51:01 INFO - PROCESS | 20195 | RSS: Main: 174125056 05:51:01 INFO - PROCESS | 20195 | 05:51:04 INFO - PROCESS | 20195 | Cycle 1(1): loaded http://localhost:56676/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56676/tests/kraken/driver.html?testName=audio-beat-detection) 05:51:05 INFO - PROCESS | 20195 | RSS: Main: 234696704 05:51:05 INFO - PROCESS | 20195 | 05:51:08 INFO - PROCESS | 20195 | Cycle 1(1): loaded http://localhost:56676/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56676/tests/kraken/driver.html?testName=audio-dft) 05:51:08 INFO - PROCESS | 20195 | RSS: Main: 217837568 05:51:08 INFO - PROCESS | 20195 | 05:51:11 INFO - PROCESS | 20195 | Cycle 1(1): loaded http://localhost:56676/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56676/tests/kraken/driver.html?testName=audio-fft) 05:51:12 INFO - PROCESS | 20195 | RSS: Main: 299741184 05:51:12 INFO - PROCESS | 20195 | 05:51:14 INFO - PROCESS | 20195 | Cycle 1(1): loaded http://localhost:56676/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56676/tests/kraken/driver.html?testName=audio-oscillator) 05:51:15 INFO - PROCESS | 20195 | RSS: Main: 210161664 05:51:15 INFO - PROCESS | 20195 | 05:51:17 INFO - PROCESS | 20195 | Cycle 1(1): loaded http://localhost:56676/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56676/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:51:18 INFO - PROCESS | 20195 | RSS: Main: 214544384 05:51:18 INFO - PROCESS | 20195 | 05:51:21 INFO - PROCESS | 20195 | Cycle 1(1): loaded http://localhost:56676/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56676/tests/kraken/driver.html?testName=imaging-darkroom) 05:51:21 INFO - PROCESS | 20195 | RSS: Main: 253681664 05:51:21 INFO - PROCESS | 20195 | 05:51:25 INFO - PROCESS | 20195 | Cycle 1(1): loaded http://localhost:56676/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56676/tests/kraken/driver.html?testName=imaging-desaturate) 05:51:25 INFO - PROCESS | 20195 | RSS: Main: 247975936 05:51:25 INFO - PROCESS | 20195 | 05:51:28 INFO - PROCESS | 20195 | Cycle 1(1): loaded http://localhost:56676/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56676/tests/kraken/driver.html?testName=json-parse-financial) 05:51:29 INFO - PROCESS | 20195 | RSS: Main: 249561088 05:51:29 INFO - PROCESS | 20195 | 05:51:31 INFO - PROCESS | 20195 | Cycle 1(1): loaded http://localhost:56676/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56676/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:51:31 INFO - PROCESS | 20195 | RSS: Main: 212979712 05:51:31 INFO - PROCESS | 20195 | 05:51:33 INFO - PROCESS | 20195 | Cycle 1(1): loaded http://localhost:56676/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56676/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:51:34 INFO - PROCESS | 20195 | RSS: Main: 220135424 05:51:34 INFO - PROCESS | 20195 | 05:51:36 INFO - PROCESS | 20195 | Cycle 1(1): loaded http://localhost:56676/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56676/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:51:37 INFO - PROCESS | 20195 | RSS: Main: 229158912 05:51:37 INFO - PROCESS | 20195 | 05:51:40 INFO - PROCESS | 20195 | Cycle 1(1): loaded http://localhost:56676/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56676/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:51:40 INFO - PROCESS | 20195 | RSS: Main: 237854720 05:51:40 INFO - PROCESS | 20195 | 05:51:43 INFO - PROCESS | 20195 | Cycle 1(1): loaded http://localhost:56676/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56676/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:51:44 INFO - PROCESS | 20195 | RSS: Main: 234577920 05:51:44 INFO - PROCESS | 20195 | 05:51:46 INFO - PROCESS | 20195 | Cycle 1(1): loaded http://localhost:56676/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56676/tests/kraken/driver.html?testName=ai-astar) 05:51:46 INFO - PROCESS | 20195 | RSS: Main: 285093888 05:51:46 INFO - PROCESS | 20195 | 05:51:46 INFO - PROCESS | 20195 | __start_tp_report 05:51:46 INFO - PROCESS | 20195 | _x_x_mozilla_page_load 05:51:46 INFO - PROCESS | 20195 | _x_x_mozilla_page_load_details 05:51:46 INFO - PROCESS | 20195 | |i|pagename|runs| 05:51:46 INFO - PROCESS | 20195 | |0;ai-astar;107;114;114;116;114;114;116;115;114;116 05:51:46 INFO - PROCESS | 20195 | |1;audio-beat-detection;156;131;132;191;130;131;127;135;151;129 05:51:46 INFO - PROCESS | 20195 | |2;audio-dft;161;162;163;167;163;163;162;161;162;163 05:51:46 INFO - PROCESS | 20195 | |3;audio-fft;76;76;76;107;77;77;78;77;99;77 05:51:46 INFO - PROCESS | 20195 | |4;audio-oscillator;84;84;108;85;84;84;109;86;85;106 05:51:46 INFO - PROCESS | 20195 | |5;imaging-gaussian-blur;117;117;118;117;119;117;118;118;118;118 05:51:46 INFO - PROCESS | 20195 | |6;imaging-darkroom;146;146;146;147;146;146;146;147;145;146 05:51:46 INFO - PROCESS | 20195 | |7;imaging-desaturate;95;95;95;95;95;95;95;96;95;94 05:51:46 INFO - PROCESS | 20195 | |8;json-parse-financial;64;62;65;64;64;65;66;66;66;65 05:51:46 INFO - PROCESS | 20195 | |9;json-stringify-tinderbox;67;66;65;66;66;65;66;66;67;67 05:51:46 INFO - PROCESS | 20195 | |10;stanford-crypto-aes;84;85;83;85;85;84;84;84;85;84 05:51:46 INFO - PROCESS | 20195 | |11;stanford-crypto-ccm;148;147;141;141;141;142;154;142;190;152 05:51:46 INFO - PROCESS | 20195 | |12;stanford-crypto-pbkdf2;180;179;180;181;180;180;181;182;180;182 05:51:46 INFO - PROCESS | 20195 | |13;stanford-crypto-sha256-iterative;70;70;73;69;70;71;70;71;70;71 05:51:46 INFO - PROCESS | 20195 | __end_tp_report 05:51:46 INFO - PROCESS | 20195 | __start_cc_report 05:51:46 INFO - PROCESS | 20195 | _x_x_mozilla_cycle_collect,1935 05:51:46 INFO - PROCESS | 20195 | __end_cc_report 05:51:46 INFO - PROCESS | 20195 | __startTimestamp1479995506512__endTimestamp 05:51:46 INFO - PROCESS | 20195 | 05:51:46 INFO - PROCESS | 20195 | ------- Summary: start ------- 05:51:46 INFO - PROCESS | 20195 | Number of tests: 14 05:51:46 INFO - PROCESS | 20195 | 05:51:46 INFO - PROCESS | 20195 | [#0] ai-astar Cycles:10 Average:114.00 Median:114.50 stddev:2.62 (2.3%) stddev-sans-first:0.97 05:51:46 INFO - PROCESS | 20195 | Values: 107.0 114.0 114.0 116.0 114.0 114.0 116.0 115.0 114.0 116.0 05:51:46 INFO - PROCESS | 20195 | 05:51:46 INFO - PROCESS | 20195 | [#1] audio-beat-detection Cycles:10 Average:141.30 Median:133.50 stddev:20.01 (15.0%) stddev-sans-first:20.50 05:51:46 INFO - PROCESS | 20195 | Values: 156.0 131.0 132.0 191.0 130.0 131.0 127.0 135.0 151.0 129.0 05:51:46 INFO - PROCESS | 20195 | 05:51:46 INFO - PROCESS | 20195 | [#2] audio-dft Cycles:10 Average:162.70 Median:163.00 stddev:1.70 (1.0%) stddev-sans-first:1.69 05:51:46 INFO - PROCESS | 20195 | Values: 161.0 162.0 163.0 167.0 163.0 163.0 162.0 161.0 162.0 163.0 05:51:46 INFO - PROCESS | 20195 | 05:51:46 INFO - PROCESS | 20195 | [#3] audio-fft Cycles:10 Average:82.00 Median:77.00 stddev:11.24 (14.6%) stddev-sans-first:11.72 05:51:46 INFO - PROCESS | 20195 | Values: 76.0 76.0 76.0 107.0 77.0 77.0 78.0 77.0 99.0 77.0 05:51:46 INFO - PROCESS | 20195 | 05:51:46 INFO - PROCESS | 20195 | [#4] audio-oscillator Cycles:10 Average:91.50 Median:84.00 stddev:11.20 (13.3%) stddev-sans-first:11.54 05:51:46 INFO - PROCESS | 20195 | Values: 84.0 84.0 108.0 85.0 84.0 84.0 109.0 86.0 85.0 106.0 05:51:46 INFO - PROCESS | 20195 | 05:51:46 INFO - PROCESS | 20195 | [#5] imaging-gaussian-blur Cycles:10 Average:117.70 Median:118.00 stddev:0.67 (0.6%) stddev-sans-first:0.67 05:51:46 INFO - PROCESS | 20195 | Values: 117.0 117.0 118.0 117.0 119.0 117.0 118.0 118.0 118.0 118.0 05:51:46 INFO - PROCESS | 20195 | 05:51:46 INFO - PROCESS | 20195 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 05:51:46 INFO - PROCESS | 20195 | Values: 146.0 146.0 146.0 147.0 146.0 146.0 146.0 147.0 145.0 146.0 05:51:46 INFO - PROCESS | 20195 | 05:51:46 INFO - PROCESS | 20195 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 05:51:46 INFO - PROCESS | 20195 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 94.0 05:51:46 INFO - PROCESS | 20195 | 05:51:46 INFO - PROCESS | 20195 | [#8] json-parse-financial Cycles:10 Average:64.70 Median:65.00 stddev:1.25 (1.9%) stddev-sans-first:1.30 05:51:46 INFO - PROCESS | 20195 | Values: 64.0 62.0 65.0 64.0 64.0 65.0 66.0 66.0 66.0 65.0 05:51:46 INFO - PROCESS | 20195 | 05:51:46 INFO - PROCESS | 20195 | [#9] json-stringify-tinderbox Cycles:10 Average:66.10 Median:66.00 stddev:0.74 (1.1%) stddev-sans-first:0.71 05:51:46 INFO - PROCESS | 20195 | Values: 67.0 66.0 65.0 66.0 66.0 65.0 66.0 66.0 67.0 67.0 05:51:46 INFO - PROCESS | 20195 | 05:51:46 INFO - PROCESS | 20195 | [#10] stanford-crypto-aes Cycles:10 Average:84.30 Median:84.50 stddev:0.67 (0.8%) stddev-sans-first:0.71 05:51:46 INFO - PROCESS | 20195 | Values: 84.0 85.0 83.0 85.0 85.0 84.0 84.0 84.0 85.0 84.0 05:51:46 INFO - PROCESS | 20195 | 05:51:46 INFO - PROCESS | 20195 | [#11] stanford-crypto-ccm Cycles:10 Average:149.80 Median:147.50 stddev:14.92 (10.1%) stddev-sans-first:15.81 05:51:46 INFO - PROCESS | 20195 | Values: 148.0 147.0 141.0 141.0 141.0 142.0 154.0 142.0 190.0 152.0 05:51:46 INFO - PROCESS | 20195 | 05:51:46 INFO - PROCESS | 20195 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.50 Median:180.50 stddev:0.97 (0.5%) stddev-sans-first:1.01 05:51:46 INFO - PROCESS | 20195 | Values: 180.0 179.0 180.0 181.0 180.0 180.0 181.0 182.0 180.0 182.0 05:51:46 INFO - PROCESS | 20195 | 05:51:46 INFO - PROCESS | 20195 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.50 Median:70.50 stddev:1.08 (1.5%) stddev-sans-first:1.13 05:51:46 INFO - PROCESS | 20195 | Values: 70.0 70.0 73.0 69.0 70.0 71.0 70.0 71.0 70.0 71.0 05:51:46 INFO - PROCESS | 20195 | -------- Summary: end -------- 05:51:46 INFO - PROCESS | 20195 | 05:51:47 INFO - TEST-INFO | 20195: exit 0 05:51:48 INFO - TEST-OK | kraken | took 64822ms 05:51:48 INFO - SUITE-END | took 1187s 05:51:48 INFO - Completed test suite (00:19:47) 05:51:48 INFO - javascript benchmark 05:51:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2562.8718564071796, 2588.4952434275324, 2597.974020259797, 2612.4775044990993, 2624.042224588025, 4581.289900065487, 4634.745089020107, 4639.721616703, 4661.044485880395, 4669.6731228814, 4646.25659894417, 4673.672842900998, 4678.53214678532, 4718.882027949303, 4776.761161941907, 4251.298535741599, 4275.25183092959, 4310.482742070956, 4343.391925130469, 4416.24923762961, 3959.335520212691, 4268.210381079494, 4387.495438024636, 4407.669424793137, 4469.262571675669, 4226.91546169078, 4275.743455392655, 4375.562443755631, 4408.515063343029, 4451.065276291983, 4301.225779359714, 4305.892352691177, 4308.289132293168, 4315.417418648473, 4316.848910288156, 4295.312749960022, 4298.828046878121, 4305.461817272841, 4305.5694430557005, 4305.590968857953, 4700.482946875841, 4738.3603213566075, 4778.713277203379, 4782.0914026334885, 4793.424789025339, 5079.390473143209, 5085.440601533828, 5086.084504789136, 5089.185730283137, 5140.1004824155625, 4878.780454879539, 5082.923756143661, 5091.898162036776, 5106.872328191787, 5109.054824857413, 4601.746903920283, 4623.144718227071, 4668.253079507296, 4698.859034228979, 4704.341392205094, 5137.897242055138, 5149.665271757323, 5151.2273159026445, 5185.611079169077, 5198.818041368534, 4940.851774446773, 4945.7774400152075, 4951.851444456672, 4959.454460009396, 4962.8759281018265, 4817.325574419584, 4873.0, 5028.396592408862, 5035.345405097314, 5036.992601479718, 4859.65982381237, 4860.878478038077, 4881.511848815161, 4900.485449027872, 4980.477049909779, 4902.4362198346935, 4908.509149085135, 4908.705477671351, 4912.6452167137995, 4941.061198372297, 4253.298205796071, 4260.531341552427, 4265.296226122655, 4302.376155457441, 4311.568843115663, 3624.3657359961903, 3669.9816500917323, 3690.944635830465, 3697.22358304759, 3748.3440397930253, 4298.699091063658, 4329.0259691569145, 4356.4336636237085, 4369.584889435499, 4369.584889435499, 5022.547970682583, 5065.594752419871, 5068.797248110108, 5074.771635276422, 5121.667091639106, 4182.853600123981, 4322.805473753689, 4327.7186982846015, 4329.393884856123, 4372.234858899744, 4420.31485119807, 4513.954860451354, 4516.480604730498, 4520.412346394947, 4520.909581808345, 4680.882977925511, 4710.858674239778, 4746.098241334135, 4762.023785123972, 4765.404324459512, 5064.772085256246, 5071.416787069535, 5074.289599456153, 5082.034413461431, 5094.057599344133, 4534.59188673021, 4697.882552936204, 4699.694519856198, 4704.5765881070865, 4745.905081898273, 4810.927836082392, 4890.535399137077, 4913.926291105565, 4916.950830491649, 5010.273510340907, 4997.625178111729, 5040.0, 5042.042012017705, 5047.444781074152, 5080.415752188545, 4890.755462226945, 4901.068796928572, 4902.583280421061, 4905.092557876804, 4905.852824415273, 4735.100330937111, 4773.04539092176, 4834.129856625841, 4851.733154676526, 4859.125357435695, 4845.830395936126, 4852.73309967941, 4852.975735121302, 4894.4616092230535, 4899.387576552931, 4951.5048495150195, 4952.628552858478, 4961.7519124044375, 4968.658691906143, 4969.900601988013, 4677.134730074947, 4708.623310135249, 4770.189067858541, 4813.879653008703, 4893.975530122327], "value": 4601.502583734012, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [542.5388419843133, 572.1932075773163, 572.5934586443625, 573.1145380387301, 580.2775544447161, 27829.165125046256, 30612.84693576532, 37608.43587346189, 38702.61297387025, 38741.0, 38846.41730374047, 39315.803420982855, 39350.0, 39409.99999999993, 39411.80294098525, 7377.262273772633, 7487.850242995137, 7591.127020392642, 7594.544327340377, 7612.124605670336, 7342.265773422669, 7583.5070720403, 7616.847663046736, 7619.847603047936, 9558.713238602853, 9672.129508344247, 9993.300468967174, 10015.39907605546, 10032.0, 10033.297669163125, 9791.216702663805, 9839.754006149831, 9840.21278297738, 9847.950760246225, 9850.21198304134, 9559.569819358076, 9646.372985755903, 9649.083337082966, 9651.635349339456, 9653.806923861554, 36098.81950590257, 36334.81832590847, 36356.454653180226, 36398.8180059098, 36702.265954681025, 2607.58278675411, 2700.7569318761407, 2862.2128914548666, 2902.6516817981765, 3402.404579198655, 26958.0, 27258.45483090347, 27313.0, 27702.5844612331, 28041.29896752577, 3382.96617033829, 3441.8451169697173, 3465.5841299044278, 3467.133216695826, 3485.8670931946963, 240800.0, 241051.0, 241086.0, 241383.0, 241398.0, 50900.745496272284, 51156.0, 51237.0, 51322.0, 51358.74320628373, 53246.73376633092, 53494.19758703622, 53694.73152634212, 53901.1914821278, 53920.460795392326, 12354.938225308995, 12590.496380144692, 12598.244105353524, 12622.116451848466, 12633.810492842615, 10557.0, 10601.045905868508, 10603.469826508644, 10605.09856662177, 10607.363558186533, 14221.14673119616, 14588.34352454142, 16101.27544260511, 16297.511074667778, 16437.671246575002, 5547.944520554824, 5610.13042978339, 5610.4670056344585, 5683.857903552362, 5734.419511219968, 746.6528064450057, 773.0684525147184, 847.817719190377, 921.5254144115786, 924.6393906376519, 25982.87008564945, 27140.728592713822, 27565.17304480869, 27601.723982760315, 28012.0, 909.5952301225891, 915.6932427636816, 925.4169872979979, 953.947532885684, 1000.4143432659171, 14104.788428173586, 14475.855241447662, 14492.94145796766, 14493.0, 14598.270086495633, 331043.0, 331207.0, 332393.0, 333566.6538340366, 334638.51657059265, 47056.5294347059, 47102.76448617735, 47258.29112563314, 47271.76364118158, 47320.0, 1002.2082554781787, 1067.4716015572187, 1118.3848883114092, 1120.7310245541012, 1143.742657901982, 25064.874675626506, 28567.714322856504, 28623.856880716296, 28627.570586440805, 29218.0, 40919.795401022806, 41421.378679319234, 41481.0, 41553.376699348926, 41560.584394155674, 279849.0, 281224.0, 282227.0, 282794.0, 282898.0, 23672.763272367058, 24284.27147185587, 24507.264782056565, 25337.366565836, 35303.11742206466, 39490.40764388479, 39922.800385997885, 39988.60011399965, 40058.799706001286, 40361.798191010035, 34723.82638086793, 34725.82637086798, 34768.47847282344, 35121.473177902866, 35196.47205291974, 34009.829950850086, 34449.0, 34569.654303457646, 34707.65292347044, 34840.47739283963, 33943.151421214665, 34653.13367165841, 34918.30163396768, 35102.12244693903, 35411.291774164885, 29903.850480747456, 31939.6806031946, 32062.6793732069, 32236.35527289488, 32476.512852307707, 23694.407639808454, 24623.753762462864, 26873.492720397368, 26893.193204203904, 27239.72760272372], "value": 17041.046496424875, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.0348713141809, 344.29247895574593, 345.99667922727394, 353.7965669739899, 377.65067312735715, 12591.559295424662, 15597.14215718135, 15655.0, 16439.178041097955, 17191.0, 15234.162121083336, 15413.306401211945, 15428.69142617147, 16693.165341732896, 17038.659226815456, 4577.656675749316, 4868.902621947559, 4880.0727861706255, 5072.543471087602, 5114.462981386957, 5000.97499512502, 5103.566196873252, 5105.336306280178, 5109.156989096795, 5112.590992720573, 4879.268109783526, 5049.0, 5053.519915608012, 5053.5451809337155, 5055.292259083732, 4981.975090124544, 5017.297578339036, 5026.145555255615, 5027.849164525033, 5032.723200223986, 4302.161078589676, 4435.711678740871, 4492.61812745917, 4494.9325760113625, 4496.752678602676, 19924.900375498175, 20011.599768004704, 20025.499362515908, 20096.196152153898, 20101.597968040558, 1842.9354972575895, 1876.5308672831793, 1877.1365172020887, 1945.863789534733, 2470.9839026775676, 8842.911570884273, 9001.954990225007, 9188.40275382098, 9252.0, 9606.75983100421, 1679.6472740724462, 1710.6151115998925, 1743.8779285450023, 1756.130715295924, 1816.6185101128647, 68542.0, 70345.0, 71055.0, 71293.64353178305, 72433.63783181104, 9507.429554226768, 9809.027343925722, 9844.803103938024, 9858.605655773832, 9955.303128780915, 12850.357482125857, 12877.291748953898, 12929.806052909214, 12938.547150849676, 12942.805857912139, 6696.799096027127, 6786.626735529593, 6971.6862741176765, 6977.511574189758, 7070.469714771412, 6585.86828263432, 6625.4037136657935, 6809.591424514545, 6809.795706128824, 6815.284395138439, 3932.587078356731, 4062.289099407651, 4073.9388909166387, 4175.749455032707, 4339.284018137035, 1811.25738447236, 1817.1277786662213, 1826.1417133947023, 1845.4832646858902, 1859.9721004184948, 644.716324817079, 763.393102483527, 817.6198067898456, 819.3608984991716, 863.9481631102028, 15969.121698306704, 16310.510684679479, 16603.501894943172, 16961.151942403078, 17341.0, 797.6370751308141, 819.1767273889822, 830.1449507007792, 831.7089018843357, 850.1116333431579, 8906.376553641312, 9472.621095156255, 9489.240860731124, 9492.95253523728, 9656.179224765974, 368087.0, 368781.0, 371955.0, 372272.0, 385856.0, 72829.0, 77681.0, 77925.0, 78592.60703696444, 80627.0, 900.9954950225207, 906.8231694819447, 922.4188761080476, 929.9163075323254, 932.3054324528134, 14201.28993550339, 14271.14373137615, 14523.854761452672, 14585.489507867174, 15073.321700523284, 33800.854079773955, 34353.484697730055, 34357.312853743286, 35213.823930881204, 35792.46311305385, 45973.0, 47325.763371182926, 47763.76118119387, 48853.02293954172, 50318.49681503138, 7082.681279342545, 7213.098362704662, 7306.671199796128, 7362.411007119311, 7743.264389882952, 10546.68359949172, 10990.890091099307, 11375.033122184706, 11700.414979250832, 12145.14983951115, 9990.350627209207, 10097.848532271875, 10143.34068285559, 10927.071198948179, 10961.397123158304, 9918.504074796376, 9931.900680993098, 10051.346662466916, 10191.28660993753, 10610.469476526298, 9999.450030248407, 10232.181425485795, 10508.684739457829, 10933.289336193122, 11136.276142050741, 7218.747343842941, 7241.855162896607, 7306.159791624032, 7397.334239918433, 7593.734219302298, 8936.955315223382, 10048.196144308584, 10060.94969525177, 10138.898611013796, 10511.737206569896], "value": 7911.650752202443, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1311.0887932886642, 1351.925644089575, 1372.8489866114733, 1606.3574570171936, 1629.087710881907, 6368.554201205906, 6589.0, 6889.345512176342, 7390.076240469942, 7395.075615548063, 6797.0144329072255, 7021.929780702191, 7027.051348068009, 7097.396721278685, 7195.460340474472, 2080.3862860456165, 2104.6001259760674, 2110.6833974903734, 2111.9049642766113, 2119.73503312086, 1837.6784062789025, 2075.584883023394, 2114.555943251919, 2140.3792900058966, 2142.7107340508987, 1836.1370156026646, 2039.8980050997466, 2109.4093653776968, 2117.269542008005, 2121.9787802122014, 1996.331229038274, 2004.2684420186672, 2009.176237742526, 2012.7383440152687, 2019.0207749241606, 2052.4766184622827, 2073.6474799284156, 2079.2514694709894, 2085.197199078366, 2086.8017538333834, 6873.450123990069, 6986.126734158231, 7005.369516743466, 7007.194172670157, 7008.92991070093, 4212.978935105336, 4300.462442194726, 4321.416608757809, 4322.654187664979, 4324.675649326282, 2597.9350516237055, 2885.5094633912286, 2893.088677066717, 2904.956425653617, 2910.9272268193254, 829.9128591497866, 874.9606267717968, 909.1408618855185, 910.6721580231111, 915.697819719491, 9639.662611808553, 9659.46872921989, 9683.806323873554, 9695.418274903528, 9708.563114659857, 3678.5401824771902, 3946.1121247719607, 3983.46223259862, 4029.5164580250557, 4049.271131196354, 8353.039400468902, 8365.07984121746, 8371.623276952552, 8378.622961966727, 8393.748187554384, 4430.490493593214, 4435.6229720473475, 4436.578525040116, 4447.977760111179, 4452.48796388413, 3988.6011398859882, 4030.9922519370157, 4033.8789836304372, 4037.5356833963883, 4041.535223449282, 3322.983385083059, 3401.6258211596705, 3437.8796742113905, 3448.3620530201993, 3456.4296891013205, 973.7516933182135, 1093.8031154392286, 1116.547798141754, 1119.143854950947, 1119.9496022679, 686.8694947959872, 795.9960200199077, 824.7938015496126, 830.2983978538126, 835.6866175184306, 4988.700677959334, 5080.441151473335, 5118.61610379223, 5135.897282054338, 5201.349831271091, 839.0770152831836, 858.3047731337512, 901.7825934987715, 912.3886995712888, 939.7321763297564, 3393.3382990317145, 3394.0666316763086, 3400.268942177444, 3400.3879301725433, 3506.281212351462, 8671.0, 8864.64541418336, 8982.68560600389, 8982.775430614287, 8988.910110898807, 8897.777555561033, 8924.0, 8924.509151996574, 8925.687600933936, 8927.553622318857, 6331.556791024676, 6470.450011749054, 6509.804705858832, 6607.2071351438135, 6762.425193858478, 5012.6240531960975, 5064.468230835857, 5128.487151284841, 5145.382554093533, 5171.095058364846, 7957.244061814118, 8020.879686804586, 8110.107888132418, 8111.634976426193, 8222.17778222173, 8124.431289809657, 8124.55314957683, 8155.755327340189, 8180.509169449852, 8235.670573177005, 6129.0, 6256.593321434092, 6278.591891527036, 6279.372062793684, 6281.12064311006, 6892.655367231719, 6908.48186386013, 6923.7576684817805, 6938.340857618518, 6955.0, 4946.777395017305, 6333.619982801047, 6352.142460767922, 6372.96813515948, 6467.902981455188, 6282.340354262919, 6341.7780377688405, 6347.71435285404, 6371.171747672773, 6381.617102973836, 5988.28140623128, 6086.178365920544, 6091.847703807441, 6094.786682466092, 6098.725557350019, 4170.0, 4208.726432781859, 4228.661707063489, 4252.510961239497, 4342.41377414045, 4347.195768782785, 4362.607365337135, 4389.122175564836, 4482.75344856036, 4641.535846415331], "value": 3890.0726687768074, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [277.8832890186122, 282.40130922444416, 286.1344433089904, 288.17725394000126, 289.3186545684911, 4651.372064771255, 4752.857414277573, 4805.255185446257, 4809.9519004809945, 4868.707877527347, 4568.954310456895, 4700.367794035343, 4706.294055891618, 4720.90558188836, 4741.0991911536785, 3703.5185425894597, 3719.5164628598313, 3731.533558305212, 3739.420389839566, 3740.5873211778385, 3743.270062337845, 3746.4898517992874, 3756.530433695788, 3758.6241375862332, 3762.398016317376, 3448.4137696591515, 3479.7042251408534, 3667.1382225177063, 3698.1679122197424, 3704.481372607837, 3657.414813629833, 3709.758865673736, 3711.4226453757046, 3713.2573485302914, 3714.5542534895717, 3253.8047717137047, 3256.837158142107, 3261.8858339957987, 3262.1681471224924, 3266.1018219989455, 4474.127545128701, 4629.0, 4645.837395691168, 4671.103915572333, 4680.742559159245, 4147.336426171798, 4156.8129434175535, 4160.54234034256, 4166.354215096665, 4168.520620128694, 5008.398992120932, 5018.498150184989, 5020.146575082208, 5022.443042656788, 5025.221090730943, 3457.8388507183013, 3458.6714262145056, 3463.1688394785324, 3467.791932484059, 3486.4944583035317, 4940.258961155855, 5189.948100518946, 5200.531952124289, 5205.973970130163, 5251.842444726626, 3509.596396414394, 3524.4360902255517, 3527.6825085742407, 3547.1909326243804, 3549.0594992327055, 3587.7980876406696, 3592.1738000259566, 3597.8560857565926, 3598.4242521196484, 3602.693771029492, 3948.9210215795524, 4147.937780933288, 4156.106437116035, 4156.293430116887, 4159.396887451363, 4959.628027897922, 4962.80892585784, 4983.377077865267, 4985.9501404985485, 5003.249512573143, 1154.3420250457327, 1184.1888306510068, 1207.84047314579, 1208.8307636930838, 1210.5097435538444, 777.7899967008876, 809.7773112394021, 824.221111050052, 826.5454000299932, 829.5934991854069, 4258.403823464718, 4261.21167583998, 4266.253405653998, 4268.189044081614, 4273.209456250604, 4936.456989731127, 4957.652964292537, 4967.627427942919, 4980.402351717817, 4991.5258050485145, 4107.139289068266, 4171.937420938689, 4187.958120418818, 4243.490781106287, 4289.528151903288, 2645.7829398476533, 2654.4160284737327, 2657.7873770098345, 2659.6010598410007, 2661.4810112027967, 4630.467496237909, 4647.303734137015, 4648.767561621906, 4651.953480465219, 4674.228752255908, 4911.533404326583, 5003.54968052877, 5055.51972562606, 5068.771905264272, 5075.365579302587, 4609.087228800077, 4618.538146185355, 4623.306504024423, 4627.120847039119, 4627.375304333939, 4857.562819346276, 4858.222684370484, 4887.926681099858, 4952.529509696573, 4962.851114466572, 4620.330052142488, 4809.350737650372, 4824.758762061953, 4840.298156767179, 4846.0, 4602.7468489233415, 4611.884702882455, 4614.700044497231, 4615.861524154281, 4626.814927403001, 3449.6550344965303, 3455.8963231103107, 3458.9827050864583, 3460.4290292101523, 3470.618231994529, 4779.545943135397, 4872.270344027819, 4952.752362381939, 4974.8756281093265, 4990.326305948651, 4766.284137710352, 4768.976155119202, 4788.449328327287, 4793.496682848251, 4818.927716084191, 4670.929936050894, 4701.294805779161, 4749.556571485646, 4755.549557384892, 4791.9281210782565, 4455.487618923865, 4685.250359942393, 4690.484046754923, 4692.296155576691, 4728.6050615069325, 4775.689580177277, 4816.662833601615, 4824.6863953842885, 4831.541003604652, 4839.177339852163, 4664.93002604968, 4692.554207350297, 4693.653173413293, 4697.6007039400665, 4710.69380490267], "value": 3658.212325698205, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [578.7887421091303, 600.3396264109479, 603.4327731931984, 692.3595674001549, 696.9895451568224, 10621.57513699453, 11059.336439813616, 11699.941500292498, 12751.362431878404, 12802.295873726942, 12116.151869369147, 12424.316662583555, 12506.437210325534, 12846.871531284683, 12951.611451656441, 738.4129616954534, 760.042346643228, 760.0727112922226, 761.2045412543905, 761.8209720715655, 609.1471939284992, 625.6746491824249, 731.3235257386923, 757.413004921185, 760.4562737642584, 798.744401791427, 808.9676412943504, 809.6761295481825, 809.6923169195727, 810.1452967119686, 806.2058872011064, 807.289585165051, 807.3783186946018, 807.5074204735107, 807.870740681491, 591.5563327504372, 592.3602509289999, 592.7599322274442, 593.1192179613271, 593.1399470767361, 12126.090543209295, 12205.20666156697, 12226.327551984727, 12241.632751017394, 12249.387530623433, 11581.942090289494, 11655.358955257458, 11758.764824703458, 11982.340971246578, 12446.0, 9412.0, 9421.952890235574, 9537.856932146024, 9578.329516933818, 9904.554295056667, 3202.7277681396877, 3215.3087086276564, 3230.5154226865925, 3261.5596894419423, 3273.2798784267425, 51254.0, 52416.73791631056, 52457.99999999962, 52611.47388526104, 53425.1986220207, 9327.440353578808, 9378.249740020781, 9566.0, 9608.327417080738, 9714.125728684452, 16604.916975415046, 16731.581710457092, 16861.0, 16961.321547138225, 17022.40421585263, 6347.873042539123, 6437.645929473924, 6479.838004049842, 6500.674966251669, 6572.211334639874, 3398.425824449497, 3505.4566542186003, 3546.219831637036, 3571.6606922342335, 3607.1703508193264, 2782.8469434181316, 2873.6264285642733, 2880.8271503709425, 2884.8846046158337, 2885.567164925278, 471.75940270461626, 475.7549861821165, 477.23880410744806, 481.03552377483624, 486.1055657590051, 473.8838984448763, 566.6345207341324, 601.1673831743067, 602.5571205164201, 634.2104080419907, 7320.633968301564, 7673.309402153833, 7736.6518506667335, 7986.880196797053, 8190.754277371689, 498.5911552526966, 579.209379197401, 581.6800759582297, 582.2081968522806, 618.7494064903722, 3434.6221915589267, 3457.930841383158, 3466.376052310558, 3490.8254587270535, 3533.293341331744, 40927.59072409238, 41439.0, 41553.37669934954, 41557.79221103875, 47116.76441617838, 59682.0, 59793.40206597965, 60990.73064885504, 61028.69485652632, 61289.0, 55056.17415738767, 57397.42602573921, 57945.420545794004, 58042.70978645164, 58597.70701146552, 6663.267040625624, 7147.912948987653, 7192.136943566806, 7210.603416812126, 7251.709931602677, 38797.6120238794, 39021.2195756089, 39433.211335773696, 39704.404433932934, 40266.59733402746, 31589.8420507896, 34378.65621343755, 34406.483902740976, 50378.0, 50499.74750126226, 8339.53462819692, 9114.589843456928, 9516.143547080796, 10147.391156530632, 10373.377597343882, 11683.0, 12172.0, 12182.0, 12223.511059557519, 13099.148555343872, 11047.72380690489, 11243.594600522427, 11282.830757538808, 11304.208705390873, 11663.88336116628, 10993.725156871142, 11460.656180314603, 11508.36703981289, 11583.710407239887, 11694.824577631172, 10850.620228291971, 11192.776144476902, 11982.161248712506, 12022.63932082039, 12046.51813927433, 9097.863532047157, 9299.953500232456, 9330.533473326443, 9798.608055677978, 9944.35361701487, 9312.255019598551, 9363.344565880323, 9394.0, 9433.811323773623, 9518.23854091657], "value": 5889.913534457285, "unit": "score"}], "name": "dromaeo_css", "value": 6109.539615596602, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 114.0, 114.0, 116.0, 114.0, 114.0, 116.0, 115.0, 114.0, 116.0], "value": 114.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 131.0, 132.0, 191.0, 130.0, 131.0, 127.0, 135.0, 151.0, 129.0], "value": 141.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 163.0, 167.0, 163.0, 163.0, 162.0, 161.0, 162.0, 163.0], "value": 162.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 76.0, 107.0, 77.0, 77.0, 78.0, 77.0, 99.0, 77.0], "value": 82.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 108.0, 85.0, 84.0, 84.0, 109.0, 86.0, 85.0, 106.0], "value": 91.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 118.0, 117.0, 119.0, 117.0, 118.0, 118.0, 118.0, 118.0], "value": 117.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 147.0, 146.0, 146.0, 146.0, 147.0, 145.0, 146.0], "value": 146.1, "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, 96.0, 95.0, 94.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 62.0, 65.0, 64.0, 64.0, 65.0, 66.0, 66.0, 66.0, 65.0], "value": 64.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 65.0, 66.0, 66.0, 65.0, 66.0, 66.0, 67.0, 67.0], "value": 66.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 83.0, 85.0, 85.0, 84.0, 84.0, 84.0, 85.0, 84.0], "value": 84.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 147.0, 141.0, 141.0, 141.0, 142.0, 154.0, 142.0, 190.0, 152.0], "value": 149.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 179.0, 180.0, 181.0, 180.0, 180.0, 181.0, 182.0, 180.0, 182.0], "value": 180.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 70.0, 73.0, 69.0, 70.0, 71.0, 70.0, 71.0, 70.0, 71.0], "value": 70.5, "unit": "score"}], "name": "kraken", "value": 1566.2, "alertThreshold": 2.0}]} 05:51:48 INFO - Return code: 0 05:51:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:51:48 INFO - # TBPL SUCCESS # 05:51:48 INFO - Running post-action listener: _resource_record_post_action 05:51:48 INFO - [mozharness: 2016-11-24 13:51:48.317460Z] Finished run-tests step (success) 05:51:48 INFO - Running post-run listener: _resource_record_post_run 05:51:48 INFO - Total resource usage - Wall time: 1204s; CPU: 13.0%; Read bytes: 0; Write bytes: 392032256; Read time: 0; Write time: 323520 05:51:48 INFO - TinderboxPrint: CPU usage
13.1% 05:51:48 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 05:51:48 INFO - TinderboxPrint: I/O write bytes / time
392,032,256 / 323,520 05:51:48 INFO - TinderboxPrint: CPU idle
8,345.6 (86.9%) 05:51:48 INFO - TinderboxPrint: CPU user
1,210.2 (12.6%) 05:51:48 INFO - TinderboxPrint: Swap in / out
0 / 0 05:51:48 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 99831808; Read time: 0; Write time: 89144 05:51:48 INFO - run-tests - Wall time: 1188s; CPU: 13.0%; Read bytes: 0; Write bytes: 291827712; Read time: 0; Write time: 234324 05:51:48 INFO - Running post-run listener: _upload_blobber_files 05:51:48 INFO - Blob upload gear active. 05:51:48 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:51:48 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 05:51:48 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', 'Cedar', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:51:48 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 Cedar -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:51:49 INFO - (blobuploader) - INFO - Open directory for files ... 05:51:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:51:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:51:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:51:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:51:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:51:50 INFO - (blobuploader) - INFO - Done attempting. 05:51:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:51:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:51:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:51:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:51:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:51:51 INFO - (blobuploader) - INFO - Done attempting. 05:51:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:51:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:51:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:51:55 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:51:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:51:55 INFO - (blobuploader) - INFO - Done attempting. 05:51:55 INFO - (blobuploader) - INFO - Iteration through files over. 05:51:55 INFO - Return code: 0 05:51:55 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:51:55 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:51:55 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/41f316f31f8dfbe3a915854760253aa7f6ec65b520058eebe1c008e2ca294fe0dab8d67cd32b4a5d73417200fc15cdaa18d636a62cd620b327ccaa4d422b30a5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/f27896520573e102fe515396f2acbe6d328a625aa6cb3214b3ba3917a2db10f0df664502348aa93b1e8584ff74150c7cdba46f08be7968eaeea76140e14f2b98", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/66040e7b96b78a3bbaee7cd4d2a3b353197c1d9193aa612485f6abbbe18b2a2506bdb1c2db521626e656fb5264a20dfce745a187ab770655b9b977a23fc06b7c"} 05:51:55 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:51:55 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:51:55 INFO - Contents: 05:51:55 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/41f316f31f8dfbe3a915854760253aa7f6ec65b520058eebe1c008e2ca294fe0dab8d67cd32b4a5d73417200fc15cdaa18d636a62cd620b327ccaa4d422b30a5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/f27896520573e102fe515396f2acbe6d328a625aa6cb3214b3ba3917a2db10f0df664502348aa93b1e8584ff74150c7cdba46f08be7968eaeea76140e14f2b98", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/66040e7b96b78a3bbaee7cd4d2a3b353197c1d9193aa612485f6abbbe18b2a2506bdb1c2db521626e656fb5264a20dfce745a187ab770655b9b977a23fc06b7c"} 05:51:55 INFO - Running post-run listener: copy_logs_to_upload_dir 05:51:55 INFO - Copying logs to upload dir... 05:51:55 INFO - mkdir: /builds/slave/test/build/upload/logs 05:51:55 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1251.830658 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2016-11-24 05:51:55.276845) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-24 05:51:55.281783) ========= 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=17051fcd5208b27b50bedfeb000001dd-1479993684.831020-144256127 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/41f316f31f8dfbe3a915854760253aa7f6ec65b520058eebe1c008e2ca294fe0dab8d67cd32b4a5d73417200fc15cdaa18d636a62cd620b327ccaa4d422b30a5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/f27896520573e102fe515396f2acbe6d328a625aa6cb3214b3ba3917a2db10f0df664502348aa93b1e8584ff74150c7cdba46f08be7968eaeea76140e14f2b98", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/66040e7b96b78a3bbaee7cd4d2a3b353197c1d9193aa612485f6abbbe18b2a2506bdb1c2db521626e656fb5264a20dfce745a187ab770655b9b977a23fc06b7c"} build_url:https://queue.taskcluster.net/v1/task/Z6wAUFeBQG-DUotjTFAx0Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011376 build_url: 'https://queue.taskcluster.net/v1/task/Z6wAUFeBQG-DUotjTFAx0Q/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/Cedar/sha512/41f316f31f8dfbe3a915854760253aa7f6ec65b520058eebe1c008e2ca294fe0dab8d67cd32b4a5d73417200fc15cdaa18d636a62cd620b327ccaa4d422b30a5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/f27896520573e102fe515396f2acbe6d328a625aa6cb3214b3ba3917a2db10f0df664502348aa93b1e8584ff74150c7cdba46f08be7968eaeea76140e14f2b98", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/66040e7b96b78a3bbaee7cd4d2a3b353197c1d9193aa612485f6abbbe18b2a2506bdb1c2db521626e656fb5264a20dfce745a187ab770655b9b977a23fc06b7c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-24 05:51:55.312315) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-24 05:51:55.312699) ========= 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=17051fcd5208b27b50bedfeb000001dd-1479993684.831020-144256127 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004515 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-24 05:51:55.397578) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-24 05:51:55.397894) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-24 05:51:55.398201) ========= ========= Total master_lag: 0.21 =========