builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-069 starttime: 1482037613.8 results: success (0) buildid: 20161217201235 builduid: d61100b0b8f94ab2a50f3e31796cc1d0 revision: 5baae61449c43851c961a83babe18adaa28e3c6b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-17 21:06:53.804632) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-17 21:06:53.805144) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-17 21:06:53.805604) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-17 21:06:53.853902) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-17 21:06:53.854267) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1482036561.723995-303408795 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-17 21:06:56-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 227M=0s 2016-12-17 21:06:56 (227 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.139586 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-17 21:06:54.024826) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-17 21:06:54.025391) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1482036561.723995-303408795 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018689 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-17 21:06:54.094570) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-17 21:06:54.094915) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 5baae61449c43851c961a83babe18adaa28e3c6b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 5baae61449c43851c961a83babe18adaa28e3c6b --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=05b19d94dfa9f3ce7b6e62cc000001f7-1482036561.723995-303408795 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-17 21:06:56,309 truncating revision to first 12 chars 2016-12-17 21:06:56,309 Setting DEBUG logging. 2016-12-17 21:06:56,309 attempt 1/10 2016-12-17 21:06:56,309 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5baae61449c4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-17 21:06:56,821 unpacking tar archive at: mozilla-central-5baae61449c4/testing/mozharness/ program finished with exit code 0 elapsedTime=0.823325 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-17 21:06:54.954096) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-17 21:06:54.954405) ========= script_repo_revision: 5baae61449c43851c961a83babe18adaa28e3c6b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-17 21:06:54.954825) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-17 21:06:54.955168) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-17 21:06:54.979679) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 58 secs) (at 2016-12-17 21:06:54.979969) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=05b19d94dfa9f3ce7b6e62cc000001f7-1482036561.723995-303408795 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:06:57 INFO - MultiFileLogger online at 20161217 21:06:57 in /builds/slave/test 21:06:57 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 21:06:57 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:06:57 INFO - {'append_to_log': False, 21:06:57 INFO - 'base_work_dir': '/builds/slave/test', 21:06:57 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 21:06:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:06:57 INFO - 'branch': 'Firefox-Non-PGO', 21:06:57 INFO - 'buildbot_json_path': 'buildprops.json', 21:06:57 INFO - 'config_files': ('talos/linux_config.py',), 21:06:57 INFO - 'default_actions': ('clobber', 21:06:57 INFO - 'read-buildbot-config', 21:06:57 INFO - 'download-and-extract', 21:06:57 INFO - 'populate-webroot', 21:06:57 INFO - 'create-virtualenv', 21:06:57 INFO - 'install', 21:06:57 INFO - 'run-tests'), 21:06:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:06:57 INFO - 'download_minidump_stackwalk': True, 21:06:57 INFO - 'download_symbols': 'ondemand', 21:06:57 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:06:57 INFO - 'tooltool.py': '/tools/tooltool.py', 21:06:57 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:06:57 INFO - '/tools/misc-python/virtualenv.py')}, 21:06:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:06:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:06:57 INFO - 'installer_path': 'installer.exe', 21:06:57 INFO - 'log_level': 'info', 21:06:57 INFO - 'log_name': 'talos', 21:06:57 INFO - 'log_to_console': True, 21:06:57 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:06:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:06:57 INFO - 'opt_config_files': (), 21:06:57 INFO - 'pip_index': False, 21:06:57 INFO - 'sps_profile': False, 21:06:57 INFO - 'sps_profile_interval': 0, 21:06:57 INFO - 'suite': 'dromaeojs', 21:06:57 INFO - 'system_bits': '32', 21:06:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:06:57 INFO - 'title': 'talos-linux64-ix-069', 21:06:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:06:57 INFO - 'use_talos_json': True, 21:06:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:06:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:06:57 INFO - 'work_dir': 'build'} 21:06:57 INFO - [mozharness: 2016-12-18 05:06:57.289472Z] Running clobber step. 21:06:57 INFO - Running pre-action listener: _resource_record_pre_action 21:06:57 INFO - Running main action method: clobber 21:06:57 INFO - rmtree: /builds/slave/test/build 21:06:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:06:57 INFO - Running post-action listener: _resource_record_post_action 21:06:57 INFO - [mozharness: 2016-12-18 05:06:57.759841Z] Finished clobber step (success) 21:06:57 INFO - [mozharness: 2016-12-18 05:06:57.759942Z] Running read-buildbot-config step. 21:06:57 INFO - Running pre-action listener: _resource_record_pre_action 21:06:57 INFO - Running main action method: read_buildbot_config 21:06:57 INFO - Using buildbot properties: 21:06:57 INFO - { 21:06:57 INFO - "project": "", 21:06:57 INFO - "product": "firefox", 21:06:57 INFO - "script_repo_revision": "production", 21:06:57 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 21:06:57 INFO - "repository": "", 21:06:57 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 21:06:57 INFO - "buildid": "20161217201235", 21:06:57 INFO - "pgo_build": "False", 21:06:57 INFO - "basedir": "/builds/slave/test", 21:06:57 INFO - "buildnumber": 746, 21:06:57 INFO - "slavename": "talos-linux64-ix-069", 21:06:57 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 21:06:57 INFO - "platform": "ubuntu64_hw", 21:06:57 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 21:06:57 INFO - "branch": "mozilla-central", 21:06:57 INFO - "repo_path": "mozilla-central", 21:06:57 INFO - "revision": "5baae61449c43851c961a83babe18adaa28e3c6b", 21:06:57 INFO - "stage_platform": "linux64", 21:06:57 INFO - "builduid": "d61100b0b8f94ab2a50f3e31796cc1d0", 21:06:57 INFO - "slavebuilddir": "test" 21:06:57 INFO - } 21:06:57 INFO - Found installer url https://queue.taskcluster.net/v1/task/CtcQ4nFARQ2idasUo0jCOw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 21:06:57 INFO - Running post-action listener: _resource_record_post_action 21:06:57 INFO - [mozharness: 2016-12-18 05:06:57.762416Z] Finished read-buildbot-config step (success) 21:06:57 INFO - [mozharness: 2016-12-18 05:06:57.762506Z] Running download-and-extract step. 21:06:57 INFO - Running pre-action listener: _resource_record_pre_action 21:06:57 INFO - Running main action method: download_and_extract 21:06:57 INFO - mkdir: /builds/slave/test/build/tests 21:06:57 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')]} 21:06:57 INFO - trying https://queue.taskcluster.net/v1/task/CtcQ4nFARQ2idasUo0jCOw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 21:06:57 INFO - Downloading https://queue.taskcluster.net/v1/task/CtcQ4nFARQ2idasUo0jCOw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 21:06:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CtcQ4nFARQ2idasUo0jCOw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 21:06:58 INFO - Downloaded 1361 bytes. 21:06:58 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 21:06:58 INFO - Using the following test package requirements: 21:06:58 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 21:06:58 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:06:58 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 21:06:58 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:06:58 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 21:06:58 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:06:58 INFO - u'jsshell-linux-x86_64.zip'], 21:06:58 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:06:58 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 21:06:58 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 21:06:58 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:06:58 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 21:06:58 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:06:58 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 21:06:58 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:06:58 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 21:06:58 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:06:58 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 21:06:58 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:06:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CtcQ4nFARQ2idasUo0jCOw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 21:06:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CtcQ4nFARQ2idasUo0jCOw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:06:58 INFO - Fetch https://queue.taskcluster.net/v1/task/CtcQ4nFARQ2idasUo0jCOw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 21:06:59 INFO - Expected file size: 20257475 21:06:59 INFO - Obtained file size: 20257475 21:07:03 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 21:07:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CtcQ4nFARQ2idasUo0jCOw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 21:07:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CtcQ4nFARQ2idasUo0jCOw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:07:03 INFO - Fetch https://queue.taskcluster.net/v1/task/CtcQ4nFARQ2idasUo0jCOw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 21:07:04 INFO - Expected file size: 20257475 21:07:04 INFO - Obtained file size: 20257475 21:07:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CtcQ4nFARQ2idasUo0jCOw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 21:07:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CtcQ4nFARQ2idasUo0jCOw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 21:07:09 INFO - Fetch https://queue.taskcluster.net/v1/task/CtcQ4nFARQ2idasUo0jCOw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 21:07:10 INFO - Expected file size: 14409734 21:07:10 INFO - Obtained file size: 14409734 21:07:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:07:10 INFO - trying https://queue.taskcluster.net/v1/task/CtcQ4nFARQ2idasUo0jCOw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 21:07:10 INFO - Downloading https://queue.taskcluster.net/v1/task/CtcQ4nFARQ2idasUo0jCOw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 21:07:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CtcQ4nFARQ2idasUo0jCOw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 21:07:48 WARNING - Socket error when accessing https://queue.taskcluster.net/v1/task/CtcQ4nFARQ2idasUo0jCOw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2: The read operation timed out 21:07:48 INFO - retry: attempt #1 caught exception: The read operation timed out 21:07:48 INFO - retry: Failed, sleeping 30 seconds before retrying 21:08:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CtcQ4nFARQ2idasUo0jCOw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #2 21:08:23 INFO - Downloaded 55634221 bytes. 21:08:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CtcQ4nFARQ2idasUo0jCOw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 21:08:23 INFO - mkdir: /builds/slave/test/properties 21:08:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:08:23 INFO - Writing to file /builds/slave/test/properties/build_url 21:08:23 INFO - Contents: 21:08:23 INFO - build_url:https://queue.taskcluster.net/v1/task/CtcQ4nFARQ2idasUo0jCOw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 21:08:23 INFO - Running post-action listener: _resource_record_post_action 21:08:23 INFO - Running post-action listener: set_extra_try_arguments 21:08:23 INFO - [mozharness: 2016-12-18 05:08:23.899104Z] Finished download-and-extract step (success) 21:08:23 INFO - [mozharness: 2016-12-18 05:08:23.899343Z] Running populate-webroot step. 21:08:23 INFO - Running pre-action listener: _resource_record_pre_action 21:08:23 INFO - Running main action method: populate_webroot 21:08:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:08:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:08:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:08:23 INFO - '--tppagecycles', 21:08:23 INFO - '7'], 21:08:23 INFO - 'tests': ['tsvgm']}}, 21:08:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:08:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:08:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:08:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:08:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:08:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:08:23 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 21:08:23 INFO - 'talos_options': ['--disable-e10s'], 21:08:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:08:23 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 21:08:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:08:23 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 21:08:23 INFO - 'talos_options': ['--disable-e10s'], 21:08:23 INFO - 'tests': ['damp', 'tps']}, 21:08:23 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 21:08:23 INFO - 'tests': ['damp', 'tps']}, 21:08:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:08:23 INFO - 'tests': ['dromaeo_dom']}, 21:08:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:08:23 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:08:23 INFO - 'tests': ['basic_compositor_video']}, 21:08:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:08:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:08:23 INFO - 'tests': ['a11yr', 21:08:23 INFO - 'ts_paint', 21:08:23 INFO - 'tpaint', 21:08:23 INFO - 'sessionrestore', 21:08:23 INFO - 'sessionrestore_no_auto_restore', 21:08:23 INFO - 'tabpaint']}, 21:08:23 INFO - 'other-e10s': {'tests': ['a11yr', 21:08:23 INFO - 'ts_paint', 21:08:23 INFO - 'tpaint', 21:08:23 INFO - 'sessionrestore', 21:08:23 INFO - 'sessionrestore_no_auto_restore', 21:08:23 INFO - 'tabpaint']}, 21:08:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:08:23 INFO - 'ts_paint', 21:08:23 INFO - 'tpaint', 21:08:23 INFO - 'sessionrestore', 21:08:23 INFO - 'sessionrestore_no_auto_restore', 21:08:23 INFO - 'tabpaint']}, 21:08:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:08:23 INFO - 'ts_paint', 21:08:23 INFO - 'tpaint', 21:08:23 INFO - 'sessionrestore', 21:08:23 INFO - 'sessionrestore_no_auto_restore', 21:08:23 INFO - 'tabpaint']}, 21:08:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:08:23 INFO - 'tests': ['a11yr', 21:08:23 INFO - 'ts_paint', 21:08:23 INFO - 'tpaint', 21:08:23 INFO - 'sessionrestore', 21:08:23 INFO - 'sessionrestore_no_auto_restore', 21:08:23 INFO - 'tabpaint']}, 21:08:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:08:23 INFO - 'tests': ['a11yr', 21:08:23 INFO - 'ts_paint', 21:08:23 INFO - 'tpaint', 21:08:23 INFO - 'sessionrestore', 21:08:23 INFO - 'sessionrestore_no_auto_restore', 21:08:23 INFO - 'tabpaint']}, 21:08:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:08:23 INFO - 'tests': ['tsvgx', 21:08:23 INFO - 'tsvgr_opacity', 21:08:23 INFO - 'tart', 21:08:23 INFO - 'tscrollx', 21:08:23 INFO - 'cart', 21:08:23 INFO - 'tsvg_static']}, 21:08:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:08:23 INFO - 'tsvgr_opacity', 21:08:23 INFO - 'tart', 21:08:23 INFO - 'tscrollx', 21:08:23 INFO - 'cart', 21:08:23 INFO - 'tsvg_static']}, 21:08:23 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 21:08:23 INFO - 'talos_options': ['--disable-e10s'], 21:08:23 INFO - 'tests': ['tp5o']}, 21:08:23 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 21:08:23 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 21:08:23 INFO - 'talos_options': ['--disable-e10s', 21:08:23 INFO - '--xperf_path', 21:08:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:08:23 INFO - 'tests': ['tp5n']}, 21:08:23 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 21:08:23 INFO - 'talos_options': ['--xperf_path', 21:08:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:08:23 INFO - 'tests': ['tp5n']}}, 21:08:23 INFO - 'talos.zip': {'path': '', 21:08:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:08:23 INFO - Running post-action listener: _resource_record_post_action 21:08:23 INFO - [mozharness: 2016-12-18 05:08:23.912934Z] Finished populate-webroot step (success) 21:08:23 INFO - [mozharness: 2016-12-18 05:08:23.913023Z] Running create-virtualenv step. 21:08:23 INFO - Running pre-action listener: _resource_record_pre_action 21:08:23 INFO - Running main action method: create_virtualenv 21:08:23 INFO - Creating virtualenv /builds/slave/test/build/venv 21:08:23 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 21:08:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:08:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:08:23 INFO - Using real prefix '/usr' 21:08:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:08:24 INFO - Installing distribute.............................................................................................................................................................................................done. 21:08:26 INFO - Installing pip.................done. 21:08:26 INFO - Return code: 0 21:08:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 21:08:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 21:08:26 INFO - Reading from file tmpfile_stdout 21:08:26 INFO - Output received: 21:08:26 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) 21:08:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:08: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')]} 21:08:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:08:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08: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')]} 21:08:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:08:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfe71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1359630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e2850>, '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 0x13576d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1357b60>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1482036561.723995-303408795', '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 21:08:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 21:08:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 21:08:26 INFO - Using env: {'DISPLAY': ':0', 21:08:26 INFO - 'HOME': '/home/cltbld', 21:08:26 INFO - 'LANG': 'en_US.UTF-8', 21:08:26 INFO - 'LANGUAGE': 'en_US:en', 21:08:26 INFO - 'LOGNAME': 'cltbld', 21:08:26 INFO - 'MAIL': '/var/mail/cltbld', 21:08:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:08:26 INFO - 'MOZ_NO_REMOTE': '1', 21:08:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:08:26 INFO - 'NO_EM_RESTART': '1', 21:08:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:08:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:08:26 INFO - 'PWD': '/builds/slave/test', 21:08:26 INFO - 'SHELL': '/bin/bash', 21:08:26 INFO - 'SHLVL': '1', 21:08:26 INFO - 'TERM': 'linux', 21:08:26 INFO - 'TMOUT': '86400', 21:08:26 INFO - 'USER': 'cltbld', 21:08:26 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1482036561.723995-303408795', 21:08:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:08:26 INFO - '_': '/tools/buildbot/bin/python'} 21:08:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:08:26 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 21:08:26 INFO - Cleaning up... 21:08:26 INFO - Return code: 0 21:08:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:08: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')]} 21:08:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:08:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08: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')]} 21:08:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:08:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xfe71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1359630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e2850>, '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 0x13576d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1357b60>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1482036561.723995-303408795', '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 21:08:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 21:08:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 21:08:26 INFO - Using env: {'DISPLAY': ':0', 21:08:26 INFO - 'HOME': '/home/cltbld', 21:08:26 INFO - 'LANG': 'en_US.UTF-8', 21:08:26 INFO - 'LANGUAGE': 'en_US:en', 21:08:26 INFO - 'LOGNAME': 'cltbld', 21:08:26 INFO - 'MAIL': '/var/mail/cltbld', 21:08:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:08:26 INFO - 'MOZ_NO_REMOTE': '1', 21:08:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:08:26 INFO - 'NO_EM_RESTART': '1', 21:08:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:08:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:08:26 INFO - 'PWD': '/builds/slave/test', 21:08:26 INFO - 'SHELL': '/bin/bash', 21:08:26 INFO - 'SHLVL': '1', 21:08:26 INFO - 'TERM': 'linux', 21:08:26 INFO - 'TMOUT': '86400', 21:08:26 INFO - 'USER': 'cltbld', 21:08:26 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1482036561.723995-303408795', 21:08:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:08:26 INFO - '_': '/tools/buildbot/bin/python'} 21:08:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:08:27 INFO - Downloading/unpacking psutil>=3.1.1 21:08:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:08:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:08:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:08:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:08:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:08:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:08:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:08:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:08:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:08:29 INFO - Installing collected packages: psutil 21:08:29 INFO - Running setup.py install for psutil 21:08:29 INFO - building 'psutil._psutil_linux' extension 21:08:29 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 21:08:29 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 21:08:29 INFO - building 'psutil._psutil_posix' extension 21:08:29 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 21:08:29 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 21:08:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:08:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:08:29 INFO - Successfully installed psutil 21:08:29 INFO - Cleaning up... 21:08:29 INFO - Return code: 0 21:08:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:08:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:08:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:08:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:08:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:08:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfe71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1359630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e2850>, '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 0x13576d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1357b60>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1482036561.723995-303408795', '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 21:08:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 21:08:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 21:08:29 INFO - Using env: {'DISPLAY': ':0', 21:08:29 INFO - 'HOME': '/home/cltbld', 21:08:29 INFO - 'LANG': 'en_US.UTF-8', 21:08:29 INFO - 'LANGUAGE': 'en_US:en', 21:08:29 INFO - 'LOGNAME': 'cltbld', 21:08:29 INFO - 'MAIL': '/var/mail/cltbld', 21:08:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:08:29 INFO - 'MOZ_NO_REMOTE': '1', 21:08:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:08:29 INFO - 'NO_EM_RESTART': '1', 21:08:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:08:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:08:29 INFO - 'PWD': '/builds/slave/test', 21:08:29 INFO - 'SHELL': '/bin/bash', 21:08:29 INFO - 'SHLVL': '1', 21:08:29 INFO - 'TERM': 'linux', 21:08:29 INFO - 'TMOUT': '86400', 21:08:29 INFO - 'USER': 'cltbld', 21:08:29 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1482036561.723995-303408795', 21:08:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:08:29 INFO - '_': '/tools/buildbot/bin/python'} 21:08:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:08:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:08:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:08:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:08:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:08:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:08:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:08:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:08:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:08:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:08:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:08:31 INFO - Installing collected packages: mozsystemmonitor 21:08:31 INFO - Running setup.py install for mozsystemmonitor 21:08:32 INFO - Successfully installed mozsystemmonitor 21:08:32 INFO - Cleaning up... 21:08:32 INFO - Return code: 0 21:08:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:08:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:08:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:08:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:08:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:08:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xfe71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1359630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e2850>, '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 0x13576d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1357b60>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1482036561.723995-303408795', '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 21:08:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 21:08:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 21:08:32 INFO - Using env: {'DISPLAY': ':0', 21:08:32 INFO - 'HOME': '/home/cltbld', 21:08:32 INFO - 'LANG': 'en_US.UTF-8', 21:08:32 INFO - 'LANGUAGE': 'en_US:en', 21:08:32 INFO - 'LOGNAME': 'cltbld', 21:08:32 INFO - 'MAIL': '/var/mail/cltbld', 21:08:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:08:32 INFO - 'MOZ_NO_REMOTE': '1', 21:08:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:08:32 INFO - 'NO_EM_RESTART': '1', 21:08:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:08:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:08:32 INFO - 'PWD': '/builds/slave/test', 21:08:32 INFO - 'SHELL': '/bin/bash', 21:08:32 INFO - 'SHLVL': '1', 21:08:32 INFO - 'TERM': 'linux', 21:08:32 INFO - 'TMOUT': '86400', 21:08:32 INFO - 'USER': 'cltbld', 21:08:32 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1482036561.723995-303408795', 21:08:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:08:32 INFO - '_': '/tools/buildbot/bin/python'} 21:08:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:08:32 INFO - Downloading/unpacking jsonschema==2.5.1 21:08:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:08:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:08:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:08:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:08:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:08:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:08:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:08:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:08: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 21:08: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 21:08: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 21:08: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 21:08: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 21:08: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 21:08:34 INFO - Downloading functools32-3.2.3-2.tar.gz 21:08:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:08:34 INFO - warning: no files found matching '*.txt' 21:08:34 INFO - no previously-included directories found matching 'build' 21:08:34 INFO - no previously-included directories found matching 'dist' 21:08:34 INFO - no previously-included directories found matching '.git*' 21:08:34 INFO - Installing collected packages: jsonschema, functools32 21:08:34 INFO - Running setup.py install for functools32 21:08:34 INFO - warning: no files found matching '*.txt' 21:08:34 INFO - no previously-included directories found matching 'build' 21:08:34 INFO - no previously-included directories found matching 'dist' 21:08:34 INFO - no previously-included directories found matching '.git*' 21:08:34 INFO - Successfully installed jsonschema functools32 21:08:34 INFO - Cleaning up... 21:08:34 INFO - Return code: 0 21:08:34 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:08:34 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')]} 21:08:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:08:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:34 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')]} 21:08:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:08:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:34 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 0xfe71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1359630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e2850>, '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 0x13576d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1357b60>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1482036561.723995-303408795', '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 21:08:34 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 21:08:34 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 21:08:34 INFO - Using env: {'DISPLAY': ':0', 21:08:34 INFO - 'HOME': '/home/cltbld', 21:08:34 INFO - 'LANG': 'en_US.UTF-8', 21:08:34 INFO - 'LANGUAGE': 'en_US:en', 21:08:34 INFO - 'LOGNAME': 'cltbld', 21:08:34 INFO - 'MAIL': '/var/mail/cltbld', 21:08:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:08:34 INFO - 'MOZ_NO_REMOTE': '1', 21:08:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:08:34 INFO - 'NO_EM_RESTART': '1', 21:08:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:08:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:08:34 INFO - 'PWD': '/builds/slave/test', 21:08:34 INFO - 'SHELL': '/bin/bash', 21:08:34 INFO - 'SHLVL': '1', 21:08:34 INFO - 'TERM': 'linux', 21:08:34 INFO - 'TMOUT': '86400', 21:08:34 INFO - 'USER': 'cltbld', 21:08:34 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1482036561.723995-303408795', 21:08:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:08:34 INFO - '_': '/tools/buildbot/bin/python'} 21:08:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:08:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:08:35 INFO - Cleaning up... 21:08:35 INFO - Return code: 0 21:08:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:08:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:08:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:08:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:08:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:08:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:35 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 0xfe71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1359630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e2850>, '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 0x13576d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1357b60>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1482036561.723995-303408795', '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 21:08:35 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 21:08:35 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 21:08:35 INFO - Using env: {'DISPLAY': ':0', 21:08:35 INFO - 'HOME': '/home/cltbld', 21:08:35 INFO - 'LANG': 'en_US.UTF-8', 21:08:35 INFO - 'LANGUAGE': 'en_US:en', 21:08:35 INFO - 'LOGNAME': 'cltbld', 21:08:35 INFO - 'MAIL': '/var/mail/cltbld', 21:08:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:08:35 INFO - 'MOZ_NO_REMOTE': '1', 21:08:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:08:35 INFO - 'NO_EM_RESTART': '1', 21:08:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:08:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:08:35 INFO - 'PWD': '/builds/slave/test', 21:08:35 INFO - 'SHELL': '/bin/bash', 21:08:35 INFO - 'SHLVL': '1', 21:08:35 INFO - 'TERM': 'linux', 21:08:35 INFO - 'TMOUT': '86400', 21:08:35 INFO - 'USER': 'cltbld', 21:08:35 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1482036561.723995-303408795', 21:08:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:08:35 INFO - '_': '/tools/buildbot/bin/python'} 21:08:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:08:35 INFO - Downloading/unpacking blobuploader==1.2.4 21:08:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:08:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:08:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:08:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:08:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:08:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:08:37 INFO - Downloading blobuploader-1.2.4.tar.gz 21:08:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:08:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:08:37 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 21:08:37 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 21:08:37 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 21:08:37 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 21:08:37 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 21:08:37 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 21:08:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:08:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:08:37 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 21:08:37 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 21:08:37 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 21:08:37 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 21:08:37 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 21:08:37 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 21:08:37 INFO - Downloading docopt-0.6.1.tar.gz 21:08:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:08:38 INFO - Installing collected packages: blobuploader, requests, docopt 21:08:38 INFO - Running setup.py install for blobuploader 21:08:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:08:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:08:38 INFO - Running setup.py install for requests 21:08:38 INFO - Running setup.py install for docopt 21:08:38 INFO - Successfully installed blobuploader requests docopt 21:08:38 INFO - Cleaning up... 21:08:38 INFO - Return code: 0 21:08:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:08:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:08:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:08:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:08:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:08:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:38 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 0xfe71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1359630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e2850>, '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 0x13576d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1357b60>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1482036561.723995-303408795', '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 21:08:38 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 21:08:38 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 21:08:38 INFO - Using env: {'DISPLAY': ':0', 21:08:38 INFO - 'HOME': '/home/cltbld', 21:08:38 INFO - 'LANG': 'en_US.UTF-8', 21:08:38 INFO - 'LANGUAGE': 'en_US:en', 21:08:38 INFO - 'LOGNAME': 'cltbld', 21:08:38 INFO - 'MAIL': '/var/mail/cltbld', 21:08:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:08:38 INFO - 'MOZ_NO_REMOTE': '1', 21:08:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:08:38 INFO - 'NO_EM_RESTART': '1', 21:08:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:08:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:08:38 INFO - 'PWD': '/builds/slave/test', 21:08:38 INFO - 'SHELL': '/bin/bash', 21:08:38 INFO - 'SHLVL': '1', 21:08:38 INFO - 'TERM': 'linux', 21:08:38 INFO - 'TMOUT': '86400', 21:08:38 INFO - 'USER': 'cltbld', 21:08:38 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1482036561.723995-303408795', 21:08:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:08:38 INFO - '_': '/tools/buildbot/bin/python'} 21:08:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:08:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:08:38 INFO - Running setup.py (path:/tmp/pip-b5eUY1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:08:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:08:38 INFO - Running setup.py (path:/tmp/pip-aU3EpS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:08:39 INFO - Running setup.py (path:/tmp/pip-DW0jSQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:08:39 INFO - Running setup.py (path:/tmp/pip-b1R0QJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:08:39 INFO - Running setup.py (path:/tmp/pip-YsyApb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:08:39 INFO - Running setup.py (path:/tmp/pip-KTXAa2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:08:39 INFO - Running setup.py (path:/tmp/pip-ofh01L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:08:39 INFO - Running setup.py (path:/tmp/pip-36GHLR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:08:39 INFO - Running setup.py (path:/tmp/pip-L6oGvf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:08:39 INFO - Running setup.py (path:/tmp/pip-QlTuPd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:08:39 INFO - Running setup.py (path:/tmp/pip-TyKLW0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:08:39 INFO - Running setup.py (path:/tmp/pip-Cjy2P4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:08:39 INFO - Running setup.py (path:/tmp/pip-sA0YoY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:08:39 INFO - Running setup.py (path:/tmp/pip-EbVOKZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:08:39 INFO - Running setup.py (path:/tmp/pip-dvXFol-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:08:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:08:40 INFO - Running setup.py (path:/tmp/pip-aD0_rD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:08:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:08:40 INFO - Running setup.py (path:/tmp/pip-Qp1Xtc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:08:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:08:40 INFO - Running setup.py install for manifestparser 21:08:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:08:40 INFO - Running setup.py install for mozcrash 21:08:40 INFO - Running setup.py install for mozdebug 21:08:40 INFO - Running setup.py install for mozdevice 21:08:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:08:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:08:40 INFO - Running setup.py install for mozfile 21:08:40 INFO - Running setup.py install for mozhttpd 21:08:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:08:40 INFO - Running setup.py install for mozinfo 21:08:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:08:40 INFO - Running setup.py install for mozInstall 21:08:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:08:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:08:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:08:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:08:40 INFO - Running setup.py install for mozleak 21:08:41 INFO - Running setup.py install for mozlog 21:08:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:08:41 INFO - Running setup.py install for moznetwork 21:08:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:08:41 INFO - Running setup.py install for mozprocess 21:08:41 INFO - Running setup.py install for mozprofile 21:08:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:08:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:08:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:08:41 INFO - Running setup.py install for mozrunner 21:08:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:08:41 INFO - Running setup.py install for mozscreenshot 21:08:41 INFO - Running setup.py install for moztest 21:08:41 INFO - Running setup.py install for mozversion 21:08:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:08:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:08:41 INFO - Cleaning up... 21:08:41 INFO - Return code: 0 21:08:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:08:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:08:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:08:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:08:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:08:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:41 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 0xfe71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1359630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e2850>, '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 0x13576d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1357b60>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1482036561.723995-303408795', '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 21:08:41 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 21:08:41 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 21:08:41 INFO - Using env: {'DISPLAY': ':0', 21:08:41 INFO - 'HOME': '/home/cltbld', 21:08:41 INFO - 'LANG': 'en_US.UTF-8', 21:08:41 INFO - 'LANGUAGE': 'en_US:en', 21:08:41 INFO - 'LOGNAME': 'cltbld', 21:08:41 INFO - 'MAIL': '/var/mail/cltbld', 21:08:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:08:41 INFO - 'MOZ_NO_REMOTE': '1', 21:08:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:08:41 INFO - 'NO_EM_RESTART': '1', 21:08:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:08:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:08:41 INFO - 'PWD': '/builds/slave/test', 21:08:41 INFO - 'SHELL': '/bin/bash', 21:08:41 INFO - 'SHLVL': '1', 21:08:41 INFO - 'TERM': 'linux', 21:08:41 INFO - 'TMOUT': '86400', 21:08:41 INFO - 'USER': 'cltbld', 21:08:41 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1482036561.723995-303408795', 21:08:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:08:41 INFO - '_': '/tools/buildbot/bin/python'} 21:08:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:08:42 INFO - Running setup.py (path:/tmp/pip-EU6JSu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:08:42 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)) 21:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:08:42 INFO - Running setup.py (path:/tmp/pip-5b40sI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:08:42 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)) 21:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:08:42 INFO - Running setup.py (path:/tmp/pip-Ivl4w9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:08:42 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)) 21:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:08:42 INFO - Running setup.py (path:/tmp/pip-F6jNyK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:08:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:08:42 INFO - Running setup.py (path:/tmp/pip-m1zDvG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:08:42 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)) 21:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:08:42 INFO - Running setup.py (path:/tmp/pip-8jBnPy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:08:42 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)) 21:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:08:42 INFO - Running setup.py (path:/tmp/pip-aiclJb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:08:42 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)) 21:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:08:42 INFO - Running setup.py (path:/tmp/pip-wtwaPU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:08:42 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)) 21:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:08:42 INFO - Running setup.py (path:/tmp/pip-e0KV4d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:08:42 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)) 21:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:08:42 INFO - Running setup.py (path:/tmp/pip-X7f4zy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:08:42 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)) 21:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:08:42 INFO - Running setup.py (path:/tmp/pip-giaaIk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:08:42 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)) 21:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:08:42 INFO - Running setup.py (path:/tmp/pip-cPFwaD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:08:43 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)) 21:08:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:08:43 INFO - Running setup.py (path:/tmp/pip-wxe2w8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:08:43 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)) 21:08:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:08:43 INFO - Running setup.py (path:/tmp/pip-mJaarE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:08:43 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)) 21:08:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:08:43 INFO - Running setup.py (path:/tmp/pip-FSda8P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:08:43 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)) 21:08:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:08:43 INFO - Running setup.py (path:/tmp/pip-Ou3eVc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:08:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 21:08:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:08:43 INFO - Running setup.py (path:/tmp/pip-2yXa6l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:08:43 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)) 21:08:43 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)) 21:08:43 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)) 21:08:43 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)) 21:08:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:08:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:08:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:08:43 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 21:08:43 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 21:08:43 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 21:08:43 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 21:08:43 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 21:08:43 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 21:08:45 INFO - Downloading blessings-1.6.tar.gz 21:08:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:08:45 INFO - Installing collected packages: blessings 21:08:45 INFO - Running setup.py install for blessings 21:08:45 INFO - Successfully installed blessings 21:08:45 INFO - Cleaning up... 21:08:45 INFO - Return code: 0 21:08:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:08:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:08:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:08:45 INFO - Reading from file tmpfile_stdout 21:08:45 INFO - Current package versions: 21:08:45 INFO - argparse == 1.2.1 21:08:45 INFO - blessings == 1.6 21:08:45 INFO - blobuploader == 1.2.4 21:08:45 INFO - docopt == 0.6.1 21:08:45 INFO - functools32 == 3.2.3-2 21:08:45 INFO - jsonschema == 2.5.1 21:08:45 INFO - manifestparser == 1.1 21:08:45 INFO - mozInstall == 1.12 21:08:45 INFO - mozcrash == 1.0 21:08:45 INFO - mozdebug == 0.1 21:08:45 INFO - mozdevice == 0.49 21:08:45 INFO - mozfile == 1.2 21:08:45 INFO - mozhttpd == 0.7 21:08:45 INFO - mozinfo == 0.9 21:08:45 INFO - mozleak == 0.1 21:08:45 INFO - mozlog == 3.4 21:08:45 INFO - moznetwork == 0.27 21:08:45 INFO - mozprocess == 0.23 21:08:45 INFO - mozprofile == 0.28 21:08:45 INFO - mozrunner == 6.13 21:08:45 INFO - mozscreenshot == 0.1 21:08:45 INFO - mozsystemmonitor == 0.3 21:08:45 INFO - moztest == 0.7 21:08:45 INFO - mozversion == 1.4 21:08:45 INFO - psutil == 3.1.1 21:08:45 INFO - requests == 1.2.3 21:08:45 INFO - wsgiref == 0.1.2 21:08:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:08:45 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')]} 21:08:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:08:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:45 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')]} 21:08:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:08:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:45 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 0xfe71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1359630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e2850>, '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 0x13576d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1357b60>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1482036561.723995-303408795', '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 21:08:45 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 21:08:45 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 21:08:45 INFO - Using env: {'DISPLAY': ':0', 21:08:45 INFO - 'HOME': '/home/cltbld', 21:08:45 INFO - 'LANG': 'en_US.UTF-8', 21:08:45 INFO - 'LANGUAGE': 'en_US:en', 21:08:45 INFO - 'LOGNAME': 'cltbld', 21:08:45 INFO - 'MAIL': '/var/mail/cltbld', 21:08:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:08:45 INFO - 'MOZ_NO_REMOTE': '1', 21:08:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:08:45 INFO - 'NO_EM_RESTART': '1', 21:08:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:08:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:08:45 INFO - 'PWD': '/builds/slave/test', 21:08:45 INFO - 'SHELL': '/bin/bash', 21:08:45 INFO - 'SHLVL': '1', 21:08:45 INFO - 'TERM': 'linux', 21:08:45 INFO - 'TMOUT': '86400', 21:08:45 INFO - 'USER': 'cltbld', 21:08:45 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1482036561.723995-303408795', 21:08:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:08:45 INFO - '_': '/tools/buildbot/bin/python'} 21:08:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:08:46 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)) 21:08:46 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)) 21:08:46 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)) 21:08:46 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)) 21:08:46 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)) 21:08:46 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)) 21:08:46 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)) 21:08:46 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)) 21:08:46 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)) 21:08:46 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)) 21:08:46 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)) 21:08:46 INFO - Cleaning up... 21:08:46 INFO - Return code: 0 21:08:46 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:08: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')]} 21:08:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:08:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08: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')]} 21:08:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:08:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:08:46 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 0xfe71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1359630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e2850>, '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 0x13576d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1357b60>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1482036561.723995-303408795', '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 21:08:46 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 21:08:46 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 21:08:46 INFO - Using env: {'DISPLAY': ':0', 21:08:46 INFO - 'HOME': '/home/cltbld', 21:08:46 INFO - 'LANG': 'en_US.UTF-8', 21:08:46 INFO - 'LANGUAGE': 'en_US:en', 21:08:46 INFO - 'LOGNAME': 'cltbld', 21:08:46 INFO - 'MAIL': '/var/mail/cltbld', 21:08:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:08:46 INFO - 'MOZ_NO_REMOTE': '1', 21:08:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:08:46 INFO - 'NO_EM_RESTART': '1', 21:08:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:08:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:08:46 INFO - 'PWD': '/builds/slave/test', 21:08:46 INFO - 'SHELL': '/bin/bash', 21:08:46 INFO - 'SHLVL': '1', 21:08:46 INFO - 'TERM': 'linux', 21:08:46 INFO - 'TMOUT': '86400', 21:08:46 INFO - 'USER': 'cltbld', 21:08:46 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1482036561.723995-303408795', 21:08:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:08:46 INFO - '_': '/tools/buildbot/bin/python'} 21:08:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:08:46 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:08:46 INFO - Cleaning up... 21:08:46 INFO - Return code: 0 21:08:46 INFO - Running post-action listener: _resource_record_post_action 21:08:46 INFO - Running post-action listener: _start_resource_monitoring 21:08:46 INFO - Starting resource monitoring. 21:08:46 INFO - [mozharness: 2016-12-18 05:08:46.324145Z] Finished create-virtualenv step (success) 21:08:46 INFO - [mozharness: 2016-12-18 05:08:46.324620Z] Running install step. 21:08:46 INFO - Running pre-action listener: _resource_record_pre_action 21:08:46 INFO - Running main action method: install 21:08:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:08:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:08:46 INFO - Reading from file tmpfile_stdout 21:08:46 INFO - Detecting whether we're running mozinstall >=1.0... 21:08:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:08:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:08:46 INFO - Reading from file tmpfile_stdout 21:08:46 INFO - Output received: 21:08:46 INFO - Usage: mozinstall [options] installer 21:08:46 INFO - Options: 21:08:46 INFO - -h, --help show this help message and exit 21:08:46 INFO - -d DEST, --destination=DEST 21:08:46 INFO - Directory to install application into. [default: 21:08:46 INFO - "/builds/slave/test"] 21:08:46 INFO - --app=APP Application being installed. [default: firefox] 21:08:46 INFO - mkdir: /builds/slave/test/build/application 21:08:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 21:08:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 21:09:02 INFO - Reading from file tmpfile_stdout 21:09:02 INFO - Output received: 21:09:02 INFO - /builds/slave/test/build/application/firefox/firefox 21:09:02 INFO - Running post-action listener: _resource_record_post_action 21:09:02 INFO - [mozharness: 2016-12-18 05:09:02.655567Z] Finished install step (success) 21:09:02 INFO - [mozharness: 2016-12-18 05:09:02.655814Z] Running run-tests step. 21:09:02 INFO - Running pre-action listener: _resource_record_pre_action 21:09:02 INFO - Running main action method: run_tests 21:09:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:09:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:09:02 INFO - Python 2.7.3 21:09:02 INFO - Return code: 0 21:09:02 INFO - grabbing minidump binary from tooltool 21:09:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:09:02 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 0x13e2850>, '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 0x13576d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1357b60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:09:02 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 21:09:02 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 21:09:02 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 21:09:02 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:09:02 INFO - Return code: 0 21:09:02 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:09:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:09:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:09:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:09:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:09:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CtcQ4nFARQ2idasUo0jCOw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--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 21:09:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/CtcQ4nFARQ2idasUo0jCOw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-069 --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 21:09:02 INFO - Using env: {'DISPLAY': ':0', 21:09:02 INFO - 'HOME': '/home/cltbld', 21:09:02 INFO - 'LANG': 'en_US.UTF-8', 21:09:02 INFO - 'LANGUAGE': 'en_US:en', 21:09:02 INFO - 'LOGNAME': 'cltbld', 21:09:02 INFO - 'MAIL': '/var/mail/cltbld', 21:09:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:09:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:09:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:09:02 INFO - 'MOZ_NO_REMOTE': '1', 21:09:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:09:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:09:02 INFO - 'NO_EM_RESTART': '1', 21:09:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:09:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:09:02 INFO - 'PWD': '/builds/slave/test', 21:09:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:09:02 INFO - 'SHELL': '/bin/bash', 21:09:02 INFO - 'SHLVL': '1', 21:09:02 INFO - 'TERM': 'linux', 21:09:02 INFO - 'TMOUT': '86400', 21:09:02 INFO - 'USER': 'cltbld', 21:09:02 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1482036561.723995-303408795', 21:09:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:09:02 INFO - '_': '/tools/buildbot/bin/python'} 21:09:02 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CtcQ4nFARQ2idasUo0jCOw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--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 21:09:02 INFO - mozversion application_buildid: 20161217201235 21:09:02 INFO - mozversion application_changeset: 5baae61449c43851c961a83babe18adaa28e3c6b 21:09:02 INFO - mozversion application_display_name: Nightly 21:09:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:09:02 INFO - mozversion application_name: Firefox 21:09:02 INFO - mozversion application_remotingname: firefox 21:09:02 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 21:09:02 INFO - mozversion application_vendor: Mozilla 21:09:02 INFO - mozversion application_version: 53.0a1 21:09:02 INFO - mozversion platform_buildid: 20161217201235 21:09:02 INFO - mozversion platform_changeset: 5baae61449c43851c961a83babe18adaa28e3c6b 21:09:02 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 21:09:02 INFO - mozversion platform_version: 53.0a1 21:09:02 INFO - using testdate: 1482037742 21:09:02 INFO - actual date: 1482037742 21:09:02 INFO - starting webserver on 'localhost:33474' 21:09:02 INFO - SUITE-START | Running 2 tests 21:09:02 INFO - TEST-START | dromaeo_css 21:09:02 INFO - operating with platform_type : linux_ 21:09:02 INFO - Initialising browser for dromaeo_css test... 21:09:02 INFO - TEST-INFO | started process 1971 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbACw01/profile http://localhost:33474/getInfo.html) 21:09:07 INFO - PROCESS | 1971 | __metrics Screen width/height:1600/1200 21:09:07 INFO - PROCESS | 1971 | colorDepth:24 21:09:07 INFO - PROCESS | 1971 | Browser inner width/height: 1024/697 21:09:07 INFO - PROCESS | 1971 | __metrics 21:09:07 INFO - PROCESS | 1971 | 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" 21:09:07 INFO - PROCESS | 1971 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:09:08 INFO - PROCESS | 1971 | console.error: PushService: 21:09:08 INFO - PROCESS | 1971 | stateChangeProcessEnqueue: Error transitioning state 21:09:08 INFO - PROCESS | 1971 | UnknownError 21:09:09 INFO - TEST-INFO | 1971: exit 0 21:09:09 INFO - Browser initialized. 21:09:09 INFO - Running cycle 1/1 for dromaeo_css test... 21:09:09 INFO - TEST-INFO | started process 2056 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbACw01/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:09:21 INFO - PROCESS | 2056 | RSS: Main: 155222016 21:09:21 INFO - PROCESS | 2056 | 21:10:11 INFO - PROCESS | 2056 | 1482037811801 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 21:12:25 INFO - PROCESS | 2056 | Cycle 1(1): loaded http://localhost:33474/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33474/tests/dromaeo/cssquery-ext.html) 21:12:27 INFO - PROCESS | 2056 | RSS: Main: 270917632 21:12:27 INFO - PROCESS | 2056 | 21:15:29 INFO - PROCESS | 2056 | Cycle 1(1): loaded http://localhost:33474/tests/dromaeo/cssquery-ext.html (next: http://localhost:33474/tests/dromaeo/cssquery-jquery.html) 21:15:30 INFO - PROCESS | 2056 | RSS: Main: 252559360 21:15:30 INFO - PROCESS | 2056 | 21:18:32 INFO - PROCESS | 2056 | Cycle 1(1): loaded http://localhost:33474/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33474/tests/dromaeo/cssquery-mootools.html) 21:18:33 INFO - PROCESS | 2056 | RSS: Main: 233873408 21:18:33 INFO - PROCESS | 2056 | 21:21:35 INFO - PROCESS | 2056 | Cycle 1(1): loaded http://localhost:33474/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33474/tests/dromaeo/cssquery-prototype.html) 21:21:36 INFO - PROCESS | 2056 | RSS: Main: 188813312 21:21:36 INFO - PROCESS | 2056 | 21:24:39 INFO - PROCESS | 2056 | Cycle 1(1): loaded http://localhost:33474/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33474/tests/dromaeo/cssquery-yui.html) 21:24:40 INFO - PROCESS | 2056 | RSS: Main: 194400256 21:24:40 INFO - PROCESS | 2056 | 21:27:43 INFO - PROCESS | 2056 | Cycle 1(1): loaded http://localhost:33474/tests/dromaeo/cssquery-yui.html (next: http://localhost:33474/tests/dromaeo/cssquery-dojo.html) 21:27:43 INFO - PROCESS | 2056 | RSS: Main: 272044032 21:27:43 INFO - PROCESS | 2056 | 21:27:43 INFO - PROCESS | 2056 | __start_tp_report 21:27:43 INFO - PROCESS | 2056 | _x_x_mozilla_page_load 21:27:43 INFO - PROCESS | 2056 | _x_x_mozilla_page_load_details 21:27:43 INFO - PROCESS | 2056 | |i|pagename|runs| 21:27:43 INFO - PROCESS | 2056 | |0;dojo.html;2564.192279431979;2585.6250843627663;2598.2075466982574;2619.6856377234735;2631.9605205921926;4585.105904348653;4650.883727906804;4651.232546629806;4658.604018658414;4661.930071048937;4685.953140468603;4699.365585645936;4708.387909571759;4721.716696998174;4761.023990082033;4301.763403012833;4301.784910754465;4341.804618792146;4365.563443655641;4450.265706158496;3950.1507175957163;4282.03654177809;4317.15815415994;4373.650107991353;4414.09511050234;4249.192653395845;4272.6795490338345;4322.848700295506;4378.62781663559;4413.6910416270875;4297.39836422901;4301.311790113598;4306.870793876189;4314.633256173229;4319.287317592593;4292.720973136736;4295.3771703102875;4295.52749197588;4297.183535128347;4301.2902871026245;4655.580997710223;4689;4698.8355407560575;4735.502772208903;4770.642201834877;5107.259447380109;5113.105206588869;5114.69311841287;5116.258142569344;5168.198929165986;5113.974430127862;5123.948760512384;5127.717975511346;5134.101532231844;5173.793048278064;4585.060062687141;4616.215243408628;4617.145828021827;4663.323818046432;4702.576768090888;5119.667221630582;5166.276721259027;5173.922391164136;5203.16749320107;5218.634695571273;4922.4093108826455;4944.53026962438;4958.727270000185;4964.329815474937;5005.224190250517;4915.877103072452;4944.134776414113;4945.010997800454;5051.520105590037;5052.317937078521;4911;4915.090708218991;4923.876903077452;5004.749762511859;5006.374203224597;4948.035133149001;4952.777125029383;4969.204927211701;4991.875203119878;5009.023240468074;4133.793310334471;4134.4831896013;4143.6685065194715;4172.186423647359;4178.331466965271;3554.5556805399324;3572.142685755452;3572.231970126383;3612.4942508048894;3651.1602331463914;4302.634276086505;4304.311310190417;4328.718633288826;4337.110892267077;4378.036831896978;5006.824761133415;5043.697378157322;5049.469805670352;5060.873478163002;5108.2848401223855;4222.831086756495;4336.046069864625;4338.891527711832;4342.761148136819;4379.167958087953;4451.59935605797;4509.301058335963;4535.319702044654;4542.772861356919;4601.539846015372;4756.6670333077045;4766.523347665206;4766.928496072631;4770.0936822003705;4811.831585894477;5127.846164615067;5130.281760553451;5134.7175905324075;5137.434882162885;5188.896222075613;4504.954950450454;4528.071745292273;4529.773511324486;4581.381513495636;4585.9312110317705;4957.256411538298;4974.0797952378925;5002.074616196015;5023.547880690755;5026.44709082008;5050.570701490267;5051.469595692546;5053.722045287397;5069.340985671839;5099.464556221692;4778.546038126373;4881.804727810848;4934.062528119646;4934.062528119646;4980.078685443204;4799.856004319876;4841.443234028132;4850.029994001285;4868.026394720999;4940.851774446773;4799.968006878538;4805.399325084364;4826.0830442215865;4834.830780922651;4872.707637541617;4864.440589332272;4914.631402644744;4918.852434426973;4923.286123511999;4970.776315065903;4703;4720.504347043511;4727.692699974491;4776.5701086902345;4777.498362672009 21:27:43 INFO - PROCESS | 2056 | |1;ext.html;509.7986295413312;542.5957661542152;542.8480025592833;560.624381664285;567.2455634006772;27460.31349216268;30230.99999999997;30245.395092098173;36382.454263186075;38003.61996380035;38698.61301386985;39047.80476097623;39092.21815563694;39134;39146.8042659787;7556.735514256989;7662.927190193377;7699.769006929773;7735.2264773522475;7738.729144479944;7304.890426643605;7490.4756667033325;7492.213317601628;7578.848423031536;7681.807954801119;9701.611935522571;9929.453880036595;9937.055979681918;10274.845877311845;10279.743006424824;9582.425054496718;9661;9667.371620844622;9670.274729395323;9676.903230967671;9582.329236953416;9661.178799802025;9662.37194582357;9665.323427360088;9666;36547.81726091352;36820.263594728225;36834.63165368339;36852.447213291554;36923.26153476915;2955.4236923799867;3040.361524079946;3148.8740450381956;3261.2173078461237;3333.799972001688;27072.45855082907;27682.584761228598;27796.57628389354;27901.860490697618;28458.146255612366;3446.758726889119;3485;3513.6662017108333;3523.8766643167623;3535.2399234164777;231322;232077;233764;233898;234256.82871585703;52324;52398.95202096013;52658;52664;52675.209871851956;54976.450235497934;55000.17499737507;55119.173212401845;55136;55240.44759552433;13322.800157997637;13959.581212563638;13974.790378144337;13999.860001400059;14040.08739431954;10275.383476991405;10482.371057736407;10484.370937743759;10496.370217786956;10545.31455455393;14428.537151083665;14925.70148597022;14927.179005154821;16365.509034728977;16591.170441477876;4942.579880710108;5854.560907931837;6062.696865156725;6142.262928448526;6236.875262494725;681.0669382945298;737.8782500887401;843.325339728215;863.0377129500562;923.1875949164827;26546.867265663546;26886.462270754477;27247.182584522496;27325.316867078083;27514.724852751617;830.759079866844;853.5348235211825;876.4653561327585;896.6368620708623;1005.3314545826943;14111.858881411259;14418.13491190532;14576.63558411027;14623.19572423527;14745.557633271019;331450;335181;335823;336372;336440;42206.15587688229;43077.78461107674;43147.78426107912;46871.29693054607;47545.762271189116;1114.1365441782684;1124.859392575928;1140.2360418519777;1151.9654410367702;1163.5927425401042;26701.06546270949;27912.72087279101;28399;29072.709272907;29080.563791543565;42627.5737242636;43191.78404107959;43203;43295.783521082194;43305.35041974436;310207;311146;458421;472468;472763;22797.658035129156;22964.655530167394;23129.306120816404;23275.741396204885;23327.41681457977;34933.12667183341;35324.64675353316;35410.11474713153;35758.10604734902;36727.26545469129;27105.86447067831;28506.85746571254;28636.713632864237;28748.856255718587;29292.70707292985;29100.27249318784;29257.268568285963;29742;30083.097507074825;30222.84888575543;28928.7107128926;29253;29330.706692932796;29458;29668.851655741582;26497.072602458822;27089.593656095567;27811.721882780912;27879.616417031593;28143.43713125767;21682.132714691867;21724.23965161212;21754.97168674982;22009.66985495187;22065.33803985883 21:27:43 INFO - PROCESS | 2056 | |2;jquery.html;336.8866144377155;355.77408345700474;364.11156777462986;366.3058504134665;375.91353988582625;12279.815802762965;15257.542273731791;15535.611609709764;16755;16902.74645880313;15891.761623575656;16231.513054608351;16569.83430165698;16611.83388166118;16782.58043548912;4852.636052296092;4853.514648535153;4978.352814134158;5036.521530454601;5049.899002019975;3604.2250916053044;4960.97519512402;4988.451270360258;4994.400671919375;5052.065367906931;3769.6795772359383;5448.91826622601;5667;5682.829515114553;5826.951148793216;5639;5798.681072541009;5808.680522571257;5812.273465816773;5817.0983497557945;5171.870703232412;5174.922376164361;5175.792968281265;5177.301064356301;5177.948220517785;19601.80398196014;19780.703289450525;19818.702719459074;19886.303979360797;19920.103595338245;1791.5073354827396;2211.1929145861804;2482.900683972639;2492.6011838105815;2500.9374765630823;12251.509939602405;12829.67925801853;13048.347582620925;13306.002049846204;13375.598732038054;1667.7998640163137;1749.95625109372;1820.2264037783889;1822.0616382562991;1856.2389420337731;67553;69744.65127674329;70306.64846675785;71089.28910710878;71187.64406177936;9664.43892368523;9677.77411195415;9830.411899656574;10065.194784417228;10068;13395;13408.262545560088;13410.396532156077;13410.530631427853;13412.12821166621;6754.662266886636;6917.27368626287;7001.544899581511;7005.789826305219;7120.537165084253;6592.472602191813;6671.299513551105;6769.8646027079185;6794.082798822095;6871;4002.2795896738285;4048.7773172475795;4086.611771881726;4145.419641250227;4163.937540936889;1783.2242974306218;1786.2765579940144;1830.405118336546;1838.6322735452964;1853.5829438376527;751.6617522115071;787.8030492376906;820.9014918209733;873.5981448533746;885.8882102960742;15485.845141548665;16126.354945802272;16289.83710162907;16429;17005.659886802194;809.4576633655465;814.2183503836384;830.4311546590484;839.7144970709988;880.9780654440818;8437.198466145706;9378.812423751488;9442.48066356357;9452.243820494481;9606.471644059504;331371;340275;340695;341029;341879;71060.64469677619;71262.64368678123;73315.63342183361;75757.62121189469;75874.62062689761;882.5631312500353;901.1079031758512;902.3322741171617;904.1591320072264;909.88171537701;14468.927655361656;15264;15720.92139539295;15806.288717007532;16126.2743176555;33695.663043369255;34016.14959626029;34595.48106778451;37035;38475.2304953905;42411;48049;49826.77165354331;50118.24822627591;52595;10471.528781205016;10564.260501764802;10692.19808514349;11081.667549973514;11392.316461012366;11922.701932451759;12005.63983080509;12330.260184388966;12419.813702794285;12934.288614125937;10678;10856.837147442953;11249;11251.156163287622;11475.598354057567;11023.503942322453;11046.33721976684;11202.66392008241;11208.943955280498;11252.099832013582;10961.780764384508;10996.735375431608;11536.82694759596;11760.235584686969;11767.76464470723;11023.283486573348;11054.834177487504;11135.55457781712;11405.486753095965;11570.94214528922;10899.29154604948;11158.163137764537;11160.441977901235;11179.776404471702;11214 21:27:43 INFO - PROCESS | 2056 | |3;mootools.html;1223;1306.705991151991;1337.4917531338092;1404.8595140485952;1651.2486818497584;6351.33311002345;6872.381485666289;7127.180374256962;7146.96426517868;7275.781726548212;7135.500514963942;7196.568205907649;7207.351338379544;7209.134903811537;7210.4952653314285;1805.3861687026408;1866.5333666583354;2083.499960009602;2120.1837292642367;2121.8302535797097;2087.6972838938345;2183.159483598818;2187.125149940021;2195.242641288753;2195.3084778294788;2145.989270053655;2163.156369016085;2177.16179270981;2178.596959562478;2185.8579192352527;2159.838012149095;2174.2172817785586;2183.836212284085;2187;2187.6062308784494;2087.91648334068;2111.873287602749;2113.830893528514;2114.7990940860595;2116.714243577113;7342.118945726547;7346.742863999734;7457.366123879477;7477.813054673622;7487.700491980314;4353.521112677603;4480.574345437179;4509.143262780061;4510.684252102355;4510.774461276923;2766.405222877071;2812.328288126288;2993.760499160062;3003.609530761009;3009.232645675361;827.7831587566328;872.2237208884098;882.8763973043779;885.8626912828522;923.4736200365794;9887.703368898945;9893.30746847721;9931.15585175261;9933;9955.800883982352;3845.115623406632;4019.698522610757;4077.327241005201;4081.9795901020307;4189.581041895786;8556.459837229235;8566.785830354167;8567.314614830799;8569;8580.528070956158;4454;4467.173572889026;4469.4189755332245;4469.620082293041;4472.664550158751;3493.9301213975577;3602.495650608917;3937.9606203938165;4057.8376864925663;4063.167050754588;3314.784539004957;3354.9329013419597;3389.7288216942584;3410.3520331137006;3490.406630872707;1109.5617231193633;1132.1565433751744;1138.5502726423017;1140.5323817234894;1143.17691262291;735.3859527294662;813.9511629302261;815.5392203405057;815.8939337886155;826.0871736730826;4706.152892479318;4757.119932812371;4802.73584952824;4846.539578740014;5023.497650234947;408.93661482470264;409.823775776413;411.98764037078934;414.8900541356543;416.2445162030904;3378.8141652209365;3398.7790793598833;3404.30211806584;3413.5391722117197;3569.161247106928;8647.87028194565;8805.559722013873;8834.602442890086;8838.911610883808;8844.601992910335;8849.115088491228;8854.557272136497;8870.512121833232;8883.33374996865;8926.68756593516;6888.311168883071;6967.198772141167;6974.163100427981;6979.092717946736;6990.4757143214465;4667.556582124693;4688.6483513737285;4734.597559207506;4868.440129385166;5018.247262910447;8070.0719417267765;8129.593520323841;8171.1828817120195;8200.343972482067;8245.95877020611;8092.231238032377;8189.50862948225;8224.095349511668;8228.588570571328;8242.793930151554;5743.339515955551;6427.807165785034;6499.252585952487;6517.576357536745;6529.281779004401;6379.7129129190225;6519.706613202512;6719.39525442693;6746.494012949147;6962.860742785015;6199;6341.239051313687;6344.333844946214;6371.681415929278;6552.115464412243;6423.871522569429;6486.05952136947;6522;6524.739010439529;6563.901541476786;6033.9698301508215;6142.846428839136;6144.508439324933;6146.846328841814;6150.200473938359;4182.686298527562;4217.98768295613;4324.783760811883;4343.283358245854;4489.753063581404;4197.349410841324;4307.8492252771;4376.212281789309;4395.296752519582;4413.7793110345 21:27:43 INFO - PROCESS | 2056 | |4;prototype.html;349.63113914819866;354.1977421141116;357.0858601978934;357.865800324878;359.1183644153603;4823.975880120599;4826.444958829736;4854.611631069511;4857.902841943159;4860.343853579766;4519.531152375474;4769.976150119253;4794.808207671697;4803.927941080887;4811.807527698897;3753.11801726594;3757.8684746033896;3776.2825063237897;3811.1996480739076;3841.0397400649836;3815.294170578438;3823.1589050408866;3836.769793812366;3838.6353296436932;3846.4614953906475;3777.9433308500393;3798.9430158547643;3825.4261860720976;3827.3302172119907;3827.6937844972335;3663.8534458621625;3749.2314075614436;3764.341240282953;3840.8655697050467;3846.9230615387814;3449.068751437098;3449.482577613353;3457.567804024497;3461;3461.5326930864517;4655.975685349218;4662.160811054043;4662.487126416091;4678.532146785328;4682.461516925563;3995.7802320872343;4192.392103145057;4343.283358245885;4404.779761011969;4410.955890441087;4928.211486162233;4935.851924442272;4946.431160416562;4952.653314268003;4965.553100220997;3539.0975301298263;3541.8937431877084;3557.4308110702164;3560.1989552350774;3570.3394871948512;4985.775640096204;5168.483151684839;5230.267762513213;5255.846492104744;5259.500347466985;3623.836927338276;3641.6358364163902;3650.7261955353456;3654.908627284286;3655.5065066216252;3691.9077023074638;3692.8522859085338;3692.9261414771554;3696.482492451059;3703.703703703697;4204.306289462204;4207.368894665763;4220.957790422118;4223.704340696122;4230.344296633965;4870.805167793319;4935.383077115361;4954.8761280968265;5023.14606516893;5040.521150490697;1235.172434468908;1243.570968016033;1247.2204871955028;1248.5630029489605;1256.9057320700801;815.0463957170125;833.9457935234191;840.8906842110608;853.8164294676675;855.9871601925976;4334.41485399467;4455.198064348384;4476.2837945928495;4488.910221795546;4503.932441013388;5154.407243167008;5165.147750621106;5169.53474187325;5185.481451854785;5205.531502164823;4297.315925641066;4462.576055274743;4465.531119232499;4476.529964353761;4497.2129877272;2675.2643023168785;2698.2984424049496;2700;2707.1878436468983;2708.295843080813;4705.505921878222;4730.716157030589;4756.857294281177;4771.520828543163;4775.331453596499;4896.507210744955;5001.699558114917;5043.8903441242865;5066.797328106908;5152.3044389007755;4623.69945953512;4655.580997710223;4702.388689470347;4755.358026666356;4763.809447622057;5072.239164125411;5084.694918304914;5096.949030509648;5112.412072611549;5124.8206312778875;4824.396950381202;4981.402231732215;4982.829035176658;5026.447090819934;5026.84919452417;4721.197396442544;4732.645051621073;4732.680986316611;4742.691725037862;4747.905041899212;3554.400519766064;3573.2317551726505;3579.2304654499408;3586.1214002569536;3587.3363427764903;4844.781984810622;5002.624803139852;5024;5039.067772462106;5051.4443411225475;4831;4880.902381952412;4882.633802464758;4888.780004899717;4890.951090489049;4823.686460380064;4864.73243971585;4866.8562887720645;4889.701840135131;4895.936372810487;4630.189716799614;4786.353842231254;4793.880152996203;4801.783919723551;4829.73436460998;4882.852529655599;4924.513120873936;4933.309336692938;4935.728534930469;4937.28409380645;4743.573078422958;4791.5687588117235;4793.23308270689;4810.584485015678;4811.302361157543 21:27:43 INFO - PROCESS | 2056 | |5;yui.html;620.2804746494068;626.8432891777059;639.3095456906541;644.3846126948763;738.9433110651769;12160.695982600439;13833.654158646039;15127;15183.468578599752;15202;15199.620009499768;15361.771058315337;15389.384424623002;15404.845951540481;15409.537713868602;616.3343588923951;762.9313361797451;784.7488803582855;786.8111653203218;787.1380837982429;635.0981606119321;756.4780301591911;777.7433446962517;786.5713186313473;790.7943934576994;823.3289868756974;825.9504429734235;826.0252901576167;826.4545400035978;826.4793180296435;645.5190882792302;819.512390127873;819.737683941139;820.0774129104786;820.1183727492939;486.80527788884376;588.2411688921286;601.5909181756404;604.6281536854847;607.268241768672;13821.239831809247;13838.861611383858;13848.376823042987;13858.584242472742;13860.02979791415;12369.13416060876;12422.37888105591;12467.93766031164;12572.811407828889;12758.61724148277;11741.647750567497;11775.94112029443;11879.465424055938;11919.642410727693;12012.339321337324;3977.3835055566433;4017.296973029708;4021.879343619696;4024.8591299304676;4055.2903241932545;64006.359936400506;64047.67976160136;64071;64709.352906470805;65220.67389663069;9518.762030949143;9575.712728618153;9812.460314682761;9905.10854023127;10120.392776433437;17973.37093201732;18101.004444755665;18135.183916723516;18325;18445.53886152857;6518.348165183444;6593.538452308292;6627.5029372797235;6692.698828552727;6980.7905762827195;3647.106458917529;3759.4172903200047;3767.6420740029653;3780.319542482325;3782.2813665404037;2716.809823312349;2768.5155097857796;2772.9029483967965;2774.6531683539556;2811.1144989328704;463.2425983516974;526.4340833603815;543.1337017457149;547.1655725019377;548.9258950041773;567.2427309541808;621.9844503887349;639.5491178719085;644.5359341274275;649.7693318871827;8124.634391452399;8307;8409;8416.242538171595;8449.493030418194;561.4413658409827;595.5354823237882;595.5622617376225;617.2531237202945;620.559402823991;3888.8250028748776;4162.791860406967;4200.3699445082875;4236.978815105905;4273.658107351404;62652.373476265566;64018.67990660017;64985.35014649887;67056.66471667709;69129.65435172792;55784.72107639517;56045.43954560484;57601;59377.406225937186;59435.70282148562;49834.50165498299;50421.24368134481;51259.487405126216;52031.73984130055;53081.46918530765;7768.88346674789;7878.921210787818;7908.960455197917;8062.677492900219;8099.14958929304;35516.644833551334;35674.14606831395;35763.64236357603;36023.45964810478;37390;43982.78008610065;45738;48932;49084.263736044704;50344;9228.40015398997;9510.373774937625;9538.618455261712;9751.171150452008;9924.40453572788;11233.494492747683;11273.436328183394;11398.316101033965;11425;11660.650180494598;10972.670819875417;10999.61501347449;11121.833172502256;11203.383813889988;11554.711132221762;10330.638427654996;10682.679519614425;10761.730956726144;11139.551858258375;11184.049355804522;11117;11144.832827507757;11609.825852612048;11693.123015774021;11774.587889423829;8595.398322117391;8764.430312029697;8902.777430564287;8920.610168136067;8931.642734290555;8983.978604814127;9558.904410955802;9716.951415242878;9755.756106097404;9874.50627468638 21:27:43 INFO - PROCESS | 2056 | __end_tp_report 21:27:43 INFO - PROCESS | 2056 | __start_cc_report 21:27:43 INFO - PROCESS | 2056 | _x_x_mozilla_cycle_collect,1300 21:27:43 INFO - PROCESS | 2056 | __end_cc_report 21:27:43 INFO - PROCESS | 2056 | __startTimestamp1482038863411__endTimestamp 21:27:43 INFO - PROCESS | 2056 | 21:27:43 INFO - PROCESS | 2056 | ------- Summary: start ------- 21:27:43 INFO - PROCESS | 2056 | Number of tests: 6 21:27:43 INFO - PROCESS | 2056 | 21:27:43 INFO - PROCESS | 2056 | [#0] dojo.html Cycles:165 Average:4633.73 Median:4761.02 stddev:509.83 (10.7%) stddev-sans-first:484.85 21:27:43 INFO - PROCESS | 2056 | Values: 2564.2 2585.6 2598.2 2619.7 2632.0 4585.1 4650.9 4651.2 4658.6 4661.9 4686.0 4699.4 4708.4 4721.7 4761.0 4301.8 4301.8 4341.8 4365.6 4450.3 3950.2 4282.0 4317.2 4373.7 4414.1 4249.2 4272.7 4322.8 4378.6 4413.7 4297.4 4301.3 4306.9 4314.6 4319.3 4292.7 4295.4 4295.5 4297.2 4301.3 4655.6 4689.0 4698.8 4735.5 4770.6 5107.3 5113.1 5114.7 5116.3 5168.2 5114.0 5123.9 5127.7 5134.1 5173.8 4585.1 4616.2 4617.1 4663.3 4702.6 5119.7 5166.3 5173.9 5203.2 5218.6 4922.4 4944.5 4958.7 4964.3 5005.2 4915.9 4944.1 4945.0 5051.5 5052.3 4911.0 4915.1 4923.9 5004.7 5006.4 4948.0 4952.8 4969.2 4991.9 5009.0 4133.8 4134.5 4143.7 4172.2 4178.3 3554.6 3572.1 3572.2 3612.5 3651.2 4302.6 4304.3 4328.7 4337.1 4378.0 5006.8 5043.7 5049.5 5060.9 5108.3 4222.8 4336.0 4338.9 4342.8 4379.2 4451.6 4509.3 4535.3 4542.8 4601.5 4756.7 4766.5 4766.9 4770.1 4811.8 5127.8 5130.3 5134.7 5137.4 5188.9 4505.0 4528.1 4529.8 4581.4 4585.9 4957.3 4974.1 5002.1 5023.5 5026.4 5050.6 5051.5 5053.7 5069.3 5099.5 4778.5 4881.8 4934.1 4934.1 4980.1 4799.9 4841.4 4850.0 4868.0 4940.9 4800.0 4805.4 4826.1 4834.8 4872.7 4864.4 4914.6 4918.9 4923.3 4970.8 4703.0 4720.5 4727.7 4776.6 4777.5 21:27:43 INFO - PROCESS | 2056 | 21:27:43 INFO - PROCESS | 2056 | [#1] ext.html Cycles:180 Average:46389.89 Median:336097.50 stddev:88720.84 (26.4%) stddev-sans-first:88902.85 21:27:43 INFO - PROCESS | 2056 | Values: 509.8 542.6 542.8 560.6 567.2 27460.3 30231.0 30245.4 36382.5 38003.6 38698.6 39047.8 39092.2 39134.0 39146.8 7556.7 7662.9 7699.8 7735.2 7738.7 7304.9 7490.5 7492.2 7578.8 7681.8 9701.6 9929.5 9937.1 10274.8 10279.7 9582.4 9661.0 9667.4 9670.3 9676.9 9582.3 9661.2 9662.4 9665.3 9666.0 36547.8 36820.3 36834.6 36852.4 36923.3 2955.4 3040.4 3148.9 3261.2 3333.8 27072.5 27682.6 27796.6 27901.9 28458.1 3446.8 3485.0 3513.7 3523.9 3535.2 231322.0 232077.0 233764.0 233898.0 234256.8 52324.0 52399.0 52658.0 52664.0 52675.2 54976.5 55000.2 55119.2 55136.0 55240.4 13322.8 13959.6 13974.8 13999.9 14040.1 10275.4 10482.4 10484.4 10496.4 10545.3 14428.5 14925.7 14927.2 16365.5 16591.2 4942.6 5854.6 6062.7 6142.3 6236.9 681.1 737.9 843.3 863.0 923.2 26546.9 26886.5 27247.2 27325.3 27514.7 830.8 853.5 876.5 896.6 1005.3 14111.9 14418.1 14576.6 14623.2 14745.6 331450.0 335181.0 335823.0 336372.0 336440.0 42206.2 43077.8 43147.8 46871.3 47545.8 1114.1 1124.9 1140.2 1152.0 1163.6 26701.1 27912.7 28399.0 29072.7 29080.6 42627.6 43191.8 43203.0 43295.8 43305.4 310207.0 311146.0 458421.0 472468.0 472763.0 22797.7 22964.7 23129.3 23275.7 23327.4 34933.1 35324.6 35410.1 35758.1 36727.3 27105.9 28506.9 28636.7 28748.9 29292.7 29100.3 29257.3 29742.0 30083.1 30222.8 28928.7 29253.0 29330.7 29458.0 29668.9 26497.1 27089.6 27811.7 27879.6 28143.4 21682.1 21724.2 21755.0 22009.7 22065.3 21:27:43 INFO - PROCESS | 2056 | 21:27:43 INFO - PROCESS | 2056 | [#2] jquery.html Cycles:180 Average:22975.94 Median:187145.57 stddev:56182.00 (30.0%) stddev-sans-first:56313.89 21:27:43 INFO - PROCESS | 2056 | Values: 336.9 355.8 364.1 366.3 375.9 12279.8 15257.5 15535.6 16755.0 16902.7 15891.8 16231.5 16569.8 16611.8 16782.6 4852.6 4853.5 4978.4 5036.5 5049.9 3604.2 4961.0 4988.5 4994.4 5052.1 3769.7 5448.9 5667.0 5682.8 5827.0 5639.0 5798.7 5808.7 5812.3 5817.1 5171.9 5174.9 5175.8 5177.3 5177.9 19601.8 19780.7 19818.7 19886.3 19920.1 1791.5 2211.2 2482.9 2492.6 2500.9 12251.5 12829.7 13048.3 13306.0 13375.6 1667.8 1750.0 1820.2 1822.1 1856.2 67553.0 69744.7 70306.6 71089.3 71187.6 9664.4 9677.8 9830.4 10065.2 10068.0 13395.0 13408.3 13410.4 13410.5 13412.1 6754.7 6917.3 7001.5 7005.8 7120.5 6592.5 6671.3 6769.9 6794.1 6871.0 4002.3 4048.8 4086.6 4145.4 4163.9 1783.2 1786.3 1830.4 1838.6 1853.6 751.7 787.8 820.9 873.6 885.9 15485.8 16126.4 16289.8 16429.0 17005.7 809.5 814.2 830.4 839.7 881.0 8437.2 9378.8 9442.5 9452.2 9606.5 331371.0 340275.0 340695.0 341029.0 341879.0 71060.6 71262.6 73315.6 75757.6 75874.6 882.6 901.1 902.3 904.2 909.9 14468.9 15264.0 15720.9 15806.3 16126.3 33695.7 34016.1 34595.5 37035.0 38475.2 42411.0 48049.0 49826.8 50118.2 52595.0 10471.5 10564.3 10692.2 11081.7 11392.3 11922.7 12005.6 12330.3 12419.8 12934.3 10678.0 10856.8 11249.0 11251.2 11475.6 11023.5 11046.3 11202.7 11208.9 11252.1 10961.8 10996.7 11536.8 11760.2 11767.8 11023.3 11054.8 11135.6 11405.5 11570.9 10899.3 11158.2 11160.4 11179.8 11214.0 21:27:43 INFO - PROCESS | 2056 | 21:27:43 INFO - PROCESS | 2056 | [#3] mootools.html Cycles:180 Average:4808.61 Median:4824.64 stddev:2654.47 (55.0%) stddev-sans-first:2648.23 21:27:43 INFO - PROCESS | 2056 | Values: 1223.0 1306.7 1337.5 1404.9 1651.2 6351.3 6872.4 7127.2 7147.0 7275.8 7135.5 7196.6 7207.4 7209.1 7210.5 1805.4 1866.5 2083.5 2120.2 2121.8 2087.7 2183.2 2187.1 2195.2 2195.3 2146.0 2163.2 2177.2 2178.6 2185.9 2159.8 2174.2 2183.8 2187.0 2187.6 2087.9 2111.9 2113.8 2114.8 2116.7 7342.1 7346.7 7457.4 7477.8 7487.7 4353.5 4480.6 4509.1 4510.7 4510.8 2766.4 2812.3 2993.8 3003.6 3009.2 827.8 872.2 882.9 885.9 923.5 9887.7 9893.3 9931.2 9933.0 9955.8 3845.1 4019.7 4077.3 4082.0 4189.6 8556.5 8566.8 8567.3 8569.0 8580.5 4454.0 4467.2 4469.4 4469.6 4472.7 3493.9 3602.5 3938.0 4057.8 4063.2 3314.8 3354.9 3389.7 3410.4 3490.4 1109.6 1132.2 1138.6 1140.5 1143.2 735.4 814.0 815.5 815.9 826.1 4706.2 4757.1 4802.7 4846.5 5023.5 408.9 409.8 412.0 414.9 416.2 3378.8 3398.8 3404.3 3413.5 3569.2 8647.9 8805.6 8834.6 8838.9 8844.6 8849.1 8854.6 8870.5 8883.3 8926.7 6888.3 6967.2 6974.2 6979.1 6990.5 4667.6 4688.6 4734.6 4868.4 5018.2 8070.1 8129.6 8171.2 8200.3 8246.0 8092.2 8189.5 8224.1 8228.6 8242.8 5743.3 6427.8 6499.3 6517.6 6529.3 6379.7 6519.7 6719.4 6746.5 6962.9 6199.0 6341.2 6344.3 6371.7 6552.1 6423.9 6486.1 6522.0 6524.7 6563.9 6034.0 6142.8 6144.5 6146.8 6150.2 4182.7 4218.0 4324.8 4343.3 4489.8 4197.3 4307.8 4376.2 4395.3 4413.8 21:27:43 INFO - PROCESS | 2056 | 21:27:43 INFO - PROCESS | 2056 | [#4] prototype.html Cycles:180 Average:4129.66 Median:4713.35 stddev:1173.37 (24.9%) stddev-sans-first:1141.84 21:27:43 INFO - PROCESS | 2056 | Values: 349.6 354.2 357.1 357.9 359.1 4824.0 4826.4 4854.6 4857.9 4860.3 4519.5 4770.0 4794.8 4803.9 4811.8 3753.1 3757.9 3776.3 3811.2 3841.0 3815.3 3823.2 3836.8 3838.6 3846.5 3777.9 3798.9 3825.4 3827.3 3827.7 3663.9 3749.2 3764.3 3840.9 3846.9 3449.1 3449.5 3457.6 3461.0 3461.5 4656.0 4662.2 4662.5 4678.5 4682.5 3995.8 4192.4 4343.3 4404.8 4411.0 4928.2 4935.9 4946.4 4952.7 4965.6 3539.1 3541.9 3557.4 3560.2 3570.3 4985.8 5168.5 5230.3 5255.8 5259.5 3623.8 3641.6 3650.7 3654.9 3655.5 3691.9 3692.9 3692.9 3696.5 3703.7 4204.3 4207.4 4221.0 4223.7 4230.3 4870.8 4935.4 4954.9 5023.1 5040.5 1235.2 1243.6 1247.2 1248.6 1256.9 815.0 833.9 840.9 853.8 856.0 4334.4 4455.2 4476.3 4488.9 4503.9 5154.4 5165.1 5169.5 5185.5 5205.5 4297.3 4462.6 4465.5 4476.5 4497.2 2675.3 2698.3 2700.0 2707.2 2708.3 4705.5 4730.7 4756.9 4771.5 4775.3 4896.5 5001.7 5043.9 5066.8 5152.3 4623.7 4655.6 4702.4 4755.4 4763.8 5072.2 5084.7 5096.9 5112.4 5124.8 4824.4 4981.4 4982.8 5026.4 5026.8 4721.2 4732.6 4732.7 4742.7 4747.9 3554.4 3573.2 3579.2 3586.1 3587.3 4844.8 5002.6 5024.0 5039.1 5051.4 4831.0 4880.9 4882.6 4888.8 4891.0 4823.7 4864.7 4866.9 4889.7 4895.9 4630.2 4786.4 4793.9 4801.8 4829.7 4882.9 4924.5 4933.3 4935.7 4937.3 4743.6 4791.6 4793.2 4810.6 4811.3 21:27:43 INFO - PROCESS | 2056 | 21:27:43 INFO - PROCESS | 2056 | [#5] yui.html Cycles:180 Average:14863.92 Median:548.05 stddev:18607.81 (3395.3%) stddev-sans-first:18629.27 21:27:43 INFO - PROCESS | 2056 | Values: 620.3 626.8 639.3 644.4 738.9 12160.7 13833.7 15127.0 15183.5 15202.0 15199.6 15361.8 15389.4 15404.8 15409.5 616.3 762.9 784.7 786.8 787.1 635.1 756.5 777.7 786.6 790.8 823.3 826.0 826.0 826.5 826.5 645.5 819.5 819.7 820.1 820.1 486.8 588.2 601.6 604.6 607.3 13821.2 13838.9 13848.4 13858.6 13860.0 12369.1 12422.4 12467.9 12572.8 12758.6 11741.6 11775.9 11879.5 11919.6 12012.3 3977.4 4017.3 4021.9 4024.9 4055.3 64006.4 64047.7 64071.0 64709.4 65220.7 9518.8 9575.7 9812.5 9905.1 10120.4 17973.4 18101.0 18135.2 18325.0 18445.5 6518.3 6593.5 6627.5 6692.7 6980.8 3647.1 3759.4 3767.6 3780.3 3782.3 2716.8 2768.5 2772.9 2774.7 2811.1 463.2 526.4 543.1 547.2 548.9 567.2 622.0 639.5 644.5 649.8 8124.6 8307.0 8409.0 8416.2 8449.5 561.4 595.5 595.6 617.3 620.6 3888.8 4162.8 4200.4 4237.0 4273.7 62652.4 64018.7 64985.4 67056.7 69129.7 55784.7 56045.4 57601.0 59377.4 59435.7 49834.5 50421.2 51259.5 52031.7 53081.5 7768.9 7878.9 7909.0 8062.7 8099.1 35516.6 35674.1 35763.6 36023.5 37390.0 43982.8 45738.0 48932.0 49084.3 50344.0 9228.4 9510.4 9538.6 9751.2 9924.4 11233.5 11273.4 11398.3 11425.0 11660.7 10972.7 10999.6 11121.8 11203.4 11554.7 10330.6 10682.7 10761.7 11139.6 11184.0 11117.0 11144.8 11609.8 11693.1 11774.6 8595.4 8764.4 8902.8 8920.6 8931.6 8984.0 9558.9 9717.0 9755.8 9874.5 21:27:43 INFO - PROCESS | 2056 | -------- Summary: end -------- 21:27:43 INFO - PROCESS | 2056 | 21:27:44 INFO - TEST-INFO | 2056: exit 0 21:27:44 INFO - TEST-OK | dromaeo_css | took 1121299ms 21:27:44 INFO - TEST-START | kraken 21:27:44 INFO - operating with platform_type : linux_ 21:27:44 INFO - Initialising browser for kraken test... 21:27:44 INFO - TEST-INFO | started process 2359 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpn38apX/profile http://localhost:33474/getInfo.html) 21:27:48 INFO - PROCESS | 2359 | __metrics Screen width/height:1600/1200 21:27:48 INFO - PROCESS | 2359 | colorDepth:24 21:27:48 INFO - PROCESS | 2359 | Browser inner width/height: 1024/697 21:27:48 INFO - PROCESS | 2359 | __metrics 21:27:48 INFO - PROCESS | 2359 | 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" 21:27:48 INFO - PROCESS | 2359 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:27:49 INFO - PROCESS | 2359 | console.error: PushService: 21:27:49 INFO - PROCESS | 2359 | stateChangeProcessEnqueue: Error transitioning state 21:27:49 INFO - PROCESS | 2359 | UnknownError 21:27:50 INFO - TEST-INFO | 2359: exit 0 21:27:50 INFO - Browser initialized. 21:27:50 INFO - Running cycle 1/1 for kraken test... 21:27:50 INFO - TEST-INFO | started process 2514 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpn38apX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:28:02 INFO - PROCESS | 2514 | RSS: Main: 155467776 21:28:02 INFO - PROCESS | 2514 | 21:28:04 INFO - PROCESS | 2514 | Cycle 1(1): loaded http://localhost:33474/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33474/tests/kraken/driver.html?testName=audio-beat-detection) 21:28:05 INFO - PROCESS | 2514 | RSS: Main: 209580032 21:28:05 INFO - PROCESS | 2514 | 21:28:08 INFO - PROCESS | 2514 | Cycle 1(1): loaded http://localhost:33474/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33474/tests/kraken/driver.html?testName=audio-dft) 21:28:09 INFO - PROCESS | 2514 | RSS: Main: 187871232 21:28:09 INFO - PROCESS | 2514 | 21:28:12 INFO - PROCESS | 2514 | Cycle 1(1): loaded http://localhost:33474/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33474/tests/kraken/driver.html?testName=audio-fft) 21:28:12 INFO - PROCESS | 2514 | RSS: Main: 270159872 21:28:12 INFO - PROCESS | 2514 | 21:28:15 INFO - PROCESS | 2514 | Cycle 1(1): loaded http://localhost:33474/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33474/tests/kraken/driver.html?testName=audio-oscillator) 21:28:15 INFO - PROCESS | 2514 | RSS: Main: 181583872 21:28:15 INFO - PROCESS | 2514 | 21:28:17 INFO - PROCESS | 2514 | Cycle 1(1): loaded http://localhost:33474/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33474/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:28:18 INFO - PROCESS | 2514 | RSS: Main: 184872960 21:28:18 INFO - PROCESS | 2514 | 21:28:21 INFO - PROCESS | 2514 | Cycle 1(1): loaded http://localhost:33474/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33474/tests/kraken/driver.html?testName=imaging-darkroom) 21:28:22 INFO - PROCESS | 2514 | RSS: Main: 224333824 21:28:22 INFO - PROCESS | 2514 | 21:28:26 INFO - PROCESS | 2514 | Cycle 1(1): loaded http://localhost:33474/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33474/tests/kraken/driver.html?testName=imaging-desaturate) 21:28:26 INFO - PROCESS | 2514 | RSS: Main: 218189824 21:28:26 INFO - PROCESS | 2514 | 21:28:29 INFO - PROCESS | 2514 | Cycle 1(1): loaded http://localhost:33474/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33474/tests/kraken/driver.html?testName=json-parse-financial) 21:28:30 INFO - PROCESS | 2514 | RSS: Main: 220225536 21:28:30 INFO - PROCESS | 2514 | 21:28:31 INFO - PROCESS | 2514 | Cycle 1(1): loaded http://localhost:33474/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33474/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:28:32 INFO - PROCESS | 2514 | RSS: Main: 183087104 21:28:32 INFO - PROCESS | 2514 | 21:28:34 INFO - PROCESS | 2514 | Cycle 1(1): loaded http://localhost:33474/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33474/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:28:35 INFO - PROCESS | 2514 | RSS: Main: 190517248 21:28:35 INFO - PROCESS | 2514 | 21:28:37 INFO - PROCESS | 2514 | Cycle 1(1): loaded http://localhost:33474/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33474/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:28:37 INFO - PROCESS | 2514 | RSS: Main: 199258112 21:28:37 INFO - PROCESS | 2514 | 21:28:40 INFO - PROCESS | 2514 | Cycle 1(1): loaded http://localhost:33474/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33474/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:28:41 INFO - PROCESS | 2514 | RSS: Main: 208695296 21:28:41 INFO - PROCESS | 2514 | 21:28:44 INFO - PROCESS | 2514 | Cycle 1(1): loaded http://localhost:33474/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33474/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:28:45 INFO - PROCESS | 2514 | RSS: Main: 205332480 21:28:45 INFO - PROCESS | 2514 | 21:28:46 INFO - PROCESS | 2514 | Cycle 1(1): loaded http://localhost:33474/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33474/tests/kraken/driver.html?testName=ai-astar) 21:28:47 INFO - PROCESS | 2514 | RSS: Main: 255922176 21:28:47 INFO - PROCESS | 2514 | 21:28:47 INFO - PROCESS | 2514 | __start_tp_report 21:28:47 INFO - PROCESS | 2514 | _x_x_mozilla_page_load 21:28:47 INFO - PROCESS | 2514 | _x_x_mozilla_page_load_details 21:28:47 INFO - PROCESS | 2514 | |i|pagename|runs| 21:28:47 INFO - PROCESS | 2514 | |0;ai-astar;106;115;113;115;115;114;117;114;114;115 21:28:47 INFO - PROCESS | 2514 | |1;audio-beat-detection;153;124;125;172;125;129;138;124;146;124 21:28:47 INFO - PROCESS | 2514 | |2;audio-dft;161;163;161;162;163;163;162;164;161;162 21:28:47 INFO - PROCESS | 2514 | |3;audio-fft;75;76;76;109;77;76;76;77;93;77 21:28:47 INFO - PROCESS | 2514 | |4;audio-oscillator;85;85;119;85;84;85;103;84;85;116 21:28:47 INFO - PROCESS | 2514 | |5;imaging-gaussian-blur;128;127;128;127;127;128;129;127;127;127 21:28:47 INFO - PROCESS | 2514 | |6;imaging-darkroom;148;148;148;148;147;148;147;147;147;147 21:28:47 INFO - PROCESS | 2514 | |7;imaging-desaturate;96;96;96;96;95;95;96;95;95;95 21:28:47 INFO - PROCESS | 2514 | |8;json-parse-financial;65;64;63;64;66;65;64;66;67;65 21:28:47 INFO - PROCESS | 2514 | |9;json-stringify-tinderbox;66;65;66;65;67;66;65;66;66;65 21:28:47 INFO - PROCESS | 2514 | |10;stanford-crypto-aes;85;84;96;85;84;83;84;85;83;91 21:28:47 INFO - PROCESS | 2514 | |11;stanford-crypto-ccm;143;141;148;132;136;145;142;137;179;140 21:28:47 INFO - PROCESS | 2514 | |12;stanford-crypto-pbkdf2;179;190;180;179;180;178;181;185;183;181 21:28:47 INFO - PROCESS | 2514 | |13;stanford-crypto-sha256-iterative;68;69;68;68;73;68;68;67;68;66 21:28:47 INFO - PROCESS | 2514 | __end_tp_report 21:28:47 INFO - PROCESS | 2514 | __start_cc_report 21:28:47 INFO - PROCESS | 2514 | _x_x_mozilla_cycle_collect,1970 21:28:47 INFO - PROCESS | 2514 | __end_cc_report 21:28:47 INFO - PROCESS | 2514 | __startTimestamp1482038927083__endTimestamp 21:28:47 INFO - PROCESS | 2514 | 21:28:47 INFO - PROCESS | 2514 | ------- Summary: start ------- 21:28:47 INFO - PROCESS | 2514 | Number of tests: 14 21:28:47 INFO - PROCESS | 2514 | 21:28:47 INFO - PROCESS | 2514 | [#0] ai-astar Cycles:10 Average:113.80 Median:115.00 stddev:2.94 (2.6%) stddev-sans-first:1.12 21:28:47 INFO - PROCESS | 2514 | Values: 106.0 115.0 113.0 115.0 115.0 114.0 117.0 114.0 114.0 115.0 21:28:47 INFO - PROCESS | 2514 | 21:28:47 INFO - PROCESS | 2514 | [#1] audio-beat-detection Cycles:10 Average:136.00 Median:133.50 stddev:16.37 (12.3%) stddev-sans-first:16.17 21:28:47 INFO - PROCESS | 2514 | Values: 153.0 124.0 125.0 172.0 125.0 129.0 138.0 124.0 146.0 124.0 21:28:47 INFO - PROCESS | 2514 | 21:28:47 INFO - PROCESS | 2514 | [#2] audio-dft Cycles:10 Average:162.20 Median:162.50 stddev:1.03 (0.6%) stddev-sans-first:1.00 21:28:47 INFO - PROCESS | 2514 | Values: 161.0 163.0 161.0 162.0 163.0 163.0 162.0 164.0 161.0 162.0 21:28:47 INFO - PROCESS | 2514 | 21:28:47 INFO - PROCESS | 2514 | [#3] audio-fft Cycles:10 Average:81.20 Median:76.50 stddev:11.11 (14.5%) stddev-sans-first:11.56 21:28:47 INFO - PROCESS | 2514 | Values: 75.0 76.0 76.0 109.0 77.0 76.0 76.0 77.0 93.0 77.0 21:28:47 INFO - PROCESS | 2514 | 21:28:47 INFO - PROCESS | 2514 | [#4] audio-oscillator Cycles:10 Average:93.10 Median:85.00 stddev:14.09 (16.6%) stddev-sans-first:14.64 21:28:47 INFO - PROCESS | 2514 | Values: 85.0 85.0 119.0 85.0 84.0 85.0 103.0 84.0 85.0 116.0 21:28:47 INFO - PROCESS | 2514 | 21:28:47 INFO - PROCESS | 2514 | [#5] imaging-gaussian-blur Cycles:10 Average:127.50 Median:127.50 stddev:0.71 (0.6%) stddev-sans-first:0.73 21:28:47 INFO - PROCESS | 2514 | Values: 128.0 127.0 128.0 127.0 127.0 128.0 129.0 127.0 127.0 127.0 21:28:47 INFO - PROCESS | 2514 | 21:28:47 INFO - PROCESS | 2514 | [#6] imaging-darkroom Cycles:10 Average:147.50 Median:148.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 21:28:47 INFO - PROCESS | 2514 | Values: 148.0 148.0 148.0 148.0 147.0 148.0 147.0 147.0 147.0 147.0 21:28:47 INFO - PROCESS | 2514 | 21:28:47 INFO - PROCESS | 2514 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:96.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 21:28:47 INFO - PROCESS | 2514 | Values: 96.0 96.0 96.0 96.0 95.0 95.0 96.0 95.0 95.0 95.0 21:28:47 INFO - PROCESS | 2514 | 21:28:47 INFO - PROCESS | 2514 | [#8] json-parse-financial Cycles:10 Average:64.90 Median:65.00 stddev:1.20 (1.8%) stddev-sans-first:1.27 21:28:47 INFO - PROCESS | 2514 | Values: 65.0 64.0 63.0 64.0 66.0 65.0 64.0 66.0 67.0 65.0 21:28:47 INFO - PROCESS | 2514 | 21:28:47 INFO - PROCESS | 2514 | [#9] json-stringify-tinderbox Cycles:10 Average:65.70 Median:66.00 stddev:0.67 (1.0%) stddev-sans-first:0.71 21:28:47 INFO - PROCESS | 2514 | Values: 66.0 65.0 66.0 65.0 67.0 66.0 65.0 66.0 66.0 65.0 21:28:47 INFO - PROCESS | 2514 | 21:28:47 INFO - PROCESS | 2514 | [#10] stanford-crypto-aes Cycles:10 Average:86.00 Median:85.00 stddev:4.19 (4.9%) stddev-sans-first:4.43 21:28:47 INFO - PROCESS | 2514 | Values: 85.0 84.0 96.0 85.0 84.0 83.0 84.0 85.0 83.0 91.0 21:28:47 INFO - PROCESS | 2514 | 21:28:47 INFO - PROCESS | 2514 | [#11] stanford-crypto-ccm Cycles:10 Average:144.30 Median:142.50 stddev:13.03 (9.1%) stddev-sans-first:13.81 21:28:47 INFO - PROCESS | 2514 | Values: 143.0 141.0 148.0 132.0 136.0 145.0 142.0 137.0 179.0 140.0 21:28:47 INFO - PROCESS | 2514 | 21:28:47 INFO - PROCESS | 2514 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.60 Median:181.00 stddev:3.60 (2.0%) stddev-sans-first:3.69 21:28:47 INFO - PROCESS | 2514 | Values: 179.0 190.0 180.0 179.0 180.0 178.0 181.0 185.0 183.0 181.0 21:28:47 INFO - PROCESS | 2514 | 21:28:47 INFO - PROCESS | 2514 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.30 Median:68.00 stddev:1.83 (2.7%) stddev-sans-first:1.94 21:28:47 INFO - PROCESS | 2514 | Values: 68.0 69.0 68.0 68.0 73.0 68.0 68.0 67.0 68.0 66.0 21:28:47 INFO - PROCESS | 2514 | -------- Summary: end -------- 21:28:47 INFO - PROCESS | 2514 | 21:28:48 INFO - TEST-INFO | 2514: exit 0 21:28:48 INFO - TEST-OK | kraken | took 63937ms 21:28:48 INFO - SUITE-END | took 1185s 21:28:48 INFO - Completed test suite (00:19:45) 21:28:48 INFO - javascript benchmark 21:28:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2564.192279431979, 2585.6250843627663, 2598.2075466982574, 2619.6856377234735, 2631.9605205921926, 4585.105904348653, 4650.883727906804, 4651.232546629806, 4658.604018658414, 4661.930071048937, 4685.953140468603, 4699.365585645936, 4708.387909571759, 4721.716696998174, 4761.023990082033, 4301.763403012833, 4301.784910754465, 4341.804618792146, 4365.563443655641, 4450.265706158496, 3950.1507175957163, 4282.03654177809, 4317.15815415994, 4373.650107991353, 4414.09511050234, 4249.192653395845, 4272.6795490338345, 4322.848700295506, 4378.62781663559, 4413.6910416270875, 4297.39836422901, 4301.311790113598, 4306.870793876189, 4314.633256173229, 4319.287317592593, 4292.720973136736, 4295.3771703102875, 4295.52749197588, 4297.183535128347, 4301.2902871026245, 4655.580997710223, 4689.0, 4698.8355407560575, 4735.502772208903, 4770.642201834877, 5107.259447380109, 5113.105206588869, 5114.69311841287, 5116.258142569344, 5168.198929165986, 5113.974430127862, 5123.948760512384, 5127.717975511346, 5134.101532231844, 5173.793048278064, 4585.060062687141, 4616.215243408628, 4617.145828021827, 4663.323818046432, 4702.576768090888, 5119.667221630582, 5166.276721259027, 5173.922391164136, 5203.16749320107, 5218.634695571273, 4922.4093108826455, 4944.53026962438, 4958.727270000185, 4964.329815474937, 5005.224190250517, 4915.877103072452, 4944.134776414113, 4945.010997800454, 5051.520105590037, 5052.317937078521, 4911.0, 4915.090708218991, 4923.876903077452, 5004.749762511859, 5006.374203224597, 4948.035133149001, 4952.777125029383, 4969.204927211701, 4991.875203119878, 5009.023240468074, 4133.793310334471, 4134.4831896013, 4143.6685065194715, 4172.186423647359, 4178.331466965271, 3554.5556805399324, 3572.142685755452, 3572.231970126383, 3612.4942508048894, 3651.1602331463914, 4302.634276086505, 4304.311310190417, 4328.718633288826, 4337.110892267077, 4378.036831896978, 5006.824761133415, 5043.697378157322, 5049.469805670352, 5060.873478163002, 5108.2848401223855, 4222.831086756495, 4336.046069864625, 4338.891527711832, 4342.761148136819, 4379.167958087953, 4451.59935605797, 4509.301058335963, 4535.319702044654, 4542.772861356919, 4601.539846015372, 4756.6670333077045, 4766.523347665206, 4766.928496072631, 4770.0936822003705, 4811.831585894477, 5127.846164615067, 5130.281760553451, 5134.7175905324075, 5137.434882162885, 5188.896222075613, 4504.954950450454, 4528.071745292273, 4529.773511324486, 4581.381513495636, 4585.9312110317705, 4957.256411538298, 4974.0797952378925, 5002.074616196015, 5023.547880690755, 5026.44709082008, 5050.570701490267, 5051.469595692546, 5053.722045287397, 5069.340985671839, 5099.464556221692, 4778.546038126373, 4881.804727810848, 4934.062528119646, 4934.062528119646, 4980.078685443204, 4799.856004319876, 4841.443234028132, 4850.029994001285, 4868.026394720999, 4940.851774446773, 4799.968006878538, 4805.399325084364, 4826.0830442215865, 4834.830780922651, 4872.707637541617, 4864.440589332272, 4914.631402644744, 4918.852434426973, 4923.286123511999, 4970.776315065903, 4703.0, 4720.504347043511, 4727.692699974491, 4776.5701086902345, 4777.498362672009], "value": 4599.82916597303, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [509.7986295413312, 542.5957661542152, 542.8480025592833, 560.624381664285, 567.2455634006772, 27460.31349216268, 30230.99999999997, 30245.395092098173, 36382.454263186075, 38003.61996380035, 38698.61301386985, 39047.80476097623, 39092.21815563694, 39134.0, 39146.8042659787, 7556.735514256989, 7662.927190193377, 7699.769006929773, 7735.2264773522475, 7738.729144479944, 7304.890426643605, 7490.4756667033325, 7492.213317601628, 7578.848423031536, 7681.807954801119, 9701.611935522571, 9929.453880036595, 9937.055979681918, 10274.845877311845, 10279.743006424824, 9582.425054496718, 9661.0, 9667.371620844622, 9670.274729395323, 9676.903230967671, 9582.329236953416, 9661.178799802025, 9662.37194582357, 9665.323427360088, 9666.0, 36547.81726091352, 36820.263594728225, 36834.63165368339, 36852.447213291554, 36923.26153476915, 2955.4236923799867, 3040.361524079946, 3148.8740450381956, 3261.2173078461237, 3333.799972001688, 27072.45855082907, 27682.584761228598, 27796.57628389354, 27901.860490697618, 28458.146255612366, 3446.758726889119, 3485.0, 3513.6662017108333, 3523.8766643167623, 3535.2399234164777, 231322.0, 232077.0, 233764.0, 233898.0, 234256.82871585703, 52324.0, 52398.95202096013, 52658.0, 52664.0, 52675.209871851956, 54976.450235497934, 55000.17499737507, 55119.173212401845, 55136.0, 55240.44759552433, 13322.800157997637, 13959.581212563638, 13974.790378144337, 13999.860001400059, 14040.08739431954, 10275.383476991405, 10482.371057736407, 10484.370937743759, 10496.370217786956, 10545.31455455393, 14428.537151083665, 14925.70148597022, 14927.179005154821, 16365.509034728977, 16591.170441477876, 4942.579880710108, 5854.560907931837, 6062.696865156725, 6142.262928448526, 6236.875262494725, 681.0669382945298, 737.8782500887401, 843.325339728215, 863.0377129500562, 923.1875949164827, 26546.867265663546, 26886.462270754477, 27247.182584522496, 27325.316867078083, 27514.724852751617, 830.759079866844, 853.5348235211825, 876.4653561327585, 896.6368620708623, 1005.3314545826943, 14111.858881411259, 14418.13491190532, 14576.63558411027, 14623.19572423527, 14745.557633271019, 331450.0, 335181.0, 335823.0, 336372.0, 336440.0, 42206.15587688229, 43077.78461107674, 43147.78426107912, 46871.29693054607, 47545.762271189116, 1114.1365441782684, 1124.859392575928, 1140.2360418519777, 1151.9654410367702, 1163.5927425401042, 26701.06546270949, 27912.72087279101, 28399.0, 29072.709272907, 29080.563791543565, 42627.5737242636, 43191.78404107959, 43203.0, 43295.783521082194, 43305.35041974436, 310207.0, 311146.0, 458421.0, 472468.0, 472763.0, 22797.658035129156, 22964.655530167394, 23129.306120816404, 23275.741396204885, 23327.41681457977, 34933.12667183341, 35324.64675353316, 35410.11474713153, 35758.10604734902, 36727.26545469129, 27105.86447067831, 28506.85746571254, 28636.713632864237, 28748.856255718587, 29292.70707292985, 29100.27249318784, 29257.268568285963, 29742.0, 30083.097507074825, 30222.84888575543, 28928.7107128926, 29253.0, 29330.706692932796, 29458.0, 29668.851655741582, 26497.072602458822, 27089.593656095567, 27811.721882780912, 27879.616417031593, 28143.43713125767, 21682.132714691867, 21724.23965161212, 21754.97168674982, 22009.66985495187, 22065.33803985883], "value": 16717.94619367057, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [336.8866144377155, 355.77408345700474, 364.11156777462986, 366.3058504134665, 375.91353988582625, 12279.815802762965, 15257.542273731791, 15535.611609709764, 16755.0, 16902.74645880313, 15891.761623575656, 16231.513054608351, 16569.83430165698, 16611.83388166118, 16782.58043548912, 4852.636052296092, 4853.514648535153, 4978.352814134158, 5036.521530454601, 5049.899002019975, 3604.2250916053044, 4960.97519512402, 4988.451270360258, 4994.400671919375, 5052.065367906931, 3769.6795772359383, 5448.91826622601, 5667.0, 5682.829515114553, 5826.951148793216, 5639.0, 5798.681072541009, 5808.680522571257, 5812.273465816773, 5817.0983497557945, 5171.870703232412, 5174.922376164361, 5175.792968281265, 5177.301064356301, 5177.948220517785, 19601.80398196014, 19780.703289450525, 19818.702719459074, 19886.303979360797, 19920.103595338245, 1791.5073354827396, 2211.1929145861804, 2482.900683972639, 2492.6011838105815, 2500.9374765630823, 12251.509939602405, 12829.67925801853, 13048.347582620925, 13306.002049846204, 13375.598732038054, 1667.7998640163137, 1749.95625109372, 1820.2264037783889, 1822.0616382562991, 1856.2389420337731, 67553.0, 69744.65127674329, 70306.64846675785, 71089.28910710878, 71187.64406177936, 9664.43892368523, 9677.77411195415, 9830.411899656574, 10065.194784417228, 10068.0, 13395.0, 13408.262545560088, 13410.396532156077, 13410.530631427853, 13412.12821166621, 6754.662266886636, 6917.27368626287, 7001.544899581511, 7005.789826305219, 7120.537165084253, 6592.472602191813, 6671.299513551105, 6769.8646027079185, 6794.082798822095, 6871.0, 4002.2795896738285, 4048.7773172475795, 4086.611771881726, 4145.419641250227, 4163.937540936889, 1783.2242974306218, 1786.2765579940144, 1830.405118336546, 1838.6322735452964, 1853.5829438376527, 751.6617522115071, 787.8030492376906, 820.9014918209733, 873.5981448533746, 885.8882102960742, 15485.845141548665, 16126.354945802272, 16289.83710162907, 16429.0, 17005.659886802194, 809.4576633655465, 814.2183503836384, 830.4311546590484, 839.7144970709988, 880.9780654440818, 8437.198466145706, 9378.812423751488, 9442.48066356357, 9452.243820494481, 9606.471644059504, 331371.0, 340275.0, 340695.0, 341029.0, 341879.0, 71060.64469677619, 71262.64368678123, 73315.63342183361, 75757.62121189469, 75874.62062689761, 882.5631312500353, 901.1079031758512, 902.3322741171617, 904.1591320072264, 909.88171537701, 14468.927655361656, 15264.0, 15720.92139539295, 15806.288717007532, 16126.2743176555, 33695.663043369255, 34016.14959626029, 34595.48106778451, 37035.0, 38475.2304953905, 42411.0, 48049.0, 49826.77165354331, 50118.24822627591, 52595.0, 10471.528781205016, 10564.260501764802, 10692.19808514349, 11081.667549973514, 11392.316461012366, 11922.701932451759, 12005.63983080509, 12330.260184388966, 12419.813702794285, 12934.288614125937, 10678.0, 10856.837147442953, 11249.0, 11251.156163287622, 11475.598354057567, 11023.503942322453, 11046.33721976684, 11202.66392008241, 11208.943955280498, 11252.099832013582, 10961.780764384508, 10996.735375431608, 11536.82694759596, 11760.235584686969, 11767.76464470723, 11023.283486573348, 11054.834177487504, 11135.55457781712, 11405.486753095965, 11570.94214528922, 10899.29154604948, 11158.163137764537, 11160.441977901235, 11179.776404471702, 11214.0], "value": 8372.773477757417, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1223.0, 1306.705991151991, 1337.4917531338092, 1404.8595140485952, 1651.2486818497584, 6351.33311002345, 6872.381485666289, 7127.180374256962, 7146.96426517868, 7275.781726548212, 7135.500514963942, 7196.568205907649, 7207.351338379544, 7209.134903811537, 7210.4952653314285, 1805.3861687026408, 1866.5333666583354, 2083.499960009602, 2120.1837292642367, 2121.8302535797097, 2087.6972838938345, 2183.159483598818, 2187.125149940021, 2195.242641288753, 2195.3084778294788, 2145.989270053655, 2163.156369016085, 2177.16179270981, 2178.596959562478, 2185.8579192352527, 2159.838012149095, 2174.2172817785586, 2183.836212284085, 2187.0, 2187.6062308784494, 2087.91648334068, 2111.873287602749, 2113.830893528514, 2114.7990940860595, 2116.714243577113, 7342.118945726547, 7346.742863999734, 7457.366123879477, 7477.813054673622, 7487.700491980314, 4353.521112677603, 4480.574345437179, 4509.143262780061, 4510.684252102355, 4510.774461276923, 2766.405222877071, 2812.328288126288, 2993.760499160062, 3003.609530761009, 3009.232645675361, 827.7831587566328, 872.2237208884098, 882.8763973043779, 885.8626912828522, 923.4736200365794, 9887.703368898945, 9893.30746847721, 9931.15585175261, 9933.0, 9955.800883982352, 3845.115623406632, 4019.698522610757, 4077.327241005201, 4081.9795901020307, 4189.581041895786, 8556.459837229235, 8566.785830354167, 8567.314614830799, 8569.0, 8580.528070956158, 4454.0, 4467.173572889026, 4469.4189755332245, 4469.620082293041, 4472.664550158751, 3493.9301213975577, 3602.495650608917, 3937.9606203938165, 4057.8376864925663, 4063.167050754588, 3314.784539004957, 3354.9329013419597, 3389.7288216942584, 3410.3520331137006, 3490.406630872707, 1109.5617231193633, 1132.1565433751744, 1138.5502726423017, 1140.5323817234894, 1143.17691262291, 735.3859527294662, 813.9511629302261, 815.5392203405057, 815.8939337886155, 826.0871736730826, 4706.152892479318, 4757.119932812371, 4802.73584952824, 4846.539578740014, 5023.497650234947, 408.93661482470264, 409.823775776413, 411.98764037078934, 414.8900541356543, 416.2445162030904, 3378.8141652209365, 3398.7790793598833, 3404.30211806584, 3413.5391722117197, 3569.161247106928, 8647.87028194565, 8805.559722013873, 8834.602442890086, 8838.911610883808, 8844.601992910335, 8849.115088491228, 8854.557272136497, 8870.512121833232, 8883.33374996865, 8926.68756593516, 6888.311168883071, 6967.198772141167, 6974.163100427981, 6979.092717946736, 6990.4757143214465, 4667.556582124693, 4688.6483513737285, 4734.597559207506, 4868.440129385166, 5018.247262910447, 8070.0719417267765, 8129.593520323841, 8171.1828817120195, 8200.343972482067, 8245.95877020611, 8092.231238032377, 8189.50862948225, 8224.095349511668, 8228.588570571328, 8242.793930151554, 5743.339515955551, 6427.807165785034, 6499.252585952487, 6517.576357536745, 6529.281779004401, 6379.7129129190225, 6519.706613202512, 6719.39525442693, 6746.494012949147, 6962.860742785015, 6199.0, 6341.239051313687, 6344.333844946214, 6371.681415929278, 6552.115464412243, 6423.871522569429, 6486.05952136947, 6522.0, 6524.739010439529, 6563.901541476786, 6033.9698301508215, 6142.846428839136, 6144.508439324933, 6146.846328841814, 6150.200473938359, 4182.686298527562, 4217.98768295613, 4324.783760811883, 4343.283358245854, 4489.753063581404, 4197.349410841324, 4307.8492252771, 4376.212281789309, 4395.296752519582, 4413.7793110345], "value": 3841.9406439144095, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.63113914819866, 354.1977421141116, 357.0858601978934, 357.865800324878, 359.1183644153603, 4823.975880120599, 4826.444958829736, 4854.611631069511, 4857.902841943159, 4860.343853579766, 4519.531152375474, 4769.976150119253, 4794.808207671697, 4803.927941080887, 4811.807527698897, 3753.11801726594, 3757.8684746033896, 3776.2825063237897, 3811.1996480739076, 3841.0397400649836, 3815.294170578438, 3823.1589050408866, 3836.769793812366, 3838.6353296436932, 3846.4614953906475, 3777.9433308500393, 3798.9430158547643, 3825.4261860720976, 3827.3302172119907, 3827.6937844972335, 3663.8534458621625, 3749.2314075614436, 3764.341240282953, 3840.8655697050467, 3846.9230615387814, 3449.068751437098, 3449.482577613353, 3457.567804024497, 3461.0, 3461.5326930864517, 4655.975685349218, 4662.160811054043, 4662.487126416091, 4678.532146785328, 4682.461516925563, 3995.7802320872343, 4192.392103145057, 4343.283358245885, 4404.779761011969, 4410.955890441087, 4928.211486162233, 4935.851924442272, 4946.431160416562, 4952.653314268003, 4965.553100220997, 3539.0975301298263, 3541.8937431877084, 3557.4308110702164, 3560.1989552350774, 3570.3394871948512, 4985.775640096204, 5168.483151684839, 5230.267762513213, 5255.846492104744, 5259.500347466985, 3623.836927338276, 3641.6358364163902, 3650.7261955353456, 3654.908627284286, 3655.5065066216252, 3691.9077023074638, 3692.8522859085338, 3692.9261414771554, 3696.482492451059, 3703.703703703697, 4204.306289462204, 4207.368894665763, 4220.957790422118, 4223.704340696122, 4230.344296633965, 4870.805167793319, 4935.383077115361, 4954.8761280968265, 5023.14606516893, 5040.521150490697, 1235.172434468908, 1243.570968016033, 1247.2204871955028, 1248.5630029489605, 1256.9057320700801, 815.0463957170125, 833.9457935234191, 840.8906842110608, 853.8164294676675, 855.9871601925976, 4334.41485399467, 4455.198064348384, 4476.2837945928495, 4488.910221795546, 4503.932441013388, 5154.407243167008, 5165.147750621106, 5169.53474187325, 5185.481451854785, 5205.531502164823, 4297.315925641066, 4462.576055274743, 4465.531119232499, 4476.529964353761, 4497.2129877272, 2675.2643023168785, 2698.2984424049496, 2700.0, 2707.1878436468983, 2708.295843080813, 4705.505921878222, 4730.716157030589, 4756.857294281177, 4771.520828543163, 4775.331453596499, 4896.507210744955, 5001.699558114917, 5043.8903441242865, 5066.797328106908, 5152.3044389007755, 4623.69945953512, 4655.580997710223, 4702.388689470347, 4755.358026666356, 4763.809447622057, 5072.239164125411, 5084.694918304914, 5096.949030509648, 5112.412072611549, 5124.8206312778875, 4824.396950381202, 4981.402231732215, 4982.829035176658, 5026.447090819934, 5026.84919452417, 4721.197396442544, 4732.645051621073, 4732.680986316611, 4742.691725037862, 4747.905041899212, 3554.400519766064, 3573.2317551726505, 3579.2304654499408, 3586.1214002569536, 3587.3363427764903, 4844.781984810622, 5002.624803139852, 5024.0, 5039.067772462106, 5051.4443411225475, 4831.0, 4880.902381952412, 4882.633802464758, 4888.780004899717, 4890.951090489049, 4823.686460380064, 4864.73243971585, 4866.8562887720645, 4889.701840135131, 4895.936372810487, 4630.189716799614, 4786.353842231254, 4793.880152996203, 4801.783919723551, 4829.73436460998, 4882.852529655599, 4924.513120873936, 4933.309336692938, 4935.728534930469, 4937.28409380645, 4743.573078422958, 4791.5687588117235, 4793.23308270689, 4810.584485015678, 4811.302361157543], "value": 3770.0855559644824, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [620.2804746494068, 626.8432891777059, 639.3095456906541, 644.3846126948763, 738.9433110651769, 12160.695982600439, 13833.654158646039, 15127.0, 15183.468578599752, 15202.0, 15199.620009499768, 15361.771058315337, 15389.384424623002, 15404.845951540481, 15409.537713868602, 616.3343588923951, 762.9313361797451, 784.7488803582855, 786.8111653203218, 787.1380837982429, 635.0981606119321, 756.4780301591911, 777.7433446962517, 786.5713186313473, 790.7943934576994, 823.3289868756974, 825.9504429734235, 826.0252901576167, 826.4545400035978, 826.4793180296435, 645.5190882792302, 819.512390127873, 819.737683941139, 820.0774129104786, 820.1183727492939, 486.80527788884376, 588.2411688921286, 601.5909181756404, 604.6281536854847, 607.268241768672, 13821.239831809247, 13838.861611383858, 13848.376823042987, 13858.584242472742, 13860.02979791415, 12369.13416060876, 12422.37888105591, 12467.93766031164, 12572.811407828889, 12758.61724148277, 11741.647750567497, 11775.94112029443, 11879.465424055938, 11919.642410727693, 12012.339321337324, 3977.3835055566433, 4017.296973029708, 4021.879343619696, 4024.8591299304676, 4055.2903241932545, 64006.359936400506, 64047.67976160136, 64071.0, 64709.352906470805, 65220.67389663069, 9518.762030949143, 9575.712728618153, 9812.460314682761, 9905.10854023127, 10120.392776433437, 17973.37093201732, 18101.004444755665, 18135.183916723516, 18325.0, 18445.53886152857, 6518.348165183444, 6593.538452308292, 6627.5029372797235, 6692.698828552727, 6980.7905762827195, 3647.106458917529, 3759.4172903200047, 3767.6420740029653, 3780.319542482325, 3782.2813665404037, 2716.809823312349, 2768.5155097857796, 2772.9029483967965, 2774.6531683539556, 2811.1144989328704, 463.2425983516974, 526.4340833603815, 543.1337017457149, 547.1655725019377, 548.9258950041773, 567.2427309541808, 621.9844503887349, 639.5491178719085, 644.5359341274275, 649.7693318871827, 8124.634391452399, 8307.0, 8409.0, 8416.242538171595, 8449.493030418194, 561.4413658409827, 595.5354823237882, 595.5622617376225, 617.2531237202945, 620.559402823991, 3888.8250028748776, 4162.791860406967, 4200.3699445082875, 4236.978815105905, 4273.658107351404, 62652.373476265566, 64018.67990660017, 64985.35014649887, 67056.66471667709, 69129.65435172792, 55784.72107639517, 56045.43954560484, 57601.0, 59377.406225937186, 59435.70282148562, 49834.50165498299, 50421.24368134481, 51259.487405126216, 52031.73984130055, 53081.46918530765, 7768.88346674789, 7878.921210787818, 7908.960455197917, 8062.677492900219, 8099.14958929304, 35516.644833551334, 35674.14606831395, 35763.64236357603, 36023.45964810478, 37390.0, 43982.78008610065, 45738.0, 48932.0, 49084.263736044704, 50344.0, 9228.40015398997, 9510.373774937625, 9538.618455261712, 9751.171150452008, 9924.40453572788, 11233.494492747683, 11273.436328183394, 11398.316101033965, 11425.0, 11660.650180494598, 10972.670819875417, 10999.61501347449, 11121.833172502256, 11203.383813889988, 11554.711132221762, 10330.638427654996, 10682.679519614425, 10761.730956726144, 11139.551858258375, 11184.049355804522, 11117.0, 11144.832827507757, 11609.825852612048, 11693.123015774021, 11774.587889423829, 8595.398322117391, 8764.430312029697, 8902.777430564287, 8920.610168136067, 8931.642734290555, 8983.978604814127, 9558.904410955802, 9716.951415242878, 9755.756106097404, 9874.50627468638], "value": 6254.977281812137, "unit": "score"}], "name": "dromaeo_css", "value": 6227.716631125976, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 115.0, 113.0, 115.0, 115.0, 114.0, 117.0, 114.0, 114.0, 115.0], "value": 113.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 124.0, 125.0, 172.0, 125.0, 129.0, 138.0, 124.0, 146.0, 124.0], "value": 136.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 163.0, 161.0, 162.0, 163.0, 163.0, 162.0, 164.0, 161.0, 162.0], "value": 162.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 109.0, 77.0, 76.0, 76.0, 77.0, 93.0, 77.0], "value": 81.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 119.0, 85.0, 84.0, 85.0, 103.0, 84.0, 85.0, 116.0], "value": 93.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 127.0, 128.0, 127.0, 127.0, 128.0, 129.0, 127.0, 127.0, 127.0], "value": 127.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 148.0, 148.0, 147.0, 148.0, 147.0, 147.0, 147.0, 147.0], "value": 147.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 96.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 63.0, 64.0, 66.0, 65.0, 64.0, 66.0, 67.0, 65.0], "value": 64.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 66.0, 65.0, 67.0, 66.0, 65.0, 66.0, 66.0, 65.0], "value": 65.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 96.0, 85.0, 84.0, 83.0, 84.0, 85.0, 83.0, 91.0], "value": 86.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 141.0, 148.0, 132.0, 136.0, 145.0, 142.0, 137.0, 179.0, 140.0], "value": 144.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 190.0, 180.0, 179.0, 180.0, 178.0, 181.0, 185.0, 183.0, 181.0], "value": 181.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 68.0, 68.0, 73.0, 68.0, 68.0, 67.0, 68.0, 66.0], "value": 68.3, "unit": "score"}], "name": "kraken", "value": 1567.5999999999997, "alertThreshold": 2.0}]} 21:28:48 INFO - Return code: 0 21:28:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:28:48 INFO - # TBPL SUCCESS # 21:28:48 INFO - Running post-action listener: _resource_record_post_action 21:28:48 INFO - [mozharness: 2016-12-18 05:28:48.397398Z] Finished run-tests step (success) 21:28:48 INFO - Running post-run listener: _resource_record_post_run 21:28:48 INFO - Total resource usage - Wall time: 1202s; CPU: 13.0%; Read bytes: 8192; Write bytes: 296861696; Read time: 16; Write time: 271680 21:28:48 INFO - TinderboxPrint: CPU usage
13.2% 21:28:48 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 16 21:28:48 INFO - TinderboxPrint: I/O write bytes / time
296,861,696 / 271,680 21:28:48 INFO - TinderboxPrint: CPU idle
8,331.2 (86.8%) 21:28:48 INFO - TinderboxPrint: CPU user
1,217.8 (12.7%) 21:28:48 INFO - TinderboxPrint: Swap in / out
0 / 0 21:28:48 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 15220736; Read time: 0; Write time: 8396 21:28:48 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 4096; Write bytes: 281227264; Read time: 8; Write time: 263164 21:28:49 INFO - Running post-run listener: _upload_blobber_files 21:28:49 INFO - Blob upload gear active. 21:28:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:28:49 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 21:28:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 21:28:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 21:28:49 INFO - (blobuploader) - INFO - Open directory for files ... 21:28:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 21:28:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:28:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:28:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 21:28:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:28:50 INFO - (blobuploader) - INFO - Done attempting. 21:28:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:28:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:28:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:28:53 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:28:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:28:53 INFO - (blobuploader) - INFO - Done attempting. 21:28:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 21:28:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:28:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:28:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 21:28:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:28:54 INFO - (blobuploader) - INFO - Done attempting. 21:28:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 21:28:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:28:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:28:55 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 21:28:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:28:55 INFO - (blobuploader) - INFO - Done attempting. 21:28:55 INFO - (blobuploader) - INFO - Iteration through files over. 21:28:55 INFO - Return code: 0 21:28:55 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:28:55 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:28:55 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/78a0c6116b5236a1704527402f8d23f3d0711b91f019ae66cd2867a4adbc5397f497ccf473dd1b6889b2eda06fc7c16b0fcca956e7e2e6ee9fa5160688f348e0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4b0a7d66ac7044dec1f6be58ece8fa9a2be5b532fe5ca82abee41c9f66e6230b8be54daede536a4addba6aa0802141df395acfd18af726c34d2aed2bdbaf0838", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e97adba8978019474058e9d3298988dc72396ecbca3dd9dd30815119ac6d2a4ddecb1beafeef98e6ceb1c0b5304e1c8086d2e113709d3b56793350c93075990f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f9a80ae1a32fc1627a68f8de40f79908d21a85b35862bf7bbdb463ebee515aab61591203467e6278e1f782de12daa225d2963c5615acf1088194a8e320c0daa8"} 21:28:55 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:28:55 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:28:55 INFO - Contents: 21:28:55 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/78a0c6116b5236a1704527402f8d23f3d0711b91f019ae66cd2867a4adbc5397f497ccf473dd1b6889b2eda06fc7c16b0fcca956e7e2e6ee9fa5160688f348e0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4b0a7d66ac7044dec1f6be58ece8fa9a2be5b532fe5ca82abee41c9f66e6230b8be54daede536a4addba6aa0802141df395acfd18af726c34d2aed2bdbaf0838", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e97adba8978019474058e9d3298988dc72396ecbca3dd9dd30815119ac6d2a4ddecb1beafeef98e6ceb1c0b5304e1c8086d2e113709d3b56793350c93075990f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f9a80ae1a32fc1627a68f8de40f79908d21a85b35862bf7bbdb463ebee515aab61591203467e6278e1f782de12daa225d2963c5615acf1088194a8e320c0daa8"} 21:28:55 INFO - Running post-run listener: copy_logs_to_upload_dir 21:28:55 INFO - Copying logs to upload dir... 21:28:55 INFO - mkdir: /builds/slave/test/build/upload/logs 21:28:55 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1318.066540 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 58 secs) (at 2016-12-17 21:28:53.040106) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-17 21:28:53.043397) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1482036561.723995-303408795 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/78a0c6116b5236a1704527402f8d23f3d0711b91f019ae66cd2867a4adbc5397f497ccf473dd1b6889b2eda06fc7c16b0fcca956e7e2e6ee9fa5160688f348e0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4b0a7d66ac7044dec1f6be58ece8fa9a2be5b532fe5ca82abee41c9f66e6230b8be54daede536a4addba6aa0802141df395acfd18af726c34d2aed2bdbaf0838", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e97adba8978019474058e9d3298988dc72396ecbca3dd9dd30815119ac6d2a4ddecb1beafeef98e6ceb1c0b5304e1c8086d2e113709d3b56793350c93075990f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f9a80ae1a32fc1627a68f8de40f79908d21a85b35862bf7bbdb463ebee515aab61591203467e6278e1f782de12daa225d2963c5615acf1088194a8e320c0daa8"} build_url:https://queue.taskcluster.net/v1/task/CtcQ4nFARQ2idasUo0jCOw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011326 build_url: 'https://queue.taskcluster.net/v1/task/CtcQ4nFARQ2idasUo0jCOw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/78a0c6116b5236a1704527402f8d23f3d0711b91f019ae66cd2867a4adbc5397f497ccf473dd1b6889b2eda06fc7c16b0fcca956e7e2e6ee9fa5160688f348e0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4b0a7d66ac7044dec1f6be58ece8fa9a2be5b532fe5ca82abee41c9f66e6230b8be54daede536a4addba6aa0802141df395acfd18af726c34d2aed2bdbaf0838", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e97adba8978019474058e9d3298988dc72396ecbca3dd9dd30815119ac6d2a4ddecb1beafeef98e6ceb1c0b5304e1c8086d2e113709d3b56793350c93075990f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f9a80ae1a32fc1627a68f8de40f79908d21a85b35862bf7bbdb463ebee515aab61591203467e6278e1f782de12daa225d2963c5615acf1088194a8e320c0daa8"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-17 21:28:53.067872) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-17 21:28:53.068163) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1482036561.723995-303408795 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004575 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-17 21:28:53.150445) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-17 21:28:53.150719) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-17 21:28:53.150972) ========= ========= Total master_lag: 0.20 =========