builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-002 starttime: 1483551290.43 results: success (0) buildid: 20170104075439 builduid: bf620d76b39049efa36d1077e6da3bb1 revision: 05a3443d658d29e77cae1da1915ca51814534d73 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-04 09:34:50.430730) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-04 09:34:50.431236) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-04 09:34:50.431544) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-04 09:34:50.446621) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 09:34:50.446977) ========= 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=a0d2d2de893abaa8702a9954000001a7-1483550019.410520-1401542043 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-01-04 09:34:50-- 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% 225M=0s 2017-01-04 09:34:50 (225 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.109533 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 09:34:50.571185) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 09:34:50.571510) ========= 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=a0d2d2de893abaa8702a9954000001a7-1483550019.410520-1401542043 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019725 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 09:34:50.622171) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 09:34:50.622523) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 05a3443d658d29e77cae1da1915ca51814534d73 --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 05a3443d658d29e77cae1da1915ca51814534d73 --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=a0d2d2de893abaa8702a9954000001a7-1483550019.410520-1401542043 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-01-04 09:34:50,920 truncating revision to first 12 chars 2017-01-04 09:34:50,920 Setting DEBUG logging. 2017-01-04 09:34:50,920 attempt 1/10 2017-01-04 09:34:50,920 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/05a3443d658d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-04 09:34:51,368 unpacking tar archive at: mozilla-aurora-05a3443d658d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.857699 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 09:34:51.494044) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-04 09:34:51.494380) ========= script_repo_revision: 05a3443d658d29e77cae1da1915ca51814534d73 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-04 09:34:51.494805) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-04 09:34:51.495099) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-04 09:34:51.505122) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2017-01-04 09:34:51.505760) ========= /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=a0d2d2de893abaa8702a9954000001a7-1483550019.410520-1401542043 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:34:51 INFO - MultiFileLogger online at 20170104 09:34:51 in /builds/slave/test 09:34:51 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:34:51 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:34:51 INFO - {'append_to_log': False, 09:34:51 INFO - 'base_work_dir': '/builds/slave/test', 09:34:51 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:34:51 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:34:51 INFO - 'branch': 'Mozilla-Aurora', 09:34:51 INFO - 'buildbot_json_path': 'buildprops.json', 09:34:51 INFO - 'config_files': ('talos/linux_config.py',), 09:34:51 INFO - 'default_actions': ('clobber', 09:34:51 INFO - 'read-buildbot-config', 09:34:51 INFO - 'download-and-extract', 09:34:51 INFO - 'populate-webroot', 09:34:51 INFO - 'create-virtualenv', 09:34:51 INFO - 'install', 09:34:51 INFO - 'run-tests'), 09:34:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:34:51 INFO - 'download_minidump_stackwalk': True, 09:34:51 INFO - 'download_symbols': 'ondemand', 09:34:51 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:34:51 INFO - 'tooltool.py': '/tools/tooltool.py', 09:34:51 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:34:51 INFO - '/tools/misc-python/virtualenv.py')}, 09:34:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:34:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:34:51 INFO - 'installer_path': 'installer.exe', 09:34:51 INFO - 'log_level': 'info', 09:34:51 INFO - 'log_name': 'talos', 09:34:51 INFO - 'log_to_console': True, 09:34:51 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:34:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:34:51 INFO - 'opt_config_files': (), 09:34:51 INFO - 'pip_index': False, 09:34:51 INFO - 'sps_profile': False, 09:34:51 INFO - 'sps_profile_interval': 0, 09:34:51 INFO - 'suite': 'dromaeojs', 09:34:51 INFO - 'system_bits': '32', 09:34:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:34:51 INFO - 'title': 'talos-linux64-ix-002', 09:34:51 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:34:51 INFO - 'use_talos_json': True, 09:34:51 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:34:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:34:51 INFO - 'work_dir': 'build'} 09:34:51 INFO - [mozharness: 2017-01-04 17:34:51.896927Z] Running clobber step. 09:34:51 INFO - Running pre-action listener: _resource_record_pre_action 09:34:51 INFO - Running main action method: clobber 09:34:51 INFO - rmtree: /builds/slave/test/build 09:34:51 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:34:52 INFO - Running post-action listener: _resource_record_post_action 09:34:52 INFO - [mozharness: 2017-01-04 17:34:52.870571Z] Finished clobber step (success) 09:34:52 INFO - [mozharness: 2017-01-04 17:34:52.870670Z] Running read-buildbot-config step. 09:34:52 INFO - Running pre-action listener: _resource_record_pre_action 09:34:52 INFO - Running main action method: read_buildbot_config 09:34:52 INFO - Using buildbot properties: 09:34:52 INFO - { 09:34:52 INFO - "project": "", 09:34:52 INFO - "product": "firefox", 09:34:52 INFO - "script_repo_revision": "production", 09:34:52 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 09:34:52 INFO - "repository": "", 09:34:52 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 09:34:52 INFO - "buildid": "20170104075439", 09:34:52 INFO - "pgo_build": "True", 09:34:52 INFO - "basedir": "/builds/slave/test", 09:34:52 INFO - "buildnumber": 708, 09:34:52 INFO - "slavename": "talos-linux64-ix-002", 09:34:52 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 09:34:52 INFO - "platform": "ubuntu64_hw", 09:34:52 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 09:34:52 INFO - "branch": "mozilla-aurora", 09:34:52 INFO - "repo_path": "releases/mozilla-aurora", 09:34:52 INFO - "revision": "05a3443d658d29e77cae1da1915ca51814534d73", 09:34:52 INFO - "stage_platform": "linux64-pgo", 09:34:52 INFO - "builduid": "bf620d76b39049efa36d1077e6da3bb1", 09:34:52 INFO - "slavebuilddir": "test" 09:34:52 INFO - } 09:34:52 INFO - Found installer url https://queue.taskcluster.net/v1/task/T-5DFV7VTcu4imG7NoWSdg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2. 09:34:52 INFO - Running post-action listener: _resource_record_post_action 09:34:52 INFO - [mozharness: 2017-01-04 17:34:52.873078Z] Finished read-buildbot-config step (success) 09:34:52 INFO - [mozharness: 2017-01-04 17:34:52.873164Z] Running download-and-extract step. 09:34:52 INFO - Running pre-action listener: _resource_record_pre_action 09:34:52 INFO - Running main action method: download_and_extract 09:34:52 INFO - mkdir: /builds/slave/test/build/tests 09:34:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:52 INFO - trying https://queue.taskcluster.net/v1/task/T-5DFV7VTcu4imG7NoWSdg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.test_packages.json 09:34:52 INFO - Downloading https://queue.taskcluster.net/v1/task/T-5DFV7VTcu4imG7NoWSdg/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:34:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/T-5DFV7VTcu4imG7NoWSdg/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:34:53 INFO - Downloaded 1361 bytes. 09:34:53 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.test_packages.json 09:34:53 INFO - Using the following test package requirements: 09:34:53 INFO - {u'common': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip'], 09:34:53 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 09:34:53 INFO - u'firefox-52.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 09:34:53 INFO - u'gtest': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 09:34:53 INFO - u'firefox-52.0a2.en-US.linux-x86_64.gtest.tests.zip'], 09:34:53 INFO - u'jittest': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 09:34:53 INFO - u'jsshell-linux-x86_64.zip'], 09:34:53 INFO - u'mochitest': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 09:34:53 INFO - u'firefox-52.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 09:34:53 INFO - u'mozbase': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip'], 09:34:53 INFO - u'reftest': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 09:34:53 INFO - u'firefox-52.0a2.en-US.linux-x86_64.reftest.tests.zip'], 09:34:53 INFO - u'talos': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 09:34:53 INFO - u'firefox-52.0a2.en-US.linux-x86_64.talos.tests.zip'], 09:34:53 INFO - u'web-platform': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 09:34:53 INFO - u'firefox-52.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 09:34:53 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 09:34:53 INFO - u'firefox-52.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 09:34:53 INFO - Downloading packages: [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:34:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/T-5DFV7VTcu4imG7NoWSdg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.common.tests.zip 09:34:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T-5DFV7VTcu4imG7NoWSdg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:34:53 INFO - Fetch https://queue.taskcluster.net/v1/task/T-5DFV7VTcu4imG7NoWSdg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.common.tests.zip into memory 09:34:54 INFO - Http code: 200 09:34:54 INFO - via: 1.1 d8f42fc9558e3e49ebfdf8834baeb756.cloudfront.net (CloudFront) 09:34:54 INFO - x-amz-cf-id: xU6mvWUUPwoTHVoL9AlsnGHFKkkoYtYAbnSb7FK8YNXQv6CAE1lo5A== 09:34:54 INFO - x-amz-version-id: b6wGHpr5DWGahRnLobeC0Pm64izSDZ2c 09:34:54 INFO - Expected file size: 21942320 09:34:54 INFO - Obtained file size: 21942320 09:34:58 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:34:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/T-5DFV7VTcu4imG7NoWSdg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.common.tests.zip 09:34:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T-5DFV7VTcu4imG7NoWSdg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:34:58 INFO - Fetch https://queue.taskcluster.net/v1/task/T-5DFV7VTcu4imG7NoWSdg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.common.tests.zip into memory 09:34:58 INFO - Http code: 200 09:34:58 INFO - via: 1.1 d8f42fc9558e3e49ebfdf8834baeb756.cloudfront.net (CloudFront) 09:34:58 INFO - x-amz-cf-id: L4jUA5ESWGNWug58S9uRpUFRYZ58D4I--VUPubcm1AOQzhRlr8aRrA== 09:34:58 INFO - x-amz-version-id: b6wGHpr5DWGahRnLobeC0Pm64izSDZ2c 09:34:59 INFO - Expected file size: 21942320 09:34:59 INFO - Obtained file size: 21942320 09:35:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/T-5DFV7VTcu4imG7NoWSdg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.talos.tests.zip 09:35:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T-5DFV7VTcu4imG7NoWSdg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:35:03 INFO - Fetch https://queue.taskcluster.net/v1/task/T-5DFV7VTcu4imG7NoWSdg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.talos.tests.zip into memory 09:35:04 INFO - Http code: 200 09:35:04 INFO - via: 1.1 b3bccc0e4c2cc0a33e7e8308d88cacdd.cloudfront.net (CloudFront) 09:35:04 INFO - x-amz-cf-id: syupuQVGVyYOb15269bpx7QAOtOJLU-Z6Tor_3ydGshO_5A_x_TNvQ== 09:35:04 INFO - x-amz-version-id: aPQeUPGRqQDz4bLVE1rY1kH9DXd9lYgU 09:35:04 INFO - Expected file size: 14409038 09:35:04 INFO - Obtained file size: 14409038 09:35:04 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:35:04 INFO - trying https://queue.taskcluster.net/v1/task/T-5DFV7VTcu4imG7NoWSdg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 09:35:04 INFO - Downloading https://queue.taskcluster.net/v1/task/T-5DFV7VTcu4imG7NoWSdg/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:35:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/T-5DFV7VTcu4imG7NoWSdg/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:35:05 INFO - Downloaded 64028405 bytes. 09:35:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/T-5DFV7VTcu4imG7NoWSdg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 09:35:05 INFO - mkdir: /builds/slave/test/properties 09:35:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:35:05 INFO - Writing to file /builds/slave/test/properties/build_url 09:35:05 INFO - Contents: 09:35:05 INFO - build_url:https://queue.taskcluster.net/v1/task/T-5DFV7VTcu4imG7NoWSdg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 09:35:06 INFO - Running post-action listener: _resource_record_post_action 09:35:06 INFO - Running post-action listener: set_extra_try_arguments 09:35:06 INFO - [mozharness: 2017-01-04 17:35:06.538337Z] Finished download-and-extract step (success) 09:35:06 INFO - [mozharness: 2017-01-04 17:35:06.538562Z] Running populate-webroot step. 09:35:06 INFO - Running pre-action listener: _resource_record_pre_action 09:35:06 INFO - Running main action method: populate_webroot 09:35:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:35:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:35:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:35:06 INFO - '--tppagecycles', 09:35:06 INFO - '7'], 09:35:06 INFO - 'tests': ['tsvgm']}}, 09:35:06 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:35:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:35:06 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:35:06 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:35:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:35:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:35:06 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:35:06 INFO - 'talos_options': ['--disable-e10s'], 09:35:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:35:06 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:35:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:35:06 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:35:06 INFO - 'talos_options': ['--disable-e10s'], 09:35:06 INFO - 'tests': ['damp', 'tps']}, 09:35:06 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:35:06 INFO - 'tests': ['damp', 'tps']}, 09:35:06 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:35:06 INFO - 'tests': ['dromaeo_dom']}, 09:35:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:35:06 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:35:06 INFO - 'tests': ['basic_compositor_video']}, 09:35:06 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:35:06 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:35:06 INFO - 'tests': ['a11yr', 09:35:06 INFO - 'ts_paint', 09:35:06 INFO - 'tpaint', 09:35:06 INFO - 'sessionrestore', 09:35:06 INFO - 'sessionrestore_no_auto_restore', 09:35:06 INFO - 'tabpaint']}, 09:35:06 INFO - 'other-e10s': {'tests': ['a11yr', 09:35:06 INFO - 'ts_paint', 09:35:06 INFO - 'tpaint', 09:35:06 INFO - 'sessionrestore', 09:35:06 INFO - 'sessionrestore_no_auto_restore', 09:35:06 INFO - 'tabpaint']}, 09:35:06 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:35:06 INFO - 'ts_paint', 09:35:06 INFO - 'tpaint', 09:35:06 INFO - 'sessionrestore', 09:35:06 INFO - 'sessionrestore_no_auto_restore', 09:35:06 INFO - 'tabpaint']}, 09:35:06 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:35:06 INFO - 'ts_paint', 09:35:06 INFO - 'tpaint', 09:35:06 INFO - 'sessionrestore', 09:35:06 INFO - 'sessionrestore_no_auto_restore', 09:35:06 INFO - 'tabpaint']}, 09:35:06 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:35:06 INFO - 'tests': ['a11yr', 09:35:06 INFO - 'ts_paint', 09:35:06 INFO - 'tpaint', 09:35:06 INFO - 'sessionrestore', 09:35:06 INFO - 'sessionrestore_no_auto_restore', 09:35:06 INFO - 'tabpaint']}, 09:35:06 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:35:06 INFO - 'tests': ['a11yr', 09:35:06 INFO - 'ts_paint', 09:35:06 INFO - 'tpaint', 09:35:06 INFO - 'sessionrestore', 09:35:06 INFO - 'sessionrestore_no_auto_restore', 09:35:06 INFO - 'tabpaint']}, 09:35:06 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:35:06 INFO - 'tests': ['tsvgx', 09:35:06 INFO - 'tsvgr_opacity', 09:35:06 INFO - 'tart', 09:35:06 INFO - 'tscrollx', 09:35:06 INFO - 'cart']}, 09:35:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:35:06 INFO - 'tsvgr_opacity', 09:35:06 INFO - 'tart', 09:35:06 INFO - 'tscrollx', 09:35:06 INFO - 'cart']}, 09:35:06 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:35:06 INFO - 'talos_options': ['--disable-e10s'], 09:35:06 INFO - 'tests': ['tp5o']}, 09:35:06 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:35:06 INFO - 'tests': ['tp5o']}, 09:35:06 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:35:06 INFO - 'talos_options': ['--disable-e10s', 09:35:06 INFO - '--xperf_path', 09:35:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:35:06 INFO - 'tests': ['tp5n']}, 09:35:06 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:35:06 INFO - 'talos_options': ['--xperf_path', 09:35:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:35:06 INFO - 'tests': ['tp5n']}}, 09:35:06 INFO - 'talos.zip': {'path': '', 09:35:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:35:06 INFO - Running post-action listener: _resource_record_post_action 09:35:06 INFO - [mozharness: 2017-01-04 17:35:06.555584Z] Finished populate-webroot step (success) 09:35:06 INFO - [mozharness: 2017-01-04 17:35:06.555672Z] Running create-virtualenv step. 09:35:06 INFO - Running pre-action listener: _resource_record_pre_action 09:35:06 INFO - Running main action method: create_virtualenv 09:35:06 INFO - Creating virtualenv /builds/slave/test/build/venv 09:35:06 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:35:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:35:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:35:06 INFO - Using real prefix '/usr' 09:35:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:35:07 INFO - Installing distribute.............................................................................................................................................................................................done. 09:35:09 INFO - Installing pip.................done. 09:35:09 INFO - Return code: 0 09:35:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:35:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:35:09 INFO - Reading from file tmpfile_stdout 09:35:09 INFO - Output received: 09:35:09 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:35:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:35:09 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:35:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:35:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:09 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:35:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:35:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:09 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 0x7f1736cc7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18668a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1933ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1930f60>, '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 0x1931d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19321d0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1483550019.410520-1401542043', '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:35:09 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:35:09 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:35:09 INFO - Using env: {'DISPLAY': ':0', 09:35:09 INFO - 'HOME': '/home/cltbld', 09:35:09 INFO - 'LANG': 'en_US.UTF-8', 09:35:09 INFO - 'LANGUAGE': 'en_US:en', 09:35:09 INFO - 'LOGNAME': 'cltbld', 09:35:09 INFO - 'MAIL': '/var/mail/cltbld', 09:35:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:09 INFO - 'MOZ_NO_REMOTE': '1', 09:35:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:35:09 INFO - 'NO_EM_RESTART': '1', 09:35:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:35:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:09 INFO - 'PWD': '/builds/slave/test', 09:35:09 INFO - 'SHELL': '/bin/bash', 09:35:09 INFO - 'SHLVL': '1', 09:35:09 INFO - 'TERM': 'linux', 09:35:09 INFO - 'TMOUT': '86400', 09:35:09 INFO - 'USER': 'cltbld', 09:35:09 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1483550019.410520-1401542043', 09:35:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:09 INFO - '_': '/tools/buildbot/bin/python'} 09:35:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:35:09 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:35:09 INFO - Cleaning up... 09:35:09 INFO - Return code: 0 09:35:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:35:09 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:35:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:35:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:09 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:35:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:35:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:09 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 0x7f1736cc7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18668a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1933ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1930f60>, '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 0x1931d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19321d0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1483550019.410520-1401542043', '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:35:09 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:35:09 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:35:09 INFO - Using env: {'DISPLAY': ':0', 09:35:09 INFO - 'HOME': '/home/cltbld', 09:35:09 INFO - 'LANG': 'en_US.UTF-8', 09:35:09 INFO - 'LANGUAGE': 'en_US:en', 09:35:09 INFO - 'LOGNAME': 'cltbld', 09:35:09 INFO - 'MAIL': '/var/mail/cltbld', 09:35:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:09 INFO - 'MOZ_NO_REMOTE': '1', 09:35:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:35:09 INFO - 'NO_EM_RESTART': '1', 09:35:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:35:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:09 INFO - 'PWD': '/builds/slave/test', 09:35:09 INFO - 'SHELL': '/bin/bash', 09:35:09 INFO - 'SHLVL': '1', 09:35:09 INFO - 'TERM': 'linux', 09:35:09 INFO - 'TMOUT': '86400', 09:35:09 INFO - 'USER': 'cltbld', 09:35:09 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1483550019.410520-1401542043', 09:35:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:09 INFO - '_': '/tools/buildbot/bin/python'} 09:35:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:35:09 INFO - Downloading/unpacking psutil>=3.1.1 09:35:09 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:35:09 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:35:09 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:35:09 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:35:09 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:35:09 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:35:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:35:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:35:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:35:11 INFO - Installing collected packages: psutil 09:35:11 INFO - Running setup.py install for psutil 09:35:11 INFO - building 'psutil._psutil_linux' extension 09:35:11 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:35:12 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:35:12 INFO - building 'psutil._psutil_posix' extension 09:35:12 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:35:12 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:35:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:35:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:35:12 INFO - Successfully installed psutil 09:35:12 INFO - Cleaning up... 09:35:12 INFO - Return code: 0 09:35:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:35:12 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:35:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:35:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:12 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:35:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:35:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:12 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 0x7f1736cc7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18668a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1933ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1930f60>, '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 0x1931d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19321d0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1483550019.410520-1401542043', '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:35:12 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:35:12 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:35:12 INFO - Using env: {'DISPLAY': ':0', 09:35:12 INFO - 'HOME': '/home/cltbld', 09:35:12 INFO - 'LANG': 'en_US.UTF-8', 09:35:12 INFO - 'LANGUAGE': 'en_US:en', 09:35:12 INFO - 'LOGNAME': 'cltbld', 09:35:12 INFO - 'MAIL': '/var/mail/cltbld', 09:35:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:12 INFO - 'MOZ_NO_REMOTE': '1', 09:35:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:35:12 INFO - 'NO_EM_RESTART': '1', 09:35:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:35:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:12 INFO - 'PWD': '/builds/slave/test', 09:35:12 INFO - 'SHELL': '/bin/bash', 09:35:12 INFO - 'SHLVL': '1', 09:35:12 INFO - 'TERM': 'linux', 09:35:12 INFO - 'TMOUT': '86400', 09:35:12 INFO - 'USER': 'cltbld', 09:35:12 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1483550019.410520-1401542043', 09:35:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:12 INFO - '_': '/tools/buildbot/bin/python'} 09:35:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:35:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:35:12 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:35:12 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:35:12 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:35:12 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:35:12 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:35:12 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:35:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:35:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:35:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:35:14 INFO - Installing collected packages: mozsystemmonitor 09:35:14 INFO - Running setup.py install for mozsystemmonitor 09:35:14 INFO - Successfully installed mozsystemmonitor 09:35:14 INFO - Cleaning up... 09:35:14 INFO - Return code: 0 09:35:14 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:35:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:35:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:35:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:35:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:35:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:14 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 0x7f1736cc7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18668a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1933ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1930f60>, '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 0x1931d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19321d0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1483550019.410520-1401542043', '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:35:14 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:35:14 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:35:14 INFO - Using env: {'DISPLAY': ':0', 09:35:14 INFO - 'HOME': '/home/cltbld', 09:35:14 INFO - 'LANG': 'en_US.UTF-8', 09:35:14 INFO - 'LANGUAGE': 'en_US:en', 09:35:14 INFO - 'LOGNAME': 'cltbld', 09:35:14 INFO - 'MAIL': '/var/mail/cltbld', 09:35:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:14 INFO - 'MOZ_NO_REMOTE': '1', 09:35:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:35:14 INFO - 'NO_EM_RESTART': '1', 09:35:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:35:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:14 INFO - 'PWD': '/builds/slave/test', 09:35:14 INFO - 'SHELL': '/bin/bash', 09:35:14 INFO - 'SHLVL': '1', 09:35:14 INFO - 'TERM': 'linux', 09:35:14 INFO - 'TMOUT': '86400', 09:35:14 INFO - 'USER': 'cltbld', 09:35:14 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1483550019.410520-1401542043', 09:35:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:14 INFO - '_': '/tools/buildbot/bin/python'} 09:35:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:35:15 INFO - Downloading/unpacking jsonschema==2.5.1 09:35:15 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:35:15 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:35:15 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:35:15 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:35:15 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:35:15 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:35:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:35:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:35:17 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:35:17 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:35:17 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:35:17 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:35:17 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:35:17 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:35:17 INFO - Downloading functools32-3.2.3-2.tar.gz 09:35:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:35:17 INFO - warning: no files found matching '*.txt' 09:35:17 INFO - no previously-included directories found matching 'build' 09:35:17 INFO - no previously-included directories found matching 'dist' 09:35:17 INFO - no previously-included directories found matching '.git*' 09:35:17 INFO - Installing collected packages: jsonschema, functools32 09:35:17 INFO - Running setup.py install for functools32 09:35:17 INFO - warning: no files found matching '*.txt' 09:35:17 INFO - no previously-included directories found matching 'build' 09:35:17 INFO - no previously-included directories found matching 'dist' 09:35:17 INFO - no previously-included directories found matching '.git*' 09:35:17 INFO - Successfully installed jsonschema functools32 09:35:17 INFO - Cleaning up... 09:35:17 INFO - Return code: 0 09:35:17 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:35:17 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:35:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:35:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:17 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:35:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:35:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:17 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 0x7f1736cc7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18668a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1933ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1930f60>, '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 0x1931d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19321d0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1483550019.410520-1401542043', '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:35:17 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:35:17 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:35:17 INFO - Using env: {'DISPLAY': ':0', 09:35:17 INFO - 'HOME': '/home/cltbld', 09:35:17 INFO - 'LANG': 'en_US.UTF-8', 09:35:17 INFO - 'LANGUAGE': 'en_US:en', 09:35:17 INFO - 'LOGNAME': 'cltbld', 09:35:17 INFO - 'MAIL': '/var/mail/cltbld', 09:35:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:17 INFO - 'MOZ_NO_REMOTE': '1', 09:35:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:35:17 INFO - 'NO_EM_RESTART': '1', 09:35:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:35:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:17 INFO - 'PWD': '/builds/slave/test', 09:35:17 INFO - 'SHELL': '/bin/bash', 09:35:17 INFO - 'SHLVL': '1', 09:35:17 INFO - 'TERM': 'linux', 09:35:17 INFO - 'TMOUT': '86400', 09:35:17 INFO - 'USER': 'cltbld', 09:35:17 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1483550019.410520-1401542043', 09:35:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:17 INFO - '_': '/tools/buildbot/bin/python'} 09:35:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:35:17 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:35:17 INFO - Cleaning up... 09:35:17 INFO - Return code: 0 09:35:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:35:17 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:35:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:35:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:17 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:35:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:35:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:17 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 0x7f1736cc7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18668a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1933ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1930f60>, '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 0x1931d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19321d0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1483550019.410520-1401542043', '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:35:17 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:35:17 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:35:17 INFO - Using env: {'DISPLAY': ':0', 09:35:17 INFO - 'HOME': '/home/cltbld', 09:35:17 INFO - 'LANG': 'en_US.UTF-8', 09:35:17 INFO - 'LANGUAGE': 'en_US:en', 09:35:17 INFO - 'LOGNAME': 'cltbld', 09:35:17 INFO - 'MAIL': '/var/mail/cltbld', 09:35:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:17 INFO - 'MOZ_NO_REMOTE': '1', 09:35:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:35:17 INFO - 'NO_EM_RESTART': '1', 09:35:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:35:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:17 INFO - 'PWD': '/builds/slave/test', 09:35:17 INFO - 'SHELL': '/bin/bash', 09:35:17 INFO - 'SHLVL': '1', 09:35:17 INFO - 'TERM': 'linux', 09:35:17 INFO - 'TMOUT': '86400', 09:35:17 INFO - 'USER': 'cltbld', 09:35:17 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1483550019.410520-1401542043', 09:35:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:17 INFO - '_': '/tools/buildbot/bin/python'} 09:35:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:35:18 INFO - Downloading/unpacking blobuploader==1.2.4 09:35:18 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:35:18 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:35:18 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:35:18 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:35:18 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:35:18 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:35:20 INFO - Downloading blobuploader-1.2.4.tar.gz 09:35:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:35:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:35:20 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:35:20 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:35:20 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:35:20 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:35:20 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:35:20 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:35:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:35:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:35:20 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:35:20 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:35:20 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:35:20 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:35:20 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:35:20 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:35:20 INFO - Downloading docopt-0.6.1.tar.gz 09:35:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:35:20 INFO - Installing collected packages: blobuploader, requests, docopt 09:35:20 INFO - Running setup.py install for blobuploader 09:35:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:35:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:35:20 INFO - Running setup.py install for requests 09:35:21 INFO - Running setup.py install for docopt 09:35:21 INFO - Successfully installed blobuploader requests docopt 09:35:21 INFO - Cleaning up... 09:35:21 INFO - Return code: 0 09:35:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:35: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:35:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:35:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35: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:35:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:35:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:21 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 0x7f1736cc7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18668a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1933ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1930f60>, '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 0x1931d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19321d0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1483550019.410520-1401542043', '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:35:21 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:35:21 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:35:21 INFO - Using env: {'DISPLAY': ':0', 09:35:21 INFO - 'HOME': '/home/cltbld', 09:35:21 INFO - 'LANG': 'en_US.UTF-8', 09:35:21 INFO - 'LANGUAGE': 'en_US:en', 09:35:21 INFO - 'LOGNAME': 'cltbld', 09:35:21 INFO - 'MAIL': '/var/mail/cltbld', 09:35:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:21 INFO - 'MOZ_NO_REMOTE': '1', 09:35:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:35:21 INFO - 'NO_EM_RESTART': '1', 09:35:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:35:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:21 INFO - 'PWD': '/builds/slave/test', 09:35:21 INFO - 'SHELL': '/bin/bash', 09:35:21 INFO - 'SHLVL': '1', 09:35:21 INFO - 'TERM': 'linux', 09:35:21 INFO - 'TMOUT': '86400', 09:35:21 INFO - 'USER': 'cltbld', 09:35:21 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1483550019.410520-1401542043', 09:35:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:21 INFO - '_': '/tools/buildbot/bin/python'} 09:35:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:35:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:35:21 INFO - Running setup.py (path:/tmp/pip-UwFAmJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:35:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:35:21 INFO - Running setup.py (path:/tmp/pip-dL7ys9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:35:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:35:21 INFO - Running setup.py (path:/tmp/pip-P5q9Fd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:35:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:35:21 INFO - Running setup.py (path:/tmp/pip-ysVATf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:35:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:35:21 INFO - Running setup.py (path:/tmp/pip-RSraY4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:35:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:35:22 INFO - Running setup.py (path:/tmp/pip-q25Nyr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:35:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:35:22 INFO - Running setup.py (path:/tmp/pip-fLjsdZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:35:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:35:22 INFO - Running setup.py (path:/tmp/pip-ENTW0W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:35:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:35:22 INFO - Running setup.py (path:/tmp/pip-eue2z9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:35:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:35:22 INFO - Running setup.py (path:/tmp/pip-eAk4eO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:35:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:35:22 INFO - Running setup.py (path:/tmp/pip-i7_4wv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:35:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:35:22 INFO - Running setup.py (path:/tmp/pip-uM0pfX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:35:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:35:22 INFO - Running setup.py (path:/tmp/pip-57rKbY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:35:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:35:22 INFO - Running setup.py (path:/tmp/pip-x7X4_L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:35:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:35:22 INFO - Running setup.py (path:/tmp/pip-af6Ko4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:35:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:35:22 INFO - Running setup.py (path:/tmp/pip-wc6G5y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:35:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:35:22 INFO - Running setup.py (path:/tmp/pip-z3bH58-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:35:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:35:22 INFO - Running setup.py install for manifestparser 09:35:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:35:23 INFO - Running setup.py install for mozcrash 09:35:23 INFO - Running setup.py install for mozdebug 09:35:23 INFO - Running setup.py install for mozdevice 09:35:23 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:35:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:35:23 INFO - Running setup.py install for mozfile 09:35:23 INFO - Running setup.py install for mozhttpd 09:35:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:35:23 INFO - Running setup.py install for mozinfo 09:35:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:35:23 INFO - Running setup.py install for mozInstall 09:35:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:35:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:35:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:35:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:35:23 INFO - Running setup.py install for mozleak 09:35:23 INFO - Running setup.py install for mozlog 09:35:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:35:23 INFO - Running setup.py install for moznetwork 09:35:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:35:24 INFO - Running setup.py install for mozprocess 09:35:24 INFO - Running setup.py install for mozprofile 09:35:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:35:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:35:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:35:24 INFO - Running setup.py install for mozrunner 09:35:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:35:24 INFO - Running setup.py install for mozscreenshot 09:35:24 INFO - Running setup.py install for moztest 09:35:24 INFO - Running setup.py install for mozversion 09:35:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:35:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:35:24 INFO - Cleaning up... 09:35:24 INFO - Return code: 0 09:35:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:35:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:35:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:35:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:35:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:35:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:24 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 0x7f1736cc7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18668a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1933ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1930f60>, '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 0x1931d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19321d0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1483550019.410520-1401542043', '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:35:24 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:35:24 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:35:24 INFO - Using env: {'DISPLAY': ':0', 09:35:24 INFO - 'HOME': '/home/cltbld', 09:35:24 INFO - 'LANG': 'en_US.UTF-8', 09:35:24 INFO - 'LANGUAGE': 'en_US:en', 09:35:24 INFO - 'LOGNAME': 'cltbld', 09:35:24 INFO - 'MAIL': '/var/mail/cltbld', 09:35:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:24 INFO - 'MOZ_NO_REMOTE': '1', 09:35:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:35:24 INFO - 'NO_EM_RESTART': '1', 09:35:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:35:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:24 INFO - 'PWD': '/builds/slave/test', 09:35:24 INFO - 'SHELL': '/bin/bash', 09:35:24 INFO - 'SHLVL': '1', 09:35:24 INFO - 'TERM': 'linux', 09:35:24 INFO - 'TMOUT': '86400', 09:35:24 INFO - 'USER': 'cltbld', 09:35:24 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1483550019.410520-1401542043', 09:35:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:24 INFO - '_': '/tools/buildbot/bin/python'} 09:35:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:35:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:35:24 INFO - Running setup.py (path:/tmp/pip-RN4VkB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:35:25 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:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:35:25 INFO - Running setup.py (path:/tmp/pip-Oh8JVP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:35:25 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:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:35:25 INFO - Running setup.py (path:/tmp/pip-VyHKiK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:35:25 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:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:35:25 INFO - Running setup.py (path:/tmp/pip-ju5QbW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:35:25 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:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:35:25 INFO - Running setup.py (path:/tmp/pip-H21pcP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:35:25 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:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:35:25 INFO - Running setup.py (path:/tmp/pip-uBCfmG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:35:25 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:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:35:25 INFO - Running setup.py (path:/tmp/pip-noI_1X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:35:25 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:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:35:25 INFO - Running setup.py (path:/tmp/pip-8wUxwh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:35:25 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:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:35:25 INFO - Running setup.py (path:/tmp/pip-Dj05mM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:35:25 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:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:35:25 INFO - Running setup.py (path:/tmp/pip-XUITaD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:35:25 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:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:35:25 INFO - Running setup.py (path:/tmp/pip-bdmAih-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:35:25 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:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:35:25 INFO - Running setup.py (path:/tmp/pip-HC4Tap-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:35:25 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:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:35:25 INFO - Running setup.py (path:/tmp/pip-KRO6SS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:35:25 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:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:35:25 INFO - Running setup.py (path:/tmp/pip-Cj4S2q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:35:25 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:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:35:25 INFO - Running setup.py (path:/tmp/pip-Ni46yy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:35:26 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:35:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:35:26 INFO - Running setup.py (path:/tmp/pip-ci9Yyw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:35:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:35:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:35:26 INFO - Running setup.py (path:/tmp/pip-1cwwEp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:35:26 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:35:26 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:35:26 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:35:26 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:35:26 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:35:26 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:35:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:35:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:35:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:35:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:35:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:35:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:35:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:35:28 INFO - Downloading blessings-1.6.tar.gz 09:35:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:35:28 INFO - Installing collected packages: blessings 09:35:28 INFO - Running setup.py install for blessings 09:35:28 INFO - Successfully installed blessings 09:35:28 INFO - Cleaning up... 09:35:28 INFO - Return code: 0 09:35:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:35:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:35:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:35:28 INFO - Reading from file tmpfile_stdout 09:35:28 INFO - Current package versions: 09:35:28 INFO - argparse == 1.2.1 09:35:28 INFO - blessings == 1.6 09:35:28 INFO - blobuploader == 1.2.4 09:35:28 INFO - docopt == 0.6.1 09:35:28 INFO - functools32 == 3.2.3-2 09:35:28 INFO - jsonschema == 2.5.1 09:35:28 INFO - manifestparser == 1.1 09:35:28 INFO - mozInstall == 1.12 09:35:28 INFO - mozcrash == 1.0 09:35:28 INFO - mozdebug == 0.1 09:35:28 INFO - mozdevice == 0.48 09:35:28 INFO - mozfile == 1.2 09:35:28 INFO - mozhttpd == 0.7 09:35:28 INFO - mozinfo == 0.9 09:35:28 INFO - mozleak == 0.1 09:35:28 INFO - mozlog == 3.4 09:35:28 INFO - moznetwork == 0.27 09:35:28 INFO - mozprocess == 0.23 09:35:28 INFO - mozprofile == 0.28 09:35:28 INFO - mozrunner == 6.13 09:35:28 INFO - mozscreenshot == 0.1 09:35:28 INFO - mozsystemmonitor == 0.3 09:35:28 INFO - moztest == 0.8 09:35:28 INFO - mozversion == 1.4 09:35:28 INFO - psutil == 3.1.1 09:35:28 INFO - requests == 1.2.3 09:35:28 INFO - wsgiref == 0.1.2 09:35:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:35:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:35:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:35:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:35:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:35:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:28 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 0x7f1736cc7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18668a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1933ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1930f60>, '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 0x1931d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19321d0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1483550019.410520-1401542043', '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:35:28 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:35:28 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:35:28 INFO - Using env: {'DISPLAY': ':0', 09:35:28 INFO - 'HOME': '/home/cltbld', 09:35:28 INFO - 'LANG': 'en_US.UTF-8', 09:35:28 INFO - 'LANGUAGE': 'en_US:en', 09:35:28 INFO - 'LOGNAME': 'cltbld', 09:35:28 INFO - 'MAIL': '/var/mail/cltbld', 09:35:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:28 INFO - 'MOZ_NO_REMOTE': '1', 09:35:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:35:28 INFO - 'NO_EM_RESTART': '1', 09:35:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:35:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:28 INFO - 'PWD': '/builds/slave/test', 09:35:28 INFO - 'SHELL': '/bin/bash', 09:35:28 INFO - 'SHLVL': '1', 09:35:28 INFO - 'TERM': 'linux', 09:35:28 INFO - 'TMOUT': '86400', 09:35:28 INFO - 'USER': 'cltbld', 09:35:28 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1483550019.410520-1401542043', 09:35:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:28 INFO - '_': '/tools/buildbot/bin/python'} 09:35:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:35:28 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:35:28 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:35:28 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:35:28 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:35:28 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:35:28 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:35:28 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:35:28 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:35:28 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:35:28 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:35:28 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:35:28 INFO - Cleaning up... 09:35:28 INFO - Return code: 0 09:35:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:35:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:35:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:35:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:35:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:35:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:28 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 0x7f1736cc7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18668a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1933ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1930f60>, '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 0x1931d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19321d0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1483550019.410520-1401542043', '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:35:28 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:35:28 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:35:28 INFO - Using env: {'DISPLAY': ':0', 09:35:28 INFO - 'HOME': '/home/cltbld', 09:35:28 INFO - 'LANG': 'en_US.UTF-8', 09:35:28 INFO - 'LANGUAGE': 'en_US:en', 09:35:28 INFO - 'LOGNAME': 'cltbld', 09:35:28 INFO - 'MAIL': '/var/mail/cltbld', 09:35:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:28 INFO - 'MOZ_NO_REMOTE': '1', 09:35:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:35:28 INFO - 'NO_EM_RESTART': '1', 09:35:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:35:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:28 INFO - 'PWD': '/builds/slave/test', 09:35:28 INFO - 'SHELL': '/bin/bash', 09:35:28 INFO - 'SHLVL': '1', 09:35:28 INFO - 'TERM': 'linux', 09:35:28 INFO - 'TMOUT': '86400', 09:35:28 INFO - 'USER': 'cltbld', 09:35:28 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1483550019.410520-1401542043', 09:35:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:28 INFO - '_': '/tools/buildbot/bin/python'} 09:35:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:35:29 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:35:29 INFO - Cleaning up... 09:35:29 INFO - Return code: 0 09:35:29 INFO - Running post-action listener: _resource_record_post_action 09:35:29 INFO - Running post-action listener: _start_resource_monitoring 09:35:29 INFO - Starting resource monitoring. 09:35:29 INFO - [mozharness: 2017-01-04 17:35:29.153961Z] Finished create-virtualenv step (success) 09:35:29 INFO - [mozharness: 2017-01-04 17:35:29.154474Z] Running install step. 09:35:29 INFO - Running pre-action listener: _resource_record_pre_action 09:35:29 INFO - Running main action method: install 09:35:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:35:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:35:29 INFO - Reading from file tmpfile_stdout 09:35:29 INFO - Detecting whether we're running mozinstall >=1.0... 09:35:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:35:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:35:29 INFO - Reading from file tmpfile_stdout 09:35:29 INFO - Output received: 09:35:29 INFO - Usage: mozinstall [options] installer 09:35:29 INFO - Options: 09:35:29 INFO - -h, --help show this help message and exit 09:35:29 INFO - -d DEST, --destination=DEST 09:35:29 INFO - Directory to install application into. [default: 09:35:29 INFO - "/builds/slave/test"] 09:35:29 INFO - --app=APP Application being installed. [default: firefox] 09:35:29 INFO - mkdir: /builds/slave/test/build/application 09:35:29 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:35:29 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:35:45 INFO - Reading from file tmpfile_stdout 09:35:45 INFO - Output received: 09:35:45 INFO - /builds/slave/test/build/application/firefox/firefox 09:35:45 INFO - Running post-action listener: _resource_record_post_action 09:35:45 INFO - [mozharness: 2017-01-04 17:35:45.381285Z] Finished install step (success) 09:35:45 INFO - [mozharness: 2017-01-04 17:35:45.381530Z] Running run-tests step. 09:35:45 INFO - Running pre-action listener: _resource_record_pre_action 09:35:45 INFO - Running main action method: run_tests 09:35:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:35:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:35:45 INFO - Python 2.7.3 09:35:45 INFO - Return code: 0 09:35:45 INFO - grabbing minidump binary from tooltool 09:35:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:35:45 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 0x1930f60>, '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 0x1931d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19321d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:35:45 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:35:45 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:35:45 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:35:45 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:35:45 INFO - Return code: 0 09:35:45 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:35:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:35:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:35:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:35:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:35:45 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/T-5DFV7VTcu4imG7NoWSdg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-002', '--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:35:45 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/T-5DFV7VTcu4imG7NoWSdg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-002 --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:35:45 INFO - Using env: {'DISPLAY': ':0', 09:35:45 INFO - 'HOME': '/home/cltbld', 09:35:45 INFO - 'LANG': 'en_US.UTF-8', 09:35:45 INFO - 'LANGUAGE': 'en_US:en', 09:35:45 INFO - 'LOGNAME': 'cltbld', 09:35:45 INFO - 'MAIL': '/var/mail/cltbld', 09:35:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:35:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:35:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:45 INFO - 'MOZ_NO_REMOTE': '1', 09:35:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:35:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:35:45 INFO - 'NO_EM_RESTART': '1', 09:35:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:35:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:45 INFO - 'PWD': '/builds/slave/test', 09:35:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:35:45 INFO - 'SHELL': '/bin/bash', 09:35:45 INFO - 'SHLVL': '1', 09:35:45 INFO - 'TERM': 'linux', 09:35:45 INFO - 'TMOUT': '86400', 09:35:45 INFO - 'USER': 'cltbld', 09:35:45 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1483550019.410520-1401542043', 09:35:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:45 INFO - '_': '/tools/buildbot/bin/python'} 09:35:45 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/T-5DFV7VTcu4imG7NoWSdg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-002', '--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:35:45 INFO - mozversion application_buildid: 20170104075439 09:35:45 INFO - mozversion application_changeset: 05a3443d658d29e77cae1da1915ca51814534d73 09:35:45 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:35:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:35:45 INFO - mozversion application_name: Firefox 09:35:45 INFO - mozversion application_remotingname: firefox-dev 09:35:45 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:35:45 INFO - mozversion application_vendor: Mozilla 09:35:45 INFO - mozversion application_version: 52.0a2 09:35:45 INFO - mozversion platform_buildid: 20170104075439 09:35:45 INFO - mozversion platform_changeset: 05a3443d658d29e77cae1da1915ca51814534d73 09:35:45 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:35:45 INFO - mozversion platform_version: 52.0a2 09:35:45 INFO - using testdate: 1483551345 09:35:45 INFO - actual date: 1483551345 09:35:45 INFO - starting webserver on 'localhost:50355' 09:35:45 INFO - SUITE-START | Running 2 tests 09:35:45 INFO - TEST-START | dromaeo_css 09:35:45 INFO - operating with platform_type : linux_ 09:35:45 INFO - Initialising browser for dromaeo_css test... 09:35:45 INFO - TEST-INFO | started process 7554 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWRSs6v/profile http://localhost:50355/getInfo.html) 09:35:49 INFO - PROCESS | 7554 | __metrics Screen width/height:1600/1200 09:35:49 INFO - PROCESS | 7554 | colorDepth:24 09:35:49 INFO - PROCESS | 7554 | Browser inner width/height: 1024/697 09:35:49 INFO - PROCESS | 7554 | __metrics 09:35:50 INFO - PROCESS | 7554 | 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:35:50 INFO - PROCESS | 7554 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:35:50 INFO - PROCESS | 7554 | console.error: PushService: 09:35:50 INFO - PROCESS | 7554 | stateChangeProcessEnqueue: Error transitioning state 09:35:50 INFO - PROCESS | 7554 | UnknownError 09:35:51 INFO - TEST-INFO | 7554: exit 0 09:35:51 INFO - Browser initialized. 09:35:51 INFO - Running cycle 1/1 for dromaeo_css test... 09:35:51 INFO - TEST-INFO | started process 7645 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWRSs6v/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:36:03 INFO - PROCESS | 7645 | RSS: Main: 144617472 09:36:03 INFO - PROCESS | 7645 | 09:39:05 INFO - PROCESS | 7645 | Cycle 1(1): loaded http://localhost:50355/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50355/tests/dromaeo/cssquery-ext.html) 09:39:06 INFO - PROCESS | 7645 | RSS: Main: 243396608 09:39:06 INFO - PROCESS | 7645 | 09:42:08 INFO - PROCESS | 7645 | Cycle 1(1): loaded http://localhost:50355/tests/dromaeo/cssquery-ext.html (next: http://localhost:50355/tests/dromaeo/cssquery-jquery.html) 09:42:09 INFO - PROCESS | 7645 | RSS: Main: 230973440 09:42:09 INFO - PROCESS | 7645 | 09:45:11 INFO - PROCESS | 7645 | Cycle 1(1): loaded http://localhost:50355/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50355/tests/dromaeo/cssquery-mootools.html) 09:45:11 INFO - PROCESS | 7645 | RSS: Main: 173817856 09:45:11 INFO - PROCESS | 7645 | 09:48:13 INFO - PROCESS | 7645 | Cycle 1(1): loaded http://localhost:50355/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50355/tests/dromaeo/cssquery-prototype.html) 09:48:14 INFO - PROCESS | 7645 | RSS: Main: 175345664 09:48:14 INFO - PROCESS | 7645 | 09:51:17 INFO - PROCESS | 7645 | Cycle 1(1): loaded http://localhost:50355/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50355/tests/dromaeo/cssquery-yui.html) 09:51:17 INFO - PROCESS | 7645 | RSS: Main: 174493696 09:51:17 INFO - PROCESS | 7645 | 09:54:20 INFO - PROCESS | 7645 | Cycle 1(1): loaded http://localhost:50355/tests/dromaeo/cssquery-yui.html (next: http://localhost:50355/tests/dromaeo/cssquery-dojo.html) 09:54:20 INFO - PROCESS | 7645 | RSS: Main: 270983168 09:54:20 INFO - PROCESS | 7645 | 09:54:20 INFO - PROCESS | 7645 | __start_tp_report 09:54:20 INFO - PROCESS | 7645 | _x_x_mozilla_page_load 09:54:20 INFO - PROCESS | 7645 | _x_x_mozilla_page_load_details 09:54:20 INFO - PROCESS | 7645 | |i|pagename|runs| 09:54:20 INFO - PROCESS | 7645 | |0;dojo.html;3210.8555115019826;3239.0930539448955;3267.199536113651;3276.803391796494;3297.0273769238074;5500.5874559408;5576.888462230753;5595.804146854855;5596.2165296858475;5597.916031259534;5595.664260144395;5652.802151924673;5655.7172141392975;5659.943400565993;5686.232358631594;5106.4382917879;5119.948800511985;5127.33344665193;5147.716875571842;5187.584993200554;4977.651564390494;5201.271821944912;5208.270842082112;5212.895742085156;5214.400343960455;4940.752962351868;5074.213496907967;5077.33994580704;5104.6937183768905;5125.026245013435;5124.718140502271;5153.304303918979;5162.045021670965;5214.817481388133;5220.843374698765;5050.318207042039;5142.974285128588;5194.142966410538;5207.6875387476875;5227.26818245446;5316.680999140025;5358.91961620576;5467.589930755209;5481.725913704299;5501.257330260403;5909.911351329733;5910.734016969247;5935.73289201987;6003.039513677834;6006.8498287542725;6035.517158627299;6041.486473649745;6043.728032238516;6052.061930400795;6057.575969682124;5515.806946756844;5555.611107222496;5581.413951535031;5611.046122159243;5626.43735626434;6122.479589234876;6166.81499555014;6170.197874276315;6171.9691401542705;6172.074188871705;5928.347881733006;5931.40685931412;5935.139404786281;5936.495397891142;5943.791967281124;5763.682997435098;5806.303243610793;5811.9709401453565;5855.38518455556;5855.443732845373;5891.852703682356;5894.410558944071;5894.941050589525;5921.73352199152;5930.318013428511;5851.561132915049;5898.056310990221;5903.557233207526;5904.9704751475965;5906.940930590725;4514.0069184779295;4536.251518499476;4542.205114105019;4558.407407037064;4569.908601828011;4082.1427500225286;4105.876823695294;4127.277726397869;4132.669386449077;4142.233686767958;5709.400512946164;5709.600327977002;5717.971410143005;5718.228039214736;5720.914186287126;5766.798162064308;5814.970925145347;5973;5986.102084687332;5997.100434934795;5739.942600573941;5802.245708057925;5808.128780682847;5822.592418530747;5827.941720582825;5213.843584692465;5236.711980841091;5238.580913526908;5245.842624721265;5246.606504512177;6033.396660333931;6116.42707509198;6374.521910856612;6389.648569328639;6393.680315984182;5944.762209511571;5953.28560572734;5999.250093738283;6090.177825993434;6113.510919126548;6317.210348706411;6357.14178585885;6361.01404282337;6364.109024736448;6367.586106903036;5779.797707080232;5940.46535811779;5950.910736339045;5951.940480595312;5954.910676339771;5843.883122337445;5976.252968378953;6017.759289628541;6022.307434645072;6104.023356262976;5904.822855314347;5944.6730429826775;5949.762009519571;5951.464368206882;5960.374160713062;5949.0481522956115;5949.137375080502;5949.405059494016;5950.732217050329;5962.046072628359;5587.469190426903;5705.4009329019855;5722.313322401172;5781.68200748946;5818.6217895836635;5960.225170727777;5969;5970.611910225822;5986.042233242661;5986.760529578768;5584.3857175711455;5625.887482250414;5762.971185144047;5774.538036957118;5777.04678728022 09:54:20 INFO - PROCESS | 7645 | |1;ext.html;573.40938833002;586.0447470622885;630.7886857902604;648.1315037849281;663.1677245057452;36493.81753091234;43312.78343608281;44182;44280.999999999956;48666.27000594994;46325.7683711581;46474.535254647446;46707;46760.2985955211;46820.297695534595;8529.061803201643;8699.39104262702;8701.738947831574;8705.869411958825;8707;8381.203785640355;8599.656013759442;10377.688669339894;10610.893891061069;10623.681289561287;10578.629747958823;10596.841047384296;10629.5216715248;10636.946815265912;10651.520681569347;10598.629047983284;10635.94682026585;10651.733706657318;10652.360858348524;10661;10500.474976251156;10584.629537966135;10585.470726463645;10586.99423554761;10594.31136976094;42810;43144.78427607873;43218;43245.35131973023;43295.134097318194;3586.7309951753464;3769.6230376962358;3798.1264309209037;3889.611038896116;3959.742616729903;32011.51982720261;32162;32437.18907027343;32501.724189630837;32900.835495822364;4251.893702657428;4364.694471387004;4449.755263460508;4532.8866778330475;4593.793279302439;362668;364695;365284;367790;367891;62956.68521657363;63355.366446335865;63897.36102638914;64089.67955160194;64108.35891641117;39626;40519.392209116886;40623.59376406198;43269.78365108154;43294.134117318106;19360;19565;19568.315108971117;19576.21695132179;19586.902065489583;13559;13627.454901803816;13631.454741810416;13704.177749335071;13907.721845563032;19705.83235844056;20675.793242067386;20739.58520829575;21546.13815447396;21582.892085539785;7375.934738273292;7478.850422991619;7490.850182996419;7604.277593628596;7732.226777322223;1018.8675472188717;1074.6346242277664;1132.2074547816562;1184.2598376014992;1205.1142410328403;34307;34437;34530.48204276938;34581.827090864885;34944.30111397809;1004.829179039565;1165.7610189911218;1203.5667159822458;1216.4890745886748;1242.3105176626877;15420.383184672712;16912.830871691127;16975;17117.82882171187;17204.31182752676;512104;516384;517538;519069;520146;62646;63637.72724545483;63665.36334636687;63726.68136659287;63736.36263637397;1331.9533816316575;1357.4434481862388;1369.4659082957455;1416.9433222671184;1440.5894320118716;30436;34779.65220347764;35442.291154177285;35523.46714799228;35724.46413303854;52636.47363526469;53031.734841325546;53165;53199.46800531945;53287.46712532825;374549;374896;380162;382640;383135;30057.84971075218;31418;33027.174320642174;33054.338913222084;33182.83408582942;50986.490135098175;51033.489665102876;51231.487685124164;51278.74360628173;52616.7369163167;42335;42419;42843.357349640406;43605;44284.33573496465;42202.10090545871;42533.149337012466;43285;43775;44544.77727611341;43264.78367608142;43593;44232.7788361069;44482;44664;35788;38758.806205969915;38860.22279554336;39114.02214944649;39209.607903920594;29295.937372389304;29346.559801602565;29402.705972940854;29532.78504112243;29590.112296631134 09:54:20 INFO - PROCESS | 7645 | |2;jquery.html;400.6074047433517;428.9656827453804;434.3246252078017;435.0121216860874;444.6909397968409;15722.842771572281;18978.335758248464;19126.808731912675;19830.20679172835;20018.899905500453;15213.771793423108;17702.734458983094;17717.7342339865;17892.64214715705;17914.372996945076;5662.320521537411;5910.0248459004215;5916.526677865761;5917;5924.111383292519;5731.598788084836;5781.768729250825;5996.370381109986;6007.6996150192535;6111.2666480022235;6033.185519954794;6066.575339726221;6080.452759251666;6090.299615544225;6093.42112499314;5745.597808153431;5964.13520039594;5998.4001599839885;6068.96965515174;6088.817335479943;5072.467390923974;5314.946850531523;5895.941040589582;5911;5911.201987731644;14141.222232777192;14233.78649320261;14392.856071439257;14411.351489183013;14651.853481465261;2439.097533912464;2452.558539462896;2459.803215742754;2568.576184929503;2611.2035829072124;10188.94905525475;10363.560384454608;10555.102816260627;10829.945850270777;10974.396408197546;1988.1351612048804;2027.6147531969027;2048.6926960955825;2049.9795002050087;2064.783197764228;81372.18627813706;81981.18018819854;83888.16111838925;84229;84311;9716.61133554664;10341.327813692085;11093.168012399103;11153.219274650859;11180.38507882058;14268.429262829579;14326.785098223534;14334.283285835667;14350.712985740436;14366.425342986164;8741.562921853882;9007.549622518847;9014.729558113268;9063.184313411693;9216.308776841764;8535.658573656985;8546.444481108709;8640.351973602004;8642.178992995656;8977.236934860479;4829;4836.226203807374;5067.442581316052;5139.203423469368;5158.252053452228;2042.9795702043086;2086.3845165675857;2152.257471172474;2152.924647637325;2265.943351416228;900.2572877376191;968.8595153702673;978.2858513285199;1040.5213601743283;1061.6496556136456;19564.3152489665;19889.10499027547;20181.49546261355;20348.18607255723;20369.287074952306;1011.7371476986659;1017.9847302290472;1040.287403128859;1066.5627092891878;1096.309325125166;10545.630902918514;10819.621313253998;10910.127189824794;11730.472128754227;12076.335801531;459746;465806;466727;468157;470445;96814;98745.50627246962;99719.50140249252;102191;102865.48567257116;963.633819148733;986;997.4539463831032;1009.9949500252452;1010.0000999010927;13189.604311870658;17110.657786844444;17234.482965511055;17893.910530447265;17993.820061799215;43749.562504374546;44086.33870491881;44379.33430998473;44841.551584483735;45634.54365456303;56943.71528142333;57423.71288143533;57714.7114264426;57831.71084144552;58627.70686146542;8669.836692129978;8696.21734043939;8701.782455438664;8792.824143517222;9170;9900.554475048497;12777.872221277668;13055;13313.66715832112;13429.059965802691;11290.887091128983;11546.364949927834;11560.94219528897;11612;11675.35785531804;11364.659060228207;11596.420178991186;11697.12271579652;11927;11935.343556104497;11586.710332241424;11757;11849.348285844017;11903;11941.46263418131;8655.0479447262;8720.912790872264;8782.956085219534;8886.511241881759;9283.535823208947;11091.223614346918;11610.825837612612;11768;11787.646370608896;11809.01008890214 09:54:20 INFO - PROCESS | 7645 | |3;mootools.html;1503.2183264702358;1534.46293797171;1674.5227610131114;1784.8125946775597;1838.7517685112514;8282.710105146321;8896.777580560489;8906.91093089069;8907.109289071097;8908.688195913144;7785.182555831627;7948.801279968004;8495.532745698983;8639.870401943976;8810.559472026389;2463.3472129885595;2491.3273416177713;2492.8906636546744;2497.6628155199087;2502.2368177705794;2464.605663093905;2491.1405565080017;2491.4767898741197;2495.0144692846316;2503.5368456835454;2453.411181316489;2456.1526273435725;2474.134053081425;2481.416867036254;2483.7019557653016;2339.6139636959883;2345;2408.8434251773665;2430.1251549442186;2450.387403149213;2392.7248366437734;2397.7482364351667;2416.8187385946126;2419.1895714935517;2430.86630235337;8900.821983560292;8952.239059679934;9002.774930626721;9037.819243615091;9053.909460905372;5249.973750131225;5381.730913454351;5384.542313903323;5391.892162156735;5393.541548968303;3474.8610055597746;3499.7725147865563;3549.982250088733;3640.1081734974837;3699.056740531147;1252.843394575678;1260.848698156218;1275.515304184404;1308.672831792052;1313.1070871807167;11917.165798394115;11924.523019079226;11962.282263064157;11988.52045918171;12008.039356851343;5070.416902056237;5108.208227724708;5190.091733946544;5200.791968321225;5221.973890130601;10495.580176792995;10510.63212787564;10513.684589462327;10514.316569422963;10516.053555180071;5589.972050139723;5685.090385538294;5685.204071430003;5686.943130568724;5689.516391106802;4427.667924905645;4513.638908887281;4944.554990050912;4987.077390682735;5113.53978141969;3989.3218152914073;4063.9796801015805;4168.8332466701595;4185.790710464526;4187.623113919762;1401.3343661760582;1406.2617126008722;1409.5700811252666;1412.682146517046;1412.7527682655493;833.7415401225518;882.5895958379384;964.4888209249109;997.5012493753123;1022.4023471493662;5786.1610066540115;5818.33089194748;5818.505427038749;5871.588988770831;5956.642601443928;942.3968660057569;958.4297343080856;981.5681100315838;1007.3351587951917;1008.5713571731984;3845.576986531479;4002.799860006988;4052.3516237402464;4055.0673345130785;4150.626443620089;10710.518026688818;11125.443727813577;11218.775624487465;11231.887681123084;11252.718682032852;11171.05046071093;11200;11204.103671706243;11204.991550760471;11220.943895280472;8177.509349438934;8392.664293428195;8476.4490308131;8477.533735644476;8599.753035809772;5776.133579962871;5842.5325973921135;5920.496757775637;5945.702714864153;6010.7295171716505;9378.906210938076;9585.472798995843;9671.129598336183;9768.902310976799;9786.266030047633;9632.662856799978;9966.750831229278;10254.333468324536;10410.531526081193;10421.374717516974;7863.803404914923;8032.236937490929;8095.2714255717265;8117.431779775595;8138.104808471182;8511.489310641382;8524.872126918226;8590.742277731679;8593.44142630727;8697.478151310941;7736.806579835549;7780.92219077802;7788.805279868048;7891.092524359772;7931.087924888711;7672.117706463828;7721.729739459092;7747.883781743166;7872.84254314922;7932.563708996061;6817.5568588041615;6878.65606719672;6917.308269173042;6998.475114366544;7031.472639552157;5331.280277162534;5365.409804921377;5402.372475706721;5438.483344082306;5541.445855414427;5130.12787826078;5130.820421285237;5263.763130659206;5302.575793936398;5306.708131052675 09:54:20 INFO - PROCESS | 7645 | |4;prototype.html;353.2387704496809;355.58041511017007;355.8594355229683;358.66106529329784;362.2139956294838;5365.302510673612;5374.704391258489;5383.650062745924;5384.757685904129;5388.003219404403;5000.499950004998;5275.393329767078;5303;5305.840824775253;5323.307969963909;4143.233501802161;4155.83636581756;4210.9578904210875;4226.852060177895;4233.248388547438;4183.874483765477;4201.243776120313;4228.133232687307;4234.9364759528635;4238.894027649302;3996.1208534122593;4135.6898232632675;4180.895477613069;4187.790610469479;4202.6637868970565;4097.856575019875;4161.167766446692;4184.686148538842;4200.852970146061;4208.6422654074595;3738.401855703102;3744.925101497982;3761.322961866863;3767.792771397572;3768.9623103768886;4885.193942999401;5177.068127737006;5188.7146206958605;5192.0394726975255;5209.765560549784;4689.484156742756;4767.261074533453;4783.641226907996;4791;4799.3760811094335;5538.698405874617;5546.667199967975;5548.250986116863;5552.694601796899;5558.666480011212;3849.7690138591774;4092.5907409259135;4098.991494167866;4104.527979282391;4107.876763697094;5657.943420565783;5713.457221563945;5714.771324165283;5754.827355179351;5757.740901659436;4194.979025104916;4196.3705444183615;4201.747895126302;4204.831806727697;4208.6633069354375;4159.334506479009;4253.128108737701;4254.7659878706345;4261.850835220753;4265.232258193555;4796.544328288807;4797.905351048624;4799.18413869643;4809.278608208727;4829.975850120727;5613.831585052455;5618.101103823368;5621;5634.3802181759975;5636.408177141423;1382.7994940733738;1416.5113036002563;1422.7865820126856;1426.7444648709277;1429.6926160875253;960.8318544254728;984.945827979468;987.2348929579633;994.3884511025667;997.8154041502345;5226.477352264743;5238.528532432099;5245.186996015624;5246.455469097485;5255.108161062015;5493.5004572920025;5504.504594586507;5509.862253443696;5510.972445137749;5512.255845460892;5029.7736601853;5220.347456567929;5228.477152284817;5233.441259943275;5243.52808247252;2903.2451562593455;2911.5341545352644;2913.562965555184;2924.1373794730607;2925.824450532975;5562.610617256834;5666.886662266814;5689.601727879091;5713.885792270467;5724.914126288109;5508.669479831223;5513.42109078549;5538.778448862;5542.667439953616;5550.666959982414;5688.943110568841;5712.228849105318;5718.513926316226;5753.108268218432;5755.5683323750045;5300.814471493479;5483.259759932358;5493.560515158858;5499.477549632778;5523.723813809213;5360.41035486104;5374.736936819766;5387.865303367447;5407.188921661782;5452.400235974119;5468.5351745100525;5733.362653179584;5760.798372056958;5762.971185144215;5766.5675074368755;3971.2653159165648;4020.2964481216254;4023.1752490739914;4046.2312160689376;4050.8784736457956;5411.431799660979;5416.945830541802;5432.8047829477455;5548.445155484419;5594.468525490072;5556.88862227548;5592.636478628876;5595.272614560081;5602.635828671123;5613.4282400927805;5563.972180139235;5565.499105080562;5581.413951535031;5601.5238704710555;5606.103023516218;5341.439148889311;5553.944460555343;5560.582956278215;5579.804706835242;5581.413951535031;5656.971715141398;5660.122680984454;5666.631668941505;5671.858203544779;5692.402297758676;5277.445868183785;5452.454754524516;5525.640833345819;5535.916961245504;5536.453231513549 09:54:20 INFO - PROCESS | 7645 | |5;yui.html;778.595130532123;801.3228821645707;819.405930700242;827.850986822372;891.2068259249269;14126.929365353184;14147.221902795342;14238.572842814707;14247.501337453192;15580;14765.926170369159;14889.627759306022;14940.47708330206;14947.402103915858;14949.551513454613;711.704642573334;726.041625054925;817.8773184022415;838.7316058861167;842.1830824100613;848.7835601921502;854.2098558833054;854.5257382152903;854.7606670132373;856.400519636254;899.7390756680587;900.729781065681;900.8153328567661;900.9774755631129;901.179926267097;757.1633345153608;770.8650986077414;872.5462759365126;879.090141703334;891.9697749099801;661.3849120318102;661.4840424468874;662.3575132121834;662.7017841971133;663.270402557188;14352.569422917224;14572.489962851356;14574.198419086946;14630.487932922402;14641.926790366086;13821.861781382258;13868.861311386858;14417;14449.205293708841;14482.493112741004;12023.759524809455;12421.75156496865;12820.23078615286;12899.484020639164;13147.737045259042;3874.244522318149;3931.2727145477893;3974.8012599370204;4018.3168861293652;4028.7179897407195;68035.65982170108;68186;69847.65076174587;69932.6503367485;69967.65016174887;11326.773464530828;11590.536378544932;11720.88279117215;11842.467088981017;11885.524579016743;20743.481412964495;22506.774932250795;22610.09559617597;22649.094036238694;22682.092716291492;8838.734837954871;8942.821143577223;8980;9036.457812531138;9150.947641021234;4759.833405830779;4832.395950506187;4838.975805121022;4880.072786170616;4916.237983112624;3776.4712940188397;3885.0093226227696;3887.8056097195026;3889.3193691103943;3907.882763517099;599.1252770954368;674.7806962737031;697.1529591546296;730.8867125595541;732.1397358104206;684.9691214721911;717.9712811487482;760.2245709376405;772.5519198864645;805.3838813307824;9203.907960920438;9560.187384072293;9956.701298961043;9980.2514811388;10038.949805250926;703.4161645833946;728.1953441447231;755.056179775281;756.3986630628664;763.4884627299723;4904.5340692634145;5038.143515602356;5050.217216331474;5055.696658200446;5064.215046667772;85538;94385.5842162368;94971;96974.51512742392;98369.01630983742;82340;86022.56988715017;87925;88492.5575372119;89037;73311;76149;76307.61846190733;76767.61616191884;78336.21663783403;9191.816163676554;9333.113354231336;9505.66730164441;9719.514024298614;9757.31698781107;50285.49714502954;51533;51692.96614067773;51827.48172518227;52047.47952520426;53935;68328.65835670789;70879.64560177166;71692.64153679198;72228.27771722426;11499.712507187387;11523.596674116367;11540.769184616092;11715.1799374043;11917.880821191677;13635.454581816617;13645.044846860625;13731.107478013897;13880.236586987812;14106.717865642835;12936.547220847226;12960.157589756636;13131.671708207372;13244.006699497384;13786;12759.872401275868;12793.872061279268;12907.612771616867;12994.870051299744;13024.218546887218;11810.291382516732;12445;12691.008044018057;12807.487700491876;12925.612231633066;10100.949495252476;10621;10957.671269861918;10994.890051099386;11024.5038973248;10663.573457061631;11112.166587506132;11226.831597525881;11368.829467557827;11466.31202127875 09:54:20 INFO - PROCESS | 7645 | __end_tp_report 09:54:20 INFO - PROCESS | 7645 | __start_cc_report 09:54:20 INFO - PROCESS | 7645 | _x_x_mozilla_cycle_collect,1059 09:54:20 INFO - PROCESS | 7645 | __end_cc_report 09:54:20 INFO - PROCESS | 7645 | __startTimestamp1483552460285__endTimestamp 09:54:20 INFO - PROCESS | 7645 | 09:54:20 INFO - PROCESS | 7645 | ------- Summary: start ------- 09:54:20 INFO - PROCESS | 7645 | Number of tests: 6 09:54:20 INFO - PROCESS | 7645 | 09:54:20 INFO - PROCESS | 7645 | [#0] dojo.html Cycles:165 Average:5577.73 Median:5781.68 stddev:631.38 (10.9%) stddev-sans-first:605.40 09:54:20 INFO - PROCESS | 7645 | Values: 3210.9 3239.1 3267.2 3276.8 3297.0 5500.6 5576.9 5595.8 5596.2 5597.9 5595.7 5652.8 5655.7 5659.9 5686.2 5106.4 5119.9 5127.3 5147.7 5187.6 4977.7 5201.3 5208.3 5212.9 5214.4 4940.8 5074.2 5077.3 5104.7 5125.0 5124.7 5153.3 5162.0 5214.8 5220.8 5050.3 5143.0 5194.1 5207.7 5227.3 5316.7 5358.9 5467.6 5481.7 5501.3 5909.9 5910.7 5935.7 6003.0 6006.8 6035.5 6041.5 6043.7 6052.1 6057.6 5515.8 5555.6 5581.4 5611.0 5626.4 6122.5 6166.8 6170.2 6172.0 6172.1 5928.3 5931.4 5935.1 5936.5 5943.8 5763.7 5806.3 5812.0 5855.4 5855.4 5891.9 5894.4 5894.9 5921.7 5930.3 5851.6 5898.1 5903.6 5905.0 5906.9 4514.0 4536.3 4542.2 4558.4 4569.9 4082.1 4105.9 4127.3 4132.7 4142.2 5709.4 5709.6 5718.0 5718.2 5720.9 5766.8 5815.0 5973.0 5986.1 5997.1 5739.9 5802.2 5808.1 5822.6 5827.9 5213.8 5236.7 5238.6 5245.8 5246.6 6033.4 6116.4 6374.5 6389.6 6393.7 5944.8 5953.3 5999.3 6090.2 6113.5 6317.2 6357.1 6361.0 6364.1 6367.6 5779.8 5940.5 5950.9 5951.9 5954.9 5843.9 5976.3 6017.8 6022.3 6104.0 5904.8 5944.7 5949.8 5951.5 5960.4 5949.0 5949.1 5949.4 5950.7 5962.0 5587.5 5705.4 5722.3 5781.7 5818.6 5960.2 5969.0 5970.6 5986.0 5986.8 5584.4 5625.9 5763.0 5774.5 5777.0 09:54:20 INFO - PROCESS | 7645 | 09:54:20 INFO - PROCESS | 7645 | [#1] ext.html Cycles:180 Average:59467.35 Median:381401.00 stddev:112492.08 (29.5%) stddev-sans-first:112720.75 09:54:20 INFO - PROCESS | 7645 | Values: 573.4 586.0 630.8 648.1 663.2 36493.8 43312.8 44182.0 44281.0 48666.3 46325.8 46474.5 46707.0 46760.3 46820.3 8529.1 8699.4 8701.7 8705.9 8707.0 8381.2 8599.7 10377.7 10610.9 10623.7 10578.6 10596.8 10629.5 10636.9 10651.5 10598.6 10635.9 10651.7 10652.4 10661.0 10500.5 10584.6 10585.5 10587.0 10594.3 42810.0 43144.8 43218.0 43245.4 43295.1 3586.7 3769.6 3798.1 3889.6 3959.7 32011.5 32162.0 32437.2 32501.7 32900.8 4251.9 4364.7 4449.8 4532.9 4593.8 362668.0 364695.0 365284.0 367790.0 367891.0 62956.7 63355.4 63897.4 64089.7 64108.4 39626.0 40519.4 40623.6 43269.8 43294.1 19360.0 19565.0 19568.3 19576.2 19586.9 13559.0 13627.5 13631.5 13704.2 13907.7 19705.8 20675.8 20739.6 21546.1 21582.9 7375.9 7478.9 7490.9 7604.3 7732.2 1018.9 1074.6 1132.2 1184.3 1205.1 34307.0 34437.0 34530.5 34581.8 34944.3 1004.8 1165.8 1203.6 1216.5 1242.3 15420.4 16912.8 16975.0 17117.8 17204.3 512104.0 516384.0 517538.0 519069.0 520146.0 62646.0 63637.7 63665.4 63726.7 63736.4 1332.0 1357.4 1369.5 1416.9 1440.6 30436.0 34779.7 35442.3 35523.5 35724.5 52636.5 53031.7 53165.0 53199.5 53287.5 374549.0 374896.0 380162.0 382640.0 383135.0 30057.8 31418.0 33027.2 33054.3 33182.8 50986.5 51033.5 51231.5 51278.7 52616.7 42335.0 42419.0 42843.4 43605.0 44284.3 42202.1 42533.1 43285.0 43775.0 44544.8 43264.8 43593.0 44232.8 44482.0 44664.0 35788.0 38758.8 38860.2 39114.0 39209.6 29295.9 29346.6 29402.7 29532.8 29590.1 09:54:20 INFO - PROCESS | 7645 | 09:54:20 INFO - PROCESS | 7645 | [#2] jquery.html Cycles:180 Average:28464.67 Median:2456.18 stddev:77269.89 (3145.9%) stddev-sans-first:77457.92 09:54:20 INFO - PROCESS | 7645 | Values: 400.6 429.0 434.3 435.0 444.7 15722.8 18978.3 19126.8 19830.2 20018.9 15213.8 17702.7 17717.7 17892.6 17914.4 5662.3 5910.0 5916.5 5917.0 5924.1 5731.6 5781.8 5996.4 6007.7 6111.3 6033.2 6066.6 6080.5 6090.3 6093.4 5745.6 5964.1 5998.4 6069.0 6088.8 5072.5 5314.9 5895.9 5911.0 5911.2 14141.2 14233.8 14392.9 14411.4 14651.9 2439.1 2452.6 2459.8 2568.6 2611.2 10188.9 10363.6 10555.1 10829.9 10974.4 1988.1 2027.6 2048.7 2050.0 2064.8 81372.2 81981.2 83888.2 84229.0 84311.0 9716.6 10341.3 11093.2 11153.2 11180.4 14268.4 14326.8 14334.3 14350.7 14366.4 8741.6 9007.5 9014.7 9063.2 9216.3 8535.7 8546.4 8640.4 8642.2 8977.2 4829.0 4836.2 5067.4 5139.2 5158.3 2043.0 2086.4 2152.3 2152.9 2265.9 900.3 968.9 978.3 1040.5 1061.6 19564.3 19889.1 20181.5 20348.2 20369.3 1011.7 1018.0 1040.3 1066.6 1096.3 10545.6 10819.6 10910.1 11730.5 12076.3 459746.0 465806.0 466727.0 468157.0 470445.0 96814.0 98745.5 99719.5 102191.0 102865.5 963.6 986.0 997.5 1010.0 1010.0 13189.6 17110.7 17234.5 17893.9 17993.8 43749.6 44086.3 44379.3 44841.6 45634.5 56943.7 57423.7 57714.7 57831.7 58627.7 8669.8 8696.2 8701.8 8792.8 9170.0 9900.6 12777.9 13055.0 13313.7 13429.1 11290.9 11546.4 11560.9 11612.0 11675.4 11364.7 11596.4 11697.1 11927.0 11935.3 11586.7 11757.0 11849.3 11903.0 11941.5 8655.0 8720.9 8783.0 8886.5 9283.5 11091.2 11610.8 11768.0 11787.6 11809.0 09:54:20 INFO - PROCESS | 7645 | 09:54:20 INFO - PROCESS | 7645 | [#3] mootools.html Cycles:180 Average:5885.61 Median:5195.44 stddev:3252.16 (62.6%) stddev-sans-first:3244.61 09:54:20 INFO - PROCESS | 7645 | Values: 1503.2 1534.5 1674.5 1784.8 1838.8 8282.7 8896.8 8906.9 8907.1 8908.7 7785.2 7948.8 8495.5 8639.9 8810.6 2463.3 2491.3 2492.9 2497.7 2502.2 2464.6 2491.1 2491.5 2495.0 2503.5 2453.4 2456.2 2474.1 2481.4 2483.7 2339.6 2345.0 2408.8 2430.1 2450.4 2392.7 2397.7 2416.8 2419.2 2430.9 8900.8 8952.2 9002.8 9037.8 9053.9 5250.0 5381.7 5384.5 5391.9 5393.5 3474.9 3499.8 3550.0 3640.1 3699.1 1252.8 1260.8 1275.5 1308.7 1313.1 11917.2 11924.5 11962.3 11988.5 12008.0 5070.4 5108.2 5190.1 5200.8 5222.0 10495.6 10510.6 10513.7 10514.3 10516.1 5590.0 5685.1 5685.2 5686.9 5689.5 4427.7 4513.6 4944.6 4987.1 5113.5 3989.3 4064.0 4168.8 4185.8 4187.6 1401.3 1406.3 1409.6 1412.7 1412.8 833.7 882.6 964.5 997.5 1022.4 5786.2 5818.3 5818.5 5871.6 5956.6 942.4 958.4 981.6 1007.3 1008.6 3845.6 4002.8 4052.4 4055.1 4150.6 10710.5 11125.4 11218.8 11231.9 11252.7 11171.1 11200.0 11204.1 11205.0 11220.9 8177.5 8392.7 8476.4 8477.5 8599.8 5776.1 5842.5 5920.5 5945.7 6010.7 9378.9 9585.5 9671.1 9768.9 9786.3 9632.7 9966.8 10254.3 10410.5 10421.4 7863.8 8032.2 8095.3 8117.4 8138.1 8511.5 8524.9 8590.7 8593.4 8697.5 7736.8 7780.9 7788.8 7891.1 7931.1 7672.1 7721.7 7747.9 7872.8 7932.6 6817.6 6878.7 6917.3 6998.5 7031.5 5331.3 5365.4 5402.4 5438.5 5541.4 5130.1 5130.8 5263.8 5302.6 5306.7 09:54:20 INFO - PROCESS | 7645 | 09:54:20 INFO - PROCESS | 7645 | [#4] prototype.html Cycles:180 Average:4656.16 Median:5314.57 stddev:1335.84 (25.1%) stddev-sans-first:1299.96 09:54:20 INFO - PROCESS | 7645 | Values: 353.2 355.6 355.9 358.7 362.2 5365.3 5374.7 5383.7 5384.8 5388.0 5000.5 5275.4 5303.0 5305.8 5323.3 4143.2 4155.8 4211.0 4226.9 4233.2 4183.9 4201.2 4228.1 4234.9 4238.9 3996.1 4135.7 4180.9 4187.8 4202.7 4097.9 4161.2 4184.7 4200.9 4208.6 3738.4 3744.9 3761.3 3767.8 3769.0 4885.2 5177.1 5188.7 5192.0 5209.8 4689.5 4767.3 4783.6 4791.0 4799.4 5538.7 5546.7 5548.3 5552.7 5558.7 3849.8 4092.6 4099.0 4104.5 4107.9 5657.9 5713.5 5714.8 5754.8 5757.7 4195.0 4196.4 4201.7 4204.8 4208.7 4159.3 4253.1 4254.8 4261.9 4265.2 4796.5 4797.9 4799.2 4809.3 4830.0 5613.8 5618.1 5621.0 5634.4 5636.4 1382.8 1416.5 1422.8 1426.7 1429.7 960.8 984.9 987.2 994.4 997.8 5226.5 5238.5 5245.2 5246.5 5255.1 5493.5 5504.5 5509.9 5511.0 5512.3 5029.8 5220.3 5228.5 5233.4 5243.5 2903.2 2911.5 2913.6 2924.1 2925.8 5562.6 5666.9 5689.6 5713.9 5724.9 5508.7 5513.4 5538.8 5542.7 5550.7 5688.9 5712.2 5718.5 5753.1 5755.6 5300.8 5483.3 5493.6 5499.5 5523.7 5360.4 5374.7 5387.9 5407.2 5452.4 5468.5 5733.4 5760.8 5763.0 5766.6 3971.3 4020.3 4023.2 4046.2 4050.9 5411.4 5416.9 5432.8 5548.4 5594.5 5556.9 5592.6 5595.3 5602.6 5613.4 5564.0 5565.5 5581.4 5601.5 5606.1 5341.4 5553.9 5560.6 5579.8 5581.4 5657.0 5660.1 5666.6 5671.9 5692.4 5277.4 5452.5 5525.6 5535.9 5536.5 09:54:20 INFO - PROCESS | 7645 | 09:54:20 INFO - PROCESS | 7645 | [#5] yui.html Cycles:180 Average:19131.75 Median:5059.96 stddev:25951.63 (512.9%) stddev-sans-first:25987.84 09:54:20 INFO - PROCESS | 7645 | Values: 778.6 801.3 819.4 827.9 891.2 14126.9 14147.2 14238.6 14247.5 15580.0 14765.9 14889.6 14940.5 14947.4 14949.6 711.7 726.0 817.9 838.7 842.2 848.8 854.2 854.5 854.8 856.4 899.7 900.7 900.8 901.0 901.2 757.2 770.9 872.5 879.1 892.0 661.4 661.5 662.4 662.7 663.3 14352.6 14572.5 14574.2 14630.5 14641.9 13821.9 13868.9 14417.0 14449.2 14482.5 12023.8 12421.8 12820.2 12899.5 13147.7 3874.2 3931.3 3974.8 4018.3 4028.7 68035.7 68186.0 69847.7 69932.7 69967.7 11326.8 11590.5 11720.9 11842.5 11885.5 20743.5 22506.8 22610.1 22649.1 22682.1 8838.7 8942.8 8980.0 9036.5 9150.9 4759.8 4832.4 4839.0 4880.1 4916.2 3776.5 3885.0 3887.8 3889.3 3907.9 599.1 674.8 697.2 730.9 732.1 685.0 718.0 760.2 772.6 805.4 9203.9 9560.2 9956.7 9980.3 10038.9 703.4 728.2 755.1 756.4 763.5 4904.5 5038.1 5050.2 5055.7 5064.2 85538.0 94385.6 94971.0 96974.5 98369.0 82340.0 86022.6 87925.0 88492.6 89037.0 73311.0 76149.0 76307.6 76767.6 78336.2 9191.8 9333.1 9505.7 9719.5 9757.3 50285.5 51533.0 51693.0 51827.5 52047.5 53935.0 68328.7 70879.6 71692.6 72228.3 11499.7 11523.6 11540.8 11715.2 11917.9 13635.5 13645.0 13731.1 13880.2 14106.7 12936.5 12960.2 13131.7 13244.0 13786.0 12759.9 12793.9 12907.6 12994.9 13024.2 11810.3 12445.0 12691.0 12807.5 12925.6 10100.9 10621.0 10957.7 10994.9 11024.5 10663.6 11112.2 11226.8 11368.8 11466.3 09:54:20 INFO - PROCESS | 7645 | -------- Summary: end -------- 09:54:20 INFO - PROCESS | 7645 | 09:54:21 INFO - TEST-INFO | 7645: exit 0 09:54:21 INFO - TEST-OK | dromaeo_css | took 1115429ms 09:54:21 INFO - TEST-START | kraken 09:54:21 INFO - operating with platform_type : linux_ 09:54:21 INFO - Initialising browser for kraken test... 09:54:21 INFO - TEST-INFO | started process 7960 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdpU5p2/profile http://localhost:50355/getInfo.html) 09:54:24 INFO - PROCESS | 7960 | __metrics Screen width/height:1600/1200 09:54:24 INFO - PROCESS | 7960 | colorDepth:24 09:54:24 INFO - PROCESS | 7960 | Browser inner width/height: 1024/697 09:54:24 INFO - PROCESS | 7960 | __metrics 09:54:25 INFO - PROCESS | 7960 | 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:54:25 INFO - PROCESS | 7960 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:54:25 INFO - PROCESS | 7960 | console.error: PushService: 09:54:25 INFO - PROCESS | 7960 | stateChangeProcessEnqueue: Error transitioning state 09:54:25 INFO - PROCESS | 7960 | UnknownError 09:54:26 INFO - TEST-INFO | 7960: exit 0 09:54:26 INFO - Browser initialized. 09:54:26 INFO - Running cycle 1/1 for kraken test... 09:54:26 INFO - TEST-INFO | started process 8053 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdpU5p2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:54:38 INFO - PROCESS | 8053 | RSS: Main: 148279296 09:54:38 INFO - PROCESS | 8053 | 09:54:40 INFO - PROCESS | 8053 | Cycle 1(1): loaded http://localhost:50355/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50355/tests/kraken/driver.html?testName=audio-beat-detection) 09:54:41 INFO - PROCESS | 8053 | RSS: Main: 189304832 09:54:41 INFO - PROCESS | 8053 | 09:54:44 INFO - PROCESS | 8053 | Cycle 1(1): loaded http://localhost:50355/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50355/tests/kraken/driver.html?testName=audio-dft) 09:54:44 INFO - PROCESS | 8053 | RSS: Main: 178454528 09:54:44 INFO - PROCESS | 8053 | 09:54:47 INFO - PROCESS | 8053 | Cycle 1(1): loaded http://localhost:50355/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50355/tests/kraken/driver.html?testName=audio-fft) 09:54:48 INFO - PROCESS | 8053 | RSS: Main: 269705216 09:54:48 INFO - PROCESS | 8053 | 09:54:50 INFO - PROCESS | 8053 | Cycle 1(1): loaded http://localhost:50355/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50355/tests/kraken/driver.html?testName=audio-oscillator) 09:54:50 INFO - PROCESS | 8053 | RSS: Main: 176021504 09:54:50 INFO - PROCESS | 8053 | 09:54:52 INFO - PROCESS | 8053 | Cycle 1(1): loaded http://localhost:50355/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50355/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:54:53 INFO - PROCESS | 8053 | RSS: Main: 177942528 09:54:53 INFO - PROCESS | 8053 | 09:54:56 INFO - PROCESS | 8053 | Cycle 1(1): loaded http://localhost:50355/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50355/tests/kraken/driver.html?testName=imaging-darkroom) 09:54:56 INFO - PROCESS | 8053 | RSS: Main: 217649152 09:54:56 INFO - PROCESS | 8053 | 09:55:00 INFO - PROCESS | 8053 | Cycle 1(1): loaded http://localhost:50355/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50355/tests/kraken/driver.html?testName=imaging-desaturate) 09:55:00 INFO - PROCESS | 8053 | RSS: Main: 216424448 09:55:00 INFO - PROCESS | 8053 | 09:55:03 INFO - PROCESS | 8053 | Cycle 1(1): loaded http://localhost:50355/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50355/tests/kraken/driver.html?testName=json-parse-financial) 09:55:04 INFO - PROCESS | 8053 | RSS: Main: 215072768 09:55:04 INFO - PROCESS | 8053 | 09:55:05 INFO - PROCESS | 8053 | Cycle 1(1): loaded http://localhost:50355/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50355/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:55:06 INFO - PROCESS | 8053 | RSS: Main: 177545216 09:55:06 INFO - PROCESS | 8053 | 09:55:07 INFO - PROCESS | 8053 | Cycle 1(1): loaded http://localhost:50355/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50355/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:55:08 INFO - PROCESS | 8053 | RSS: Main: 183914496 09:55:08 INFO - PROCESS | 8053 | 09:55:10 INFO - PROCESS | 8053 | Cycle 1(1): loaded http://localhost:50355/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50355/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:55:11 INFO - PROCESS | 8053 | RSS: Main: 190443520 09:55:11 INFO - PROCESS | 8053 | 09:55:13 INFO - PROCESS | 8053 | Cycle 1(1): loaded http://localhost:50355/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50355/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:55:14 INFO - PROCESS | 8053 | RSS: Main: 198238208 09:55:14 INFO - PROCESS | 8053 | 09:55:16 INFO - PROCESS | 8053 | Cycle 1(1): loaded http://localhost:50355/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50355/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:55:17 INFO - PROCESS | 8053 | RSS: Main: 195420160 09:55:17 INFO - PROCESS | 8053 | 09:55:19 INFO - PROCESS | 8053 | Cycle 1(1): loaded http://localhost:50355/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50355/tests/kraken/driver.html?testName=ai-astar) 09:55:19 INFO - PROCESS | 8053 | RSS: Main: 246812672 09:55:19 INFO - PROCESS | 8053 | 09:55:19 INFO - PROCESS | 8053 | __start_tp_report 09:55:19 INFO - PROCESS | 8053 | _x_x_mozilla_page_load 09:55:19 INFO - PROCESS | 8053 | _x_x_mozilla_page_load_details 09:55:19 INFO - PROCESS | 8053 | |i|pagename|runs| 09:55:19 INFO - PROCESS | 8053 | |0;ai-astar;101;110;111;110;111;111;110;110;111;111 09:55:19 INFO - PROCESS | 8053 | |1;audio-beat-detection;141;117;117;156;118;119;125;120;150;117 09:55:19 INFO - PROCESS | 8053 | |2;audio-dft;161;162;159;161;160;160;159;159;159;161 09:55:19 INFO - PROCESS | 8053 | |3;audio-fft;73;75;74;108;74;76;75;74;98;74 09:55:19 INFO - PROCESS | 8053 | |4;audio-oscillator;81;81;109;82;82;82;106;81;81;111 09:55:19 INFO - PROCESS | 8053 | |5;imaging-gaussian-blur;116;117;117;117;116;116;117;117;118;117 09:55:19 INFO - PROCESS | 8053 | |6;imaging-darkroom;146;146;145;146;146;146;146;146;147;145 09:55:19 INFO - PROCESS | 8053 | |7;imaging-desaturate;85;85;84;84;84;85;85;84;85;84 09:55:19 INFO - PROCESS | 8053 | |8;json-parse-financial;51;52;50;51;50;53;51;51;50;51 09:55:19 INFO - PROCESS | 8053 | |9;json-stringify-tinderbox;61;60;60;60;61;61;59;64;60;60 09:55:19 INFO - PROCESS | 8053 | |10;stanford-crypto-aes;74;75;82;74;81;74;75;74;74;74 09:55:19 INFO - PROCESS | 8053 | |11;stanford-crypto-ccm;124;121;122;123;128;127;130;120;175;125 09:55:19 INFO - PROCESS | 8053 | |12;stanford-crypto-pbkdf2;155;157;154;154;157;153;157;156;159;159 09:55:19 INFO - PROCESS | 8053 | |13;stanford-crypto-sha256-iterative;63;63;63;63;62;62;62;63;61;64 09:55:19 INFO - PROCESS | 8053 | __end_tp_report 09:55:19 INFO - PROCESS | 8053 | __start_cc_report 09:55:19 INFO - PROCESS | 8053 | _x_x_mozilla_cycle_collect,1609 09:55:19 INFO - PROCESS | 8053 | __end_cc_report 09:55:19 INFO - PROCESS | 8053 | __startTimestamp1483552519440__endTimestamp 09:55:19 INFO - PROCESS | 8053 | 09:55:19 INFO - PROCESS | 8053 | ------- Summary: start ------- 09:55:19 INFO - PROCESS | 8053 | Number of tests: 14 09:55:19 INFO - PROCESS | 8053 | 09:55:19 INFO - PROCESS | 8053 | [#0] ai-astar Cycles:10 Average:109.60 Median:111.00 stddev:3.06 (2.8%) stddev-sans-first:0.53 09:55:19 INFO - PROCESS | 8053 | Values: 101.0 110.0 111.0 110.0 111.0 111.0 110.0 110.0 111.0 111.0 09:55:19 INFO - PROCESS | 8053 | 09:55:19 INFO - PROCESS | 8053 | [#1] audio-beat-detection Cycles:10 Average:128.00 Median:122.50 stddev:15.11 (12.3%) stddev-sans-first:15.27 09:55:19 INFO - PROCESS | 8053 | Values: 141.0 117.0 117.0 156.0 118.0 119.0 125.0 120.0 150.0 117.0 09:55:19 INFO - PROCESS | 8053 | 09:55:19 INFO - PROCESS | 8053 | [#2] audio-dft Cycles:10 Average:160.10 Median:160.50 stddev:1.10 (0.7%) stddev-sans-first:1.12 09:55:19 INFO - PROCESS | 8053 | Values: 161.0 162.0 159.0 161.0 160.0 160.0 159.0 159.0 159.0 161.0 09:55:19 INFO - PROCESS | 8053 | 09:55:19 INFO - PROCESS | 8053 | [#3] audio-fft Cycles:10 Average:80.10 Median:74.50 stddev:12.32 (16.5%) stddev-sans-first:12.80 09:55:19 INFO - PROCESS | 8053 | Values: 73.0 75.0 74.0 108.0 74.0 76.0 75.0 74.0 98.0 74.0 09:55:19 INFO - PROCESS | 8053 | 09:55:19 INFO - PROCESS | 8053 | [#4] audio-oscillator Cycles:10 Average:89.60 Median:81.00 stddev:13.22 (16.3%) stddev-sans-first:13.65 09:55:19 INFO - PROCESS | 8053 | Values: 81.0 81.0 109.0 82.0 82.0 82.0 106.0 81.0 81.0 111.0 09:55:19 INFO - PROCESS | 8053 | 09:55:19 INFO - PROCESS | 8053 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.63 (0.5%) stddev-sans-first:0.60 09:55:19 INFO - PROCESS | 8053 | Values: 116.0 117.0 117.0 117.0 116.0 116.0 117.0 117.0 118.0 117.0 09:55:19 INFO - PROCESS | 8053 | 09:55:19 INFO - PROCESS | 8053 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 09:55:19 INFO - PROCESS | 8053 | Values: 146.0 146.0 145.0 146.0 146.0 146.0 146.0 146.0 147.0 145.0 09:55:19 INFO - PROCESS | 8053 | 09:55:19 INFO - PROCESS | 8053 | [#7] imaging-desaturate Cycles:10 Average:84.50 Median:85.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 09:55:19 INFO - PROCESS | 8053 | Values: 85.0 85.0 84.0 84.0 84.0 85.0 85.0 84.0 85.0 84.0 09:55:19 INFO - PROCESS | 8053 | 09:55:19 INFO - PROCESS | 8053 | [#8] json-parse-financial Cycles:10 Average:51.00 Median:51.00 stddev:0.94 (1.8%) stddev-sans-first:1.00 09:55:19 INFO - PROCESS | 8053 | Values: 51.0 52.0 50.0 51.0 50.0 53.0 51.0 51.0 50.0 51.0 09:55:19 INFO - PROCESS | 8053 | 09:55:19 INFO - PROCESS | 8053 | [#9] json-stringify-tinderbox Cycles:10 Average:60.60 Median:60.50 stddev:1.35 (2.2%) stddev-sans-first:1.42 09:55:19 INFO - PROCESS | 8053 | Values: 61.0 60.0 60.0 60.0 61.0 61.0 59.0 64.0 60.0 60.0 09:55:19 INFO - PROCESS | 8053 | 09:55:19 INFO - PROCESS | 8053 | [#10] stanford-crypto-aes Cycles:10 Average:75.70 Median:74.50 stddev:3.09 (4.2%) stddev-sans-first:3.22 09:55:19 INFO - PROCESS | 8053 | Values: 74.0 75.0 82.0 74.0 81.0 74.0 75.0 74.0 74.0 74.0 09:55:19 INFO - PROCESS | 8053 | 09:55:19 INFO - PROCESS | 8053 | [#11] stanford-crypto-ccm Cycles:10 Average:129.50 Median:126.00 stddev:16.30 (12.9%) stddev-sans-first:17.16 09:55:19 INFO - PROCESS | 8053 | Values: 124.0 121.0 122.0 123.0 128.0 127.0 130.0 120.0 175.0 125.0 09:55:19 INFO - PROCESS | 8053 | 09:55:19 INFO - PROCESS | 8053 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:156.10 Median:157.00 stddev:2.08 (1.3%) stddev-sans-first:2.17 09:55:19 INFO - PROCESS | 8053 | Values: 155.0 157.0 154.0 154.0 157.0 153.0 157.0 156.0 159.0 159.0 09:55:19 INFO - PROCESS | 8053 | 09:55:19 INFO - PROCESS | 8053 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.60 Median:63.00 stddev:0.84 (1.3%) stddev-sans-first:0.88 09:55:19 INFO - PROCESS | 8053 | Values: 63.0 63.0 63.0 63.0 62.0 62.0 62.0 63.0 61.0 64.0 09:55:19 INFO - PROCESS | 8053 | -------- Summary: end -------- 09:55:19 INFO - PROCESS | 8053 | 09:55:20 INFO - TEST-INFO | 8053: exit 0 09:55:20 INFO - TEST-OK | kraken | took 59362ms 09:55:20 INFO - SUITE-END | took 1174s 09:55:20 INFO - Completed test suite (00:19:34) 09:55:20 INFO - javascript benchmark 09:55:20 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3210.8555115019826, 3239.0930539448955, 3267.199536113651, 3276.803391796494, 3297.0273769238074, 5500.5874559408, 5576.888462230753, 5595.804146854855, 5596.2165296858475, 5597.916031259534, 5595.664260144395, 5652.802151924673, 5655.7172141392975, 5659.943400565993, 5686.232358631594, 5106.4382917879, 5119.948800511985, 5127.33344665193, 5147.716875571842, 5187.584993200554, 4977.651564390494, 5201.271821944912, 5208.270842082112, 5212.895742085156, 5214.400343960455, 4940.752962351868, 5074.213496907967, 5077.33994580704, 5104.6937183768905, 5125.026245013435, 5124.718140502271, 5153.304303918979, 5162.045021670965, 5214.817481388133, 5220.843374698765, 5050.318207042039, 5142.974285128588, 5194.142966410538, 5207.6875387476875, 5227.26818245446, 5316.680999140025, 5358.91961620576, 5467.589930755209, 5481.725913704299, 5501.257330260403, 5909.911351329733, 5910.734016969247, 5935.73289201987, 6003.039513677834, 6006.8498287542725, 6035.517158627299, 6041.486473649745, 6043.728032238516, 6052.061930400795, 6057.575969682124, 5515.806946756844, 5555.611107222496, 5581.413951535031, 5611.046122159243, 5626.43735626434, 6122.479589234876, 6166.81499555014, 6170.197874276315, 6171.9691401542705, 6172.074188871705, 5928.347881733006, 5931.40685931412, 5935.139404786281, 5936.495397891142, 5943.791967281124, 5763.682997435098, 5806.303243610793, 5811.9709401453565, 5855.38518455556, 5855.443732845373, 5891.852703682356, 5894.410558944071, 5894.941050589525, 5921.73352199152, 5930.318013428511, 5851.561132915049, 5898.056310990221, 5903.557233207526, 5904.9704751475965, 5906.940930590725, 4514.0069184779295, 4536.251518499476, 4542.205114105019, 4558.407407037064, 4569.908601828011, 4082.1427500225286, 4105.876823695294, 4127.277726397869, 4132.669386449077, 4142.233686767958, 5709.400512946164, 5709.600327977002, 5717.971410143005, 5718.228039214736, 5720.914186287126, 5766.798162064308, 5814.970925145347, 5973.0, 5986.102084687332, 5997.100434934795, 5739.942600573941, 5802.245708057925, 5808.128780682847, 5822.592418530747, 5827.941720582825, 5213.843584692465, 5236.711980841091, 5238.580913526908, 5245.842624721265, 5246.606504512177, 6033.396660333931, 6116.42707509198, 6374.521910856612, 6389.648569328639, 6393.680315984182, 5944.762209511571, 5953.28560572734, 5999.250093738283, 6090.177825993434, 6113.510919126548, 6317.210348706411, 6357.14178585885, 6361.01404282337, 6364.109024736448, 6367.586106903036, 5779.797707080232, 5940.46535811779, 5950.910736339045, 5951.940480595312, 5954.910676339771, 5843.883122337445, 5976.252968378953, 6017.759289628541, 6022.307434645072, 6104.023356262976, 5904.822855314347, 5944.6730429826775, 5949.762009519571, 5951.464368206882, 5960.374160713062, 5949.0481522956115, 5949.137375080502, 5949.405059494016, 5950.732217050329, 5962.046072628359, 5587.469190426903, 5705.4009329019855, 5722.313322401172, 5781.68200748946, 5818.6217895836635, 5960.225170727777, 5969.0, 5970.611910225822, 5986.042233242661, 5986.760529578768, 5584.3857175711455, 5625.887482250414, 5762.971185144047, 5774.538036957118, 5777.04678728022], "value": 5535.354218218674, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [573.40938833002, 586.0447470622885, 630.7886857902604, 648.1315037849281, 663.1677245057452, 36493.81753091234, 43312.78343608281, 44182.0, 44280.999999999956, 48666.27000594994, 46325.7683711581, 46474.535254647446, 46707.0, 46760.2985955211, 46820.297695534595, 8529.061803201643, 8699.39104262702, 8701.738947831574, 8705.869411958825, 8707.0, 8381.203785640355, 8599.656013759442, 10377.688669339894, 10610.893891061069, 10623.681289561287, 10578.629747958823, 10596.841047384296, 10629.5216715248, 10636.946815265912, 10651.520681569347, 10598.629047983284, 10635.94682026585, 10651.733706657318, 10652.360858348524, 10661.0, 10500.474976251156, 10584.629537966135, 10585.470726463645, 10586.99423554761, 10594.31136976094, 42810.0, 43144.78427607873, 43218.0, 43245.35131973023, 43295.134097318194, 3586.7309951753464, 3769.6230376962358, 3798.1264309209037, 3889.611038896116, 3959.742616729903, 32011.51982720261, 32162.0, 32437.18907027343, 32501.724189630837, 32900.835495822364, 4251.893702657428, 4364.694471387004, 4449.755263460508, 4532.8866778330475, 4593.793279302439, 362668.0, 364695.0, 365284.0, 367790.0, 367891.0, 62956.68521657363, 63355.366446335865, 63897.36102638914, 64089.67955160194, 64108.35891641117, 39626.0, 40519.392209116886, 40623.59376406198, 43269.78365108154, 43294.134117318106, 19360.0, 19565.0, 19568.315108971117, 19576.21695132179, 19586.902065489583, 13559.0, 13627.454901803816, 13631.454741810416, 13704.177749335071, 13907.721845563032, 19705.83235844056, 20675.793242067386, 20739.58520829575, 21546.13815447396, 21582.892085539785, 7375.934738273292, 7478.850422991619, 7490.850182996419, 7604.277593628596, 7732.226777322223, 1018.8675472188717, 1074.6346242277664, 1132.2074547816562, 1184.2598376014992, 1205.1142410328403, 34307.0, 34437.0, 34530.48204276938, 34581.827090864885, 34944.30111397809, 1004.829179039565, 1165.7610189911218, 1203.5667159822458, 1216.4890745886748, 1242.3105176626877, 15420.383184672712, 16912.830871691127, 16975.0, 17117.82882171187, 17204.31182752676, 512104.0, 516384.0, 517538.0, 519069.0, 520146.0, 62646.0, 63637.72724545483, 63665.36334636687, 63726.68136659287, 63736.36263637397, 1331.9533816316575, 1357.4434481862388, 1369.4659082957455, 1416.9433222671184, 1440.5894320118716, 30436.0, 34779.65220347764, 35442.291154177285, 35523.46714799228, 35724.46413303854, 52636.47363526469, 53031.734841325546, 53165.0, 53199.46800531945, 53287.46712532825, 374549.0, 374896.0, 380162.0, 382640.0, 383135.0, 30057.84971075218, 31418.0, 33027.174320642174, 33054.338913222084, 33182.83408582942, 50986.490135098175, 51033.489665102876, 51231.487685124164, 51278.74360628173, 52616.7369163167, 42335.0, 42419.0, 42843.357349640406, 43605.0, 44284.33573496465, 42202.10090545871, 42533.149337012466, 43285.0, 43775.0, 44544.77727611341, 43264.78367608142, 43593.0, 44232.7788361069, 44482.0, 44664.0, 35788.0, 38758.806205969915, 38860.22279554336, 39114.02214944649, 39209.607903920594, 29295.937372389304, 29346.559801602565, 29402.705972940854, 29532.78504112243, 29590.112296631134], "value": 21061.12501360907, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [400.6074047433517, 428.9656827453804, 434.3246252078017, 435.0121216860874, 444.6909397968409, 15722.842771572281, 18978.335758248464, 19126.808731912675, 19830.20679172835, 20018.899905500453, 15213.771793423108, 17702.734458983094, 17717.7342339865, 17892.64214715705, 17914.372996945076, 5662.320521537411, 5910.0248459004215, 5916.526677865761, 5917.0, 5924.111383292519, 5731.598788084836, 5781.768729250825, 5996.370381109986, 6007.6996150192535, 6111.2666480022235, 6033.185519954794, 6066.575339726221, 6080.452759251666, 6090.299615544225, 6093.42112499314, 5745.597808153431, 5964.13520039594, 5998.4001599839885, 6068.96965515174, 6088.817335479943, 5072.467390923974, 5314.946850531523, 5895.941040589582, 5911.0, 5911.201987731644, 14141.222232777192, 14233.78649320261, 14392.856071439257, 14411.351489183013, 14651.853481465261, 2439.097533912464, 2452.558539462896, 2459.803215742754, 2568.576184929503, 2611.2035829072124, 10188.94905525475, 10363.560384454608, 10555.102816260627, 10829.945850270777, 10974.396408197546, 1988.1351612048804, 2027.6147531969027, 2048.6926960955825, 2049.9795002050087, 2064.783197764228, 81372.18627813706, 81981.18018819854, 83888.16111838925, 84229.0, 84311.0, 9716.61133554664, 10341.327813692085, 11093.168012399103, 11153.219274650859, 11180.38507882058, 14268.429262829579, 14326.785098223534, 14334.283285835667, 14350.712985740436, 14366.425342986164, 8741.562921853882, 9007.549622518847, 9014.729558113268, 9063.184313411693, 9216.308776841764, 8535.658573656985, 8546.444481108709, 8640.351973602004, 8642.178992995656, 8977.236934860479, 4829.0, 4836.226203807374, 5067.442581316052, 5139.203423469368, 5158.252053452228, 2042.9795702043086, 2086.3845165675857, 2152.257471172474, 2152.924647637325, 2265.943351416228, 900.2572877376191, 968.8595153702673, 978.2858513285199, 1040.5213601743283, 1061.6496556136456, 19564.3152489665, 19889.10499027547, 20181.49546261355, 20348.18607255723, 20369.287074952306, 1011.7371476986659, 1017.9847302290472, 1040.287403128859, 1066.5627092891878, 1096.309325125166, 10545.630902918514, 10819.621313253998, 10910.127189824794, 11730.472128754227, 12076.335801531, 459746.0, 465806.0, 466727.0, 468157.0, 470445.0, 96814.0, 98745.50627246962, 99719.50140249252, 102191.0, 102865.48567257116, 963.633819148733, 986.0, 997.4539463831032, 1009.9949500252452, 1010.0000999010927, 13189.604311870658, 17110.657786844444, 17234.482965511055, 17893.910530447265, 17993.820061799215, 43749.562504374546, 44086.33870491881, 44379.33430998473, 44841.551584483735, 45634.54365456303, 56943.71528142333, 57423.71288143533, 57714.7114264426, 57831.71084144552, 58627.70686146542, 8669.836692129978, 8696.21734043939, 8701.782455438664, 8792.824143517222, 9170.0, 9900.554475048497, 12777.872221277668, 13055.0, 13313.66715832112, 13429.059965802691, 11290.887091128983, 11546.364949927834, 11560.94219528897, 11612.0, 11675.35785531804, 11364.659060228207, 11596.420178991186, 11697.12271579652, 11927.0, 11935.343556104497, 11586.710332241424, 11757.0, 11849.348285844017, 11903.0, 11941.46263418131, 8655.0479447262, 8720.912790872264, 8782.956085219534, 8886.511241881759, 9283.535823208947, 11091.223614346918, 11610.825837612612, 11768.0, 11787.646370608896, 11809.01008890214], "value": 9282.46095572348, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1503.2183264702358, 1534.46293797171, 1674.5227610131114, 1784.8125946775597, 1838.7517685112514, 8282.710105146321, 8896.777580560489, 8906.91093089069, 8907.109289071097, 8908.688195913144, 7785.182555831627, 7948.801279968004, 8495.532745698983, 8639.870401943976, 8810.559472026389, 2463.3472129885595, 2491.3273416177713, 2492.8906636546744, 2497.6628155199087, 2502.2368177705794, 2464.605663093905, 2491.1405565080017, 2491.4767898741197, 2495.0144692846316, 2503.5368456835454, 2453.411181316489, 2456.1526273435725, 2474.134053081425, 2481.416867036254, 2483.7019557653016, 2339.6139636959883, 2345.0, 2408.8434251773665, 2430.1251549442186, 2450.387403149213, 2392.7248366437734, 2397.7482364351667, 2416.8187385946126, 2419.1895714935517, 2430.86630235337, 8900.821983560292, 8952.239059679934, 9002.774930626721, 9037.819243615091, 9053.909460905372, 5249.973750131225, 5381.730913454351, 5384.542313903323, 5391.892162156735, 5393.541548968303, 3474.8610055597746, 3499.7725147865563, 3549.982250088733, 3640.1081734974837, 3699.056740531147, 1252.843394575678, 1260.848698156218, 1275.515304184404, 1308.672831792052, 1313.1070871807167, 11917.165798394115, 11924.523019079226, 11962.282263064157, 11988.52045918171, 12008.039356851343, 5070.416902056237, 5108.208227724708, 5190.091733946544, 5200.791968321225, 5221.973890130601, 10495.580176792995, 10510.63212787564, 10513.684589462327, 10514.316569422963, 10516.053555180071, 5589.972050139723, 5685.090385538294, 5685.204071430003, 5686.943130568724, 5689.516391106802, 4427.667924905645, 4513.638908887281, 4944.554990050912, 4987.077390682735, 5113.53978141969, 3989.3218152914073, 4063.9796801015805, 4168.8332466701595, 4185.790710464526, 4187.623113919762, 1401.3343661760582, 1406.2617126008722, 1409.5700811252666, 1412.682146517046, 1412.7527682655493, 833.7415401225518, 882.5895958379384, 964.4888209249109, 997.5012493753123, 1022.4023471493662, 5786.1610066540115, 5818.33089194748, 5818.505427038749, 5871.588988770831, 5956.642601443928, 942.3968660057569, 958.4297343080856, 981.5681100315838, 1007.3351587951917, 1008.5713571731984, 3845.576986531479, 4002.799860006988, 4052.3516237402464, 4055.0673345130785, 4150.626443620089, 10710.518026688818, 11125.443727813577, 11218.775624487465, 11231.887681123084, 11252.718682032852, 11171.05046071093, 11200.0, 11204.103671706243, 11204.991550760471, 11220.943895280472, 8177.509349438934, 8392.664293428195, 8476.4490308131, 8477.533735644476, 8599.753035809772, 5776.133579962871, 5842.5325973921135, 5920.496757775637, 5945.702714864153, 6010.7295171716505, 9378.906210938076, 9585.472798995843, 9671.129598336183, 9768.902310976799, 9786.266030047633, 9632.662856799978, 9966.750831229278, 10254.333468324536, 10410.531526081193, 10421.374717516974, 7863.803404914923, 8032.236937490929, 8095.2714255717265, 8117.431779775595, 8138.104808471182, 8511.489310641382, 8524.872126918226, 8590.742277731679, 8593.44142630727, 8697.478151310941, 7736.806579835549, 7780.92219077802, 7788.805279868048, 7891.092524359772, 7931.087924888711, 7672.117706463828, 7721.729739459092, 7747.883781743166, 7872.84254314922, 7932.563708996061, 6817.5568588041615, 6878.65606719672, 6917.308269173042, 6998.475114366544, 7031.472639552157, 5331.280277162534, 5365.409804921377, 5402.372475706721, 5438.483344082306, 5541.445855414427, 5130.12787826078, 5130.820421285237, 5263.763130659206, 5302.575793936398, 5306.708131052675], "value": 4768.439606210924, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.2387704496809, 355.58041511017007, 355.8594355229683, 358.66106529329784, 362.2139956294838, 5365.302510673612, 5374.704391258489, 5383.650062745924, 5384.757685904129, 5388.003219404403, 5000.499950004998, 5275.393329767078, 5303.0, 5305.840824775253, 5323.307969963909, 4143.233501802161, 4155.83636581756, 4210.9578904210875, 4226.852060177895, 4233.248388547438, 4183.874483765477, 4201.243776120313, 4228.133232687307, 4234.9364759528635, 4238.894027649302, 3996.1208534122593, 4135.6898232632675, 4180.895477613069, 4187.790610469479, 4202.6637868970565, 4097.856575019875, 4161.167766446692, 4184.686148538842, 4200.852970146061, 4208.6422654074595, 3738.401855703102, 3744.925101497982, 3761.322961866863, 3767.792771397572, 3768.9623103768886, 4885.193942999401, 5177.068127737006, 5188.7146206958605, 5192.0394726975255, 5209.765560549784, 4689.484156742756, 4767.261074533453, 4783.641226907996, 4791.0, 4799.3760811094335, 5538.698405874617, 5546.667199967975, 5548.250986116863, 5552.694601796899, 5558.666480011212, 3849.7690138591774, 4092.5907409259135, 4098.991494167866, 4104.527979282391, 4107.876763697094, 5657.943420565783, 5713.457221563945, 5714.771324165283, 5754.827355179351, 5757.740901659436, 4194.979025104916, 4196.3705444183615, 4201.747895126302, 4204.831806727697, 4208.6633069354375, 4159.334506479009, 4253.128108737701, 4254.7659878706345, 4261.850835220753, 4265.232258193555, 4796.544328288807, 4797.905351048624, 4799.18413869643, 4809.278608208727, 4829.975850120727, 5613.831585052455, 5618.101103823368, 5621.0, 5634.3802181759975, 5636.408177141423, 1382.7994940733738, 1416.5113036002563, 1422.7865820126856, 1426.7444648709277, 1429.6926160875253, 960.8318544254728, 984.945827979468, 987.2348929579633, 994.3884511025667, 997.8154041502345, 5226.477352264743, 5238.528532432099, 5245.186996015624, 5246.455469097485, 5255.108161062015, 5493.5004572920025, 5504.504594586507, 5509.862253443696, 5510.972445137749, 5512.255845460892, 5029.7736601853, 5220.347456567929, 5228.477152284817, 5233.441259943275, 5243.52808247252, 2903.2451562593455, 2911.5341545352644, 2913.562965555184, 2924.1373794730607, 2925.824450532975, 5562.610617256834, 5666.886662266814, 5689.601727879091, 5713.885792270467, 5724.914126288109, 5508.669479831223, 5513.42109078549, 5538.778448862, 5542.667439953616, 5550.666959982414, 5688.943110568841, 5712.228849105318, 5718.513926316226, 5753.108268218432, 5755.5683323750045, 5300.814471493479, 5483.259759932358, 5493.560515158858, 5499.477549632778, 5523.723813809213, 5360.41035486104, 5374.736936819766, 5387.865303367447, 5407.188921661782, 5452.400235974119, 5468.5351745100525, 5733.362653179584, 5760.798372056958, 5762.971185144215, 5766.5675074368755, 3971.2653159165648, 4020.2964481216254, 4023.1752490739914, 4046.2312160689376, 4050.8784736457956, 5411.431799660979, 5416.945830541802, 5432.8047829477455, 5548.445155484419, 5594.468525490072, 5556.88862227548, 5592.636478628876, 5595.272614560081, 5602.635828671123, 5613.4282400927805, 5563.972180139235, 5565.499105080562, 5581.413951535031, 5601.5238704710555, 5606.103023516218, 5341.439148889311, 5553.944460555343, 5560.582956278215, 5579.804706835242, 5581.413951535031, 5656.971715141398, 5660.122680984454, 5666.631668941505, 5671.858203544779, 5692.402297758676, 5277.445868183785, 5452.454754524516, 5525.640833345819, 5535.916961245504, 5536.453231513549], "value": 4238.429657090906, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [778.595130532123, 801.3228821645707, 819.405930700242, 827.850986822372, 891.2068259249269, 14126.929365353184, 14147.221902795342, 14238.572842814707, 14247.501337453192, 15580.0, 14765.926170369159, 14889.627759306022, 14940.47708330206, 14947.402103915858, 14949.551513454613, 711.704642573334, 726.041625054925, 817.8773184022415, 838.7316058861167, 842.1830824100613, 848.7835601921502, 854.2098558833054, 854.5257382152903, 854.7606670132373, 856.400519636254, 899.7390756680587, 900.729781065681, 900.8153328567661, 900.9774755631129, 901.179926267097, 757.1633345153608, 770.8650986077414, 872.5462759365126, 879.090141703334, 891.9697749099801, 661.3849120318102, 661.4840424468874, 662.3575132121834, 662.7017841971133, 663.270402557188, 14352.569422917224, 14572.489962851356, 14574.198419086946, 14630.487932922402, 14641.926790366086, 13821.861781382258, 13868.861311386858, 14417.0, 14449.205293708841, 14482.493112741004, 12023.759524809455, 12421.75156496865, 12820.23078615286, 12899.484020639164, 13147.737045259042, 3874.244522318149, 3931.2727145477893, 3974.8012599370204, 4018.3168861293652, 4028.7179897407195, 68035.65982170108, 68186.0, 69847.65076174587, 69932.6503367485, 69967.65016174887, 11326.773464530828, 11590.536378544932, 11720.88279117215, 11842.467088981017, 11885.524579016743, 20743.481412964495, 22506.774932250795, 22610.09559617597, 22649.094036238694, 22682.092716291492, 8838.734837954871, 8942.821143577223, 8980.0, 9036.457812531138, 9150.947641021234, 4759.833405830779, 4832.395950506187, 4838.975805121022, 4880.072786170616, 4916.237983112624, 3776.4712940188397, 3885.0093226227696, 3887.8056097195026, 3889.3193691103943, 3907.882763517099, 599.1252770954368, 674.7806962737031, 697.1529591546296, 730.8867125595541, 732.1397358104206, 684.9691214721911, 717.9712811487482, 760.2245709376405, 772.5519198864645, 805.3838813307824, 9203.907960920438, 9560.187384072293, 9956.701298961043, 9980.2514811388, 10038.949805250926, 703.4161645833946, 728.1953441447231, 755.056179775281, 756.3986630628664, 763.4884627299723, 4904.5340692634145, 5038.143515602356, 5050.217216331474, 5055.696658200446, 5064.215046667772, 85538.0, 94385.5842162368, 94971.0, 96974.51512742392, 98369.01630983742, 82340.0, 86022.56988715017, 87925.0, 88492.5575372119, 89037.0, 73311.0, 76149.0, 76307.61846190733, 76767.61616191884, 78336.21663783403, 9191.816163676554, 9333.113354231336, 9505.66730164441, 9719.514024298614, 9757.31698781107, 50285.49714502954, 51533.0, 51692.96614067773, 51827.48172518227, 52047.47952520426, 53935.0, 68328.65835670789, 70879.64560177166, 71692.64153679198, 72228.27771722426, 11499.712507187387, 11523.596674116367, 11540.769184616092, 11715.1799374043, 11917.880821191677, 13635.454581816617, 13645.044846860625, 13731.107478013897, 13880.236586987812, 14106.717865642835, 12936.547220847226, 12960.157589756636, 13131.671708207372, 13244.006699497384, 13786.0, 12759.872401275868, 12793.872061279268, 12907.612771616867, 12994.870051299744, 13024.218546887218, 11810.291382516732, 12445.0, 12691.008044018057, 12807.487700491876, 12925.612231633066, 10100.949495252476, 10621.0, 10957.671269861918, 10994.890051099386, 11024.5038973248, 10663.573457061631, 11112.166587506132, 11226.831597525881, 11368.829467557827, 11466.31202127875], "value": 7485.425226043384, "unit": "score"}], "name": "dromaeo_css", "value": 7396.43017981579, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 110.0, 111.0, 110.0, 111.0, 111.0, 110.0, 110.0, 111.0, 111.0], "value": 109.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 117.0, 117.0, 156.0, 118.0, 119.0, 125.0, 120.0, 150.0, 117.0], "value": 128.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 159.0, 161.0, 160.0, 160.0, 159.0, 159.0, 159.0, 161.0], "value": 160.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 75.0, 74.0, 108.0, 74.0, 76.0, 75.0, 74.0, 98.0, 74.0], "value": 80.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 109.0, 82.0, 82.0, 82.0, 106.0, 81.0, 81.0, 111.0], "value": 89.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 117.0, 116.0, 116.0, 117.0, 117.0, 118.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 146.0, 146.0, 146.0, 146.0, 146.0, 147.0, 145.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 84.0, 84.0, 84.0, 85.0, 85.0, 84.0, 85.0, 84.0], "value": 84.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 52.0, 50.0, 51.0, 50.0, 53.0, 51.0, 51.0, 50.0, 51.0], "value": 51.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 60.0, 60.0, 60.0, 61.0, 61.0, 59.0, 64.0, 60.0, 60.0], "value": 60.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 82.0, 74.0, 81.0, 74.0, 75.0, 74.0, 74.0, 74.0], "value": 75.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 121.0, 122.0, 123.0, 128.0, 127.0, 130.0, 120.0, 175.0, 125.0], "value": 129.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 157.0, 154.0, 154.0, 157.0, 153.0, 157.0, 156.0, 159.0, 159.0], "value": 156.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 63.0, 63.0, 62.0, 62.0, 62.0, 63.0, 61.0, 64.0], "value": 62.6, "unit": "score"}], "name": "kraken", "value": 1450.0999999999997, "alertThreshold": 2.0}]} 09:55:20 INFO - Return code: 0 09:55:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:55:20 INFO - # TBPL SUCCESS # 09:55:20 INFO - Running post-action listener: _resource_record_post_action 09:55:20 INFO - [mozharness: 2017-01-04 17:55:20.738272Z] Finished run-tests step (success) 09:55:20 INFO - Running post-run listener: _resource_record_post_run 09:55:20 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 266240; Write bytes: 374018048; Read time: 24; Write time: 261732 09:55:20 INFO - TinderboxPrint: CPU usage
13.0% 09:55:20 INFO - TinderboxPrint: I/O read bytes / time
266,240 / 24 09:55:20 INFO - TinderboxPrint: I/O write bytes / time
374,018,048 / 261,732 09:55:20 INFO - TinderboxPrint: CPU idle
8,269.7 (87.0%) 09:55:20 INFO - TinderboxPrint: CPU user
1,192.8 (12.5%) 09:55:20 INFO - TinderboxPrint: Swap in / out
0 / 0 09:55:20 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 124149760; Read time: 0; Write time: 89908 09:55:20 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 262144; Write bytes: 248754176; Read time: 12; Write time: 171788 09:55:21 INFO - Running post-run listener: _upload_blobber_files 09:55:21 INFO - Blob upload gear active. 09:55:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:55:21 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:55:21 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:55:21 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:55:21 INFO - (blobuploader) - INFO - Open directory for files ... 09:55:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:55:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:55:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:55:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:55:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:55:22 INFO - (blobuploader) - INFO - Done attempting. 09:55:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:55:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:55:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:55:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:55:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:55:25 INFO - (blobuploader) - INFO - Done attempting. 09:55:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:55:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:55:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:55:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:55:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:55:26 INFO - (blobuploader) - INFO - Done attempting. 09:55:26 INFO - (blobuploader) - INFO - Iteration through files over. 09:55:26 INFO - Return code: 0 09:55:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:55:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:55:26 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d1160fe39e87bf8941b36ab560996c3871ef963584330ca60897cc861b182a220e65012a3cefeb880afbf3fd0ffaefaa2623aa165a43668a8874538770160cdc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f2985fd014bb8847384454650ea8d1287031fdf38d74f7cff9ec950f84a195fb1bdf46d422496c88aa81d2dfb11be2049ccc612ab1afae3088ab9c8db2e016aa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e4eb7f908731770e26690fd0d072c3baaa3672b53903bbe248b7b512fb110069a156eac68e27ff6d37109e59cbd97717e9deed4e740e0b4ade7011b71ac3330a"} 09:55:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:55:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:55:26 INFO - Contents: 09:55:26 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d1160fe39e87bf8941b36ab560996c3871ef963584330ca60897cc861b182a220e65012a3cefeb880afbf3fd0ffaefaa2623aa165a43668a8874538770160cdc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f2985fd014bb8847384454650ea8d1287031fdf38d74f7cff9ec950f84a195fb1bdf46d422496c88aa81d2dfb11be2049ccc612ab1afae3088ab9c8db2e016aa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e4eb7f908731770e26690fd0d072c3baaa3672b53903bbe248b7b512fb110069a156eac68e27ff6d37109e59cbd97717e9deed4e740e0b4ade7011b71ac3330a"} 09:55:26 INFO - Running post-run listener: copy_logs_to_upload_dir 09:55:26 INFO - Copying logs to upload dir... 09:55:26 INFO - mkdir: /builds/slave/test/build/upload/logs 09:55:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1234.930726 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2017-01-04 09:55:26.450867) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-04 09:55:26.454407) ========= 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=a0d2d2de893abaa8702a9954000001a7-1483550019.410520-1401542043 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/d1160fe39e87bf8941b36ab560996c3871ef963584330ca60897cc861b182a220e65012a3cefeb880afbf3fd0ffaefaa2623aa165a43668a8874538770160cdc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f2985fd014bb8847384454650ea8d1287031fdf38d74f7cff9ec950f84a195fb1bdf46d422496c88aa81d2dfb11be2049ccc612ab1afae3088ab9c8db2e016aa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e4eb7f908731770e26690fd0d072c3baaa3672b53903bbe248b7b512fb110069a156eac68e27ff6d37109e59cbd97717e9deed4e740e0b4ade7011b71ac3330a"} build_url:https://queue.taskcluster.net/v1/task/T-5DFV7VTcu4imG7NoWSdg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011468 build_url: 'https://queue.taskcluster.net/v1/task/T-5DFV7VTcu4imG7NoWSdg/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/d1160fe39e87bf8941b36ab560996c3871ef963584330ca60897cc861b182a220e65012a3cefeb880afbf3fd0ffaefaa2623aa165a43668a8874538770160cdc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f2985fd014bb8847384454650ea8d1287031fdf38d74f7cff9ec950f84a195fb1bdf46d422496c88aa81d2dfb11be2049ccc612ab1afae3088ab9c8db2e016aa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e4eb7f908731770e26690fd0d072c3baaa3672b53903bbe248b7b512fb110069a156eac68e27ff6d37109e59cbd97717e9deed4e740e0b4ade7011b71ac3330a"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-04 09:55:26.479169) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 09:55:26.479480) ========= 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=a0d2d2de893abaa8702a9954000001a7-1483550019.410520-1401542043 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004453 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 09:55:26.565286) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-04 09:55:26.565598) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-04 09:55:26.565894) ========= ========= Total master_lag: 0.17 =========