builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-035 starttime: 1478237398.09 results: success (0) buildid: 20161103193337 builduid: 413f2fc371464edd9974e7ed43a07690 revision: 38fcc30d818f99f3798865d551acce5681b0a3c0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-03 22:29:58.085878) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-03 22:29:58.086366) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-03 22:29:58.086660) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-03 22:29:58.101702) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-03 22:29:58.102028) ========= 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=ed14279e38b05397cf4aa391000001a4-1478237397.368329-415775308 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-11-03 22:29:58-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.1M=0s 2016-11-03 22:29:58 (46.1 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.094733 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-03 22:29:58.211041) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-03 22:29:58.211404) ========= 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=ed14279e38b05397cf4aa391000001a4-1478237397.368329-415775308 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019306 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-03 22:29:58.261682) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-03 22:29:58.262036) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 38fcc30d818f99f3798865d551acce5681b0a3c0 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 38fcc30d818f99f3798865d551acce5681b0a3c0 --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=ed14279e38b05397cf4aa391000001a4-1478237397.368329-415775308 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-11-03 22:29:58,296 truncating revision to first 12 chars 2016-11-03 22:29:58,296 Setting DEBUG logging. 2016-11-03 22:29:58,296 attempt 1/10 2016-11-03 22:29:58,296 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/38fcc30d818f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-03 22:29:58,621 unpacking tar archive at: mozilla-central-38fcc30d818f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.729163 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-03 22:29:59.005197) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-03 22:29:59.005520) ========= script_repo_revision: 38fcc30d818f99f3798865d551acce5681b0a3c0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-03 22:29:59.005925) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-03 22:29:59.006190) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-03 22:29:59.015092) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-11-03 22:29:59.015454) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=ed14279e38b05397cf4aa391000001a4-1478237397.368329-415775308 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:29:59 INFO - MultiFileLogger online at 20161103 22:29:59 in /builds/slave/test 22:29:59 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 22:29:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:29:59 INFO - {'append_to_log': False, 22:29:59 INFO - 'base_work_dir': '/builds/slave/test', 22:29:59 INFO - 'blob_upload_branch': 'Firefox', 22:29:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:29:59 INFO - 'branch': 'Firefox', 22:29:59 INFO - 'buildbot_json_path': 'buildprops.json', 22:29:59 INFO - 'config_files': ('talos/linux_config.py',), 22:29:59 INFO - 'default_actions': ('clobber', 22:29:59 INFO - 'read-buildbot-config', 22:29:59 INFO - 'download-and-extract', 22:29:59 INFO - 'populate-webroot', 22:29:59 INFO - 'create-virtualenv', 22:29:59 INFO - 'install', 22:29:59 INFO - 'run-tests'), 22:29:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:29:59 INFO - 'download_minidump_stackwalk': True, 22:29:59 INFO - 'download_symbols': 'ondemand', 22:29:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:29:59 INFO - 'tooltool.py': '/tools/tooltool.py', 22:29:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:29:59 INFO - '/tools/misc-python/virtualenv.py')}, 22:29:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:29:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:29:59 INFO - 'installer_path': 'installer.exe', 22:29:59 INFO - 'log_level': 'info', 22:29:59 INFO - 'log_name': 'talos', 22:29:59 INFO - 'log_to_console': True, 22:29:59 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:29:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:29:59 INFO - 'opt_config_files': (), 22:29:59 INFO - 'pip_index': False, 22:29:59 INFO - 'sps_profile': False, 22:29:59 INFO - 'sps_profile_interval': 0, 22:29:59 INFO - 'suite': 'dromaeojs', 22:29:59 INFO - 'system_bits': '32', 22:29:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:29:59 INFO - 'title': 'talos-linux64-ix-035', 22:29:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:29:59 INFO - 'use_talos_json': True, 22:29:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:29:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:29:59 INFO - 'work_dir': 'build'} 22:29:59 INFO - [mozharness: 2016-11-04 05:29:59.137568Z] Running clobber step. 22:29:59 INFO - Running pre-action listener: _resource_record_pre_action 22:29:59 INFO - Running main action method: clobber 22:29:59 INFO - rmtree: /builds/slave/test/build 22:29:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:29:59 INFO - Running post-action listener: _resource_record_post_action 22:29:59 INFO - [mozharness: 2016-11-04 05:29:59.593088Z] Finished clobber step (success) 22:29:59 INFO - [mozharness: 2016-11-04 05:29:59.593189Z] Running read-buildbot-config step. 22:29:59 INFO - Running pre-action listener: _resource_record_pre_action 22:29:59 INFO - Running main action method: read_buildbot_config 22:29:59 INFO - Using buildbot properties: 22:29:59 INFO - { 22:29:59 INFO - "project": "", 22:29:59 INFO - "product": "firefox", 22:29:59 INFO - "script_repo_revision": "production", 22:29:59 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 22:29:59 INFO - "repository": "", 22:29:59 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 22:29:59 INFO - "buildid": "20161103193337", 22:29:59 INFO - "pgo_build": "True", 22:29:59 INFO - "basedir": "/builds/slave/test", 22:29:59 INFO - "buildnumber": 613, 22:29:59 INFO - "slavename": "talos-linux64-ix-035", 22:29:59 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 22:29:59 INFO - "platform": "ubuntu64_hw", 22:29:59 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 22:29:59 INFO - "branch": "mozilla-central", 22:29:59 INFO - "repo_path": "mozilla-central", 22:29:59 INFO - "revision": "38fcc30d818f99f3798865d551acce5681b0a3c0", 22:29:59 INFO - "stage_platform": "linux64-pgo", 22:29:59 INFO - "builduid": "413f2fc371464edd9974e7ed43a07690", 22:29:59 INFO - "slavebuilddir": "test" 22:29:59 INFO - } 22:29:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/cYOYjmA-S368aTKo2xKKNg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2. 22:29:59 INFO - Running post-action listener: _resource_record_post_action 22:29:59 INFO - [mozharness: 2016-11-04 05:29:59.595887Z] Finished read-buildbot-config step (success) 22:29:59 INFO - [mozharness: 2016-11-04 05:29:59.595975Z] Running download-and-extract step. 22:29:59 INFO - Running pre-action listener: _resource_record_pre_action 22:29:59 INFO - Running main action method: download_and_extract 22:29:59 INFO - mkdir: /builds/slave/test/build/tests 22:29:59 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')]} 22:29:59 INFO - trying https://queue.taskcluster.net/v1/task/cYOYjmA-S368aTKo2xKKNg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 22:29:59 INFO - Downloading https://queue.taskcluster.net/v1/task/cYOYjmA-S368aTKo2xKKNg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 22:29:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cYOYjmA-S368aTKo2xKKNg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 22:30:00 INFO - Downloaded 1361 bytes. 22:30:00 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 22:30:00 INFO - Using the following test package requirements: 22:30:00 INFO - {u'common': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 22:30:00 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 22:30:00 INFO - u'firefox-52.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:30:00 INFO - u'gtest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 22:30:00 INFO - u'firefox-52.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:30:00 INFO - u'jittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 22:30:00 INFO - u'jsshell-linux-x86_64.zip'], 22:30:00 INFO - u'mochitest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 22:30:00 INFO - u'firefox-52.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:30:00 INFO - u'mozbase': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 22:30:00 INFO - u'reftest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 22:30:00 INFO - u'firefox-52.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:30:00 INFO - u'talos': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 22:30:00 INFO - u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:30:00 INFO - u'web-platform': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 22:30:00 INFO - u'firefox-52.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:30:00 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 22:30:00 INFO - u'firefox-52.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:30:00 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:30:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cYOYjmA-S368aTKo2xKKNg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 22:30:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cYOYjmA-S368aTKo2xKKNg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:30:00 INFO - Fetch https://queue.taskcluster.net/v1/task/cYOYjmA-S368aTKo2xKKNg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 22:30:00 INFO - Http code: 200 22:30:00 INFO - via: 1.1 9552af4ceb2e0dd55964a80d1e549fd5.cloudfront.net (CloudFront) 22:30:00 INFO - x-amz-cf-id: BfM5ffaMpceHzer6u24iBbLWVjlgT62mUPxyxxbLmzvCfCShG7h34A== 22:30:00 INFO - x-amz-version-id: mOEiEQl7Y1ZO9NbFNAiB2pg6kbfmFuZh 22:30:01 INFO - Expected file size: 22003039 22:30:01 INFO - Obtained file size: 22003039 22:30:05 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 22:30:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cYOYjmA-S368aTKo2xKKNg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 22:30:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cYOYjmA-S368aTKo2xKKNg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:30:05 INFO - Fetch https://queue.taskcluster.net/v1/task/cYOYjmA-S368aTKo2xKKNg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 22:30:05 INFO - Http code: 200 22:30:05 INFO - via: 1.1 0cf1d7257c633ae75623c5e75bf3805e.cloudfront.net (CloudFront) 22:30:05 INFO - x-amz-cf-id: HAiRGCe0ztAITUInaEWto0hx1MQQNMwfkkYKnH6SILIkKha42j3zwQ== 22:30:05 INFO - x-amz-version-id: mOEiEQl7Y1ZO9NbFNAiB2pg6kbfmFuZh 22:30:05 INFO - Expected file size: 22003039 22:30:05 INFO - Obtained file size: 22003039 22:30:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cYOYjmA-S368aTKo2xKKNg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip 22:30:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cYOYjmA-S368aTKo2xKKNg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 22:30:10 INFO - Fetch https://queue.taskcluster.net/v1/task/cYOYjmA-S368aTKo2xKKNg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip into memory 22:30:11 INFO - Http code: 200 22:30:11 INFO - via: 1.1 e2af8a85927835558866752f53562ecd.cloudfront.net (CloudFront) 22:30:11 INFO - x-amz-cf-id: _rXZnPNGfiTf7j6zk2RSuBxyTqwxyLXCvpRYBfLHdQFbJvmuK5tSlQ== 22:30:11 INFO - x-amz-version-id: D89ziDfhgMAGlUQqBBWrk_lqoQT0Ep1b 22:30:11 INFO - Expected file size: 14409038 22:30:11 INFO - Obtained file size: 14409038 22:30:11 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')]} 22:30:11 INFO - trying https://queue.taskcluster.net/v1/task/cYOYjmA-S368aTKo2xKKNg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 22:30:11 INFO - Downloading https://queue.taskcluster.net/v1/task/cYOYjmA-S368aTKo2xKKNg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 22:30:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cYOYjmA-S368aTKo2xKKNg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 22:30:12 INFO - Downloaded 59622114 bytes. 22:30:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cYOYjmA-S368aTKo2xKKNg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 22:30:12 INFO - mkdir: /builds/slave/test/properties 22:30:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:30:12 INFO - Writing to file /builds/slave/test/properties/build_url 22:30:12 INFO - Contents: 22:30:12 INFO - build_url:https://queue.taskcluster.net/v1/task/cYOYjmA-S368aTKo2xKKNg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 22:30:13 INFO - Running post-action listener: _resource_record_post_action 22:30:13 INFO - Running post-action listener: set_extra_try_arguments 22:30:13 INFO - [mozharness: 2016-11-04 05:30:13.429149Z] Finished download-and-extract step (success) 22:30:13 INFO - [mozharness: 2016-11-04 05:30:13.429412Z] Running populate-webroot step. 22:30:13 INFO - Running pre-action listener: _resource_record_pre_action 22:30:13 INFO - Running main action method: populate_webroot 22:30:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:30:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:30:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:30:13 INFO - '--tppagecycles', 22:30:13 INFO - '7'], 22:30:13 INFO - 'tests': ['tsvgm']}}, 22:30:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:30:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:30:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:30:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:30:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:30:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:30:13 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:30:13 INFO - 'talos_options': ['--disable-e10s'], 22:30:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:30:13 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:30:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:30:13 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:30:13 INFO - 'talos_options': ['--disable-e10s'], 22:30:13 INFO - 'tests': ['damp', 'tps']}, 22:30:13 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:30:13 INFO - 'tests': ['damp', 'tps']}, 22:30:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:30:13 INFO - 'tests': ['dromaeo_dom']}, 22:30:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:30:13 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:30:13 INFO - 'tests': ['basic_compositor_video']}, 22:30:13 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:30:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:30:13 INFO - 'tests': ['a11yr', 22:30:13 INFO - 'ts_paint', 22:30:13 INFO - 'tpaint', 22:30:13 INFO - 'sessionrestore', 22:30:13 INFO - 'sessionrestore_no_auto_restore', 22:30:13 INFO - 'tabpaint']}, 22:30:13 INFO - 'other-e10s': {'tests': ['a11yr', 22:30:13 INFO - 'ts_paint', 22:30:13 INFO - 'tpaint', 22:30:13 INFO - 'sessionrestore', 22:30:13 INFO - 'sessionrestore_no_auto_restore', 22:30:13 INFO - 'tabpaint']}, 22:30:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:30:13 INFO - 'ts_paint', 22:30:13 INFO - 'tpaint', 22:30:13 INFO - 'sessionrestore', 22:30:13 INFO - 'sessionrestore_no_auto_restore', 22:30:13 INFO - 'tabpaint']}, 22:30:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:30:13 INFO - 'ts_paint', 22:30:13 INFO - 'tpaint', 22:30:13 INFO - 'sessionrestore', 22:30:13 INFO - 'sessionrestore_no_auto_restore', 22:30:13 INFO - 'tabpaint']}, 22:30:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:30:13 INFO - 'tests': ['a11yr', 22:30:13 INFO - 'ts_paint', 22:30:13 INFO - 'tpaint', 22:30:13 INFO - 'sessionrestore', 22:30:13 INFO - 'sessionrestore_no_auto_restore', 22:30:13 INFO - 'tabpaint']}, 22:30:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:30:13 INFO - 'tests': ['a11yr', 22:30:13 INFO - 'ts_paint', 22:30:13 INFO - 'tpaint', 22:30:13 INFO - 'sessionrestore', 22:30:13 INFO - 'sessionrestore_no_auto_restore', 22:30:13 INFO - 'tabpaint']}, 22:30:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:30:13 INFO - 'tests': ['tsvgx', 22:30:13 INFO - 'tsvgr_opacity', 22:30:13 INFO - 'tart', 22:30:13 INFO - 'tscrollx', 22:30:13 INFO - 'cart']}, 22:30:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:30:13 INFO - 'tsvgr_opacity', 22:30:13 INFO - 'tart', 22:30:13 INFO - 'tscrollx', 22:30:13 INFO - 'cart']}, 22:30:13 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:30:13 INFO - 'talos_options': ['--disable-e10s'], 22:30:13 INFO - 'tests': ['tp5o']}, 22:30:13 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:30:13 INFO - 'tests': ['tp5o']}, 22:30:13 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:30:13 INFO - 'talos_options': ['--disable-e10s', 22:30:13 INFO - '--xperf_path', 22:30:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:30:13 INFO - 'tests': ['tp5n']}, 22:30:13 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:30:13 INFO - 'talos_options': ['--xperf_path', 22:30:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:30:13 INFO - 'tests': ['tp5n']}}, 22:30:13 INFO - 'talos.zip': {'path': '', 22:30:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:30:13 INFO - Running post-action listener: _resource_record_post_action 22:30:13 INFO - [mozharness: 2016-11-04 05:30:13.447689Z] Finished populate-webroot step (success) 22:30:13 INFO - [mozharness: 2016-11-04 05:30:13.447777Z] Running create-virtualenv step. 22:30:13 INFO - Running pre-action listener: _resource_record_pre_action 22:30:13 INFO - Running main action method: create_virtualenv 22:30:13 INFO - Creating virtualenv /builds/slave/test/build/venv 22:30:13 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 22:30:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:30:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:30:13 INFO - Using real prefix '/usr' 22:30:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:30:14 INFO - Installing distribute.............................................................................................................................................................................................done. 22:30:16 INFO - Installing pip.................done. 22:30:16 INFO - Return code: 0 22:30:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 22:30:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 22:30:16 INFO - Reading from file tmpfile_stdout 22:30:16 INFO - Output received: 22:30:16 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) 22:30:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:30:16 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')]} 22:30:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:30:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:16 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')]} 22:30:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:30:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:16 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 0x7fdb5134cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12e28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13ae7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x143d790>, '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 0x143e690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x143eb20>, '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': 'ed14279e38b05397cf4aa391000001a4-1478237397.368329-415775308', '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 22:30:16 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 22:30:16 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 22:30:16 INFO - Using env: {'DISPLAY': ':0', 22:30:16 INFO - 'HOME': '/home/cltbld', 22:30:16 INFO - 'LANG': 'en_US.UTF-8', 22:30:16 INFO - 'LANGUAGE': 'en_US:en', 22:30:16 INFO - 'LOGNAME': 'cltbld', 22:30:16 INFO - 'MAIL': '/var/mail/cltbld', 22:30:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:30:16 INFO - 'MOZ_NO_REMOTE': '1', 22:30:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:30:16 INFO - 'NO_EM_RESTART': '1', 22:30:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:30:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:30:16 INFO - 'PWD': '/builds/slave/test', 22:30:16 INFO - 'SHELL': '/bin/bash', 22:30:16 INFO - 'SHLVL': '1', 22:30:16 INFO - 'TERM': 'linux', 22:30:16 INFO - 'TMOUT': '86400', 22:30:16 INFO - 'USER': 'cltbld', 22:30:16 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1478237397.368329-415775308', 22:30:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:30:16 INFO - '_': '/tools/buildbot/bin/python'} 22:30:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:30:16 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 22:30:16 INFO - Cleaning up... 22:30:16 INFO - Return code: 0 22:30:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:30:16 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')]} 22:30:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:30:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:16 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')]} 22:30:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:30:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:16 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 0x7fdb5134cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12e28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13ae7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x143d790>, '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 0x143e690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x143eb20>, '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': 'ed14279e38b05397cf4aa391000001a4-1478237397.368329-415775308', '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 22:30:16 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 22:30:16 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 22:30:16 INFO - Using env: {'DISPLAY': ':0', 22:30:16 INFO - 'HOME': '/home/cltbld', 22:30:16 INFO - 'LANG': 'en_US.UTF-8', 22:30:16 INFO - 'LANGUAGE': 'en_US:en', 22:30:16 INFO - 'LOGNAME': 'cltbld', 22:30:16 INFO - 'MAIL': '/var/mail/cltbld', 22:30:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:30:16 INFO - 'MOZ_NO_REMOTE': '1', 22:30:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:30:16 INFO - 'NO_EM_RESTART': '1', 22:30:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:30:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:30:16 INFO - 'PWD': '/builds/slave/test', 22:30:16 INFO - 'SHELL': '/bin/bash', 22:30:16 INFO - 'SHLVL': '1', 22:30:16 INFO - 'TERM': 'linux', 22:30:16 INFO - 'TMOUT': '86400', 22:30:16 INFO - 'USER': 'cltbld', 22:30:16 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1478237397.368329-415775308', 22:30:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:30:16 INFO - '_': '/tools/buildbot/bin/python'} 22:30:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:30:16 INFO - Downloading/unpacking psutil>=3.1.1 22:30:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:30:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:30:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:30:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:30:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:30:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:30:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:30:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:30:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:30:18 INFO - Installing collected packages: psutil 22:30:18 INFO - Running setup.py install for psutil 22:30:18 INFO - building 'psutil._psutil_linux' extension 22:30:18 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 22:30:18 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 22:30:18 INFO - building 'psutil._psutil_posix' extension 22:30:18 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 22:30:19 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 22:30:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:30:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:30:19 INFO - Successfully installed psutil 22:30:19 INFO - Cleaning up... 22:30:19 INFO - Return code: 0 22:30:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:30:19 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')]} 22:30:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:30:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:19 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')]} 22:30:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:30:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:19 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 0x7fdb5134cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12e28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13ae7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x143d790>, '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 0x143e690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x143eb20>, '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': 'ed14279e38b05397cf4aa391000001a4-1478237397.368329-415775308', '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 22:30:19 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 22:30:19 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 22:30:19 INFO - Using env: {'DISPLAY': ':0', 22:30:19 INFO - 'HOME': '/home/cltbld', 22:30:19 INFO - 'LANG': 'en_US.UTF-8', 22:30:19 INFO - 'LANGUAGE': 'en_US:en', 22:30:19 INFO - 'LOGNAME': 'cltbld', 22:30:19 INFO - 'MAIL': '/var/mail/cltbld', 22:30:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:30:19 INFO - 'MOZ_NO_REMOTE': '1', 22:30:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:30:19 INFO - 'NO_EM_RESTART': '1', 22:30:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:30:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:30:19 INFO - 'PWD': '/builds/slave/test', 22:30:19 INFO - 'SHELL': '/bin/bash', 22:30:19 INFO - 'SHLVL': '1', 22:30:19 INFO - 'TERM': 'linux', 22:30:19 INFO - 'TMOUT': '86400', 22:30:19 INFO - 'USER': 'cltbld', 22:30:19 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1478237397.368329-415775308', 22:30:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:30:19 INFO - '_': '/tools/buildbot/bin/python'} 22:30:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:30:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:30:19 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 22:30:19 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 22:30:19 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 22:30:19 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 22:30:19 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 22:30:19 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 22:30:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:30:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:30:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:30:21 INFO - Installing collected packages: mozsystemmonitor 22:30:21 INFO - Running setup.py install for mozsystemmonitor 22:30:21 INFO - Successfully installed mozsystemmonitor 22:30:21 INFO - Cleaning up... 22:30:21 INFO - Return code: 0 22:30:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:30:21 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')]} 22:30:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:30:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:21 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')]} 22:30:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:30:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:21 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 0x7fdb5134cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12e28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13ae7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x143d790>, '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 0x143e690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x143eb20>, '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': 'ed14279e38b05397cf4aa391000001a4-1478237397.368329-415775308', '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 22:30:21 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 22:30:21 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 22:30:21 INFO - Using env: {'DISPLAY': ':0', 22:30:21 INFO - 'HOME': '/home/cltbld', 22:30:21 INFO - 'LANG': 'en_US.UTF-8', 22:30:21 INFO - 'LANGUAGE': 'en_US:en', 22:30:21 INFO - 'LOGNAME': 'cltbld', 22:30:21 INFO - 'MAIL': '/var/mail/cltbld', 22:30:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:30:21 INFO - 'MOZ_NO_REMOTE': '1', 22:30:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:30:21 INFO - 'NO_EM_RESTART': '1', 22:30:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:30:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:30:21 INFO - 'PWD': '/builds/slave/test', 22:30:21 INFO - 'SHELL': '/bin/bash', 22:30:21 INFO - 'SHLVL': '1', 22:30:21 INFO - 'TERM': 'linux', 22:30:21 INFO - 'TMOUT': '86400', 22:30:21 INFO - 'USER': 'cltbld', 22:30:21 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1478237397.368329-415775308', 22:30:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:30:21 INFO - '_': '/tools/buildbot/bin/python'} 22:30:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:30:21 INFO - Downloading/unpacking jsonschema==2.5.1 22:30:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:30:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:30:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:30:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:30:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:30:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:30:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:30:23 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:30:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:30:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:30:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:30:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:30:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:30:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:30:23 INFO - Downloading functools32-3.2.3-2.tar.gz 22:30:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:30:24 INFO - warning: no files found matching '*.txt' 22:30:24 INFO - no previously-included directories found matching 'build' 22:30:24 INFO - no previously-included directories found matching 'dist' 22:30:24 INFO - no previously-included directories found matching '.git*' 22:30:24 INFO - Installing collected packages: jsonschema, functools32 22:30:24 INFO - Running setup.py install for functools32 22:30:24 INFO - warning: no files found matching '*.txt' 22:30:24 INFO - no previously-included directories found matching 'build' 22:30:24 INFO - no previously-included directories found matching 'dist' 22:30:24 INFO - no previously-included directories found matching '.git*' 22:30:24 INFO - Successfully installed jsonschema functools32 22:30:24 INFO - Cleaning up... 22:30:24 INFO - Return code: 0 22:30:24 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:30:24 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')]} 22:30:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:30:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:24 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')]} 22:30:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:30:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:24 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 0x7fdb5134cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12e28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13ae7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x143d790>, '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 0x143e690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x143eb20>, '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': 'ed14279e38b05397cf4aa391000001a4-1478237397.368329-415775308', '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 22:30:24 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 22:30:24 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 22:30:24 INFO - Using env: {'DISPLAY': ':0', 22:30:24 INFO - 'HOME': '/home/cltbld', 22:30:24 INFO - 'LANG': 'en_US.UTF-8', 22:30:24 INFO - 'LANGUAGE': 'en_US:en', 22:30:24 INFO - 'LOGNAME': 'cltbld', 22:30:24 INFO - 'MAIL': '/var/mail/cltbld', 22:30:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:30:24 INFO - 'MOZ_NO_REMOTE': '1', 22:30:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:30:24 INFO - 'NO_EM_RESTART': '1', 22:30:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:30:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:30:24 INFO - 'PWD': '/builds/slave/test', 22:30:24 INFO - 'SHELL': '/bin/bash', 22:30:24 INFO - 'SHLVL': '1', 22:30:24 INFO - 'TERM': 'linux', 22:30:24 INFO - 'TMOUT': '86400', 22:30:24 INFO - 'USER': 'cltbld', 22:30:24 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1478237397.368329-415775308', 22:30:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:30:24 INFO - '_': '/tools/buildbot/bin/python'} 22:30:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:30:24 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:30:24 INFO - Cleaning up... 22:30:24 INFO - Return code: 0 22:30:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:30:24 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')]} 22:30:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:30:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:24 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')]} 22:30:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:30:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:24 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 0x7fdb5134cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12e28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13ae7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x143d790>, '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 0x143e690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x143eb20>, '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': 'ed14279e38b05397cf4aa391000001a4-1478237397.368329-415775308', '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 22:30:24 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 22:30:24 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 22:30:24 INFO - Using env: {'DISPLAY': ':0', 22:30:24 INFO - 'HOME': '/home/cltbld', 22:30:24 INFO - 'LANG': 'en_US.UTF-8', 22:30:24 INFO - 'LANGUAGE': 'en_US:en', 22:30:24 INFO - 'LOGNAME': 'cltbld', 22:30:24 INFO - 'MAIL': '/var/mail/cltbld', 22:30:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:30:24 INFO - 'MOZ_NO_REMOTE': '1', 22:30:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:30:24 INFO - 'NO_EM_RESTART': '1', 22:30:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:30:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:30:24 INFO - 'PWD': '/builds/slave/test', 22:30:24 INFO - 'SHELL': '/bin/bash', 22:30:24 INFO - 'SHLVL': '1', 22:30:24 INFO - 'TERM': 'linux', 22:30:24 INFO - 'TMOUT': '86400', 22:30:24 INFO - 'USER': 'cltbld', 22:30:24 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1478237397.368329-415775308', 22:30:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:30:24 INFO - '_': '/tools/buildbot/bin/python'} 22:30:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:30:24 INFO - Downloading/unpacking blobuploader==1.2.4 22:30:24 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 22:30:24 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 22:30:24 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 22:30:24 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 22:30:24 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 22:30:24 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 22:30:26 INFO - Downloading blobuploader-1.2.4.tar.gz 22:30:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:30:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:30:26 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 22:30:26 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 22:30:26 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 22:30:26 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 22:30:26 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 22:30:26 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 22:30:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:30:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:30:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:30:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:30:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:30:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:30:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:30:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:30:27 INFO - Downloading docopt-0.6.1.tar.gz 22:30:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:30:27 INFO - Installing collected packages: blobuploader, requests, docopt 22:30:27 INFO - Running setup.py install for blobuploader 22:30:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:30:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:30:27 INFO - Running setup.py install for requests 22:30:27 INFO - Running setup.py install for docopt 22:30:27 INFO - Successfully installed blobuploader requests docopt 22:30:27 INFO - Cleaning up... 22:30:28 INFO - Return code: 0 22:30:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:30:28 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')]} 22:30:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:30:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:28 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')]} 22:30:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:30:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:28 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 0x7fdb5134cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12e28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13ae7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x143d790>, '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 0x143e690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x143eb20>, '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': 'ed14279e38b05397cf4aa391000001a4-1478237397.368329-415775308', '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 22:30:28 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 22:30:28 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 22:30:28 INFO - Using env: {'DISPLAY': ':0', 22:30:28 INFO - 'HOME': '/home/cltbld', 22:30:28 INFO - 'LANG': 'en_US.UTF-8', 22:30:28 INFO - 'LANGUAGE': 'en_US:en', 22:30:28 INFO - 'LOGNAME': 'cltbld', 22:30:28 INFO - 'MAIL': '/var/mail/cltbld', 22:30:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:30:28 INFO - 'MOZ_NO_REMOTE': '1', 22:30:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:30:28 INFO - 'NO_EM_RESTART': '1', 22:30:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:30:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:30:28 INFO - 'PWD': '/builds/slave/test', 22:30:28 INFO - 'SHELL': '/bin/bash', 22:30:28 INFO - 'SHLVL': '1', 22:30:28 INFO - 'TERM': 'linux', 22:30:28 INFO - 'TMOUT': '86400', 22:30:28 INFO - 'USER': 'cltbld', 22:30:28 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1478237397.368329-415775308', 22:30:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:30:28 INFO - '_': '/tools/buildbot/bin/python'} 22:30:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:30:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:30:28 INFO - Running setup.py (path:/tmp/pip-PHl7CS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:30:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:30:28 INFO - Running setup.py (path:/tmp/pip-e7w0sX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:30:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:30:28 INFO - Running setup.py (path:/tmp/pip-YLR01k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:30:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:30:28 INFO - Running setup.py (path:/tmp/pip-ErQX6H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:30:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:30:28 INFO - Running setup.py (path:/tmp/pip-f4gipM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:30:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:30:28 INFO - Running setup.py (path:/tmp/pip-EyKHZs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:30:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:30:28 INFO - Running setup.py (path:/tmp/pip-EIUSbQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:30:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:30:28 INFO - Running setup.py (path:/tmp/pip-cLfRIS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:30:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:30:28 INFO - Running setup.py (path:/tmp/pip-kIjN0M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:30:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:30:28 INFO - Running setup.py (path:/tmp/pip-nnROFh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:30:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:30:28 INFO - Running setup.py (path:/tmp/pip-reRANH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:30:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:30:29 INFO - Running setup.py (path:/tmp/pip-EE0u2C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:30:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:30:29 INFO - Running setup.py (path:/tmp/pip-ByqctM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:30:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:30:29 INFO - Running setup.py (path:/tmp/pip-2x6Hbd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:30:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:30:29 INFO - Running setup.py (path:/tmp/pip-svUQYY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:30:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:30:29 INFO - Running setup.py (path:/tmp/pip-wqcj2s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:30:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:30:29 INFO - Running setup.py (path:/tmp/pip-tlyfgs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:30:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:30:29 INFO - Running setup.py install for manifestparser 22:30:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:30:29 INFO - Running setup.py install for mozcrash 22:30:29 INFO - Running setup.py install for mozdebug 22:30:29 INFO - Running setup.py install for mozdevice 22:30:29 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:30:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:30:29 INFO - Running setup.py install for mozfile 22:30:29 INFO - Running setup.py install for mozhttpd 22:30:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:30:30 INFO - Running setup.py install for mozinfo 22:30:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:30:30 INFO - Running setup.py install for mozInstall 22:30:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:30:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:30:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:30:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:30:30 INFO - Running setup.py install for mozleak 22:30:30 INFO - Running setup.py install for mozlog 22:30:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:30:30 INFO - Running setup.py install for moznetwork 22:30:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:30:30 INFO - Running setup.py install for mozprocess 22:30:30 INFO - Running setup.py install for mozprofile 22:30:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:30:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:30:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:30:30 INFO - Running setup.py install for mozrunner 22:30:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:30:30 INFO - Running setup.py install for mozscreenshot 22:30:31 INFO - Running setup.py install for moztest 22:30:31 INFO - Running setup.py install for mozversion 22:30:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:30:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:30:31 INFO - Cleaning up... 22:30:31 INFO - Return code: 0 22:30:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:30:31 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')]} 22:30:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:30:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:31 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')]} 22:30:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:30:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:31 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 0x7fdb5134cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12e28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13ae7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x143d790>, '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 0x143e690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x143eb20>, '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': 'ed14279e38b05397cf4aa391000001a4-1478237397.368329-415775308', '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 22:30:31 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 22:30:31 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 22:30:31 INFO - Using env: {'DISPLAY': ':0', 22:30:31 INFO - 'HOME': '/home/cltbld', 22:30:31 INFO - 'LANG': 'en_US.UTF-8', 22:30:31 INFO - 'LANGUAGE': 'en_US:en', 22:30:31 INFO - 'LOGNAME': 'cltbld', 22:30:31 INFO - 'MAIL': '/var/mail/cltbld', 22:30:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:30:31 INFO - 'MOZ_NO_REMOTE': '1', 22:30:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:30:31 INFO - 'NO_EM_RESTART': '1', 22:30:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:30:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:30:31 INFO - 'PWD': '/builds/slave/test', 22:30:31 INFO - 'SHELL': '/bin/bash', 22:30:31 INFO - 'SHLVL': '1', 22:30:31 INFO - 'TERM': 'linux', 22:30:31 INFO - 'TMOUT': '86400', 22:30:31 INFO - 'USER': 'cltbld', 22:30:31 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1478237397.368329-415775308', 22:30:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:30:31 INFO - '_': '/tools/buildbot/bin/python'} 22:30:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:30:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:30:31 INFO - Running setup.py (path:/tmp/pip-DfihwR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:30:31 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)) 22:30:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:30:31 INFO - Running setup.py (path:/tmp/pip-qy6Cuy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:30:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 22:30:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:30:31 INFO - Running setup.py (path:/tmp/pip-_c7JrC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:30:31 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)) 22:30:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:30:31 INFO - Running setup.py (path:/tmp/pip-6c0PBB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:30:31 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)) 22:30:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:30:31 INFO - Running setup.py (path:/tmp/pip-PNpfbi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:30:31 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)) 22:30:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:30:31 INFO - Running setup.py (path:/tmp/pip-YfnoD5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:30:31 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)) 22:30:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:30:31 INFO - Running setup.py (path:/tmp/pip-usb9II-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:30:31 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)) 22:30:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:30:31 INFO - Running setup.py (path:/tmp/pip-oBNdeX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:30:32 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)) 22:30:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:30:32 INFO - Running setup.py (path:/tmp/pip-jUzFha-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:30:32 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)) 22:30:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:30:32 INFO - Running setup.py (path:/tmp/pip-4H6ZW1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:30:32 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)) 22:30:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:30:32 INFO - Running setup.py (path:/tmp/pip-gHh8Kg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:30:32 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)) 22:30:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:30:32 INFO - Running setup.py (path:/tmp/pip-j4brfr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:30:32 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)) 22:30:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:30:32 INFO - Running setup.py (path:/tmp/pip-mTjG8O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:30:32 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)) 22:30:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:30:32 INFO - Running setup.py (path:/tmp/pip-MaDOtz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:30:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 22:30:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:30:32 INFO - Running setup.py (path:/tmp/pip-ZGL2HH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:30:32 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)) 22:30:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:30:32 INFO - Running setup.py (path:/tmp/pip-sfW36L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:30:32 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)) 22:30:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:30:32 INFO - Running setup.py (path:/tmp/pip-Rz1ljq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:30:32 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)) 22:30:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:30:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:30:32 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)) 22:30:32 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)) 22:30:32 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)) 22:30:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:30: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 22:30: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 22:30: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 22:30: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 22:30: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 22:30: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 22:30:34 INFO - Downloading blessings-1.6.tar.gz 22:30:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:30:34 INFO - Installing collected packages: blessings 22:30:34 INFO - Running setup.py install for blessings 22:30:34 INFO - Successfully installed blessings 22:30:34 INFO - Cleaning up... 22:30:34 INFO - Return code: 0 22:30:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:30:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:30:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:30:35 INFO - Reading from file tmpfile_stdout 22:30:35 INFO - Current package versions: 22:30:35 INFO - argparse == 1.2.1 22:30:35 INFO - blessings == 1.6 22:30:35 INFO - blobuploader == 1.2.4 22:30:35 INFO - docopt == 0.6.1 22:30:35 INFO - functools32 == 3.2.3-2 22:30:35 INFO - jsonschema == 2.5.1 22:30:35 INFO - manifestparser == 1.1 22:30:35 INFO - mozInstall == 1.12 22:30:35 INFO - mozcrash == 0.17 22:30:35 INFO - mozdebug == 0.1 22:30:35 INFO - mozdevice == 0.48 22:30:35 INFO - mozfile == 1.2 22:30:35 INFO - mozhttpd == 0.7 22:30:35 INFO - mozinfo == 0.9 22:30:35 INFO - mozleak == 0.1 22:30:35 INFO - mozlog == 3.4 22:30:35 INFO - moznetwork == 0.27 22:30:35 INFO - mozprocess == 0.23 22:30:35 INFO - mozprofile == 0.28 22:30:35 INFO - mozrunner == 6.12 22:30:35 INFO - mozscreenshot == 0.1 22:30:35 INFO - mozsystemmonitor == 0.3 22:30:35 INFO - moztest == 0.7 22:30:35 INFO - mozversion == 1.4 22:30:35 INFO - psutil == 3.1.1 22:30:35 INFO - requests == 1.2.3 22:30:35 INFO - wsgiref == 0.1.2 22:30:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:30: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')]} 22:30:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:30:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30: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')]} 22:30:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:30:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:35 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 0x7fdb5134cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12e28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13ae7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x143d790>, '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 0x143e690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x143eb20>, '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': 'ed14279e38b05397cf4aa391000001a4-1478237397.368329-415775308', '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 22:30:35 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 22:30:35 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 22:30:35 INFO - Using env: {'DISPLAY': ':0', 22:30:35 INFO - 'HOME': '/home/cltbld', 22:30:35 INFO - 'LANG': 'en_US.UTF-8', 22:30:35 INFO - 'LANGUAGE': 'en_US:en', 22:30:35 INFO - 'LOGNAME': 'cltbld', 22:30:35 INFO - 'MAIL': '/var/mail/cltbld', 22:30:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:30:35 INFO - 'MOZ_NO_REMOTE': '1', 22:30:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:30:35 INFO - 'NO_EM_RESTART': '1', 22:30:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:30:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:30:35 INFO - 'PWD': '/builds/slave/test', 22:30:35 INFO - 'SHELL': '/bin/bash', 22:30:35 INFO - 'SHLVL': '1', 22:30:35 INFO - 'TERM': 'linux', 22:30:35 INFO - 'TMOUT': '86400', 22:30:35 INFO - 'USER': 'cltbld', 22:30:35 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1478237397.368329-415775308', 22:30:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:30:35 INFO - '_': '/tools/buildbot/bin/python'} 22:30:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:30:35 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)) 22:30:35 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)) 22:30:35 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)) 22:30:35 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)) 22:30:35 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)) 22:30:35 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)) 22:30:35 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)) 22:30:35 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)) 22:30:35 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)) 22:30:35 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)) 22:30:35 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)) 22:30:35 INFO - Cleaning up... 22:30:35 INFO - Return code: 0 22:30:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:30: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')]} 22:30:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:30:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30: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')]} 22:30:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:30:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb5134cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12e28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13ae7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x143d790>, '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 0x143e690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x143eb20>, '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': 'ed14279e38b05397cf4aa391000001a4-1478237397.368329-415775308', '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 22:30: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', 'jsonschema'] in /builds/slave/test/build 22:30: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 jsonschema 22:30:35 INFO - Using env: {'DISPLAY': ':0', 22:30:35 INFO - 'HOME': '/home/cltbld', 22:30:35 INFO - 'LANG': 'en_US.UTF-8', 22:30:35 INFO - 'LANGUAGE': 'en_US:en', 22:30:35 INFO - 'LOGNAME': 'cltbld', 22:30:35 INFO - 'MAIL': '/var/mail/cltbld', 22:30:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:30:35 INFO - 'MOZ_NO_REMOTE': '1', 22:30:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:30:35 INFO - 'NO_EM_RESTART': '1', 22:30:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:30:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:30:35 INFO - 'PWD': '/builds/slave/test', 22:30:35 INFO - 'SHELL': '/bin/bash', 22:30:35 INFO - 'SHLVL': '1', 22:30:35 INFO - 'TERM': 'linux', 22:30:35 INFO - 'TMOUT': '86400', 22:30:35 INFO - 'USER': 'cltbld', 22:30:35 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1478237397.368329-415775308', 22:30:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:30:35 INFO - '_': '/tools/buildbot/bin/python'} 22:30:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:30:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:30:35 INFO - Cleaning up... 22:30:35 INFO - Return code: 0 22:30:35 INFO - Running post-action listener: _resource_record_post_action 22:30:35 INFO - Running post-action listener: _start_resource_monitoring 22:30:35 INFO - Starting resource monitoring. 22:30:35 INFO - [mozharness: 2016-11-04 05:30:35.592925Z] Finished create-virtualenv step (success) 22:30:35 INFO - [mozharness: 2016-11-04 05:30:35.593400Z] Running install step. 22:30:35 INFO - Running pre-action listener: _resource_record_pre_action 22:30:35 INFO - Running main action method: install 22:30:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:30:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:30:35 INFO - Reading from file tmpfile_stdout 22:30:35 INFO - Detecting whether we're running mozinstall >=1.0... 22:30:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:30:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:30:35 INFO - Reading from file tmpfile_stdout 22:30:35 INFO - Output received: 22:30:35 INFO - Usage: mozinstall [options] installer 22:30:35 INFO - Options: 22:30:35 INFO - -h, --help show this help message and exit 22:30:35 INFO - -d DEST, --destination=DEST 22:30:35 INFO - Directory to install application into. [default: 22:30:35 INFO - "/builds/slave/test"] 22:30:35 INFO - --app=APP Application being installed. [default: firefox] 22:30:35 INFO - mkdir: /builds/slave/test/build/application 22:30:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 22:30:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 22:30:52 INFO - Reading from file tmpfile_stdout 22:30:52 INFO - Output received: 22:30:52 INFO - /builds/slave/test/build/application/firefox/firefox 22:30:52 INFO - Running post-action listener: _resource_record_post_action 22:30:52 INFO - [mozharness: 2016-11-04 05:30:52.844223Z] Finished install step (success) 22:30:52 INFO - [mozharness: 2016-11-04 05:30:52.844470Z] Running run-tests step. 22:30:52 INFO - Running pre-action listener: _resource_record_pre_action 22:30:52 INFO - Running main action method: run_tests 22:30:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:30:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:30:52 INFO - Python 2.7.3 22:30:52 INFO - Return code: 0 22:30:52 INFO - grabbing minidump binary from tooltool 22:30:52 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')]} 22:30:52 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 0x143d790>, '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 0x143e690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x143eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:30:52 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 22:30:52 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 22:30:52 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 22:30:52 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:30:52 INFO - Return code: 0 22:30:52 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:30:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:30:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:30:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:30:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:30:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cYOYjmA-S368aTKo2xKKNg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--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 22:30:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/cYOYjmA-S368aTKo2xKKNg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-035 --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 22:30:52 INFO - Using env: {'DISPLAY': ':0', 22:30:52 INFO - 'HOME': '/home/cltbld', 22:30:52 INFO - 'LANG': 'en_US.UTF-8', 22:30:52 INFO - 'LANGUAGE': 'en_US:en', 22:30:52 INFO - 'LOGNAME': 'cltbld', 22:30:52 INFO - 'MAIL': '/var/mail/cltbld', 22:30:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:30:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:30:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:30:52 INFO - 'MOZ_NO_REMOTE': '1', 22:30:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:30:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:30:52 INFO - 'NO_EM_RESTART': '1', 22:30:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:30:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:30:52 INFO - 'PWD': '/builds/slave/test', 22:30:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:30:52 INFO - 'SHELL': '/bin/bash', 22:30:52 INFO - 'SHLVL': '1', 22:30:52 INFO - 'TERM': 'linux', 22:30:52 INFO - 'TMOUT': '86400', 22:30:52 INFO - 'USER': 'cltbld', 22:30:52 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1478237397.368329-415775308', 22:30:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:30:52 INFO - '_': '/tools/buildbot/bin/python'} 22:30:52 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cYOYjmA-S368aTKo2xKKNg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--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 22:30:53 INFO - mozversion application_buildid: 20161103193337 22:30:53 INFO - mozversion application_changeset: 38fcc30d818f99f3798865d551acce5681b0a3c0 22:30:53 INFO - mozversion application_display_name: Nightly 22:30:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:30:53 INFO - mozversion application_name: Firefox 22:30:53 INFO - mozversion application_remotingname: firefox 22:30:53 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 22:30:53 INFO - mozversion application_vendor: Mozilla 22:30:53 INFO - mozversion application_version: 52.0a1 22:30:53 INFO - mozversion platform_buildid: 20161103193337 22:30:53 INFO - mozversion platform_changeset: 38fcc30d818f99f3798865d551acce5681b0a3c0 22:30:53 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 22:30:53 INFO - mozversion platform_version: 52.0a1 22:30:53 INFO - using testdate: 1478237453 22:30:53 INFO - actual date: 1478237453 22:30:53 INFO - starting webserver on 'localhost:57097' 22:30:53 INFO - SUITE-START | Running 2 tests 22:30:53 INFO - TEST-START | dromaeo_css 22:30:53 INFO - operating with platform_type : linux_ 22:30:53 INFO - Initialising browser for dromaeo_css test... 22:30:53 INFO - TEST-INFO | started process 9045 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWsC5qi/profile http://localhost:57097/getInfo.html) 22:30:57 INFO - PROCESS | 9045 | __metrics Screen width/height:1600/1200 22:30:57 INFO - PROCESS | 9045 | colorDepth:24 22:30:57 INFO - PROCESS | 9045 | Browser inner width/height: 1024/697 22:30:57 INFO - PROCESS | 9045 | __metrics 22:30:57 INFO - PROCESS | 9045 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 22:30:57 INFO - PROCESS | 9045 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:30:58 INFO - PROCESS | 9045 | console.error: PushService: 22:30:58 INFO - PROCESS | 9045 | stateChangeProcessEnqueue: Error transitioning state 22:30:58 INFO - PROCESS | 9045 | UnknownError 22:30:58 INFO - TEST-INFO | 9045: exit 0 22:30:58 INFO - Browser initialized. 22:30:58 INFO - Running cycle 1/1 for dromaeo_css test... 22:30:58 INFO - TEST-INFO | started process 9127 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWsC5qi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:31:11 INFO - PROCESS | 9127 | RSS: Main: 172969984 22:31:11 INFO - PROCESS | 9127 | 22:32:02 INFO - PROCESS | 9127 | 1478237522681 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] 22:34:13 INFO - PROCESS | 9127 | Cycle 1(1): loaded http://localhost:57097/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57097/tests/dromaeo/cssquery-ext.html) 22:34:14 INFO - PROCESS | 9127 | RSS: Main: 306769920 22:34:14 INFO - PROCESS | 9127 | 22:37:17 INFO - PROCESS | 9127 | Cycle 1(1): loaded http://localhost:57097/tests/dromaeo/cssquery-ext.html (next: http://localhost:57097/tests/dromaeo/cssquery-jquery.html) 22:37:17 INFO - PROCESS | 9127 | RSS: Main: 268505088 22:37:17 INFO - PROCESS | 9127 | 22:40:19 INFO - PROCESS | 9127 | Cycle 1(1): loaded http://localhost:57097/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57097/tests/dromaeo/cssquery-mootools.html) 22:40:20 INFO - PROCESS | 9127 | RSS: Main: 217354240 22:40:20 INFO - PROCESS | 9127 | 22:43:22 INFO - PROCESS | 9127 | Cycle 1(1): loaded http://localhost:57097/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57097/tests/dromaeo/cssquery-prototype.html) 22:43:23 INFO - PROCESS | 9127 | RSS: Main: 216297472 22:43:23 INFO - PROCESS | 9127 | 22:46:25 INFO - PROCESS | 9127 | Cycle 1(1): loaded http://localhost:57097/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57097/tests/dromaeo/cssquery-yui.html) 22:46:26 INFO - PROCESS | 9127 | RSS: Main: 215298048 22:46:26 INFO - PROCESS | 9127 | 22:49:29 INFO - PROCESS | 9127 | Cycle 1(1): loaded http://localhost:57097/tests/dromaeo/cssquery-yui.html (next: http://localhost:57097/tests/dromaeo/cssquery-dojo.html) 22:49:29 INFO - PROCESS | 9127 | RSS: Main: 309338112 22:49:29 INFO - PROCESS | 9127 | 22:49:29 INFO - PROCESS | 9127 | __start_tp_report 22:49:29 INFO - PROCESS | 9127 | _x_x_mozilla_page_load 22:49:29 INFO - PROCESS | 9127 | _x_x_mozilla_page_load_details 22:49:29 INFO - PROCESS | 9127 | |i|pagename|runs| 22:49:29 INFO - PROCESS | 9127 | |0;dojo.html;2985.6566494853087;3015.8539754893122;3021.033269353807;3057.067594383715;3068.125584208501;5255.237990491377;5290.92063619046;5326.627136100475;5399.892002159954;5405.783768649254;5363.329583802025;5404.513593776559;5464.180372944061;5500.28496295481;5558.6942718150485;4860.2466617674145;4886.047220791947;4932.704037757728;4940.7776650050655;4976.676516026465;4860.392450943632;5047.242913562976;5050;5058.367704036996;5103.132467480518;4967.031428871372;4984.077945580079;5015.247712843066;5040.697558146523;5079.79680812767;4712.6465515086165;4743.881402964919;4746.572808447257;4747;4778.737169455679;4759.809607615691;4767.642426818003;4780.617550595944;4840.564349208553;4870.415550133971;5271.947280527223;5273.841784746463;5280.471952804727;5291.021161085211;5314.548263397616;5608.663480191201;5618.943810561883;5626.043572592628;5683.232763576947;5692.5161361284345;5785.913211301834;5814.534837213013;5836.941630583682;5884.382139875337;5887.764489420418;5409.377921538995;5410.540104091197;5413.512783849473;5414.296141501619;5414.972925135388;5659.603827732019;5712.285964254468;5879.382664820132;5934.9406505934385;5940.1683764272175;5566.638168519034;5589.860253493695;5595.972020139955;5665.9716701417055;5673.744681489343;5616.971915140398;5652.3217213934595;5756.107803290497;5756.222909907109;5760.251167348218;5481.424450432647;5716.028275193227;5716.828495145152;5737.225474560964;5744.798932037359;5532.446755324516;5551.694656793915;5567.972160139173;5598.58010649203;5628.465295796893;4317.244482215599;4317.611414972667;4325.805338759764;4329.328954012133;4366.061296821199;3845.8846234613006;3848.230353929225;3867.45855580219;3901.0247438140464;3916.2950668879307;5344.198370244417;5399.217113518518;5399.892002160013;5408.945910540923;5416.431274716177;5742.196092547047;5746.0519014363;5798.449147330997;5799.768009279666;5816.534677225812;5416.83749487516;5460.645058071213;5464.23500709901;5472.7263636818;5482.671039737628;4793.640476964241;4826.745046287921;4990.076835785391;4993.6005119590345;5053.974730126326;5876.44173803488;5916.645001299936;5935.376785437553;5953.493953013869;6017.909731354121;5699.827006820266;5761.740721667451;5772.076467765138;5775.451332123442;5779.364269930388;5680.176374425768;5976.641401515923;5983.790567330123;5986.521078313637;5987.910181347196;5511.696856672921;5564.415736347625;5593.244911936837;5608.691521966331;5662.688552129673;5735.0537161369575;5745.827625171251;5758.481736643722;5760.711964401679;5764.481196692318;5574.9721251393485;5576.10782274834;5578.163275508706;5579.637323573955;5610.6072574921;5395.81114660985;5456.099743542271;5459.5632349412745;5487.478689524489;5491.423400542885;5474.288342515448;5516.282883225315;5521.641093328921;5536.557075434036;5567.8608034797835;5487.094629386106;5563.193336966198;5581.49766521015;5583.385827558884;5606;5523.585731070266;5556.944430555643;5572.219889215432;5577.414371490935;5659.179418984307 22:49:29 INFO - PROCESS | 9127 | |1;ext.html;553.4784318742921;579.762297458042;581.2327727399831;595.8123191194776;600.0818747316606;30063.699363006363;40012;41003.079630444314;41012.58987410132;41067.794661026724;42491.36262956058;42505.57494425055;42535.361969570484;42595.57404425955;42602.786986065104;7942.4837385570045;8702.95648521758;8855.778605534848;8860.11398860112;8863.512506812123;7917.485363451357;10328.845067323997;10536.894631053707;10546.209034322419;10919.890801092006;10651.360918344924;10700.30448020876;10704.839427408595;10714.250002499828;10730.141588672888;10514.053735163834;10560.94719526405;10576.735581610445;10586.629467968662;10643.78712425755;10389.324693894872;10470.319429237075;10473.790524189473;10476.476176191236;10478.161747060218;39984.60015399838;40145;40340;40380.59619403798;40400.79799601013;3488.5988111367265;3535;3595.892123236281;3702.685271751904;3862.806859657006;29810.40379192426;30017.399652006836;30246;30823.691763082308;31005.379892402027;4207.852725154636;4244.108737165199;4352.412424322739;4354.368616550614;4407.493138289074;329969;332273;332641.99999999756;333432;333769;56210;56565.43434565684;56571;56616;56664.716676416356;34679;35585.28829423449;37494.81252593774;37524.24951500955;37528;15046.623834404227;15071.246437678072;15134;15160.620984475476;15181.240937953058;9749.122578967785;9841.655542056136;11454.599089031844;11573.88426115745;11706.590269340531;17790.55523611894;18365.81634183668;18426.355077572487;18470.81529184718;18496.167672454772;6992.895106573405;7072.611006394594;7152.4278057755255;7167.63814875172;7294.124705035323;981.6024510073431;1017.9694609161737;1054.3094273251033;1104.618906477264;1120.882307357732;29091.85454072716;31064.22339441532;31126.68873311238;31242;31541.52687709686;1022.4734261855151;1026.4457193115784;1033.7260625934134;1096.9012788849043;1125.3641692443907;16380.918095409446;16446.682269340803;16483.752743708854;16533.58666033359;16581.502554923372;158208;158388;158856.20571897065;158860;159115;54831.45168548343;55244;55339.446605534235;55371;55415.687646852486;1180.3979970215253;1231.2673958994562;1293.6571808470574;1326.9402876870563;1358.7146699193083;27386.315342116606;31972.36055278835;32194.390280485415;32412.4920154685;32474.512882306313;47465.52534474611;47881.281780774014;48155.27767083567;48186.27720584264;48215.27677084917;361934;362514;363783;364403;365433;24764.876175619727;24970.875145624155;25357.87321063383;27063.594046089718;27277.59083613708;45183;45185.32222016738;45237.77381113073;45332.77333613443;45676.086478269586;36956;37584.43623345597;38805.6119438802;39559.80220098881;39695;37551.624483754815;38010;38602.6139738599;39371.409428859166;39910.2017959645;38496.2300753989;38696.41955370615;39081;39230.411543826296;39492.40761388524;31499.370012600077;33553.49669755004;33799.4930076054;34109.48835767515;34115.488267676505;26409.73590264073;27102.50936198528;27147.457050859266;27203.33518288032;27352.72647273502 22:49:29 INFO - PROCESS | 9127 | |2;jquery.html;365.53029357275904;396.76194283429925;399.18964502060817;405.8194103623887;413.4525768902814;13442.327883605818;15219.543413697593;16704.74942875858;18922.526936826587;19438;18752.62494750104;18790.43628691138;19282.807171928278;19355;19358.903205483988;5559.888022395517;5729.713514324288;5965.642061476304;5976.223090998186;5977.671228082476;5624.71876406178;5889.116632505115;5921.851953701149;5925.733341999598;5926.081457374114;5652.463016013493;5778.075507918734;5882.3529411764675;5887.7644894204395;5887.88224235515;5780.1907732917425;5796.275465566804;5827.242458480391;5828.504577110951;5829.417058294158;5025.7235852028125;5201.817936372209;5208;5220.765065572021;5252.107141785906;22836.200732974266;23037;23155.53688926229;23307.766922330728;23371.182008629785;2338.427085364097;2791.3858951030743;2934.9119526414243;3072.585200997859;3118.048995056507;9298.256139508823;13780.862191378159;14091.154530728189;14181.503647372292;14970.326335314938;1846.2061313635131;1907.12272354717;1949.395687336916;1956.2370675436591;2018.7173795668618;81843.26821172186;82052;82066;83802.58098709528;83973;11027.669169924915;11155.553777848958;11241.269317494336;11248.437578121062;11439.656810295704;15564.221788910509;15569.610759731097;15570;15573.532794016197;15584.92207538955;7991.200879912079;8067.39494537912;8151.429399942066;8287.378446616527;8295.751127466187;7594.772156835304;7636.50362726421;7641.923580764121;7813.843723125619;7818.022747156606;4772.785224664898;4852.611791056777;4861.368022157097;4933.925991110136;4953.2570114483115;1977.6440240756801;2004.9398518044482;2014.3956812956349;2018.9798102019085;2078.293380250722;877.394597727566;947.7914858731068;987.9456629885282;1016.217512515359;1058.2803693488502;18516.907415463105;19330.226790928486;19346.129424175946;19435.51411214731;19442.90278548598;906.5059542549326;919.852823548229;946.2713710442921;949.8717673114041;976.1214906584131;10114.291999560108;10827.891721082688;10990.340579565252;11054.557817687364;11063.336199828036;405730;412674;422798;422986;425284;82925.22202752673;84263.57868210619;87542;88761.55619221991;89241.55379223192;978.8091322192104;988.0465350936381;989.6635144050913;992.2855544008447;996.2329006664972;16015.673808845673;16521.66956660836;16557.834421656113;16898.831011689726;17456.82543174552;37990;41351;41409.1718165629;42134;42331.3650295252;46790;55159.72420138034;56702.43297566971;57205.713971429876;57287.42712572821;8185.795355115932;8248.917510824815;8400.789980250544;8442.53566053873;8722.215000649972;12969.675758106123;13286.800697989345;13362.398692059076;13367.665808354868;13480.73038539204;11324.32054076725;11404.543818247177;11450.312981221154;12074.215176013531;12132.818007730068;11188.88811111911;11255.718607034889;11446.599369022044;11468.254563453349;11556.537738490702;11702.180847340946;11732.824007639721;11756.23584467007;11809.645710628694;11925.522979081086;7323.450741194325;7640.3505702015955;8330.625121869409;8373.455725377831;9047.68333108338;11258.605948791754;11758.176927615326;11788.29270243788;11921;12202.938985305016 22:49:29 INFO - PROCESS | 9127 | |3;mootools.html;1310.3120861547693;1460.9050411723238;1495.970080598388;1603.182376987736;1735.3405705831783;7497.3252407283335;8211.219934106257;8470.40707150499;8472.27985621223;8698.608562614681;8531.402801803877;8594;8601.483910965346;8610.56947152643;8613.310935125191;2303.481716613757;2318.7565305642916;2334.3347146063375;2337.2053501809382;2339.450229196146;2270.20542810016;2321.1759825261956;2343.250159948817;2345.2143531916745;2347.096367898363;2269.4780200553882;2273.6134857074253;2305.6772051912744;2321.292005938188;2327.208749025331;2209.3151123151792;2225.6995305633855;2248.775122487738;2249.044156233594;2249.876256805875;2225.376894469551;2245.41619179013;2245.955080898389;2247.494313779403;2247.6291411917014;8417.11620279868;8535.573221338907;8581.656733730642;8596.140385961417;8599.871001934976;4998.2002879539095;5208.869778255536;5212.478752124757;5220.660657057279;5231.2937753403185;3345.498175273704;3436.3642726095754;3483.216276337829;3498.8628695674006;3504.754667173299;1161.1581603337597;1172.5016867831137;1196.1387800783423;1198.3768440411025;1207.812789017695;12074.215176013531;12110.091743119214;12234.265944043296;12251.938740306241;12264.325462099581;4313.568643135662;4606.884827879263;4607.654425918069;4646.953530464652;4653.441587009513;9000.954995224982;9004.32467564922;9005.594748236344;9009.414388064755;9014.59434325457;5149.562287205629;5165.74171291434;5169.121249387613;5179.741012949337;5181.611379146579;4207.095474473003;4329.523752387235;4640.164770341371;4718.575328220477;4824.614030877521;3634.672879440863;3734.2531493700826;3757.492738480325;3783.754055986352;3833.9041523961564;1124.9437528123724;1134.2003887259461;1148.724306166514;1172.8709841917382;1176.0885313881638;861.5692153923038;923.4920793563513;967.3760424526282;968.85674903613;976.2043934193609;5702.54379649627;5897.233359663216;5960.0761881908375;6176.227971503562;6346.587471814318;439.79329715033884;453.22724754293847;455.9672342145044;464.30818081058976;465.72754938361436;4001.199760047944;4022.7586344819406;4035.071933455263;4041.7372870763893;4062.512498500199;10414.271001029856;10686.412247326472;10723.83914241287;10794.190435717353;10803.459827008619;10582.259241852997;10587.57649694019;10590;10596;10604;7892.131865494791;8053.6778528858185;8112.675492980215;8126.349892008625;8142.226488483585;5601.6358936668985;5680.4319568042865;5702.543796496353;5741.224934633771;5859.824205273849;9636.084571965652;9755.463449510062;9843.753906152404;9953.850692239477;9992.900070999487;9452.196563292197;9821.410715357102;9851.556679949563;10182.185425166117;10190.541425635716;6626.436752875867;6786.049953006484;6810.8637827244165;7521.135069466863;7676.385889128745;7582.810429739301;7597.468177227541;8045.477043992122;8138.3489320853005;8331.125231850569;7323.890141647773;7511.5868627223845;7562.621868906423;7860.213978602094;7885.526868387686;7248.456365772483;7271.781846544613;7441.888371674321;7499.1750907399055;7773.1449540549365;6540.084388185563;6632.668366581748;6638.17022872141;6642.435392991457;6644.800655980328;4946.480619534897;5027.723475208899;5048.268001139741;5049.671771334852;5188.948110518846;4856.781444835062;4898.387701537308;4924.285978533164;4989.451160372429;5073.822416215415 22:49:29 INFO - PROCESS | 9127 | |4;prototype.html;322.1671977937033;330.1218758103445;337.1638336924427;340.9624941256463;343.18835952971216;5179.7827510535;5189.870253243671;5197.480251974801;5199;5201.453847346022;5112.744362781856;5115.462876397981;5124.538791508773;5129.769160387782;5158.92261616076;3971.5631280559114;3991.8602848900296;4060.959390406095;4101.26177288088;4105.876823695279;4024.718269721106;4046.3728122141115;4049.676025917934;4054.858079967202;4068.247374235761;4016.7188296819236;4016.7389119707273;4019.738716983387;4021.9798901005456;4025.7584544927254;3823.4647149399248;3903.355946268862;3939.6454319111417;4015.6586690131376;4078.9592104078874;3548.7870727756417;3562.786232826039;3588.5334906461994;3594.8202589870402;3631.364511210554;5054.9494505054845;5056.443791182967;5080.644354895159;5093.300550708131;5145.614078944094;4394.4287242658565;4450.688451808374;4580.404547408849;4622.751856998591;4685.742284174369;5349.43830897754;5352.678839269656;5361.785528578891;5369.838904832861;5372.059889519319;3917.099067214528;3926.666233370167;3936.6457018868155;3936.8031598420253;3952.7233093683453;5331.147016477384;5526.47498487643;5535.308086489189;5541.833744987616;5657.632253903483;3991.261616600938;4009.959900401017;4017.116234428421;4020.8592699255387;4022.336314508132;4061.817218225187;4083.5303940047274;4092.2224777292813;4098.954766534574;4102.733322334038;4438.663118128463;4589.35748995141;4618.261078227468;4651.627669888314;4666.813327466931;5407.432219616962;5421.213923981;5425.7174409481695;5448.338256831705;5462.262594549685;1408.5774267719644;1426.9001169918213;1428.52144531581;1435.131745294103;1435.6913263648157;876.9035406105323;923.7459698582943;937.6577549194632;938.8263171313329;943.3585162089707;4603.815847366068;4619.676622636383;4643.212363240177;4647.674662773641;4656.045510670305;4815.422149342101;4828.686135401188;4836.911694868626;4850.320955066293;4905.3623029006;4298.839313385369;4621.584057434847;4633.745144017112;4636.8145274188655;4645.583097155332;2533.936651583725;2582.8893575762604;2584.694729161761;2587.611858221282;2594.3514121469634;4974.602031837444;4981.875453113701;4983.975080124576;5008.899822003539;5011.373578302712;4896.4858689837765;4924.926126108111;4956.330895329156;4988.426330971912;5302.443243459458;5792.362840087587;5804.8390321935785;5818.31268932005;5821.767129314865;5829.38791426911;5549.611527193058;5581.553475721851;5613.494785469327;5631.761012577226;5677.5457963362005;5404.864878378072;5493.862653433696;5515.393306736176;5527.585431092764;5581.4883302514845;5692.288463942007;5706.543476521785;5734.311882574118;5760.8271751847515;5764.164196191445;3964.4647972524494;3997.8400863965117;4013.4983127109113;4060.903556039794;4065.414488349487;5342.679439233659;5585.804496842754;5613.270274864241;5630.887382252414;5700.914486282626;5410.242566040679;5441.238226648352;5441.945580544144;5443.183522471661;5451.4276001019325;5220.1647736362;5434.538064264581;5450.700211488248;5457.208704737712;5496.972515137399;5402.459754024566;5404.243405923095;5411.323584551931;5411.431799660979;5415.2689386933835;5223.589630799739;5325.520703136736;5345;5458.454154584669;5478.506934375925;5360.16917377807;5365.195220716923;5368.892622147613;5390.490662614474;5392.541633961 22:49:29 INFO - PROCESS | 9127 | |5;yui.html;679.9524033317666;747.3684736397742;752.1049950558835;753.3107206905681;754.5661244784251;11365.772684546315;11689.707757306072;11813.23213991091;11873.168878178509;11880.584179553718;11189.048930840889;11285.717857053578;11308.491117899694;11317.773644527104;11319.490622921967;650.4536189600734;764.8585011772826;779.12737733738;780.9219078092202;781.1758594682636;657.7270432770418;767.1944458318743;785.1128225105651;790.6876783670447;791.8297566023303;815.6248125862111;822.7696245051366;825.1871906172415;826.1036775098995;826.3348004856089;675.4360109308733;818.6316157728986;818.8976377952756;819.5369616166847;820.1757233979841;503.4889587069128;575.7927146227355;591.3701907468533;594.5481434110108;594.5897330841749;11593.188476806627;11630.30218186903;11640.825387619192;11642.534298628045;11644.88355116455;12406.193597416139;12529.561465348761;12878.162919410302;12926.935365323206;13257.734845303137;11535.653930382103;11646.301221926711;11667.591634292843;11807.763844723144;11826;3620.619834917322;3665.376885929398;3729.850805967758;3831.7509361891666;4088.1823635273063;38514.22971540582;45478.54521454776;59370.109448358744;59646.40353596495;59872;9749.171320437701;10065.547050382751;10395.68812935598;10397;10655.733606659896;17571.648567028842;17621.647567048843;17762.625101230726;17800.821991779918;18031.188596513188;7741.419393545507;7776.92223077762;7856.33221176195;7880.487768295043;8004.799880003046;4165.479315085614;4249.171411574714;4262.126264115849;4330.545292744279;4489.079738653568;3001.4897467430587;3070.585470961392;3073.707997740211;3088.258817883692;3094.5822313987774;547.9780808767684;550.3203543623622;552.4585905812246;561.6040691312614;568.7724910035936;538.3593523706776;550.9338879334506;574.2018594154129;671.0705672643327;687.2920891481782;8371.58142092893;8838.248748856291;8937.910620893837;9003.279737620975;9032.774180645405;623.7643337287429;632.7785275153659;679.5523300001502;682.9897551536731;700.2332445971653;4308.698391112592;4357.869263922087;4378.277584198571;4564;4631.606313463393;54249.1862622061;70485.29514704889;71339.64330178419;73345;85725.57137214275;44596.55403445989;45104.3234351485;68568;69878;70736;62852;63346;63621;64462.67768661126;65833;8761.780955476164;8802.29581633455;8854.645814167361;8868.201861832466;8932.553372331487;40729.59270407258;42472.36291455569;42983.78508107439;43761;44590.3311450322;53916.921661567336;54805.72597136989;61204.38795612165;63308;64686.676566616865;11236.325820450473;11377.715557111138;11452;11603.36181510025;11790.174687771774;12686.619401417973;12940.482380705043;13048.934755326163;13196.538121165713;13450.596482105553;11782.17524773292;11905.46425410876;12050.819237711266;12213.938930305647;13263.535776247785;12017.441136165442;12144.332061736335;12246;12552.623421297376;12877.8068328977;12135;12357.629271121521;12512.937435312766;12550.623481295575;12981.675458113623;9730.95134524323;9743.80512389734;9916.603335866484;10035.448050357301;10155.695329140137;9761.365511241747;9763.804723905625;10134.695959121238;10219.693409197736;10295.279330446796 22:49:29 INFO - PROCESS | 9127 | __end_tp_report 22:49:29 INFO - PROCESS | 9127 | __start_cc_report 22:49:29 INFO - PROCESS | 9127 | _x_x_mozilla_cycle_collect,1255 22:49:29 INFO - PROCESS | 9127 | __end_cc_report 22:49:29 INFO - PROCESS | 9127 | __startTimestamp1478238569275__endTimestamp 22:49:29 INFO - PROCESS | 9127 | 22:49:29 INFO - PROCESS | 9127 | ------- Summary: start ------- 22:49:29 INFO - PROCESS | 9127 | Number of tests: 6 22:49:29 INFO - PROCESS | 9127 | 22:49:29 INFO - PROCESS | 9127 | [#0] dojo.html Cycles:165 Average:5314.21 Median:5511.70 stddev:611.88 (11.1%) stddev-sans-first:585.86 22:49:29 INFO - PROCESS | 9127 | Values: 2985.7 3015.9 3021.0 3057.1 3068.1 5255.2 5290.9 5326.6 5399.9 5405.8 5363.3 5404.5 5464.2 5500.3 5558.7 4860.2 4886.0 4932.7 4940.8 4976.7 4860.4 5047.2 5050.0 5058.4 5103.1 4967.0 4984.1 5015.2 5040.7 5079.8 4712.6 4743.9 4746.6 4747.0 4778.7 4759.8 4767.6 4780.6 4840.6 4870.4 5271.9 5273.8 5280.5 5291.0 5314.5 5608.7 5618.9 5626.0 5683.2 5692.5 5785.9 5814.5 5836.9 5884.4 5887.8 5409.4 5410.5 5413.5 5414.3 5415.0 5659.6 5712.3 5879.4 5934.9 5940.2 5566.6 5589.9 5596.0 5666.0 5673.7 5617.0 5652.3 5756.1 5756.2 5760.3 5481.4 5716.0 5716.8 5737.2 5744.8 5532.4 5551.7 5568.0 5598.6 5628.5 4317.2 4317.6 4325.8 4329.3 4366.1 3845.9 3848.2 3867.5 3901.0 3916.3 5344.2 5399.2 5399.9 5408.9 5416.4 5742.2 5746.1 5798.4 5799.8 5816.5 5416.8 5460.6 5464.2 5472.7 5482.7 4793.6 4826.7 4990.1 4993.6 5054.0 5876.4 5916.6 5935.4 5953.5 6017.9 5699.8 5761.7 5772.1 5775.5 5779.4 5680.2 5976.6 5983.8 5986.5 5987.9 5511.7 5564.4 5593.2 5608.7 5662.7 5735.1 5745.8 5758.5 5760.7 5764.5 5575.0 5576.1 5578.2 5579.6 5610.6 5395.8 5456.1 5459.6 5487.5 5491.4 5474.3 5516.3 5521.6 5536.6 5567.9 5487.1 5563.2 5581.5 5583.4 5606.0 5523.6 5556.9 5572.2 5577.4 5659.2 22:49:29 INFO - PROCESS | 9127 | 22:49:29 INFO - PROCESS | 9127 | [#1] ext.html Cycles:180 Average:45641.20 Median:34112.49 stddev:78609.60 (230.4%) stddev-sans-first:78757.22 22:49:29 INFO - PROCESS | 9127 | Values: 553.5 579.8 581.2 595.8 600.1 30063.7 40012.0 41003.1 41012.6 41067.8 42491.4 42505.6 42535.4 42595.6 42602.8 7942.5 8703.0 8855.8 8860.1 8863.5 7917.5 10328.8 10536.9 10546.2 10919.9 10651.4 10700.3 10704.8 10714.3 10730.1 10514.1 10560.9 10576.7 10586.6 10643.8 10389.3 10470.3 10473.8 10476.5 10478.2 39984.6 40145.0 40340.0 40380.6 40400.8 3488.6 3535.0 3595.9 3702.7 3862.8 29810.4 30017.4 30246.0 30823.7 31005.4 4207.9 4244.1 4352.4 4354.4 4407.5 329969.0 332273.0 332642.0 333432.0 333769.0 56210.0 56565.4 56571.0 56616.0 56664.7 34679.0 35585.3 37494.8 37524.2 37528.0 15046.6 15071.2 15134.0 15160.6 15181.2 9749.1 9841.7 11454.6 11573.9 11706.6 17790.6 18365.8 18426.4 18470.8 18496.2 6992.9 7072.6 7152.4 7167.6 7294.1 981.6 1018.0 1054.3 1104.6 1120.9 29091.9 31064.2 31126.7 31242.0 31541.5 1022.5 1026.4 1033.7 1096.9 1125.4 16380.9 16446.7 16483.8 16533.6 16581.5 158208.0 158388.0 158856.2 158860.0 159115.0 54831.5 55244.0 55339.4 55371.0 55415.7 1180.4 1231.3 1293.7 1326.9 1358.7 27386.3 31972.4 32194.4 32412.5 32474.5 47465.5 47881.3 48155.3 48186.3 48215.3 361934.0 362514.0 363783.0 364403.0 365433.0 24764.9 24970.9 25357.9 27063.6 27277.6 45183.0 45185.3 45237.8 45332.8 45676.1 36956.0 37584.4 38805.6 39559.8 39695.0 37551.6 38010.0 38602.6 39371.4 39910.2 38496.2 38696.4 39081.0 39230.4 39492.4 31499.4 33553.5 33799.5 34109.5 34115.5 26409.7 27102.5 27147.5 27203.3 27352.7 22:49:29 INFO - PROCESS | 9127 | 22:49:29 INFO - PROCESS | 9127 | [#2] jquery.html Cycles:180 Average:26790.31 Median:203067.91 stddev:69228.62 (34.1%) stddev-sans-first:69394.39 22:49:29 INFO - PROCESS | 9127 | Values: 365.5 396.8 399.2 405.8 413.5 13442.3 15219.5 16704.7 18922.5 19438.0 18752.6 18790.4 19282.8 19355.0 19358.9 5559.9 5729.7 5965.6 5976.2 5977.7 5624.7 5889.1 5921.9 5925.7 5926.1 5652.5 5778.1 5882.4 5887.8 5887.9 5780.2 5796.3 5827.2 5828.5 5829.4 5025.7 5201.8 5208.0 5220.8 5252.1 22836.2 23037.0 23155.5 23307.8 23371.2 2338.4 2791.4 2934.9 3072.6 3118.0 9298.3 13780.9 14091.2 14181.5 14970.3 1846.2 1907.1 1949.4 1956.2 2018.7 81843.3 82052.0 82066.0 83802.6 83973.0 11027.7 11155.6 11241.3 11248.4 11439.7 15564.2 15569.6 15570.0 15573.5 15584.9 7991.2 8067.4 8151.4 8287.4 8295.8 7594.8 7636.5 7641.9 7813.8 7818.0 4772.8 4852.6 4861.4 4933.9 4953.3 1977.6 2004.9 2014.4 2019.0 2078.3 877.4 947.8 987.9 1016.2 1058.3 18516.9 19330.2 19346.1 19435.5 19442.9 906.5 919.9 946.3 949.9 976.1 10114.3 10827.9 10990.3 11054.6 11063.3 405730.0 412674.0 422798.0 422986.0 425284.0 82925.2 84263.6 87542.0 88761.6 89241.6 978.8 988.0 989.7 992.3 996.2 16015.7 16521.7 16557.8 16898.8 17456.8 37990.0 41351.0 41409.2 42134.0 42331.4 46790.0 55159.7 56702.4 57205.7 57287.4 8185.8 8248.9 8400.8 8442.5 8722.2 12969.7 13286.8 13362.4 13367.7 13480.7 11324.3 11404.5 11450.3 12074.2 12132.8 11188.9 11255.7 11446.6 11468.3 11556.5 11702.2 11732.8 11756.2 11809.6 11925.5 7323.5 7640.4 8330.6 8373.5 9047.7 11258.6 11758.2 11788.3 11921.0 12202.9 22:49:29 INFO - PROCESS | 9127 | 22:49:29 INFO - PROCESS | 9127 | [#3] mootools.html Cycles:180 Average:5597.35 Median:4967.97 stddev:3184.57 (64.1%) stddev-sans-first:3177.21 22:49:29 INFO - PROCESS | 9127 | Values: 1310.3 1460.9 1496.0 1603.2 1735.3 7497.3 8211.2 8470.4 8472.3 8698.6 8531.4 8594.0 8601.5 8610.6 8613.3 2303.5 2318.8 2334.3 2337.2 2339.5 2270.2 2321.2 2343.3 2345.2 2347.1 2269.5 2273.6 2305.7 2321.3 2327.2 2209.3 2225.7 2248.8 2249.0 2249.9 2225.4 2245.4 2246.0 2247.5 2247.6 8417.1 8535.6 8581.7 8596.1 8599.9 4998.2 5208.9 5212.5 5220.7 5231.3 3345.5 3436.4 3483.2 3498.9 3504.8 1161.2 1172.5 1196.1 1198.4 1207.8 12074.2 12110.1 12234.3 12251.9 12264.3 4313.6 4606.9 4607.7 4647.0 4653.4 9001.0 9004.3 9005.6 9009.4 9014.6 5149.6 5165.7 5169.1 5179.7 5181.6 4207.1 4329.5 4640.2 4718.6 4824.6 3634.7 3734.3 3757.5 3783.8 3833.9 1124.9 1134.2 1148.7 1172.9 1176.1 861.6 923.5 967.4 968.9 976.2 5702.5 5897.2 5960.1 6176.2 6346.6 439.8 453.2 456.0 464.3 465.7 4001.2 4022.8 4035.1 4041.7 4062.5 10414.3 10686.4 10723.8 10794.2 10803.5 10582.3 10587.6 10590.0 10596.0 10604.0 7892.1 8053.7 8112.7 8126.3 8142.2 5601.6 5680.4 5702.5 5741.2 5859.8 9636.1 9755.5 9843.8 9953.9 9992.9 9452.2 9821.4 9851.6 10182.2 10190.5 6626.4 6786.0 6810.9 7521.1 7676.4 7582.8 7597.5 8045.5 8138.3 8331.1 7323.9 7511.6 7562.6 7860.2 7885.5 7248.5 7271.8 7441.9 7499.2 7773.1 6540.1 6632.7 6638.2 6642.4 6644.8 4946.5 5027.7 5048.3 5049.7 5188.9 4856.8 4898.4 4924.3 4989.5 5073.8 22:49:29 INFO - PROCESS | 9127 | 22:49:29 INFO - PROCESS | 9127 | [#4] prototype.html Cycles:180 Average:4475.56 Median:4986.20 stddev:1309.00 (26.3%) stddev-sans-first:1275.01 22:49:29 INFO - PROCESS | 9127 | Values: 322.2 330.1 337.2 341.0 343.2 5179.8 5189.9 5197.5 5199.0 5201.5 5112.7 5115.5 5124.5 5129.8 5158.9 3971.6 3991.9 4061.0 4101.3 4105.9 4024.7 4046.4 4049.7 4054.9 4068.2 4016.7 4016.7 4019.7 4022.0 4025.8 3823.5 3903.4 3939.6 4015.7 4079.0 3548.8 3562.8 3588.5 3594.8 3631.4 5054.9 5056.4 5080.6 5093.3 5145.6 4394.4 4450.7 4580.4 4622.8 4685.7 5349.4 5352.7 5361.8 5369.8 5372.1 3917.1 3926.7 3936.6 3936.8 3952.7 5331.1 5526.5 5535.3 5541.8 5657.6 3991.3 4010.0 4017.1 4020.9 4022.3 4061.8 4083.5 4092.2 4099.0 4102.7 4438.7 4589.4 4618.3 4651.6 4666.8 5407.4 5421.2 5425.7 5448.3 5462.3 1408.6 1426.9 1428.5 1435.1 1435.7 876.9 923.7 937.7 938.8 943.4 4603.8 4619.7 4643.2 4647.7 4656.0 4815.4 4828.7 4836.9 4850.3 4905.4 4298.8 4621.6 4633.7 4636.8 4645.6 2533.9 2582.9 2584.7 2587.6 2594.4 4974.6 4981.9 4984.0 5008.9 5011.4 4896.5 4924.9 4956.3 4988.4 5302.4 5792.4 5804.8 5818.3 5821.8 5829.4 5549.6 5581.6 5613.5 5631.8 5677.5 5404.9 5493.9 5515.4 5527.6 5581.5 5692.3 5706.5 5734.3 5760.8 5764.2 3964.5 3997.8 4013.5 4060.9 4065.4 5342.7 5585.8 5613.3 5630.9 5700.9 5410.2 5441.2 5441.9 5443.2 5451.4 5220.2 5434.5 5450.7 5457.2 5497.0 5402.5 5404.2 5411.3 5411.4 5415.3 5223.6 5325.5 5345.0 5458.5 5478.5 5360.2 5365.2 5368.9 5390.5 5392.5 22:49:29 INFO - PROCESS | 9127 | 22:49:29 INFO - PROCESS | 9127 | [#5] yui.html Cycles:180 Average:15723.85 Median:25021.27 stddev:20257.71 (81.0%) stddev-sans-first:20283.04 22:49:29 INFO - PROCESS | 9127 | Values: 680.0 747.4 752.1 753.3 754.6 11365.8 11689.7 11813.2 11873.2 11880.6 11189.0 11285.7 11308.5 11317.8 11319.5 650.5 764.9 779.1 780.9 781.2 657.7 767.2 785.1 790.7 791.8 815.6 822.8 825.2 826.1 826.3 675.4 818.6 818.9 819.5 820.2 503.5 575.8 591.4 594.5 594.6 11593.2 11630.3 11640.8 11642.5 11644.9 12406.2 12529.6 12878.2 12926.9 13257.7 11535.7 11646.3 11667.6 11807.8 11826.0 3620.6 3665.4 3729.9 3831.8 4088.2 38514.2 45478.5 59370.1 59646.4 59872.0 9749.2 10065.5 10395.7 10397.0 10655.7 17571.6 17621.6 17762.6 17800.8 18031.2 7741.4 7776.9 7856.3 7880.5 8004.8 4165.5 4249.2 4262.1 4330.5 4489.1 3001.5 3070.6 3073.7 3088.3 3094.6 548.0 550.3 552.5 561.6 568.8 538.4 550.9 574.2 671.1 687.3 8371.6 8838.2 8937.9 9003.3 9032.8 623.8 632.8 679.6 683.0 700.2 4308.7 4357.9 4378.3 4564.0 4631.6 54249.2 70485.3 71339.6 73345.0 85725.6 44596.6 45104.3 68568.0 69878.0 70736.0 62852.0 63346.0 63621.0 64462.7 65833.0 8761.8 8802.3 8854.6 8868.2 8932.6 40729.6 42472.4 42983.8 43761.0 44590.3 53916.9 54805.7 61204.4 63308.0 64686.7 11236.3 11377.7 11452.0 11603.4 11790.2 12686.6 12940.5 13048.9 13196.5 13450.6 11782.2 11905.5 12050.8 12213.9 13263.5 12017.4 12144.3 12246.0 12552.6 12877.8 12135.0 12357.6 12512.9 12550.6 12981.7 9731.0 9743.8 9916.6 10035.4 10155.7 9761.4 9763.8 10134.7 10219.7 10295.3 22:49:29 INFO - PROCESS | 9127 | -------- Summary: end -------- 22:49:29 INFO - PROCESS | 9127 | 22:49:30 INFO - TEST-INFO | 9127: exit 0 22:49:30 INFO - TEST-OK | dromaeo_css | took 1117097ms 22:49:30 INFO - TEST-START | kraken 22:49:30 INFO - operating with platform_type : linux_ 22:49:30 INFO - Initialising browser for kraken test... 22:49:30 INFO - TEST-INFO | started process 9434 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7xXIvz/profile http://localhost:57097/getInfo.html) 22:49:34 INFO - PROCESS | 9434 | __metrics Screen width/height:1600/1200 22:49:34 INFO - PROCESS | 9434 | colorDepth:24 22:49:34 INFO - PROCESS | 9434 | Browser inner width/height: 1024/697 22:49:34 INFO - PROCESS | 9434 | __metrics 22:49:34 INFO - PROCESS | 9434 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 22:49:34 INFO - PROCESS | 9434 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:49:35 INFO - PROCESS | 9434 | console.error: PushService: 22:49:35 INFO - PROCESS | 9434 | stateChangeProcessEnqueue: Error transitioning state 22:49:35 INFO - PROCESS | 9434 | UnknownError 22:49:35 INFO - TEST-INFO | 9434: exit 0 22:49:35 INFO - Browser initialized. 22:49:35 INFO - Running cycle 1/1 for kraken test... 22:49:35 INFO - TEST-INFO | started process 9520 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7xXIvz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:49:47 INFO - PROCESS | 9520 | RSS: Main: 172412928 22:49:47 INFO - PROCESS | 9520 | 22:49:50 INFO - PROCESS | 9520 | Cycle 1(1): loaded http://localhost:57097/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57097/tests/kraken/driver.html?testName=audio-beat-detection) 22:49:51 INFO - PROCESS | 9520 | RSS: Main: 233357312 22:49:51 INFO - PROCESS | 9520 | 22:49:54 INFO - PROCESS | 9520 | Cycle 1(1): loaded http://localhost:57097/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57097/tests/kraken/driver.html?testName=audio-dft) 22:49:54 INFO - PROCESS | 9520 | RSS: Main: 216588288 22:49:54 INFO - PROCESS | 9520 | 22:49:57 INFO - PROCESS | 9520 | Cycle 1(1): loaded http://localhost:57097/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57097/tests/kraken/driver.html?testName=audio-fft) 22:49:58 INFO - PROCESS | 9520 | RSS: Main: 297361408 22:49:58 INFO - PROCESS | 9520 | 22:50:00 INFO - PROCESS | 9520 | Cycle 1(1): loaded http://localhost:57097/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57097/tests/kraken/driver.html?testName=audio-oscillator) 22:50:01 INFO - PROCESS | 9520 | RSS: Main: 209739776 22:50:01 INFO - PROCESS | 9520 | 22:50:03 INFO - PROCESS | 9520 | Cycle 1(1): loaded http://localhost:57097/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57097/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:50:03 INFO - PROCESS | 9520 | RSS: Main: 213471232 22:50:03 INFO - PROCESS | 9520 | 22:50:06 INFO - PROCESS | 9520 | Cycle 1(1): loaded http://localhost:57097/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57097/tests/kraken/driver.html?testName=imaging-darkroom) 22:50:07 INFO - PROCESS | 9520 | RSS: Main: 248258560 22:50:07 INFO - PROCESS | 9520 | 22:50:10 INFO - PROCESS | 9520 | Cycle 1(1): loaded http://localhost:57097/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57097/tests/kraken/driver.html?testName=imaging-desaturate) 22:50:11 INFO - PROCESS | 9520 | RSS: Main: 246710272 22:50:11 INFO - PROCESS | 9520 | 22:50:13 INFO - PROCESS | 9520 | Cycle 1(1): loaded http://localhost:57097/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57097/tests/kraken/driver.html?testName=json-parse-financial) 22:50:14 INFO - PROCESS | 9520 | RSS: Main: 247033856 22:50:14 INFO - PROCESS | 9520 | 22:50:15 INFO - PROCESS | 9520 | Cycle 1(1): loaded http://localhost:57097/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57097/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:50:16 INFO - PROCESS | 9520 | RSS: Main: 209211392 22:50:16 INFO - PROCESS | 9520 | 22:50:18 INFO - PROCESS | 9520 | Cycle 1(1): loaded http://localhost:57097/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57097/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:50:18 INFO - PROCESS | 9520 | RSS: Main: 217276416 22:50:18 INFO - PROCESS | 9520 | 22:50:21 INFO - PROCESS | 9520 | Cycle 1(1): loaded http://localhost:57097/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57097/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:50:21 INFO - PROCESS | 9520 | RSS: Main: 225206272 22:50:21 INFO - PROCESS | 9520 | 22:50:24 INFO - PROCESS | 9520 | Cycle 1(1): loaded http://localhost:57097/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57097/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:50:25 INFO - PROCESS | 9520 | RSS: Main: 229732352 22:50:25 INFO - PROCESS | 9520 | 22:50:27 INFO - PROCESS | 9520 | Cycle 1(1): loaded http://localhost:57097/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57097/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:50:28 INFO - PROCESS | 9520 | RSS: Main: 231079936 22:50:28 INFO - PROCESS | 9520 | 22:50:30 INFO - PROCESS | 9520 | Cycle 1(1): loaded http://localhost:57097/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57097/tests/kraken/driver.html?testName=ai-astar) 22:50:30 INFO - PROCESS | 9520 | RSS: Main: 281735168 22:50:30 INFO - PROCESS | 9520 | 22:50:30 INFO - PROCESS | 9520 | __start_tp_report 22:50:30 INFO - PROCESS | 9520 | _x_x_mozilla_page_load 22:50:30 INFO - PROCESS | 9520 | _x_x_mozilla_page_load_details 22:50:30 INFO - PROCESS | 9520 | |i|pagename|runs| 22:50:30 INFO - PROCESS | 9520 | |0;ai-astar;105;110;111;112;112;111;111;111;112;112 22:50:30 INFO - PROCESS | 9520 | |1;audio-beat-detection;152;122;128;174;125;123;127;124;153;124 22:50:30 INFO - PROCESS | 9520 | |2;audio-dft;161;159;160;162;162;160;164;162;162;162 22:50:30 INFO - PROCESS | 9520 | |3;audio-fft;74;77;75;116;76;75;74;76;110;76 22:50:30 INFO - PROCESS | 9520 | |4;audio-oscillator;82;82;128;84;83;82;101;84;83;111 22:50:30 INFO - PROCESS | 9520 | |5;imaging-gaussian-blur;117;116;118;116;116;117;117;118;118;118 22:50:30 INFO - PROCESS | 9520 | |6;imaging-darkroom;145;147;145;145;145;146;145;145;147;145 22:50:30 INFO - PROCESS | 9520 | |7;imaging-desaturate;85;84;84;85;84;85;85;85;84;84 22:50:30 INFO - PROCESS | 9520 | |8;json-parse-financial;52;51;51;50;55;52;52;52;52;52 22:50:30 INFO - PROCESS | 9520 | |9;json-stringify-tinderbox;60;61;61;61;61;60;61;61;61;61 22:50:30 INFO - PROCESS | 9520 | |10;stanford-crypto-aes;80;80;80;79;84;79;79;80;79;86 22:50:30 INFO - PROCESS | 9520 | |11;stanford-crypto-ccm;135;141;134;138;131;136;149;134;220;135 22:50:30 INFO - PROCESS | 9520 | |12;stanford-crypto-pbkdf2;167;163;166;166;166;164;165;166;163;171 22:50:30 INFO - PROCESS | 9520 | |13;stanford-crypto-sha256-iterative;69;64;64;63;63;63;69;63;64;63 22:50:30 INFO - PROCESS | 9520 | __end_tp_report 22:50:30 INFO - PROCESS | 9520 | __start_cc_report 22:50:30 INFO - PROCESS | 9520 | _x_x_mozilla_cycle_collect,1658 22:50:30 INFO - PROCESS | 9520 | __end_cc_report 22:50:30 INFO - PROCESS | 9520 | __startTimestamp1478238630283__endTimestamp 22:50:30 INFO - PROCESS | 9520 | 22:50:30 INFO - PROCESS | 9520 | ------- Summary: start ------- 22:50:30 INFO - PROCESS | 9520 | Number of tests: 14 22:50:30 INFO - PROCESS | 9520 | 22:50:30 INFO - PROCESS | 9520 | [#0] ai-astar Cycles:10 Average:110.70 Median:111.50 stddev:2.11 (1.9%) stddev-sans-first:0.71 22:50:30 INFO - PROCESS | 9520 | Values: 105.0 110.0 111.0 112.0 112.0 111.0 111.0 111.0 112.0 112.0 22:50:30 INFO - PROCESS | 9520 | 22:50:30 INFO - PROCESS | 9520 | [#1] audio-beat-detection Cycles:10 Average:135.20 Median:127.50 stddev:17.96 (14.1%) stddev-sans-first:17.99 22:50:30 INFO - PROCESS | 9520 | Values: 152.0 122.0 128.0 174.0 125.0 123.0 127.0 124.0 153.0 124.0 22:50:30 INFO - PROCESS | 9520 | 22:50:30 INFO - PROCESS | 9520 | [#2] audio-dft Cycles:10 Average:161.40 Median:162.00 stddev:1.43 (0.9%) stddev-sans-first:1.51 22:50:30 INFO - PROCESS | 9520 | Values: 161.0 159.0 160.0 162.0 162.0 160.0 164.0 162.0 162.0 162.0 22:50:30 INFO - PROCESS | 9520 | 22:50:30 INFO - PROCESS | 9520 | [#3] audio-fft Cycles:10 Average:82.90 Median:75.50 stddev:15.95 (21.1%) stddev-sans-first:16.59 22:50:30 INFO - PROCESS | 9520 | Values: 74.0 77.0 75.0 116.0 76.0 75.0 74.0 76.0 110.0 76.0 22:50:30 INFO - PROCESS | 9520 | 22:50:30 INFO - PROCESS | 9520 | [#4] audio-oscillator Cycles:10 Average:92.00 Median:82.50 stddev:16.08 (19.5%) stddev-sans-first:16.65 22:50:30 INFO - PROCESS | 9520 | Values: 82.0 82.0 128.0 84.0 83.0 82.0 101.0 84.0 83.0 111.0 22:50:30 INFO - PROCESS | 9520 | 22:50:30 INFO - PROCESS | 9520 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.50 stddev:0.88 (0.7%) stddev-sans-first:0.93 22:50:30 INFO - PROCESS | 9520 | Values: 117.0 116.0 118.0 116.0 116.0 117.0 117.0 118.0 118.0 118.0 22:50:30 INFO - PROCESS | 9520 | 22:50:30 INFO - PROCESS | 9520 | [#6] imaging-darkroom Cycles:10 Average:145.50 Median:145.00 stddev:0.85 (0.6%) stddev-sans-first:0.88 22:50:30 INFO - PROCESS | 9520 | Values: 145.0 147.0 145.0 145.0 145.0 146.0 145.0 145.0 147.0 145.0 22:50:30 INFO - PROCESS | 9520 | 22:50:30 INFO - PROCESS | 9520 | [#7] imaging-desaturate Cycles:10 Average:84.50 Median:85.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 22:50:30 INFO - PROCESS | 9520 | Values: 85.0 84.0 84.0 85.0 84.0 85.0 85.0 85.0 84.0 84.0 22:50:30 INFO - PROCESS | 9520 | 22:50:30 INFO - PROCESS | 9520 | [#8] json-parse-financial Cycles:10 Average:51.90 Median:52.00 stddev:1.29 (2.5%) stddev-sans-first:1.36 22:50:30 INFO - PROCESS | 9520 | Values: 52.0 51.0 51.0 50.0 55.0 52.0 52.0 52.0 52.0 52.0 22:50:30 INFO - PROCESS | 9520 | 22:50:30 INFO - PROCESS | 9520 | [#9] json-stringify-tinderbox Cycles:10 Average:60.80 Median:61.00 stddev:0.42 (0.7%) stddev-sans-first:0.33 22:50:30 INFO - PROCESS | 9520 | Values: 60.0 61.0 61.0 61.0 61.0 60.0 61.0 61.0 61.0 61.0 22:50:30 INFO - PROCESS | 9520 | 22:50:30 INFO - PROCESS | 9520 | [#10] stanford-crypto-aes Cycles:10 Average:80.60 Median:80.00 stddev:2.41 (3.0%) stddev-sans-first:2.55 22:50:30 INFO - PROCESS | 9520 | Values: 80.0 80.0 80.0 79.0 84.0 79.0 79.0 80.0 79.0 86.0 22:50:30 INFO - PROCESS | 9520 | 22:50:30 INFO - PROCESS | 9520 | [#11] stanford-crypto-ccm Cycles:10 Average:145.30 Median:137.00 stddev:26.72 (19.5%) stddev-sans-first:28.08 22:50:30 INFO - PROCESS | 9520 | Values: 135.0 141.0 134.0 138.0 131.0 136.0 149.0 134.0 220.0 135.0 22:50:30 INFO - PROCESS | 9520 | 22:50:30 INFO - PROCESS | 9520 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.70 Median:166.00 stddev:2.31 (1.4%) stddev-sans-first:2.40 22:50:30 INFO - PROCESS | 9520 | Values: 167.0 163.0 166.0 166.0 166.0 164.0 165.0 166.0 163.0 171.0 22:50:30 INFO - PROCESS | 9520 | 22:50:30 INFO - PROCESS | 9520 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.50 Median:64.00 stddev:2.42 (3.8%) stddev-sans-first:1.94 22:50:30 INFO - PROCESS | 9520 | Values: 69.0 64.0 64.0 63.0 63.0 63.0 69.0 63.0 64.0 63.0 22:50:30 INFO - PROCESS | 9520 | -------- Summary: end -------- 22:50:30 INFO - PROCESS | 9520 | 22:50:31 INFO - TEST-INFO | 9520: exit 0 22:50:31 INFO - TEST-OK | kraken | took 61305ms 22:50:31 INFO - SUITE-END | took 1178s 22:50:31 INFO - Completed test suite (00:19:38) 22:50:31 INFO - javascript benchmark 22:50:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2985.6566494853087, 3015.8539754893122, 3021.033269353807, 3057.067594383715, 3068.125584208501, 5255.237990491377, 5290.92063619046, 5326.627136100475, 5399.892002159954, 5405.783768649254, 5363.329583802025, 5404.513593776559, 5464.180372944061, 5500.28496295481, 5558.6942718150485, 4860.2466617674145, 4886.047220791947, 4932.704037757728, 4940.7776650050655, 4976.676516026465, 4860.392450943632, 5047.242913562976, 5050.0, 5058.367704036996, 5103.132467480518, 4967.031428871372, 4984.077945580079, 5015.247712843066, 5040.697558146523, 5079.79680812767, 4712.6465515086165, 4743.881402964919, 4746.572808447257, 4747.0, 4778.737169455679, 4759.809607615691, 4767.642426818003, 4780.617550595944, 4840.564349208553, 4870.415550133971, 5271.947280527223, 5273.841784746463, 5280.471952804727, 5291.021161085211, 5314.548263397616, 5608.663480191201, 5618.943810561883, 5626.043572592628, 5683.232763576947, 5692.5161361284345, 5785.913211301834, 5814.534837213013, 5836.941630583682, 5884.382139875337, 5887.764489420418, 5409.377921538995, 5410.540104091197, 5413.512783849473, 5414.296141501619, 5414.972925135388, 5659.603827732019, 5712.285964254468, 5879.382664820132, 5934.9406505934385, 5940.1683764272175, 5566.638168519034, 5589.860253493695, 5595.972020139955, 5665.9716701417055, 5673.744681489343, 5616.971915140398, 5652.3217213934595, 5756.107803290497, 5756.222909907109, 5760.251167348218, 5481.424450432647, 5716.028275193227, 5716.828495145152, 5737.225474560964, 5744.798932037359, 5532.446755324516, 5551.694656793915, 5567.972160139173, 5598.58010649203, 5628.465295796893, 4317.244482215599, 4317.611414972667, 4325.805338759764, 4329.328954012133, 4366.061296821199, 3845.8846234613006, 3848.230353929225, 3867.45855580219, 3901.0247438140464, 3916.2950668879307, 5344.198370244417, 5399.217113518518, 5399.892002160013, 5408.945910540923, 5416.431274716177, 5742.196092547047, 5746.0519014363, 5798.449147330997, 5799.768009279666, 5816.534677225812, 5416.83749487516, 5460.645058071213, 5464.23500709901, 5472.7263636818, 5482.671039737628, 4793.640476964241, 4826.745046287921, 4990.076835785391, 4993.6005119590345, 5053.974730126326, 5876.44173803488, 5916.645001299936, 5935.376785437553, 5953.493953013869, 6017.909731354121, 5699.827006820266, 5761.740721667451, 5772.076467765138, 5775.451332123442, 5779.364269930388, 5680.176374425768, 5976.641401515923, 5983.790567330123, 5986.521078313637, 5987.910181347196, 5511.696856672921, 5564.415736347625, 5593.244911936837, 5608.691521966331, 5662.688552129673, 5735.0537161369575, 5745.827625171251, 5758.481736643722, 5760.711964401679, 5764.481196692318, 5574.9721251393485, 5576.10782274834, 5578.163275508706, 5579.637323573955, 5610.6072574921, 5395.81114660985, 5456.099743542271, 5459.5632349412745, 5487.478689524489, 5491.423400542885, 5474.288342515448, 5516.282883225315, 5521.641093328921, 5536.557075434036, 5567.8608034797835, 5487.094629386106, 5563.193336966198, 5581.49766521015, 5583.385827558884, 5606.0, 5523.585731070266, 5556.944430555643, 5572.219889215432, 5577.414371490935, 5659.179418984307], "value": 5271.779950425189, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [553.4784318742921, 579.762297458042, 581.2327727399831, 595.8123191194776, 600.0818747316606, 30063.699363006363, 40012.0, 41003.079630444314, 41012.58987410132, 41067.794661026724, 42491.36262956058, 42505.57494425055, 42535.361969570484, 42595.57404425955, 42602.786986065104, 7942.4837385570045, 8702.95648521758, 8855.778605534848, 8860.11398860112, 8863.512506812123, 7917.485363451357, 10328.845067323997, 10536.894631053707, 10546.209034322419, 10919.890801092006, 10651.360918344924, 10700.30448020876, 10704.839427408595, 10714.250002499828, 10730.141588672888, 10514.053735163834, 10560.94719526405, 10576.735581610445, 10586.629467968662, 10643.78712425755, 10389.324693894872, 10470.319429237075, 10473.790524189473, 10476.476176191236, 10478.161747060218, 39984.60015399838, 40145.0, 40340.0, 40380.59619403798, 40400.79799601013, 3488.5988111367265, 3535.0, 3595.892123236281, 3702.685271751904, 3862.806859657006, 29810.40379192426, 30017.399652006836, 30246.0, 30823.691763082308, 31005.379892402027, 4207.852725154636, 4244.108737165199, 4352.412424322739, 4354.368616550614, 4407.493138289074, 329969.0, 332273.0, 332641.99999999756, 333432.0, 333769.0, 56210.0, 56565.43434565684, 56571.0, 56616.0, 56664.716676416356, 34679.0, 35585.28829423449, 37494.81252593774, 37524.24951500955, 37528.0, 15046.623834404227, 15071.246437678072, 15134.0, 15160.620984475476, 15181.240937953058, 9749.122578967785, 9841.655542056136, 11454.599089031844, 11573.88426115745, 11706.590269340531, 17790.55523611894, 18365.81634183668, 18426.355077572487, 18470.81529184718, 18496.167672454772, 6992.895106573405, 7072.611006394594, 7152.4278057755255, 7167.63814875172, 7294.124705035323, 981.6024510073431, 1017.9694609161737, 1054.3094273251033, 1104.618906477264, 1120.882307357732, 29091.85454072716, 31064.22339441532, 31126.68873311238, 31242.0, 31541.52687709686, 1022.4734261855151, 1026.4457193115784, 1033.7260625934134, 1096.9012788849043, 1125.3641692443907, 16380.918095409446, 16446.682269340803, 16483.752743708854, 16533.58666033359, 16581.502554923372, 158208.0, 158388.0, 158856.20571897065, 158860.0, 159115.0, 54831.45168548343, 55244.0, 55339.446605534235, 55371.0, 55415.687646852486, 1180.3979970215253, 1231.2673958994562, 1293.6571808470574, 1326.9402876870563, 1358.7146699193083, 27386.315342116606, 31972.36055278835, 32194.390280485415, 32412.4920154685, 32474.512882306313, 47465.52534474611, 47881.281780774014, 48155.27767083567, 48186.27720584264, 48215.27677084917, 361934.0, 362514.0, 363783.0, 364403.0, 365433.0, 24764.876175619727, 24970.875145624155, 25357.87321063383, 27063.594046089718, 27277.59083613708, 45183.0, 45185.32222016738, 45237.77381113073, 45332.77333613443, 45676.086478269586, 36956.0, 37584.43623345597, 38805.6119438802, 39559.80220098881, 39695.0, 37551.624483754815, 38010.0, 38602.6139738599, 39371.409428859166, 39910.2017959645, 38496.2300753989, 38696.41955370615, 39081.0, 39230.411543826296, 39492.40761388524, 31499.370012600077, 33553.49669755004, 33799.4930076054, 34109.48835767515, 34115.488267676505, 26409.73590264073, 27102.50936198528, 27147.457050859266, 27203.33518288032, 27352.72647273502], "value": 18689.709212890917, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [365.53029357275904, 396.76194283429925, 399.18964502060817, 405.8194103623887, 413.4525768902814, 13442.327883605818, 15219.543413697593, 16704.74942875858, 18922.526936826587, 19438.0, 18752.62494750104, 18790.43628691138, 19282.807171928278, 19355.0, 19358.903205483988, 5559.888022395517, 5729.713514324288, 5965.642061476304, 5976.223090998186, 5977.671228082476, 5624.71876406178, 5889.116632505115, 5921.851953701149, 5925.733341999598, 5926.081457374114, 5652.463016013493, 5778.075507918734, 5882.3529411764675, 5887.7644894204395, 5887.88224235515, 5780.1907732917425, 5796.275465566804, 5827.242458480391, 5828.504577110951, 5829.417058294158, 5025.7235852028125, 5201.817936372209, 5208.0, 5220.765065572021, 5252.107141785906, 22836.200732974266, 23037.0, 23155.53688926229, 23307.766922330728, 23371.182008629785, 2338.427085364097, 2791.3858951030743, 2934.9119526414243, 3072.585200997859, 3118.048995056507, 9298.256139508823, 13780.862191378159, 14091.154530728189, 14181.503647372292, 14970.326335314938, 1846.2061313635131, 1907.12272354717, 1949.395687336916, 1956.2370675436591, 2018.7173795668618, 81843.26821172186, 82052.0, 82066.0, 83802.58098709528, 83973.0, 11027.669169924915, 11155.553777848958, 11241.269317494336, 11248.437578121062, 11439.656810295704, 15564.221788910509, 15569.610759731097, 15570.0, 15573.532794016197, 15584.92207538955, 7991.200879912079, 8067.39494537912, 8151.429399942066, 8287.378446616527, 8295.751127466187, 7594.772156835304, 7636.50362726421, 7641.923580764121, 7813.843723125619, 7818.022747156606, 4772.785224664898, 4852.611791056777, 4861.368022157097, 4933.925991110136, 4953.2570114483115, 1977.6440240756801, 2004.9398518044482, 2014.3956812956349, 2018.9798102019085, 2078.293380250722, 877.394597727566, 947.7914858731068, 987.9456629885282, 1016.217512515359, 1058.2803693488502, 18516.907415463105, 19330.226790928486, 19346.129424175946, 19435.51411214731, 19442.90278548598, 906.5059542549326, 919.852823548229, 946.2713710442921, 949.8717673114041, 976.1214906584131, 10114.291999560108, 10827.891721082688, 10990.340579565252, 11054.557817687364, 11063.336199828036, 405730.0, 412674.0, 422798.0, 422986.0, 425284.0, 82925.22202752673, 84263.57868210619, 87542.0, 88761.55619221991, 89241.55379223192, 978.8091322192104, 988.0465350936381, 989.6635144050913, 992.2855544008447, 996.2329006664972, 16015.673808845673, 16521.66956660836, 16557.834421656113, 16898.831011689726, 17456.82543174552, 37990.0, 41351.0, 41409.1718165629, 42134.0, 42331.3650295252, 46790.0, 55159.72420138034, 56702.43297566971, 57205.713971429876, 57287.42712572821, 8185.795355115932, 8248.917510824815, 8400.789980250544, 8442.53566053873, 8722.215000649972, 12969.675758106123, 13286.800697989345, 13362.398692059076, 13367.665808354868, 13480.73038539204, 11324.32054076725, 11404.543818247177, 11450.312981221154, 12074.215176013531, 12132.818007730068, 11188.88811111911, 11255.718607034889, 11446.599369022044, 11468.254563453349, 11556.537738490702, 11702.180847340946, 11732.824007639721, 11756.23584467007, 11809.645710628694, 11925.522979081086, 7323.450741194325, 7640.3505702015955, 8330.625121869409, 8373.455725377831, 9047.68333108338, 11258.605948791754, 11758.176927615326, 11788.29270243788, 11921.0, 12202.938985305016], "value": 9199.695930386013, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1310.3120861547693, 1460.9050411723238, 1495.970080598388, 1603.182376987736, 1735.3405705831783, 7497.3252407283335, 8211.219934106257, 8470.40707150499, 8472.27985621223, 8698.608562614681, 8531.402801803877, 8594.0, 8601.483910965346, 8610.56947152643, 8613.310935125191, 2303.481716613757, 2318.7565305642916, 2334.3347146063375, 2337.2053501809382, 2339.450229196146, 2270.20542810016, 2321.1759825261956, 2343.250159948817, 2345.2143531916745, 2347.096367898363, 2269.4780200553882, 2273.6134857074253, 2305.6772051912744, 2321.292005938188, 2327.208749025331, 2209.3151123151792, 2225.6995305633855, 2248.775122487738, 2249.044156233594, 2249.876256805875, 2225.376894469551, 2245.41619179013, 2245.955080898389, 2247.494313779403, 2247.6291411917014, 8417.11620279868, 8535.573221338907, 8581.656733730642, 8596.140385961417, 8599.871001934976, 4998.2002879539095, 5208.869778255536, 5212.478752124757, 5220.660657057279, 5231.2937753403185, 3345.498175273704, 3436.3642726095754, 3483.216276337829, 3498.8628695674006, 3504.754667173299, 1161.1581603337597, 1172.5016867831137, 1196.1387800783423, 1198.3768440411025, 1207.812789017695, 12074.215176013531, 12110.091743119214, 12234.265944043296, 12251.938740306241, 12264.325462099581, 4313.568643135662, 4606.884827879263, 4607.654425918069, 4646.953530464652, 4653.441587009513, 9000.954995224982, 9004.32467564922, 9005.594748236344, 9009.414388064755, 9014.59434325457, 5149.562287205629, 5165.74171291434, 5169.121249387613, 5179.741012949337, 5181.611379146579, 4207.095474473003, 4329.523752387235, 4640.164770341371, 4718.575328220477, 4824.614030877521, 3634.672879440863, 3734.2531493700826, 3757.492738480325, 3783.754055986352, 3833.9041523961564, 1124.9437528123724, 1134.2003887259461, 1148.724306166514, 1172.8709841917382, 1176.0885313881638, 861.5692153923038, 923.4920793563513, 967.3760424526282, 968.85674903613, 976.2043934193609, 5702.54379649627, 5897.233359663216, 5960.0761881908375, 6176.227971503562, 6346.587471814318, 439.79329715033884, 453.22724754293847, 455.9672342145044, 464.30818081058976, 465.72754938361436, 4001.199760047944, 4022.7586344819406, 4035.071933455263, 4041.7372870763893, 4062.512498500199, 10414.271001029856, 10686.412247326472, 10723.83914241287, 10794.190435717353, 10803.459827008619, 10582.259241852997, 10587.57649694019, 10590.0, 10596.0, 10604.0, 7892.131865494791, 8053.6778528858185, 8112.675492980215, 8126.349892008625, 8142.226488483585, 5601.6358936668985, 5680.4319568042865, 5702.543796496353, 5741.224934633771, 5859.824205273849, 9636.084571965652, 9755.463449510062, 9843.753906152404, 9953.850692239477, 9992.900070999487, 9452.196563292197, 9821.410715357102, 9851.556679949563, 10182.185425166117, 10190.541425635716, 6626.436752875867, 6786.049953006484, 6810.8637827244165, 7521.135069466863, 7676.385889128745, 7582.810429739301, 7597.468177227541, 8045.477043992122, 8138.3489320853005, 8331.125231850569, 7323.890141647773, 7511.5868627223845, 7562.621868906423, 7860.213978602094, 7885.526868387686, 7248.456365772483, 7271.781846544613, 7441.888371674321, 7499.1750907399055, 7773.1449540549365, 6540.084388185563, 6632.668366581748, 6638.17022872141, 6642.435392991457, 6644.800655980328, 4946.480619534897, 5027.723475208899, 5048.268001139741, 5049.671771334852, 5188.948110518846, 4856.781444835062, 4898.387701537308, 4924.285978533164, 4989.451160372429, 5073.822416215415], "value": 4421.557917582187, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [322.1671977937033, 330.1218758103445, 337.1638336924427, 340.9624941256463, 343.18835952971216, 5179.7827510535, 5189.870253243671, 5197.480251974801, 5199.0, 5201.453847346022, 5112.744362781856, 5115.462876397981, 5124.538791508773, 5129.769160387782, 5158.92261616076, 3971.5631280559114, 3991.8602848900296, 4060.959390406095, 4101.26177288088, 4105.876823695279, 4024.718269721106, 4046.3728122141115, 4049.676025917934, 4054.858079967202, 4068.247374235761, 4016.7188296819236, 4016.7389119707273, 4019.738716983387, 4021.9798901005456, 4025.7584544927254, 3823.4647149399248, 3903.355946268862, 3939.6454319111417, 4015.6586690131376, 4078.9592104078874, 3548.7870727756417, 3562.786232826039, 3588.5334906461994, 3594.8202589870402, 3631.364511210554, 5054.9494505054845, 5056.443791182967, 5080.644354895159, 5093.300550708131, 5145.614078944094, 4394.4287242658565, 4450.688451808374, 4580.404547408849, 4622.751856998591, 4685.742284174369, 5349.43830897754, 5352.678839269656, 5361.785528578891, 5369.838904832861, 5372.059889519319, 3917.099067214528, 3926.666233370167, 3936.6457018868155, 3936.8031598420253, 3952.7233093683453, 5331.147016477384, 5526.47498487643, 5535.308086489189, 5541.833744987616, 5657.632253903483, 3991.261616600938, 4009.959900401017, 4017.116234428421, 4020.8592699255387, 4022.336314508132, 4061.817218225187, 4083.5303940047274, 4092.2224777292813, 4098.954766534574, 4102.733322334038, 4438.663118128463, 4589.35748995141, 4618.261078227468, 4651.627669888314, 4666.813327466931, 5407.432219616962, 5421.213923981, 5425.7174409481695, 5448.338256831705, 5462.262594549685, 1408.5774267719644, 1426.9001169918213, 1428.52144531581, 1435.131745294103, 1435.6913263648157, 876.9035406105323, 923.7459698582943, 937.6577549194632, 938.8263171313329, 943.3585162089707, 4603.815847366068, 4619.676622636383, 4643.212363240177, 4647.674662773641, 4656.045510670305, 4815.422149342101, 4828.686135401188, 4836.911694868626, 4850.320955066293, 4905.3623029006, 4298.839313385369, 4621.584057434847, 4633.745144017112, 4636.8145274188655, 4645.583097155332, 2533.936651583725, 2582.8893575762604, 2584.694729161761, 2587.611858221282, 2594.3514121469634, 4974.602031837444, 4981.875453113701, 4983.975080124576, 5008.899822003539, 5011.373578302712, 4896.4858689837765, 4924.926126108111, 4956.330895329156, 4988.426330971912, 5302.443243459458, 5792.362840087587, 5804.8390321935785, 5818.31268932005, 5821.767129314865, 5829.38791426911, 5549.611527193058, 5581.553475721851, 5613.494785469327, 5631.761012577226, 5677.5457963362005, 5404.864878378072, 5493.862653433696, 5515.393306736176, 5527.585431092764, 5581.4883302514845, 5692.288463942007, 5706.543476521785, 5734.311882574118, 5760.8271751847515, 5764.164196191445, 3964.4647972524494, 3997.8400863965117, 4013.4983127109113, 4060.903556039794, 4065.414488349487, 5342.679439233659, 5585.804496842754, 5613.270274864241, 5630.887382252414, 5700.914486282626, 5410.242566040679, 5441.238226648352, 5441.945580544144, 5443.183522471661, 5451.4276001019325, 5220.1647736362, 5434.538064264581, 5450.700211488248, 5457.208704737712, 5496.972515137399, 5402.459754024566, 5404.243405923095, 5411.323584551931, 5411.431799660979, 5415.2689386933835, 5223.589630799739, 5325.520703136736, 5345.0, 5458.454154584669, 5478.506934375925, 5360.16917377807, 5365.195220716923, 5368.892622147613, 5390.490662614474, 5392.541633961], "value": 4065.7628058814284, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [679.9524033317666, 747.3684736397742, 752.1049950558835, 753.3107206905681, 754.5661244784251, 11365.772684546315, 11689.707757306072, 11813.23213991091, 11873.168878178509, 11880.584179553718, 11189.048930840889, 11285.717857053578, 11308.491117899694, 11317.773644527104, 11319.490622921967, 650.4536189600734, 764.8585011772826, 779.12737733738, 780.9219078092202, 781.1758594682636, 657.7270432770418, 767.1944458318743, 785.1128225105651, 790.6876783670447, 791.8297566023303, 815.6248125862111, 822.7696245051366, 825.1871906172415, 826.1036775098995, 826.3348004856089, 675.4360109308733, 818.6316157728986, 818.8976377952756, 819.5369616166847, 820.1757233979841, 503.4889587069128, 575.7927146227355, 591.3701907468533, 594.5481434110108, 594.5897330841749, 11593.188476806627, 11630.30218186903, 11640.825387619192, 11642.534298628045, 11644.88355116455, 12406.193597416139, 12529.561465348761, 12878.162919410302, 12926.935365323206, 13257.734845303137, 11535.653930382103, 11646.301221926711, 11667.591634292843, 11807.763844723144, 11826.0, 3620.619834917322, 3665.376885929398, 3729.850805967758, 3831.7509361891666, 4088.1823635273063, 38514.22971540582, 45478.54521454776, 59370.109448358744, 59646.40353596495, 59872.0, 9749.171320437701, 10065.547050382751, 10395.68812935598, 10397.0, 10655.733606659896, 17571.648567028842, 17621.647567048843, 17762.625101230726, 17800.821991779918, 18031.188596513188, 7741.419393545507, 7776.92223077762, 7856.33221176195, 7880.487768295043, 8004.799880003046, 4165.479315085614, 4249.171411574714, 4262.126264115849, 4330.545292744279, 4489.079738653568, 3001.4897467430587, 3070.585470961392, 3073.707997740211, 3088.258817883692, 3094.5822313987774, 547.9780808767684, 550.3203543623622, 552.4585905812246, 561.6040691312614, 568.7724910035936, 538.3593523706776, 550.9338879334506, 574.2018594154129, 671.0705672643327, 687.2920891481782, 8371.58142092893, 8838.248748856291, 8937.910620893837, 9003.279737620975, 9032.774180645405, 623.7643337287429, 632.7785275153659, 679.5523300001502, 682.9897551536731, 700.2332445971653, 4308.698391112592, 4357.869263922087, 4378.277584198571, 4564.0, 4631.606313463393, 54249.1862622061, 70485.29514704889, 71339.64330178419, 73345.0, 85725.57137214275, 44596.55403445989, 45104.3234351485, 68568.0, 69878.0, 70736.0, 62852.0, 63346.0, 63621.0, 64462.67768661126, 65833.0, 8761.780955476164, 8802.29581633455, 8854.645814167361, 8868.201861832466, 8932.553372331487, 40729.59270407258, 42472.36291455569, 42983.78508107439, 43761.0, 44590.3311450322, 53916.921661567336, 54805.72597136989, 61204.38795612165, 63308.0, 64686.676566616865, 11236.325820450473, 11377.715557111138, 11452.0, 11603.36181510025, 11790.174687771774, 12686.619401417973, 12940.482380705043, 13048.934755326163, 13196.538121165713, 13450.596482105553, 11782.17524773292, 11905.46425410876, 12050.819237711266, 12213.938930305647, 13263.535776247785, 12017.441136165442, 12144.332061736335, 12246.0, 12552.623421297376, 12877.8068328977, 12135.0, 12357.629271121521, 12512.937435312766, 12550.623481295575, 12981.675458113623, 9730.95134524323, 9743.80512389734, 9916.603335866484, 10035.448050357301, 10155.695329140137, 9761.365511241747, 9763.804723905625, 10134.695959121238, 10219.693409197736, 10295.279330446796], "value": 6522.490568875628, "unit": "score"}], "name": "dromaeo_css", "value": 6882.586161891858, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 110.0, 111.0, 112.0, 112.0, 111.0, 111.0, 111.0, 112.0, 112.0], "value": 110.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 122.0, 128.0, 174.0, 125.0, 123.0, 127.0, 124.0, 153.0, 124.0], "value": 135.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 159.0, 160.0, 162.0, 162.0, 160.0, 164.0, 162.0, 162.0, 162.0], "value": 161.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 77.0, 75.0, 116.0, 76.0, 75.0, 74.0, 76.0, 110.0, 76.0], "value": 82.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 128.0, 84.0, 83.0, 82.0, 101.0, 84.0, 83.0, 111.0], "value": 92.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 118.0, 116.0, 116.0, 117.0, 117.0, 118.0, 118.0, 118.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 147.0, 145.0, 145.0, 145.0, 146.0, 145.0, 145.0, 147.0, 145.0], "value": 145.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 84.0, 85.0, 84.0, 85.0, 85.0, 85.0, 84.0, 84.0], "value": 84.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 51.0, 50.0, 55.0, 52.0, 52.0, 52.0, 52.0, 52.0], "value": 51.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 61.0, 61.0, 61.0, 60.0, 61.0, 61.0, 61.0, 61.0], "value": 60.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 80.0, 79.0, 84.0, 79.0, 79.0, 80.0, 79.0, 86.0], "value": 80.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 141.0, 134.0, 138.0, 131.0, 136.0, 149.0, 134.0, 220.0, 135.0], "value": 145.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 163.0, 166.0, 166.0, 166.0, 164.0, 165.0, 166.0, 163.0, 171.0], "value": 165.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 64.0, 64.0, 63.0, 63.0, 63.0, 69.0, 63.0, 64.0, 63.0], "value": 64.5, "unit": "score"}], "name": "kraken", "value": 1498.1, "alertThreshold": 2.0}]} 22:50:32 INFO - Return code: 0 22:50:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:50:32 INFO - # TBPL SUCCESS # 22:50:32 INFO - Running post-action listener: _resource_record_post_action 22:50:32 INFO - [mozharness: 2016-11-04 05:50:32.075337Z] Finished run-tests step (success) 22:50:32 INFO - Running post-run listener: _resource_record_post_run 22:50:32 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 8192; Write bytes: 403886080; Read time: 16; Write time: 252084 22:50:32 INFO - TinderboxPrint: CPU usage
13.0% 22:50:32 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 16 22:50:32 INFO - TinderboxPrint: I/O write bytes / time
403,886,080 / 252,084 22:50:32 INFO - TinderboxPrint: CPU idle
8,297.7 (86.9%) 22:50:32 INFO - TinderboxPrint: CPU user
1,195.9 (12.5%) 22:50:32 INFO - TinderboxPrint: Swap in / out
0 / 0 22:50:32 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 115253248; Read time: 0; Write time: 88180 22:50:32 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 4096; Write bytes: 288632832; Read time: 8; Write time: 163904 22:50:32 INFO - Running post-run listener: _upload_blobber_files 22:50:32 INFO - Blob upload gear active. 22:50:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:50:32 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 22:50:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:50:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:50:33 INFO - (blobuploader) - INFO - Open directory for files ... 22:50:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:50:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:50:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:50:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:50:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:50:33 INFO - (blobuploader) - INFO - Done attempting. 22:50:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:50:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:50:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:50:39 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:50:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:50:39 INFO - (blobuploader) - INFO - Done attempting. 22:50:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:50:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:50:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:50:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:50:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:50:39 INFO - (blobuploader) - INFO - Done attempting. 22:50:39 INFO - (blobuploader) - INFO - Iteration through files over. 22:50:39 INFO - Return code: 0 22:50:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:50:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:50:39 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/402e3c7423d09d4afddcc8f19dace02491f6911e88123d1d80bca2e3778335527858f7ebb9c5dbb1ff0ea6632ee74c9614238fb0650b374b2950635bd4d648f9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2ae9adf8209a3b0a79318e7eefebbdfc06a048c0b2901715c267075ec6b3ccef17efdb58628ac2568c71beaad02a458869f7c1060877a9b7c4dcd33dd1ef2201", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5cf120d54871448c9af8881137db3062dcf340fc08562568f33e120bbb6eb88c6ccb4eb4983398b3cba53f7c88969c8d269edf69025553245927dc7bc1082e5a"} 22:50:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:50:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:50:39 INFO - Contents: 22:50:39 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/402e3c7423d09d4afddcc8f19dace02491f6911e88123d1d80bca2e3778335527858f7ebb9c5dbb1ff0ea6632ee74c9614238fb0650b374b2950635bd4d648f9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2ae9adf8209a3b0a79318e7eefebbdfc06a048c0b2901715c267075ec6b3ccef17efdb58628ac2568c71beaad02a458869f7c1060877a9b7c4dcd33dd1ef2201", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5cf120d54871448c9af8881137db3062dcf340fc08562568f33e120bbb6eb88c6ccb4eb4983398b3cba53f7c88969c8d269edf69025553245927dc7bc1082e5a"} 22:50:39 INFO - Running post-run listener: copy_logs_to_upload_dir 22:50:39 INFO - Copying logs to upload dir... 22:50:39 INFO - mkdir: /builds/slave/test/build/upload/logs 22:50:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1240.872928 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-11-03 22:50:39.903712) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-03 22:50:39.907180) ========= 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=ed14279e38b05397cf4aa391000001a4-1478237397.368329-415775308 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/402e3c7423d09d4afddcc8f19dace02491f6911e88123d1d80bca2e3778335527858f7ebb9c5dbb1ff0ea6632ee74c9614238fb0650b374b2950635bd4d648f9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2ae9adf8209a3b0a79318e7eefebbdfc06a048c0b2901715c267075ec6b3ccef17efdb58628ac2568c71beaad02a458869f7c1060877a9b7c4dcd33dd1ef2201", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5cf120d54871448c9af8881137db3062dcf340fc08562568f33e120bbb6eb88c6ccb4eb4983398b3cba53f7c88969c8d269edf69025553245927dc7bc1082e5a"} build_url:https://queue.taskcluster.net/v1/task/cYOYjmA-S368aTKo2xKKNg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011517 build_url: 'https://queue.taskcluster.net/v1/task/cYOYjmA-S368aTKo2xKKNg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/402e3c7423d09d4afddcc8f19dace02491f6911e88123d1d80bca2e3778335527858f7ebb9c5dbb1ff0ea6632ee74c9614238fb0650b374b2950635bd4d648f9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2ae9adf8209a3b0a79318e7eefebbdfc06a048c0b2901715c267075ec6b3ccef17efdb58628ac2568c71beaad02a458869f7c1060877a9b7c4dcd33dd1ef2201", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5cf120d54871448c9af8881137db3062dcf340fc08562568f33e120bbb6eb88c6ccb4eb4983398b3cba53f7c88969c8d269edf69025553245927dc7bc1082e5a"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-03 22:50:39.933486) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-03 22:50:39.933832) ========= 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=ed14279e38b05397cf4aa391000001a4-1478237397.368329-415775308 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004519 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-03 22:50:40.017969) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-03 22:50:40.018277) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-03 22:50:40.018630) ========= ========= Total master_lag: 0.17 =========