builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-062 starttime: 1480094405.35 results: success (0) buildid: 20161125063919 builduid: f425bfbde1f946da9b9dfff7e187e699 revision: d5179b827ff8b1e942fa0143f9b59fe28c54c4c0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-25 09:20:05.350754) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-25 09:20:05.351220) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-25 09:20:05.351570) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-25 09:20:05.367480) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 09:20:05.367803) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1480092200.869698-777599883 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-11-25 09:20:06-- 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% 223M=0s 2016-11-25 09:20:06 (223 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.113452 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 09:20:05.496013) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 09:20:05.496396) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1480092200.869698-777599883 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018787 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 09:20:05.546036) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-25 09:20:05.546419) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev d5179b827ff8b1e942fa0143f9b59fe28c54c4c0 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev d5179b827ff8b1e942fa0143f9b59fe28c54c4c0 --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=a4ce256d2ec601f24a60a71e000001bc-1480092200.869698-777599883 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-11-25 09:20:06,744 truncating revision to first 12 chars 2016-11-25 09:20:06,744 Setting DEBUG logging. 2016-11-25 09:20:06,744 attempt 1/10 2016-11-25 09:20:06,744 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/d5179b827ff8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-25 09:20:08,090 unpacking tar archive at: mozilla-aurora-d5179b827ff8/testing/mozharness/ program finished with exit code 0 elapsedTime=1.651056 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-25 09:20:07.211169) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-25 09:20:07.211547) ========= script_repo_revision: d5179b827ff8b1e942fa0143f9b59fe28c54c4c0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-25 09:20:07.211990) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-25 09:20:07.212386) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-25 09:20:07.222704) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-11-25 09:20:07.223357) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=a4ce256d2ec601f24a60a71e000001bc-1480092200.869698-777599883 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:20:08 INFO - MultiFileLogger online at 20161125 09:20:08 in /builds/slave/test 09:20:08 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 09:20:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:20:08 INFO - {'append_to_log': False, 09:20:08 INFO - 'base_work_dir': '/builds/slave/test', 09:20:08 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:20:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:20:08 INFO - 'branch': 'Mozilla-Aurora', 09:20:08 INFO - 'buildbot_json_path': 'buildprops.json', 09:20:08 INFO - 'config_files': ('talos/linux_config.py',), 09:20:08 INFO - 'default_actions': ('clobber', 09:20:08 INFO - 'read-buildbot-config', 09:20:08 INFO - 'download-and-extract', 09:20:08 INFO - 'populate-webroot', 09:20:08 INFO - 'create-virtualenv', 09:20:08 INFO - 'install', 09:20:08 INFO - 'run-tests'), 09:20:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:20:08 INFO - 'download_minidump_stackwalk': True, 09:20:08 INFO - 'download_symbols': 'ondemand', 09:20:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:20:08 INFO - 'tooltool.py': '/tools/tooltool.py', 09:20:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:20:08 INFO - '/tools/misc-python/virtualenv.py')}, 09:20:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:20:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:20:08 INFO - 'installer_path': 'installer.exe', 09:20:08 INFO - 'log_level': 'info', 09:20:08 INFO - 'log_name': 'talos', 09:20:08 INFO - 'log_to_console': True, 09:20:08 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:20:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:20:08 INFO - 'opt_config_files': (), 09:20:08 INFO - 'pip_index': False, 09:20:08 INFO - 'sps_profile': False, 09:20:08 INFO - 'sps_profile_interval': 0, 09:20:08 INFO - 'suite': 'dromaeojs', 09:20:08 INFO - 'system_bits': '32', 09:20:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:20:08 INFO - 'title': 'talos-linux64-ix-062', 09:20:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:20:08 INFO - 'use_talos_json': True, 09:20:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:20:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:20:08 INFO - 'work_dir': 'build'} 09:20:08 INFO - [mozharness: 2016-11-25 17:20:08.523822Z] Running clobber step. 09:20:08 INFO - Running pre-action listener: _resource_record_pre_action 09:20:08 INFO - Running main action method: clobber 09:20:08 INFO - rmtree: /builds/slave/test/build 09:20:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:20:08 INFO - Running post-action listener: _resource_record_post_action 09:20:08 INFO - [mozharness: 2016-11-25 17:20:08.982528Z] Finished clobber step (success) 09:20:08 INFO - [mozharness: 2016-11-25 17:20:08.982627Z] Running read-buildbot-config step. 09:20:08 INFO - Running pre-action listener: _resource_record_pre_action 09:20:08 INFO - Running main action method: read_buildbot_config 09:20:08 INFO - Using buildbot properties: 09:20:08 INFO - { 09:20:08 INFO - "project": "", 09:20:08 INFO - "product": "firefox", 09:20:08 INFO - "script_repo_revision": "production", 09:20:08 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 09:20:08 INFO - "repository": "", 09:20:08 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 09:20:08 INFO - "buildid": "20161125063919", 09:20:08 INFO - "pgo_build": "True", 09:20:08 INFO - "basedir": "/builds/slave/test", 09:20:08 INFO - "buildnumber": 646, 09:20:08 INFO - "slavename": "talos-linux64-ix-062", 09:20:08 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 09:20:08 INFO - "platform": "ubuntu64_hw", 09:20:08 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 09:20:08 INFO - "branch": "mozilla-aurora", 09:20:08 INFO - "repo_path": "releases/mozilla-aurora", 09:20:08 INFO - "revision": "d5179b827ff8b1e942fa0143f9b59fe28c54c4c0", 09:20:08 INFO - "stage_platform": "linux64-pgo", 09:20:08 INFO - "builduid": "f425bfbde1f946da9b9dfff7e187e699", 09:20:08 INFO - "slavebuilddir": "test" 09:20:08 INFO - } 09:20:08 INFO - Found installer url https://queue.taskcluster.net/v1/task/QEbwtMvvQWKP5smYDPfgeQ/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2. 09:20:08 INFO - Running post-action listener: _resource_record_post_action 09:20:08 INFO - [mozharness: 2016-11-25 17:20:08.985049Z] Finished read-buildbot-config step (success) 09:20:08 INFO - [mozharness: 2016-11-25 17:20:08.985136Z] Running download-and-extract step. 09:20:08 INFO - Running pre-action listener: _resource_record_pre_action 09:20:08 INFO - Running main action method: download_and_extract 09:20:08 INFO - mkdir: /builds/slave/test/build/tests 09:20:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:20:08 INFO - trying https://queue.taskcluster.net/v1/task/QEbwtMvvQWKP5smYDPfgeQ/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.test_packages.json 09:20:08 INFO - Downloading https://queue.taskcluster.net/v1/task/QEbwtMvvQWKP5smYDPfgeQ/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.test_packages.json 09:20:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QEbwtMvvQWKP5smYDPfgeQ/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 09:20:09 INFO - Downloaded 1361 bytes. 09:20:09 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.test_packages.json 09:20:09 INFO - Using the following test package requirements: 09:20:09 INFO - {u'common': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip'], 09:20:09 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 09:20:09 INFO - u'firefox-52.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 09:20:09 INFO - u'gtest': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 09:20:09 INFO - u'firefox-52.0a2.en-US.linux-x86_64.gtest.tests.zip'], 09:20:09 INFO - u'jittest': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 09:20:09 INFO - u'jsshell-linux-x86_64.zip'], 09:20:09 INFO - u'mochitest': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 09:20:09 INFO - u'firefox-52.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 09:20:09 INFO - u'mozbase': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip'], 09:20:09 INFO - u'reftest': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 09:20:09 INFO - u'firefox-52.0a2.en-US.linux-x86_64.reftest.tests.zip'], 09:20:09 INFO - u'talos': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 09:20:09 INFO - u'firefox-52.0a2.en-US.linux-x86_64.talos.tests.zip'], 09:20:09 INFO - u'web-platform': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 09:20:09 INFO - u'firefox-52.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 09:20:09 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 09:20:09 INFO - u'firefox-52.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 09:20:09 INFO - Downloading packages: [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:20:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QEbwtMvvQWKP5smYDPfgeQ/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.common.tests.zip 09:20:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QEbwtMvvQWKP5smYDPfgeQ/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:20:09 INFO - Fetch https://queue.taskcluster.net/v1/task/QEbwtMvvQWKP5smYDPfgeQ/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.common.tests.zip into memory 09:20:10 INFO - Http code: 200 09:20:10 INFO - via: 1.1 5ed093c37d643652e278a198e0556a40.cloudfront.net (CloudFront) 09:20:10 INFO - x-amz-cf-id: rtVOq9On4REq_CDivz85Kpn8i8qDm1YZorKQXj0ALhGKvk2T9Vjysw== 09:20:10 INFO - x-amz-version-id: 9NeLWtnM7YVj25tHpGpm8gVQp9oYqCGT 09:20:10 INFO - Expected file size: 21950997 09:20:10 INFO - Obtained file size: 21950997 09:20:14 INFO - Downloading packages: [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-52.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:20:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QEbwtMvvQWKP5smYDPfgeQ/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.common.tests.zip 09:20:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QEbwtMvvQWKP5smYDPfgeQ/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:20:14 INFO - Fetch https://queue.taskcluster.net/v1/task/QEbwtMvvQWKP5smYDPfgeQ/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.common.tests.zip into memory 09:20:15 INFO - Http code: 200 09:20:15 INFO - via: 1.1 23172d761378a3865feafcda78328053.cloudfront.net (CloudFront) 09:20:15 INFO - x-amz-cf-id: YobW1cv2VBg7WApmmDi0h-WvxyTEoadhjPQkN5XB5IgSAt7dVkdPaQ== 09:20:15 INFO - x-amz-version-id: 9NeLWtnM7YVj25tHpGpm8gVQp9oYqCGT 09:20:15 INFO - Expected file size: 21950997 09:20:15 INFO - Obtained file size: 21950997 09:20:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QEbwtMvvQWKP5smYDPfgeQ/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.talos.tests.zip 09:20:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QEbwtMvvQWKP5smYDPfgeQ/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:20:19 INFO - Fetch https://queue.taskcluster.net/v1/task/QEbwtMvvQWKP5smYDPfgeQ/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.talos.tests.zip into memory 09:20:20 INFO - Http code: 200 09:20:20 INFO - via: 1.1 20f1c35f343f4b271ae8dcacfd7ea0e9.cloudfront.net (CloudFront) 09:20:20 INFO - x-amz-cf-id: -Tf_LRtnnCbcFgjzajXK8w6q5gprZq_FPt9jLGpI12S6LUnscn7Znw== 09:20:20 INFO - x-amz-version-id: wuMAnfjS4I0D3HFxXr17eYaC6YiMa72G 09:20:21 INFO - Expected file size: 14409038 09:20:21 INFO - Obtained file size: 14409038 09:20: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')]} 09:20:21 INFO - trying https://queue.taskcluster.net/v1/task/QEbwtMvvQWKP5smYDPfgeQ/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 09:20:21 INFO - Downloading https://queue.taskcluster.net/v1/task/QEbwtMvvQWKP5smYDPfgeQ/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 09:20:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QEbwtMvvQWKP5smYDPfgeQ/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:20:24 INFO - Downloaded 63886345 bytes. 09:20:24 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QEbwtMvvQWKP5smYDPfgeQ/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 09:20:24 INFO - mkdir: /builds/slave/test/properties 09:20:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:20:24 INFO - Writing to file /builds/slave/test/properties/build_url 09:20:24 INFO - Contents: 09:20:24 INFO - build_url:https://queue.taskcluster.net/v1/task/QEbwtMvvQWKP5smYDPfgeQ/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 09:20:24 INFO - Running post-action listener: _resource_record_post_action 09:20:24 INFO - Running post-action listener: set_extra_try_arguments 09:20:24 INFO - [mozharness: 2016-11-25 17:20:24.848770Z] Finished download-and-extract step (success) 09:20:24 INFO - [mozharness: 2016-11-25 17:20:24.848994Z] Running populate-webroot step. 09:20:24 INFO - Running pre-action listener: _resource_record_pre_action 09:20:24 INFO - Running main action method: populate_webroot 09:20:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:20:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:20:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:20:24 INFO - '--tppagecycles', 09:20:24 INFO - '7'], 09:20:24 INFO - 'tests': ['tsvgm']}}, 09:20:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:20:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:20:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:20:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:20:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:20:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:20:24 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:20:24 INFO - 'talos_options': ['--disable-e10s'], 09:20:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:20:24 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:20:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:20:24 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:20:24 INFO - 'talos_options': ['--disable-e10s'], 09:20:24 INFO - 'tests': ['damp', 'tps']}, 09:20:24 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:20:24 INFO - 'tests': ['damp', 'tps']}, 09:20:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:20:24 INFO - 'tests': ['dromaeo_dom']}, 09:20:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:20:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:20:24 INFO - 'tests': ['basic_compositor_video']}, 09:20:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:20:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:20:24 INFO - 'tests': ['a11yr', 09:20:24 INFO - 'ts_paint', 09:20:24 INFO - 'tpaint', 09:20:24 INFO - 'sessionrestore', 09:20:24 INFO - 'sessionrestore_no_auto_restore', 09:20:24 INFO - 'tabpaint']}, 09:20:24 INFO - 'other-e10s': {'tests': ['a11yr', 09:20:24 INFO - 'ts_paint', 09:20:24 INFO - 'tpaint', 09:20:24 INFO - 'sessionrestore', 09:20:24 INFO - 'sessionrestore_no_auto_restore', 09:20:24 INFO - 'tabpaint']}, 09:20:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:20:24 INFO - 'ts_paint', 09:20:24 INFO - 'tpaint', 09:20:24 INFO - 'sessionrestore', 09:20:24 INFO - 'sessionrestore_no_auto_restore', 09:20:24 INFO - 'tabpaint']}, 09:20:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:20:24 INFO - 'ts_paint', 09:20:24 INFO - 'tpaint', 09:20:24 INFO - 'sessionrestore', 09:20:24 INFO - 'sessionrestore_no_auto_restore', 09:20:24 INFO - 'tabpaint']}, 09:20:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:20:24 INFO - 'tests': ['a11yr', 09:20:24 INFO - 'ts_paint', 09:20:24 INFO - 'tpaint', 09:20:24 INFO - 'sessionrestore', 09:20:24 INFO - 'sessionrestore_no_auto_restore', 09:20:24 INFO - 'tabpaint']}, 09:20:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:20:24 INFO - 'tests': ['a11yr', 09:20:24 INFO - 'ts_paint', 09:20:24 INFO - 'tpaint', 09:20:24 INFO - 'sessionrestore', 09:20:24 INFO - 'sessionrestore_no_auto_restore', 09:20:24 INFO - 'tabpaint']}, 09:20:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:20:24 INFO - 'tests': ['tsvgx', 09:20:24 INFO - 'tsvgr_opacity', 09:20:24 INFO - 'tart', 09:20:24 INFO - 'tscrollx', 09:20:24 INFO - 'cart']}, 09:20:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:20:24 INFO - 'tsvgr_opacity', 09:20:24 INFO - 'tart', 09:20:24 INFO - 'tscrollx', 09:20:24 INFO - 'cart']}, 09:20:24 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:20:24 INFO - 'talos_options': ['--disable-e10s'], 09:20:24 INFO - 'tests': ['tp5o']}, 09:20:24 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:20:24 INFO - 'tests': ['tp5o']}, 09:20:24 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:20:24 INFO - 'talos_options': ['--disable-e10s', 09:20:24 INFO - '--xperf_path', 09:20:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:20:24 INFO - 'tests': ['tp5n']}, 09:20:24 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:20:24 INFO - 'talos_options': ['--xperf_path', 09:20:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:20:24 INFO - 'tests': ['tp5n']}}, 09:20:24 INFO - 'talos.zip': {'path': '', 09:20:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:20:24 INFO - Running post-action listener: _resource_record_post_action 09:20:24 INFO - [mozharness: 2016-11-25 17:20:24.871815Z] Finished populate-webroot step (success) 09:20:24 INFO - [mozharness: 2016-11-25 17:20:24.871902Z] Running create-virtualenv step. 09:20:24 INFO - Running pre-action listener: _resource_record_pre_action 09:20:24 INFO - Running main action method: create_virtualenv 09:20:24 INFO - Creating virtualenv /builds/slave/test/build/venv 09:20:24 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 09:20:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:20:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:20:24 INFO - Using real prefix '/usr' 09:20:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:20:25 INFO - Installing distribute.............................................................................................................................................................................................done. 09:20:27 INFO - Installing pip.................done. 09:20:27 INFO - Return code: 0 09:20:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:20:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:20:27 INFO - Reading from file tmpfile_stdout 09:20:27 INFO - Output received: 09:20:27 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) 09:20:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:20:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:20:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:20:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:20:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:20:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3ac878f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x264c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2719ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2716f60>, '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 0x2717d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27181d0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1480092200.869698-777599883', '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 09:20:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:20:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:20:27 INFO - Using env: {'DISPLAY': ':0', 09:20:27 INFO - 'HOME': '/home/cltbld', 09:20:27 INFO - 'LANG': 'en_US.UTF-8', 09:20:27 INFO - 'LANGUAGE': 'en_US:en', 09:20:27 INFO - 'LOGNAME': 'cltbld', 09:20:27 INFO - 'MAIL': '/var/mail/cltbld', 09:20:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:27 INFO - 'MOZ_NO_REMOTE': '1', 09:20:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:20:27 INFO - 'NO_EM_RESTART': '1', 09:20:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:20:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:27 INFO - 'PWD': '/builds/slave/test', 09:20:27 INFO - 'SHELL': '/bin/bash', 09:20:27 INFO - 'SHLVL': '1', 09:20:27 INFO - 'TERM': 'linux', 09:20:27 INFO - 'TMOUT': '86400', 09:20:27 INFO - 'USER': 'cltbld', 09:20:27 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1480092200.869698-777599883', 09:20:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:27 INFO - '_': '/tools/buildbot/bin/python'} 09:20:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:20:27 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 09:20:27 INFO - Cleaning up... 09:20:27 INFO - Return code: 0 09:20:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:20:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:20:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:20:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:20:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:20:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fa3ac878f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x264c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2719ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2716f60>, '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 0x2717d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27181d0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1480092200.869698-777599883', '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 09:20:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:20:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:20:27 INFO - Using env: {'DISPLAY': ':0', 09:20:27 INFO - 'HOME': '/home/cltbld', 09:20:27 INFO - 'LANG': 'en_US.UTF-8', 09:20:27 INFO - 'LANGUAGE': 'en_US:en', 09:20:27 INFO - 'LOGNAME': 'cltbld', 09:20:27 INFO - 'MAIL': '/var/mail/cltbld', 09:20:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:27 INFO - 'MOZ_NO_REMOTE': '1', 09:20:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:20:27 INFO - 'NO_EM_RESTART': '1', 09:20:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:20:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:27 INFO - 'PWD': '/builds/slave/test', 09:20:27 INFO - 'SHELL': '/bin/bash', 09:20:27 INFO - 'SHLVL': '1', 09:20:27 INFO - 'TERM': 'linux', 09:20:27 INFO - 'TMOUT': '86400', 09:20:27 INFO - 'USER': 'cltbld', 09:20:27 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1480092200.869698-777599883', 09:20:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:27 INFO - '_': '/tools/buildbot/bin/python'} 09:20:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:20:28 INFO - Downloading/unpacking psutil>=3.1.1 09:20:28 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 09:20:28 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 09:20:28 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 09:20:28 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 09:20:28 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 09:20:28 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 09:20:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:20:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:20:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:20:30 INFO - Installing collected packages: psutil 09:20:30 INFO - Running setup.py install for psutil 09:20:30 INFO - building 'psutil._psutil_linux' extension 09:20:30 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 09:20:30 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 09:20:30 INFO - building 'psutil._psutil_posix' extension 09:20:30 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 09:20:30 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 09:20:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:20:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:20:30 INFO - Successfully installed psutil 09:20:30 INFO - Cleaning up... 09:20:30 INFO - Return code: 0 09:20:30 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:20:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:20:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:20:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:20:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:20:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3ac878f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x264c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2719ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2716f60>, '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 0x2717d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27181d0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1480092200.869698-777599883', '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 09:20:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:20:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:20:30 INFO - Using env: {'DISPLAY': ':0', 09:20:30 INFO - 'HOME': '/home/cltbld', 09:20:30 INFO - 'LANG': 'en_US.UTF-8', 09:20:30 INFO - 'LANGUAGE': 'en_US:en', 09:20:30 INFO - 'LOGNAME': 'cltbld', 09:20:30 INFO - 'MAIL': '/var/mail/cltbld', 09:20:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:30 INFO - 'MOZ_NO_REMOTE': '1', 09:20:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:20:30 INFO - 'NO_EM_RESTART': '1', 09:20:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:20:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:30 INFO - 'PWD': '/builds/slave/test', 09:20:30 INFO - 'SHELL': '/bin/bash', 09:20:30 INFO - 'SHLVL': '1', 09:20:30 INFO - 'TERM': 'linux', 09:20:30 INFO - 'TMOUT': '86400', 09:20:30 INFO - 'USER': 'cltbld', 09:20:30 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1480092200.869698-777599883', 09:20:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:30 INFO - '_': '/tools/buildbot/bin/python'} 09:20:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:20:30 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:20:30 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 09:20:30 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 09:20:30 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 09:20:30 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 09:20:30 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 09:20:30 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 09:20:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:20:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:20:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:20:32 INFO - Installing collected packages: mozsystemmonitor 09:20:32 INFO - Running setup.py install for mozsystemmonitor 09:20:33 INFO - Successfully installed mozsystemmonitor 09:20:33 INFO - Cleaning up... 09:20:33 INFO - Return code: 0 09:20:33 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:20:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:20:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:20:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:20:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:20:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fa3ac878f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x264c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2719ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2716f60>, '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 0x2717d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27181d0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1480092200.869698-777599883', '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 09:20:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:20:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:20:33 INFO - Using env: {'DISPLAY': ':0', 09:20:33 INFO - 'HOME': '/home/cltbld', 09:20:33 INFO - 'LANG': 'en_US.UTF-8', 09:20:33 INFO - 'LANGUAGE': 'en_US:en', 09:20:33 INFO - 'LOGNAME': 'cltbld', 09:20:33 INFO - 'MAIL': '/var/mail/cltbld', 09:20:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:33 INFO - 'MOZ_NO_REMOTE': '1', 09:20:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:20:33 INFO - 'NO_EM_RESTART': '1', 09:20:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:20:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:33 INFO - 'PWD': '/builds/slave/test', 09:20:33 INFO - 'SHELL': '/bin/bash', 09:20:33 INFO - 'SHLVL': '1', 09:20:33 INFO - 'TERM': 'linux', 09:20:33 INFO - 'TMOUT': '86400', 09:20:33 INFO - 'USER': 'cltbld', 09:20:33 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1480092200.869698-777599883', 09:20:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:33 INFO - '_': '/tools/buildbot/bin/python'} 09:20:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:20:33 INFO - Downloading/unpacking jsonschema==2.5.1 09:20:33 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 09:20:33 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 09:20:33 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 09:20:33 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 09:20:33 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 09:20:33 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 09:20:35 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:20:35 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:20:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:35 INFO - Downloading functools32-3.2.3-2.tar.gz 09:20:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:20:35 INFO - warning: no files found matching '*.txt' 09:20:35 INFO - no previously-included directories found matching 'build' 09:20:35 INFO - no previously-included directories found matching 'dist' 09:20:35 INFO - no previously-included directories found matching '.git*' 09:20:35 INFO - Installing collected packages: jsonschema, functools32 09:20:35 INFO - Running setup.py install for functools32 09:20:35 INFO - warning: no files found matching '*.txt' 09:20:35 INFO - no previously-included directories found matching 'build' 09:20:35 INFO - no previously-included directories found matching 'dist' 09:20:35 INFO - no previously-included directories found matching '.git*' 09:20:35 INFO - Successfully installed jsonschema functools32 09:20:35 INFO - Cleaning up... 09:20:35 INFO - Return code: 0 09:20:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:20: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')]} 09:20:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:20:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20: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')]} 09:20:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:20:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20: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', '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 0x7fa3ac878f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x264c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2719ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2716f60>, '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 0x2717d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27181d0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1480092200.869698-777599883', '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 09:20: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:20: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 functools32==3.2.3-2 09:20:35 INFO - Using env: {'DISPLAY': ':0', 09:20:35 INFO - 'HOME': '/home/cltbld', 09:20:35 INFO - 'LANG': 'en_US.UTF-8', 09:20:35 INFO - 'LANGUAGE': 'en_US:en', 09:20:35 INFO - 'LOGNAME': 'cltbld', 09:20:35 INFO - 'MAIL': '/var/mail/cltbld', 09:20:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:35 INFO - 'MOZ_NO_REMOTE': '1', 09:20:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:20:35 INFO - 'NO_EM_RESTART': '1', 09:20:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:20:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:35 INFO - 'PWD': '/builds/slave/test', 09:20:35 INFO - 'SHELL': '/bin/bash', 09:20:35 INFO - 'SHLVL': '1', 09:20:35 INFO - 'TERM': 'linux', 09:20:35 INFO - 'TMOUT': '86400', 09:20:35 INFO - 'USER': 'cltbld', 09:20:35 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1480092200.869698-777599883', 09:20:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:35 INFO - '_': '/tools/buildbot/bin/python'} 09:20:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:20:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:20:35 INFO - Cleaning up... 09:20:36 INFO - Return code: 0 09:20:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:20:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:20:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:20:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:20:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:20:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fa3ac878f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x264c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2719ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2716f60>, '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 0x2717d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27181d0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1480092200.869698-777599883', '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 09:20:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:20:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:20:36 INFO - Using env: {'DISPLAY': ':0', 09:20:36 INFO - 'HOME': '/home/cltbld', 09:20:36 INFO - 'LANG': 'en_US.UTF-8', 09:20:36 INFO - 'LANGUAGE': 'en_US:en', 09:20:36 INFO - 'LOGNAME': 'cltbld', 09:20:36 INFO - 'MAIL': '/var/mail/cltbld', 09:20:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:36 INFO - 'MOZ_NO_REMOTE': '1', 09:20:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:20:36 INFO - 'NO_EM_RESTART': '1', 09:20:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:20:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:36 INFO - 'PWD': '/builds/slave/test', 09:20:36 INFO - 'SHELL': '/bin/bash', 09:20:36 INFO - 'SHLVL': '1', 09:20:36 INFO - 'TERM': 'linux', 09:20:36 INFO - 'TMOUT': '86400', 09:20:36 INFO - 'USER': 'cltbld', 09:20:36 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1480092200.869698-777599883', 09:20:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:36 INFO - '_': '/tools/buildbot/bin/python'} 09:20:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:20:36 INFO - Downloading/unpacking blobuploader==1.2.4 09:20:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:38 INFO - Downloading blobuploader-1.2.4.tar.gz 09:20:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:20:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:20:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:20:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:20:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:38 INFO - Downloading docopt-0.6.1.tar.gz 09:20:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:20:38 INFO - Installing collected packages: blobuploader, requests, docopt 09:20:38 INFO - Running setup.py install for blobuploader 09:20:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:20:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:20:39 INFO - Running setup.py install for requests 09:20:39 INFO - Running setup.py install for docopt 09:20:39 INFO - Successfully installed blobuploader requests docopt 09:20:39 INFO - Cleaning up... 09:20:39 INFO - Return code: 0 09:20:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:20:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:20:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:20:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:20:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:20:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:39 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 0x7fa3ac878f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x264c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2719ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2716f60>, '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 0x2717d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27181d0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1480092200.869698-777599883', '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 09:20:39 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 09:20:39 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 09:20:39 INFO - Using env: {'DISPLAY': ':0', 09:20:39 INFO - 'HOME': '/home/cltbld', 09:20:39 INFO - 'LANG': 'en_US.UTF-8', 09:20:39 INFO - 'LANGUAGE': 'en_US:en', 09:20:39 INFO - 'LOGNAME': 'cltbld', 09:20:39 INFO - 'MAIL': '/var/mail/cltbld', 09:20:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:39 INFO - 'MOZ_NO_REMOTE': '1', 09:20:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:20:39 INFO - 'NO_EM_RESTART': '1', 09:20:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:20:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:39 INFO - 'PWD': '/builds/slave/test', 09:20:39 INFO - 'SHELL': '/bin/bash', 09:20:39 INFO - 'SHLVL': '1', 09:20:39 INFO - 'TERM': 'linux', 09:20:39 INFO - 'TMOUT': '86400', 09:20:39 INFO - 'USER': 'cltbld', 09:20:39 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1480092200.869698-777599883', 09:20:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:39 INFO - '_': '/tools/buildbot/bin/python'} 09:20:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:20:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:20:39 INFO - Running setup.py (path:/tmp/pip-tU27u1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:20:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:20:39 INFO - Running setup.py (path:/tmp/pip-UCG5Fi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:20:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:20:39 INFO - Running setup.py (path:/tmp/pip-6sCv9p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:20:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:20:40 INFO - Running setup.py (path:/tmp/pip-brhcj7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:20:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:20:40 INFO - Running setup.py (path:/tmp/pip-IsaobR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:20:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:20:40 INFO - Running setup.py (path:/tmp/pip-7vTKLe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:20:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:20:40 INFO - Running setup.py (path:/tmp/pip-8QA8iM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:20:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:20:40 INFO - Running setup.py (path:/tmp/pip-rXAZ69-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:20:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:20:40 INFO - Running setup.py (path:/tmp/pip-Vaieyz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:20:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:20:40 INFO - Running setup.py (path:/tmp/pip-Fjd6Z4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:20:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:20:40 INFO - Running setup.py (path:/tmp/pip-sMl3qJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:20:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:20:40 INFO - Running setup.py (path:/tmp/pip-LOKyEO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:20:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:20:40 INFO - Running setup.py (path:/tmp/pip-5HHyCU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:20:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:20:40 INFO - Running setup.py (path:/tmp/pip-jqwOLU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:20:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:20:40 INFO - Running setup.py (path:/tmp/pip-jj9KCS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:20:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:20:40 INFO - Running setup.py (path:/tmp/pip-B753Dg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:20:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:20:40 INFO - Running setup.py (path:/tmp/pip-FDZpTP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:20:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:20:41 INFO - Running setup.py install for manifestparser 09:20:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:20:41 INFO - Running setup.py install for mozcrash 09:20:41 INFO - Running setup.py install for mozdebug 09:20:41 INFO - Running setup.py install for mozdevice 09:20:41 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:20:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:20:41 INFO - Running setup.py install for mozfile 09:20:41 INFO - Running setup.py install for mozhttpd 09:20:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:20:41 INFO - Running setup.py install for mozinfo 09:20:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:20:41 INFO - Running setup.py install for mozInstall 09:20:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:20:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:20:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:20:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:20:41 INFO - Running setup.py install for mozleak 09:20:41 INFO - Running setup.py install for mozlog 09:20:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:20:42 INFO - Running setup.py install for moznetwork 09:20:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:20:42 INFO - Running setup.py install for mozprocess 09:20:42 INFO - Running setup.py install for mozprofile 09:20:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:20:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:20:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:20:42 INFO - Running setup.py install for mozrunner 09:20:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:20:42 INFO - Running setup.py install for mozscreenshot 09:20:42 INFO - Running setup.py install for moztest 09:20:42 INFO - Running setup.py install for mozversion 09:20:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:20:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:20:42 INFO - Cleaning up... 09:20:42 INFO - Return code: 0 09:20:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:20:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:20:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:20:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:20:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:20:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:42 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 0x7fa3ac878f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x264c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2719ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2716f60>, '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 0x2717d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27181d0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1480092200.869698-777599883', '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 09:20:42 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 09:20:42 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 09:20:42 INFO - Using env: {'DISPLAY': ':0', 09:20:42 INFO - 'HOME': '/home/cltbld', 09:20:42 INFO - 'LANG': 'en_US.UTF-8', 09:20:42 INFO - 'LANGUAGE': 'en_US:en', 09:20:42 INFO - 'LOGNAME': 'cltbld', 09:20:42 INFO - 'MAIL': '/var/mail/cltbld', 09:20:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:42 INFO - 'MOZ_NO_REMOTE': '1', 09:20:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:20:42 INFO - 'NO_EM_RESTART': '1', 09:20:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:20:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:42 INFO - 'PWD': '/builds/slave/test', 09:20:42 INFO - 'SHELL': '/bin/bash', 09:20:42 INFO - 'SHLVL': '1', 09:20:42 INFO - 'TERM': 'linux', 09:20:42 INFO - 'TMOUT': '86400', 09:20:42 INFO - 'USER': 'cltbld', 09:20:42 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1480092200.869698-777599883', 09:20:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:42 INFO - '_': '/tools/buildbot/bin/python'} 09:20:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:20:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:20:43 INFO - Running setup.py (path:/tmp/pip-7wkRuO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:20:43 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)) 09:20:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:20:43 INFO - Running setup.py (path:/tmp/pip-dTmb7V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:20:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:20:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:20:43 INFO - Running setup.py (path:/tmp/pip-CQAwuS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:20:43 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)) 09:20:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:20:43 INFO - Running setup.py (path:/tmp/pip-IVkhp7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:20:43 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)) 09:20:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:20:43 INFO - Running setup.py (path:/tmp/pip-Kcex46-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:20:43 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)) 09:20:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:20:43 INFO - Running setup.py (path:/tmp/pip-OkYY52-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:20:43 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)) 09:20:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:20:43 INFO - Running setup.py (path:/tmp/pip-YuUp13-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:20:43 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)) 09:20:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:20:43 INFO - Running setup.py (path:/tmp/pip-lm6d91-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:20:43 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)) 09:20:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:20:43 INFO - Running setup.py (path:/tmp/pip-DKBCgO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:20:43 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)) 09:20:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:20:43 INFO - Running setup.py (path:/tmp/pip-511Qk7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:20:43 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)) 09:20:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:20:43 INFO - Running setup.py (path:/tmp/pip-XunNXY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:20:43 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)) 09:20:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:20:43 INFO - Running setup.py (path:/tmp/pip-HkNa7m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:20:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:20:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:20:44 INFO - Running setup.py (path:/tmp/pip-bLfgQ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:20:44 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)) 09:20:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:20:44 INFO - Running setup.py (path:/tmp/pip-3RWf99-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:20:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:20:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:20:44 INFO - Running setup.py (path:/tmp/pip-GBlo5T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:20:44 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)) 09:20:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:20:44 INFO - Running setup.py (path:/tmp/pip-Wgo2Hb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:20:44 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)) 09:20:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:20:44 INFO - Running setup.py (path:/tmp/pip-L1H7w6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:20:44 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)) 09:20:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:20:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:20:44 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)) 09:20:44 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)) 09:20:44 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)) 09:20:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:20:44 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 09:20:44 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 09:20:44 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 09:20:44 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 09:20:44 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 09:20:44 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 09:20:46 INFO - Downloading blessings-1.6.tar.gz 09:20:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:20:46 INFO - Installing collected packages: blessings 09:20:46 INFO - Running setup.py install for blessings 09:20:46 INFO - Successfully installed blessings 09:20:46 INFO - Cleaning up... 09:20:46 INFO - Return code: 0 09:20:46 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:20:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:20:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:20:46 INFO - Reading from file tmpfile_stdout 09:20:46 INFO - Current package versions: 09:20:46 INFO - argparse == 1.2.1 09:20:46 INFO - blessings == 1.6 09:20:46 INFO - blobuploader == 1.2.4 09:20:46 INFO - docopt == 0.6.1 09:20:46 INFO - functools32 == 3.2.3-2 09:20:46 INFO - jsonschema == 2.5.1 09:20:46 INFO - manifestparser == 1.1 09:20:46 INFO - mozInstall == 1.12 09:20:46 INFO - mozcrash == 1.0 09:20:46 INFO - mozdebug == 0.1 09:20:46 INFO - mozdevice == 0.48 09:20:46 INFO - mozfile == 1.2 09:20:46 INFO - mozhttpd == 0.7 09:20:46 INFO - mozinfo == 0.9 09:20:46 INFO - mozleak == 0.1 09:20:46 INFO - mozlog == 3.4 09:20:46 INFO - moznetwork == 0.27 09:20:46 INFO - mozprocess == 0.23 09:20:46 INFO - mozprofile == 0.28 09:20:46 INFO - mozrunner == 6.13 09:20:46 INFO - mozscreenshot == 0.1 09:20:46 INFO - mozsystemmonitor == 0.3 09:20:46 INFO - moztest == 0.7 09:20:46 INFO - mozversion == 1.4 09:20:46 INFO - psutil == 3.1.1 09:20:46 INFO - requests == 1.2.3 09:20:46 INFO - wsgiref == 0.1.2 09:20:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:20:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:20:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:20:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:20:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:20:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:46 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 0x7fa3ac878f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x264c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2719ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2716f60>, '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 0x2717d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27181d0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1480092200.869698-777599883', '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 09:20:46 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 09:20:46 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 09:20:46 INFO - Using env: {'DISPLAY': ':0', 09:20:46 INFO - 'HOME': '/home/cltbld', 09:20:46 INFO - 'LANG': 'en_US.UTF-8', 09:20:46 INFO - 'LANGUAGE': 'en_US:en', 09:20:46 INFO - 'LOGNAME': 'cltbld', 09:20:46 INFO - 'MAIL': '/var/mail/cltbld', 09:20:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:46 INFO - 'MOZ_NO_REMOTE': '1', 09:20:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:20:46 INFO - 'NO_EM_RESTART': '1', 09:20:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:20:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:46 INFO - 'PWD': '/builds/slave/test', 09:20:46 INFO - 'SHELL': '/bin/bash', 09:20:46 INFO - 'SHLVL': '1', 09:20:46 INFO - 'TERM': 'linux', 09:20:46 INFO - 'TMOUT': '86400', 09:20:46 INFO - 'USER': 'cltbld', 09:20:46 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1480092200.869698-777599883', 09:20:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:46 INFO - '_': '/tools/buildbot/bin/python'} 09:20:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:20:47 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)) 09:20:47 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)) 09:20:47 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)) 09:20:47 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)) 09:20:47 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)) 09:20:47 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)) 09:20:47 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)) 09:20:47 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)) 09:20:47 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)) 09:20:47 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)) 09:20:47 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)) 09:20:47 INFO - Cleaning up... 09:20:47 INFO - Return code: 0 09:20:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:20:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:20:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:20:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:20:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:20:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:47 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 0x7fa3ac878f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x264c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2719ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2716f60>, '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 0x2717d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27181d0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1480092200.869698-777599883', '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 09:20:47 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 09:20:47 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 09:20:47 INFO - Using env: {'DISPLAY': ':0', 09:20:47 INFO - 'HOME': '/home/cltbld', 09:20:47 INFO - 'LANG': 'en_US.UTF-8', 09:20:47 INFO - 'LANGUAGE': 'en_US:en', 09:20:47 INFO - 'LOGNAME': 'cltbld', 09:20:47 INFO - 'MAIL': '/var/mail/cltbld', 09:20:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:47 INFO - 'MOZ_NO_REMOTE': '1', 09:20:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:20:47 INFO - 'NO_EM_RESTART': '1', 09:20:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:20:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:47 INFO - 'PWD': '/builds/slave/test', 09:20:47 INFO - 'SHELL': '/bin/bash', 09:20:47 INFO - 'SHLVL': '1', 09:20:47 INFO - 'TERM': 'linux', 09:20:47 INFO - 'TMOUT': '86400', 09:20:47 INFO - 'USER': 'cltbld', 09:20:47 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1480092200.869698-777599883', 09:20:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:47 INFO - '_': '/tools/buildbot/bin/python'} 09:20:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:20:47 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:20:47 INFO - Cleaning up... 09:20:47 INFO - Return code: 0 09:20:47 INFO - Running post-action listener: _resource_record_post_action 09:20:47 INFO - Running post-action listener: _start_resource_monitoring 09:20:47 INFO - Starting resource monitoring. 09:20:47 INFO - [mozharness: 2016-11-25 17:20:47.312459Z] Finished create-virtualenv step (success) 09:20:47 INFO - [mozharness: 2016-11-25 17:20:47.312922Z] Running install step. 09:20:47 INFO - Running pre-action listener: _resource_record_pre_action 09:20:47 INFO - Running main action method: install 09:20:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:20:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:20:47 INFO - Reading from file tmpfile_stdout 09:20:47 INFO - Detecting whether we're running mozinstall >=1.0... 09:20:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:20:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:20:47 INFO - Reading from file tmpfile_stdout 09:20:47 INFO - Output received: 09:20:47 INFO - Usage: mozinstall [options] installer 09:20:47 INFO - Options: 09:20:47 INFO - -h, --help show this help message and exit 09:20:47 INFO - -d DEST, --destination=DEST 09:20:47 INFO - Directory to install application into. [default: 09:20:47 INFO - "/builds/slave/test"] 09:20:47 INFO - --app=APP Application being installed. [default: firefox] 09:20:47 INFO - mkdir: /builds/slave/test/build/application 09:20:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:20:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:21:03 INFO - Reading from file tmpfile_stdout 09:21:03 INFO - Output received: 09:21:03 INFO - /builds/slave/test/build/application/firefox/firefox 09:21:03 INFO - Running post-action listener: _resource_record_post_action 09:21:03 INFO - [mozharness: 2016-11-25 17:21:03.583359Z] Finished install step (success) 09:21:03 INFO - [mozharness: 2016-11-25 17:21:03.583600Z] Running run-tests step. 09:21:03 INFO - Running pre-action listener: _resource_record_pre_action 09:21:03 INFO - Running main action method: run_tests 09:21:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:21:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:21:03 INFO - Python 2.7.3 09:21:03 INFO - Return code: 0 09:21:03 INFO - grabbing minidump binary from tooltool 09:21:03 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')]} 09:21:03 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 0x2716f60>, '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 0x2717d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27181d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:21:03 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 09:21:03 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 09:21:03 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 09:21:03 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:21:03 INFO - Return code: 0 09:21:03 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:21:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:21:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:21:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:21:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:21:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QEbwtMvvQWKP5smYDPfgeQ/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-062', '--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 09:21:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/QEbwtMvvQWKP5smYDPfgeQ/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-062 --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 09:21:03 INFO - Using env: {'DISPLAY': ':0', 09:21:03 INFO - 'HOME': '/home/cltbld', 09:21:03 INFO - 'LANG': 'en_US.UTF-8', 09:21:03 INFO - 'LANGUAGE': 'en_US:en', 09:21:03 INFO - 'LOGNAME': 'cltbld', 09:21:03 INFO - 'MAIL': '/var/mail/cltbld', 09:21:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:21:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:21:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:21:03 INFO - 'MOZ_NO_REMOTE': '1', 09:21:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:21:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:21:03 INFO - 'NO_EM_RESTART': '1', 09:21:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:21:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:21:03 INFO - 'PWD': '/builds/slave/test', 09:21:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:21:03 INFO - 'SHELL': '/bin/bash', 09:21:03 INFO - 'SHLVL': '1', 09:21:03 INFO - 'TERM': 'linux', 09:21:03 INFO - 'TMOUT': '86400', 09:21:03 INFO - 'USER': 'cltbld', 09:21:03 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1480092200.869698-777599883', 09:21:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:21:03 INFO - '_': '/tools/buildbot/bin/python'} 09:21:03 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QEbwtMvvQWKP5smYDPfgeQ/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-062', '--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 09:21:03 INFO - mozversion application_buildid: 20161125063919 09:21:03 INFO - mozversion application_changeset: d5179b827ff8b1e942fa0143f9b59fe28c54c4c0 09:21:03 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:21:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:21:03 INFO - mozversion application_name: Firefox 09:21:03 INFO - mozversion application_remotingname: firefox-dev 09:21:03 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:21:03 INFO - mozversion application_vendor: Mozilla 09:21:03 INFO - mozversion application_version: 52.0a2 09:21:03 INFO - mozversion platform_buildid: 20161125063919 09:21:03 INFO - mozversion platform_changeset: d5179b827ff8b1e942fa0143f9b59fe28c54c4c0 09:21:03 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:21:03 INFO - mozversion platform_version: 52.0a2 09:21:03 INFO - using testdate: 1480094463 09:21:03 INFO - actual date: 1480094463 09:21:03 INFO - starting webserver on 'localhost:34338' 09:21:03 INFO - SUITE-START | Running 2 tests 09:21:03 INFO - TEST-START | dromaeo_css 09:21:03 INFO - operating with platform_type : linux_ 09:21:03 INFO - Initialising browser for dromaeo_css test... 09:21:03 INFO - TEST-INFO | started process 2237 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8Nn7fS/profile http://localhost:34338/getInfo.html) 09:21:07 INFO - PROCESS | 2237 | __metrics Screen width/height:1600/1200 09:21:07 INFO - PROCESS | 2237 | colorDepth:24 09:21:07 INFO - PROCESS | 2237 | Browser inner width/height: 1024/697 09:21:07 INFO - PROCESS | 2237 | __metrics 09:21:07 INFO - PROCESS | 2237 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:21:07 INFO - PROCESS | 2237 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:21:08 INFO - PROCESS | 2237 | console.error: PushService: 09:21:08 INFO - PROCESS | 2237 | stateChangeProcessEnqueue: Error transitioning state 09:21:08 INFO - PROCESS | 2237 | UnknownError 09:21:08 INFO - TEST-INFO | 2237: exit 0 09:21:08 INFO - Browser initialized. 09:21:08 INFO - Running cycle 1/1 for dromaeo_css test... 09:21:08 INFO - TEST-INFO | started process 2329 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8Nn7fS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:21:20 INFO - PROCESS | 2329 | RSS: Main: 144691200 09:21:20 INFO - PROCESS | 2329 | 09:22:11 INFO - PROCESS | 2329 | 1480094531376 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] 09:24:22 INFO - PROCESS | 2329 | Cycle 1(1): loaded http://localhost:34338/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34338/tests/dromaeo/cssquery-ext.html) 09:24:23 INFO - PROCESS | 2329 | RSS: Main: 243376128 09:24:23 INFO - PROCESS | 2329 | 09:27:26 INFO - PROCESS | 2329 | Cycle 1(1): loaded http://localhost:34338/tests/dromaeo/cssquery-ext.html (next: http://localhost:34338/tests/dromaeo/cssquery-jquery.html) 09:27:26 INFO - PROCESS | 2329 | RSS: Main: 226271232 09:27:26 INFO - PROCESS | 2329 | 09:30:28 INFO - PROCESS | 2329 | Cycle 1(1): loaded http://localhost:34338/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34338/tests/dromaeo/cssquery-mootools.html) 09:30:29 INFO - PROCESS | 2329 | RSS: Main: 212176896 09:30:29 INFO - PROCESS | 2329 | 09:33:31 INFO - PROCESS | 2329 | Cycle 1(1): loaded http://localhost:34338/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34338/tests/dromaeo/cssquery-prototype.html) 09:33:32 INFO - PROCESS | 2329 | RSS: Main: 222982144 09:33:32 INFO - PROCESS | 2329 | 09:36:34 INFO - PROCESS | 2329 | Cycle 1(1): loaded http://localhost:34338/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34338/tests/dromaeo/cssquery-yui.html) 09:36:35 INFO - PROCESS | 2329 | RSS: Main: 174395392 09:36:35 INFO - PROCESS | 2329 | 09:39:37 INFO - PROCESS | 2329 | Cycle 1(1): loaded http://localhost:34338/tests/dromaeo/cssquery-yui.html (next: http://localhost:34338/tests/dromaeo/cssquery-dojo.html) 09:39:37 INFO - PROCESS | 2329 | RSS: Main: 267362304 09:39:37 INFO - PROCESS | 2329 | 09:39:37 INFO - PROCESS | 2329 | __start_tp_report 09:39:37 INFO - PROCESS | 2329 | _x_x_mozilla_page_load 09:39:37 INFO - PROCESS | 2329 | _x_x_mozilla_page_load_details 09:39:37 INFO - PROCESS | 2329 | |i|pagename|runs| 09:39:37 INFO - PROCESS | 2329 | |0;dojo.html;3245.9837700811495;3278.7704860659755;3287.5068739689045;3315.9005229843115;3329.9011326262334;5480.26016487774;5561.4716601922855;5567.3040869891265;5569.331680198372;5590.4409559044125;5607.663540187592;5669.914951275724;5693.857653558653;5696.544276457884;5710.657360558359;5095.210242412422;5099.71951542665;5128.794848206067;5129.9230511542355;5187.740612969355;4934.111859865223;5200.297959775438;5201.55786758126;5206.140986737184;5207.036698210824;4933.827316043939;5007.0486607544635;5046.4448910619985;5059.367579052619;5085.771140298695;5116.718580478072;5147.433782283946;5149.433562308143;5209.348831396075;5217.582593392557;5038.39539255288;5107.901801112778;5180.5337519623035;5191.714455704935;5224.712640804754;5288.735563221823;5319.20211968204;5482.451754824526;5487.478689524489;5516.531094856942;5969.492593129589;5984.790532331391;6042.969785151046;6072.939270607281;6084.726187321537;6118.112873633343;6128.479079278267;6136.723847426876;6143.078538219303;6146.323904370515;5427.402985671612;5467.699276539828;5474.562035037267;5517.862053448696;5520.972395137998;6060.2727672678675;6157.507399408126;6176.135341052256;6181.81454556364;6184.041473571604;5892.941070589325;5896.64620122794;5903.498202652822;5911.142884281841;5913.113033044991;5723.141528770718;5767.625104368202;5992.250968628921;6031.788887388921;6035.1249068885645;6037.63774173551;6048.637081775108;6055.394460553997;6066.029435290332;6090.512758979271;5991;6039.607425517327;6054.122152287893;6056.515478761688;6066.939330606726;4451.354553589711;4465.732056076646;4607.193741095295;4637.814487420533;4679.391679081766;4148.937765933513;4174.812133454002;4194.433751443577;4195.538490766014;4218.135282267128;5790.565707571865;5806.709664516758;5809.390014048548;5810.6513609183585;5810.854728631818;5696.287964004499;5755.280589926259;5972.641641501524;6097.634141951408;6115.1744514490865;5697.886042279131;5777.884442311214;5783.971080144573;5790.10253410722;5810.970945145247;5244.134717771602;5261.105612045962;5265.815696450606;5275.155975044051;5282.683039017671;5951.821445356646;6006.399360063959;6261.530385221035;6311.621302721759;6328.208973878265;5893.5874488785375;5907.4683278505145;5933.258342707161;5997.400259973968;6037.969810150921;6224.035274532454;6231.75072997075;6235.781747638811;6244;6257.311695713559;5620.971895140498;5894.528437725057;5894.970525147347;5896.027155519291;5925.081612350093;5712.285964254468;6017.638941663514;6059.151718759289;6063.878722425615;6150.415710507495;5899.528037757055;5953.047512397996;5964.821055368346;5978.551608629457;5989.1914591529585;5926.140709596998;5941.584089113893;5942.732577033958;5952.8809423812145;5963.582549221513;5501.889962200654;5616.606837521498;5781.335146458043;5795.710214489343;5858.502027327725;5966.075258334965;5973.641581505123;5992.161097446448;5993.850153746192;6001.609895356963;5504.064309067388;5556.611037227355;5825.70871456434;5833.620814647034;5835.183074369676 09:39:37 INFO - PROCESS | 2329 | |1;ext.html;559.8264537993223;598.7604958016793;614.2138063279003;640.9487241020719;646.2051676437984;33636.83181584092;44040.33939490906;44513.332300015485;44545.33182002272;44832.775836120854;45414;45746.7712661437;45831.31253031199;45944;46027.76986115073;8041.839163216732;8697.695580654678;8886.066962968893;8891.155340242667;8903.554822258893;8046.839063218732;10456.895431045668;10694.197935154894;10694.304870183452;11022.834657480144;10758.139348852073;10768.461576921161;10831.67504974852;10832.350058996446;10834.349939003645;10531.578736850555;10550;10602.522886470128;10603.469826508644;10609.045185933302;10461.110805581535;10490.21323400748;10555.102816260627;10574.577016919315;10576.788464230673;41639.7918010411;42030;42042.369364459555;42055.78972105151;42076.57923420788;3640.12636967126;3769.924601507982;3771.17034252464;3837.1174629835014;4043.676505879552;30788.846055769576;33296.50055249173;33316.50025249623;33519.6648033519;33571.83214083938;4375.649948004184;4418.656549877266;4450.910981780378;4478.4178056852725;4488.236999710057;359988;368922.9999999973;369851;370129;370471;68465.65767171132;69382.65308673526;69424.6528767363;69521.65239173772;69591;40173;40321.59678403178;40531;44535.777321113834;44690;17639.823601764074;18015.2793888243;18020.098995050193;18029.7295540567;18029.909850450927;12125.333106679042;12295.385230738428;13555.661108472366;13662.248576328397;13891.930540347234;20517.17931282732;21803.236886709205;21939.451513712283;22145.55708885813;22194;7858.253465920729;7900.920990790133;8010.394023436547;8127.959360203279;8231.670733170726;961.8605302231277;1053.963111291101;1144.553624086607;1145.2899202494507;1176.505867535637;30257.394852102836;32112.83943580267;32344.676553234167;32540.186495337333;32758.67241327604;1098.6209757633603;1112.2825777373548;1144.3534403062242;1165.830954511591;1181.6100686773343;15289.9235503824;17456.738148927776;17599;18377.17302721354;18571.72142417865;509694;509909;512295;512625;513506;61739.07391389133;62225;62519;62534.68732656308;62536.37463625397;1325.9535916242885;1360.1023324605712;1383.3359987205997;1392.7771556551106;1465.501729412005;30551.694483054885;34229;34416.31167376688;34508.65491345155;35073.1231719209;51137.744311278206;51900.48099519107;51924.480755191966;51950.480495196076;51964.48035519596;402206;402639;404009;404618;404726;27905.72094279031;28174.577381338888;28408.715912840606;28525;31017.379652406373;49861.750691246314;50089;50117.997640047724;50604.24093638525;51466;40808;41429.58570414257;42037.36943945782;42325.36511952385;42749.572504275806;41159.7942010288;41529.58470415257;41758.16483670249;43187.35218971781;43588.56411435845;41716.165676686905;42975.355369668854;43967;44007.119857603306;45829.08341833211;35602.8219858899;38262.42606360962;38460.230795384494;38520.61479385282;39262.214755705296;28946.710532895246;28959.276018099717;29027.709722902502;29319.560206596492;29348.55977160387 09:39:37 INFO - PROCESS | 2329 | |2;jquery.html;393.5965635223897;435.9520452750197;437.42041794622116;443.3814828314501;446.1040577545434;16576.33694652214;19365.128569214383;20192.192312307507;20914;21137.57724845502;19332.613347733037;20189.59620807583;20207.49481262969;20343.59312813743;20438.69341959868;5724.62789918656;5965.791197308095;5966.522678185735;5969.373215812342;5977.402259774031;5532.834014979557;5863.76544938202;5888.470037696607;5894.823155305347;5900.822975310747;5658.066419040853;5865.560082993771;5868.882622347551;5870.911936320958;5880.2355693759755;5781.306243250795;5809.7676092956235;5849.181114643953;5856.180134781134;5857.619254748428;5087.643864929457;5233.659812112239;5234.26720259164;5256.185291279858;5276.762545685453;23561.88219058911;23654.526909461885;23759.287221383387;23877;23906.28281151568;2486.987565062163;2524.5329614021466;2541.707703614071;2658.5746280595204;3040.726334629894;14623;15015.324310406058;15127.621809454742;15720.449784257497;15985.520434386986;1905.1141219333076;1918.7985261547615;1923.2595450751421;1970.9408717738347;2019.3941817454852;87566.56216718939;88268;88346;89076;89224.55387723085;10198.18414526836;11206.439678016066;11385.259958102697;11510.48202830858;11820.349880756641;15049.021813582081;15221.695566088616;15889.28498217583;15904.284307206204;16625.168741562873;8094.716684915999;8131.14622964592;8249.257566819015;8327.167283271745;8392;7758.883616745753;7765.961170194226;7862.724804631925;7932.801679957932;7958;6946.548474349152;6982.685779139951;7076.716931322792;7103.183133939559;7116.323949224816;1929.9614007720047;1977.703344498308;1992.0377981487645;2032.9365693820785;2059.969100463494;966.7244835221928;1013.0426746724274;1020.0564477857952;1022.2128960700219;1125.7579620381657;20190.495237619176;20887.164513419597;21030.894845525676;21098.894505527373;21284.574308513744;1019.2712210769336;1036.3678156324636;1077.8652668416448;1095.0691911874842;1111.5053801058614;10958.28771129874;11837.230580012272;11924.821127683092;11937.522499100112;11998.040156787436;499833.5858523165;501568;505389;505960;508693;79620.60189699015;87250.99999999873;87259.56370218108;89343.10656893348;93158;1073.7047311989265;1077.1006209814736;1087.7933192693363;1088.2980477592066;1121.9943900280446;18528;18732.157052932922;18870.622587547896;19024.1439135242;19056.90471547689;33956.15109622279;42846.92882677958;43830.561694382646;45854.77072614749;46156.76921615505;63961.3603863974;64588;65140.67429662821;66002.33997659961;66723;8816.118388161132;9045.276377889884;9311.627534898527;9329.766755831159;9401.200897923753;13298.335083245605;13311;13455.86544134546;13642.386092625657;13679.38442770058;11500.539978401104;12172.817407738714;12220;12303.630891073282;12387.69030774238;11673;12013.399330033639;12145;12316;12554.686132846751;11780.52877884875;12131.939340303243;12200.51197952072;12207.877921220674;12661;8741.475511469333;8840.248578870867;8942.284617230736;9092.318076144447;9300.488473134043;9276.814463710823;11500.137489688139;11982;12423.192492487959;12520.749585008067 09:39:37 INFO - PROCESS | 2329 | |3;mootools.html;1377.3870627570734;1503.1507198432887;1545.3818472610953;1677.8657707383402;1827.2325623238246;7838.608069596518;8409.369297302705;8784.692535761249;8815.647374105029;9039.231665308456;8804.075572064921;8878.556072196398;8883.644654213824;8897.199252067312;8922.687705930293;2493.164789795421;2519.571672815626;2519.659845920805;2520.231329444519;2523.823332366726;2468.8395254308502;2525.785308248792;2526.7978561715113;2528.645989561463;2530.177692250017;2469;2475.1460746042676;2489.9293303879326;2497.213377786001;2498.225550079472;2407.121400688745;2425.260295609838;2457.041753716052;2464.5194187133516;2471.5180539794746;2340.9531809363716;2365.101261520611;2389.462370966536;2405.2663937499055;2422.406510404962;8277.130901255341;8791.384603077788;8813.250873675745;8862.734117976406;8868.955655221682;5530.640508366943;5746.856328591777;5758.8770189813;5766.365699773021;5770.480656740871;3717.212090931801;3741.906452338686;3832.5784163741964;3847.499825022729;3885.6891448684037;1263.6777621706406;1287.0990306785288;1302.986970130296;1330.05566048105;1341.557286095586;12219.938900305531;12337.69155771095;12346.75306493874;12358.258504489759;12385.318807465586;4701.224297990793;4754.643401744883;4791.880202994953;4842.709437433765;4851.684640498356;10033.749156271006;10039.297249192632;10041.297109202433;10050.59797608102;10070.043345882275;5475.589330800206;5499.065158922993;5499.670019798745;5499.780008799603;5515.834524964257;4183.163367326486;4438.223310920576;4445.977770111129;4975.029869175476;5090.211017292326;4004.6596039336982;4060.5939406059156;4121.196366708523;4159.812808423568;4173.269677806372;1255.6044845873703;1281.333706472629;1285.8714128587067;1285.984072582668;1298.461138627478;824.5258976088772;953.3279038277998;1009.8586197932295;1010.2069875147973;1037.3931250218707;5836.8540786479825;6014.729337179838;6097.634141951497;6170.197874276405;6339.714712837934;473.68973322473835;479.4534230976689;485.66489122506067;490.8821882748186;493.50156342094743;4258.148370325947;4263.147370525907;4329.631981281564;4339.088791353851;4443.311286750559;10705.892941070646;10949;11053.502592383362;11081.612143574936;11126.165537584713;11056.502457389437;11073.50169242386;11073.667789966314;11075.446227688582;11092.168087393316;7983.2415920487465;8271.421000530025;8293.875591866126;8429.157084291523;8460.619272132646;6183.876322473615;6322.672238829898;6406.261684572513;6451.41937225652;6486.027095935458;10118;10147;10216.335938163995;10271.383716977005;10393.428361440194;9792;10006.799864002534;10036.397816131055;10439.269251152346;10447.947760261453;7798.1032181297605;7953.363730901629;8103.675852966051;8158.551279679437;8227.177282271725;8338.66645334204;8411.411201215857;8416.158384161536;8427;8440.704575339834;7381.298776616213;7619.542827430372;7838.960805196166;7868.84262314762;7926.920730792849;7697.422693297913;7810.140884502586;7816.53100813953;7891.487053341514;7974.481658692166;6867.3476019778045;7088.787336379917;7232.674529646073;7307.671154798153;7311.963440182765;5290.391604965478;5359.088954877602;5455.890882182413;5470;5506.476884695948;5374.677519348852;5434.728263586726;5466.562674986071;5522.447755224445;5608.046632072476 09:39:37 INFO - PROCESS | 2329 | |4;prototype.html;335.88076232937306;340.2412619857717;349.5962163700926;352.91882866940585;355.73675480144703;5242.973785131074;5296.894062118756;5321.92017119743;5332;5339.652922560031;5257.158854583267;5258.842234732964;5269.2611438225385;5279.234510995903;5286.286351342567;4021.9689057026308;4204.306289462235;4205;4219.767912764797;4220.810063547147;4042.151148258869;4111.671066314688;4116.876493705194;4152.007036622423;4166.916661666764;4115.156392939441;4120.835166593332;4126.958730412687;4127.896802579945;4131.987663022549;4010.1177740896956;4123.649489793341;4132.462779838616;4140.834366625331;4144.191882582898;3525.506429099928;3624.6012938577;3650.689691376236;3655.250673611903;3680.595134535199;5125;5130.1535246684625;5135.974320128413;5141.203113517411;5145.716985565792;4597.310403439477;4712.670113092085;4716.787744551469;4739.601817463857;4741.31250968611;5450.5367043801325;5498.230247765276;5507.4767897050115;5508.55931525477;5521.254630624854;4027.7715296834745;4035.1727895781587;4039.8284497495692;4040.1717647882115;4051.8379264829373;5521.5306698930635;5732.369439361667;5735.856603584902;5753.424657534255;5762.386531771111;4085.4076158956786;4096.959030409717;4127.422160897417;4127.823570282455;4133.338665813455;4169.8957526062095;4183.3515805050265;4191.287481128215;4193.853215137456;4196.853110141131;4729.037449458437;4733.36099626546;4737.976310118427;4739.312799644033;4745.311929770164;5351.224072509464;5440.538758218302;5494.615376923657;5535.169724541351;5537.612367134342;1391.3947432866735;1391.8538553451945;1391.8886489080849;1391.979120313196;1394.1913690059944;948.2887834124407;989.9010098990187;1003.4230317567428;1005.1958433253368;1006.1799633298838;5217.426083130853;5226.5818734501145;5241.580673546107;5325.520703136659;5345.075772721817;5601.579881508822;5601.859953501195;5613.821097569475;5629.746661400165;5668.0404645934805;5440.3471583409455;5452.26394436746;5452.391544494384;5476.945230547643;5478.45215478457;2848.2879280179955;2931.9853400733286;2951.9852400738287;2969.777266705006;2980.940381192364;5756.294541565458;5773.393793651606;5778.191053252548;5791.189233507313;5806.65160090396;5501.394846566875;5603.732491458017;5651.4348565143155;5721.256236689203;5728.942710572841;5735.713214339266;5750;5750.84983003401;5759.625624334489;5776.855578610568;5518;5524.751386187711;5537.418571050143;5558.833235002957;5572.665640061609;5250.212468129811;5434.826069572203;5472.863178420412;5495.807646732345;5548.4174161712435;5685.289338832646;5698.857528561653;5714.285714285715;5716.971415142898;5748.022836117786;4094.529129150186;4112.280350938634;4113.177364527166;4126.731762435432;4147.3986271991;5394.298741163623;5690.236026831695;5716.256886604715;5733.569982251264;5734.541236701138;5629.943700562942;5636.661800291831;5665.688387138747;5668.319801623831;5681.318241811009;5527.806526771705;5552.7238929030445;5578.832635020955;5643.661380317194;5652.812909288931;5529.889402212014;5539.7230138492105;5564.805231816867;5582.688068304026;5595.216669666169;5372.811951581676;5439.864003399788;5551.444855514416;5592.524635406036;5610.523105535912;5462.808801691922;5487.64330318528;5497.587680923867;5526.723663816873;5528.640638358494 09:39:37 INFO - PROCESS | 2329 | |5;yui.html;799.2966189753016;816.5590581086213;817.1420008990555;855.9400841941065;898.6630013744846;13874.583762487116;16324.918375408137;16465.094419806905;16512.091834949104;16517;14506.564803055899;14767.778483322758;14791.630209244748;14884.404623815062;14902.478413255538;721.26070777453;838.5094719589048;855.7732200966718;857.8841856349406;858.1332853817627;729.1323325242944;832.8167803083312;836.69042454292;849.7195925344621;854.0690647194557;846.2468403121196;852.3479538153317;853.9957300213521;857.4555157475017;857.7641148684099;742.0724094881398;851.642310229705;853.0658928473312;853.7310747114639;857.37840065952;558.7290164270325;637.08260105448;638.7796210307437;646.8059582125344;647.1683886206249;15038.32327545263;15077.170755608437;15090.24548772557;15099.471518496799;15109.697806043818;13559.932200338935;13819.378127984164;13854.238016909067;13875;13907.235102069382;12177.452014659362;12351.258924464471;12379.319137447437;12426.068044896578;12524.123311368208;3794.354959656865;4415.536368681274;4424.535423780521;4438.889027774331;4514.435695538058;59880.40119598792;67567.66216168937;67782.66108669474;67839.66080169567;68715.9999999995;11340.546378144782;11377.01643954042;11391.715207119723;11478.253913495597;11577.073834093253;19603.31388401399;19634.993076765335;19838.90080549588;19841.702374464396;19859.205631774857;7998.920010799934;8062.475939063942;8104.432689711782;8187.304079153219;8280.8343833123;4676.041411510632;4699.835505757282;4755.595774359149;4817.542333478314;4924.4090709115135;3469.8959031229106;3567;3575.4100573405617;3576.3741345264475;3579.749417540799;642.6336987916842;656.8226578823691;664.6012392564519;666.0774826864828;666.4734859460972;559.7621011070361;570.5179123640518;664.9368310010541;735.4741359927677;747.1557140431278;9287.303452241109;9593;9779.51102444875;9790.804183916282;10020.799584008279;715.2061212054615;754.5585832288173;763.2329508843577;765.3800421658475;788.3496115704567;4666.772421724754;4676.55572720591;4682.882927926829;4714.104320179155;4737.336772851803;56486.152707709414;75138;92207;93283.53358233166;95702;49986.250206246936;63204.68397657982;85197.14802852015;87459.56270218735;87937;71356;72037;72173.63913180401;73301.26698732945;74868;8748.69379571712;8979.10208979097;9009.54952252398;9042.457452552604;9062.864057039282;49024.26463602977;50765.49234507755;51585.484145158065;51872.74063629658;53128.468715313895;65431.34568654443;69885.65057174681;71350.92973605504;71625.28374716185;74908;11267.154963377943;11322.886771132184;11644;11648.41757912118;11783.998360139141;12913;12997.805032924325;13288.734225315633;13396.397162127923;13493.595192144252;12286.75426491483;12516.506460566145;12569.308688022247;12598.307093109966;13016.088873778745;11693.356865372487;12301.815472767737;12468.625941221779;12545.247285162919;12773.169743966613;12537.373131343214;12541;12663.556775512812;13006.349682516025;13106.771848785538;9508.952455237957;9510.239180865652;9587.616495340108;9761.69996967173;10281.2803103785;10146.847797282899;10521.368717876952;10818.404987725437;10834.187435942493;10939.50772215268 09:39:37 INFO - PROCESS | 2329 | __end_tp_report 09:39:37 INFO - PROCESS | 2329 | __start_cc_report 09:39:37 INFO - PROCESS | 2329 | _x_x_mozilla_cycle_collect,1060 09:39:37 INFO - PROCESS | 2329 | __end_cc_report 09:39:37 INFO - PROCESS | 2329 | __startTimestamp1480095577961__endTimestamp 09:39:37 INFO - PROCESS | 2329 | 09:39:37 INFO - PROCESS | 2329 | ------- Summary: start ------- 09:39:37 INFO - PROCESS | 2329 | Number of tests: 6 09:39:37 INFO - PROCESS | 2329 | 09:39:37 INFO - PROCESS | 2329 | [#0] dojo.html Cycles:165 Average:5585.52 Median:5806.71 stddev:628.38 (10.8%) stddev-sans-first:602.91 09:39:37 INFO - PROCESS | 2329 | Values: 3246.0 3278.8 3287.5 3315.9 3329.9 5480.3 5561.5 5567.3 5569.3 5590.4 5607.7 5669.9 5693.9 5696.5 5710.7 5095.2 5099.7 5128.8 5129.9 5187.7 4934.1 5200.3 5201.6 5206.1 5207.0 4933.8 5007.0 5046.4 5059.4 5085.8 5116.7 5147.4 5149.4 5209.3 5217.6 5038.4 5107.9 5180.5 5191.7 5224.7 5288.7 5319.2 5482.5 5487.5 5516.5 5969.5 5984.8 6043.0 6072.9 6084.7 6118.1 6128.5 6136.7 6143.1 6146.3 5427.4 5467.7 5474.6 5517.9 5521.0 6060.3 6157.5 6176.1 6181.8 6184.0 5892.9 5896.6 5903.5 5911.1 5913.1 5723.1 5767.6 5992.3 6031.8 6035.1 6037.6 6048.6 6055.4 6066.0 6090.5 5991.0 6039.6 6054.1 6056.5 6066.9 4451.4 4465.7 4607.2 4637.8 4679.4 4148.9 4174.8 4194.4 4195.5 4218.1 5790.6 5806.7 5809.4 5810.7 5810.9 5696.3 5755.3 5972.6 6097.6 6115.2 5697.9 5777.9 5784.0 5790.1 5811.0 5244.1 5261.1 5265.8 5275.2 5282.7 5951.8 6006.4 6261.5 6311.6 6328.2 5893.6 5907.5 5933.3 5997.4 6038.0 6224.0 6231.8 6235.8 6244.0 6257.3 5621.0 5894.5 5895.0 5896.0 5925.1 5712.3 6017.6 6059.2 6063.9 6150.4 5899.5 5953.0 5964.8 5978.6 5989.2 5926.1 5941.6 5942.7 5952.9 5963.6 5501.9 5616.6 5781.3 5795.7 5858.5 5966.1 5973.6 5992.2 5993.9 6001.6 5504.1 5556.6 5825.7 5833.6 5835.2 09:39:37 INFO - PROCESS | 2329 | 09:39:37 INFO - PROCESS | 2329 | [#1] ext.html Cycles:180 Average:59805.11 Median:38490.42 stddev:114130.87 (296.5%) stddev-sans-first:114364.36 09:39:37 INFO - PROCESS | 2329 | Values: 559.8 598.8 614.2 640.9 646.2 33636.8 44040.3 44513.3 44545.3 44832.8 45414.0 45746.8 45831.3 45944.0 46027.8 8041.8 8697.7 8886.1 8891.2 8903.6 8046.8 10456.9 10694.2 10694.3 11022.8 10758.1 10768.5 10831.7 10832.4 10834.3 10531.6 10550.0 10602.5 10603.5 10609.0 10461.1 10490.2 10555.1 10574.6 10576.8 41639.8 42030.0 42042.4 42055.8 42076.6 3640.1 3769.9 3771.2 3837.1 4043.7 30788.8 33296.5 33316.5 33519.7 33571.8 4375.6 4418.7 4450.9 4478.4 4488.2 359988.0 368923.0 369851.0 370129.0 370471.0 68465.7 69382.7 69424.7 69521.7 69591.0 40173.0 40321.6 40531.0 44535.8 44690.0 17639.8 18015.3 18020.1 18029.7 18029.9 12125.3 12295.4 13555.7 13662.2 13891.9 20517.2 21803.2 21939.5 22145.6 22194.0 7858.3 7900.9 8010.4 8128.0 8231.7 961.9 1054.0 1144.6 1145.3 1176.5 30257.4 32112.8 32344.7 32540.2 32758.7 1098.6 1112.3 1144.4 1165.8 1181.6 15289.9 17456.7 17599.0 18377.2 18571.7 509694.0 509909.0 512295.0 512625.0 513506.0 61739.1 62225.0 62519.0 62534.7 62536.4 1326.0 1360.1 1383.3 1392.8 1465.5 30551.7 34229.0 34416.3 34508.7 35073.1 51137.7 51900.5 51924.5 51950.5 51964.5 402206.0 402639.0 404009.0 404618.0 404726.0 27905.7 28174.6 28408.7 28525.0 31017.4 49861.8 50089.0 50118.0 50604.2 51466.0 40808.0 41429.6 42037.4 42325.4 42749.6 41159.8 41529.6 41758.2 43187.4 43588.6 41716.2 42975.4 43967.0 44007.1 45829.1 35602.8 38262.4 38460.2 38520.6 39262.2 28946.7 28959.3 29027.7 29319.6 29348.6 09:39:37 INFO - PROCESS | 2329 | 09:39:37 INFO - PROCESS | 2329 | [#2] jquery.html Cycles:180 Average:30187.44 Median:23818.14 stddev:83106.79 (348.9%) stddev-sans-first:83309.82 09:39:37 INFO - PROCESS | 2329 | Values: 393.6 436.0 437.4 443.4 446.1 16576.3 19365.1 20192.2 20914.0 21137.6 19332.6 20189.6 20207.5 20343.6 20438.7 5724.6 5965.8 5966.5 5969.4 5977.4 5532.8 5863.8 5888.5 5894.8 5900.8 5658.1 5865.6 5868.9 5870.9 5880.2 5781.3 5809.8 5849.2 5856.2 5857.6 5087.6 5233.7 5234.3 5256.2 5276.8 23561.9 23654.5 23759.3 23877.0 23906.3 2487.0 2524.5 2541.7 2658.6 3040.7 14623.0 15015.3 15127.6 15720.4 15985.5 1905.1 1918.8 1923.3 1970.9 2019.4 87566.6 88268.0 88346.0 89076.0 89224.6 10198.2 11206.4 11385.3 11510.5 11820.3 15049.0 15221.7 15889.3 15904.3 16625.2 8094.7 8131.1 8249.3 8327.2 8392.0 7758.9 7766.0 7862.7 7932.8 7958.0 6946.5 6982.7 7076.7 7103.2 7116.3 1930.0 1977.7 1992.0 2032.9 2060.0 966.7 1013.0 1020.1 1022.2 1125.8 20190.5 20887.2 21030.9 21098.9 21284.6 1019.3 1036.4 1077.9 1095.1 1111.5 10958.3 11837.2 11924.8 11937.5 11998.0 499833.6 501568.0 505389.0 505960.0 508693.0 79620.6 87251.0 87259.6 89343.1 93158.0 1073.7 1077.1 1087.8 1088.3 1122.0 18528.0 18732.2 18870.6 19024.1 19056.9 33956.2 42846.9 43830.6 45854.8 46156.8 63961.4 64588.0 65140.7 66002.3 66723.0 8816.1 9045.3 9311.6 9329.8 9401.2 13298.3 13311.0 13455.9 13642.4 13679.4 11500.5 12172.8 12220.0 12303.6 12387.7 11673.0 12013.4 12145.0 12316.0 12554.7 11780.5 12131.9 12200.5 12207.9 12661.0 8741.5 8840.2 8942.3 9092.3 9300.5 9276.8 11500.1 11982.0 12423.2 12520.7 09:39:37 INFO - PROCESS | 2329 | 09:39:37 INFO - PROCESS | 2329 | [#3] mootools.html Cycles:180 Average:5906.91 Median:2587.46 stddev:3280.49 (126.8%) stddev-sans-first:3272.03 09:39:37 INFO - PROCESS | 2329 | Values: 1377.4 1503.2 1545.4 1677.9 1827.2 7838.6 8409.4 8784.7 8815.6 9039.2 8804.1 8878.6 8883.6 8897.2 8922.7 2493.2 2519.6 2519.7 2520.2 2523.8 2468.8 2525.8 2526.8 2528.6 2530.2 2469.0 2475.1 2489.9 2497.2 2498.2 2407.1 2425.3 2457.0 2464.5 2471.5 2341.0 2365.1 2389.5 2405.3 2422.4 8277.1 8791.4 8813.3 8862.7 8869.0 5530.6 5746.9 5758.9 5766.4 5770.5 3717.2 3741.9 3832.6 3847.5 3885.7 1263.7 1287.1 1303.0 1330.1 1341.6 12219.9 12337.7 12346.8 12358.3 12385.3 4701.2 4754.6 4791.9 4842.7 4851.7 10033.7 10039.3 10041.3 10050.6 10070.0 5475.6 5499.1 5499.7 5499.8 5515.8 4183.2 4438.2 4446.0 4975.0 5090.2 4004.7 4060.6 4121.2 4159.8 4173.3 1255.6 1281.3 1285.9 1286.0 1298.5 824.5 953.3 1009.9 1010.2 1037.4 5836.9 6014.7 6097.6 6170.2 6339.7 473.7 479.5 485.7 490.9 493.5 4258.1 4263.1 4329.6 4339.1 4443.3 10705.9 10949.0 11053.5 11081.6 11126.2 11056.5 11073.5 11073.7 11075.4 11092.2 7983.2 8271.4 8293.9 8429.2 8460.6 6183.9 6322.7 6406.3 6451.4 6486.0 10118.0 10147.0 10216.3 10271.4 10393.4 9792.0 10006.8 10036.4 10439.3 10447.9 7798.1 7953.4 8103.7 8158.6 8227.2 8338.7 8411.4 8416.2 8427.0 8440.7 7381.3 7619.5 7839.0 7868.8 7926.9 7697.4 7810.1 7816.5 7891.5 7974.5 6867.3 7088.8 7232.7 7307.7 7312.0 5290.4 5359.1 5455.9 5470.0 5506.5 5374.7 5434.7 5466.6 5522.4 5608.0 09:39:37 INFO - PROCESS | 2329 | 09:39:37 INFO - PROCESS | 2329 | [#4] prototype.html Cycles:180 Average:4661.27 Median:5328.76 stddev:1353.75 (25.4%) stddev-sans-first:1318.04 09:39:37 INFO - PROCESS | 2329 | Values: 335.9 340.2 349.6 352.9 355.7 5243.0 5296.9 5321.9 5332.0 5339.7 5257.2 5258.8 5269.3 5279.2 5286.3 4022.0 4204.3 4205.0 4219.8 4220.8 4042.2 4111.7 4116.9 4152.0 4166.9 4115.2 4120.8 4127.0 4127.9 4132.0 4010.1 4123.6 4132.5 4140.8 4144.2 3525.5 3624.6 3650.7 3655.3 3680.6 5125.0 5130.2 5136.0 5141.2 5145.7 4597.3 4712.7 4716.8 4739.6 4741.3 5450.5 5498.2 5507.5 5508.6 5521.3 4027.8 4035.2 4039.8 4040.2 4051.8 5521.5 5732.4 5735.9 5753.4 5762.4 4085.4 4097.0 4127.4 4127.8 4133.3 4169.9 4183.4 4191.3 4193.9 4196.9 4729.0 4733.4 4738.0 4739.3 4745.3 5351.2 5440.5 5494.6 5535.2 5537.6 1391.4 1391.9 1391.9 1392.0 1394.2 948.3 989.9 1003.4 1005.2 1006.2 5217.4 5226.6 5241.6 5325.5 5345.1 5601.6 5601.9 5613.8 5629.7 5668.0 5440.3 5452.3 5452.4 5476.9 5478.5 2848.3 2932.0 2952.0 2969.8 2980.9 5756.3 5773.4 5778.2 5791.2 5806.7 5501.4 5603.7 5651.4 5721.3 5728.9 5735.7 5750.0 5750.8 5759.6 5776.9 5518.0 5524.8 5537.4 5558.8 5572.7 5250.2 5434.8 5472.9 5495.8 5548.4 5685.3 5698.9 5714.3 5717.0 5748.0 4094.5 4112.3 4113.2 4126.7 4147.4 5394.3 5690.2 5716.3 5733.6 5734.5 5629.9 5636.7 5665.7 5668.3 5681.3 5527.8 5552.7 5578.8 5643.7 5652.8 5529.9 5539.7 5564.8 5582.7 5595.2 5372.8 5439.9 5551.4 5592.5 5610.5 5462.8 5487.6 5497.6 5526.7 5528.6 09:39:37 INFO - PROCESS | 2329 | 09:39:37 INFO - PROCESS | 2329 | [#5] yui.html Cycles:180 Average:18204.23 Median:51729.11 stddev:24307.69 (47.0%) stddev-sans-first:24340.75 09:39:37 INFO - PROCESS | 2329 | Values: 799.3 816.6 817.1 855.9 898.7 13874.6 16324.9 16465.1 16512.1 16517.0 14506.6 14767.8 14791.6 14884.4 14902.5 721.3 838.5 855.8 857.9 858.1 729.1 832.8 836.7 849.7 854.1 846.2 852.3 854.0 857.5 857.8 742.1 851.6 853.1 853.7 857.4 558.7 637.1 638.8 646.8 647.2 15038.3 15077.2 15090.2 15099.5 15109.7 13559.9 13819.4 13854.2 13875.0 13907.2 12177.5 12351.3 12379.3 12426.1 12524.1 3794.4 4415.5 4424.5 4438.9 4514.4 59880.4 67567.7 67782.7 67839.7 68716.0 11340.5 11377.0 11391.7 11478.3 11577.1 19603.3 19635.0 19838.9 19841.7 19859.2 7998.9 8062.5 8104.4 8187.3 8280.8 4676.0 4699.8 4755.6 4817.5 4924.4 3469.9 3567.0 3575.4 3576.4 3579.7 642.6 656.8 664.6 666.1 666.5 559.8 570.5 664.9 735.5 747.2 9287.3 9593.0 9779.5 9790.8 10020.8 715.2 754.6 763.2 765.4 788.3 4666.8 4676.6 4682.9 4714.1 4737.3 56486.2 75138.0 92207.0 93283.5 95702.0 49986.3 63204.7 85197.1 87459.6 87937.0 71356.0 72037.0 72173.6 73301.3 74868.0 8748.7 8979.1 9009.5 9042.5 9062.9 49024.3 50765.5 51585.5 51872.7 53128.5 65431.3 69885.7 71350.9 71625.3 74908.0 11267.2 11322.9 11644.0 11648.4 11784.0 12913.0 12997.8 13288.7 13396.4 13493.6 12286.8 12516.5 12569.3 12598.3 13016.1 11693.4 12301.8 12468.6 12545.2 12773.2 12537.4 12541.0 12663.6 13006.3 13106.8 9509.0 9510.2 9587.6 9761.7 10281.3 10146.8 10521.4 10818.4 10834.2 10939.5 09:39:37 INFO - PROCESS | 2329 | -------- Summary: end -------- 09:39:37 INFO - PROCESS | 2329 | 09:39:38 INFO - TEST-INFO | 2329: exit 0 09:39:38 INFO - TEST-OK | dromaeo_css | took 1114867ms 09:39:38 INFO - TEST-START | kraken 09:39:38 INFO - operating with platform_type : linux_ 09:39:38 INFO - Initialising browser for kraken test... 09:39:38 INFO - TEST-INFO | started process 2800 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZGOsoa/profile http://localhost:34338/getInfo.html) 09:39:42 INFO - PROCESS | 2800 | __metrics Screen width/height:1600/1200 09:39:42 INFO - PROCESS | 2800 | colorDepth:24 09:39:42 INFO - PROCESS | 2800 | Browser inner width/height: 1024/697 09:39:42 INFO - PROCESS | 2800 | __metrics 09:39:42 INFO - PROCESS | 2800 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:39:42 INFO - PROCESS | 2800 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:39:43 INFO - PROCESS | 2800 | console.error: PushService: 09:39:43 INFO - PROCESS | 2800 | stateChangeProcessEnqueue: Error transitioning state 09:39:43 INFO - PROCESS | 2800 | UnknownError 09:39:43 INFO - TEST-INFO | 2800: exit 0 09:39:43 INFO - Browser initialized. 09:39:43 INFO - Running cycle 1/1 for kraken test... 09:39:43 INFO - TEST-INFO | started process 2932 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZGOsoa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:39:55 INFO - PROCESS | 2932 | RSS: Main: 144343040 09:39:55 INFO - PROCESS | 2932 | 09:39:57 INFO - PROCESS | 2932 | Cycle 1(1): loaded http://localhost:34338/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34338/tests/kraken/driver.html?testName=audio-beat-detection) 09:39:58 INFO - PROCESS | 2932 | RSS: Main: 185479168 09:39:58 INFO - PROCESS | 2932 | 09:40:01 INFO - PROCESS | 2932 | Cycle 1(1): loaded http://localhost:34338/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34338/tests/kraken/driver.html?testName=audio-dft) 09:40:01 INFO - PROCESS | 2932 | RSS: Main: 175083520 09:40:01 INFO - PROCESS | 2932 | 09:40:04 INFO - PROCESS | 2932 | Cycle 1(1): loaded http://localhost:34338/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34338/tests/kraken/driver.html?testName=audio-fft) 09:40:05 INFO - PROCESS | 2932 | RSS: Main: 266502144 09:40:05 INFO - PROCESS | 2932 | 09:40:07 INFO - PROCESS | 2932 | Cycle 1(1): loaded http://localhost:34338/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34338/tests/kraken/driver.html?testName=audio-oscillator) 09:40:07 INFO - PROCESS | 2932 | RSS: Main: 173498368 09:40:07 INFO - PROCESS | 2932 | 09:40:10 INFO - PROCESS | 2932 | Cycle 1(1): loaded http://localhost:34338/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34338/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:40:10 INFO - PROCESS | 2932 | RSS: Main: 175419392 09:40:10 INFO - PROCESS | 2932 | 09:40:13 INFO - PROCESS | 2932 | Cycle 1(1): loaded http://localhost:34338/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34338/tests/kraken/driver.html?testName=imaging-darkroom) 09:40:14 INFO - PROCESS | 2932 | RSS: Main: 214495232 09:40:14 INFO - PROCESS | 2932 | 09:40:17 INFO - PROCESS | 2932 | Cycle 1(1): loaded http://localhost:34338/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34338/tests/kraken/driver.html?testName=imaging-desaturate) 09:40:18 INFO - PROCESS | 2932 | RSS: Main: 212996096 09:40:18 INFO - PROCESS | 2932 | 09:40:20 INFO - PROCESS | 2932 | Cycle 1(1): loaded http://localhost:34338/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34338/tests/kraken/driver.html?testName=json-parse-financial) 09:40:21 INFO - PROCESS | 2932 | RSS: Main: 211468288 09:40:21 INFO - PROCESS | 2932 | 09:40:22 INFO - PROCESS | 2932 | Cycle 1(1): loaded http://localhost:34338/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34338/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:40:23 INFO - PROCESS | 2932 | RSS: Main: 174075904 09:40:23 INFO - PROCESS | 2932 | 09:40:25 INFO - PROCESS | 2932 | Cycle 1(1): loaded http://localhost:34338/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34338/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:40:25 INFO - PROCESS | 2932 | RSS: Main: 180867072 09:40:25 INFO - PROCESS | 2932 | 09:40:27 INFO - PROCESS | 2932 | Cycle 1(1): loaded http://localhost:34338/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34338/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:40:28 INFO - PROCESS | 2932 | RSS: Main: 190672896 09:40:28 INFO - PROCESS | 2932 | 09:40:30 INFO - PROCESS | 2932 | Cycle 1(1): loaded http://localhost:34338/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34338/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:40:31 INFO - PROCESS | 2932 | RSS: Main: 198635520 09:40:31 INFO - PROCESS | 2932 | 09:40:34 INFO - PROCESS | 2932 | Cycle 1(1): loaded http://localhost:34338/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34338/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:40:34 INFO - PROCESS | 2932 | RSS: Main: 195780608 09:40:34 INFO - PROCESS | 2932 | 09:40:36 INFO - PROCESS | 2932 | Cycle 1(1): loaded http://localhost:34338/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34338/tests/kraken/driver.html?testName=ai-astar) 09:40:36 INFO - PROCESS | 2932 | RSS: Main: 247164928 09:40:36 INFO - PROCESS | 2932 | 09:40:36 INFO - PROCESS | 2932 | __start_tp_report 09:40:36 INFO - PROCESS | 2932 | _x_x_mozilla_page_load 09:40:36 INFO - PROCESS | 2932 | _x_x_mozilla_page_load_details 09:40:36 INFO - PROCESS | 2932 | |i|pagename|runs| 09:40:36 INFO - PROCESS | 2932 | |0;ai-astar;103;111;112;114;111;115;111;111;116;111 09:40:36 INFO - PROCESS | 2932 | |1;audio-beat-detection;145;123;129;164;131;130;129;121;150;123 09:40:36 INFO - PROCESS | 2932 | |2;audio-dft;166;161;162;161;161;162;161;163;160;162 09:40:36 INFO - PROCESS | 2932 | |3;audio-fft;75;76;75;120;77;76;77;76;97;75 09:40:36 INFO - PROCESS | 2932 | |4;audio-oscillator;83;82;114;82;82;81;101;81;81;118 09:40:36 INFO - PROCESS | 2932 | |5;imaging-gaussian-blur;117;117;117;117;117;118;117;117;117;117 09:40:36 INFO - PROCESS | 2932 | |6;imaging-darkroom;147;146;147;146;147;147;146;146;147;145 09:40:36 INFO - PROCESS | 2932 | |7;imaging-desaturate;85;85;85;85;85;85;85;85;85;84 09:40:36 INFO - PROCESS | 2932 | |8;json-parse-financial;50;51;51;49;50;51;51;50;51;50 09:40:36 INFO - PROCESS | 2932 | |9;json-stringify-tinderbox;58;58;58;59;60;59;58;60;58;58 09:40:36 INFO - PROCESS | 2932 | |10;stanford-crypto-aes;77;76;77;79;76;76;76;76;76;76 09:40:36 INFO - PROCESS | 2932 | |11;stanford-crypto-ccm;136;127;126;131;144;127;126;129;170;130 09:40:36 INFO - PROCESS | 2932 | |12;stanford-crypto-pbkdf2;158;160;160;158;157;158;156;156;156;159 09:40:36 INFO - PROCESS | 2932 | |13;stanford-crypto-sha256-iterative;63;67;63;64;64;63;63;61;64;63 09:40:36 INFO - PROCESS | 2932 | __end_tp_report 09:40:36 INFO - PROCESS | 2932 | __start_cc_report 09:40:36 INFO - PROCESS | 2932 | _x_x_mozilla_cycle_collect,1639 09:40:36 INFO - PROCESS | 2932 | __end_cc_report 09:40:36 INFO - PROCESS | 2932 | __startTimestamp1480095636886__endTimestamp 09:40:36 INFO - PROCESS | 2932 | 09:40:36 INFO - PROCESS | 2932 | ------- Summary: start ------- 09:40:36 INFO - PROCESS | 2932 | Number of tests: 14 09:40:36 INFO - PROCESS | 2932 | 09:40:36 INFO - PROCESS | 2932 | [#0] ai-astar Cycles:10 Average:111.50 Median:111.50 stddev:3.54 (3.2%) stddev-sans-first:2.01 09:40:36 INFO - PROCESS | 2932 | Values: 103.0 111.0 112.0 114.0 111.0 115.0 111.0 111.0 116.0 111.0 09:40:36 INFO - PROCESS | 2932 | 09:40:36 INFO - PROCESS | 2932 | [#1] audio-beat-detection Cycles:10 Average:134.50 Median:130.50 stddev:13.99 (10.7%) stddev-sans-first:14.31 09:40:36 INFO - PROCESS | 2932 | Values: 145.0 123.0 129.0 164.0 131.0 130.0 129.0 121.0 150.0 123.0 09:40:36 INFO - PROCESS | 2932 | 09:40:36 INFO - PROCESS | 2932 | [#2] audio-dft Cycles:10 Average:161.90 Median:162.00 stddev:1.66 (1.0%) stddev-sans-first:0.88 09:40:36 INFO - PROCESS | 2932 | Values: 166.0 161.0 162.0 161.0 161.0 162.0 161.0 163.0 160.0 162.0 09:40:36 INFO - PROCESS | 2932 | 09:40:36 INFO - PROCESS | 2932 | [#3] audio-fft Cycles:10 Average:82.40 Median:76.00 stddev:14.80 (19.5%) stddev-sans-first:15.46 09:40:36 INFO - PROCESS | 2932 | Values: 75.0 76.0 75.0 120.0 77.0 76.0 77.0 76.0 97.0 75.0 09:40:36 INFO - PROCESS | 2932 | 09:40:36 INFO - PROCESS | 2932 | [#4] audio-oscillator Cycles:10 Average:90.50 Median:81.50 stddev:14.77 (18.1%) stddev-sans-first:15.41 09:40:36 INFO - PROCESS | 2932 | Values: 83.0 82.0 114.0 82.0 82.0 81.0 101.0 81.0 81.0 118.0 09:40:36 INFO - PROCESS | 2932 | 09:40:36 INFO - PROCESS | 2932 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 09:40:36 INFO - PROCESS | 2932 | Values: 117.0 117.0 117.0 117.0 117.0 118.0 117.0 117.0 117.0 117.0 09:40:36 INFO - PROCESS | 2932 | 09:40:36 INFO - PROCESS | 2932 | [#6] imaging-darkroom Cycles:10 Average:146.40 Median:147.00 stddev:0.70 (0.5%) stddev-sans-first:0.71 09:40:36 INFO - PROCESS | 2932 | Values: 147.0 146.0 147.0 146.0 147.0 147.0 146.0 146.0 147.0 145.0 09:40:36 INFO - PROCESS | 2932 | 09:40:36 INFO - PROCESS | 2932 | [#7] imaging-desaturate Cycles:10 Average:84.90 Median:85.00 stddev:0.32 (0.4%) stddev-sans-first:0.33 09:40:36 INFO - PROCESS | 2932 | Values: 85.0 85.0 85.0 85.0 85.0 85.0 85.0 85.0 85.0 84.0 09:40:36 INFO - PROCESS | 2932 | 09:40:36 INFO - PROCESS | 2932 | [#8] json-parse-financial Cycles:10 Average:50.40 Median:51.00 stddev:0.70 (1.4%) stddev-sans-first:0.73 09:40:36 INFO - PROCESS | 2932 | Values: 50.0 51.0 51.0 49.0 50.0 51.0 51.0 50.0 51.0 50.0 09:40:36 INFO - PROCESS | 2932 | 09:40:36 INFO - PROCESS | 2932 | [#9] json-stringify-tinderbox Cycles:10 Average:58.60 Median:58.50 stddev:0.84 (1.4%) stddev-sans-first:0.87 09:40:36 INFO - PROCESS | 2932 | Values: 58.0 58.0 58.0 59.0 60.0 59.0 58.0 60.0 58.0 58.0 09:40:36 INFO - PROCESS | 2932 | 09:40:36 INFO - PROCESS | 2932 | [#10] stanford-crypto-aes Cycles:10 Average:76.50 Median:76.00 stddev:0.97 (1.3%) stddev-sans-first:1.01 09:40:36 INFO - PROCESS | 2932 | Values: 77.0 76.0 77.0 79.0 76.0 76.0 76.0 76.0 76.0 76.0 09:40:36 INFO - PROCESS | 2932 | 09:40:36 INFO - PROCESS | 2932 | [#11] stanford-crypto-ccm Cycles:10 Average:134.60 Median:130.50 stddev:13.63 (10.4%) stddev-sans-first:14.45 09:40:36 INFO - PROCESS | 2932 | Values: 136.0 127.0 126.0 131.0 144.0 127.0 126.0 129.0 170.0 130.0 09:40:36 INFO - PROCESS | 2932 | 09:40:36 INFO - PROCESS | 2932 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:157.80 Median:158.00 stddev:1.55 (1.0%) stddev-sans-first:1.64 09:40:36 INFO - PROCESS | 2932 | Values: 158.0 160.0 160.0 158.0 157.0 158.0 156.0 156.0 156.0 159.0 09:40:36 INFO - PROCESS | 2932 | 09:40:36 INFO - PROCESS | 2932 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.50 Median:63.50 stddev:1.51 (2.4%) stddev-sans-first:1.59 09:40:36 INFO - PROCESS | 2932 | Values: 63.0 67.0 63.0 64.0 64.0 63.0 63.0 61.0 64.0 63.0 09:40:36 INFO - PROCESS | 2932 | -------- Summary: end -------- 09:40:36 INFO - PROCESS | 2932 | 09:40:37 INFO - TEST-INFO | 2932: exit 0 09:40:37 INFO - TEST-OK | kraken | took 59160ms 09:40:37 INFO - SUITE-END | took 1174s 09:40:38 INFO - Completed test suite (00:19:34) 09:40:38 INFO - javascript benchmark 09:40:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3245.9837700811495, 3278.7704860659755, 3287.5068739689045, 3315.9005229843115, 3329.9011326262334, 5480.26016487774, 5561.4716601922855, 5567.3040869891265, 5569.331680198372, 5590.4409559044125, 5607.663540187592, 5669.914951275724, 5693.857653558653, 5696.544276457884, 5710.657360558359, 5095.210242412422, 5099.71951542665, 5128.794848206067, 5129.9230511542355, 5187.740612969355, 4934.111859865223, 5200.297959775438, 5201.55786758126, 5206.140986737184, 5207.036698210824, 4933.827316043939, 5007.0486607544635, 5046.4448910619985, 5059.367579052619, 5085.771140298695, 5116.718580478072, 5147.433782283946, 5149.433562308143, 5209.348831396075, 5217.582593392557, 5038.39539255288, 5107.901801112778, 5180.5337519623035, 5191.714455704935, 5224.712640804754, 5288.735563221823, 5319.20211968204, 5482.451754824526, 5487.478689524489, 5516.531094856942, 5969.492593129589, 5984.790532331391, 6042.969785151046, 6072.939270607281, 6084.726187321537, 6118.112873633343, 6128.479079278267, 6136.723847426876, 6143.078538219303, 6146.323904370515, 5427.402985671612, 5467.699276539828, 5474.562035037267, 5517.862053448696, 5520.972395137998, 6060.2727672678675, 6157.507399408126, 6176.135341052256, 6181.81454556364, 6184.041473571604, 5892.941070589325, 5896.64620122794, 5903.498202652822, 5911.142884281841, 5913.113033044991, 5723.141528770718, 5767.625104368202, 5992.250968628921, 6031.788887388921, 6035.1249068885645, 6037.63774173551, 6048.637081775108, 6055.394460553997, 6066.029435290332, 6090.512758979271, 5991.0, 6039.607425517327, 6054.122152287893, 6056.515478761688, 6066.939330606726, 4451.354553589711, 4465.732056076646, 4607.193741095295, 4637.814487420533, 4679.391679081766, 4148.937765933513, 4174.812133454002, 4194.433751443577, 4195.538490766014, 4218.135282267128, 5790.565707571865, 5806.709664516758, 5809.390014048548, 5810.6513609183585, 5810.854728631818, 5696.287964004499, 5755.280589926259, 5972.641641501524, 6097.634141951408, 6115.1744514490865, 5697.886042279131, 5777.884442311214, 5783.971080144573, 5790.10253410722, 5810.970945145247, 5244.134717771602, 5261.105612045962, 5265.815696450606, 5275.155975044051, 5282.683039017671, 5951.821445356646, 6006.399360063959, 6261.530385221035, 6311.621302721759, 6328.208973878265, 5893.5874488785375, 5907.4683278505145, 5933.258342707161, 5997.400259973968, 6037.969810150921, 6224.035274532454, 6231.75072997075, 6235.781747638811, 6244.0, 6257.311695713559, 5620.971895140498, 5894.528437725057, 5894.970525147347, 5896.027155519291, 5925.081612350093, 5712.285964254468, 6017.638941663514, 6059.151718759289, 6063.878722425615, 6150.415710507495, 5899.528037757055, 5953.047512397996, 5964.821055368346, 5978.551608629457, 5989.1914591529585, 5926.140709596998, 5941.584089113893, 5942.732577033958, 5952.8809423812145, 5963.582549221513, 5501.889962200654, 5616.606837521498, 5781.335146458043, 5795.710214489343, 5858.502027327725, 5966.075258334965, 5973.641581505123, 5992.161097446448, 5993.850153746192, 6001.609895356963, 5504.064309067388, 5556.611037227355, 5825.70871456434, 5833.620814647034, 5835.183074369676], "value": 5544.174955855893, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [559.8264537993223, 598.7604958016793, 614.2138063279003, 640.9487241020719, 646.2051676437984, 33636.83181584092, 44040.33939490906, 44513.332300015485, 44545.33182002272, 44832.775836120854, 45414.0, 45746.7712661437, 45831.31253031199, 45944.0, 46027.76986115073, 8041.839163216732, 8697.695580654678, 8886.066962968893, 8891.155340242667, 8903.554822258893, 8046.839063218732, 10456.895431045668, 10694.197935154894, 10694.304870183452, 11022.834657480144, 10758.139348852073, 10768.461576921161, 10831.67504974852, 10832.350058996446, 10834.349939003645, 10531.578736850555, 10550.0, 10602.522886470128, 10603.469826508644, 10609.045185933302, 10461.110805581535, 10490.21323400748, 10555.102816260627, 10574.577016919315, 10576.788464230673, 41639.7918010411, 42030.0, 42042.369364459555, 42055.78972105151, 42076.57923420788, 3640.12636967126, 3769.924601507982, 3771.17034252464, 3837.1174629835014, 4043.676505879552, 30788.846055769576, 33296.50055249173, 33316.50025249623, 33519.6648033519, 33571.83214083938, 4375.649948004184, 4418.656549877266, 4450.910981780378, 4478.4178056852725, 4488.236999710057, 359988.0, 368922.9999999973, 369851.0, 370129.0, 370471.0, 68465.65767171132, 69382.65308673526, 69424.6528767363, 69521.65239173772, 69591.0, 40173.0, 40321.59678403178, 40531.0, 44535.777321113834, 44690.0, 17639.823601764074, 18015.2793888243, 18020.098995050193, 18029.7295540567, 18029.909850450927, 12125.333106679042, 12295.385230738428, 13555.661108472366, 13662.248576328397, 13891.930540347234, 20517.17931282732, 21803.236886709205, 21939.451513712283, 22145.55708885813, 22194.0, 7858.253465920729, 7900.920990790133, 8010.394023436547, 8127.959360203279, 8231.670733170726, 961.8605302231277, 1053.963111291101, 1144.553624086607, 1145.2899202494507, 1176.505867535637, 30257.394852102836, 32112.83943580267, 32344.676553234167, 32540.186495337333, 32758.67241327604, 1098.6209757633603, 1112.2825777373548, 1144.3534403062242, 1165.830954511591, 1181.6100686773343, 15289.9235503824, 17456.738148927776, 17599.0, 18377.17302721354, 18571.72142417865, 509694.0, 509909.0, 512295.0, 512625.0, 513506.0, 61739.07391389133, 62225.0, 62519.0, 62534.68732656308, 62536.37463625397, 1325.9535916242885, 1360.1023324605712, 1383.3359987205997, 1392.7771556551106, 1465.501729412005, 30551.694483054885, 34229.0, 34416.31167376688, 34508.65491345155, 35073.1231719209, 51137.744311278206, 51900.48099519107, 51924.480755191966, 51950.480495196076, 51964.48035519596, 402206.0, 402639.0, 404009.0, 404618.0, 404726.0, 27905.72094279031, 28174.577381338888, 28408.715912840606, 28525.0, 31017.379652406373, 49861.750691246314, 50089.0, 50117.997640047724, 50604.24093638525, 51466.0, 40808.0, 41429.58570414257, 42037.36943945782, 42325.36511952385, 42749.572504275806, 41159.7942010288, 41529.58470415257, 41758.16483670249, 43187.35218971781, 43588.56411435845, 41716.165676686905, 42975.355369668854, 43967.0, 44007.119857603306, 45829.08341833211, 35602.8219858899, 38262.42606360962, 38460.230795384494, 38520.61479385282, 39262.214755705296, 28946.710532895246, 28959.276018099717, 29027.709722902502, 29319.560206596492, 29348.55977160387], "value": 20929.364743138238, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [393.5965635223897, 435.9520452750197, 437.42041794622116, 443.3814828314501, 446.1040577545434, 16576.33694652214, 19365.128569214383, 20192.192312307507, 20914.0, 21137.57724845502, 19332.613347733037, 20189.59620807583, 20207.49481262969, 20343.59312813743, 20438.69341959868, 5724.62789918656, 5965.791197308095, 5966.522678185735, 5969.373215812342, 5977.402259774031, 5532.834014979557, 5863.76544938202, 5888.470037696607, 5894.823155305347, 5900.822975310747, 5658.066419040853, 5865.560082993771, 5868.882622347551, 5870.911936320958, 5880.2355693759755, 5781.306243250795, 5809.7676092956235, 5849.181114643953, 5856.180134781134, 5857.619254748428, 5087.643864929457, 5233.659812112239, 5234.26720259164, 5256.185291279858, 5276.762545685453, 23561.88219058911, 23654.526909461885, 23759.287221383387, 23877.0, 23906.28281151568, 2486.987565062163, 2524.5329614021466, 2541.707703614071, 2658.5746280595204, 3040.726334629894, 14623.0, 15015.324310406058, 15127.621809454742, 15720.449784257497, 15985.520434386986, 1905.1141219333076, 1918.7985261547615, 1923.2595450751421, 1970.9408717738347, 2019.3941817454852, 87566.56216718939, 88268.0, 88346.0, 89076.0, 89224.55387723085, 10198.18414526836, 11206.439678016066, 11385.259958102697, 11510.48202830858, 11820.349880756641, 15049.021813582081, 15221.695566088616, 15889.28498217583, 15904.284307206204, 16625.168741562873, 8094.716684915999, 8131.14622964592, 8249.257566819015, 8327.167283271745, 8392.0, 7758.883616745753, 7765.961170194226, 7862.724804631925, 7932.801679957932, 7958.0, 6946.548474349152, 6982.685779139951, 7076.716931322792, 7103.183133939559, 7116.323949224816, 1929.9614007720047, 1977.703344498308, 1992.0377981487645, 2032.9365693820785, 2059.969100463494, 966.7244835221928, 1013.0426746724274, 1020.0564477857952, 1022.2128960700219, 1125.7579620381657, 20190.495237619176, 20887.164513419597, 21030.894845525676, 21098.894505527373, 21284.574308513744, 1019.2712210769336, 1036.3678156324636, 1077.8652668416448, 1095.0691911874842, 1111.5053801058614, 10958.28771129874, 11837.230580012272, 11924.821127683092, 11937.522499100112, 11998.040156787436, 499833.5858523165, 501568.0, 505389.0, 505960.0, 508693.0, 79620.60189699015, 87250.99999999873, 87259.56370218108, 89343.10656893348, 93158.0, 1073.7047311989265, 1077.1006209814736, 1087.7933192693363, 1088.2980477592066, 1121.9943900280446, 18528.0, 18732.157052932922, 18870.622587547896, 19024.1439135242, 19056.90471547689, 33956.15109622279, 42846.92882677958, 43830.561694382646, 45854.77072614749, 46156.76921615505, 63961.3603863974, 64588.0, 65140.67429662821, 66002.33997659961, 66723.0, 8816.118388161132, 9045.276377889884, 9311.627534898527, 9329.766755831159, 9401.200897923753, 13298.335083245605, 13311.0, 13455.86544134546, 13642.386092625657, 13679.38442770058, 11500.539978401104, 12172.817407738714, 12220.0, 12303.630891073282, 12387.69030774238, 11673.0, 12013.399330033639, 12145.0, 12316.0, 12554.686132846751, 11780.52877884875, 12131.939340303243, 12200.51197952072, 12207.877921220674, 12661.0, 8741.475511469333, 8840.248578870867, 8942.284617230736, 9092.318076144447, 9300.488473134043, 9276.814463710823, 11500.137489688139, 11982.0, 12423.192492487959, 12520.749585008067], "value": 9771.369630210425, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1377.3870627570734, 1503.1507198432887, 1545.3818472610953, 1677.8657707383402, 1827.2325623238246, 7838.608069596518, 8409.369297302705, 8784.692535761249, 8815.647374105029, 9039.231665308456, 8804.075572064921, 8878.556072196398, 8883.644654213824, 8897.199252067312, 8922.687705930293, 2493.164789795421, 2519.571672815626, 2519.659845920805, 2520.231329444519, 2523.823332366726, 2468.8395254308502, 2525.785308248792, 2526.7978561715113, 2528.645989561463, 2530.177692250017, 2469.0, 2475.1460746042676, 2489.9293303879326, 2497.213377786001, 2498.225550079472, 2407.121400688745, 2425.260295609838, 2457.041753716052, 2464.5194187133516, 2471.5180539794746, 2340.9531809363716, 2365.101261520611, 2389.462370966536, 2405.2663937499055, 2422.406510404962, 8277.130901255341, 8791.384603077788, 8813.250873675745, 8862.734117976406, 8868.955655221682, 5530.640508366943, 5746.856328591777, 5758.8770189813, 5766.365699773021, 5770.480656740871, 3717.212090931801, 3741.906452338686, 3832.5784163741964, 3847.499825022729, 3885.6891448684037, 1263.6777621706406, 1287.0990306785288, 1302.986970130296, 1330.05566048105, 1341.557286095586, 12219.938900305531, 12337.69155771095, 12346.75306493874, 12358.258504489759, 12385.318807465586, 4701.224297990793, 4754.643401744883, 4791.880202994953, 4842.709437433765, 4851.684640498356, 10033.749156271006, 10039.297249192632, 10041.297109202433, 10050.59797608102, 10070.043345882275, 5475.589330800206, 5499.065158922993, 5499.670019798745, 5499.780008799603, 5515.834524964257, 4183.163367326486, 4438.223310920576, 4445.977770111129, 4975.029869175476, 5090.211017292326, 4004.6596039336982, 4060.5939406059156, 4121.196366708523, 4159.812808423568, 4173.269677806372, 1255.6044845873703, 1281.333706472629, 1285.8714128587067, 1285.984072582668, 1298.461138627478, 824.5258976088772, 953.3279038277998, 1009.8586197932295, 1010.2069875147973, 1037.3931250218707, 5836.8540786479825, 6014.729337179838, 6097.634141951497, 6170.197874276405, 6339.714712837934, 473.68973322473835, 479.4534230976689, 485.66489122506067, 490.8821882748186, 493.50156342094743, 4258.148370325947, 4263.147370525907, 4329.631981281564, 4339.088791353851, 4443.311286750559, 10705.892941070646, 10949.0, 11053.502592383362, 11081.612143574936, 11126.165537584713, 11056.502457389437, 11073.50169242386, 11073.667789966314, 11075.446227688582, 11092.168087393316, 7983.2415920487465, 8271.421000530025, 8293.875591866126, 8429.157084291523, 8460.619272132646, 6183.876322473615, 6322.672238829898, 6406.261684572513, 6451.41937225652, 6486.027095935458, 10118.0, 10147.0, 10216.335938163995, 10271.383716977005, 10393.428361440194, 9792.0, 10006.799864002534, 10036.397816131055, 10439.269251152346, 10447.947760261453, 7798.1032181297605, 7953.363730901629, 8103.675852966051, 8158.551279679437, 8227.177282271725, 8338.66645334204, 8411.411201215857, 8416.158384161536, 8427.0, 8440.704575339834, 7381.298776616213, 7619.542827430372, 7838.960805196166, 7868.84262314762, 7926.920730792849, 7697.422693297913, 7810.140884502586, 7816.53100813953, 7891.487053341514, 7974.481658692166, 6867.3476019778045, 7088.787336379917, 7232.674529646073, 7307.671154798153, 7311.963440182765, 5290.391604965478, 5359.088954877602, 5455.890882182413, 5470.0, 5506.476884695948, 5374.677519348852, 5434.728263586726, 5466.562674986071, 5522.447755224445, 5608.046632072476], "value": 4699.787612262242, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [335.88076232937306, 340.2412619857717, 349.5962163700926, 352.91882866940585, 355.73675480144703, 5242.973785131074, 5296.894062118756, 5321.92017119743, 5332.0, 5339.652922560031, 5257.158854583267, 5258.842234732964, 5269.2611438225385, 5279.234510995903, 5286.286351342567, 4021.9689057026308, 4204.306289462235, 4205.0, 4219.767912764797, 4220.810063547147, 4042.151148258869, 4111.671066314688, 4116.876493705194, 4152.007036622423, 4166.916661666764, 4115.156392939441, 4120.835166593332, 4126.958730412687, 4127.896802579945, 4131.987663022549, 4010.1177740896956, 4123.649489793341, 4132.462779838616, 4140.834366625331, 4144.191882582898, 3525.506429099928, 3624.6012938577, 3650.689691376236, 3655.250673611903, 3680.595134535199, 5125.0, 5130.1535246684625, 5135.974320128413, 5141.203113517411, 5145.716985565792, 4597.310403439477, 4712.670113092085, 4716.787744551469, 4739.601817463857, 4741.31250968611, 5450.5367043801325, 5498.230247765276, 5507.4767897050115, 5508.55931525477, 5521.254630624854, 4027.7715296834745, 4035.1727895781587, 4039.8284497495692, 4040.1717647882115, 4051.8379264829373, 5521.5306698930635, 5732.369439361667, 5735.856603584902, 5753.424657534255, 5762.386531771111, 4085.4076158956786, 4096.959030409717, 4127.422160897417, 4127.823570282455, 4133.338665813455, 4169.8957526062095, 4183.3515805050265, 4191.287481128215, 4193.853215137456, 4196.853110141131, 4729.037449458437, 4733.36099626546, 4737.976310118427, 4739.312799644033, 4745.311929770164, 5351.224072509464, 5440.538758218302, 5494.615376923657, 5535.169724541351, 5537.612367134342, 1391.3947432866735, 1391.8538553451945, 1391.8886489080849, 1391.979120313196, 1394.1913690059944, 948.2887834124407, 989.9010098990187, 1003.4230317567428, 1005.1958433253368, 1006.1799633298838, 5217.426083130853, 5226.5818734501145, 5241.580673546107, 5325.520703136659, 5345.075772721817, 5601.579881508822, 5601.859953501195, 5613.821097569475, 5629.746661400165, 5668.0404645934805, 5440.3471583409455, 5452.26394436746, 5452.391544494384, 5476.945230547643, 5478.45215478457, 2848.2879280179955, 2931.9853400733286, 2951.9852400738287, 2969.777266705006, 2980.940381192364, 5756.294541565458, 5773.393793651606, 5778.191053252548, 5791.189233507313, 5806.65160090396, 5501.394846566875, 5603.732491458017, 5651.4348565143155, 5721.256236689203, 5728.942710572841, 5735.713214339266, 5750.0, 5750.84983003401, 5759.625624334489, 5776.855578610568, 5518.0, 5524.751386187711, 5537.418571050143, 5558.833235002957, 5572.665640061609, 5250.212468129811, 5434.826069572203, 5472.863178420412, 5495.807646732345, 5548.4174161712435, 5685.289338832646, 5698.857528561653, 5714.285714285715, 5716.971415142898, 5748.022836117786, 4094.529129150186, 4112.280350938634, 4113.177364527166, 4126.731762435432, 4147.3986271991, 5394.298741163623, 5690.236026831695, 5716.256886604715, 5733.569982251264, 5734.541236701138, 5629.943700562942, 5636.661800291831, 5665.688387138747, 5668.319801623831, 5681.318241811009, 5527.806526771705, 5552.7238929030445, 5578.832635020955, 5643.661380317194, 5652.812909288931, 5529.889402212014, 5539.7230138492105, 5564.805231816867, 5582.688068304026, 5595.216669666169, 5372.811951581676, 5439.864003399788, 5551.444855514416, 5592.524635406036, 5610.523105535912, 5462.808801691922, 5487.64330318528, 5497.587680923867, 5526.723663816873, 5528.640638358494], "value": 4235.234820083858, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [799.2966189753016, 816.5590581086213, 817.1420008990555, 855.9400841941065, 898.6630013744846, 13874.583762487116, 16324.918375408137, 16465.094419806905, 16512.091834949104, 16517.0, 14506.564803055899, 14767.778483322758, 14791.630209244748, 14884.404623815062, 14902.478413255538, 721.26070777453, 838.5094719589048, 855.7732200966718, 857.8841856349406, 858.1332853817627, 729.1323325242944, 832.8167803083312, 836.69042454292, 849.7195925344621, 854.0690647194557, 846.2468403121196, 852.3479538153317, 853.9957300213521, 857.4555157475017, 857.7641148684099, 742.0724094881398, 851.642310229705, 853.0658928473312, 853.7310747114639, 857.37840065952, 558.7290164270325, 637.08260105448, 638.7796210307437, 646.8059582125344, 647.1683886206249, 15038.32327545263, 15077.170755608437, 15090.24548772557, 15099.471518496799, 15109.697806043818, 13559.932200338935, 13819.378127984164, 13854.238016909067, 13875.0, 13907.235102069382, 12177.452014659362, 12351.258924464471, 12379.319137447437, 12426.068044896578, 12524.123311368208, 3794.354959656865, 4415.536368681274, 4424.535423780521, 4438.889027774331, 4514.435695538058, 59880.40119598792, 67567.66216168937, 67782.66108669474, 67839.66080169567, 68715.9999999995, 11340.546378144782, 11377.01643954042, 11391.715207119723, 11478.253913495597, 11577.073834093253, 19603.31388401399, 19634.993076765335, 19838.90080549588, 19841.702374464396, 19859.205631774857, 7998.920010799934, 8062.475939063942, 8104.432689711782, 8187.304079153219, 8280.8343833123, 4676.041411510632, 4699.835505757282, 4755.595774359149, 4817.542333478314, 4924.4090709115135, 3469.8959031229106, 3567.0, 3575.4100573405617, 3576.3741345264475, 3579.749417540799, 642.6336987916842, 656.8226578823691, 664.6012392564519, 666.0774826864828, 666.4734859460972, 559.7621011070361, 570.5179123640518, 664.9368310010541, 735.4741359927677, 747.1557140431278, 9287.303452241109, 9593.0, 9779.51102444875, 9790.804183916282, 10020.799584008279, 715.2061212054615, 754.5585832288173, 763.2329508843577, 765.3800421658475, 788.3496115704567, 4666.772421724754, 4676.55572720591, 4682.882927926829, 4714.104320179155, 4737.336772851803, 56486.152707709414, 75138.0, 92207.0, 93283.53358233166, 95702.0, 49986.250206246936, 63204.68397657982, 85197.14802852015, 87459.56270218735, 87937.0, 71356.0, 72037.0, 72173.63913180401, 73301.26698732945, 74868.0, 8748.69379571712, 8979.10208979097, 9009.54952252398, 9042.457452552604, 9062.864057039282, 49024.26463602977, 50765.49234507755, 51585.484145158065, 51872.74063629658, 53128.468715313895, 65431.34568654443, 69885.65057174681, 71350.92973605504, 71625.28374716185, 74908.0, 11267.154963377943, 11322.886771132184, 11644.0, 11648.41757912118, 11783.998360139141, 12913.0, 12997.805032924325, 13288.734225315633, 13396.397162127923, 13493.595192144252, 12286.75426491483, 12516.506460566145, 12569.308688022247, 12598.307093109966, 13016.088873778745, 11693.356865372487, 12301.815472767737, 12468.625941221779, 12545.247285162919, 12773.169743966613, 12537.373131343214, 12541.0, 12663.556775512812, 13006.349682516025, 13106.771848785538, 9508.952455237957, 9510.239180865652, 9587.616495340108, 9761.69996967173, 10281.2803103785, 10146.847797282899, 10521.368717876952, 10818.404987725437, 10834.187435942493, 10939.50772215268], "value": 7250.519271887326, "unit": "score"}], "name": "dromaeo_css", "value": 7395.823672310763, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 111.0, 112.0, 114.0, 111.0, 115.0, 111.0, 111.0, 116.0, 111.0], "value": 111.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 123.0, 129.0, 164.0, 131.0, 130.0, 129.0, 121.0, 150.0, 123.0], "value": 134.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 161.0, 162.0, 161.0, 161.0, 162.0, 161.0, 163.0, 160.0, 162.0], "value": 161.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 120.0, 77.0, 76.0, 77.0, 76.0, 97.0, 75.0], "value": 82.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 114.0, 82.0, 82.0, 81.0, 101.0, 81.0, 81.0, 118.0], "value": 90.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 118.0, 117.0, 117.0, 117.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 147.0, 146.0, 147.0, 147.0, 146.0, 146.0, 147.0, 145.0], "value": 146.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 85.0, 85.0, 85.0, 85.0, 85.0, 85.0, 85.0, 84.0], "value": 84.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 51.0, 51.0, 49.0, 50.0, 51.0, 51.0, 50.0, 51.0, 50.0], "value": 50.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 58.0, 59.0, 60.0, 59.0, 58.0, 60.0, 58.0, 58.0], "value": 58.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 77.0, 79.0, 76.0, 76.0, 76.0, 76.0, 76.0, 76.0], "value": 76.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 127.0, 126.0, 131.0, 144.0, 127.0, 126.0, 129.0, 170.0, 130.0], "value": 134.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 160.0, 160.0, 158.0, 157.0, 158.0, 156.0, 156.0, 156.0, 159.0], "value": 157.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 67.0, 63.0, 64.0, 64.0, 63.0, 63.0, 61.0, 64.0, 63.0], "value": 63.5, "unit": "score"}], "name": "kraken", "value": 1470.5999999999997, "alertThreshold": 2.0}]} 09:40:38 INFO - Return code: 0 09:40:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:40:38 INFO - # TBPL SUCCESS # 09:40:38 INFO - Running post-action listener: _resource_record_post_action 09:40:38 INFO - [mozharness: 2016-11-25 17:40:38.199916Z] Finished run-tests step (success) 09:40:38 INFO - Running post-run listener: _resource_record_post_run 09:40:38 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 12288; Write bytes: 345022464; Read time: 200; Write time: 231992 09:40:38 INFO - TinderboxPrint: CPU usage
13.0% 09:40:38 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 200 09:40:38 INFO - TinderboxPrint: I/O write bytes / time
345,022,464 / 231,992 09:40:38 INFO - TinderboxPrint: CPU idle
8,259.9 (87.0%) 09:40:38 INFO - TinderboxPrint: CPU user
1,191.1 (12.5%) 09:40:38 INFO - TinderboxPrint: Swap in / out
0 / 0 09:40:38 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 108068864; Read time: 0; Write time: 107024 09:40:38 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 8192; Write bytes: 236953600; Read time: 192; Write time: 124968 09:40:38 INFO - Running post-run listener: _upload_blobber_files 09:40:38 INFO - Blob upload gear active. 09:40:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:40:38 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 09:40:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:40:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:40:39 INFO - (blobuploader) - INFO - Open directory for files ... 09:40:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:40:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:40:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:40:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:40:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:40:42 INFO - (blobuploader) - INFO - Done attempting. 09:40:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:40:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:40:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:40:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:40:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:40:43 INFO - (blobuploader) - INFO - Done attempting. 09:40:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:40:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:40:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:40:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:40:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:40:45 INFO - (blobuploader) - INFO - Done attempting. 09:40:45 INFO - (blobuploader) - INFO - Iteration through files over. 09:40:45 INFO - Return code: 0 09:40:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:40:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:40:45 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/848ca3cdfbc1462efde16e7b1068de6e37fab0f1109f704687f14f9503fc357652f16520f593aa1ec80e9461cb7c33d82aae198ab9d8616c47a80bcafe5416a8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4d10ae280b54b645964239b92a7b26b9f0ec3efa6ea30feaff85358053f2d0b70cd40056551ba9ea6e538ba75a89c7a34879407612a3a0b389691813b9e5c3d4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c0c0bfc7013224957c89a08dcbc243b2a0aec51763d814859968944a81d45533d16c985e4323b1efddff90ed7849debebc509c4265ba39f08749091376cca945"} 09:40:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:40:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:40:45 INFO - Contents: 09:40:45 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/848ca3cdfbc1462efde16e7b1068de6e37fab0f1109f704687f14f9503fc357652f16520f593aa1ec80e9461cb7c33d82aae198ab9d8616c47a80bcafe5416a8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4d10ae280b54b645964239b92a7b26b9f0ec3efa6ea30feaff85358053f2d0b70cd40056551ba9ea6e538ba75a89c7a34879407612a3a0b389691813b9e5c3d4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c0c0bfc7013224957c89a08dcbc243b2a0aec51763d814859968944a81d45533d16c985e4323b1efddff90ed7849debebc509c4265ba39f08749091376cca945"} 09:40:45 INFO - Running post-run listener: copy_logs_to_upload_dir 09:40:45 INFO - Copying logs to upload dir... 09:40:45 INFO - mkdir: /builds/slave/test/build/upload/logs 09:40:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1236.717531 ========= master_lag: 0.34 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-11-25 09:40:44.279774) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-25 09:40:44.288778) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1480092200.869698-777599883 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/848ca3cdfbc1462efde16e7b1068de6e37fab0f1109f704687f14f9503fc357652f16520f593aa1ec80e9461cb7c33d82aae198ab9d8616c47a80bcafe5416a8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4d10ae280b54b645964239b92a7b26b9f0ec3efa6ea30feaff85358053f2d0b70cd40056551ba9ea6e538ba75a89c7a34879407612a3a0b389691813b9e5c3d4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c0c0bfc7013224957c89a08dcbc243b2a0aec51763d814859968944a81d45533d16c985e4323b1efddff90ed7849debebc509c4265ba39f08749091376cca945"} build_url:https://queue.taskcluster.net/v1/task/QEbwtMvvQWKP5smYDPfgeQ/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011275 build_url: 'https://queue.taskcluster.net/v1/task/QEbwtMvvQWKP5smYDPfgeQ/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/848ca3cdfbc1462efde16e7b1068de6e37fab0f1109f704687f14f9503fc357652f16520f593aa1ec80e9461cb7c33d82aae198ab9d8616c47a80bcafe5416a8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4d10ae280b54b645964239b92a7b26b9f0ec3efa6ea30feaff85358053f2d0b70cd40056551ba9ea6e538ba75a89c7a34879407612a3a0b389691813b9e5c3d4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c0c0bfc7013224957c89a08dcbc243b2a0aec51763d814859968944a81d45533d16c985e4323b1efddff90ed7849debebc509c4265ba39f08749091376cca945"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-25 09:40:44.316397) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 09:40:44.316765) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1480092200.869698-777599883 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004464 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 09:40:44.368426) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-25 09:40:44.368813) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-25 09:40:44.369141) ========= ========= Total master_lag: 0.46 =========