builder: graphics_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-026 starttime: 1479400150.79 results: success (0) buildid: 20161117070428 builduid: 8e26d31035764f7fb7727ebc3d874a24 revision: f27e26039a4d9d4f4041347d341b98054fb10612 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-17 08:29:10.790898) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-17 08:29:10.791336) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-17 08:29:10.791697) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-17 08:29:10.884626) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-17 08:29:10.884899) ========= 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=41bf3e9aed84707956354da8000001c5-1479398284.657764-1420876439 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-11-17 08:29:11-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 42.4M=0s 2016-11-17 08:29:11 (42.4 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.085469 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-17 08:29:10.990904) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-17 08:29:10.991209) ========= 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=41bf3e9aed84707956354da8000001c5-1479398284.657764-1420876439 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021875 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-17 08:29:11.041067) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-17 08:29:11.041380) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev f27e26039a4d9d4f4041347d341b98054fb10612 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/graphics --rev f27e26039a4d9d4f4041347d341b98054fb10612 --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=41bf3e9aed84707956354da8000001c5-1479398284.657764-1420876439 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-11-17 08:29:11,717 truncating revision to first 12 chars 2016-11-17 08:29:11,717 Setting DEBUG logging. 2016-11-17 08:29:11,717 attempt 1/10 2016-11-17 08:29:11,718 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/f27e26039a4d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-17 08:29:12,977 unpacking tar archive at: graphics-f27e26039a4d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.574330 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-17 08:29:12.631661) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-17 08:29:12.631937) ========= script_repo_revision: f27e26039a4d9d4f4041347d341b98054fb10612 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-17 08:29:12.632280) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-17 08:29:12.632673) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-17 08:29:12.642729) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2016-11-17 08:29:12.642988) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 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', 'Graphics', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] 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=41bf3e9aed84707956354da8000001c5-1479398284.657764-1420876439 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:29:13 INFO - MultiFileLogger online at 20161117 08:29:13 in /builds/slave/test 08:29:13 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 08:29:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:29:13 INFO - {'append_to_log': False, 08:29:13 INFO - 'base_work_dir': '/builds/slave/test', 08:29:13 INFO - 'blob_upload_branch': 'Graphics', 08:29:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:29:13 INFO - 'branch': 'Graphics', 08:29:13 INFO - 'buildbot_json_path': 'buildprops.json', 08:29:13 INFO - 'config_files': ('talos/linux_config.py',), 08:29:13 INFO - 'default_actions': ('clobber', 08:29:13 INFO - 'read-buildbot-config', 08:29:13 INFO - 'download-and-extract', 08:29:13 INFO - 'populate-webroot', 08:29:13 INFO - 'create-virtualenv', 08:29:13 INFO - 'install', 08:29:13 INFO - 'run-tests'), 08:29:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:29:13 INFO - 'download_minidump_stackwalk': True, 08:29:13 INFO - 'download_symbols': 'ondemand', 08:29:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:29:13 INFO - 'tooltool.py': '/tools/tooltool.py', 08:29:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:29:13 INFO - '/tools/misc-python/virtualenv.py')}, 08:29:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:29:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:29:13 INFO - 'installer_path': 'installer.exe', 08:29:13 INFO - 'log_level': 'info', 08:29:13 INFO - 'log_name': 'talos', 08:29:13 INFO - 'log_to_console': True, 08:29:13 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:29:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:29:13 INFO - 'opt_config_files': (), 08:29:13 INFO - 'pip_index': False, 08:29:13 INFO - 'sps_profile': False, 08:29:13 INFO - 'sps_profile_interval': 0, 08:29:13 INFO - 'suite': 'dromaeojs', 08:29:13 INFO - 'system_bits': '32', 08:29:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:29:13 INFO - 'title': 'talos-linux64-ix-026', 08:29:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:29:13 INFO - 'use_talos_json': True, 08:29:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:29:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:29:13 INFO - 'work_dir': 'build'} 08:29:13 INFO - [mozharness: 2016-11-17 16:29:13.423652Z] Running clobber step. 08:29:13 INFO - Running pre-action listener: _resource_record_pre_action 08:29:13 INFO - Running main action method: clobber 08:29:13 INFO - rmtree: /builds/slave/test/build 08:29:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:29:13 INFO - Running post-action listener: _resource_record_post_action 08:29:13 INFO - [mozharness: 2016-11-17 16:29:13.883500Z] Finished clobber step (success) 08:29:13 INFO - [mozharness: 2016-11-17 16:29:13.883598Z] Running read-buildbot-config step. 08:29:13 INFO - Running pre-action listener: _resource_record_pre_action 08:29:13 INFO - Running main action method: read_buildbot_config 08:29:13 INFO - Using buildbot properties: 08:29:13 INFO - { 08:29:13 INFO - "project": "", 08:29:13 INFO - "product": "firefox", 08:29:13 INFO - "script_repo_revision": "default", 08:29:13 INFO - "builddir": "graphics_ubuntu64_hw_test-dromaeojs", 08:29:13 INFO - "repository": "", 08:29:13 INFO - "buildername": "Ubuntu HW 12.04 x64 graphics talos dromaeojs", 08:29:13 INFO - "buildid": "20161117070428", 08:29:13 INFO - "pgo_build": "False", 08:29:13 INFO - "basedir": "/builds/slave/test", 08:29:13 INFO - "buildnumber": 1, 08:29:13 INFO - "slavename": "talos-linux64-ix-026", 08:29:13 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 08:29:13 INFO - "platform": "ubuntu64_hw", 08:29:13 INFO - "scheduler": "tests-graphics-linux64-talos", 08:29:13 INFO - "branch": "graphics", 08:29:13 INFO - "repo_path": "projects/graphics", 08:29:13 INFO - "revision": "f27e26039a4d9d4f4041347d341b98054fb10612", 08:29:13 INFO - "stage_platform": "linux64", 08:29:13 INFO - "builduid": "8e26d31035764f7fb7727ebc3d874a24", 08:29:13 INFO - "slavebuilddir": "test" 08:29:13 INFO - } 08:29:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/JIiAHDWhR_iuSqkoCeNzGQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 08:29:13 INFO - Running post-action listener: _resource_record_post_action 08:29:13 INFO - [mozharness: 2016-11-17 16:29:13.886006Z] Finished read-buildbot-config step (success) 08:29:13 INFO - [mozharness: 2016-11-17 16:29:13.886092Z] Running download-and-extract step. 08:29:13 INFO - Running pre-action listener: _resource_record_pre_action 08:29:13 INFO - Running main action method: download_and_extract 08:29:13 INFO - mkdir: /builds/slave/test/build/tests 08:29:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:29:13 INFO - trying https://queue.taskcluster.net/v1/task/JIiAHDWhR_iuSqkoCeNzGQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 08:29:13 INFO - Downloading https://queue.taskcluster.net/v1/task/JIiAHDWhR_iuSqkoCeNzGQ/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 08:29:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JIiAHDWhR_iuSqkoCeNzGQ/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 08:29:14 INFO - Downloaded 1361 bytes. 08:29:14 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 08:29:14 INFO - Using the following test package requirements: 08:29:14 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 08:29:14 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 08:29:14 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 08:29:14 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 08:29:14 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 08:29:14 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 08:29:14 INFO - u'jsshell-linux-x86_64.zip'], 08:29:14 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 08:29:14 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 08:29:14 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 08:29:14 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 08:29:14 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 08:29:14 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 08:29:14 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 08:29:14 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 08:29:14 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 08:29:14 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 08:29:14 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 08:29:14 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:29:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JIiAHDWhR_iuSqkoCeNzGQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 08:29:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JIiAHDWhR_iuSqkoCeNzGQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:29:14 INFO - Fetch https://queue.taskcluster.net/v1/task/JIiAHDWhR_iuSqkoCeNzGQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 08:29:15 INFO - Http code: 200 08:29:15 INFO - via: 1.1 9552af4ceb2e0dd55964a80d1e549fd5.cloudfront.net (CloudFront) 08:29:15 INFO - x-amz-cf-id: 9vH8Zrfxn8o45JBmJ7MiZy9C4BEIedYp79jgNZCtYMc5mQ58_HJzQQ== 08:29:15 INFO - x-amz-version-id: qKnLrUZDnMyGVBsFE44X4IlsK8xqYhc0 08:29:15 INFO - Expected file size: 20353917 08:29:15 INFO - Obtained file size: 20353917 08:29:19 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 08:29:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JIiAHDWhR_iuSqkoCeNzGQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 08:29:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JIiAHDWhR_iuSqkoCeNzGQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:29:19 INFO - Fetch https://queue.taskcluster.net/v1/task/JIiAHDWhR_iuSqkoCeNzGQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 08:29:19 INFO - Http code: 200 08:29:19 INFO - via: 1.1 bfc29822e21ecc352f71dce83e06ab74.cloudfront.net (CloudFront) 08:29:19 INFO - x-amz-cf-id: OwPYSLKZY46YImQmzCkxh8PlQfBHMw2FcBFiQWpXXQU9OHxGvKC_nQ== 08:29:19 INFO - x-amz-version-id: qKnLrUZDnMyGVBsFE44X4IlsK8xqYhc0 08:29:20 INFO - Expected file size: 20353917 08:29:20 INFO - Obtained file size: 20353917 08:29:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JIiAHDWhR_iuSqkoCeNzGQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 08:29:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JIiAHDWhR_iuSqkoCeNzGQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 08:29:24 INFO - Fetch https://queue.taskcluster.net/v1/task/JIiAHDWhR_iuSqkoCeNzGQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 08:29:25 INFO - Http code: 200 08:29:25 INFO - via: 1.1 bfc29822e21ecc352f71dce83e06ab74.cloudfront.net (CloudFront) 08:29:25 INFO - x-amz-cf-id: CpTTYWg6RjClrnr442qlhXMDV7g5ICKcg67flUF28Hh8QUdR7nmbFg== 08:29:25 INFO - x-amz-version-id: iEtDA1OaaJnOJBKXfQDm7QqwnpBMBq5z 08:29:25 INFO - Expected file size: 14409366 08:29:25 INFO - Obtained file size: 14409366 08:29: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')]} 08:29:26 INFO - trying https://queue.taskcluster.net/v1/task/JIiAHDWhR_iuSqkoCeNzGQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 08:29:26 INFO - Downloading https://queue.taskcluster.net/v1/task/JIiAHDWhR_iuSqkoCeNzGQ/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 08:29:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JIiAHDWhR_iuSqkoCeNzGQ/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 08:29:28 INFO - Downloaded 55754214 bytes. 08:29:28 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JIiAHDWhR_iuSqkoCeNzGQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 08:29:28 INFO - mkdir: /builds/slave/test/properties 08:29:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:29:28 INFO - Writing to file /builds/slave/test/properties/build_url 08:29:28 INFO - Contents: 08:29:28 INFO - build_url:https://queue.taskcluster.net/v1/task/JIiAHDWhR_iuSqkoCeNzGQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 08:29:29 INFO - Running post-action listener: _resource_record_post_action 08:29:29 INFO - Running post-action listener: set_extra_try_arguments 08:29:29 INFO - [mozharness: 2016-11-17 16:29:29.507583Z] Finished download-and-extract step (success) 08:29:29 INFO - [mozharness: 2016-11-17 16:29:29.507808Z] Running populate-webroot step. 08:29:29 INFO - Running pre-action listener: _resource_record_pre_action 08:29:29 INFO - Running main action method: populate_webroot 08:29:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:29:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:29:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:29:29 INFO - '--tppagecycles', 08:29:29 INFO - '7'], 08:29:29 INFO - 'tests': ['tsvgm']}}, 08:29:29 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:29:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:29:29 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:29:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:29:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:29:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:29:29 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:29:29 INFO - 'talos_options': ['--disable-e10s'], 08:29:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:29:29 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:29:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:29:29 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:29:29 INFO - 'talos_options': ['--disable-e10s'], 08:29:29 INFO - 'tests': ['damp', 'tps']}, 08:29:29 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:29:29 INFO - 'tests': ['damp', 'tps']}, 08:29:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:29:29 INFO - 'tests': ['dromaeo_dom']}, 08:29:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:29:29 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:29:29 INFO - 'tests': ['basic_compositor_video']}, 08:29:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:29:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:29:29 INFO - 'tests': ['a11yr', 08:29:29 INFO - 'ts_paint', 08:29:29 INFO - 'tpaint', 08:29:29 INFO - 'sessionrestore', 08:29:29 INFO - 'sessionrestore_no_auto_restore', 08:29:29 INFO - 'tabpaint']}, 08:29:29 INFO - 'other-e10s': {'tests': ['a11yr', 08:29:29 INFO - 'ts_paint', 08:29:29 INFO - 'tpaint', 08:29:29 INFO - 'sessionrestore', 08:29:29 INFO - 'sessionrestore_no_auto_restore', 08:29:29 INFO - 'tabpaint']}, 08:29:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:29:29 INFO - 'ts_paint', 08:29:29 INFO - 'tpaint', 08:29:29 INFO - 'sessionrestore', 08:29:29 INFO - 'sessionrestore_no_auto_restore', 08:29:29 INFO - 'tabpaint']}, 08:29:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:29:29 INFO - 'ts_paint', 08:29:29 INFO - 'tpaint', 08:29:29 INFO - 'sessionrestore', 08:29:29 INFO - 'sessionrestore_no_auto_restore', 08:29:29 INFO - 'tabpaint']}, 08:29:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:29:29 INFO - 'tests': ['a11yr', 08:29:29 INFO - 'ts_paint', 08:29:29 INFO - 'tpaint', 08:29:29 INFO - 'sessionrestore', 08:29:29 INFO - 'sessionrestore_no_auto_restore', 08:29:29 INFO - 'tabpaint']}, 08:29:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:29:29 INFO - 'tests': ['a11yr', 08:29:29 INFO - 'ts_paint', 08:29:29 INFO - 'tpaint', 08:29:29 INFO - 'sessionrestore', 08:29:29 INFO - 'sessionrestore_no_auto_restore', 08:29:29 INFO - 'tabpaint']}, 08:29:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:29:29 INFO - 'tests': ['tsvgx', 08:29:29 INFO - 'tsvgr_opacity', 08:29:29 INFO - 'tart', 08:29:29 INFO - 'tscrollx', 08:29:29 INFO - 'cart']}, 08:29:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:29:29 INFO - 'tsvgr_opacity', 08:29:29 INFO - 'tart', 08:29:29 INFO - 'tscrollx', 08:29:29 INFO - 'cart']}, 08:29:29 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:29:29 INFO - 'talos_options': ['--disable-e10s'], 08:29:29 INFO - 'tests': ['tp5o']}, 08:29:29 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:29:29 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:29:29 INFO - 'talos_options': ['--disable-e10s', 08:29:29 INFO - '--xperf_path', 08:29:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:29:29 INFO - 'tests': ['tp5n']}, 08:29:29 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:29:29 INFO - 'talos_options': ['--xperf_path', 08:29:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:29:29 INFO - 'tests': ['tp5n']}}, 08:29:29 INFO - 'talos.zip': {'path': '', 08:29:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:29:29 INFO - Running post-action listener: _resource_record_post_action 08:29:29 INFO - [mozharness: 2016-11-17 16:29:29.523639Z] Finished populate-webroot step (success) 08:29:29 INFO - [mozharness: 2016-11-17 16:29:29.523726Z] Running create-virtualenv step. 08:29:29 INFO - Running pre-action listener: _resource_record_pre_action 08:29:29 INFO - Running main action method: create_virtualenv 08:29:29 INFO - Creating virtualenv /builds/slave/test/build/venv 08:29:29 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 08:29:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:29:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:29:29 INFO - Using real prefix '/usr' 08:29:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:29:30 INFO - Installing distribute.............................................................................................................................................................................................done. 08:29:32 INFO - Installing pip.................done. 08:29:32 INFO - Return code: 0 08:29:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:29:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:29:32 INFO - Reading from file tmpfile_stdout 08:29:32 INFO - Output received: 08:29:32 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) 08:29:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:29: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')]} 08:29:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:29:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29: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')]} 08:29:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:29:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9451375e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2658b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27f4130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2882fc0>, '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 0x2883ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2884350>, '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': '41bf3e9aed84707956354da8000001c5-1479398284.657764-1420876439', '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 08:29: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', 'pip>=1.5'] in /builds/slave/test/build 08:29: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 pip>=1.5 08:29:32 INFO - Using env: {'DISPLAY': ':0', 08:29:32 INFO - 'HOME': '/home/cltbld', 08:29:32 INFO - 'LANG': 'en_US.UTF-8', 08:29:32 INFO - 'LANGUAGE': 'en_US:en', 08:29:32 INFO - 'LOGNAME': 'cltbld', 08:29:32 INFO - 'MAIL': '/var/mail/cltbld', 08:29:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:29:32 INFO - 'MOZ_NO_REMOTE': '1', 08:29:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:29:32 INFO - 'NO_EM_RESTART': '1', 08:29:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:29:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:29:32 INFO - 'PWD': '/builds/slave/test', 08:29:32 INFO - 'SHELL': '/bin/bash', 08:29:32 INFO - 'SHLVL': '1', 08:29:32 INFO - 'TERM': 'linux', 08:29:32 INFO - 'TMOUT': '86400', 08:29:32 INFO - 'USER': 'cltbld', 08:29:32 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1479398284.657764-1420876439', 08:29:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:29:32 INFO - '_': '/tools/buildbot/bin/python'} 08:29:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:29:32 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 08:29:32 INFO - Cleaning up... 08:29:32 INFO - Return code: 0 08:29:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:29: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')]} 08:29:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:29:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29: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')]} 08:29:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:29:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29: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', '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 0x7f9451375e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2658b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27f4130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2882fc0>, '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 0x2883ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2884350>, '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': '41bf3e9aed84707956354da8000001c5-1479398284.657764-1420876439', '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 08:29: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', 'psutil>=3.1.1'] in /builds/slave/test/build 08:29: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 psutil>=3.1.1 08:29:32 INFO - Using env: {'DISPLAY': ':0', 08:29:32 INFO - 'HOME': '/home/cltbld', 08:29:32 INFO - 'LANG': 'en_US.UTF-8', 08:29:32 INFO - 'LANGUAGE': 'en_US:en', 08:29:32 INFO - 'LOGNAME': 'cltbld', 08:29:32 INFO - 'MAIL': '/var/mail/cltbld', 08:29:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:29:32 INFO - 'MOZ_NO_REMOTE': '1', 08:29:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:29:32 INFO - 'NO_EM_RESTART': '1', 08:29:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:29:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:29:32 INFO - 'PWD': '/builds/slave/test', 08:29:32 INFO - 'SHELL': '/bin/bash', 08:29:32 INFO - 'SHLVL': '1', 08:29:32 INFO - 'TERM': 'linux', 08:29:32 INFO - 'TMOUT': '86400', 08:29:32 INFO - 'USER': 'cltbld', 08:29:32 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1479398284.657764-1420876439', 08:29:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:29:32 INFO - '_': '/tools/buildbot/bin/python'} 08:29:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:29:32 INFO - Downloading/unpacking psutil>=3.1.1 08:29: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 08:29: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 08:29: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 08:29: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 08:29: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 08:29: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 08:29:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:29:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:29:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:29:34 INFO - Installing collected packages: psutil 08:29:34 INFO - Running setup.py install for psutil 08:29:34 INFO - building 'psutil._psutil_linux' extension 08:29:34 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 08:29:34 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 08:29:35 INFO - building 'psutil._psutil_posix' extension 08:29:35 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 08:29:35 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 08:29:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:29:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:29:35 INFO - Successfully installed psutil 08:29:35 INFO - Cleaning up... 08:29:35 INFO - Return code: 0 08:29:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:29: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')]} 08:29:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:29:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29: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')]} 08:29:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:29:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9451375e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2658b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27f4130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2882fc0>, '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 0x2883ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2884350>, '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': '41bf3e9aed84707956354da8000001c5-1479398284.657764-1420876439', '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 08:29: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:29: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 mozsystemmonitor==0.3 08:29:35 INFO - Using env: {'DISPLAY': ':0', 08:29:35 INFO - 'HOME': '/home/cltbld', 08:29:35 INFO - 'LANG': 'en_US.UTF-8', 08:29:35 INFO - 'LANGUAGE': 'en_US:en', 08:29:35 INFO - 'LOGNAME': 'cltbld', 08:29:35 INFO - 'MAIL': '/var/mail/cltbld', 08:29:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:29:35 INFO - 'MOZ_NO_REMOTE': '1', 08:29:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:29:35 INFO - 'NO_EM_RESTART': '1', 08:29:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:29:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:29:35 INFO - 'PWD': '/builds/slave/test', 08:29:35 INFO - 'SHELL': '/bin/bash', 08:29:35 INFO - 'SHLVL': '1', 08:29:35 INFO - 'TERM': 'linux', 08:29:35 INFO - 'TMOUT': '86400', 08:29:35 INFO - 'USER': 'cltbld', 08:29:35 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1479398284.657764-1420876439', 08:29:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:29:35 INFO - '_': '/tools/buildbot/bin/python'} 08:29:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:29:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:29: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 08:29: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 08:29: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 08:29: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 08:29: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 08:29: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 08:29:37 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:29:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:29:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:29:37 INFO - Installing collected packages: mozsystemmonitor 08:29:37 INFO - Running setup.py install for mozsystemmonitor 08:29:37 INFO - Successfully installed mozsystemmonitor 08:29:37 INFO - Cleaning up... 08:29:37 INFO - Return code: 0 08:29:37 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:29:37 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')]} 08:29:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:29:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29:37 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')]} 08:29:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:29:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29:37 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 0x7f9451375e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2658b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27f4130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2882fc0>, '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 0x2883ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2884350>, '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': '41bf3e9aed84707956354da8000001c5-1479398284.657764-1420876439', '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 08:29:37 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 08:29:37 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 08:29:37 INFO - Using env: {'DISPLAY': ':0', 08:29:37 INFO - 'HOME': '/home/cltbld', 08:29:37 INFO - 'LANG': 'en_US.UTF-8', 08:29:37 INFO - 'LANGUAGE': 'en_US:en', 08:29:37 INFO - 'LOGNAME': 'cltbld', 08:29:37 INFO - 'MAIL': '/var/mail/cltbld', 08:29:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:29:37 INFO - 'MOZ_NO_REMOTE': '1', 08:29:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:29:37 INFO - 'NO_EM_RESTART': '1', 08:29:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:29:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:29:37 INFO - 'PWD': '/builds/slave/test', 08:29:37 INFO - 'SHELL': '/bin/bash', 08:29:37 INFO - 'SHLVL': '1', 08:29:37 INFO - 'TERM': 'linux', 08:29:37 INFO - 'TMOUT': '86400', 08:29:37 INFO - 'USER': 'cltbld', 08:29:37 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1479398284.657764-1420876439', 08:29:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:29:37 INFO - '_': '/tools/buildbot/bin/python'} 08:29:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:29:38 INFO - Downloading/unpacking jsonschema==2.5.1 08:29:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:29:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:29:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:29:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:29:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:29:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:29:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:29:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:29:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:29:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:29:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:29:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:29:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:29:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:29:40 INFO - Downloading functools32-3.2.3-2.tar.gz 08:29:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:29:40 INFO - warning: no files found matching '*.txt' 08:29:40 INFO - no previously-included directories found matching 'build' 08:29:40 INFO - no previously-included directories found matching 'dist' 08:29:40 INFO - no previously-included directories found matching '.git*' 08:29:40 INFO - Installing collected packages: jsonschema, functools32 08:29:40 INFO - Running setup.py install for functools32 08:29:40 INFO - warning: no files found matching '*.txt' 08:29:40 INFO - no previously-included directories found matching 'build' 08:29:40 INFO - no previously-included directories found matching 'dist' 08:29:40 INFO - no previously-included directories found matching '.git*' 08:29:40 INFO - Successfully installed jsonschema functools32 08:29:40 INFO - Cleaning up... 08:29:40 INFO - Return code: 0 08:29:40 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:29:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:29:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:29:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:29:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:29:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f9451375e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2658b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27f4130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2882fc0>, '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 0x2883ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2884350>, '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': '41bf3e9aed84707956354da8000001c5-1479398284.657764-1420876439', '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 08:29:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:29:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:29:40 INFO - Using env: {'DISPLAY': ':0', 08:29:40 INFO - 'HOME': '/home/cltbld', 08:29:40 INFO - 'LANG': 'en_US.UTF-8', 08:29:40 INFO - 'LANGUAGE': 'en_US:en', 08:29:40 INFO - 'LOGNAME': 'cltbld', 08:29:40 INFO - 'MAIL': '/var/mail/cltbld', 08:29:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:29:40 INFO - 'MOZ_NO_REMOTE': '1', 08:29:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:29:40 INFO - 'NO_EM_RESTART': '1', 08:29:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:29:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:29:40 INFO - 'PWD': '/builds/slave/test', 08:29:40 INFO - 'SHELL': '/bin/bash', 08:29:40 INFO - 'SHLVL': '1', 08:29:40 INFO - 'TERM': 'linux', 08:29:40 INFO - 'TMOUT': '86400', 08:29:40 INFO - 'USER': 'cltbld', 08:29:40 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1479398284.657764-1420876439', 08:29:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:29:40 INFO - '_': '/tools/buildbot/bin/python'} 08:29:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:29:40 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:29:40 INFO - Cleaning up... 08:29:40 INFO - Return code: 0 08:29:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:29:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:29:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:29:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:29:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:29:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f9451375e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2658b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27f4130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2882fc0>, '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 0x2883ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2884350>, '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': '41bf3e9aed84707956354da8000001c5-1479398284.657764-1420876439', '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 08:29:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:29:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:29:40 INFO - Using env: {'DISPLAY': ':0', 08:29:40 INFO - 'HOME': '/home/cltbld', 08:29:40 INFO - 'LANG': 'en_US.UTF-8', 08:29:40 INFO - 'LANGUAGE': 'en_US:en', 08:29:40 INFO - 'LOGNAME': 'cltbld', 08:29:40 INFO - 'MAIL': '/var/mail/cltbld', 08:29:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:29:40 INFO - 'MOZ_NO_REMOTE': '1', 08:29:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:29:40 INFO - 'NO_EM_RESTART': '1', 08:29:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:29:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:29:40 INFO - 'PWD': '/builds/slave/test', 08:29:40 INFO - 'SHELL': '/bin/bash', 08:29:40 INFO - 'SHLVL': '1', 08:29:40 INFO - 'TERM': 'linux', 08:29:40 INFO - 'TMOUT': '86400', 08:29:40 INFO - 'USER': 'cltbld', 08:29:40 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1479398284.657764-1420876439', 08:29:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:29:40 INFO - '_': '/tools/buildbot/bin/python'} 08:29:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:29:40 INFO - Downloading/unpacking blobuploader==1.2.4 08:29:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:29:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:29:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:29:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:29:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:29:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:29:42 INFO - Downloading blobuploader-1.2.4.tar.gz 08:29:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:29:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:29:42 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 08:29:42 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 08:29:42 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 08:29:42 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 08:29:42 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 08:29:42 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 08:29:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:29:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:29: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 08:29: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 08:29: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 08:29: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 08:29: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 08:29: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 08:29:43 INFO - Downloading docopt-0.6.1.tar.gz 08:29:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:29:43 INFO - Installing collected packages: blobuploader, requests, docopt 08:29:43 INFO - Running setup.py install for blobuploader 08:29:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:29:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:29:43 INFO - Running setup.py install for requests 08:29:44 INFO - Running setup.py install for docopt 08:29:44 INFO - Successfully installed blobuploader requests docopt 08:29:44 INFO - Cleaning up... 08:29:44 INFO - Return code: 0 08:29:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:29:44 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')]} 08:29:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:29:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29:44 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')]} 08:29:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:29:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29:44 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 0x7f9451375e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2658b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27f4130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2882fc0>, '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 0x2883ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2884350>, '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': '41bf3e9aed84707956354da8000001c5-1479398284.657764-1420876439', '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 08:29:44 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 08:29:44 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 08:29:44 INFO - Using env: {'DISPLAY': ':0', 08:29:44 INFO - 'HOME': '/home/cltbld', 08:29:44 INFO - 'LANG': 'en_US.UTF-8', 08:29:44 INFO - 'LANGUAGE': 'en_US:en', 08:29:44 INFO - 'LOGNAME': 'cltbld', 08:29:44 INFO - 'MAIL': '/var/mail/cltbld', 08:29:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:29:44 INFO - 'MOZ_NO_REMOTE': '1', 08:29:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:29:44 INFO - 'NO_EM_RESTART': '1', 08:29:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:29:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:29:44 INFO - 'PWD': '/builds/slave/test', 08:29:44 INFO - 'SHELL': '/bin/bash', 08:29:44 INFO - 'SHLVL': '1', 08:29:44 INFO - 'TERM': 'linux', 08:29:44 INFO - 'TMOUT': '86400', 08:29:44 INFO - 'USER': 'cltbld', 08:29:44 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1479398284.657764-1420876439', 08:29:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:29:44 INFO - '_': '/tools/buildbot/bin/python'} 08:29:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:29:44 INFO - Running setup.py (path:/tmp/pip-tguV16-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:29:44 INFO - Running setup.py (path:/tmp/pip-oQYVXD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:29:44 INFO - Running setup.py (path:/tmp/pip-DqMiuN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:29:44 INFO - Running setup.py (path:/tmp/pip-MjYq1B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:29:44 INFO - Running setup.py (path:/tmp/pip-8mWwYx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:29:44 INFO - Running setup.py (path:/tmp/pip-QXNviT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:29:44 INFO - Running setup.py (path:/tmp/pip-Rp35gv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:29:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:29:45 INFO - Running setup.py (path:/tmp/pip-urFm9G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:29:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:29:45 INFO - Running setup.py (path:/tmp/pip-zY1sbk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:29:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:29:45 INFO - Running setup.py (path:/tmp/pip-FAYq9B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:29:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:29:45 INFO - Running setup.py (path:/tmp/pip-sp_RTe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:29:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:29:45 INFO - Running setup.py (path:/tmp/pip-ZKna8m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:29:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:29:45 INFO - Running setup.py (path:/tmp/pip-QSLEOG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:29:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:29:45 INFO - Running setup.py (path:/tmp/pip-EiHHdE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:29:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:29:45 INFO - Running setup.py (path:/tmp/pip-VacbnZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:29:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:29:45 INFO - Running setup.py (path:/tmp/pip-tNqoOx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:29:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:29:45 INFO - Running setup.py (path:/tmp/pip-WaY_oW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:29:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:29:45 INFO - Running setup.py install for manifestparser 08:29:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:29:45 INFO - Running setup.py install for mozcrash 08:29:45 INFO - Running setup.py install for mozdebug 08:29:46 INFO - Running setup.py install for mozdevice 08:29:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:29:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:29:46 INFO - Running setup.py install for mozfile 08:29:46 INFO - Running setup.py install for mozhttpd 08:29:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:29:46 INFO - Running setup.py install for mozinfo 08:29:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:29:46 INFO - Running setup.py install for mozInstall 08:29:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:29:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:29:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:29:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:29:46 INFO - Running setup.py install for mozleak 08:29:46 INFO - Running setup.py install for mozlog 08:29:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:29:46 INFO - Running setup.py install for moznetwork 08:29:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:29:46 INFO - Running setup.py install for mozprocess 08:29:47 INFO - Running setup.py install for mozprofile 08:29:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:29:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:29:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:29:47 INFO - Running setup.py install for mozrunner 08:29:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:29:47 INFO - Running setup.py install for mozscreenshot 08:29:47 INFO - Running setup.py install for moztest 08:29:47 INFO - Running setup.py install for mozversion 08:29:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:29:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:29:47 INFO - Cleaning up... 08:29:47 INFO - Return code: 0 08:29:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:29:47 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')]} 08:29:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:29:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29:47 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')]} 08:29:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:29:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29:47 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 0x7f9451375e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2658b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27f4130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2882fc0>, '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 0x2883ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2884350>, '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': '41bf3e9aed84707956354da8000001c5-1479398284.657764-1420876439', '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 08:29:47 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 08:29:47 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 08:29:47 INFO - Using env: {'DISPLAY': ':0', 08:29:47 INFO - 'HOME': '/home/cltbld', 08:29:47 INFO - 'LANG': 'en_US.UTF-8', 08:29:47 INFO - 'LANGUAGE': 'en_US:en', 08:29:47 INFO - 'LOGNAME': 'cltbld', 08:29:47 INFO - 'MAIL': '/var/mail/cltbld', 08:29:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:29:47 INFO - 'MOZ_NO_REMOTE': '1', 08:29:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:29:47 INFO - 'NO_EM_RESTART': '1', 08:29:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:29:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:29:47 INFO - 'PWD': '/builds/slave/test', 08:29:47 INFO - 'SHELL': '/bin/bash', 08:29:47 INFO - 'SHLVL': '1', 08:29:47 INFO - 'TERM': 'linux', 08:29:47 INFO - 'TMOUT': '86400', 08:29:47 INFO - 'USER': 'cltbld', 08:29:47 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1479398284.657764-1420876439', 08:29:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:29:47 INFO - '_': '/tools/buildbot/bin/python'} 08:29:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:29:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:29:47 INFO - Running setup.py (path:/tmp/pip-ROm4EU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:29:47 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)) 08:29:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:29:47 INFO - Running setup.py (path:/tmp/pip-PwjrdZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:29:47 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)) 08:29:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:29:47 INFO - Running setup.py (path:/tmp/pip-BRlQQe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:29:48 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)) 08:29:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:29:48 INFO - Running setup.py (path:/tmp/pip-HTqJax-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:29:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:29:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:29:48 INFO - Running setup.py (path:/tmp/pip-ov9NL1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:29:48 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)) 08:29:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:29:48 INFO - Running setup.py (path:/tmp/pip-BIZ55K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:29:48 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)) 08:29:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:29:48 INFO - Running setup.py (path:/tmp/pip-qPmUHK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:29:48 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)) 08:29:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:29:48 INFO - Running setup.py (path:/tmp/pip-tNP5A1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:29:48 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)) 08:29:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:29:48 INFO - Running setup.py (path:/tmp/pip-CmUtJG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:29:48 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)) 08:29:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:29:48 INFO - Running setup.py (path:/tmp/pip-iFJxE2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:29:48 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)) 08:29:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:29:48 INFO - Running setup.py (path:/tmp/pip-jIC90c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:29:48 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)) 08:29:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:29:48 INFO - Running setup.py (path:/tmp/pip-RnfchO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:29:48 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)) 08:29:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:29:48 INFO - Running setup.py (path:/tmp/pip-VykRB2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:29:48 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)) 08:29:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:29:48 INFO - Running setup.py (path:/tmp/pip-9Wnz3V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:29:48 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)) 08:29:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:29:48 INFO - Running setup.py (path:/tmp/pip-6V5IIO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:29:48 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)) 08:29:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:29:48 INFO - Running setup.py (path:/tmp/pip-yTBNdk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:29:48 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)) 08:29:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:29:48 INFO - Running setup.py (path:/tmp/pip-EKp33P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:29:49 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)) 08:29:49 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)) 08:29:49 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)) 08:29:49 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)) 08:29:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:29:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:29:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:29:49 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 08:29:49 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 08:29:49 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 08:29:49 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 08:29:49 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 08:29:49 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 08:29:50 INFO - Downloading blessings-1.6.tar.gz 08:29:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:29:51 INFO - Installing collected packages: blessings 08:29:51 INFO - Running setup.py install for blessings 08:29:51 INFO - Successfully installed blessings 08:29:51 INFO - Cleaning up... 08:29:51 INFO - Return code: 0 08:29:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:29:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:29:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:29:51 INFO - Reading from file tmpfile_stdout 08:29:51 INFO - Current package versions: 08:29:51 INFO - argparse == 1.2.1 08:29:51 INFO - blessings == 1.6 08:29:51 INFO - blobuploader == 1.2.4 08:29:51 INFO - docopt == 0.6.1 08:29:51 INFO - functools32 == 3.2.3-2 08:29:51 INFO - jsonschema == 2.5.1 08:29:51 INFO - manifestparser == 1.1 08:29:51 INFO - mozInstall == 1.12 08:29:51 INFO - mozcrash == 1.0 08:29:51 INFO - mozdebug == 0.1 08:29:51 INFO - mozdevice == 0.48 08:29:51 INFO - mozfile == 1.2 08:29:51 INFO - mozhttpd == 0.7 08:29:51 INFO - mozinfo == 0.9 08:29:51 INFO - mozleak == 0.1 08:29:51 INFO - mozlog == 3.4 08:29:51 INFO - moznetwork == 0.27 08:29:51 INFO - mozprocess == 0.23 08:29:51 INFO - mozprofile == 0.28 08:29:51 INFO - mozrunner == 6.13 08:29:51 INFO - mozscreenshot == 0.1 08:29:51 INFO - mozsystemmonitor == 0.3 08:29:51 INFO - moztest == 0.7 08:29:51 INFO - mozversion == 1.4 08:29:51 INFO - psutil == 3.1.1 08:29:51 INFO - requests == 1.2.3 08:29:51 INFO - wsgiref == 0.1.2 08:29:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:29:51 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')]} 08:29:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:29:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29:51 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')]} 08:29:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:29:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29:51 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 0x7f9451375e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2658b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27f4130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2882fc0>, '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 0x2883ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2884350>, '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': '41bf3e9aed84707956354da8000001c5-1479398284.657764-1420876439', '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 08:29:51 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 08:29:51 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 08:29:51 INFO - Using env: {'DISPLAY': ':0', 08:29:51 INFO - 'HOME': '/home/cltbld', 08:29:51 INFO - 'LANG': 'en_US.UTF-8', 08:29:51 INFO - 'LANGUAGE': 'en_US:en', 08:29:51 INFO - 'LOGNAME': 'cltbld', 08:29:51 INFO - 'MAIL': '/var/mail/cltbld', 08:29:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:29:51 INFO - 'MOZ_NO_REMOTE': '1', 08:29:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:29:51 INFO - 'NO_EM_RESTART': '1', 08:29:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:29:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:29:51 INFO - 'PWD': '/builds/slave/test', 08:29:51 INFO - 'SHELL': '/bin/bash', 08:29:51 INFO - 'SHLVL': '1', 08:29:51 INFO - 'TERM': 'linux', 08:29:51 INFO - 'TMOUT': '86400', 08:29:51 INFO - 'USER': 'cltbld', 08:29:51 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1479398284.657764-1420876439', 08:29:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:29:51 INFO - '_': '/tools/buildbot/bin/python'} 08:29:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:29:51 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)) 08:29:51 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)) 08:29:51 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)) 08:29:51 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)) 08:29:51 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)) 08:29:51 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)) 08:29:51 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)) 08:29:51 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)) 08:29:51 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)) 08:29:51 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)) 08:29:51 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)) 08:29:51 INFO - Cleaning up... 08:29:51 INFO - Return code: 0 08:29:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:29:51 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')]} 08:29:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:29:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29:51 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')]} 08:29:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:29:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29:51 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 0x7f9451375e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2658b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27f4130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2882fc0>, '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 0x2883ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2884350>, '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': '41bf3e9aed84707956354da8000001c5-1479398284.657764-1420876439', '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 08:29:51 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 08:29:51 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 08:29:51 INFO - Using env: {'DISPLAY': ':0', 08:29:51 INFO - 'HOME': '/home/cltbld', 08:29:51 INFO - 'LANG': 'en_US.UTF-8', 08:29:51 INFO - 'LANGUAGE': 'en_US:en', 08:29:51 INFO - 'LOGNAME': 'cltbld', 08:29:51 INFO - 'MAIL': '/var/mail/cltbld', 08:29:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:29:51 INFO - 'MOZ_NO_REMOTE': '1', 08:29:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:29:51 INFO - 'NO_EM_RESTART': '1', 08:29:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:29:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:29:51 INFO - 'PWD': '/builds/slave/test', 08:29:51 INFO - 'SHELL': '/bin/bash', 08:29:51 INFO - 'SHLVL': '1', 08:29:51 INFO - 'TERM': 'linux', 08:29:51 INFO - 'TMOUT': '86400', 08:29:51 INFO - 'USER': 'cltbld', 08:29:51 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1479398284.657764-1420876439', 08:29:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:29:51 INFO - '_': '/tools/buildbot/bin/python'} 08:29:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:29:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:29:51 INFO - Cleaning up... 08:29:51 INFO - Return code: 0 08:29:51 INFO - Running post-action listener: _resource_record_post_action 08:29:51 INFO - Running post-action listener: _start_resource_monitoring 08:29:51 INFO - Starting resource monitoring. 08:29:51 INFO - [mozharness: 2016-11-17 16:29:51.918459Z] Finished create-virtualenv step (success) 08:29:51 INFO - [mozharness: 2016-11-17 16:29:51.918974Z] Running install step. 08:29:51 INFO - Running pre-action listener: _resource_record_pre_action 08:29:51 INFO - Running main action method: install 08:29:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:29:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:29:52 INFO - Reading from file tmpfile_stdout 08:29:52 INFO - Detecting whether we're running mozinstall >=1.0... 08:29:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:29:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:29:52 INFO - Reading from file tmpfile_stdout 08:29:52 INFO - Output received: 08:29:52 INFO - Usage: mozinstall [options] installer 08:29:52 INFO - Options: 08:29:52 INFO - -h, --help show this help message and exit 08:29:52 INFO - -d DEST, --destination=DEST 08:29:52 INFO - Directory to install application into. [default: 08:29:52 INFO - "/builds/slave/test"] 08:29:52 INFO - --app=APP Application being installed. [default: firefox] 08:29:52 INFO - mkdir: /builds/slave/test/build/application 08:29:52 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'] 08:29:52 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 08:30:08 INFO - Reading from file tmpfile_stdout 08:30:08 INFO - Output received: 08:30:08 INFO - /builds/slave/test/build/application/firefox/firefox 08:30:08 INFO - Running post-action listener: _resource_record_post_action 08:30:08 INFO - [mozharness: 2016-11-17 16:30:08.280278Z] Finished install step (success) 08:30:08 INFO - [mozharness: 2016-11-17 16:30:08.280531Z] Running run-tests step. 08:30:08 INFO - Running pre-action listener: _resource_record_pre_action 08:30:08 INFO - Running main action method: run_tests 08:30:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:30:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:30:08 INFO - Python 2.7.3 08:30:08 INFO - Return code: 0 08:30:08 INFO - grabbing minidump binary from tooltool 08:30:08 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')]} 08:30:08 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 0x2882fc0>, '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 0x2883ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2884350>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:30:08 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 08:30:08 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 08:30:08 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 08:30:08 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:30:08 INFO - Return code: 0 08:30:08 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:30:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:30:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:30:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:30:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:30:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JIiAHDWhR_iuSqkoCeNzGQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-026', '--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 08:30:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JIiAHDWhR_iuSqkoCeNzGQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-026 --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 08:30:08 INFO - Using env: {'DISPLAY': ':0', 08:30:08 INFO - 'HOME': '/home/cltbld', 08:30:08 INFO - 'LANG': 'en_US.UTF-8', 08:30:08 INFO - 'LANGUAGE': 'en_US:en', 08:30:08 INFO - 'LOGNAME': 'cltbld', 08:30:08 INFO - 'MAIL': '/var/mail/cltbld', 08:30:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:30:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:30:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:08 INFO - 'MOZ_NO_REMOTE': '1', 08:30:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:30:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:30:08 INFO - 'NO_EM_RESTART': '1', 08:30:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:30:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:08 INFO - 'PWD': '/builds/slave/test', 08:30:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:30:08 INFO - 'SHELL': '/bin/bash', 08:30:08 INFO - 'SHLVL': '1', 08:30:08 INFO - 'TERM': 'linux', 08:30:08 INFO - 'TMOUT': '86400', 08:30:08 INFO - 'USER': 'cltbld', 08:30:08 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1479398284.657764-1420876439', 08:30:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:08 INFO - '_': '/tools/buildbot/bin/python'} 08:30:08 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JIiAHDWhR_iuSqkoCeNzGQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-026', '--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 08:30:08 INFO - mozversion application_buildid: 20161117070428 08:30:08 INFO - mozversion application_changeset: f27e26039a4d9d4f4041347d341b98054fb10612 08:30:08 INFO - mozversion application_display_name: Nightly 08:30:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:30:08 INFO - mozversion application_name: Firefox 08:30:08 INFO - mozversion application_remotingname: firefox 08:30:08 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 08:30:08 INFO - mozversion application_vendor: Mozilla 08:30:08 INFO - mozversion application_version: 53.0a1 08:30:08 INFO - mozversion platform_buildid: 20161117070428 08:30:08 INFO - mozversion platform_changeset: f27e26039a4d9d4f4041347d341b98054fb10612 08:30:08 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 08:30:08 INFO - mozversion platform_version: 53.0a1 08:30:08 INFO - using testdate: 1479400208 08:30:08 INFO - actual date: 1479400208 08:30:08 INFO - starting webserver on 'localhost:59485' 08:30:08 INFO - SUITE-START | Running 2 tests 08:30:08 INFO - TEST-START | dromaeo_css 08:30:08 INFO - operating with platform_type : linux_ 08:30:08 INFO - Initialising browser for dromaeo_css test... 08:30:08 INFO - TEST-INFO | started process 18283 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYBHscu/profile http://localhost:59485/getInfo.html) 08:30:12 INFO - PROCESS | 18283 | __metrics Screen width/height:1600/1200 08:30:12 INFO - PROCESS | 18283 | colorDepth:24 08:30:12 INFO - PROCESS | 18283 | Browser inner width/height: 1024/697 08:30:12 INFO - PROCESS | 18283 | __metrics 08:30:13 INFO - PROCESS | 18283 | 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" 08:30:13 INFO - PROCESS | 18283 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:30:14 INFO - PROCESS | 18283 | console.error: PushService: 08:30:14 INFO - PROCESS | 18283 | stateChangeProcessEnqueue: Error transitioning state 08:30:14 INFO - PROCESS | 18283 | UnknownError 08:30:14 INFO - TEST-INFO | 18283: exit 0 08:30:14 INFO - Browser initialized. 08:30:14 INFO - Running cycle 1/1 for dromaeo_css test... 08:30:14 INFO - TEST-INFO | started process 18365 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYBHscu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:30:27 INFO - PROCESS | 18365 | RSS: Main: 174473216 08:30:27 INFO - PROCESS | 18365 | 08:31:18 INFO - PROCESS | 18365 | 1479400278309 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] 08:33:34 INFO - PROCESS | 18365 | Cycle 1(1): loaded http://localhost:59485/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59485/tests/dromaeo/cssquery-ext.html) 08:33:35 INFO - PROCESS | 18365 | RSS: Main: 317169664 08:33:35 INFO - PROCESS | 18365 | 08:36:38 INFO - PROCESS | 18365 | Cycle 1(1): loaded http://localhost:59485/tests/dromaeo/cssquery-ext.html (next: http://localhost:59485/tests/dromaeo/cssquery-jquery.html) 08:36:38 INFO - PROCESS | 18365 | RSS: Main: 283115520 08:36:38 INFO - PROCESS | 18365 | 08:39:41 INFO - PROCESS | 18365 | Cycle 1(1): loaded http://localhost:59485/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59485/tests/dromaeo/cssquery-mootools.html) 08:39:41 INFO - PROCESS | 18365 | RSS: Main: 224145408 08:39:41 INFO - PROCESS | 18365 | 08:42:44 INFO - PROCESS | 18365 | Cycle 1(1): loaded http://localhost:59485/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59485/tests/dromaeo/cssquery-prototype.html) 08:42:44 INFO - PROCESS | 18365 | RSS: Main: 220753920 08:42:44 INFO - PROCESS | 18365 | 08:45:47 INFO - PROCESS | 18365 | Cycle 1(1): loaded http://localhost:59485/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59485/tests/dromaeo/cssquery-yui.html) 08:45:48 INFO - PROCESS | 18365 | RSS: Main: 220962816 08:45:48 INFO - PROCESS | 18365 | 08:48:51 INFO - PROCESS | 18365 | Cycle 1(1): loaded http://localhost:59485/tests/dromaeo/cssquery-yui.html (next: http://localhost:59485/tests/dromaeo/cssquery-dojo.html) 08:48:51 INFO - PROCESS | 18365 | RSS: Main: 320028672 08:48:51 INFO - PROCESS | 18365 | 08:48:51 INFO - PROCESS | 18365 | __start_tp_report 08:48:51 INFO - PROCESS | 18365 | _x_x_mozilla_page_load 08:48:51 INFO - PROCESS | 18365 | _x_x_mozilla_page_load_details 08:48:51 INFO - PROCESS | 18365 | |i|pagename|runs| 08:48:51 INFO - PROCESS | 18365 | |0;dojo.html;2579.600161974892;2580.793536517079;2615.2285075902605;2618.1491015419983;2631.052820984445;4560.47554531229;4593.379893714347;4689.718616882985;4724.692894961825;4734.502877197892;4657.6972496787685;4674.111918735446;4710.552497512731;4742.69172503787;4788.257820037891;4244.575542445747;4267.829286828523;4309.935350969738;4322.28682267427;4336.544662810406;4299.957000429987;4365.563443655625;4401.449818772653;4415.80128894199;4447.35513350564;4130.112025914443;4361.956380436219;4365.388845561624;4376.234159022174;4452.1986042512335;4164.1879833432495;4206.095689426757;4210.705250632457;4211.3261878099665;4220.704550681454;4267.125239325931;4270.978645106755;4288.249556327662;4358.8910277243;4365.49796773372;4615.584597386218;4639.118567472169;4646.070785842845;4660.580547750685;4667.509911459282;4957.925631115536;4982.053409852117;4985.850424487271;4994.150994330972;5036.294918711384;4839.7338146401935;4892.951070489321;4906.950930490685;4965.503449655005;4965.553100220997;4668.2064049111395;4669.906601867943;4673.766311684402;4674.906501869944;4678.415198100238;5036.269740887551;5054.873628159252;5082.593392528589;5087.923681144785;5092.8472145835685;4805.687630304089;4838.927416088762;4883.902321953541;4898.951010489849;4900.166971614834;5003.449620541737;5004.3994720633755;5011.874703132378;5012.373453318335;5051.090803655304;4979.950200498021;4986.227134794112;4991.276264941564;5024.221245706848;5025.447200807908;4770.356001939694;4779.856604301876;4803.78382972767;4862.562369386701;4961.330220420269;4221.598948099926;4222.535521092677;4223.788810559459;4224.8732538023905;4226.450561426995;3721.1999420124803;3731.514903062639;3735.0475628714503;3735.5704094029534;3737.252549490113;4315.180115777992;4318.935215971763;4355.216061108968;4355.324924636687;4448.266036104072;4995.025969935828;5081.796728130834;5097.9745101275;5100.515451032146;5144.151215049475;4186.853460128881;4369.9126017479475;4382.342648602735;4385.736855788663;4430.424044874146;4432.578905004019;4529.750863702462;4564.452265728134;4584.816607335669;4618.145643056046;4762.809487620457;4764.475907650087;4770.403699537558;4777.259524773665;4837.467878533288;4755.167845626932;5076.82231121909;5094.974525127351;5095.0574143783515;5141;4581.816727330869;4627.0514544518965;4667.486576476517;4684.391029166187;4728.834490792806;4897.363342765418;4902.926456103232;4908.656394052382;4910.116179087799;4942.456329803791;4974.776135073858;4990.600751939909;4990.8253211137435;4994.425641051325;5029.346184995927;4692.554207350297;4707.59985401231;4715.221988372017;4797.56821886032;4798.976005119952;4759.809607615656;4799.064182484382;4824.758762061953;4825.083234185494;4825.420949486084;4643.233866412004;4665.626749860071;4674.392328997209;4678.017616300479;4683.250679891201;4955.900881982412;4958.553730164158;4958.925616115689;4971.651984361205;4997.5002499749735;4856.028794241095;4856.660033797601;4861.975690121527;4865.1242776300605;4909.656324057424 08:48:51 INFO - PROCESS | 18365 | |1;ext.html;536.8899375627997;547.0563278344856;560.4787547580748;567.3957235544143;568.8208214412457;27432.177034688968;36725.632743672555;38011.99999999996;38102.428463573066;41763.37354939678;39247.60752392475;39661.20677586446;39665;39667;39693.80153099238;7422.257774222561;7610.657520411567;7610.733624323149;7612.7716168515035;7618.809529761773;7453.142888567802;7544;7548.131964824061;7550.4337174711845;7551.093868735759;7520.511166774142;7566.583837888914;7582.810429739246;7583.734569290076;7585.127710313302;7512.624368781567;7553;7557.811054723621;7564.924350756477;7568.583727894964;7276.308750668731;7325.377342925858;7331.1569169545655;7334.66993985272;7335.193128755833;36974.8151259242;37168.628313716785;37294.81352593247;37348;37359.626403735885;2851.5444224539324;2955.7192066753623;2989.9402011959855;3144.371125774854;3172.4448221561133;27305.726942730715;27909.441811163662;28272.43455130886;28367.43265134686;28951.85524072387;3442.2705650538364;3464.532288141094;3511.4557243627023;3534.3638145133864;3583.354996100727;249475;249639;249985;250477;250666;29706.851465742533;48781.756091219315;48955.51044489581;49050.50949490531;49084;31038.534421983688;32759.50860737091;33036;33063.83468082644;33071.173220669196;11116.110711143088;11135.109191264679;11140.888591114148;11237.044851187577;11252.718682033015;10411.063004329495;10492.842607360895;10494.527746251437;10496.632617858339;10504.47477626131;7537.095548534211;7584.582847943416;7660.61696915152;7665.923340766521;7670.769876903591;2958.156925276286;3075.185075954874;3090.5982222310954;3099.628044634613;3101.968980310168;879.4151888993747;961.6682244625594;962.0619895601733;962.138885697297;968.4867020479157;26412.095464200924;27646;30955.690443095733;31164.844175778977;31818.204544886565;928.373347990104;945.2957546627806;958.9568469418892;1002.2433063037361;1010.2675560218782;13304.467821287233;14041.648958775908;14172.574822755334;14371.856281437262;14393.136411815325;357514;366173;367132;367561;367866;45486.772566136955;46033;46055.309170362474;46097.078058438645;46154.76922615366;1167.6613782003124;1183.2841131115724;1208.2206976500283;1210.709429736857;1215.465195314059;25281.87359063193;25835.612465812654;26103.216903492925;26524.204273871816;26681.466370672864;39965.20069598534;40698.389524157756;40699.79650101731;40814.59185408108;40824.1835163301;252341;252987;253697;254631;255522;22627;22642;22786.886065569564;22949.88525057364;22984;38745.03137421474;38810.80594597009;38849;39266.607333926295;39287.2142557153;31422;32670.83664581662;33863.492047619795;34041.31917361589;34105.48841767326;33549.49675754915;33727.15682107967;33927.32145357128;33989.66010339865;34082.488762669076;28545.42909141847;28991.130266092052;31002.379952401276;32986;33261.16847078843;28398.29004274827;30856.83700322605;31009.58776124012;31211.531827023067;31387.686123138476;23764.643530347403;23775.048998039885;23892;24182.39544011414;24287.75712242855 08:48:51 INFO - PROCESS | 18365 | |2;jquery.html;338.82042517465754;344.2512535235863;351.4271737068578;356.1594636657488;377.9508663873696;12508.749825003495;15900.840991590081;15987.360505579778;16862.915685421587;17011.57471063224;16696;16810.83189168108;17011.48965531036;17125.743113853303;17132.486025419257;4808.519148085181;4829.275608658694;4959.479254678261;4980.526849949249;5010.6993580385115;4912.115819152551;4990.251462280668;5001.224810154432;5010.874728131789;5252.107141785887;5335.733213339317;5342.2253773202865;5344.038073146832;5348.866278343034;5359.035373632744;5181.274621552986;5327.733613319338;5338.546223571001;5516.889662206733;5556.638818476786;4920.261960705887;4937.284093806414;4946.901061978776;4949.579285760715;4958.826441074545;11103.167262455348;11132.666020019413;11145.554177832877;11184;11217.102631789438;1750.719884818423;1837.3477415517566;1857.6841936870628;1918.7026010968323;2026.0376321247436;8020.478668886562;8276.875846862302;8364.58177091143;8646.827063458759;8838.86741698875;1514.5910604136932;1603.7651008723335;1608.340580362046;1634.133909028217;1711.5806627376248;67776.6611166946;68203;69250;69600.6519967402;69873.65063174651;7588.544687318778;8026.117127116013;8075.3539716822515;8091.514509129471;8575.356848236408;11201.495932683049;11277;11288.54845806175;11288.717782055513;11309.660710178709;6572.835679107965;6719.428848547928;6798.456123510107;7001;7008.719651213996;6621.735130594819;6641.136652235178;6855.429214044733;6873.862522749617;6877.1747390312485;3448.655134486531;3476.304739052149;3503.5270238517983;3545.804980726085;3570.839312230956;1723.9827601724073;1724.7240441529295;1736.218701584267;1745.7555942168106;1764.7794025746782;790.5138339921023;810.5217921426387;832.138736407815;859.1022381611232;903.3495882964255;15518.068915865084;15654.765178522332;16229.675406491804;16268.837311626732;17334.219960101826;846.4455781463151;859.6561375449745;871.3639043498282;881.7531091294447;899.024558354193;8751.431156974777;8777.868331975025;9096.363254572116;9258.842644669416;9670.274729395323;390224;398040.31354983064;401955;403377;403638;76089;81041;81863.59068204621;84151.15848841434;84346;903.4534106865383;928.122923836981;933.2254228990058;940.036462625814;945.8864936207699;15007.249637518298;15304.540863774104;15500.5349839505;15966;16212;26385.868070659522;29060.27349316284;34045.319093618484;38092.428613570264;38541.42187867128;45801.77099114483;52160.47839521556;52324;52800.47199528109;53020.20469693035;4156.134794641013;4357.259265924864;5824.15549745293;6592.571482853599;6969.30306969299;8355.164483551596;10298.897011030094;11137.443127843737;11307.830382544103;11793.52825886955;9746.171575415885;9853.950730246303;10015.14871235953;10423.94788026055;10672.57309707634;9882.950585247028;10078.848817267894;10101.79796404053;10143.695689129338;10378.792424151627;9780.119789219005;9948.502574871083;10149.23880708935;10221.846672299773;10245.975402459695;7063.364297213276;7162.104736907887;7177.4616903731385;7240.420766338575;7433.293837085468;4675.041616468684;5822.010258256191;7459.552426854406;7811.257930496594;10609.522571484456 08:48:51 INFO - PROCESS | 18365 | |3;mootools.html;1197.6586672798255;1313.7241179352332;1334.833145856768;1370.540868808949;1604.679064187162;5774.3648198698165;6580.736770529179;6895.931040689592;6909.274526174742;6920.999999999994;6774;6820.488463365243;6829.726810927569;6844.38400543951;6853.485988550866;2048.354768247997;2077.054940002303;2088.404804630673;2091.0381224636685;2093.654546999743;1974.2103158736477;2033.2172113736244;2042.3158241988951;2044.1414605865498;2062.278202629083;2014.6977953306975;2030.0255877178963;2046.3860841747492;2050.477128332273;2052.9794702052936;2013.0438041930113;2030.2285131650026;2031.6546187148294;2031.8171364577258;2032.6036422897541;2008.7187793708893;2014.869033512817;2049.1905697249504;2051.2615458434952;2052.415061707406;6675.332466753334;6681.76613818514;6734.124563806674;6740;6741.0899528564;4029.093453972862;4185.079282557833;4191.622753952159;4246.596573325529;4251.957480425187;2614.9084782032537;2797.1328888044773;2830.674472435676;2836.0782745607553;2836.8297902125732;873.0832625742944;877.9253763430116;878.9121087891224;890.9599068041889;901.711452335253;8506.234438900467;8568.700095496628;8573.485590864506;8589.184027517376;8594.312455003585;3383.8138902360606;3528.153243221608;3621.7464777465325;3622.3298689742483;3629.9092522686615;7128.108986376703;7134.1795693495915;7134.500584959003;7138.9286107139305;7142;4143.212789569972;4151.771652559078;4152.792360381969;4155.501339839178;4157.043879907638;3256.0557438342616;3350.5811773528308;3678.9080272992855;3735.402335626287;3763.8494460221286;2991.1624745071413;2994.7304742573274;3083.722464978163;3095.659477457478;3119.5788568542957;928.071928071928;930.9348345615878;937.4422218779815;939.2908354192541;945.8864936207561;765.9502132361379;767.223876725379;770.3759954436924;783.518136346143;790.6481615680967;4639.628829693616;4663.207254766698;4667.239415795869;4790.209615413488;4818.6626936114835;371.85869369639363;379.6203796203796;382.6345839723058;384.75952529668956;388.56480741569624;3288.8026718396973;3307.140143562691;3311.602607687093;3334.249793796425;3367.579052618423;7674.270944260398;8074.515529068157;8075.111737708848;8080.43436959419;8094.028716554051;8054.718084867001;8059.355251579859;8062.072861620989;8063.959680201561;8065.838683226302;6401.8719625608155;6467.741290348427;6472.741090356333;6479.287278399373;6482.837929051717;4598.793054312631;4673.556012178838;4694.2723877799;4705.952940470552;4760.357351757469;7024.121984751906;7150.42796576283;7197.928020719726;7205.351518363373;7215.134183897966;7295.452841037049;7445.478816482794;7459.142198647225;7460.142083660231;7467.6639551221415;5229.294045303835;5951.226340575698;6074.969625151846;6115.663638499926;6145.0782382642965;5954.166416701752;6130.601510901777;6354;6361.045843123384;6451.354864513699;5591.944080559142;5622.578306626938;5648.181013752901;5691.203231547669;5812.331581868139;5509.779608815603;5626.971865140648;5636.689982050862;5664.490195882226;5736.5410767139365;5315.5216030555885;5364.14173732201;5386.13821788512;5404.648697834628;5433.7554810034435;3963.742356746802;4056.350983842571;4107.8973025674595;4200.096979149497;4247.554006829362;4213.220554197484;4214.346776249687;4229.640480559187;4265.552117027792;4290.592393722592 08:48:51 INFO - PROCESS | 18365 | |4;prototype.html;280.07993740562245;280.660400914893;284.79921655233056;289.9347646779475;290.1382892808361;4757.54803293687;4761.952380476194;4783.210770222909;4796.376471058767;4833.589144922681;4692.882677933053;4695.3191787190835;4696.953030469695;4707.835225767099;4768;3486.518229752358;3576.5171701820314;3585.8924232273075;3610.1696609779765;3629.383004889162;3625.2779929533394;3662.487251784752;3666.853325866962;3719.3863012602887;3782.8486860525545;3686.3548878946212;3687;3698.7965661888584;3711.1464363196346;3729.0863738384132;3589.353916295066;3698.7225958052986;3715.5880765309143;3722.8324725387424;3731.4402839574145;3252.674732526752;3261.4944683574086;3263.559419478364;3264.2002709336125;3265.2816380396275;4540.8864778380475;4555.157295900269;4558.407407037064;4575.885602859922;4582.266837306049;4213.810378532973;4216.641585465209;4229.873103806891;4234.640055595278;4241.091508821012;4788.257820037899;4872.976674898276;4905.062500312357;4906.215005599122;4966.90066198674;3530.6292839251764;3533.1873669056004;3538.2746536960117;3541.6812486876306;3542.813157592184;5139.8458046258675;5146.125158722989;5155.66488178271;5171.810483588795;5181.326427564392;3506.929861402809;3606.1886075633297;3636.3636363636256;3645.6172101929446;3658.835352409148;3669.6330866591434;3671.1189314564313;3680.392735198716;3680.595134535199;3680.742348035612;4152.958470415318;4204.600562946515;4212.431321771583;4216.704830661824;4236.703430759879;4907.852864329636;4945.554900059012;4951.975240123848;5018.42288136869;5022.773975171126;1141.57191053355;1202.5550546297925;1202.8857258560422;1205.9879401206051;1240.9193402428814;807.7399257158734;828.0353388302681;837.5267973594899;837.7109897085496;840.4243093480985;4203.747775133502;4221.535631080578;4314.762688052125;4315.956840431619;4331.350297455407;4972.57733092684;4976.278439626234;4981.227909674007;5099.209622508443;5134.743262836918;4410.213863385334;4413.933790993137;4421.204183246982;4431.224535706238;4441.133978874089;2561.9743802561734;2592.468543948486;2609.869506524666;2620.65931428913;2647.4705058987893;4650.906981860344;4653.53464653532;4664.277037059262;4668.883277918079;4674.813007479663;4990.857588201784;4997.300917688003;5004.274380214849;5017.792171444553;5067.949320506821;4535.909281814345;4587.587117159472;4616.976915115471;4639.280911458729;4718.245080787126;4846.321514987834;4868.269759536098;4886.438059623047;4898.848770538992;4899.216125419915;4879.512048795092;4886.169351210232;4898.853034408973;4899.951000490092;4924.513120873936;4487.259602165606;4669.649776266726;4675.929861052155;4716.575508204278;4721.669483136147;3456.879009234665;3466.272082862627;3469.976356974648;3472.2534655049285;3486;5004.874878128076;5010;5024.382444639853;5035.571976382049;5059;4631.282151266559;4725.432948046119;4748.620110391092;4847.61219102478;4878.487758785276;4701.858944231679;4751.738654374042;4786.8085276588545;4798.49615790337;4799.28010798383;4640.443146822403;4670.696404733681;4673.602743766818;4690.2026655469335;4701.9529804701515;4693.2021556336185;4815.253635686474;4844.577711144428;4872.6832755870755;5003.199488081889;4847.891167342217;4877.804937790264;4883.438404583376;4897.8285759998225;4921.532454416824 08:48:51 INFO - PROCESS | 18365 | |5;yui.html;628.0829988217203;634.2610858350023;644.9522765220424;648.795629376746;737.7934178430038;11824.586139485109;12713.300768457731;12722.809157862617;12739.108262421634;12768.233905965648;12178.573749918754;12313.199642023263;12324.32216228107;12372;12379.56671516497;618.3599974026884;631.7725618777237;743;754.2419868032633;768.7040489411561;754.6151462754008;770.4953255617579;771.6604693934679;772.0580891312618;772.5133166105373;808.030363563723;810.1695761844128;810.7486679129489;811.3144392987916;812.2162113560413;638.5179189711738;656.4092316914767;771.7453240430644;806.8830019647177;808.0707186735272;588.5968111843401;592.6177615438062;594.2690490696424;594.432317137133;594.8245267646055;12424.707452524997;12473.251604903735;12494.81257781134;12523.624291271186;12606.873931260661;11695.473703683354;11801.704957376049;11907.821382679267;11936.104792140624;12100;10652.41411722355;10813.621523246728;10865.185111116618;10982.780344393148;11180.161487888441;3073.661897191307;3204.1348835814433;3237.805731656108;3264.461363874958;4024.7987600619854;45813.77093114513;48569.75715121437;49657.255141172915;49996.75001624969;50124.49875501235;9317.347785655074;9432.292578056673;9440.386374885611;9469.857952130724;9574.856377154349;15691.372345106296;15748.133852638215;15883.047017179006;15883.126428046337;16092.75860862088;6092.817215483543;6141.969290153521;6221.906671399933;6231.844203894848;6276.435120839146;3382.374260761767;3488.843002064913;3493.371193185251;3613.6928361089076;3619.8371073301237;2705.878235479408;2789.0377819652185;2798.9860050699617;2800;2816.2959260184953;478.3852749217239;482.9903401932012;484.4477295882697;488.3748801534042;497.64169797745564;549.6207616744433;563.0568797264566;567.4977644784313;575.4245754245754;628.6636649392644;7413.096603660644;7431.665575049135;7482.812929676802;7511.549307041595;7512.197072702512;584.3922320786335;601.2064075420403;601.3445344574435;612.21024877907;614.8063360041573;3710.777353358807;3722.1253005543676;3732.552093748713;3774.037620406779;3900.8244628991765;54226.72886635621;55975.72012139994;57038.714806425705;72705;75699;34924.825375872955;46141.76929115333;54193;56254;58676;42409;50808.83823235368;53002.734986324824;54402;54490.18264726032;7954.164812694584;8097.39269554774;8173.100958894635;8327.583620819056;8574.356923230907;36664;37656.24687506289;37705.43441848319;38383.23233535369;38903.80548097241;41995.160096797284;42032;44127.558724412345;48201.75899120482;49573.25640115474;10810.621628242974;10860.294080885033;10900.400477973788;11018;11223.38271395081;11693.415329233674;11961;12121.69695757613;12130.33283169434;12909.677258068623;10309.175265978554;10428.739281518023;10983;11005.61480348184;11314.15143864197;11079.917275279457;11726.355050472306;12002.459889305177;12184.878151218729;12324.815127772912;11249.71875703114;11375;11758.58844940423;11758.70603234926;11762.3530705812;9303.116203960613;9407.52962351866;9522.190613797904;9589.184919281939;9818.711524838718;8487.745367638981;8563.871541926752;9349;9388.295877809329;9506.382085164441 08:48:51 INFO - PROCESS | 18365 | __end_tp_report 08:48:51 INFO - PROCESS | 18365 | __start_cc_report 08:48:51 INFO - PROCESS | 18365 | _x_x_mozilla_cycle_collect,1451 08:48:51 INFO - PROCESS | 18365 | __end_cc_report 08:48:51 INFO - PROCESS | 18365 | __startTimestamp1479401331803__endTimestamp 08:48:51 INFO - PROCESS | 18365 | 08:48:51 INFO - PROCESS | 18365 | ------- Summary: start ------- 08:48:51 INFO - PROCESS | 18365 | Number of tests: 6 08:48:51 INFO - PROCESS | 18365 | 08:48:51 INFO - PROCESS | 18365 | [#0] dojo.html Cycles:165 Average:4613.94 Median:4715.22 stddev:477.14 (10.1%) stddev-sans-first:451.13 08:48:51 INFO - PROCESS | 18365 | Values: 2579.6 2580.8 2615.2 2618.1 2631.1 4560.5 4593.4 4689.7 4724.7 4734.5 4657.7 4674.1 4710.6 4742.7 4788.3 4244.6 4267.8 4309.9 4322.3 4336.5 4300.0 4365.6 4401.4 4415.8 4447.4 4130.1 4362.0 4365.4 4376.2 4452.2 4164.2 4206.1 4210.7 4211.3 4220.7 4267.1 4271.0 4288.2 4358.9 4365.5 4615.6 4639.1 4646.1 4660.6 4667.5 4957.9 4982.1 4985.9 4994.2 5036.3 4839.7 4893.0 4907.0 4965.5 4965.6 4668.2 4669.9 4673.8 4674.9 4678.4 5036.3 5054.9 5082.6 5087.9 5092.8 4805.7 4838.9 4883.9 4899.0 4900.2 5003.4 5004.4 5011.9 5012.4 5051.1 4980.0 4986.2 4991.3 5024.2 5025.4 4770.4 4779.9 4803.8 4862.6 4961.3 4221.6 4222.5 4223.8 4224.9 4226.5 3721.2 3731.5 3735.0 3735.6 3737.3 4315.2 4318.9 4355.2 4355.3 4448.3 4995.0 5081.8 5098.0 5100.5 5144.2 4186.9 4369.9 4382.3 4385.7 4430.4 4432.6 4529.8 4564.5 4584.8 4618.1 4762.8 4764.5 4770.4 4777.3 4837.5 4755.2 5076.8 5095.0 5095.1 5141.0 4581.8 4627.1 4667.5 4684.4 4728.8 4897.4 4902.9 4908.7 4910.1 4942.5 4974.8 4990.6 4990.8 4994.4 5029.3 4692.6 4707.6 4715.2 4797.6 4799.0 4759.8 4799.1 4824.8 4825.1 4825.4 4643.2 4665.6 4674.4 4678.0 4683.3 4955.9 4958.6 4958.9 4971.7 4997.5 4856.0 4856.7 4862.0 4865.1 4909.7 08:48:51 INFO - PROCESS | 18365 | 08:48:51 INFO - PROCESS | 18365 | [#1] ext.html Cycles:180 Average:42749.96 Median:34015.49 stddev:77628.11 (228.2%) stddev-sans-first:77781.17 08:48:51 INFO - PROCESS | 18365 | Values: 536.9 547.1 560.5 567.4 568.8 27432.2 36725.6 38012.0 38102.4 41763.4 39247.6 39661.2 39665.0 39667.0 39693.8 7422.3 7610.7 7610.7 7612.8 7618.8 7453.1 7544.0 7548.1 7550.4 7551.1 7520.5 7566.6 7582.8 7583.7 7585.1 7512.6 7553.0 7557.8 7564.9 7568.6 7276.3 7325.4 7331.2 7334.7 7335.2 36974.8 37168.6 37294.8 37348.0 37359.6 2851.5 2955.7 2989.9 3144.4 3172.4 27305.7 27909.4 28272.4 28367.4 28951.9 3442.3 3464.5 3511.5 3534.4 3583.4 249475.0 249639.0 249985.0 250477.0 250666.0 29706.9 48781.8 48955.5 49050.5 49084.0 31038.5 32759.5 33036.0 33063.8 33071.2 11116.1 11135.1 11140.9 11237.0 11252.7 10411.1 10492.8 10494.5 10496.6 10504.5 7537.1 7584.6 7660.6 7665.9 7670.8 2958.2 3075.2 3090.6 3099.6 3102.0 879.4 961.7 962.1 962.1 968.5 26412.1 27646.0 30955.7 31164.8 31818.2 928.4 945.3 959.0 1002.2 1010.3 13304.5 14041.6 14172.6 14371.9 14393.1 357514.0 366173.0 367132.0 367561.0 367866.0 45486.8 46033.0 46055.3 46097.1 46154.8 1167.7 1183.3 1208.2 1210.7 1215.5 25281.9 25835.6 26103.2 26524.2 26681.5 39965.2 40698.4 40699.8 40814.6 40824.2 252341.0 252987.0 253697.0 254631.0 255522.0 22627.0 22642.0 22786.9 22949.9 22984.0 38745.0 38810.8 38849.0 39266.6 39287.2 31422.0 32670.8 33863.5 34041.3 34105.5 33549.5 33727.2 33927.3 33989.7 34082.5 28545.4 28991.1 31002.4 32986.0 33261.2 28398.3 30856.8 31009.6 31211.5 31387.7 23764.6 23775.0 23892.0 24182.4 24287.8 08:48:51 INFO - PROCESS | 18365 | 08:48:51 INFO - PROCESS | 18365 | [#2] jquery.html Cycles:180 Average:23853.15 Median:4954.20 stddev:66135.35 (1334.9%) stddev-sans-first:66297.31 08:48:51 INFO - PROCESS | 18365 | Values: 338.8 344.3 351.4 356.2 378.0 12508.7 15900.8 15987.4 16862.9 17011.6 16696.0 16810.8 17011.5 17125.7 17132.5 4808.5 4829.3 4959.5 4980.5 5010.7 4912.1 4990.3 5001.2 5010.9 5252.1 5335.7 5342.2 5344.0 5348.9 5359.0 5181.3 5327.7 5338.5 5516.9 5556.6 4920.3 4937.3 4946.9 4949.6 4958.8 11103.2 11132.7 11145.6 11184.0 11217.1 1750.7 1837.3 1857.7 1918.7 2026.0 8020.5 8276.9 8364.6 8646.8 8838.9 1514.6 1603.8 1608.3 1634.1 1711.6 67776.7 68203.0 69250.0 69600.7 69873.7 7588.5 8026.1 8075.4 8091.5 8575.4 11201.5 11277.0 11288.5 11288.7 11309.7 6572.8 6719.4 6798.5 7001.0 7008.7 6621.7 6641.1 6855.4 6873.9 6877.2 3448.7 3476.3 3503.5 3545.8 3570.8 1724.0 1724.7 1736.2 1745.8 1764.8 790.5 810.5 832.1 859.1 903.3 15518.1 15654.8 16229.7 16268.8 17334.2 846.4 859.7 871.4 881.8 899.0 8751.4 8777.9 9096.4 9258.8 9670.3 390224.0 398040.3 401955.0 403377.0 403638.0 76089.0 81041.0 81863.6 84151.2 84346.0 903.5 928.1 933.2 940.0 945.9 15007.2 15304.5 15500.5 15966.0 16212.0 26385.9 29060.3 34045.3 38092.4 38541.4 45801.8 52160.5 52324.0 52800.5 53020.2 4156.1 4357.3 5824.2 6592.6 6969.3 8355.2 10298.9 11137.4 11307.8 11793.5 9746.2 9854.0 10015.1 10423.9 10672.6 9883.0 10078.8 10101.8 10143.7 10378.8 9780.1 9948.5 10149.2 10221.8 10246.0 7063.4 7162.1 7177.5 7240.4 7433.3 4675.0 5822.0 7459.6 7811.3 10609.5 08:48:51 INFO - PROCESS | 18365 | 08:48:51 INFO - PROCESS | 18365 | [#3] mootools.html Cycles:180 Average:4409.16 Median:5272.41 stddev:2344.02 (44.5%) stddev-sans-first:2338.16 08:48:51 INFO - PROCESS | 18365 | Values: 1197.7 1313.7 1334.8 1370.5 1604.7 5774.4 6580.7 6895.9 6909.3 6921.0 6774.0 6820.5 6829.7 6844.4 6853.5 2048.4 2077.1 2088.4 2091.0 2093.7 1974.2 2033.2 2042.3 2044.1 2062.3 2014.7 2030.0 2046.4 2050.5 2053.0 2013.0 2030.2 2031.7 2031.8 2032.6 2008.7 2014.9 2049.2 2051.3 2052.4 6675.3 6681.8 6734.1 6740.0 6741.1 4029.1 4185.1 4191.6 4246.6 4252.0 2614.9 2797.1 2830.7 2836.1 2836.8 873.1 877.9 878.9 891.0 901.7 8506.2 8568.7 8573.5 8589.2 8594.3 3383.8 3528.2 3621.7 3622.3 3629.9 7128.1 7134.2 7134.5 7138.9 7142.0 4143.2 4151.8 4152.8 4155.5 4157.0 3256.1 3350.6 3678.9 3735.4 3763.8 2991.2 2994.7 3083.7 3095.7 3119.6 928.1 930.9 937.4 939.3 945.9 766.0 767.2 770.4 783.5 790.6 4639.6 4663.2 4667.2 4790.2 4818.7 371.9 379.6 382.6 384.8 388.6 3288.8 3307.1 3311.6 3334.2 3367.6 7674.3 8074.5 8075.1 8080.4 8094.0 8054.7 8059.4 8062.1 8064.0 8065.8 6401.9 6467.7 6472.7 6479.3 6482.8 4598.8 4673.6 4694.3 4706.0 4760.4 7024.1 7150.4 7197.9 7205.4 7215.1 7295.5 7445.5 7459.1 7460.1 7467.7 5229.3 5951.2 6075.0 6115.7 6145.1 5954.2 6130.6 6354.0 6361.0 6451.4 5591.9 5622.6 5648.2 5691.2 5812.3 5509.8 5627.0 5636.7 5664.5 5736.5 5315.5 5364.1 5386.1 5404.6 5433.8 3963.7 4056.4 4107.9 4200.1 4247.6 4213.2 4214.3 4229.6 4265.6 4290.6 08:48:51 INFO - PROCESS | 18365 | 08:48:51 INFO - PROCESS | 18365 | [#4] prototype.html Cycles:180 Average:4057.79 Median:4639.86 stddev:1173.63 (25.3%) stddev-sans-first:1142.16 08:48:51 INFO - PROCESS | 18365 | Values: 280.1 280.7 284.8 289.9 290.1 4757.5 4762.0 4783.2 4796.4 4833.6 4692.9 4695.3 4697.0 4707.8 4768.0 3486.5 3576.5 3585.9 3610.2 3629.4 3625.3 3662.5 3666.9 3719.4 3782.8 3686.4 3687.0 3698.8 3711.1 3729.1 3589.4 3698.7 3715.6 3722.8 3731.4 3252.7 3261.5 3263.6 3264.2 3265.3 4540.9 4555.2 4558.4 4575.9 4582.3 4213.8 4216.6 4229.9 4234.6 4241.1 4788.3 4873.0 4905.1 4906.2 4966.9 3530.6 3533.2 3538.3 3541.7 3542.8 5139.8 5146.1 5155.7 5171.8 5181.3 3506.9 3606.2 3636.4 3645.6 3658.8 3669.6 3671.1 3680.4 3680.6 3680.7 4153.0 4204.6 4212.4 4216.7 4236.7 4907.9 4945.6 4952.0 5018.4 5022.8 1141.6 1202.6 1202.9 1206.0 1240.9 807.7 828.0 837.5 837.7 840.4 4203.7 4221.5 4314.8 4316.0 4331.4 4972.6 4976.3 4981.2 5099.2 5134.7 4410.2 4413.9 4421.2 4431.2 4441.1 2562.0 2592.5 2609.9 2620.7 2647.5 4650.9 4653.5 4664.3 4668.9 4674.8 4990.9 4997.3 5004.3 5017.8 5067.9 4535.9 4587.6 4617.0 4639.3 4718.2 4846.3 4868.3 4886.4 4898.8 4899.2 4879.5 4886.2 4898.9 4900.0 4924.5 4487.3 4669.6 4675.9 4716.6 4721.7 3456.9 3466.3 3470.0 3472.3 3486.0 5004.9 5010.0 5024.4 5035.6 5059.0 4631.3 4725.4 4748.6 4847.6 4878.5 4701.9 4751.7 4786.8 4798.5 4799.3 4640.4 4670.7 4673.6 4690.2 4702.0 4693.2 4815.3 4844.6 4872.7 5003.2 4847.9 4877.8 4883.4 4897.8 4921.5 08:48:51 INFO - PROCESS | 18365 | 08:48:51 INFO - PROCESS | 18365 | [#5] yui.html Cycles:180 Average:13854.36 Median:24527.10 stddev:16999.66 (69.3%) stddev-sans-first:17018.33 08:48:51 INFO - PROCESS | 18365 | Values: 628.1 634.3 645.0 648.8 737.8 11824.6 12713.3 12722.8 12739.1 12768.2 12178.6 12313.2 12324.3 12372.0 12379.6 618.4 631.8 743.0 754.2 768.7 754.6 770.5 771.7 772.1 772.5 808.0 810.2 810.7 811.3 812.2 638.5 656.4 771.7 806.9 808.1 588.6 592.6 594.3 594.4 594.8 12424.7 12473.3 12494.8 12523.6 12606.9 11695.5 11801.7 11907.8 11936.1 12100.0 10652.4 10813.6 10865.2 10982.8 11180.2 3073.7 3204.1 3237.8 3264.5 4024.8 45813.8 48569.8 49657.3 49996.8 50124.5 9317.3 9432.3 9440.4 9469.9 9574.9 15691.4 15748.1 15883.0 15883.1 16092.8 6092.8 6142.0 6221.9 6231.8 6276.4 3382.4 3488.8 3493.4 3613.7 3619.8 2705.9 2789.0 2799.0 2800.0 2816.3 478.4 483.0 484.4 488.4 497.6 549.6 563.1 567.5 575.4 628.7 7413.1 7431.7 7482.8 7511.5 7512.2 584.4 601.2 601.3 612.2 614.8 3710.8 3722.1 3732.6 3774.0 3900.8 54226.7 55975.7 57038.7 72705.0 75699.0 34924.8 46141.8 54193.0 56254.0 58676.0 42409.0 50808.8 53002.7 54402.0 54490.2 7954.2 8097.4 8173.1 8327.6 8574.4 36664.0 37656.2 37705.4 38383.2 38903.8 41995.2 42032.0 44127.6 48201.8 49573.3 10810.6 10860.3 10900.4 11018.0 11223.4 11693.4 11961.0 12121.7 12130.3 12909.7 10309.2 10428.7 10983.0 11005.6 11314.2 11079.9 11726.4 12002.5 12184.9 12324.8 11249.7 11375.0 11758.6 11758.7 11762.4 9303.1 9407.5 9522.2 9589.2 9818.7 8487.7 8563.9 9349.0 9388.3 9506.4 08:48:51 INFO - PROCESS | 18365 | -------- Summary: end -------- 08:48:51 INFO - PROCESS | 18365 | 08:48:52 INFO - TEST-INFO | 18365: exit 0 08:48:52 INFO - TEST-OK | dromaeo_css | took 1124260ms 08:48:52 INFO - TEST-START | kraken 08:48:52 INFO - operating with platform_type : linux_ 08:48:52 INFO - Initialising browser for kraken test... 08:48:52 INFO - TEST-INFO | started process 18670 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNdE9kO/profile http://localhost:59485/getInfo.html) 08:48:57 INFO - PROCESS | 18670 | __metrics Screen width/height:1600/1200 08:48:57 INFO - PROCESS | 18670 | colorDepth:24 08:48:57 INFO - PROCESS | 18670 | Browser inner width/height: 1024/697 08:48:57 INFO - PROCESS | 18670 | __metrics 08:48:57 INFO - PROCESS | 18670 | 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" 08:48:57 INFO - PROCESS | 18670 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:48:58 INFO - PROCESS | 18670 | console.error: PushService: 08:48:58 INFO - PROCESS | 18670 | stateChangeProcessEnqueue: Error transitioning state 08:48:58 INFO - PROCESS | 18670 | UnknownError 08:48:59 INFO - TEST-INFO | 18670: exit 0 08:48:59 INFO - Browser initialized. 08:48:59 INFO - Running cycle 1/1 for kraken test... 08:48:59 INFO - TEST-INFO | started process 18753 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNdE9kO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:49:11 INFO - PROCESS | 18753 | RSS: Main: 174804992 08:49:11 INFO - PROCESS | 18753 | 08:49:14 INFO - PROCESS | 18753 | Cycle 1(1): loaded http://localhost:59485/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59485/tests/kraken/driver.html?testName=audio-beat-detection) 08:49:15 INFO - PROCESS | 18753 | RSS: Main: 240181248 08:49:15 INFO - PROCESS | 18753 | 08:49:17 INFO - PROCESS | 18753 | Cycle 1(1): loaded http://localhost:59485/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59485/tests/kraken/driver.html?testName=audio-dft) 08:49:18 INFO - PROCESS | 18753 | RSS: Main: 218259456 08:49:18 INFO - PROCESS | 18753 | 08:49:21 INFO - PROCESS | 18753 | Cycle 1(1): loaded http://localhost:59485/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59485/tests/kraken/driver.html?testName=audio-fft) 08:49:22 INFO - PROCESS | 18753 | RSS: Main: 300457984 08:49:22 INFO - PROCESS | 18753 | 08:49:24 INFO - PROCESS | 18753 | Cycle 1(1): loaded http://localhost:59485/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59485/tests/kraken/driver.html?testName=audio-oscillator) 08:49:25 INFO - PROCESS | 18753 | RSS: Main: 208867328 08:49:25 INFO - PROCESS | 18753 | 08:49:27 INFO - PROCESS | 18753 | Cycle 1(1): loaded http://localhost:59485/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59485/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:49:27 INFO - PROCESS | 18753 | RSS: Main: 214212608 08:49:27 INFO - PROCESS | 18753 | 08:49:31 INFO - PROCESS | 18753 | Cycle 1(1): loaded http://localhost:59485/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59485/tests/kraken/driver.html?testName=imaging-darkroom) 08:49:31 INFO - PROCESS | 18753 | RSS: Main: 253841408 08:49:31 INFO - PROCESS | 18753 | 08:49:35 INFO - PROCESS | 18753 | Cycle 1(1): loaded http://localhost:59485/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59485/tests/kraken/driver.html?testName=imaging-desaturate) 08:49:35 INFO - PROCESS | 18753 | RSS: Main: 253681664 08:49:35 INFO - PROCESS | 18753 | 08:49:38 INFO - PROCESS | 18753 | Cycle 1(1): loaded http://localhost:59485/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59485/tests/kraken/driver.html?testName=json-parse-financial) 08:49:39 INFO - PROCESS | 18753 | RSS: Main: 253100032 08:49:39 INFO - PROCESS | 18753 | 08:49:41 INFO - PROCESS | 18753 | Cycle 1(1): loaded http://localhost:59485/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59485/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:49:41 INFO - PROCESS | 18753 | RSS: Main: 214540288 08:49:41 INFO - PROCESS | 18753 | 08:49:43 INFO - PROCESS | 18753 | Cycle 1(1): loaded http://localhost:59485/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59485/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:49:44 INFO - PROCESS | 18753 | RSS: Main: 222134272 08:49:44 INFO - PROCESS | 18753 | 08:49:46 INFO - PROCESS | 18753 | Cycle 1(1): loaded http://localhost:59485/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59485/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:49:47 INFO - PROCESS | 18753 | RSS: Main: 230834176 08:49:47 INFO - PROCESS | 18753 | 08:49:49 INFO - PROCESS | 18753 | Cycle 1(1): loaded http://localhost:59485/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59485/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:49:50 INFO - PROCESS | 18753 | RSS: Main: 239521792 08:49:50 INFO - PROCESS | 18753 | 08:49:53 INFO - PROCESS | 18753 | Cycle 1(1): loaded http://localhost:59485/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59485/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:49:54 INFO - PROCESS | 18753 | RSS: Main: 236040192 08:49:54 INFO - PROCESS | 18753 | 08:49:56 INFO - PROCESS | 18753 | Cycle 1(1): loaded http://localhost:59485/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59485/tests/kraken/driver.html?testName=ai-astar) 08:49:56 INFO - PROCESS | 18753 | RSS: Main: 287027200 08:49:56 INFO - PROCESS | 18753 | 08:49:56 INFO - PROCESS | 18753 | __start_tp_report 08:49:56 INFO - PROCESS | 18753 | _x_x_mozilla_page_load 08:49:56 INFO - PROCESS | 18753 | _x_x_mozilla_page_load_details 08:49:56 INFO - PROCESS | 18753 | |i|pagename|runs| 08:49:56 INFO - PROCESS | 18753 | |0;ai-astar;107;118;116;117;117;116;115;118;117;117 08:49:56 INFO - PROCESS | 18753 | |1;audio-beat-detection;152;128;130;178;129;133;127;130;178;130 08:49:56 INFO - PROCESS | 18753 | |2;audio-dft;162;162;163;162;162;160;162;161;162;161 08:49:56 INFO - PROCESS | 18753 | |3;audio-fft;76;76;77;113;77;77;77;76;96;76 08:49:56 INFO - PROCESS | 18753 | |4;audio-oscillator;84;83;126;84;84;83;104;84;83;112 08:49:56 INFO - PROCESS | 18753 | |5;imaging-gaussian-blur;116;117;117;117;117;117;117;116;117;117 08:49:56 INFO - PROCESS | 18753 | |6;imaging-darkroom;147;146;146;147;147;146;147;147;146;146 08:49:56 INFO - PROCESS | 18753 | |7;imaging-desaturate;95;95;96;96;96;97;97;96;96;95 08:49:56 INFO - PROCESS | 18753 | |8;json-parse-financial;64;62;64;63;64;64;64;64;64;64 08:49:56 INFO - PROCESS | 18753 | |9;json-stringify-tinderbox;66;67;66;67;67;67;67;66;66;67 08:49:56 INFO - PROCESS | 18753 | |10;stanford-crypto-aes;93;84;94;84;84;85;85;84;84;85 08:49:56 INFO - PROCESS | 18753 | |11;stanford-crypto-ccm;146;143;144;143;144;139;144;149;183;141 08:49:56 INFO - PROCESS | 18753 | |12;stanford-crypto-pbkdf2;188;184;180;182;176;175;180;180;184;175 08:49:56 INFO - PROCESS | 18753 | |13;stanford-crypto-sha256-iterative;69;70;70;70;70;68;68;72;74;69 08:49:56 INFO - PROCESS | 18753 | __end_tp_report 08:49:56 INFO - PROCESS | 18753 | __start_cc_report 08:49:56 INFO - PROCESS | 18753 | _x_x_mozilla_cycle_collect,1973 08:49:56 INFO - PROCESS | 18753 | __end_cc_report 08:49:56 INFO - PROCESS | 18753 | __startTimestamp1479401396453__endTimestamp 08:49:56 INFO - PROCESS | 18753 | 08:49:56 INFO - PROCESS | 18753 | ------- Summary: start ------- 08:49:56 INFO - PROCESS | 18753 | Number of tests: 14 08:49:56 INFO - PROCESS | 18753 | 08:49:56 INFO - PROCESS | 18753 | [#0] ai-astar Cycles:10 Average:115.80 Median:117.00 stddev:3.22 (2.8%) stddev-sans-first:0.97 08:49:56 INFO - PROCESS | 18753 | Values: 107.0 118.0 116.0 117.0 117.0 116.0 115.0 118.0 117.0 117.0 08:49:56 INFO - PROCESS | 18753 | 08:49:56 INFO - PROCESS | 18753 | [#1] audio-beat-detection Cycles:10 Average:141.50 Median:131.50 stddev:20.53 (15.6%) stddev-sans-first:21.42 08:49:56 INFO - PROCESS | 18753 | Values: 152.0 128.0 130.0 178.0 129.0 133.0 127.0 130.0 178.0 130.0 08:49:56 INFO - PROCESS | 18753 | 08:49:56 INFO - PROCESS | 18753 | [#2] audio-dft Cycles:10 Average:161.70 Median:162.00 stddev:0.82 (0.5%) stddev-sans-first:0.87 08:49:56 INFO - PROCESS | 18753 | Values: 162.0 162.0 163.0 162.0 162.0 160.0 162.0 161.0 162.0 161.0 08:49:56 INFO - PROCESS | 18753 | 08:49:56 INFO - PROCESS | 18753 | [#3] audio-fft Cycles:10 Average:82.10 Median:77.00 stddev:12.48 (16.2%) stddev-sans-first:13.04 08:49:56 INFO - PROCESS | 18753 | Values: 76.0 76.0 77.0 113.0 77.0 77.0 77.0 76.0 96.0 76.0 08:49:56 INFO - PROCESS | 18753 | 08:49:56 INFO - PROCESS | 18753 | [#4] audio-oscillator Cycles:10 Average:92.70 Median:83.50 stddev:15.61 (18.7%) stddev-sans-first:16.24 08:49:56 INFO - PROCESS | 18753 | Values: 84.0 83.0 126.0 84.0 84.0 83.0 104.0 84.0 83.0 112.0 08:49:56 INFO - PROCESS | 18753 | 08:49:56 INFO - PROCESS | 18753 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 08:49:56 INFO - PROCESS | 18753 | Values: 116.0 117.0 117.0 117.0 117.0 117.0 117.0 116.0 117.0 117.0 08:49:56 INFO - PROCESS | 18753 | 08:49:56 INFO - PROCESS | 18753 | [#6] imaging-darkroom Cycles:10 Average:146.50 Median:147.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 08:49:56 INFO - PROCESS | 18753 | Values: 147.0 146.0 146.0 147.0 147.0 146.0 147.0 147.0 146.0 146.0 08:49:56 INFO - PROCESS | 18753 | 08:49:56 INFO - PROCESS | 18753 | [#7] imaging-desaturate Cycles:10 Average:95.90 Median:96.00 stddev:0.74 (0.8%) stddev-sans-first:0.71 08:49:56 INFO - PROCESS | 18753 | Values: 95.0 95.0 96.0 96.0 96.0 97.0 97.0 96.0 96.0 95.0 08:49:56 INFO - PROCESS | 18753 | 08:49:56 INFO - PROCESS | 18753 | [#8] json-parse-financial Cycles:10 Average:63.70 Median:64.00 stddev:0.67 (1.1%) stddev-sans-first:0.71 08:49:56 INFO - PROCESS | 18753 | Values: 64.0 62.0 64.0 63.0 64.0 64.0 64.0 64.0 64.0 64.0 08:49:56 INFO - PROCESS | 18753 | 08:49:56 INFO - PROCESS | 18753 | [#9] json-stringify-tinderbox Cycles:10 Average:66.60 Median:67.00 stddev:0.52 (0.8%) stddev-sans-first:0.50 08:49:56 INFO - PROCESS | 18753 | Values: 66.0 67.0 66.0 67.0 67.0 67.0 67.0 66.0 66.0 67.0 08:49:56 INFO - PROCESS | 18753 | 08:49:56 INFO - PROCESS | 18753 | [#10] stanford-crypto-aes Cycles:10 Average:86.20 Median:85.00 stddev:3.88 (4.6%) stddev-sans-first:3.24 08:49:56 INFO - PROCESS | 18753 | Values: 93.0 84.0 94.0 84.0 84.0 85.0 85.0 84.0 84.0 85.0 08:49:56 INFO - PROCESS | 18753 | 08:49:56 INFO - PROCESS | 18753 | [#11] stanford-crypto-ccm Cycles:10 Average:147.60 Median:144.00 stddev:12.72 (8.8%) stddev-sans-first:13.48 08:49:56 INFO - PROCESS | 18753 | Values: 146.0 143.0 144.0 143.0 144.0 139.0 144.0 149.0 183.0 141.0 08:49:56 INFO - PROCESS | 18753 | 08:49:56 INFO - PROCESS | 18753 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.40 Median:181.00 stddev:4.27 (2.4%) stddev-sans-first:3.54 08:49:56 INFO - PROCESS | 18753 | Values: 188.0 184.0 180.0 182.0 176.0 175.0 180.0 180.0 184.0 175.0 08:49:56 INFO - PROCESS | 18753 | 08:49:56 INFO - PROCESS | 18753 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.00 Median:70.00 stddev:1.83 (2.6%) stddev-sans-first:1.90 08:49:56 INFO - PROCESS | 18753 | Values: 69.0 70.0 70.0 70.0 70.0 68.0 68.0 72.0 74.0 69.0 08:49:56 INFO - PROCESS | 18753 | -------- Summary: end -------- 08:49:56 INFO - PROCESS | 18753 | 08:49:57 INFO - TEST-INFO | 18753: exit 0 08:49:57 INFO - TEST-OK | kraken | took 64967ms 08:49:57 INFO - SUITE-END | took 1189s 08:49:58 INFO - Completed test suite (00:19:49) 08:49:58 INFO - javascript benchmark 08:49:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2579.600161974892, 2580.793536517079, 2615.2285075902605, 2618.1491015419983, 2631.052820984445, 4560.47554531229, 4593.379893714347, 4689.718616882985, 4724.692894961825, 4734.502877197892, 4657.6972496787685, 4674.111918735446, 4710.552497512731, 4742.69172503787, 4788.257820037891, 4244.575542445747, 4267.829286828523, 4309.935350969738, 4322.28682267427, 4336.544662810406, 4299.957000429987, 4365.563443655625, 4401.449818772653, 4415.80128894199, 4447.35513350564, 4130.112025914443, 4361.956380436219, 4365.388845561624, 4376.234159022174, 4452.1986042512335, 4164.1879833432495, 4206.095689426757, 4210.705250632457, 4211.3261878099665, 4220.704550681454, 4267.125239325931, 4270.978645106755, 4288.249556327662, 4358.8910277243, 4365.49796773372, 4615.584597386218, 4639.118567472169, 4646.070785842845, 4660.580547750685, 4667.509911459282, 4957.925631115536, 4982.053409852117, 4985.850424487271, 4994.150994330972, 5036.294918711384, 4839.7338146401935, 4892.951070489321, 4906.950930490685, 4965.503449655005, 4965.553100220997, 4668.2064049111395, 4669.906601867943, 4673.766311684402, 4674.906501869944, 4678.415198100238, 5036.269740887551, 5054.873628159252, 5082.593392528589, 5087.923681144785, 5092.8472145835685, 4805.687630304089, 4838.927416088762, 4883.902321953541, 4898.951010489849, 4900.166971614834, 5003.449620541737, 5004.3994720633755, 5011.874703132378, 5012.373453318335, 5051.090803655304, 4979.950200498021, 4986.227134794112, 4991.276264941564, 5024.221245706848, 5025.447200807908, 4770.356001939694, 4779.856604301876, 4803.78382972767, 4862.562369386701, 4961.330220420269, 4221.598948099926, 4222.535521092677, 4223.788810559459, 4224.8732538023905, 4226.450561426995, 3721.1999420124803, 3731.514903062639, 3735.0475628714503, 3735.5704094029534, 3737.252549490113, 4315.180115777992, 4318.935215971763, 4355.216061108968, 4355.324924636687, 4448.266036104072, 4995.025969935828, 5081.796728130834, 5097.9745101275, 5100.515451032146, 5144.151215049475, 4186.853460128881, 4369.9126017479475, 4382.342648602735, 4385.736855788663, 4430.424044874146, 4432.578905004019, 4529.750863702462, 4564.452265728134, 4584.816607335669, 4618.145643056046, 4762.809487620457, 4764.475907650087, 4770.403699537558, 4777.259524773665, 4837.467878533288, 4755.167845626932, 5076.82231121909, 5094.974525127351, 5095.0574143783515, 5141.0, 4581.816727330869, 4627.0514544518965, 4667.486576476517, 4684.391029166187, 4728.834490792806, 4897.363342765418, 4902.926456103232, 4908.656394052382, 4910.116179087799, 4942.456329803791, 4974.776135073858, 4990.600751939909, 4990.8253211137435, 4994.425641051325, 5029.346184995927, 4692.554207350297, 4707.59985401231, 4715.221988372017, 4797.56821886032, 4798.976005119952, 4759.809607615656, 4799.064182484382, 4824.758762061953, 4825.083234185494, 4825.420949486084, 4643.233866412004, 4665.626749860071, 4674.392328997209, 4678.017616300479, 4683.250679891201, 4955.900881982412, 4958.553730164158, 4958.925616115689, 4971.651984361205, 4997.5002499749735, 4856.028794241095, 4856.660033797601, 4861.975690121527, 4865.1242776300605, 4909.656324057424], "value": 4583.7731430989015, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [536.8899375627997, 547.0563278344856, 560.4787547580748, 567.3957235544143, 568.8208214412457, 27432.177034688968, 36725.632743672555, 38011.99999999996, 38102.428463573066, 41763.37354939678, 39247.60752392475, 39661.20677586446, 39665.0, 39667.0, 39693.80153099238, 7422.257774222561, 7610.657520411567, 7610.733624323149, 7612.7716168515035, 7618.809529761773, 7453.142888567802, 7544.0, 7548.131964824061, 7550.4337174711845, 7551.093868735759, 7520.511166774142, 7566.583837888914, 7582.810429739246, 7583.734569290076, 7585.127710313302, 7512.624368781567, 7553.0, 7557.811054723621, 7564.924350756477, 7568.583727894964, 7276.308750668731, 7325.377342925858, 7331.1569169545655, 7334.66993985272, 7335.193128755833, 36974.8151259242, 37168.628313716785, 37294.81352593247, 37348.0, 37359.626403735885, 2851.5444224539324, 2955.7192066753623, 2989.9402011959855, 3144.371125774854, 3172.4448221561133, 27305.726942730715, 27909.441811163662, 28272.43455130886, 28367.43265134686, 28951.85524072387, 3442.2705650538364, 3464.532288141094, 3511.4557243627023, 3534.3638145133864, 3583.354996100727, 249475.0, 249639.0, 249985.0, 250477.0, 250666.0, 29706.851465742533, 48781.756091219315, 48955.51044489581, 49050.50949490531, 49084.0, 31038.534421983688, 32759.50860737091, 33036.0, 33063.83468082644, 33071.173220669196, 11116.110711143088, 11135.109191264679, 11140.888591114148, 11237.044851187577, 11252.718682033015, 10411.063004329495, 10492.842607360895, 10494.527746251437, 10496.632617858339, 10504.47477626131, 7537.095548534211, 7584.582847943416, 7660.61696915152, 7665.923340766521, 7670.769876903591, 2958.156925276286, 3075.185075954874, 3090.5982222310954, 3099.628044634613, 3101.968980310168, 879.4151888993747, 961.6682244625594, 962.0619895601733, 962.138885697297, 968.4867020479157, 26412.095464200924, 27646.0, 30955.690443095733, 31164.844175778977, 31818.204544886565, 928.373347990104, 945.2957546627806, 958.9568469418892, 1002.2433063037361, 1010.2675560218782, 13304.467821287233, 14041.648958775908, 14172.574822755334, 14371.856281437262, 14393.136411815325, 357514.0, 366173.0, 367132.0, 367561.0, 367866.0, 45486.772566136955, 46033.0, 46055.309170362474, 46097.078058438645, 46154.76922615366, 1167.6613782003124, 1183.2841131115724, 1208.2206976500283, 1210.709429736857, 1215.465195314059, 25281.87359063193, 25835.612465812654, 26103.216903492925, 26524.204273871816, 26681.466370672864, 39965.20069598534, 40698.389524157756, 40699.79650101731, 40814.59185408108, 40824.1835163301, 252341.0, 252987.0, 253697.0, 254631.0, 255522.0, 22627.0, 22642.0, 22786.886065569564, 22949.88525057364, 22984.0, 38745.03137421474, 38810.80594597009, 38849.0, 39266.607333926295, 39287.2142557153, 31422.0, 32670.83664581662, 33863.492047619795, 34041.31917361589, 34105.48841767326, 33549.49675754915, 33727.15682107967, 33927.32145357128, 33989.66010339865, 34082.488762669076, 28545.42909141847, 28991.130266092052, 31002.379952401276, 32986.0, 33261.16847078843, 28398.29004274827, 30856.83700322605, 31009.58776124012, 31211.531827023067, 31387.686123138476, 23764.643530347403, 23775.048998039885, 23892.0, 24182.39544011414, 24287.75712242855], "value": 15650.108167345636, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [338.82042517465754, 344.2512535235863, 351.4271737068578, 356.1594636657488, 377.9508663873696, 12508.749825003495, 15900.840991590081, 15987.360505579778, 16862.915685421587, 17011.57471063224, 16696.0, 16810.83189168108, 17011.48965531036, 17125.743113853303, 17132.486025419257, 4808.519148085181, 4829.275608658694, 4959.479254678261, 4980.526849949249, 5010.6993580385115, 4912.115819152551, 4990.251462280668, 5001.224810154432, 5010.874728131789, 5252.107141785887, 5335.733213339317, 5342.2253773202865, 5344.038073146832, 5348.866278343034, 5359.035373632744, 5181.274621552986, 5327.733613319338, 5338.546223571001, 5516.889662206733, 5556.638818476786, 4920.261960705887, 4937.284093806414, 4946.901061978776, 4949.579285760715, 4958.826441074545, 11103.167262455348, 11132.666020019413, 11145.554177832877, 11184.0, 11217.102631789438, 1750.719884818423, 1837.3477415517566, 1857.6841936870628, 1918.7026010968323, 2026.0376321247436, 8020.478668886562, 8276.875846862302, 8364.58177091143, 8646.827063458759, 8838.86741698875, 1514.5910604136932, 1603.7651008723335, 1608.340580362046, 1634.133909028217, 1711.5806627376248, 67776.6611166946, 68203.0, 69250.0, 69600.6519967402, 69873.65063174651, 7588.544687318778, 8026.117127116013, 8075.3539716822515, 8091.514509129471, 8575.356848236408, 11201.495932683049, 11277.0, 11288.54845806175, 11288.717782055513, 11309.660710178709, 6572.835679107965, 6719.428848547928, 6798.456123510107, 7001.0, 7008.719651213996, 6621.735130594819, 6641.136652235178, 6855.429214044733, 6873.862522749617, 6877.1747390312485, 3448.655134486531, 3476.304739052149, 3503.5270238517983, 3545.804980726085, 3570.839312230956, 1723.9827601724073, 1724.7240441529295, 1736.218701584267, 1745.7555942168106, 1764.7794025746782, 790.5138339921023, 810.5217921426387, 832.138736407815, 859.1022381611232, 903.3495882964255, 15518.068915865084, 15654.765178522332, 16229.675406491804, 16268.837311626732, 17334.219960101826, 846.4455781463151, 859.6561375449745, 871.3639043498282, 881.7531091294447, 899.024558354193, 8751.431156974777, 8777.868331975025, 9096.363254572116, 9258.842644669416, 9670.274729395323, 390224.0, 398040.31354983064, 401955.0, 403377.0, 403638.0, 76089.0, 81041.0, 81863.59068204621, 84151.15848841434, 84346.0, 903.4534106865383, 928.122923836981, 933.2254228990058, 940.036462625814, 945.8864936207699, 15007.249637518298, 15304.540863774104, 15500.5349839505, 15966.0, 16212.0, 26385.868070659522, 29060.27349316284, 34045.319093618484, 38092.428613570264, 38541.42187867128, 45801.77099114483, 52160.47839521556, 52324.0, 52800.47199528109, 53020.20469693035, 4156.134794641013, 4357.259265924864, 5824.15549745293, 6592.571482853599, 6969.30306969299, 8355.164483551596, 10298.897011030094, 11137.443127843737, 11307.830382544103, 11793.52825886955, 9746.171575415885, 9853.950730246303, 10015.14871235953, 10423.94788026055, 10672.57309707634, 9882.950585247028, 10078.848817267894, 10101.79796404053, 10143.695689129338, 10378.792424151627, 9780.119789219005, 9948.502574871083, 10149.23880708935, 10221.846672299773, 10245.975402459695, 7063.364297213276, 7162.104736907887, 7177.4616903731385, 7240.420766338575, 7433.293837085468, 4675.041616468684, 5822.010258256191, 7459.552426854406, 7811.257930496594, 10609.522571484456], "value": 7580.2470427056405, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1197.6586672798255, 1313.7241179352332, 1334.833145856768, 1370.540868808949, 1604.679064187162, 5774.3648198698165, 6580.736770529179, 6895.931040689592, 6909.274526174742, 6920.999999999994, 6774.0, 6820.488463365243, 6829.726810927569, 6844.38400543951, 6853.485988550866, 2048.354768247997, 2077.054940002303, 2088.404804630673, 2091.0381224636685, 2093.654546999743, 1974.2103158736477, 2033.2172113736244, 2042.3158241988951, 2044.1414605865498, 2062.278202629083, 2014.6977953306975, 2030.0255877178963, 2046.3860841747492, 2050.477128332273, 2052.9794702052936, 2013.0438041930113, 2030.2285131650026, 2031.6546187148294, 2031.8171364577258, 2032.6036422897541, 2008.7187793708893, 2014.869033512817, 2049.1905697249504, 2051.2615458434952, 2052.415061707406, 6675.332466753334, 6681.76613818514, 6734.124563806674, 6740.0, 6741.0899528564, 4029.093453972862, 4185.079282557833, 4191.622753952159, 4246.596573325529, 4251.957480425187, 2614.9084782032537, 2797.1328888044773, 2830.674472435676, 2836.0782745607553, 2836.8297902125732, 873.0832625742944, 877.9253763430116, 878.9121087891224, 890.9599068041889, 901.711452335253, 8506.234438900467, 8568.700095496628, 8573.485590864506, 8589.184027517376, 8594.312455003585, 3383.8138902360606, 3528.153243221608, 3621.7464777465325, 3622.3298689742483, 3629.9092522686615, 7128.108986376703, 7134.1795693495915, 7134.500584959003, 7138.9286107139305, 7142.0, 4143.212789569972, 4151.771652559078, 4152.792360381969, 4155.501339839178, 4157.043879907638, 3256.0557438342616, 3350.5811773528308, 3678.9080272992855, 3735.402335626287, 3763.8494460221286, 2991.1624745071413, 2994.7304742573274, 3083.722464978163, 3095.659477457478, 3119.5788568542957, 928.071928071928, 930.9348345615878, 937.4422218779815, 939.2908354192541, 945.8864936207561, 765.9502132361379, 767.223876725379, 770.3759954436924, 783.518136346143, 790.6481615680967, 4639.628829693616, 4663.207254766698, 4667.239415795869, 4790.209615413488, 4818.6626936114835, 371.85869369639363, 379.6203796203796, 382.6345839723058, 384.75952529668956, 388.56480741569624, 3288.8026718396973, 3307.140143562691, 3311.602607687093, 3334.249793796425, 3367.579052618423, 7674.270944260398, 8074.515529068157, 8075.111737708848, 8080.43436959419, 8094.028716554051, 8054.718084867001, 8059.355251579859, 8062.072861620989, 8063.959680201561, 8065.838683226302, 6401.8719625608155, 6467.741290348427, 6472.741090356333, 6479.287278399373, 6482.837929051717, 4598.793054312631, 4673.556012178838, 4694.2723877799, 4705.952940470552, 4760.357351757469, 7024.121984751906, 7150.42796576283, 7197.928020719726, 7205.351518363373, 7215.134183897966, 7295.452841037049, 7445.478816482794, 7459.142198647225, 7460.142083660231, 7467.6639551221415, 5229.294045303835, 5951.226340575698, 6074.969625151846, 6115.663638499926, 6145.0782382642965, 5954.166416701752, 6130.601510901777, 6354.0, 6361.045843123384, 6451.354864513699, 5591.944080559142, 5622.578306626938, 5648.181013752901, 5691.203231547669, 5812.331581868139, 5509.779608815603, 5626.971865140648, 5636.689982050862, 5664.490195882226, 5736.5410767139365, 5315.5216030555885, 5364.14173732201, 5386.13821788512, 5404.648697834628, 5433.7554810034435, 3963.742356746802, 4056.350983842571, 4107.8973025674595, 4200.096979149497, 4247.554006829362, 4213.220554197484, 4214.346776249687, 4229.640480559187, 4265.552117027792, 4290.592393722592], "value": 3564.510892398729, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [280.07993740562245, 280.660400914893, 284.79921655233056, 289.9347646779475, 290.1382892808361, 4757.54803293687, 4761.952380476194, 4783.210770222909, 4796.376471058767, 4833.589144922681, 4692.882677933053, 4695.3191787190835, 4696.953030469695, 4707.835225767099, 4768.0, 3486.518229752358, 3576.5171701820314, 3585.8924232273075, 3610.1696609779765, 3629.383004889162, 3625.2779929533394, 3662.487251784752, 3666.853325866962, 3719.3863012602887, 3782.8486860525545, 3686.3548878946212, 3687.0, 3698.7965661888584, 3711.1464363196346, 3729.0863738384132, 3589.353916295066, 3698.7225958052986, 3715.5880765309143, 3722.8324725387424, 3731.4402839574145, 3252.674732526752, 3261.4944683574086, 3263.559419478364, 3264.2002709336125, 3265.2816380396275, 4540.8864778380475, 4555.157295900269, 4558.407407037064, 4575.885602859922, 4582.266837306049, 4213.810378532973, 4216.641585465209, 4229.873103806891, 4234.640055595278, 4241.091508821012, 4788.257820037899, 4872.976674898276, 4905.062500312357, 4906.215005599122, 4966.90066198674, 3530.6292839251764, 3533.1873669056004, 3538.2746536960117, 3541.6812486876306, 3542.813157592184, 5139.8458046258675, 5146.125158722989, 5155.66488178271, 5171.810483588795, 5181.326427564392, 3506.929861402809, 3606.1886075633297, 3636.3636363636256, 3645.6172101929446, 3658.835352409148, 3669.6330866591434, 3671.1189314564313, 3680.392735198716, 3680.595134535199, 3680.742348035612, 4152.958470415318, 4204.600562946515, 4212.431321771583, 4216.704830661824, 4236.703430759879, 4907.852864329636, 4945.554900059012, 4951.975240123848, 5018.42288136869, 5022.773975171126, 1141.57191053355, 1202.5550546297925, 1202.8857258560422, 1205.9879401206051, 1240.9193402428814, 807.7399257158734, 828.0353388302681, 837.5267973594899, 837.7109897085496, 840.4243093480985, 4203.747775133502, 4221.535631080578, 4314.762688052125, 4315.956840431619, 4331.350297455407, 4972.57733092684, 4976.278439626234, 4981.227909674007, 5099.209622508443, 5134.743262836918, 4410.213863385334, 4413.933790993137, 4421.204183246982, 4431.224535706238, 4441.133978874089, 2561.9743802561734, 2592.468543948486, 2609.869506524666, 2620.65931428913, 2647.4705058987893, 4650.906981860344, 4653.53464653532, 4664.277037059262, 4668.883277918079, 4674.813007479663, 4990.857588201784, 4997.300917688003, 5004.274380214849, 5017.792171444553, 5067.949320506821, 4535.909281814345, 4587.587117159472, 4616.976915115471, 4639.280911458729, 4718.245080787126, 4846.321514987834, 4868.269759536098, 4886.438059623047, 4898.848770538992, 4899.216125419915, 4879.512048795092, 4886.169351210232, 4898.853034408973, 4899.951000490092, 4924.513120873936, 4487.259602165606, 4669.649776266726, 4675.929861052155, 4716.575508204278, 4721.669483136147, 3456.879009234665, 3466.272082862627, 3469.976356974648, 3472.2534655049285, 3486.0, 5004.874878128076, 5010.0, 5024.382444639853, 5035.571976382049, 5059.0, 4631.282151266559, 4725.432948046119, 4748.620110391092, 4847.61219102478, 4878.487758785276, 4701.858944231679, 4751.738654374042, 4786.8085276588545, 4798.49615790337, 4799.28010798383, 4640.443146822403, 4670.696404733681, 4673.602743766818, 4690.2026655469335, 4701.9529804701515, 4693.2021556336185, 4815.253635686474, 4844.577711144428, 4872.6832755870755, 5003.199488081889, 4847.891167342217, 4877.804937790264, 4883.438404583376, 4897.8285759998225, 4921.532454416824], "value": 3680.637994826843, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [628.0829988217203, 634.2610858350023, 644.9522765220424, 648.795629376746, 737.7934178430038, 11824.586139485109, 12713.300768457731, 12722.809157862617, 12739.108262421634, 12768.233905965648, 12178.573749918754, 12313.199642023263, 12324.32216228107, 12372.0, 12379.56671516497, 618.3599974026884, 631.7725618777237, 743.0, 754.2419868032633, 768.7040489411561, 754.6151462754008, 770.4953255617579, 771.6604693934679, 772.0580891312618, 772.5133166105373, 808.030363563723, 810.1695761844128, 810.7486679129489, 811.3144392987916, 812.2162113560413, 638.5179189711738, 656.4092316914767, 771.7453240430644, 806.8830019647177, 808.0707186735272, 588.5968111843401, 592.6177615438062, 594.2690490696424, 594.432317137133, 594.8245267646055, 12424.707452524997, 12473.251604903735, 12494.81257781134, 12523.624291271186, 12606.873931260661, 11695.473703683354, 11801.704957376049, 11907.821382679267, 11936.104792140624, 12100.0, 10652.41411722355, 10813.621523246728, 10865.185111116618, 10982.780344393148, 11180.161487888441, 3073.661897191307, 3204.1348835814433, 3237.805731656108, 3264.461363874958, 4024.7987600619854, 45813.77093114513, 48569.75715121437, 49657.255141172915, 49996.75001624969, 50124.49875501235, 9317.347785655074, 9432.292578056673, 9440.386374885611, 9469.857952130724, 9574.856377154349, 15691.372345106296, 15748.133852638215, 15883.047017179006, 15883.126428046337, 16092.75860862088, 6092.817215483543, 6141.969290153521, 6221.906671399933, 6231.844203894848, 6276.435120839146, 3382.374260761767, 3488.843002064913, 3493.371193185251, 3613.6928361089076, 3619.8371073301237, 2705.878235479408, 2789.0377819652185, 2798.9860050699617, 2800.0, 2816.2959260184953, 478.3852749217239, 482.9903401932012, 484.4477295882697, 488.3748801534042, 497.64169797745564, 549.6207616744433, 563.0568797264566, 567.4977644784313, 575.4245754245754, 628.6636649392644, 7413.096603660644, 7431.665575049135, 7482.812929676802, 7511.549307041595, 7512.197072702512, 584.3922320786335, 601.2064075420403, 601.3445344574435, 612.21024877907, 614.8063360041573, 3710.777353358807, 3722.1253005543676, 3732.552093748713, 3774.037620406779, 3900.8244628991765, 54226.72886635621, 55975.72012139994, 57038.714806425705, 72705.0, 75699.0, 34924.825375872955, 46141.76929115333, 54193.0, 56254.0, 58676.0, 42409.0, 50808.83823235368, 53002.734986324824, 54402.0, 54490.18264726032, 7954.164812694584, 8097.39269554774, 8173.100958894635, 8327.583620819056, 8574.356923230907, 36664.0, 37656.24687506289, 37705.43441848319, 38383.23233535369, 38903.80548097241, 41995.160096797284, 42032.0, 44127.558724412345, 48201.75899120482, 49573.25640115474, 10810.621628242974, 10860.294080885033, 10900.400477973788, 11018.0, 11223.38271395081, 11693.415329233674, 11961.0, 12121.69695757613, 12130.33283169434, 12909.677258068623, 10309.175265978554, 10428.739281518023, 10983.0, 11005.61480348184, 11314.15143864197, 11079.917275279457, 11726.355050472306, 12002.459889305177, 12184.878151218729, 12324.815127772912, 11249.71875703114, 11375.0, 11758.58844940423, 11758.70603234926, 11762.3530705812, 9303.116203960613, 9407.52962351866, 9522.190613797904, 9589.184919281939, 9818.711524838718, 8487.745367638981, 8563.871541926752, 9349.0, 9388.295877809329, 9506.382085164441], "value": 5973.1138172231895, "unit": "score"}], "name": "dromaeo_css", "value": 5910.168275235166, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 118.0, 116.0, 117.0, 117.0, 116.0, 115.0, 118.0, 117.0, 117.0], "value": 115.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 128.0, 130.0, 178.0, 129.0, 133.0, 127.0, 130.0, 178.0, 130.0], "value": 141.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 163.0, 162.0, 162.0, 160.0, 162.0, 161.0, 162.0, 161.0], "value": 161.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 77.0, 113.0, 77.0, 77.0, 77.0, 76.0, 96.0, 76.0], "value": 82.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 126.0, 84.0, 84.0, 83.0, 104.0, 84.0, 83.0, 112.0], "value": 92.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 147.0, 147.0, 146.0, 147.0, 147.0, 146.0, 146.0], "value": 146.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 96.0, 96.0, 97.0, 97.0, 96.0, 96.0, 95.0], "value": 95.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 62.0, 64.0, 63.0, 64.0, 64.0, 64.0, 64.0, 64.0, 64.0], "value": 63.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 66.0, 67.0, 67.0, 67.0, 67.0, 66.0, 66.0, 67.0], "value": 66.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 84.0, 94.0, 84.0, 84.0, 85.0, 85.0, 84.0, 84.0, 85.0], "value": 86.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 143.0, 144.0, 143.0, 144.0, 139.0, 144.0, 149.0, 183.0, 141.0], "value": 147.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 184.0, 180.0, 182.0, 176.0, 175.0, 180.0, 180.0, 184.0, 175.0], "value": 180.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 70.0, 70.0, 70.0, 68.0, 68.0, 72.0, 74.0, 69.0], "value": 70.0, "unit": "score"}], "name": "kraken", "value": 1567.5, "alertThreshold": 2.0}]} 08:49:58 INFO - Return code: 0 08:49:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:49:58 INFO - # TBPL SUCCESS # 08:49:58 INFO - Running post-action listener: _resource_record_post_action 08:49:58 INFO - [mozharness: 2016-11-17 16:49:58.281757Z] Finished run-tests step (success) 08:49:58 INFO - Running post-run listener: _resource_record_post_run 08:49:58 INFO - Total resource usage - Wall time: 1206s; CPU: 13.0%; Read bytes: 0; Write bytes: 390590464; Read time: 0; Write time: 245884 08:49:58 INFO - TinderboxPrint: CPU usage
13.1% 08:49:58 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 08:49:58 INFO - TinderboxPrint: I/O write bytes / time
390,590,464 / 245,884 08:49:58 INFO - TinderboxPrint: CPU idle
8,362.6 (86.9%) 08:49:58 INFO - TinderboxPrint: CPU user
1,212.8 (12.6%) 08:49:58 INFO - TinderboxPrint: Swap in / out
0 / 0 08:49:58 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 99934208; Read time: 0; Write time: 80072 08:49:58 INFO - run-tests - Wall time: 1190s; CPU: 13.0%; Read bytes: 0; Write bytes: 290656256; Read time: 0; Write time: 165812 08:49:58 INFO - Running post-run listener: _upload_blobber_files 08:49:58 INFO - Blob upload gear active. 08:49:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:49:58 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 08:49:58 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', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:49:58 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 Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:49:59 INFO - (blobuploader) - INFO - Open directory for files ... 08:49:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:49:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:49:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:50:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:50:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:50:01 INFO - (blobuploader) - INFO - Done attempting. 08:50:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:50:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:50:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:50:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:50:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:50:02 INFO - (blobuploader) - INFO - Done attempting. 08:50:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:50:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:50:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:50:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:50:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:50:07 INFO - (blobuploader) - INFO - Done attempting. 08:50:07 INFO - (blobuploader) - INFO - Iteration through files over. 08:50:07 INFO - Return code: 0 08:50:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:50:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:50:07 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/319d3d24501b147c60e838a8425c474abb5d83f80876ef6284b44b8147c6a7f20a847f9859702db2545220a29108ae90b98741f3b92a8d4d172e22794377ddcc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/87ba918b435ca109f86841fc11565e7354b7297d1510935b29f21d3a7c86ab60b3c07d18868de75d5fb176e2aa5badef63d48ceed51d71c35dbe200c1ab29b96", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/ce2a4108e4e9510074bec5e7495e18f96715828bf3c28b59c0d398dbdc3bc6c9233ef5746e5174b3d8a9769c509972b7ef21d37f0d64c6e461bdda41b9fbb5db"} 08:50:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:50:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:50:07 INFO - Contents: 08:50:07 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/319d3d24501b147c60e838a8425c474abb5d83f80876ef6284b44b8147c6a7f20a847f9859702db2545220a29108ae90b98741f3b92a8d4d172e22794377ddcc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/87ba918b435ca109f86841fc11565e7354b7297d1510935b29f21d3a7c86ab60b3c07d18868de75d5fb176e2aa5badef63d48ceed51d71c35dbe200c1ab29b96", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/ce2a4108e4e9510074bec5e7495e18f96715828bf3c28b59c0d398dbdc3bc6c9233ef5746e5174b3d8a9769c509972b7ef21d37f0d64c6e461bdda41b9fbb5db"} 08:50:07 INFO - Running post-run listener: copy_logs_to_upload_dir 08:50:07 INFO - Copying logs to upload dir... 08:50:07 INFO - mkdir: /builds/slave/test/build/upload/logs 08:50:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1254.394480 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2016-11-17 08:50:07.066906) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-17 08:50:07.070306) ========= 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=41bf3e9aed84707956354da8000001c5-1479398284.657764-1420876439 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/319d3d24501b147c60e838a8425c474abb5d83f80876ef6284b44b8147c6a7f20a847f9859702db2545220a29108ae90b98741f3b92a8d4d172e22794377ddcc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/87ba918b435ca109f86841fc11565e7354b7297d1510935b29f21d3a7c86ab60b3c07d18868de75d5fb176e2aa5badef63d48ceed51d71c35dbe200c1ab29b96", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/ce2a4108e4e9510074bec5e7495e18f96715828bf3c28b59c0d398dbdc3bc6c9233ef5746e5174b3d8a9769c509972b7ef21d37f0d64c6e461bdda41b9fbb5db"} build_url:https://queue.taskcluster.net/v1/task/JIiAHDWhR_iuSqkoCeNzGQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011270 build_url: 'https://queue.taskcluster.net/v1/task/JIiAHDWhR_iuSqkoCeNzGQ/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/Graphics/sha512/319d3d24501b147c60e838a8425c474abb5d83f80876ef6284b44b8147c6a7f20a847f9859702db2545220a29108ae90b98741f3b92a8d4d172e22794377ddcc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/87ba918b435ca109f86841fc11565e7354b7297d1510935b29f21d3a7c86ab60b3c07d18868de75d5fb176e2aa5badef63d48ceed51d71c35dbe200c1ab29b96", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/ce2a4108e4e9510074bec5e7495e18f96715828bf3c28b59c0d398dbdc3bc6c9233ef5746e5174b3d8a9769c509972b7ef21d37f0d64c6e461bdda41b9fbb5db"}' ========= master_lag: 0.06 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-17 08:50:07.138193) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-17 08:50:07.138813) ========= 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=41bf3e9aed84707956354da8000001c5-1479398284.657764-1420876439 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004473 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-17 08:50:07.159816) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-17 08:50:07.160304) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-17 08:50:07.160791) ========= ========= Total master_lag: 0.17 =========