builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-023 starttime: 1485984850.85 results: success (0) buildid: 20170201114508 builduid: b2c64dbead90424085f4591e704b1aa6 revision: a15e503db4d1d7261680fb497af39b65babff013 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-01 13:34:10.854873) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-01 13:34:10.855313) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-01 13:34:10.855600) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-01 13:34:10.874166) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-01 13:34:10.874769) ========= 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=6d15e502d8e29d5d2676d664000001c3-1485984850.840905-452561807 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-01 13:34:11-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 232M=0s 2017-02-01 13:34:11 (232 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.267856 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-01 13:34:11.174259) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-01 13:34:11.174600) ========= 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=6d15e502d8e29d5d2676d664000001c3-1485984850.840905-452561807 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019272 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-01 13:34:11.224514) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-01 13:34:11.224880) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev a15e503db4d1d7261680fb497af39b65babff013 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev a15e503db4d1d7261680fb497af39b65babff013 --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=6d15e502d8e29d5d2676d664000001c3-1485984850.840905-452561807 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-01 13:34:11,839 truncating revision to first 12 chars 2017-02-01 13:34:11,839 Setting DEBUG logging. 2017-02-01 13:34:11,840 attempt 1/10 2017-02-01 13:34:11,840 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/a15e503db4d1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-01 13:34:13,070 unpacking tar archive at: mozilla-beta-a15e503db4d1/testing/mozharness/ program finished with exit code 0 elapsedTime=1.574119 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-01 13:34:12.812555) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-01 13:34:12.812887) ========= script_repo_revision: a15e503db4d1d7261680fb497af39b65babff013 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-01 13:34:12.813262) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-01 13:34:12.813524) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-01 13:34:12.823603) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2017-02-01 13:34:12.823957) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] 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=6d15e502d8e29d5d2676d664000001c3-1485984850.840905-452561807 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 13:34:13 INFO - MultiFileLogger online at 20170201 13:34:13 in /builds/slave/test 13:34:13 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 13:34:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:34:13 INFO - {'append_to_log': False, 13:34:13 INFO - 'base_work_dir': '/builds/slave/test', 13:34:13 INFO - 'blob_upload_branch': 'Mozilla-Beta', 13:34:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:34:13 INFO - 'branch': 'Mozilla-Beta', 13:34:13 INFO - 'buildbot_json_path': 'buildprops.json', 13:34:13 INFO - 'config_files': ('talos/linux_config.py',), 13:34:13 INFO - 'default_actions': ('clobber', 13:34:13 INFO - 'read-buildbot-config', 13:34:13 INFO - 'download-and-extract', 13:34:13 INFO - 'populate-webroot', 13:34:13 INFO - 'create-virtualenv', 13:34:13 INFO - 'install', 13:34:13 INFO - 'run-tests'), 13:34:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:34:13 INFO - 'download_minidump_stackwalk': True, 13:34:13 INFO - 'download_symbols': 'ondemand', 13:34:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:34:13 INFO - 'tooltool.py': '/tools/tooltool.py', 13:34:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:34:13 INFO - '/tools/misc-python/virtualenv.py')}, 13:34:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:34:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:34:13 INFO - 'installer_path': 'installer.exe', 13:34:13 INFO - 'log_level': 'info', 13:34:13 INFO - 'log_name': 'talos', 13:34:13 INFO - 'log_to_console': True, 13:34:13 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 13:34:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 13:34:13 INFO - 'opt_config_files': (), 13:34:13 INFO - 'pip_index': False, 13:34:13 INFO - 'sps_profile': False, 13:34:13 INFO - 'sps_profile_interval': 0, 13:34:13 INFO - 'suite': 'dromaeojs', 13:34:13 INFO - 'system_bits': '32', 13:34:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:34:13 INFO - 'title': 'talos-linux64-ix-023', 13:34:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:34:13 INFO - 'use_talos_json': True, 13:34:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:34:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:34:13 INFO - 'work_dir': 'build'} 13:34:13 INFO - [mozharness: 2017-02-01 21:34:13.536087Z] Running clobber step. 13:34:13 INFO - Running pre-action listener: _resource_record_pre_action 13:34:13 INFO - Running main action method: clobber 13:34:13 INFO - rmtree: /builds/slave/test/build 13:34:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:34:14 INFO - Running post-action listener: _resource_record_post_action 13:34:14 INFO - [mozharness: 2017-02-01 21:34:14.555454Z] Finished clobber step (success) 13:34:14 INFO - [mozharness: 2017-02-01 21:34:14.555554Z] Running read-buildbot-config step. 13:34:14 INFO - Running pre-action listener: _resource_record_pre_action 13:34:14 INFO - Running main action method: read_buildbot_config 13:34:14 INFO - Using buildbot properties: 13:34:14 INFO - { 13:34:14 INFO - "project": "", 13:34:14 INFO - "product": "firefox", 13:34:14 INFO - "script_repo_revision": "production", 13:34:14 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 13:34:14 INFO - "repository": "", 13:34:14 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 13:34:14 INFO - "buildid": "20170201114508", 13:34:14 INFO - "pgo_build": "True", 13:34:14 INFO - "basedir": "/builds/slave/test", 13:34:14 INFO - "buildnumber": 123, 13:34:14 INFO - "slavename": "talos-linux64-ix-023", 13:34:14 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 13:34:14 INFO - "platform": "ubuntu64_hw", 13:34:14 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 13:34:14 INFO - "branch": "mozilla-beta", 13:34:14 INFO - "repo_path": "releases/mozilla-beta", 13:34:14 INFO - "revision": "a15e503db4d1d7261680fb497af39b65babff013", 13:34:14 INFO - "stage_platform": "linux64-pgo", 13:34:14 INFO - "builduid": "b2c64dbead90424085f4591e704b1aa6", 13:34:14 INFO - "slavebuilddir": "test" 13:34:14 INFO - } 13:34:14 INFO - Found installer url https://queue.taskcluster.net/v1/task/ZFbBpRivTiOec9RJz_EHSg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2. 13:34:14 INFO - Running post-action listener: _resource_record_post_action 13:34:14 INFO - [mozharness: 2017-02-01 21:34:14.557973Z] Finished read-buildbot-config step (success) 13:34:14 INFO - [mozharness: 2017-02-01 21:34:14.558061Z] Running download-and-extract step. 13:34:14 INFO - Running pre-action listener: _resource_record_pre_action 13:34:14 INFO - Running main action method: download_and_extract 13:34:14 INFO - mkdir: /builds/slave/test/build/tests 13:34:14 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')]} 13:34:14 INFO - trying https://queue.taskcluster.net/v1/task/ZFbBpRivTiOec9RJz_EHSg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.test_packages.json 13:34:14 INFO - Downloading https://queue.taskcluster.net/v1/task/ZFbBpRivTiOec9RJz_EHSg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-52.0.en-US.linux-x86_64.test_packages.json 13:34:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZFbBpRivTiOec9RJz_EHSg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 13:34:15 INFO - Downloaded 1327 bytes. 13:34:15 INFO - Reading from file /builds/slave/test/build/firefox-52.0.en-US.linux-x86_64.test_packages.json 13:34:15 INFO - Using the following test package requirements: 13:34:15 INFO - {u'common': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip'], 13:34:15 INFO - u'cppunittest': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 13:34:15 INFO - u'firefox-52.0.en-US.linux-x86_64.cppunittest.tests.zip'], 13:34:15 INFO - u'gtest': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 13:34:15 INFO - u'firefox-52.0.en-US.linux-x86_64.gtest.tests.zip'], 13:34:15 INFO - u'jittest': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 13:34:15 INFO - u'jsshell-linux-x86_64.zip'], 13:34:15 INFO - u'mochitest': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 13:34:15 INFO - u'firefox-52.0.en-US.linux-x86_64.mochitest.tests.zip'], 13:34:15 INFO - u'mozbase': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip'], 13:34:15 INFO - u'reftest': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 13:34:15 INFO - u'firefox-52.0.en-US.linux-x86_64.reftest.tests.zip'], 13:34:15 INFO - u'talos': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 13:34:15 INFO - u'firefox-52.0.en-US.linux-x86_64.talos.tests.zip'], 13:34:15 INFO - u'web-platform': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 13:34:15 INFO - u'firefox-52.0.en-US.linux-x86_64.web-platform.tests.zip'], 13:34:15 INFO - u'xpcshell': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 13:34:15 INFO - u'firefox-52.0.en-US.linux-x86_64.xpcshell.tests.zip']} 13:34:15 INFO - Downloading packages: [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 13:34:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZFbBpRivTiOec9RJz_EHSg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.common.tests.zip 13:34:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZFbBpRivTiOec9RJz_EHSg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 13:34:15 INFO - Fetch https://queue.taskcluster.net/v1/task/ZFbBpRivTiOec9RJz_EHSg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.common.tests.zip into memory 13:34:15 INFO - Http code: 200 13:34:15 INFO - via: 1.1 93ab344e1a3fa44edbf3f1bc5729427a.cloudfront.net (CloudFront) 13:34:15 INFO - x-amz-cf-id: -qxThp3gDnPdOLLYWIQHBUiI-KhRrt6GH4czrgf4orRpzroplx-IfQ== 13:34:15 INFO - x-amz-version-id: ulP3Mdl1xyV9z.AgzBuZB2YDJ.ieD_ec 13:34:15 INFO - Expected file size: 21954837 13:34:15 INFO - Obtained file size: 21954837 13:34:20 INFO - Downloading packages: [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', u'firefox-52.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 13:34:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZFbBpRivTiOec9RJz_EHSg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.common.tests.zip 13:34:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZFbBpRivTiOec9RJz_EHSg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 13:34:20 INFO - Fetch https://queue.taskcluster.net/v1/task/ZFbBpRivTiOec9RJz_EHSg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.common.tests.zip into memory 13:34:20 INFO - Http code: 200 13:34:20 INFO - via: 1.1 d897b81191cbfd68a1b6fc44f98c52b0.cloudfront.net (CloudFront) 13:34:20 INFO - x-amz-cf-id: 0glhpe8End7w0-edDae7Cv69MiFZgXuZ6CJS4jrM7YvncFgg1BkCLQ== 13:34:20 INFO - x-amz-version-id: ulP3Mdl1xyV9z.AgzBuZB2YDJ.ieD_ec 13:34:20 INFO - Expected file size: 21954837 13:34:20 INFO - Obtained file size: 21954837 13:34:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZFbBpRivTiOec9RJz_EHSg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.talos.tests.zip 13:34:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZFbBpRivTiOec9RJz_EHSg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 13:34:25 INFO - Fetch https://queue.taskcluster.net/v1/task/ZFbBpRivTiOec9RJz_EHSg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.talos.tests.zip into memory 13:34:25 INFO - Http code: 200 13:34:25 INFO - via: 1.1 9552af4ceb2e0dd55964a80d1e549fd5.cloudfront.net (CloudFront) 13:34:25 INFO - x-amz-cf-id: Wu8hwOL93LrYmpvdYgf3wIIJvPUJr1ikmEWpqdAnzTukyxPmsLTshg== 13:34:25 INFO - x-amz-version-id: kUuM.yr_QVZUFCUz6tlNy0pqHWlWtPsj 13:34:25 INFO - Expected file size: 14403005 13:34:25 INFO - Obtained file size: 14403005 13:34:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:34:26 INFO - trying https://queue.taskcluster.net/v1/task/ZFbBpRivTiOec9RJz_EHSg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 13:34:26 INFO - Downloading https://queue.taskcluster.net/v1/task/ZFbBpRivTiOec9RJz_EHSg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 13:34:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZFbBpRivTiOec9RJz_EHSg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-52.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 13:34:27 INFO - Downloaded 57683226 bytes. 13:34:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZFbBpRivTiOec9RJz_EHSg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 13:34:27 INFO - mkdir: /builds/slave/test/properties 13:34:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:34:27 INFO - Writing to file /builds/slave/test/properties/build_url 13:34:27 INFO - Contents: 13:34:27 INFO - build_url:https://queue.taskcluster.net/v1/task/ZFbBpRivTiOec9RJz_EHSg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 13:34:27 INFO - Running post-action listener: _resource_record_post_action 13:34:27 INFO - Running post-action listener: set_extra_try_arguments 13:34:27 INFO - [mozharness: 2017-02-01 21:34:27.947273Z] Finished download-and-extract step (success) 13:34:27 INFO - [mozharness: 2017-02-01 21:34:27.947501Z] Running populate-webroot step. 13:34:27 INFO - Running pre-action listener: _resource_record_pre_action 13:34:27 INFO - Running main action method: populate_webroot 13:34:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:34:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:34:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:34:27 INFO - '--tppagecycles', 13:34:27 INFO - '7'], 13:34:27 INFO - 'tests': ['tsvgm']}}, 13:34:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:34:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:34:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:34:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:34:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:34:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:34:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:34:27 INFO - 'talos_options': ['--disable-e10s'], 13:34:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:34:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:34:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:34:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:34:27 INFO - 'talos_options': ['--disable-e10s'], 13:34:27 INFO - 'tests': ['damp', 'tps']}, 13:34:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:34:27 INFO - 'tests': ['damp', 'tps']}, 13:34:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:34:27 INFO - 'tests': ['dromaeo_dom']}, 13:34:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:34:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:34:27 INFO - 'tests': ['basic_compositor_video']}, 13:34:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:34:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:34:27 INFO - 'tests': ['a11yr', 13:34:27 INFO - 'ts_paint', 13:34:27 INFO - 'tpaint', 13:34:27 INFO - 'sessionrestore', 13:34:27 INFO - 'sessionrestore_no_auto_restore', 13:34:27 INFO - 'tabpaint']}, 13:34:27 INFO - 'other-e10s': {'tests': ['a11yr', 13:34:27 INFO - 'ts_paint', 13:34:27 INFO - 'tpaint', 13:34:27 INFO - 'sessionrestore', 13:34:27 INFO - 'sessionrestore_no_auto_restore', 13:34:27 INFO - 'tabpaint']}, 13:34:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:34:27 INFO - 'ts_paint', 13:34:27 INFO - 'tpaint', 13:34:27 INFO - 'sessionrestore', 13:34:27 INFO - 'sessionrestore_no_auto_restore', 13:34:27 INFO - 'tabpaint']}, 13:34:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:34:27 INFO - 'ts_paint', 13:34:27 INFO - 'tpaint', 13:34:27 INFO - 'sessionrestore', 13:34:27 INFO - 'sessionrestore_no_auto_restore', 13:34:27 INFO - 'tabpaint']}, 13:34:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:34:27 INFO - 'tests': ['a11yr', 13:34:27 INFO - 'ts_paint', 13:34:27 INFO - 'tpaint', 13:34:27 INFO - 'sessionrestore', 13:34:27 INFO - 'sessionrestore_no_auto_restore', 13:34:27 INFO - 'tabpaint']}, 13:34:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:34:27 INFO - 'tests': ['a11yr', 13:34:27 INFO - 'ts_paint', 13:34:27 INFO - 'tpaint', 13:34:27 INFO - 'sessionrestore', 13:34:27 INFO - 'sessionrestore_no_auto_restore', 13:34:27 INFO - 'tabpaint']}, 13:34:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:34:27 INFO - 'tests': ['tsvgx', 13:34:27 INFO - 'tsvgr_opacity', 13:34:27 INFO - 'tart', 13:34:27 INFO - 'tscrollx', 13:34:27 INFO - 'cart']}, 13:34:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:34:27 INFO - 'tsvgr_opacity', 13:34:27 INFO - 'tart', 13:34:27 INFO - 'tscrollx', 13:34:27 INFO - 'cart']}, 13:34:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:34:27 INFO - 'talos_options': ['--disable-e10s'], 13:34:27 INFO - 'tests': ['tp5o']}, 13:34:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:34:27 INFO - 'tests': ['tp5o']}, 13:34:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:34:27 INFO - 'talos_options': ['--disable-e10s', 13:34:27 INFO - '--xperf_path', 13:34:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:34:27 INFO - 'tests': ['tp5n']}, 13:34:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:34:27 INFO - 'talos_options': ['--xperf_path', 13:34:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:34:27 INFO - 'tests': ['tp5n']}}, 13:34:27 INFO - 'talos.zip': {'path': '', 13:34:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:34:27 INFO - Running post-action listener: _resource_record_post_action 13:34:27 INFO - [mozharness: 2017-02-01 21:34:27.966415Z] Finished populate-webroot step (success) 13:34:27 INFO - [mozharness: 2017-02-01 21:34:27.966502Z] Running create-virtualenv step. 13:34:27 INFO - Running pre-action listener: _resource_record_pre_action 13:34:27 INFO - Running main action method: create_virtualenv 13:34:27 INFO - Creating virtualenv /builds/slave/test/build/venv 13:34:27 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 13:34:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:34:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:34:28 INFO - Using real prefix '/usr' 13:34:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:34:28 INFO - Installing distribute.............................................................................................................................................................................................done. 13:34:30 INFO - Installing pip.................done. 13:34:30 INFO - Return code: 0 13:34:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 13:34:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 13:34:30 INFO - Reading from file tmpfile_stdout 13:34:30 INFO - Output received: 13:34:30 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) 13:34:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:34:30 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')]} 13:34:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:34:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34:30 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')]} 13:34:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:34:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34:30 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 0x7f3b480f5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x124dca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x124af60>, '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 0x124bd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x124c1d0>, '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': '6d15e502d8e29d5d2676d664000001c3-1485984850.840905-452561807', '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 13:34:30 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 13:34:30 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 13:34:30 INFO - Using env: {'DISPLAY': ':0', 13:34:30 INFO - 'HOME': '/home/cltbld', 13:34:30 INFO - 'LANG': 'en_US.UTF-8', 13:34:30 INFO - 'LANGUAGE': 'en_US:en', 13:34:30 INFO - 'LOGNAME': 'cltbld', 13:34:30 INFO - 'MAIL': '/var/mail/cltbld', 13:34:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:34:30 INFO - 'MOZ_NO_REMOTE': '1', 13:34:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:34:30 INFO - 'NO_EM_RESTART': '1', 13:34:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:34:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:34:30 INFO - 'PWD': '/builds/slave/test', 13:34:30 INFO - 'SHELL': '/bin/bash', 13:34:30 INFO - 'SHLVL': '1', 13:34:30 INFO - 'TERM': 'linux', 13:34:30 INFO - 'TMOUT': '86400', 13:34:30 INFO - 'USER': 'cltbld', 13:34:30 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1485984850.840905-452561807', 13:34:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:34:30 INFO - '_': '/tools/buildbot/bin/python'} 13:34:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:34:30 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 13:34:30 INFO - Cleaning up... 13:34:30 INFO - Return code: 0 13:34:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:34:30 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')]} 13:34:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:34:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34:30 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')]} 13:34:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:34:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34:30 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 0x7f3b480f5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x124dca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x124af60>, '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 0x124bd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x124c1d0>, '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': '6d15e502d8e29d5d2676d664000001c3-1485984850.840905-452561807', '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 13:34:30 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 13:34:30 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 13:34:30 INFO - Using env: {'DISPLAY': ':0', 13:34:30 INFO - 'HOME': '/home/cltbld', 13:34:30 INFO - 'LANG': 'en_US.UTF-8', 13:34:30 INFO - 'LANGUAGE': 'en_US:en', 13:34:30 INFO - 'LOGNAME': 'cltbld', 13:34:30 INFO - 'MAIL': '/var/mail/cltbld', 13:34:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:34:30 INFO - 'MOZ_NO_REMOTE': '1', 13:34:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:34:30 INFO - 'NO_EM_RESTART': '1', 13:34:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:34:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:34:30 INFO - 'PWD': '/builds/slave/test', 13:34:30 INFO - 'SHELL': '/bin/bash', 13:34:30 INFO - 'SHLVL': '1', 13:34:30 INFO - 'TERM': 'linux', 13:34:30 INFO - 'TMOUT': '86400', 13:34:30 INFO - 'USER': 'cltbld', 13:34:30 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1485984850.840905-452561807', 13:34:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:34:30 INFO - '_': '/tools/buildbot/bin/python'} 13:34:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:34:31 INFO - Downloading/unpacking psutil>=3.1.1 13:34: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 13:34: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 13:34: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 13:34: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 13:34: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 13:34: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 13:34:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:34:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:34:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:34:33 INFO - Installing collected packages: psutil 13:34:33 INFO - Running setup.py install for psutil 13:34:33 INFO - building 'psutil._psutil_linux' extension 13:34:33 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 13:34:33 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 13:34:33 INFO - building 'psutil._psutil_posix' extension 13:34:33 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 13:34:33 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 13:34:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:34:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:34:33 INFO - Successfully installed psutil 13:34:33 INFO - Cleaning up... 13:34:33 INFO - Return code: 0 13:34:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:34:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:34:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:34:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:34:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:34:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34:33 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 0x7f3b480f5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x124dca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x124af60>, '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 0x124bd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x124c1d0>, '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': '6d15e502d8e29d5d2676d664000001c3-1485984850.840905-452561807', '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 13:34:33 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 13:34:33 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 13:34:33 INFO - Using env: {'DISPLAY': ':0', 13:34:33 INFO - 'HOME': '/home/cltbld', 13:34:33 INFO - 'LANG': 'en_US.UTF-8', 13:34:33 INFO - 'LANGUAGE': 'en_US:en', 13:34:33 INFO - 'LOGNAME': 'cltbld', 13:34:33 INFO - 'MAIL': '/var/mail/cltbld', 13:34:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:34:33 INFO - 'MOZ_NO_REMOTE': '1', 13:34:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:34:33 INFO - 'NO_EM_RESTART': '1', 13:34:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:34:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:34:33 INFO - 'PWD': '/builds/slave/test', 13:34:33 INFO - 'SHELL': '/bin/bash', 13:34:33 INFO - 'SHLVL': '1', 13:34:33 INFO - 'TERM': 'linux', 13:34:33 INFO - 'TMOUT': '86400', 13:34:33 INFO - 'USER': 'cltbld', 13:34:33 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1485984850.840905-452561807', 13:34:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:34:33 INFO - '_': '/tools/buildbot/bin/python'} 13:34:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:34:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:34: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 13:34: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 13:34: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 13:34: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 13:34: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 13:34: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 13:34:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:34:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:34:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:34:36 INFO - Installing collected packages: mozsystemmonitor 13:34:36 INFO - Running setup.py install for mozsystemmonitor 13:34:36 INFO - Successfully installed mozsystemmonitor 13:34:36 INFO - Cleaning up... 13:34:36 INFO - Return code: 0 13:34:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:34:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:34:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:34:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:34:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:34:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34:36 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 0x7f3b480f5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x124dca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x124af60>, '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 0x124bd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x124c1d0>, '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': '6d15e502d8e29d5d2676d664000001c3-1485984850.840905-452561807', '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 13:34:36 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 13:34:36 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 13:34:36 INFO - Using env: {'DISPLAY': ':0', 13:34:36 INFO - 'HOME': '/home/cltbld', 13:34:36 INFO - 'LANG': 'en_US.UTF-8', 13:34:36 INFO - 'LANGUAGE': 'en_US:en', 13:34:36 INFO - 'LOGNAME': 'cltbld', 13:34:36 INFO - 'MAIL': '/var/mail/cltbld', 13:34:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:34:36 INFO - 'MOZ_NO_REMOTE': '1', 13:34:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:34:36 INFO - 'NO_EM_RESTART': '1', 13:34:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:34:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:34:36 INFO - 'PWD': '/builds/slave/test', 13:34:36 INFO - 'SHELL': '/bin/bash', 13:34:36 INFO - 'SHLVL': '1', 13:34:36 INFO - 'TERM': 'linux', 13:34:36 INFO - 'TMOUT': '86400', 13:34:36 INFO - 'USER': 'cltbld', 13:34:36 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1485984850.840905-452561807', 13:34:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:34:36 INFO - '_': '/tools/buildbot/bin/python'} 13:34:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:34:36 INFO - Downloading/unpacking jsonschema==2.5.1 13:34:36 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 13:34:36 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 13:34:36 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 13:34:36 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 13:34:36 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 13:34:36 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 13:34:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:34:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:34:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:34:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:34:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:34:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:34:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:34:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:34:38 INFO - Downloading functools32-3.2.3-2.tar.gz 13:34:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:34:38 INFO - warning: no files found matching '*.txt' 13:34:38 INFO - no previously-included directories found matching 'build' 13:34:38 INFO - no previously-included directories found matching 'dist' 13:34:38 INFO - no previously-included directories found matching '.git*' 13:34:38 INFO - Installing collected packages: jsonschema, functools32 13:34:38 INFO - Running setup.py install for functools32 13:34:38 INFO - warning: no files found matching '*.txt' 13:34:38 INFO - no previously-included directories found matching 'build' 13:34:38 INFO - no previously-included directories found matching 'dist' 13:34:38 INFO - no previously-included directories found matching '.git*' 13:34:38 INFO - Successfully installed jsonschema functools32 13:34:38 INFO - Cleaning up... 13:34:39 INFO - Return code: 0 13:34:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:34: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')]} 13:34:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:34:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34: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')]} 13:34:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:34:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34:39 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 0x7f3b480f5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x124dca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x124af60>, '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 0x124bd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x124c1d0>, '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': '6d15e502d8e29d5d2676d664000001c3-1485984850.840905-452561807', '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 13:34:39 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 13:34:39 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 13:34:39 INFO - Using env: {'DISPLAY': ':0', 13:34:39 INFO - 'HOME': '/home/cltbld', 13:34:39 INFO - 'LANG': 'en_US.UTF-8', 13:34:39 INFO - 'LANGUAGE': 'en_US:en', 13:34:39 INFO - 'LOGNAME': 'cltbld', 13:34:39 INFO - 'MAIL': '/var/mail/cltbld', 13:34:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:34:39 INFO - 'MOZ_NO_REMOTE': '1', 13:34:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:34:39 INFO - 'NO_EM_RESTART': '1', 13:34:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:34:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:34:39 INFO - 'PWD': '/builds/slave/test', 13:34:39 INFO - 'SHELL': '/bin/bash', 13:34:39 INFO - 'SHLVL': '1', 13:34:39 INFO - 'TERM': 'linux', 13:34:39 INFO - 'TMOUT': '86400', 13:34:39 INFO - 'USER': 'cltbld', 13:34:39 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1485984850.840905-452561807', 13:34:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:34:39 INFO - '_': '/tools/buildbot/bin/python'} 13:34:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:34:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:34:39 INFO - Cleaning up... 13:34:39 INFO - Return code: 0 13:34:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:34: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')]} 13:34:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:34:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34: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')]} 13:34:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:34:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34:39 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 0x7f3b480f5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x124dca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x124af60>, '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 0x124bd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x124c1d0>, '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': '6d15e502d8e29d5d2676d664000001c3-1485984850.840905-452561807', '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 13:34:39 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 13:34:39 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 13:34:39 INFO - Using env: {'DISPLAY': ':0', 13:34:39 INFO - 'HOME': '/home/cltbld', 13:34:39 INFO - 'LANG': 'en_US.UTF-8', 13:34:39 INFO - 'LANGUAGE': 'en_US:en', 13:34:39 INFO - 'LOGNAME': 'cltbld', 13:34:39 INFO - 'MAIL': '/var/mail/cltbld', 13:34:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:34:39 INFO - 'MOZ_NO_REMOTE': '1', 13:34:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:34:39 INFO - 'NO_EM_RESTART': '1', 13:34:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:34:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:34:39 INFO - 'PWD': '/builds/slave/test', 13:34:39 INFO - 'SHELL': '/bin/bash', 13:34:39 INFO - 'SHLVL': '1', 13:34:39 INFO - 'TERM': 'linux', 13:34:39 INFO - 'TMOUT': '86400', 13:34:39 INFO - 'USER': 'cltbld', 13:34:39 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1485984850.840905-452561807', 13:34:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:34:39 INFO - '_': '/tools/buildbot/bin/python'} 13:34:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:34:39 INFO - Downloading/unpacking blobuploader==1.2.4 13:34:39 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 13:34:39 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 13:34:39 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 13:34:39 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 13:34:39 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 13:34:39 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 13:34:41 INFO - Downloading blobuploader-1.2.4.tar.gz 13:34:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:34:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:34:41 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 13:34:41 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 13:34:41 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 13:34:41 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 13:34:41 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 13:34:41 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 13:34:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:34:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:34:41 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 13:34:41 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 13:34:41 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 13:34:41 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 13:34:41 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 13:34:41 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 13:34:42 INFO - Downloading docopt-0.6.1.tar.gz 13:34:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:34:42 INFO - Installing collected packages: blobuploader, requests, docopt 13:34:42 INFO - Running setup.py install for blobuploader 13:34:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:34:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:34:42 INFO - Running setup.py install for requests 13:34:42 INFO - Running setup.py install for docopt 13:34:42 INFO - Successfully installed blobuploader requests docopt 13:34:42 INFO - Cleaning up... 13:34:42 INFO - Return code: 0 13:34:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:34:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:34:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:34:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:34:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:34:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34:42 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 0x7f3b480f5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x124dca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x124af60>, '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 0x124bd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x124c1d0>, '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': '6d15e502d8e29d5d2676d664000001c3-1485984850.840905-452561807', '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 13:34:42 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 13:34:42 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 13:34:42 INFO - Using env: {'DISPLAY': ':0', 13:34:42 INFO - 'HOME': '/home/cltbld', 13:34:42 INFO - 'LANG': 'en_US.UTF-8', 13:34:42 INFO - 'LANGUAGE': 'en_US:en', 13:34:42 INFO - 'LOGNAME': 'cltbld', 13:34:42 INFO - 'MAIL': '/var/mail/cltbld', 13:34:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:34:42 INFO - 'MOZ_NO_REMOTE': '1', 13:34:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:34:42 INFO - 'NO_EM_RESTART': '1', 13:34:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:34:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:34:42 INFO - 'PWD': '/builds/slave/test', 13:34:42 INFO - 'SHELL': '/bin/bash', 13:34:42 INFO - 'SHLVL': '1', 13:34:42 INFO - 'TERM': 'linux', 13:34:42 INFO - 'TMOUT': '86400', 13:34:42 INFO - 'USER': 'cltbld', 13:34:42 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1485984850.840905-452561807', 13:34:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:34:42 INFO - '_': '/tools/buildbot/bin/python'} 13:34:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:34:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:34:43 INFO - Running setup.py (path:/tmp/pip-ph3CjB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:34:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:34:43 INFO - Running setup.py (path:/tmp/pip-ifvqQZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:34:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:34:43 INFO - Running setup.py (path:/tmp/pip-NFNbbd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:34:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:34:43 INFO - Running setup.py (path:/tmp/pip-8i64Di-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:34:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:34:43 INFO - Running setup.py (path:/tmp/pip-LyGP6I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:34:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:34:43 INFO - Running setup.py (path:/tmp/pip-I00dYK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:34:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:34:43 INFO - Running setup.py (path:/tmp/pip-4RFGs7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:34:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:34:43 INFO - Running setup.py (path:/tmp/pip-64cLoU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:34:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:34:43 INFO - Running setup.py (path:/tmp/pip-K9aRFv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:34:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:34:43 INFO - Running setup.py (path:/tmp/pip-M9pq2i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:34:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:34:43 INFO - Running setup.py (path:/tmp/pip-jpVqxV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:34:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:34:43 INFO - Running setup.py (path:/tmp/pip-QhZNLw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:34:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:34:43 INFO - Running setup.py (path:/tmp/pip-z2HYRd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:34:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:34:44 INFO - Running setup.py (path:/tmp/pip-OK8DSJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:34:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:34:44 INFO - Running setup.py (path:/tmp/pip-58KIPY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:34:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:34:44 INFO - Running setup.py (path:/tmp/pip-785pyg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:34:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:34:44 INFO - Running setup.py (path:/tmp/pip-HLqtnb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:34:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:34:44 INFO - Running setup.py install for manifestparser 13:34:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:34:44 INFO - Running setup.py install for mozcrash 13:34:44 INFO - Running setup.py install for mozdebug 13:34:44 INFO - Running setup.py install for mozdevice 13:34:44 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:34:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:34:44 INFO - Running setup.py install for mozfile 13:34:44 INFO - Running setup.py install for mozhttpd 13:34:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:34:44 INFO - Running setup.py install for mozinfo 13:34:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:34:45 INFO - Running setup.py install for mozInstall 13:34:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:34:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:34:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:34:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:34:45 INFO - Running setup.py install for mozleak 13:34:45 INFO - Running setup.py install for mozlog 13:34:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:34:45 INFO - Running setup.py install for moznetwork 13:34:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:34:45 INFO - Running setup.py install for mozprocess 13:34:45 INFO - Running setup.py install for mozprofile 13:34:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:34:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:34:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:34:45 INFO - Running setup.py install for mozrunner 13:34:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:34:45 INFO - Running setup.py install for mozscreenshot 13:34:45 INFO - Running setup.py install for moztest 13:34:46 INFO - Running setup.py install for mozversion 13:34:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:34:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:34:46 INFO - Cleaning up... 13:34:46 INFO - Return code: 0 13:34:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:34:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:34:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:34:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:34:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:34:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34:46 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 0x7f3b480f5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x124dca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x124af60>, '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 0x124bd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x124c1d0>, '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': '6d15e502d8e29d5d2676d664000001c3-1485984850.840905-452561807', '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 13:34:46 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 13:34:46 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 13:34:46 INFO - Using env: {'DISPLAY': ':0', 13:34:46 INFO - 'HOME': '/home/cltbld', 13:34:46 INFO - 'LANG': 'en_US.UTF-8', 13:34:46 INFO - 'LANGUAGE': 'en_US:en', 13:34:46 INFO - 'LOGNAME': 'cltbld', 13:34:46 INFO - 'MAIL': '/var/mail/cltbld', 13:34:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:34:46 INFO - 'MOZ_NO_REMOTE': '1', 13:34:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:34:46 INFO - 'NO_EM_RESTART': '1', 13:34:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:34:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:34:46 INFO - 'PWD': '/builds/slave/test', 13:34:46 INFO - 'SHELL': '/bin/bash', 13:34:46 INFO - 'SHLVL': '1', 13:34:46 INFO - 'TERM': 'linux', 13:34:46 INFO - 'TMOUT': '86400', 13:34:46 INFO - 'USER': 'cltbld', 13:34:46 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1485984850.840905-452561807', 13:34:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:34:46 INFO - '_': '/tools/buildbot/bin/python'} 13:34:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:34:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:34:46 INFO - Running setup.py (path:/tmp/pip-Q_XJ80-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:34:46 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)) 13:34:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:34:46 INFO - Running setup.py (path:/tmp/pip-jaAhg6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:34:46 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)) 13:34:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:34:46 INFO - Running setup.py (path:/tmp/pip-nRuxbT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:34:46 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)) 13:34:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:34:46 INFO - Running setup.py (path:/tmp/pip-6Ji0Ue-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:34:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:34:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:34:46 INFO - Running setup.py (path:/tmp/pip-dmRhRV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:34:46 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)) 13:34:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:34:46 INFO - Running setup.py (path:/tmp/pip-Txm2R1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:34:46 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)) 13:34:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:34:46 INFO - Running setup.py (path:/tmp/pip-m9MZCr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:34:46 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)) 13:34:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:34:46 INFO - Running setup.py (path:/tmp/pip-Pq8oNJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:34:46 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)) 13:34:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:34:46 INFO - Running setup.py (path:/tmp/pip-3Degzi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:34:47 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)) 13:34:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:34:47 INFO - Running setup.py (path:/tmp/pip-Z2zyHX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:34:47 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)) 13:34:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:34:47 INFO - Running setup.py (path:/tmp/pip-HjVG4U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:34:47 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)) 13:34:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:34:47 INFO - Running setup.py (path:/tmp/pip-mBzWOG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:34:47 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)) 13:34:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:34:47 INFO - Running setup.py (path:/tmp/pip-oCbvaJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:34:47 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)) 13:34:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:34:47 INFO - Running setup.py (path:/tmp/pip-XHKkOp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:34:47 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)) 13:34:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:34:47 INFO - Running setup.py (path:/tmp/pip-iJKMHT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:34:47 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)) 13:34:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:34:47 INFO - Running setup.py (path:/tmp/pip-tEp54C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:34:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 13:34:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:34:47 INFO - Running setup.py (path:/tmp/pip-Hp7iUM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:34:47 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)) 13:34:47 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)) 13:34:47 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)) 13:34:47 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)) 13:34:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:34:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:34:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:34:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:34:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:34:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:34:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:34:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:34:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:34:49 INFO - Downloading blessings-1.6.tar.gz 13:34:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:34:49 INFO - Installing collected packages: blessings 13:34:49 INFO - Running setup.py install for blessings 13:34:49 INFO - Successfully installed blessings 13:34:49 INFO - Cleaning up... 13:34:49 INFO - Return code: 0 13:34:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:34:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:34:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:34:50 INFO - Reading from file tmpfile_stdout 13:34:50 INFO - Current package versions: 13:34:50 INFO - argparse == 1.2.1 13:34:50 INFO - blessings == 1.6 13:34:50 INFO - blobuploader == 1.2.4 13:34:50 INFO - docopt == 0.6.1 13:34:50 INFO - functools32 == 3.2.3-2 13:34:50 INFO - jsonschema == 2.5.1 13:34:50 INFO - manifestparser == 1.1 13:34:50 INFO - mozInstall == 1.12 13:34:50 INFO - mozcrash == 1.0 13:34:50 INFO - mozdebug == 0.1 13:34:50 INFO - mozdevice == 0.48 13:34:50 INFO - mozfile == 1.2 13:34:50 INFO - mozhttpd == 0.7 13:34:50 INFO - mozinfo == 0.9 13:34:50 INFO - mozleak == 0.1 13:34:50 INFO - mozlog == 3.4 13:34:50 INFO - moznetwork == 0.27 13:34:50 INFO - mozprocess == 0.23 13:34:50 INFO - mozprofile == 0.28 13:34:50 INFO - mozrunner == 6.13 13:34:50 INFO - mozscreenshot == 0.1 13:34:50 INFO - mozsystemmonitor == 0.3 13:34:50 INFO - moztest == 0.8 13:34:50 INFO - mozversion == 1.4 13:34:50 INFO - psutil == 3.1.1 13:34:50 INFO - requests == 1.2.3 13:34:50 INFO - wsgiref == 0.1.2 13:34:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:34:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:34:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:34:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:34:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:34:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34:50 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 0x7f3b480f5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x124dca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x124af60>, '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 0x124bd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x124c1d0>, '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': '6d15e502d8e29d5d2676d664000001c3-1485984850.840905-452561807', '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 13:34:50 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 13:34:50 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 13:34:50 INFO - Using env: {'DISPLAY': ':0', 13:34:50 INFO - 'HOME': '/home/cltbld', 13:34:50 INFO - 'LANG': 'en_US.UTF-8', 13:34:50 INFO - 'LANGUAGE': 'en_US:en', 13:34:50 INFO - 'LOGNAME': 'cltbld', 13:34:50 INFO - 'MAIL': '/var/mail/cltbld', 13:34:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:34:50 INFO - 'MOZ_NO_REMOTE': '1', 13:34:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:34:50 INFO - 'NO_EM_RESTART': '1', 13:34:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:34:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:34:50 INFO - 'PWD': '/builds/slave/test', 13:34:50 INFO - 'SHELL': '/bin/bash', 13:34:50 INFO - 'SHLVL': '1', 13:34:50 INFO - 'TERM': 'linux', 13:34:50 INFO - 'TMOUT': '86400', 13:34:50 INFO - 'USER': 'cltbld', 13:34:50 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1485984850.840905-452561807', 13:34:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:34:50 INFO - '_': '/tools/buildbot/bin/python'} 13:34:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:34:50 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)) 13:34:50 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)) 13:34:50 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)) 13:34:50 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)) 13:34:50 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)) 13:34:50 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)) 13:34:50 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)) 13:34:50 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)) 13:34:50 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)) 13:34:50 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)) 13:34:50 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)) 13:34:50 INFO - Cleaning up... 13:34:50 INFO - Return code: 0 13:34:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:34:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:34:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:34:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:34:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:34:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:34:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3b480f5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x124dca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x124af60>, '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 0x124bd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x124c1d0>, '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': '6d15e502d8e29d5d2676d664000001c3-1485984850.840905-452561807', '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 13:34:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 13:34:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 13:34:50 INFO - Using env: {'DISPLAY': ':0', 13:34:50 INFO - 'HOME': '/home/cltbld', 13:34:50 INFO - 'LANG': 'en_US.UTF-8', 13:34:50 INFO - 'LANGUAGE': 'en_US:en', 13:34:50 INFO - 'LOGNAME': 'cltbld', 13:34:50 INFO - 'MAIL': '/var/mail/cltbld', 13:34:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:34:50 INFO - 'MOZ_NO_REMOTE': '1', 13:34:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:34:50 INFO - 'NO_EM_RESTART': '1', 13:34:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:34:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:34:50 INFO - 'PWD': '/builds/slave/test', 13:34:50 INFO - 'SHELL': '/bin/bash', 13:34:50 INFO - 'SHLVL': '1', 13:34:50 INFO - 'TERM': 'linux', 13:34:50 INFO - 'TMOUT': '86400', 13:34:50 INFO - 'USER': 'cltbld', 13:34:50 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1485984850.840905-452561807', 13:34:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:34:50 INFO - '_': '/tools/buildbot/bin/python'} 13:34:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:34:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:34:50 INFO - Cleaning up... 13:34:50 INFO - Return code: 0 13:34:50 INFO - Running post-action listener: _resource_record_post_action 13:34:50 INFO - Running post-action listener: _start_resource_monitoring 13:34:50 INFO - Starting resource monitoring. 13:34:50 INFO - [mozharness: 2017-02-01 21:34:50.556241Z] Finished create-virtualenv step (success) 13:34:50 INFO - [mozharness: 2017-02-01 21:34:50.556761Z] Running install step. 13:34:50 INFO - Running pre-action listener: _resource_record_pre_action 13:34:50 INFO - Running main action method: install 13:34:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:34:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:34:50 INFO - Reading from file tmpfile_stdout 13:34:50 INFO - Detecting whether we're running mozinstall >=1.0... 13:34:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:34:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:34:50 INFO - Reading from file tmpfile_stdout 13:34:50 INFO - Output received: 13:34:50 INFO - Usage: mozinstall [options] installer 13:34:50 INFO - Options: 13:34:50 INFO - -h, --help show this help message and exit 13:34:50 INFO - -d DEST, --destination=DEST 13:34:50 INFO - Directory to install application into. [default: 13:34:50 INFO - "/builds/slave/test"] 13:34:50 INFO - --app=APP Application being installed. [default: firefox] 13:34:50 INFO - mkdir: /builds/slave/test/build/application 13:34:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 13:34:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 13:35:05 INFO - Reading from file tmpfile_stdout 13:35:05 INFO - Output received: 13:35:05 INFO - /builds/slave/test/build/application/firefox/firefox 13:35:05 INFO - Running post-action listener: _resource_record_post_action 13:35:05 INFO - [mozharness: 2017-02-01 21:35:05.049723Z] Finished install step (success) 13:35:05 INFO - [mozharness: 2017-02-01 21:35:05.049967Z] Running run-tests step. 13:35:05 INFO - Running pre-action listener: _resource_record_pre_action 13:35:05 INFO - Running main action method: run_tests 13:35:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:35:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:35:05 INFO - Python 2.7.3 13:35:05 INFO - Return code: 0 13:35:05 INFO - grabbing minidump binary from tooltool 13:35:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:35:05 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 0x124af60>, '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 0x124bd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x124c1d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:35:05 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 13:35:05 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 13:35:05 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 13:35:05 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:35:05 INFO - Return code: 0 13:35:05 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 13:35:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:35:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:35:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 13:35:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:35:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZFbBpRivTiOec9RJz_EHSg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-023', '--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 13:35:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ZFbBpRivTiOec9RJz_EHSg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-023 --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 13:35:05 INFO - Using env: {'DISPLAY': ':0', 13:35:05 INFO - 'HOME': '/home/cltbld', 13:35:05 INFO - 'LANG': 'en_US.UTF-8', 13:35:05 INFO - 'LANGUAGE': 'en_US:en', 13:35:05 INFO - 'LOGNAME': 'cltbld', 13:35:05 INFO - 'MAIL': '/var/mail/cltbld', 13:35:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:35:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 13:35:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:35:05 INFO - 'MOZ_NO_REMOTE': '1', 13:35:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:35:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:35:05 INFO - 'NO_EM_RESTART': '1', 13:35:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:35:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:35:05 INFO - 'PWD': '/builds/slave/test', 13:35:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:35:05 INFO - 'SHELL': '/bin/bash', 13:35:05 INFO - 'SHLVL': '1', 13:35:05 INFO - 'TERM': 'linux', 13:35:05 INFO - 'TMOUT': '86400', 13:35:05 INFO - 'USER': 'cltbld', 13:35:05 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1485984850.840905-452561807', 13:35:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:35:05 INFO - '_': '/tools/buildbot/bin/python'} 13:35:05 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZFbBpRivTiOec9RJz_EHSg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-023', '--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 13:35:05 INFO - mozversion application_buildid: 20170201114508 13:35:05 INFO - mozversion application_changeset: a15e503db4d1d7261680fb497af39b65babff013 13:35:05 INFO - mozversion application_display_name: Firefox 13:35:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:35:05 INFO - mozversion application_name: Firefox 13:35:05 INFO - mozversion application_remotingname: firefox 13:35:05 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 13:35:05 INFO - mozversion application_vendor: Mozilla 13:35:05 INFO - mozversion application_version: 52.0 13:35:05 INFO - mozversion platform_buildid: 20170201114508 13:35:05 INFO - mozversion platform_changeset: a15e503db4d1d7261680fb497af39b65babff013 13:35:05 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 13:35:05 INFO - mozversion platform_version: 52.0 13:35:05 INFO - using testdate: 1485984905 13:35:05 INFO - actual date: 1485984905 13:35:05 INFO - starting webserver on 'localhost:53115' 13:35:05 INFO - SUITE-START | Running 2 tests 13:35:05 INFO - TEST-START | dromaeo_css 13:35:05 INFO - operating with platform_type : linux_ 13:35:05 INFO - Initialising browser for dromaeo_css test... 13:35:05 INFO - TEST-INFO | started process 4257 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbSo1_w/profile http://localhost:53115/getInfo.html) 13:35:09 INFO - PROCESS | 4257 | __metrics Screen width/height:1600/1200 13:35:09 INFO - PROCESS | 4257 | colorDepth:24 13:35:09 INFO - PROCESS | 4257 | Browser inner width/height: 1024/697 13:35:09 INFO - PROCESS | 4257 | __metrics 13:35:09 INFO - PROCESS | 4257 | 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" 13:35:09 INFO - PROCESS | 4257 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:35:10 INFO - PROCESS | 4257 | console.error: PushService: 13:35:10 INFO - PROCESS | 4257 | stateChangeProcessEnqueue: Error transitioning state 13:35:10 INFO - PROCESS | 4257 | UnknownError 13:35:10 INFO - TEST-INFO | 4257: exit 0 13:35:10 INFO - Browser initialized. 13:35:10 INFO - Running cycle 1/1 for dromaeo_css test... 13:35:10 INFO - TEST-INFO | started process 4348 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbSo1_w/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:35:22 INFO - PROCESS | 4348 | RSS: Main: 145027072 13:35:22 INFO - PROCESS | 4348 | 13:38:23 INFO - PROCESS | 4348 | Cycle 1(1): loaded http://localhost:53115/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53115/tests/dromaeo/cssquery-ext.html) 13:38:24 INFO - PROCESS | 4348 | RSS: Main: 240197632 13:38:24 INFO - PROCESS | 4348 | 13:41:27 INFO - PROCESS | 4348 | Cycle 1(1): loaded http://localhost:53115/tests/dromaeo/cssquery-ext.html (next: http://localhost:53115/tests/dromaeo/cssquery-jquery.html) 13:41:27 INFO - PROCESS | 4348 | RSS: Main: 238866432 13:41:27 INFO - PROCESS | 4348 | 13:44:29 INFO - PROCESS | 4348 | Cycle 1(1): loaded http://localhost:53115/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53115/tests/dromaeo/cssquery-mootools.html) 13:44:30 INFO - PROCESS | 4348 | RSS: Main: 210276352 13:44:30 INFO - PROCESS | 4348 | 13:47:32 INFO - PROCESS | 4348 | Cycle 1(1): loaded http://localhost:53115/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53115/tests/dromaeo/cssquery-prototype.html) 13:47:32 INFO - PROCESS | 4348 | RSS: Main: 218746880 13:47:32 INFO - PROCESS | 4348 | 13:50:35 INFO - PROCESS | 4348 | Cycle 1(1): loaded http://localhost:53115/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53115/tests/dromaeo/cssquery-yui.html) 13:50:35 INFO - PROCESS | 4348 | RSS: Main: 175095808 13:50:35 INFO - PROCESS | 4348 | 13:53:38 INFO - PROCESS | 4348 | Cycle 1(1): loaded http://localhost:53115/tests/dromaeo/cssquery-yui.html (next: http://localhost:53115/tests/dromaeo/cssquery-dojo.html) 13:53:38 INFO - PROCESS | 4348 | RSS: Main: 262168576 13:53:38 INFO - PROCESS | 4348 | 13:53:38 INFO - PROCESS | 4348 | __start_tp_report 13:53:38 INFO - PROCESS | 4348 | _x_x_mozilla_page_load 13:53:38 INFO - PROCESS | 4348 | _x_x_mozilla_page_load_details 13:53:38 INFO - PROCESS | 4348 | |i|pagename|runs| 13:53:38 INFO - PROCESS | 4348 | |0;dojo.html;3247.074583743633;3283.2448536836528;3285.342931413718;3306.30567580808;3328.866845326187;5551.611387202893;5622.381538030813;5624.493795558399;5629.49334559889;5649.18086877403;5505.476979686923;5529.751161197746;5537.003339398917;5537.446255374461;5538.030844602199;4984.825531106412;4988.875278118058;5017.19724844025;5052.873678158038;5057.089723849724;4842.685225460352;4929.876753081166;4935.333729946465;5049.091163590552;5097.133487307166;4872.123017856784;4937.950620493803;4952.108620448318;4964.726940018298;4971.37857767779;4955.727434991074;4955.776990035457;4964.255361695757;4964.726940018298;4997.525235102659;4799.040191961587;4836.758162091916;4891.926621100651;4908.8772780680765;4939.827106051307;5376.731163441851;5384.488473595042;5407.594430417735;5430.8099216527235;5447.264619276426;5829.795957141479;5840.8247752567495;5880.382559831199;5887.234659494238;5923.17075609415;5805.564582656276;5922.644641321492;5922.674252916088;5952.553558483131;5968.074948382964;5448.237246785453;5543;5616.213730077751;5616.466435688603;5641.830745077655;6161.507079433724;6169;6174.38256174388;6176.320604733476;6177.722002509898;5620.18507316445;5923.259592550931;5925.9407405925385;5930.436608522184;5932.911006334908;5728.828135155869;5912.497437717842;5970.552208584287;5979.760809567656;6067.605605635619;5965.940340596625;5983.970080149571;5986.07215881539;5991.730372133265;6027.819165425044;5953.493953013955;6006.549508786771;6021;6023.909641355383;6026.5178785697035;4517.570830771071;4537.795799189044;4545.636349092065;4574.519675434031;4588.197065513522;4110.979445102756;4120.072983578671;4142.79286035697;4154.439150714676;4160.563140870226;5703.600747947604;5712.000399930079;5713.914291285634;5718.027935251017;5729.083346664514;5835.44563266489;5879.764809407576;5910.911336329958;5923.940760592339;6020.157177995085;5718.228039214736;5721.227634269404;5736.053551164011;5738.799142030009;5749.310082790092;5154.685510249914;5162.664426812245;5167.509086636764;5167.689938603696;5170.922436163461;6058.757649694051;6107.297660769068;6177.135201071854;6236.6258024518675;6247.9062814057825;5903.793367232298;6001.549883758648;6002.189704389852;6046.123312119806;6080.118382834376;6229.034499652561;6254.4996400286955;6274.560780745304;6282.308946016026;6290.716917738621;5662.150677398423;5924.585279030599;5925.99258126111;5927.318358388668;5966.403359663999;5883.35283118848;5957.017092179742;5992.13114098462;6021.7591296347655;6114.633122012693;5874.324452687995;5900.1149827526215;5914.349421563538;5914.822555323347;5921.61509501881;5890.705464726832;5907.91138132937;5911.349751527243;5913.172155898092;5924.4667979882015;5499.807506737245;5620.297462817148;5756.597038207286;5784.710764461676;5798.797042103506;5897.587168898136;5904.734286957183;5907.379725128972;5915.58590898633;5921.911171332347;5548.69482178484;5610.018246806874;5751.626144300607;5759.107338362561;5763.6541807491685 13:53:38 INFO - PROCESS | 4348 | |1;ext.html;586.126671259823;608.0666177417663;643.9259485159207;659.6339031837331;675.9590231044192;33803.323933521315;44487.55512444874;45406.772966135126;45494.545054549446;49217.75391123039;44973;45127.32309015367;45204.77397613016;45404.31893521592;45476;8274.875876861823;8871.512066836322;9017.45895246284;9030.50332231727;9037.18665320121;8338.332933365347;10814.567417303298;11097.611583594577;11098.44507774612;11102.222844400896;10846.72883177919;10934.125269978382;10971.506282217319;10974.231803773739;10990.395528245943;10751.51618177184;10777.83833242494;10802.297850639723;10809.297395669257;10809.35143891369;10684.305520141166;10694.304870183412;10750.569977200903;10752.139828813677;10770.838437423445;37115.257694845954;37216.813915930245;37373;37416.06459838498;37523;3426.931461370759;3541.8406171722077;3751.5498140223076;3866.284737323576;4199.895002624959;32743.18142046444;32750.344993100243;32952.505712414335;32962.6703732962;33107.50338744921;4443.7111587746695;4490.581850107751;4541.818327266906;4548.363229147922;4623.930641040387;333025;334481;334757;336456.99999999756;336632;37507.81246093752;61266.69366653227;61635;61644;61786.69106654438;38575.42136867949;41217.587824121976;41479;41484.1703165941;41564.79217603953;15820.841791581937;15926.203689815695;15931;15942.123183224801;15961.281742321624;11897.583584574499;12143.757124857453;12258.632241032783;13082.869171308355;13162.14446061003;9365.953170234105;9553.522323883917;9615.759606009906;9649.855252171223;9682.467464289392;3610.151614370621;3616.4032934565503;3639.3994990826745;3649.59854416014;3657.5428071491065;1036.9429681367446;1085.484394912426;1111.8325757954117;1163.1898666162572;1178.7171078940992;31460.842695786374;32767;32850;32902.34195316128;33183.17042073919;1067.8184708599556;1107.9944600276947;1113.4098927568402;1135.392564977733;1168.3457263932225;14669.92665036668;17901.7314740279;18483.260669573065;18574.44276671702;18904.432867014013;515777;516793;518549;519889;519891;59956.40043599595;60316.396836031076;60517.09224361638;60577.69711151416;60751.392486075456;1325.4698120751777;1344.3547097393111;1403.4175817035816;1420.4531255466513;1423.4804296431728;33960.320793584484;35443.64556354507;35595.64404355923;36271.27457450889;36499.63500364962;57055.71472142613;57944;58089.709551451975;58142;58195.418045819;349617;349785;350364;350565;351684;30300;30430.239244019078;30811.691883080883;30859.382812343178;30893.845530772203;49264;49675.50324496709;49723.25415118704;49798;50205.49794502155;42267.5773242276;42897.04649305269;43035.35446968235;43744;44500.55499445093;42801.57198428101;43102;43327;43336;43725.125497489236;41209.58790412058;41619.375709363776;42547.36178957256;43033.13933721245;43199.13601727885;35491.290174195856;37090;37736.433953491265;37879.43180852345;38203.61796382112;28455.28861778472;28776.56835147516;28876.133715988555;29093.41813163767;29143.271418213866 13:53:38 INFO - PROCESS | 4348 | |2;jquery.html;380.2319314983731;385.3352966133418;418.1615860200298;420.145004914998;422.30741583802563;13980.580582582514;19476.90261548692;20174.6973795393;20227;20522.38432847017;14630.780538291934;16130.919345403287;18949.715754263663;19177.520561985923;19687;5214.895702085965;5454.53636440903;5461.644993075457;5461.890762184754;5513.862153446176;5479.315085614298;5479.835604931858;5481.671099734029;5511.58663100267;5610.69141197234;5921.763129474816;5940.04959206527;5950.137230101633;5952.821415357546;5955.731992060368;5518.420565840569;5790.305163380379;5942.138389933457;5952.315483719384;5952.672603006833;5730.19777231188;5734.455226753452;5736.655800651933;5737.196792449061;5742.799002034909;14816.851831481656;15187.164705941168;22693.886530567408;23323.650145247666;23561.88219058911;2363.314638754759;2421.055788242587;2435.5250726108416;2462.2367066209354;2640.577507598775;10581.470926453647;10862.076723478514;11142.27575207609;13367;14799.186044767534;1977.7995982594525;2032.3598066609118;2059.804318589732;2095.8847263400507;2105.178980197709;81318.18681813165;82045;82140;84197.57901210454;84215.15784842134;11309;11393.202475826612;11581.247218930912;11650.126240531994;11735.589254376056;16184.352625895068;16217.59456013609;16302.918485407497;16377.181140942905;16440.342386304652;7520.887186692204;7944.523328600302;8169.63236654352;8349.83300334002;8423.915760842436;8166.918330816735;8215.055268644062;8226.696524973016;8414.284785793154;8455.408121431441;4910.631702622246;4980.477049909779;5005.799768009311;5019.146745053276;5125.718085505258;2007.6988451732357;2052.056054215048;2066.132224465728;2069.275753486298;2102.9789702102785;883.9204471597587;970.6360114956891;977.4330888084894;997.2520609542843;1010.4291075542295;19115.13981870819;19848.404547863298;19939.202431902562;20073.29743459001;20193.39419817408;1009.5103874620803;1009.9949500252452;1012.5899010900597;1028.9742756431149;1076.5855145768778;10207.948960255151;11123.16576256784;11196.38419886914;11204.663860084047;11330.830037549444;354303;363773;366085;487304;495635;77388.61305693549;98328;100385;100866.49566752119;105117;1048.5334026358198;1067.0929709746654;1089.9019088282093;1113.6213687346178;1114.1309778372881;17937.37219197322;18210.180541875914;18390.63218735591;18431.292847476467;18571.535711607317;43247.13505729931;43408.34887476754;44755.32867007062;45254.773726131156;46526.30210546777;46358;56472.43527564672;63314.683426584415;63523.36476635175;64203.35796641974;8498.065212826461;8625.956870215608;8756.168164024408;8789.121087891159;8823.558822059;10988.846171152234;12649.177803442746;13330.800037999632;13403.731925361242;13556.525521606696;10316.48417579103;11912.166148369532;12049.51801927913;12116.515339386326;12117.27296362221;11819.881801181878;12188.20776649515;12282.570110046105;12312.630621081382;12485;12062.095342849147;12236.327002014974;12299.93850030744;12385.876141238472;12443.875561244271;7699.3840492761565;8851.33614978892;8879.778005549913;8895.599698013475;9002.81994360096;8820.162084601952;11907.749686282816;11942.283462992249;12246.632601021984;12320.383980800745 13:53:38 INFO - PROCESS | 4348 | |3;mootools.html;1503.120674405473;1526.5725596832888;1649.1259632394836;1742.416290542668;1776.5292197567646;8235.464694794835;8744.256738177262;8751.868721969175;8754.649814007433;8758.64965401384;7467.8879816802655;7584.658690358933;8282.83434331313;8361.540115293672;8600.8709869352;2452.926412207632;2484.5155194737035;2506.987465062672;2521.596544552872;2523.1673547729297;2454.398672325283;2506.949861002779;2507.4608959073794;2511.962320565193;2516.207394670682;2463.815213858959;2480.2807185916063;2519.420533277347;2521.2940376694555;2521.470491196851;2408.5664580375524;2419.0323870451784;2419.2863105383867;2421.225207933462;2438.756124387565;2418.830681852271;2432.659427680126;2433.2578563538113;2453.7055553333535;2454.9263522094366;8526.190011948855;8640.827183456297;8655.567221638892;8664.653413863438;8668.826623467496;5298.046351656701;5432.076546987022;5432.91850622241;5433.375161856397;5449;3350.7654464187517;3371.0729549373873;3377;3454.3091381723757;3470.2538954124734;1036.6216331039116;1054.541274545575;1063.6330465989222;1073.366713638949;1079.6221322537128;11841.585544505988;11858.229215100991;11867.347295898722;11886.762264754743;11888.762224755457;4778.961777298177;4810.5670489656095;4845.781939812717;4936.3829521309835;4962.15643340633;10001.299909006446;10014.49927503622;10016.549255283384;10029.64896228643;10031.498425078717;5550.833474995757;5560.833175004756;5561.833145005657;5562.443755624405;5562.916556251659;4487.080148569536;4646.767661616906;4947.332110165154;5026.52248036436;5033.546980771748;4069.944320766455;4110.383442483651;4133.318002529609;4138.441310423114;4165.83336666536;1344.6729120355762;1369.7671395862728;1380.0822453068797;1383.508854356689;1391.1513976474344;890.7505898348472;905.6513242401722;917.5412293853074;991.3853410885154;991.5686676295834;5584.860378490489;5639.43605639441;5649.943500564942;5757.222774925415;5861.120831875252;900.4687234531527;922.4880191493785;926.5830376330543;950.4819873169139;969.8545218217183;3944.546377166605;4128.401381799622;4133.8966525836495;4144.606262405066;4251.29853574163;10330.948345258226;10713.250072494851;10723.571057157626;10732;10743.14054875608;10550.525226364833;10588.205884558689;10634.89365106339;10648.520816563274;10684.198685098649;7749.806254843674;8195.508269503849;8333.250007499355;8338.458000229966;8357.12250213731;5430.891382172255;5583.972080139573;5687.260656114679;5707.400722924033;5725.541956643542;9012.549372531215;9033.864492032493;9101.499417531835;9363.063693630855;9611.375260608038;9391.154796068387;9394.342396032212;9421.481818500048;9524.61901523959;9547.713568592953;7045.295470452914;7235.891461628185;7484.738034168778;7608.695652173851;7786.143524212219;8185.140560241089;8447.324214062983;8448.028476725009;8449.28181104613;8451.366147539082;7135.464840137114;7276.745313914199;7382.114146302478;7400.333969942731;7634.122075961335;7161.892571611325;7254.782356529313;7296.890546641911;7310.926890731024;7312.744053958086;6790.117284752951;6925.619090949845;6926.376626103674;6926.757563485254;6927.099477067949;4937.654364194472;4980.252962055576;5040.672356296829;5071;5207.3230480037355;4885.828995985124;4973.975130124326;4994.925076123934;5037.395512538519;5049.469805670352 13:53:38 INFO - PROCESS | 4348 | |4;prototype.html;339.2841105267884;349.7342020064751;351.5359725162786;352.2461931466661;354.854509651043;5334;5398.946010539893;5423.566114710824;5432.70120143392;5434.266374039508;5045.243213517974;5151.974240128803;5160.632432405416;5239.52844244018;5293.444188360225;4196.601322874338;4198.727082739612;4243.639290660298;4247.766372849492;4249.3413520904305;4190.664746820247;4198.727082739628;4254.595813397722;4258.638015768648;4266.466691663542;4090.97954510227;4171.895702607444;4184.916301673965;4191.245575796355;4195.979020104895;4166.250074986501;4173.478315210584;4175.958240417587;4180.7909604519655;4193.874183774461;3727.34771415004;3732.981335093334;3733.1227161617;3743.3074881147077;3743.9064023399633;5075.111855425286;5148.5623721983675;5154.433012368637;5171;5171.560417364529;4808.519148085198;4823.456493921946;4823.756953016864;4837.637177211688;4839.354619429411;5533.723313834333;5539.667619942817;5555.444455554452;5562.554995600342;5569;3992.5208974922903;4086.3870419437026;4127.628513433775;4170.957260684829;4178.456800615901;5665.433456654343;5715.485606295411;5718.714064296769;5731.799387021434;5733.598648094635;4212.599803018708;4220.683448741357;4245.214635292419;4247.97876010618;4263.189993901211;4029.2545879012478;4164.916701665949;4259.936100958488;4300;4306.375575541591;4758.262469317261;4758.762061896891;4767.904641907143;4773.64197685175;4774.236122220428;5581.832545023656;5585.301837270341;5596.460973232393;5604;5638.323401191801;1410.541573988458;1412.3009110490373;1426.8430472648001;1426.86444787745;1427.0937954398823;939.0327962198946;952.9952350238204;958.7727708533089;965.6764983730388;966.3139171188535;5202.635815492955;5243.318368612056;5256.710880901511;5307.8142265021315;5331.120365139708;5307.310049693515;5403.729813509309;5485.50630443262;5486.753096110605;5505.366882808448;5180.523550788007;5203.5576975957465;5236.790528378822;5265.4997775211295;5272.947270527246;2766.51585972454;2877.7841661875023;2888.407876385336;2927.2828157101644;2928.560715892633;5613.214150019001;5661.34894487131;5672.404397538199;5690.374058853523;5723.914141287884;5531.751071201806;5538.778448862081;5548.059528449928;5556.16657501378;5566.858793947231;5660.971695141498;5663.405342439067;5681.431856814285;5948.7323070461935;5981.102834574762;5726.427357264407;5754.223179870663;5776.942230577641;5782.335031471434;5797.681127537857;5488.670679759227;5703.771849125988;5734.827955161351;5752.856178595569;5778.797742079007;5985.281766187911;5986.4013598639785;6016.338202797601;6053.3946605339115;6069.726862291119;4160.355144952538;4164.791760411907;4166.270902592095;4190.455240818796;4208.158368326286;5604.971975140098;5615.859603509945;5673.205751194919;5708.372079071216;5895.467178533527;5552.916706249329;5565.634605292816;5588;5595.013770111715;5600.299962504687;5397.78408863641;5586.664800112007;5605.747741351731;5606.579506536945;5613.915791263216;5579.19101730255;5603.795184035452;5618.747156377891;5628.605997580294;5634.774609015593;5679.37526872052;5702.6293290935955;5709.314882214161;5711.657760426379;5722.198892155185;5409.8523144018545;5439.129739241703;5499.257600223918;5502.477264659851;5545.861353466195 13:53:38 INFO - PROCESS | 4348 | |5;yui.html;798.8202654402756;808.5795386399072;846.2848892685681;872.2803686958259;896.686159844055;14825.332860021297;15616.76574851378;15751.60620984476;15782.921085394557;15794.052356858596;14502.637434064154;14772.778408323857;14783.482578109768;14883.553493395215;14918.104913705185;713.407871466684;749.2694622742837;844.1178967978477;855.7732200966749;856.3620103023226;853.6030745703247;870.29071306885;870.6169285514385;870.8171284030361;874.4578361415931;915.3226612306878;919.296737995434;919.3426699909563;920.1028996728203;920.3603495570582;914.3919293669702;914.7392992996997;914.7530166854946;914.8170365926775;915.6978197194976;682.6245564939263;683.4976292425064;683.4976292425064;683.7641013850214;683.7948615415355;15197.544073677807;15255.466058688004;15447;15459.458918937895;15553.22233888301;14705;15545.689086218325;15603.297851596706;15891.92054039734;16040.438584649599;11632.185746997713;11704.707382315424;11790.17468777186;11857.703557411047;11973.820392694117;3609.819509024538;3614.5120408744747;3629.891103266906;3655.141041855162;3745.4381842723537;69453;69614.3038569618;70263.29736702619;72743.2725672747;72894.54210915805;10522.684319470274;11159.051480624237;11522.07823374128;11543.249688770204;11944.64166075019;20802.375928722162;21138;21184.470388240417;21224.469388265417;21351.03920323589;8112.6349314281;8371.874421883676;8453.66185352579;8495.787605309917;8567.314614830799;4525.185466615975;4613.861584152481;4647.3029045643425;4655.162070827216;4796.112719146969;3439.329330780474;3483.651634836496;3489.1102768793794;3563.6792688658147;3565.9286814264087;570.4267211452463;580.6109906362747;596.5943158652158;608.3764141754737;615.6552330694825;624.5596854217765;631.504269148716;662.2318110991228;687.3058211206717;707.9981825716553;9068.365214434927;9119.316051296046;9329.148842601944;9559.283053770994;9791.216702663769;686.8935315026179;696.8989496522976;709.7054722290295;719.4100837313352;729.0886392009987;4318.589733975267;4545.681802273809;4587.082583483317;4621;4651.486803869288;52776.208356874675;70562;72429.2757072433;74000.62999685075;78150.21849781429;46718.065638687716;62213.06680399798;73144;74059.88910166352;75119;69106.308936911;69707;69916;71344.28655713376;71820;9056.864147037932;9533.761655958657;9589.472579008221;9683.854742178732;9698.321117521706;46525.767371162925;46935.06129877315;47171.29243061425;48162.759186203846;48175.759121204166;61279;65594;66719.66640166768;67045.66477167583;68506.31493684999;11891.227070240408;11963.820542692041;12128.332941688292;12201.389930503618;12334.321612311409;13063.412146453242;13439.529616463376;13646.181229126285;13646.931765341109;13711.794323084961;12473.313967731861;13103.60689179326;13112.672183195496;13172.802407963696;13417.597472075853;12156.513739450324;12597;12598.992080633345;12727.19144433913;13261.535846245335;12061.939690301493;12071.517139314681;12311.967136103423;12771.872281277068;12979.206709767679;10111.556631863139;10270;10691.572337106429;10758;10933.617323393642;10776.568937242422;10853.620123295646;10855.45722713877;11009.779804403706;11129.66611001639 13:53:38 INFO - PROCESS | 4348 | __end_tp_report 13:53:38 INFO - PROCESS | 4348 | __start_cc_report 13:53:38 INFO - PROCESS | 4348 | _x_x_mozilla_cycle_collect,965 13:53:38 INFO - PROCESS | 4348 | __end_cc_report 13:53:38 INFO - PROCESS | 4348 | __startTimestamp1485986018547__endTimestamp 13:53:38 INFO - PROCESS | 4348 | 13:53:38 INFO - PROCESS | 4348 | ------- Summary: start ------- 13:53:38 INFO - PROCESS | 4348 | Number of tests: 6 13:53:38 INFO - PROCESS | 4348 | 13:53:38 INFO - PROCESS | 4348 | [#0] dojo.html Cycles:165 Average:5532.70 Median:5751.63 stddev:640.29 (11.1%) stddev-sans-first:616.64 13:53:38 INFO - PROCESS | 4348 | Values: 3247.1 3283.2 3285.3 3306.3 3328.9 5551.6 5622.4 5624.5 5629.5 5649.2 5505.5 5529.8 5537.0 5537.4 5538.0 4984.8 4988.9 5017.2 5052.9 5057.1 4842.7 4929.9 4935.3 5049.1 5097.1 4872.1 4938.0 4952.1 4964.7 4971.4 4955.7 4955.8 4964.3 4964.7 4997.5 4799.0 4836.8 4891.9 4908.9 4939.8 5376.7 5384.5 5407.6 5430.8 5447.3 5829.8 5840.8 5880.4 5887.2 5923.2 5805.6 5922.6 5922.7 5952.6 5968.1 5448.2 5543.0 5616.2 5616.5 5641.8 6161.5 6169.0 6174.4 6176.3 6177.7 5620.2 5923.3 5925.9 5930.4 5932.9 5728.8 5912.5 5970.6 5979.8 6067.6 5965.9 5984.0 5986.1 5991.7 6027.8 5953.5 6006.5 6021.0 6023.9 6026.5 4517.6 4537.8 4545.6 4574.5 4588.2 4111.0 4120.1 4142.8 4154.4 4160.6 5703.6 5712.0 5713.9 5718.0 5729.1 5835.4 5879.8 5910.9 5923.9 6020.2 5718.2 5721.2 5736.1 5738.8 5749.3 5154.7 5162.7 5167.5 5167.7 5170.9 6058.8 6107.3 6177.1 6236.6 6247.9 5903.8 6001.5 6002.2 6046.1 6080.1 6229.0 6254.5 6274.6 6282.3 6290.7 5662.2 5924.6 5926.0 5927.3 5966.4 5883.4 5957.0 5992.1 6021.8 6114.6 5874.3 5900.1 5914.3 5914.8 5921.6 5890.7 5907.9 5911.3 5913.2 5924.5 5499.8 5620.3 5756.6 5784.7 5798.8 5897.6 5904.7 5907.4 5915.6 5921.9 5548.7 5610.0 5751.6 5759.1 5763.7 13:53:38 INFO - PROCESS | 4348 | 13:53:38 INFO - PROCESS | 4348 | [#1] ext.html Cycles:180 Average:56833.96 Median:20074.62 stddev:108397.33 (540.0%) stddev-sans-first:108619.15 13:53:38 INFO - PROCESS | 4348 | Values: 586.1 608.1 643.9 659.6 676.0 33803.3 44487.6 45406.8 45494.5 49217.8 44973.0 45127.3 45204.8 45404.3 45476.0 8274.9 8871.5 9017.5 9030.5 9037.2 8338.3 10814.6 11097.6 11098.4 11102.2 10846.7 10934.1 10971.5 10974.2 10990.4 10751.5 10777.8 10802.3 10809.3 10809.4 10684.3 10694.3 10750.6 10752.1 10770.8 37115.3 37216.8 37373.0 37416.1 37523.0 3426.9 3541.8 3751.5 3866.3 4199.9 32743.2 32750.3 32952.5 32962.7 33107.5 4443.7 4490.6 4541.8 4548.4 4623.9 333025.0 334481.0 334757.0 336457.0 336632.0 37507.8 61266.7 61635.0 61644.0 61786.7 38575.4 41217.6 41479.0 41484.2 41564.8 15820.8 15926.2 15931.0 15942.1 15961.3 11897.6 12143.8 12258.6 13082.9 13162.1 9366.0 9553.5 9615.8 9649.9 9682.5 3610.2 3616.4 3639.4 3649.6 3657.5 1036.9 1085.5 1111.8 1163.2 1178.7 31460.8 32767.0 32850.0 32902.3 33183.2 1067.8 1108.0 1113.4 1135.4 1168.3 14669.9 17901.7 18483.3 18574.4 18904.4 515777.0 516793.0 518549.0 519889.0 519891.0 59956.4 60316.4 60517.1 60577.7 60751.4 1325.5 1344.4 1403.4 1420.5 1423.5 33960.3 35443.6 35595.6 36271.3 36499.6 57055.7 57944.0 58089.7 58142.0 58195.4 349617.0 349785.0 350364.0 350565.0 351684.0 30300.0 30430.2 30811.7 30859.4 30893.8 49264.0 49675.5 49723.3 49798.0 50205.5 42267.6 42897.0 43035.4 43744.0 44500.6 42801.6 43102.0 43327.0 43336.0 43725.1 41209.6 41619.4 42547.4 43033.1 43199.1 35491.3 37090.0 37736.4 37879.4 38203.6 28455.3 28776.6 28876.1 29093.4 29143.3 13:53:38 INFO - PROCESS | 4348 | 13:53:38 INFO - PROCESS | 4348 | [#2] jquery.html Cycles:180 Average:27205.14 Median:23008.77 stddev:69678.87 (302.8%) stddev-sans-first:69845.23 13:53:38 INFO - PROCESS | 4348 | Values: 380.2 385.3 418.2 420.1 422.3 13980.6 19476.9 20174.7 20227.0 20522.4 14630.8 16130.9 18949.7 19177.5 19687.0 5214.9 5454.5 5461.6 5461.9 5513.9 5479.3 5479.8 5481.7 5511.6 5610.7 5921.8 5940.0 5950.1 5952.8 5955.7 5518.4 5790.3 5942.1 5952.3 5952.7 5730.2 5734.5 5736.7 5737.2 5742.8 14816.9 15187.2 22693.9 23323.7 23561.9 2363.3 2421.1 2435.5 2462.2 2640.6 10581.5 10862.1 11142.3 13367.0 14799.2 1977.8 2032.4 2059.8 2095.9 2105.2 81318.2 82045.0 82140.0 84197.6 84215.2 11309.0 11393.2 11581.2 11650.1 11735.6 16184.4 16217.6 16302.9 16377.2 16440.3 7520.9 7944.5 8169.6 8349.8 8423.9 8166.9 8215.1 8226.7 8414.3 8455.4 4910.6 4980.5 5005.8 5019.1 5125.7 2007.7 2052.1 2066.1 2069.3 2103.0 883.9 970.6 977.4 997.3 1010.4 19115.1 19848.4 19939.2 20073.3 20193.4 1009.5 1010.0 1012.6 1029.0 1076.6 10207.9 11123.2 11196.4 11204.7 11330.8 354303.0 363773.0 366085.0 487304.0 495635.0 77388.6 98328.0 100385.0 100866.5 105117.0 1048.5 1067.1 1089.9 1113.6 1114.1 17937.4 18210.2 18390.6 18431.3 18571.5 43247.1 43408.3 44755.3 45254.8 46526.3 46358.0 56472.4 63314.7 63523.4 64203.4 8498.1 8626.0 8756.2 8789.1 8823.6 10988.8 12649.2 13330.8 13403.7 13556.5 10316.5 11912.2 12049.5 12116.5 12117.3 11819.9 12188.2 12282.6 12312.6 12485.0 12062.1 12236.3 12299.9 12385.9 12443.9 7699.4 8851.3 8879.8 8895.6 9002.8 8820.2 11907.7 11942.3 12246.6 12320.4 13:53:38 INFO - PROCESS | 4348 | 13:53:38 INFO - PROCESS | 4348 | [#3] mootools.html Cycles:180 Average:5686.49 Median:5039.03 stddev:3106.71 (61.7%) stddev-sans-first:3099.51 13:53:38 INFO - PROCESS | 4348 | Values: 1503.1 1526.6 1649.1 1742.4 1776.5 8235.5 8744.3 8751.9 8754.6 8758.6 7467.9 7584.7 8282.8 8361.5 8600.9 2452.9 2484.5 2507.0 2521.6 2523.2 2454.4 2506.9 2507.5 2512.0 2516.2 2463.8 2480.3 2519.4 2521.3 2521.5 2408.6 2419.0 2419.3 2421.2 2438.8 2418.8 2432.7 2433.3 2453.7 2454.9 8526.2 8640.8 8655.6 8664.7 8668.8 5298.0 5432.1 5432.9 5433.4 5449.0 3350.8 3371.1 3377.0 3454.3 3470.3 1036.6 1054.5 1063.6 1073.4 1079.6 11841.6 11858.2 11867.3 11886.8 11888.8 4779.0 4810.6 4845.8 4936.4 4962.2 10001.3 10014.5 10016.5 10029.6 10031.5 5550.8 5560.8 5561.8 5562.4 5562.9 4487.1 4646.8 4947.3 5026.5 5033.5 4069.9 4110.4 4133.3 4138.4 4165.8 1344.7 1369.8 1380.1 1383.5 1391.2 890.8 905.7 917.5 991.4 991.6 5584.9 5639.4 5649.9 5757.2 5861.1 900.5 922.5 926.6 950.5 969.9 3944.5 4128.4 4133.9 4144.6 4251.3 10330.9 10713.3 10723.6 10732.0 10743.1 10550.5 10588.2 10634.9 10648.5 10684.2 7749.8 8195.5 8333.3 8338.5 8357.1 5430.9 5584.0 5687.3 5707.4 5725.5 9012.5 9033.9 9101.5 9363.1 9611.4 9391.2 9394.3 9421.5 9524.6 9547.7 7045.3 7235.9 7484.7 7608.7 7786.1 8185.1 8447.3 8448.0 8449.3 8451.4 7135.5 7276.7 7382.1 7400.3 7634.1 7161.9 7254.8 7296.9 7310.9 7312.7 6790.1 6925.6 6926.4 6926.8 6927.1 4937.7 4980.3 5040.7 5071.0 5207.3 4885.8 4974.0 4994.9 5037.4 5049.5 13:53:38 INFO - PROCESS | 4348 | 13:53:38 INFO - PROCESS | 4348 | [#4] prototype.html Cycles:180 Average:4698.30 Median:5332.56 stddev:1362.46 (25.5%) stddev-sans-first:1326.42 13:53:38 INFO - PROCESS | 4348 | Values: 339.3 349.7 351.5 352.2 354.9 5334.0 5398.9 5423.6 5432.7 5434.3 5045.2 5152.0 5160.6 5239.5 5293.4 4196.6 4198.7 4243.6 4247.8 4249.3 4190.7 4198.7 4254.6 4258.6 4266.5 4091.0 4171.9 4184.9 4191.2 4196.0 4166.3 4173.5 4176.0 4180.8 4193.9 3727.3 3733.0 3733.1 3743.3 3743.9 5075.1 5148.6 5154.4 5171.0 5171.6 4808.5 4823.5 4823.8 4837.6 4839.4 5533.7 5539.7 5555.4 5562.6 5569.0 3992.5 4086.4 4127.6 4171.0 4178.5 5665.4 5715.5 5718.7 5731.8 5733.6 4212.6 4220.7 4245.2 4248.0 4263.2 4029.3 4164.9 4259.9 4300.0 4306.4 4758.3 4758.8 4767.9 4773.6 4774.2 5581.8 5585.3 5596.5 5604.0 5638.3 1410.5 1412.3 1426.8 1426.9 1427.1 939.0 953.0 958.8 965.7 966.3 5202.6 5243.3 5256.7 5307.8 5331.1 5307.3 5403.7 5485.5 5486.8 5505.4 5180.5 5203.6 5236.8 5265.5 5272.9 2766.5 2877.8 2888.4 2927.3 2928.6 5613.2 5661.3 5672.4 5690.4 5723.9 5531.8 5538.8 5548.1 5556.2 5566.9 5661.0 5663.4 5681.4 5948.7 5981.1 5726.4 5754.2 5776.9 5782.3 5797.7 5488.7 5703.8 5734.8 5752.9 5778.8 5985.3 5986.4 6016.3 6053.4 6069.7 4160.4 4164.8 4166.3 4190.5 4208.2 5605.0 5615.9 5673.2 5708.4 5895.5 5552.9 5565.6 5588.0 5595.0 5600.3 5397.8 5586.7 5605.7 5606.6 5613.9 5579.2 5603.8 5618.7 5628.6 5634.8 5679.4 5702.6 5709.3 5711.7 5722.2 5409.9 5439.1 5499.3 5502.5 5545.9 13:53:38 INFO - PROCESS | 4348 | 13:53:38 INFO - PROCESS | 4348 | [#5] yui.html Cycles:180 Average:17551.38 Median:46826.56 stddev:22385.73 (47.8%) stddev-sans-first:22413.18 13:53:38 INFO - PROCESS | 4348 | Values: 798.8 808.6 846.3 872.3 896.7 14825.3 15616.8 15751.6 15782.9 15794.1 14502.6 14772.8 14783.5 14883.6 14918.1 713.4 749.3 844.1 855.8 856.4 853.6 870.3 870.6 870.8 874.5 915.3 919.3 919.3 920.1 920.4 914.4 914.7 914.8 914.8 915.7 682.6 683.5 683.5 683.8 683.8 15197.5 15255.5 15447.0 15459.5 15553.2 14705.0 15545.7 15603.3 15891.9 16040.4 11632.2 11704.7 11790.2 11857.7 11973.8 3609.8 3614.5 3629.9 3655.1 3745.4 69453.0 69614.3 70263.3 72743.3 72894.5 10522.7 11159.1 11522.1 11543.2 11944.6 20802.4 21138.0 21184.5 21224.5 21351.0 8112.6 8371.9 8453.7 8495.8 8567.3 4525.2 4613.9 4647.3 4655.2 4796.1 3439.3 3483.7 3489.1 3563.7 3565.9 570.4 580.6 596.6 608.4 615.7 624.6 631.5 662.2 687.3 708.0 9068.4 9119.3 9329.1 9559.3 9791.2 686.9 696.9 709.7 719.4 729.1 4318.6 4545.7 4587.1 4621.0 4651.5 52776.2 70562.0 72429.3 74000.6 78150.2 46718.1 62213.1 73144.0 74059.9 75119.0 69106.3 69707.0 69916.0 71344.3 71820.0 9056.9 9533.8 9589.5 9683.9 9698.3 46525.8 46935.1 47171.3 48162.8 48175.8 61279.0 65594.0 66719.7 67045.7 68506.3 11891.2 11963.8 12128.3 12201.4 12334.3 13063.4 13439.5 13646.2 13646.9 13711.8 12473.3 13103.6 13112.7 13172.8 13417.6 12156.5 12597.0 12599.0 12727.2 13261.5 12061.9 12071.5 12312.0 12771.9 12979.2 10111.6 10270.0 10691.6 10758.0 10933.6 10776.6 10853.6 10855.5 11009.8 11129.7 13:53:38 INFO - PROCESS | 4348 | -------- Summary: end -------- 13:53:38 INFO - PROCESS | 4348 | 13:53:39 INFO - TEST-INFO | 4348: exit 0 13:53:39 INFO - TEST-OK | dromaeo_css | took 1113969ms 13:53:39 INFO - TEST-START | kraken 13:53:39 INFO - operating with platform_type : linux_ 13:53:39 INFO - Initialising browser for kraken test... 13:53:39 INFO - TEST-INFO | started process 4650 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZxQAHv/profile http://localhost:53115/getInfo.html) 13:53:43 INFO - PROCESS | 4650 | __metrics Screen width/height:1600/1200 13:53:43 INFO - PROCESS | 4650 | colorDepth:24 13:53:43 INFO - PROCESS | 4650 | Browser inner width/height: 1024/697 13:53:43 INFO - PROCESS | 4650 | __metrics 13:53:43 INFO - PROCESS | 4650 | 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" 13:53:43 INFO - PROCESS | 4650 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:53:44 INFO - PROCESS | 4650 | console.error: PushService: 13:53:44 INFO - PROCESS | 4650 | stateChangeProcessEnqueue: Error transitioning state 13:53:44 INFO - PROCESS | 4650 | UnknownError 13:53:44 INFO - TEST-INFO | 4650: exit 0 13:53:44 INFO - Browser initialized. 13:53:44 INFO - Running cycle 1/1 for kraken test... 13:53:44 INFO - TEST-INFO | started process 4744 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZxQAHv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:53:56 INFO - PROCESS | 4744 | RSS: Main: 142036992 13:53:56 INFO - PROCESS | 4744 | 13:53:58 INFO - PROCESS | 4744 | Cycle 1(1): loaded http://localhost:53115/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53115/tests/kraken/driver.html?testName=audio-beat-detection) 13:53:59 INFO - PROCESS | 4744 | RSS: Main: 183664640 13:53:59 INFO - PROCESS | 4744 | 13:54:02 INFO - PROCESS | 4744 | Cycle 1(1): loaded http://localhost:53115/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53115/tests/kraken/driver.html?testName=audio-dft) 13:54:02 INFO - PROCESS | 4744 | RSS: Main: 173457408 13:54:02 INFO - PROCESS | 4744 | 13:54:05 INFO - PROCESS | 4744 | Cycle 1(1): loaded http://localhost:53115/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53115/tests/kraken/driver.html?testName=audio-fft) 13:54:06 INFO - PROCESS | 4744 | RSS: Main: 264560640 13:54:06 INFO - PROCESS | 4744 | 13:54:08 INFO - PROCESS | 4744 | Cycle 1(1): loaded http://localhost:53115/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53115/tests/kraken/driver.html?testName=audio-oscillator) 13:54:08 INFO - PROCESS | 4744 | RSS: Main: 171393024 13:54:08 INFO - PROCESS | 4744 | 13:54:11 INFO - PROCESS | 4744 | Cycle 1(1): loaded http://localhost:53115/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53115/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:54:11 INFO - PROCESS | 4744 | RSS: Main: 173936640 13:54:11 INFO - PROCESS | 4744 | 13:54:14 INFO - PROCESS | 4744 | Cycle 1(1): loaded http://localhost:53115/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53115/tests/kraken/driver.html?testName=imaging-darkroom) 13:54:15 INFO - PROCESS | 4744 | RSS: Main: 212959232 13:54:15 INFO - PROCESS | 4744 | 13:54:18 INFO - PROCESS | 4744 | Cycle 1(1): loaded http://localhost:53115/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53115/tests/kraken/driver.html?testName=imaging-desaturate) 13:54:19 INFO - PROCESS | 4744 | RSS: Main: 211406848 13:54:19 INFO - PROCESS | 4744 | 13:54:21 INFO - PROCESS | 4744 | Cycle 1(1): loaded http://localhost:53115/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53115/tests/kraken/driver.html?testName=json-parse-financial) 13:54:22 INFO - PROCESS | 4744 | RSS: Main: 209985536 13:54:22 INFO - PROCESS | 4744 | 13:54:23 INFO - PROCESS | 4744 | Cycle 1(1): loaded http://localhost:53115/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53115/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:54:24 INFO - PROCESS | 4744 | RSS: Main: 172658688 13:54:24 INFO - PROCESS | 4744 | 13:54:26 INFO - PROCESS | 4744 | Cycle 1(1): loaded http://localhost:53115/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53115/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:54:26 INFO - PROCESS | 4744 | RSS: Main: 179294208 13:54:26 INFO - PROCESS | 4744 | 13:54:28 INFO - PROCESS | 4744 | Cycle 1(1): loaded http://localhost:53115/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53115/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:54:29 INFO - PROCESS | 4744 | RSS: Main: 189341696 13:54:29 INFO - PROCESS | 4744 | 13:54:31 INFO - PROCESS | 4744 | Cycle 1(1): loaded http://localhost:53115/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53115/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:54:32 INFO - PROCESS | 4744 | RSS: Main: 197943296 13:54:32 INFO - PROCESS | 4744 | 13:54:35 INFO - PROCESS | 4744 | Cycle 1(1): loaded http://localhost:53115/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53115/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:54:35 INFO - PROCESS | 4744 | RSS: Main: 193875968 13:54:35 INFO - PROCESS | 4744 | 13:54:37 INFO - PROCESS | 4744 | Cycle 1(1): loaded http://localhost:53115/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53115/tests/kraken/driver.html?testName=ai-astar) 13:54:37 INFO - PROCESS | 4744 | RSS: Main: 245792768 13:54:37 INFO - PROCESS | 4744 | 13:54:37 INFO - PROCESS | 4744 | __start_tp_report 13:54:37 INFO - PROCESS | 4744 | _x_x_mozilla_page_load 13:54:37 INFO - PROCESS | 4744 | _x_x_mozilla_page_load_details 13:54:37 INFO - PROCESS | 4744 | |i|pagename|runs| 13:54:37 INFO - PROCESS | 4744 | |0;ai-astar;102;112;111;112;111;112;112;112;112;111 13:54:37 INFO - PROCESS | 4744 | |1;audio-beat-detection;150;121;117;159;123;119;116;121;148;118 13:54:37 INFO - PROCESS | 4744 | |2;audio-dft;163;161;162;162;162;161;177;161;161;163 13:54:37 INFO - PROCESS | 4744 | |3;audio-fft;74;74;76;114;77;78;75;76;107;76 13:54:37 INFO - PROCESS | 4744 | |4;audio-oscillator;82;81;122;82;83;82;102;83;82;106 13:54:37 INFO - PROCESS | 4744 | |5;imaging-gaussian-blur;116;117;117;117;116;116;117;116;116;117 13:54:37 INFO - PROCESS | 4744 | |6;imaging-darkroom;147;147;146;146;145;146;146;146;145;146 13:54:37 INFO - PROCESS | 4744 | |7;imaging-desaturate;85;85;84;84;84;85;85;84;85;84 13:54:37 INFO - PROCESS | 4744 | |8;json-parse-financial;50;52;51;50;51;51;50;50;49;52 13:54:37 INFO - PROCESS | 4744 | |9;json-stringify-tinderbox;58;60;59;59;60;59;58;59;59;59 13:54:37 INFO - PROCESS | 4744 | |10;stanford-crypto-aes;74;75;74;74;75;75;75;76;74;76 13:54:37 INFO - PROCESS | 4744 | |11;stanford-crypto-ccm;123;128;131;121;129;122;121;127;175;126 13:54:37 INFO - PROCESS | 4744 | |12;stanford-crypto-pbkdf2;158;157;153;155;159;159;152;156;153;156 13:54:37 INFO - PROCESS | 4744 | |13;stanford-crypto-sha256-iterative;64;61;64;64;62;61;62;64;62;63 13:54:37 INFO - PROCESS | 4744 | __end_tp_report 13:54:37 INFO - PROCESS | 4744 | __start_cc_report 13:54:37 INFO - PROCESS | 4744 | _x_x_mozilla_cycle_collect,1548 13:54:37 INFO - PROCESS | 4744 | __end_cc_report 13:54:37 INFO - PROCESS | 4744 | __startTimestamp1485986077695__endTimestamp 13:54:37 INFO - PROCESS | 4744 | 13:54:37 INFO - PROCESS | 4744 | ------- Summary: start ------- 13:54:37 INFO - PROCESS | 4744 | Number of tests: 14 13:54:37 INFO - PROCESS | 4744 | 13:54:37 INFO - PROCESS | 4744 | [#0] ai-astar Cycles:10 Average:110.70 Median:112.00 stddev:3.09 (2.8%) stddev-sans-first:0.50 13:54:37 INFO - PROCESS | 4744 | Values: 102.0 112.0 111.0 112.0 111.0 112.0 112.0 112.0 112.0 111.0 13:54:37 INFO - PROCESS | 4744 | 13:54:37 INFO - PROCESS | 4744 | [#1] audio-beat-detection Cycles:10 Average:129.20 Median:122.00 stddev:16.33 (13.4%) stddev-sans-first:15.49 13:54:37 INFO - PROCESS | 4744 | Values: 150.0 121.0 117.0 159.0 123.0 119.0 116.0 121.0 148.0 118.0 13:54:37 INFO - PROCESS | 4744 | 13:54:37 INFO - PROCESS | 4744 | [#2] audio-dft Cycles:10 Average:163.30 Median:162.00 stddev:4.88 (3.0%) stddev-sans-first:5.17 13:54:37 INFO - PROCESS | 4744 | Values: 163.0 161.0 162.0 162.0 162.0 161.0 177.0 161.0 161.0 163.0 13:54:37 INFO - PROCESS | 4744 | 13:54:37 INFO - PROCESS | 4744 | [#3] audio-fft Cycles:10 Average:82.70 Median:76.00 stddev:14.80 (19.5%) stddev-sans-first:15.35 13:54:37 INFO - PROCESS | 4744 | Values: 74.0 74.0 76.0 114.0 77.0 78.0 75.0 76.0 107.0 76.0 13:54:37 INFO - PROCESS | 4744 | 13:54:37 INFO - PROCESS | 4744 | [#4] audio-oscillator Cycles:10 Average:90.50 Median:82.00 stddev:14.36 (17.5%) stddev-sans-first:14.90 13:54:37 INFO - PROCESS | 4744 | Values: 82.0 81.0 122.0 82.0 83.0 82.0 102.0 83.0 82.0 106.0 13:54:37 INFO - PROCESS | 4744 | 13:54:37 INFO - PROCESS | 4744 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 13:54:37 INFO - PROCESS | 4744 | Values: 116.0 117.0 117.0 117.0 116.0 116.0 117.0 116.0 116.0 117.0 13:54:37 INFO - PROCESS | 4744 | 13:54:37 INFO - PROCESS | 4744 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.60 13:54:37 INFO - PROCESS | 4744 | Values: 147.0 147.0 146.0 146.0 145.0 146.0 146.0 146.0 145.0 146.0 13:54:37 INFO - PROCESS | 4744 | 13:54:37 INFO - PROCESS | 4744 | [#7] imaging-desaturate Cycles:10 Average:84.50 Median:85.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 13:54:37 INFO - PROCESS | 4744 | Values: 85.0 85.0 84.0 84.0 84.0 85.0 85.0 84.0 85.0 84.0 13:54:37 INFO - PROCESS | 4744 | 13:54:37 INFO - PROCESS | 4744 | [#8] json-parse-financial Cycles:10 Average:50.60 Median:51.00 stddev:0.97 (1.9%) stddev-sans-first:1.00 13:54:37 INFO - PROCESS | 4744 | Values: 50.0 52.0 51.0 50.0 51.0 51.0 50.0 50.0 49.0 52.0 13:54:37 INFO - PROCESS | 4744 | 13:54:37 INFO - PROCESS | 4744 | [#9] json-stringify-tinderbox Cycles:10 Average:59.00 Median:59.00 stddev:0.67 (1.1%) stddev-sans-first:0.60 13:54:37 INFO - PROCESS | 4744 | Values: 58.0 60.0 59.0 59.0 60.0 59.0 58.0 59.0 59.0 59.0 13:54:37 INFO - PROCESS | 4744 | 13:54:37 INFO - PROCESS | 4744 | [#10] stanford-crypto-aes Cycles:10 Average:74.80 Median:75.00 stddev:0.79 (1.1%) stddev-sans-first:0.78 13:54:37 INFO - PROCESS | 4744 | Values: 74.0 75.0 74.0 74.0 75.0 75.0 75.0 76.0 74.0 76.0 13:54:37 INFO - PROCESS | 4744 | 13:54:37 INFO - PROCESS | 4744 | [#11] stanford-crypto-ccm Cycles:10 Average:130.30 Median:127.50 stddev:16.09 (12.6%) stddev-sans-first:16.85 13:54:37 INFO - PROCESS | 4744 | Values: 123.0 128.0 131.0 121.0 129.0 122.0 121.0 127.0 175.0 126.0 13:54:37 INFO - PROCESS | 4744 | 13:54:37 INFO - PROCESS | 4744 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:155.80 Median:156.50 stddev:2.53 (1.6%) stddev-sans-first:2.55 13:54:37 INFO - PROCESS | 4744 | Values: 158.0 157.0 153.0 155.0 159.0 159.0 152.0 156.0 153.0 156.0 13:54:37 INFO - PROCESS | 4744 | 13:54:37 INFO - PROCESS | 4744 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.70 Median:63.50 stddev:1.25 (2.0%) stddev-sans-first:1.24 13:54:37 INFO - PROCESS | 4744 | Values: 64.0 61.0 64.0 64.0 62.0 61.0 62.0 64.0 62.0 63.0 13:54:37 INFO - PROCESS | 4744 | -------- Summary: end -------- 13:54:37 INFO - PROCESS | 4744 | 13:54:38 INFO - TEST-INFO | 4744: exit 0 13:54:38 INFO - TEST-OK | kraken | took 59395ms 13:54:38 INFO - SUITE-END | took 1173s 13:54:38 INFO - Completed test suite (00:19:33) 13:54:38 INFO - javascript benchmark 13:54:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3247.074583743633, 3283.2448536836528, 3285.342931413718, 3306.30567580808, 3328.866845326187, 5551.611387202893, 5622.381538030813, 5624.493795558399, 5629.49334559889, 5649.18086877403, 5505.476979686923, 5529.751161197746, 5537.003339398917, 5537.446255374461, 5538.030844602199, 4984.825531106412, 4988.875278118058, 5017.19724844025, 5052.873678158038, 5057.089723849724, 4842.685225460352, 4929.876753081166, 4935.333729946465, 5049.091163590552, 5097.133487307166, 4872.123017856784, 4937.950620493803, 4952.108620448318, 4964.726940018298, 4971.37857767779, 4955.727434991074, 4955.776990035457, 4964.255361695757, 4964.726940018298, 4997.525235102659, 4799.040191961587, 4836.758162091916, 4891.926621100651, 4908.8772780680765, 4939.827106051307, 5376.731163441851, 5384.488473595042, 5407.594430417735, 5430.8099216527235, 5447.264619276426, 5829.795957141479, 5840.8247752567495, 5880.382559831199, 5887.234659494238, 5923.17075609415, 5805.564582656276, 5922.644641321492, 5922.674252916088, 5952.553558483131, 5968.074948382964, 5448.237246785453, 5543.0, 5616.213730077751, 5616.466435688603, 5641.830745077655, 6161.507079433724, 6169.0, 6174.38256174388, 6176.320604733476, 6177.722002509898, 5620.18507316445, 5923.259592550931, 5925.9407405925385, 5930.436608522184, 5932.911006334908, 5728.828135155869, 5912.497437717842, 5970.552208584287, 5979.760809567656, 6067.605605635619, 5965.940340596625, 5983.970080149571, 5986.07215881539, 5991.730372133265, 6027.819165425044, 5953.493953013955, 6006.549508786771, 6021.0, 6023.909641355383, 6026.5178785697035, 4517.570830771071, 4537.795799189044, 4545.636349092065, 4574.519675434031, 4588.197065513522, 4110.979445102756, 4120.072983578671, 4142.79286035697, 4154.439150714676, 4160.563140870226, 5703.600747947604, 5712.000399930079, 5713.914291285634, 5718.027935251017, 5729.083346664514, 5835.44563266489, 5879.764809407576, 5910.911336329958, 5923.940760592339, 6020.157177995085, 5718.228039214736, 5721.227634269404, 5736.053551164011, 5738.799142030009, 5749.310082790092, 5154.685510249914, 5162.664426812245, 5167.509086636764, 5167.689938603696, 5170.922436163461, 6058.757649694051, 6107.297660769068, 6177.135201071854, 6236.6258024518675, 6247.9062814057825, 5903.793367232298, 6001.549883758648, 6002.189704389852, 6046.123312119806, 6080.118382834376, 6229.034499652561, 6254.4996400286955, 6274.560780745304, 6282.308946016026, 6290.716917738621, 5662.150677398423, 5924.585279030599, 5925.99258126111, 5927.318358388668, 5966.403359663999, 5883.35283118848, 5957.017092179742, 5992.13114098462, 6021.7591296347655, 6114.633122012693, 5874.324452687995, 5900.1149827526215, 5914.349421563538, 5914.822555323347, 5921.61509501881, 5890.705464726832, 5907.91138132937, 5911.349751527243, 5913.172155898092, 5924.4667979882015, 5499.807506737245, 5620.297462817148, 5756.597038207286, 5784.710764461676, 5798.797042103506, 5897.587168898136, 5904.734286957183, 5907.379725128972, 5915.58590898633, 5921.911171332347, 5548.69482178484, 5610.018246806874, 5751.626144300607, 5759.107338362561, 5763.6541807491685], "value": 5489.647302833411, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [586.126671259823, 608.0666177417663, 643.9259485159207, 659.6339031837331, 675.9590231044192, 33803.323933521315, 44487.55512444874, 45406.772966135126, 45494.545054549446, 49217.75391123039, 44973.0, 45127.32309015367, 45204.77397613016, 45404.31893521592, 45476.0, 8274.875876861823, 8871.512066836322, 9017.45895246284, 9030.50332231727, 9037.18665320121, 8338.332933365347, 10814.567417303298, 11097.611583594577, 11098.44507774612, 11102.222844400896, 10846.72883177919, 10934.125269978382, 10971.506282217319, 10974.231803773739, 10990.395528245943, 10751.51618177184, 10777.83833242494, 10802.297850639723, 10809.297395669257, 10809.35143891369, 10684.305520141166, 10694.304870183412, 10750.569977200903, 10752.139828813677, 10770.838437423445, 37115.257694845954, 37216.813915930245, 37373.0, 37416.06459838498, 37523.0, 3426.931461370759, 3541.8406171722077, 3751.5498140223076, 3866.284737323576, 4199.895002624959, 32743.18142046444, 32750.344993100243, 32952.505712414335, 32962.6703732962, 33107.50338744921, 4443.7111587746695, 4490.581850107751, 4541.818327266906, 4548.363229147922, 4623.930641040387, 333025.0, 334481.0, 334757.0, 336456.99999999756, 336632.0, 37507.81246093752, 61266.69366653227, 61635.0, 61644.0, 61786.69106654438, 38575.42136867949, 41217.587824121976, 41479.0, 41484.1703165941, 41564.79217603953, 15820.841791581937, 15926.203689815695, 15931.0, 15942.123183224801, 15961.281742321624, 11897.583584574499, 12143.757124857453, 12258.632241032783, 13082.869171308355, 13162.14446061003, 9365.953170234105, 9553.522323883917, 9615.759606009906, 9649.855252171223, 9682.467464289392, 3610.151614370621, 3616.4032934565503, 3639.3994990826745, 3649.59854416014, 3657.5428071491065, 1036.9429681367446, 1085.484394912426, 1111.8325757954117, 1163.1898666162572, 1178.7171078940992, 31460.842695786374, 32767.0, 32850.0, 32902.34195316128, 33183.17042073919, 1067.8184708599556, 1107.9944600276947, 1113.4098927568402, 1135.392564977733, 1168.3457263932225, 14669.92665036668, 17901.7314740279, 18483.260669573065, 18574.44276671702, 18904.432867014013, 515777.0, 516793.0, 518549.0, 519889.0, 519891.0, 59956.40043599595, 60316.396836031076, 60517.09224361638, 60577.69711151416, 60751.392486075456, 1325.4698120751777, 1344.3547097393111, 1403.4175817035816, 1420.4531255466513, 1423.4804296431728, 33960.320793584484, 35443.64556354507, 35595.64404355923, 36271.27457450889, 36499.63500364962, 57055.71472142613, 57944.0, 58089.709551451975, 58142.0, 58195.418045819, 349617.0, 349785.0, 350364.0, 350565.0, 351684.0, 30300.0, 30430.239244019078, 30811.691883080883, 30859.382812343178, 30893.845530772203, 49264.0, 49675.50324496709, 49723.25415118704, 49798.0, 50205.49794502155, 42267.5773242276, 42897.04649305269, 43035.35446968235, 43744.0, 44500.55499445093, 42801.57198428101, 43102.0, 43327.0, 43336.0, 43725.125497489236, 41209.58790412058, 41619.375709363776, 42547.36178957256, 43033.13933721245, 43199.13601727885, 35491.290174195856, 37090.0, 37736.433953491265, 37879.43180852345, 38203.61796382112, 28455.28861778472, 28776.56835147516, 28876.133715988555, 29093.41813163767, 29143.271418213866], "value": 19810.97547945886, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [380.2319314983731, 385.3352966133418, 418.1615860200298, 420.145004914998, 422.30741583802563, 13980.580582582514, 19476.90261548692, 20174.6973795393, 20227.0, 20522.38432847017, 14630.780538291934, 16130.919345403287, 18949.715754263663, 19177.520561985923, 19687.0, 5214.895702085965, 5454.53636440903, 5461.644993075457, 5461.890762184754, 5513.862153446176, 5479.315085614298, 5479.835604931858, 5481.671099734029, 5511.58663100267, 5610.69141197234, 5921.763129474816, 5940.04959206527, 5950.137230101633, 5952.821415357546, 5955.731992060368, 5518.420565840569, 5790.305163380379, 5942.138389933457, 5952.315483719384, 5952.672603006833, 5730.19777231188, 5734.455226753452, 5736.655800651933, 5737.196792449061, 5742.799002034909, 14816.851831481656, 15187.164705941168, 22693.886530567408, 23323.650145247666, 23561.88219058911, 2363.314638754759, 2421.055788242587, 2435.5250726108416, 2462.2367066209354, 2640.577507598775, 10581.470926453647, 10862.076723478514, 11142.27575207609, 13367.0, 14799.186044767534, 1977.7995982594525, 2032.3598066609118, 2059.804318589732, 2095.8847263400507, 2105.178980197709, 81318.18681813165, 82045.0, 82140.0, 84197.57901210454, 84215.15784842134, 11309.0, 11393.202475826612, 11581.247218930912, 11650.126240531994, 11735.589254376056, 16184.352625895068, 16217.59456013609, 16302.918485407497, 16377.181140942905, 16440.342386304652, 7520.887186692204, 7944.523328600302, 8169.63236654352, 8349.83300334002, 8423.915760842436, 8166.918330816735, 8215.055268644062, 8226.696524973016, 8414.284785793154, 8455.408121431441, 4910.631702622246, 4980.477049909779, 5005.799768009311, 5019.146745053276, 5125.718085505258, 2007.6988451732357, 2052.056054215048, 2066.132224465728, 2069.275753486298, 2102.9789702102785, 883.9204471597587, 970.6360114956891, 977.4330888084894, 997.2520609542843, 1010.4291075542295, 19115.13981870819, 19848.404547863298, 19939.202431902562, 20073.29743459001, 20193.39419817408, 1009.5103874620803, 1009.9949500252452, 1012.5899010900597, 1028.9742756431149, 1076.5855145768778, 10207.948960255151, 11123.16576256784, 11196.38419886914, 11204.663860084047, 11330.830037549444, 354303.0, 363773.0, 366085.0, 487304.0, 495635.0, 77388.61305693549, 98328.0, 100385.0, 100866.49566752119, 105117.0, 1048.5334026358198, 1067.0929709746654, 1089.9019088282093, 1113.6213687346178, 1114.1309778372881, 17937.37219197322, 18210.180541875914, 18390.63218735591, 18431.292847476467, 18571.535711607317, 43247.13505729931, 43408.34887476754, 44755.32867007062, 45254.773726131156, 46526.30210546777, 46358.0, 56472.43527564672, 63314.683426584415, 63523.36476635175, 64203.35796641974, 8498.065212826461, 8625.956870215608, 8756.168164024408, 8789.121087891159, 8823.558822059, 10988.846171152234, 12649.177803442746, 13330.800037999632, 13403.731925361242, 13556.525521606696, 10316.48417579103, 11912.166148369532, 12049.51801927913, 12116.515339386326, 12117.27296362221, 11819.881801181878, 12188.20776649515, 12282.570110046105, 12312.630621081382, 12485.0, 12062.095342849147, 12236.327002014974, 12299.93850030744, 12385.876141238472, 12443.875561244271, 7699.3840492761565, 8851.33614978892, 8879.778005549913, 8895.599698013475, 9002.81994360096, 8820.162084601952, 11907.749686282816, 11942.283462992249, 12246.632601021984, 12320.383980800745], "value": 9363.057979117759, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1503.120674405473, 1526.5725596832888, 1649.1259632394836, 1742.416290542668, 1776.5292197567646, 8235.464694794835, 8744.256738177262, 8751.868721969175, 8754.649814007433, 8758.64965401384, 7467.8879816802655, 7584.658690358933, 8282.83434331313, 8361.540115293672, 8600.8709869352, 2452.926412207632, 2484.5155194737035, 2506.987465062672, 2521.596544552872, 2523.1673547729297, 2454.398672325283, 2506.949861002779, 2507.4608959073794, 2511.962320565193, 2516.207394670682, 2463.815213858959, 2480.2807185916063, 2519.420533277347, 2521.2940376694555, 2521.470491196851, 2408.5664580375524, 2419.0323870451784, 2419.2863105383867, 2421.225207933462, 2438.756124387565, 2418.830681852271, 2432.659427680126, 2433.2578563538113, 2453.7055553333535, 2454.9263522094366, 8526.190011948855, 8640.827183456297, 8655.567221638892, 8664.653413863438, 8668.826623467496, 5298.046351656701, 5432.076546987022, 5432.91850622241, 5433.375161856397, 5449.0, 3350.7654464187517, 3371.0729549373873, 3377.0, 3454.3091381723757, 3470.2538954124734, 1036.6216331039116, 1054.541274545575, 1063.6330465989222, 1073.366713638949, 1079.6221322537128, 11841.585544505988, 11858.229215100991, 11867.347295898722, 11886.762264754743, 11888.762224755457, 4778.961777298177, 4810.5670489656095, 4845.781939812717, 4936.3829521309835, 4962.15643340633, 10001.299909006446, 10014.49927503622, 10016.549255283384, 10029.64896228643, 10031.498425078717, 5550.833474995757, 5560.833175004756, 5561.833145005657, 5562.443755624405, 5562.916556251659, 4487.080148569536, 4646.767661616906, 4947.332110165154, 5026.52248036436, 5033.546980771748, 4069.944320766455, 4110.383442483651, 4133.318002529609, 4138.441310423114, 4165.83336666536, 1344.6729120355762, 1369.7671395862728, 1380.0822453068797, 1383.508854356689, 1391.1513976474344, 890.7505898348472, 905.6513242401722, 917.5412293853074, 991.3853410885154, 991.5686676295834, 5584.860378490489, 5639.43605639441, 5649.943500564942, 5757.222774925415, 5861.120831875252, 900.4687234531527, 922.4880191493785, 926.5830376330543, 950.4819873169139, 969.8545218217183, 3944.546377166605, 4128.401381799622, 4133.8966525836495, 4144.606262405066, 4251.29853574163, 10330.948345258226, 10713.250072494851, 10723.571057157626, 10732.0, 10743.14054875608, 10550.525226364833, 10588.205884558689, 10634.89365106339, 10648.520816563274, 10684.198685098649, 7749.806254843674, 8195.508269503849, 8333.250007499355, 8338.458000229966, 8357.12250213731, 5430.891382172255, 5583.972080139573, 5687.260656114679, 5707.400722924033, 5725.541956643542, 9012.549372531215, 9033.864492032493, 9101.499417531835, 9363.063693630855, 9611.375260608038, 9391.154796068387, 9394.342396032212, 9421.481818500048, 9524.61901523959, 9547.713568592953, 7045.295470452914, 7235.891461628185, 7484.738034168778, 7608.695652173851, 7786.143524212219, 8185.140560241089, 8447.324214062983, 8448.028476725009, 8449.28181104613, 8451.366147539082, 7135.464840137114, 7276.745313914199, 7382.114146302478, 7400.333969942731, 7634.122075961335, 7161.892571611325, 7254.782356529313, 7296.890546641911, 7310.926890731024, 7312.744053958086, 6790.117284752951, 6925.619090949845, 6926.376626103674, 6926.757563485254, 6927.099477067949, 4937.654364194472, 4980.252962055576, 5040.672356296829, 5071.0, 5207.3230480037355, 4885.828995985124, 4973.975130124326, 4994.925076123934, 5037.395512538519, 5049.469805670352], "value": 4621.1797607854905, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [339.2841105267884, 349.7342020064751, 351.5359725162786, 352.2461931466661, 354.854509651043, 5334.0, 5398.946010539893, 5423.566114710824, 5432.70120143392, 5434.266374039508, 5045.243213517974, 5151.974240128803, 5160.632432405416, 5239.52844244018, 5293.444188360225, 4196.601322874338, 4198.727082739612, 4243.639290660298, 4247.766372849492, 4249.3413520904305, 4190.664746820247, 4198.727082739628, 4254.595813397722, 4258.638015768648, 4266.466691663542, 4090.97954510227, 4171.895702607444, 4184.916301673965, 4191.245575796355, 4195.979020104895, 4166.250074986501, 4173.478315210584, 4175.958240417587, 4180.7909604519655, 4193.874183774461, 3727.34771415004, 3732.981335093334, 3733.1227161617, 3743.3074881147077, 3743.9064023399633, 5075.111855425286, 5148.5623721983675, 5154.433012368637, 5171.0, 5171.560417364529, 4808.519148085198, 4823.456493921946, 4823.756953016864, 4837.637177211688, 4839.354619429411, 5533.723313834333, 5539.667619942817, 5555.444455554452, 5562.554995600342, 5569.0, 3992.5208974922903, 4086.3870419437026, 4127.628513433775, 4170.957260684829, 4178.456800615901, 5665.433456654343, 5715.485606295411, 5718.714064296769, 5731.799387021434, 5733.598648094635, 4212.599803018708, 4220.683448741357, 4245.214635292419, 4247.97876010618, 4263.189993901211, 4029.2545879012478, 4164.916701665949, 4259.936100958488, 4300.0, 4306.375575541591, 4758.262469317261, 4758.762061896891, 4767.904641907143, 4773.64197685175, 4774.236122220428, 5581.832545023656, 5585.301837270341, 5596.460973232393, 5604.0, 5638.323401191801, 1410.541573988458, 1412.3009110490373, 1426.8430472648001, 1426.86444787745, 1427.0937954398823, 939.0327962198946, 952.9952350238204, 958.7727708533089, 965.6764983730388, 966.3139171188535, 5202.635815492955, 5243.318368612056, 5256.710880901511, 5307.8142265021315, 5331.120365139708, 5307.310049693515, 5403.729813509309, 5485.50630443262, 5486.753096110605, 5505.366882808448, 5180.523550788007, 5203.5576975957465, 5236.790528378822, 5265.4997775211295, 5272.947270527246, 2766.51585972454, 2877.7841661875023, 2888.407876385336, 2927.2828157101644, 2928.560715892633, 5613.214150019001, 5661.34894487131, 5672.404397538199, 5690.374058853523, 5723.914141287884, 5531.751071201806, 5538.778448862081, 5548.059528449928, 5556.16657501378, 5566.858793947231, 5660.971695141498, 5663.405342439067, 5681.431856814285, 5948.7323070461935, 5981.102834574762, 5726.427357264407, 5754.223179870663, 5776.942230577641, 5782.335031471434, 5797.681127537857, 5488.670679759227, 5703.771849125988, 5734.827955161351, 5752.856178595569, 5778.797742079007, 5985.281766187911, 5986.4013598639785, 6016.338202797601, 6053.3946605339115, 6069.726862291119, 4160.355144952538, 4164.791760411907, 4166.270902592095, 4190.455240818796, 4208.158368326286, 5604.971975140098, 5615.859603509945, 5673.205751194919, 5708.372079071216, 5895.467178533527, 5552.916706249329, 5565.634605292816, 5588.0, 5595.013770111715, 5600.299962504687, 5397.78408863641, 5586.664800112007, 5605.747741351731, 5606.579506536945, 5613.915791263216, 5579.19101730255, 5603.795184035452, 5618.747156377891, 5628.605997580294, 5634.774609015593, 5679.37526872052, 5702.6293290935955, 5709.314882214161, 5711.657760426379, 5722.198892155185, 5409.8523144018545, 5439.129739241703, 5499.257600223918, 5502.477264659851, 5545.861353466195], "value": 4266.712488002054, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [798.8202654402756, 808.5795386399072, 846.2848892685681, 872.2803686958259, 896.686159844055, 14825.332860021297, 15616.76574851378, 15751.60620984476, 15782.921085394557, 15794.052356858596, 14502.637434064154, 14772.778408323857, 14783.482578109768, 14883.553493395215, 14918.104913705185, 713.407871466684, 749.2694622742837, 844.1178967978477, 855.7732200966749, 856.3620103023226, 853.6030745703247, 870.29071306885, 870.6169285514385, 870.8171284030361, 874.4578361415931, 915.3226612306878, 919.296737995434, 919.3426699909563, 920.1028996728203, 920.3603495570582, 914.3919293669702, 914.7392992996997, 914.7530166854946, 914.8170365926775, 915.6978197194976, 682.6245564939263, 683.4976292425064, 683.4976292425064, 683.7641013850214, 683.7948615415355, 15197.544073677807, 15255.466058688004, 15447.0, 15459.458918937895, 15553.22233888301, 14705.0, 15545.689086218325, 15603.297851596706, 15891.92054039734, 16040.438584649599, 11632.185746997713, 11704.707382315424, 11790.17468777186, 11857.703557411047, 11973.820392694117, 3609.819509024538, 3614.5120408744747, 3629.891103266906, 3655.141041855162, 3745.4381842723537, 69453.0, 69614.3038569618, 70263.29736702619, 72743.2725672747, 72894.54210915805, 10522.684319470274, 11159.051480624237, 11522.07823374128, 11543.249688770204, 11944.64166075019, 20802.375928722162, 21138.0, 21184.470388240417, 21224.469388265417, 21351.03920323589, 8112.6349314281, 8371.874421883676, 8453.66185352579, 8495.787605309917, 8567.314614830799, 4525.185466615975, 4613.861584152481, 4647.3029045643425, 4655.162070827216, 4796.112719146969, 3439.329330780474, 3483.651634836496, 3489.1102768793794, 3563.6792688658147, 3565.9286814264087, 570.4267211452463, 580.6109906362747, 596.5943158652158, 608.3764141754737, 615.6552330694825, 624.5596854217765, 631.504269148716, 662.2318110991228, 687.3058211206717, 707.9981825716553, 9068.365214434927, 9119.316051296046, 9329.148842601944, 9559.283053770994, 9791.216702663769, 686.8935315026179, 696.8989496522976, 709.7054722290295, 719.4100837313352, 729.0886392009987, 4318.589733975267, 4545.681802273809, 4587.082583483317, 4621.0, 4651.486803869288, 52776.208356874675, 70562.0, 72429.2757072433, 74000.62999685075, 78150.21849781429, 46718.065638687716, 62213.06680399798, 73144.0, 74059.88910166352, 75119.0, 69106.308936911, 69707.0, 69916.0, 71344.28655713376, 71820.0, 9056.864147037932, 9533.761655958657, 9589.472579008221, 9683.854742178732, 9698.321117521706, 46525.767371162925, 46935.06129877315, 47171.29243061425, 48162.759186203846, 48175.759121204166, 61279.0, 65594.0, 66719.66640166768, 67045.66477167583, 68506.31493684999, 11891.227070240408, 11963.820542692041, 12128.332941688292, 12201.389930503618, 12334.321612311409, 13063.412146453242, 13439.529616463376, 13646.181229126285, 13646.931765341109, 13711.794323084961, 12473.313967731861, 13103.60689179326, 13112.672183195496, 13172.802407963696, 13417.597472075853, 12156.513739450324, 12597.0, 12598.992080633345, 12727.19144433913, 13261.535846245335, 12061.939690301493, 12071.517139314681, 12311.967136103423, 12771.872281277068, 12979.206709767679, 10111.556631863139, 10270.0, 10691.572337106429, 10758.0, 10933.617323393642, 10776.568937242422, 10853.620123295646, 10855.45722713877, 11009.779804403706, 11129.66611001639], "value": 7226.95756807539, "unit": "score"}], "name": "dromaeo_css", "value": 7249.1251021070775, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 112.0, 111.0, 112.0, 111.0, 112.0, 112.0, 112.0, 112.0, 111.0], "value": 110.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 121.0, 117.0, 159.0, 123.0, 119.0, 116.0, 121.0, 148.0, 118.0], "value": 129.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 161.0, 162.0, 162.0, 162.0, 161.0, 177.0, 161.0, 161.0, 163.0], "value": 163.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 76.0, 114.0, 77.0, 78.0, 75.0, 76.0, 107.0, 76.0], "value": 82.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 122.0, 82.0, 83.0, 82.0, 102.0, 83.0, 82.0, 106.0], "value": 90.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 117.0, 116.0, 116.0, 117.0, 116.0, 116.0, 117.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 146.0, 146.0, 145.0, 146.0, 146.0, 146.0, 145.0, 146.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 84.0, 84.0, 84.0, 85.0, 85.0, 84.0, 85.0, 84.0], "value": 84.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 52.0, 51.0, 50.0, 51.0, 51.0, 50.0, 50.0, 49.0, 52.0], "value": 50.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 60.0, 59.0, 59.0, 60.0, 59.0, 58.0, 59.0, 59.0, 59.0], "value": 59.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 74.0, 74.0, 75.0, 75.0, 75.0, 76.0, 74.0, 76.0], "value": 74.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 128.0, 131.0, 121.0, 129.0, 122.0, 121.0, 127.0, 175.0, 126.0], "value": 130.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 157.0, 153.0, 155.0, 159.0, 159.0, 152.0, 156.0, 153.0, 156.0], "value": 155.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 61.0, 64.0, 64.0, 62.0, 61.0, 62.0, 64.0, 62.0, 63.0], "value": 62.7, "unit": "score"}], "name": "kraken", "value": 1456.6, "alertThreshold": 2.0}]} 13:54:38 INFO - Return code: 0 13:54:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:54:38 INFO - # TBPL SUCCESS # 13:54:38 INFO - Running post-action listener: _resource_record_post_action 13:54:38 INFO - [mozharness: 2017-02-01 21:54:38.989203Z] Finished run-tests step (success) 13:54:38 INFO - Running post-run listener: _resource_record_post_run 13:54:39 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 8192; Write bytes: 337465344; Read time: 72; Write time: 141636 13:54:39 INFO - TinderboxPrint: CPU usage
12.9% 13:54:39 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 72 13:54:39 INFO - TinderboxPrint: I/O write bytes / time
337,465,344 / 141,636 13:54:39 INFO - TinderboxPrint: CPU idle
8,257.0 (87.1%) 13:54:39 INFO - TinderboxPrint: CPU user
1,185.5 (12.5%) 13:54:39 INFO - TinderboxPrint: Swap in / out
0 / 0 13:54:39 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 117129216; Read time: 0; Write time: 51348 13:54:39 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 4096; Write bytes: 220336128; Read time: 60; Write time: 90288 13:54:39 INFO - Running post-run listener: _upload_blobber_files 13:54:39 INFO - Blob upload gear active. 13:54:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:54:39 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 13:54:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:54:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:54:40 INFO - (blobuploader) - INFO - Open directory for files ... 13:54:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:54:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:54:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:54:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:54:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:54:41 INFO - (blobuploader) - INFO - Done attempting. 13:54:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:54:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:54:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:54:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:54:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:54:41 INFO - (blobuploader) - INFO - Done attempting. 13:54:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:54:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:54:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:54:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:54:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:54:45 INFO - (blobuploader) - INFO - Done attempting. 13:54:45 INFO - (blobuploader) - INFO - Iteration through files over. 13:54:45 INFO - Return code: 0 13:54:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:54:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:54:45 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/398e8bc634f35f29f2015978e13c9cfb49f04d91c3f45ea835e3b5a113ad720e90e05f54d8c9cd8c5cc3b5546111f0847d5e4140834abbaa97cef0bd12c00994", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/03c97be8dfacb10c35b9096404007b0b2e9f9093fbea2fb992a3c933f954ac4f9ac31c382e852c9e0bab52e55b14a058734eb63ec4fcea3f86384aa1dcd0f1d9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b35ede0ba8c7371fac613319106800050ffb929331a2968defc83d80152d39316fb2a016cd7811c8afa4de144a9104d82b20a7cd64b5992ee6c0cfa620803435"} 13:54:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:54:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:54:45 INFO - Contents: 13:54:45 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/398e8bc634f35f29f2015978e13c9cfb49f04d91c3f45ea835e3b5a113ad720e90e05f54d8c9cd8c5cc3b5546111f0847d5e4140834abbaa97cef0bd12c00994", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/03c97be8dfacb10c35b9096404007b0b2e9f9093fbea2fb992a3c933f954ac4f9ac31c382e852c9e0bab52e55b14a058734eb63ec4fcea3f86384aa1dcd0f1d9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b35ede0ba8c7371fac613319106800050ffb929331a2968defc83d80152d39316fb2a016cd7811c8afa4de144a9104d82b20a7cd64b5992ee6c0cfa620803435"} 13:54:45 INFO - Running post-run listener: copy_logs_to_upload_dir 13:54:45 INFO - Copying logs to upload dir... 13:54:45 INFO - mkdir: /builds/slave/test/build/upload/logs 13:54:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1232.153110 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2017-02-01 13:54:44.986892) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-01 13:54:44.990012) ========= 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=6d15e502d8e29d5d2676d664000001c3-1485984850.840905-452561807 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/398e8bc634f35f29f2015978e13c9cfb49f04d91c3f45ea835e3b5a113ad720e90e05f54d8c9cd8c5cc3b5546111f0847d5e4140834abbaa97cef0bd12c00994", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/03c97be8dfacb10c35b9096404007b0b2e9f9093fbea2fb992a3c933f954ac4f9ac31c382e852c9e0bab52e55b14a058734eb63ec4fcea3f86384aa1dcd0f1d9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b35ede0ba8c7371fac613319106800050ffb929331a2968defc83d80152d39316fb2a016cd7811c8afa4de144a9104d82b20a7cd64b5992ee6c0cfa620803435"} build_url:https://queue.taskcluster.net/v1/task/ZFbBpRivTiOec9RJz_EHSg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011501 build_url: 'https://queue.taskcluster.net/v1/task/ZFbBpRivTiOec9RJz_EHSg/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/398e8bc634f35f29f2015978e13c9cfb49f04d91c3f45ea835e3b5a113ad720e90e05f54d8c9cd8c5cc3b5546111f0847d5e4140834abbaa97cef0bd12c00994", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/03c97be8dfacb10c35b9096404007b0b2e9f9093fbea2fb992a3c933f954ac4f9ac31c382e852c9e0bab52e55b14a058734eb63ec4fcea3f86384aa1dcd0f1d9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b35ede0ba8c7371fac613319106800050ffb929331a2968defc83d80152d39316fb2a016cd7811c8afa4de144a9104d82b20a7cd64b5992ee6c0cfa620803435"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-01 13:54:45.017333) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-01 13:54:45.017923) ========= 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=6d15e502d8e29d5d2676d664000001c3-1485984850.840905-452561807 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004451 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-01 13:54:45.102356) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-01 13:54:45.102727) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-01 13:54:45.103175) ========= ========= Total master_lag: 0.18 =========