builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-035 starttime: 1476453806.73 results: success (0) buildid: 20161014053035 builduid: c70a1c7826d44c0280f0df062e29b3af revision: 3d5379dbab72482bea7cbfbb503de9f70672a897 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-14 07:03:26.732761) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-14 07:03:26.733264) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-14 07:03:26.733615) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-14 07:03:26.748546) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 07:03:26.748933) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=ed14279e38b05397cf4aa391000001a4-1476453806.485331-1094630735 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-14 07:03:27-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 22.0M=0.001s 2016-10-14 07:03:27 (22.0 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.075354 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 07:03:26.838588) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 07:03:26.838980) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=ed14279e38b05397cf4aa391000001a4-1476453806.485331-1094630735 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019382 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 07:03:26.888899) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-14 07:03:26.889315) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 3d5379dbab72482bea7cbfbb503de9f70672a897 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 3d5379dbab72482bea7cbfbb503de9f70672a897 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=ed14279e38b05397cf4aa391000001a4-1476453806.485331-1094630735 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-14 07:03:27,401 truncating revision to first 12 chars 2016-10-14 07:03:27,401 Setting DEBUG logging. 2016-10-14 07:03:27,401 attempt 1/10 2016-10-14 07:03:27,401 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/3d5379dbab72?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-14 07:03:28,768 unpacking tar archive at: autoland-3d5379dbab72/testing/mozharness/ program finished with exit code 0 elapsedTime=1.701943 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-14 07:03:28.606106) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-14 07:03:28.606510) ========= script_repo_revision: 3d5379dbab72482bea7cbfbb503de9f70672a897 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-14 07:03:28.607028) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-14 07:03:28.607350) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-14 07:03:28.618793) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-10-14 07:03:28.619467) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=ed14279e38b05397cf4aa391000001a4-1476453806.485331-1094630735 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:03:29 INFO - MultiFileLogger online at 20161014 07:03:29 in /builds/slave/test 07:03:29 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 07:03:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:03:29 INFO - {'append_to_log': False, 07:03:29 INFO - 'base_work_dir': '/builds/slave/test', 07:03:29 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 07:03:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:03:29 INFO - 'branch': 'Autoland-Non-PGO', 07:03:29 INFO - 'buildbot_json_path': 'buildprops.json', 07:03:29 INFO - 'config_files': ('talos/linux_config.py',), 07:03:29 INFO - 'default_actions': ('clobber', 07:03:29 INFO - 'read-buildbot-config', 07:03:29 INFO - 'download-and-extract', 07:03:29 INFO - 'populate-webroot', 07:03:29 INFO - 'create-virtualenv', 07:03:29 INFO - 'install', 07:03:29 INFO - 'run-tests'), 07:03:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:03:29 INFO - 'download_minidump_stackwalk': True, 07:03:29 INFO - 'download_symbols': 'ondemand', 07:03:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:03:29 INFO - 'tooltool.py': '/tools/tooltool.py', 07:03:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:03:29 INFO - '/tools/misc-python/virtualenv.py')}, 07:03:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:03:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:03:29 INFO - 'installer_path': 'installer.exe', 07:03:29 INFO - 'log_level': 'info', 07:03:29 INFO - 'log_name': 'talos', 07:03:29 INFO - 'log_to_console': True, 07:03:29 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:03:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:03:29 INFO - 'opt_config_files': (), 07:03:29 INFO - 'pip_index': False, 07:03:29 INFO - 'sps_profile': False, 07:03:29 INFO - 'sps_profile_interval': 0, 07:03:29 INFO - 'suite': 'dromaeojs', 07:03:29 INFO - 'system_bits': '32', 07:03:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:03:29 INFO - 'title': 'talos-linux64-ix-035', 07:03:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:03:29 INFO - 'use_talos_json': True, 07:03:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:03:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:03:29 INFO - 'work_dir': 'build'} 07:03:29 INFO - [mozharness: 2016-10-14 14:03:29.224192Z] Running clobber step. 07:03:29 INFO - Running pre-action listener: _resource_record_pre_action 07:03:29 INFO - Running main action method: clobber 07:03:29 INFO - rmtree: /builds/slave/test/build 07:03:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:03:29 INFO - Running post-action listener: _resource_record_post_action 07:03:29 INFO - [mozharness: 2016-10-14 14:03:29.685449Z] Finished clobber step (success) 07:03:29 INFO - [mozharness: 2016-10-14 14:03:29.685551Z] Running read-buildbot-config step. 07:03:29 INFO - Running pre-action listener: _resource_record_pre_action 07:03:29 INFO - Running main action method: read_buildbot_config 07:03:29 INFO - Using buildbot properties: 07:03:29 INFO - { 07:03:29 INFO - "project": "", 07:03:29 INFO - "product": "firefox", 07:03:29 INFO - "script_repo_revision": "production", 07:03:29 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 07:03:29 INFO - "repository": "", 07:03:29 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 07:03:29 INFO - "buildid": "20161014053035", 07:03:29 INFO - "pgo_build": "False", 07:03:29 INFO - "basedir": "/builds/slave/test", 07:03:29 INFO - "buildnumber": 815, 07:03:29 INFO - "slavename": "talos-linux64-ix-035", 07:03:29 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 07:03:29 INFO - "platform": "ubuntu64_hw", 07:03:29 INFO - "scheduler": "tests-autoland-linux64-talos", 07:03:29 INFO - "branch": "autoland", 07:03:29 INFO - "repo_path": "integration/autoland", 07:03:29 INFO - "revision": "3d5379dbab72482bea7cbfbb503de9f70672a897", 07:03:29 INFO - "stage_platform": "linux64", 07:03:29 INFO - "builduid": "c70a1c7826d44c0280f0df062e29b3af", 07:03:29 INFO - "slavebuilddir": "test" 07:03:29 INFO - } 07:03:29 INFO - Found installer url https://queue.taskcluster.net/v1/task/AG3-GhfwRxqUJt864HcpdQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2. 07:03:29 INFO - Running post-action listener: _resource_record_post_action 07:03:29 INFO - [mozharness: 2016-10-14 14:03:29.687962Z] Finished read-buildbot-config step (success) 07:03:29 INFO - [mozharness: 2016-10-14 14:03:29.688048Z] Running download-and-extract step. 07:03:29 INFO - Running pre-action listener: _resource_record_pre_action 07:03:29 INFO - Running main action method: download_and_extract 07:03:29 INFO - mkdir: /builds/slave/test/build/tests 07:03:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:03:29 INFO - trying https://queue.taskcluster.net/v1/task/AG3-GhfwRxqUJt864HcpdQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 07:03:29 INFO - Downloading https://queue.taskcluster.net/v1/task/AG3-GhfwRxqUJt864HcpdQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 07:03:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AG3-GhfwRxqUJt864HcpdQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 07:03:30 INFO - Downloaded 1361 bytes. 07:03:30 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 07:03:30 INFO - Using the following test package requirements: 07:03:30 INFO - {u'common': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 07:03:30 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 07:03:30 INFO - u'firefox-52.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 07:03:30 INFO - u'gtest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 07:03:30 INFO - u'firefox-52.0a1.en-US.linux-x86_64.gtest.tests.zip'], 07:03:30 INFO - u'jittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 07:03:30 INFO - u'jsshell-linux-x86_64.zip'], 07:03:30 INFO - u'mochitest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 07:03:30 INFO - u'firefox-52.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 07:03:30 INFO - u'mozbase': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 07:03:30 INFO - u'reftest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 07:03:30 INFO - u'firefox-52.0a1.en-US.linux-x86_64.reftest.tests.zip'], 07:03:30 INFO - u'talos': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 07:03:30 INFO - u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'], 07:03:30 INFO - u'web-platform': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 07:03:30 INFO - u'firefox-52.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 07:03:30 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 07:03:30 INFO - u'firefox-52.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 07:03:30 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 07:03:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AG3-GhfwRxqUJt864HcpdQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 07:03:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AG3-GhfwRxqUJt864HcpdQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:03:30 INFO - Fetch https://queue.taskcluster.net/v1/task/AG3-GhfwRxqUJt864HcpdQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 07:03:30 INFO - Http code: 200 07:03:30 INFO - via: 1.1 c0740de9f16cf32ffedfd2f9806b8483.cloudfront.net (CloudFront) 07:03:30 INFO - x-amz-cf-id: 4VAGIDbXtV-twd1FjugJ5U0WRgKsUAtuMHYWMlDnUXVHUm0WbWgj9g== 07:03:30 INFO - x-amz-version-id: _txWgxfGaaKlChDZr1oIBDR6uMnJUsAK 07:03:31 INFO - Expected file size: 20553992 07:03:31 INFO - Obtained file size: 20553992 07:03:35 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 07:03:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AG3-GhfwRxqUJt864HcpdQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 07:03:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AG3-GhfwRxqUJt864HcpdQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:03:35 INFO - Fetch https://queue.taskcluster.net/v1/task/AG3-GhfwRxqUJt864HcpdQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 07:03:35 INFO - Http code: 200 07:03:35 INFO - via: 1.1 66ed69e8b47ad05050331602c798132f.cloudfront.net (CloudFront) 07:03:35 INFO - x-amz-cf-id: NKxrpxgwUePeOpODRbkjf7F256ENHaCBuTtkrRMzcw94t0L6oOBfPg== 07:03:35 INFO - x-amz-version-id: _txWgxfGaaKlChDZr1oIBDR6uMnJUsAK 07:03:36 INFO - Expected file size: 20553992 07:03:36 INFO - Obtained file size: 20553992 07:03:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AG3-GhfwRxqUJt864HcpdQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip 07:03:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AG3-GhfwRxqUJt864HcpdQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 07:03:40 INFO - Fetch https://queue.taskcluster.net/v1/task/AG3-GhfwRxqUJt864HcpdQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip into memory 07:03:41 INFO - Http code: 200 07:03:41 INFO - via: 1.1 d8f42fc9558e3e49ebfdf8834baeb756.cloudfront.net (CloudFront) 07:03:41 INFO - x-amz-cf-id: IMCHzjDHVJeRXkmBwSmOlSESdi63_2O71eZ24Ub54Z9QqQ5Ql3OLhQ== 07:03:41 INFO - x-amz-version-id: BtwdRiqcdyrTBUCCisxdf_y5opYTRBJH 07:03:41 INFO - Expected file size: 14409006 07:03:41 INFO - Obtained file size: 14409006 07:03:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:03:41 INFO - trying https://queue.taskcluster.net/v1/task/AG3-GhfwRxqUJt864HcpdQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 07:03:41 INFO - Downloading https://queue.taskcluster.net/v1/task/AG3-GhfwRxqUJt864HcpdQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 07:03:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AG3-GhfwRxqUJt864HcpdQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 07:03:42 INFO - Downloaded 55656049 bytes. 07:03:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AG3-GhfwRxqUJt864HcpdQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 07:03:42 INFO - mkdir: /builds/slave/test/properties 07:03:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:03:42 INFO - Writing to file /builds/slave/test/properties/build_url 07:03:42 INFO - Contents: 07:03:42 INFO - build_url:https://queue.taskcluster.net/v1/task/AG3-GhfwRxqUJt864HcpdQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 07:03:43 INFO - Running post-action listener: _resource_record_post_action 07:03:43 INFO - Running post-action listener: set_extra_try_arguments 07:03:43 INFO - [mozharness: 2016-10-14 14:03:43.416952Z] Finished download-and-extract step (success) 07:03:43 INFO - [mozharness: 2016-10-14 14:03:43.417178Z] Running populate-webroot step. 07:03:43 INFO - Running pre-action listener: _resource_record_pre_action 07:03:43 INFO - Running main action method: populate_webroot 07:03:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:03:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:03:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:03:43 INFO - '--tppagecycles', 07:03:43 INFO - '7'], 07:03:43 INFO - 'tests': ['tsvgm']}}, 07:03:43 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:03:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:03:43 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:03:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:03:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:03:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:03:43 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:03:43 INFO - 'talos_options': ['--disable-e10s'], 07:03:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:03:43 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:03:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:03:43 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:03:43 INFO - 'talos_options': ['--disable-e10s'], 07:03:43 INFO - 'tests': ['damp', 'tps']}, 07:03:43 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:03:43 INFO - 'tests': ['damp', 'tps']}, 07:03:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:03:43 INFO - 'tests': ['dromaeo_dom']}, 07:03:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:03:43 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:03:43 INFO - 'tests': ['basic_compositor_video']}, 07:03:43 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:03:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:03:43 INFO - 'tests': ['a11yr', 07:03:43 INFO - 'ts_paint', 07:03:43 INFO - 'tpaint', 07:03:43 INFO - 'sessionrestore', 07:03:43 INFO - 'sessionrestore_no_auto_restore', 07:03:43 INFO - 'tabpaint']}, 07:03:43 INFO - 'other-e10s': {'tests': ['a11yr', 07:03:43 INFO - 'ts_paint', 07:03:43 INFO - 'tpaint', 07:03:43 INFO - 'sessionrestore', 07:03:43 INFO - 'sessionrestore_no_auto_restore', 07:03:43 INFO - 'tabpaint']}, 07:03:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:03:43 INFO - 'ts_paint', 07:03:43 INFO - 'tpaint', 07:03:43 INFO - 'sessionrestore', 07:03:43 INFO - 'sessionrestore_no_auto_restore', 07:03:43 INFO - 'tabpaint']}, 07:03:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:03:43 INFO - 'ts_paint', 07:03:43 INFO - 'tpaint', 07:03:43 INFO - 'sessionrestore', 07:03:43 INFO - 'sessionrestore_no_auto_restore', 07:03:43 INFO - 'tabpaint']}, 07:03:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:03:43 INFO - 'tests': ['a11yr', 07:03:43 INFO - 'ts_paint', 07:03:43 INFO - 'tpaint', 07:03:43 INFO - 'sessionrestore', 07:03:43 INFO - 'sessionrestore_no_auto_restore', 07:03:43 INFO - 'tabpaint']}, 07:03:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:03:43 INFO - 'tests': ['a11yr', 07:03:43 INFO - 'ts_paint', 07:03:43 INFO - 'tpaint', 07:03:43 INFO - 'sessionrestore', 07:03:43 INFO - 'sessionrestore_no_auto_restore', 07:03:43 INFO - 'tabpaint']}, 07:03:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:03:43 INFO - 'tests': ['tsvgx', 07:03:43 INFO - 'tsvgr_opacity', 07:03:43 INFO - 'tart', 07:03:43 INFO - 'tscrollx', 07:03:43 INFO - 'cart']}, 07:03:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:03:43 INFO - 'tsvgr_opacity', 07:03:43 INFO - 'tart', 07:03:43 INFO - 'tscrollx', 07:03:43 INFO - 'cart']}, 07:03:43 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:03:43 INFO - 'talos_options': ['--disable-e10s'], 07:03:43 INFO - 'tests': ['tp5o']}, 07:03:43 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:03:43 INFO - 'tests': ['tp5o']}, 07:03:43 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:03:43 INFO - 'talos_options': ['--disable-e10s', 07:03:43 INFO - '--xperf_path', 07:03:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:03:43 INFO - 'tests': ['tp5n']}, 07:03:43 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:03:43 INFO - 'talos_options': ['--xperf_path', 07:03:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:03:43 INFO - 'tests': ['tp5n']}}, 07:03:43 INFO - 'talos.zip': {'path': '', 07:03:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:03:43 INFO - Running post-action listener: _resource_record_post_action 07:03:43 INFO - [mozharness: 2016-10-14 14:03:43.437524Z] Finished populate-webroot step (success) 07:03:43 INFO - [mozharness: 2016-10-14 14:03:43.437611Z] Running create-virtualenv step. 07:03:43 INFO - Running pre-action listener: _resource_record_pre_action 07:03:43 INFO - Running main action method: create_virtualenv 07:03:43 INFO - Creating virtualenv /builds/slave/test/build/venv 07:03:43 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 07:03:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:03:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:03:43 INFO - Using real prefix '/usr' 07:03:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:03:44 INFO - Installing distribute.............................................................................................................................................................................................done. 07:03:46 INFO - Installing pip.................done. 07:03:46 INFO - Return code: 0 07:03:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:03:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:03:46 INFO - Reading from file tmpfile_stdout 07:03:46 INFO - Output received: 07:03:46 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) 07:03:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:03:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:03:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:03:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:03:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:03:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:03:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:03:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2c1c50f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b178a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2be4ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2be1f60>, '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 0x2be2d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be31d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1476453806.485331-1094630735', '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 07:03:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:03:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:03:46 INFO - Using env: {'DISPLAY': ':0', 07:03:46 INFO - 'HOME': '/home/cltbld', 07:03:46 INFO - 'LANG': 'en_US.UTF-8', 07:03:46 INFO - 'LANGUAGE': 'en_US:en', 07:03:46 INFO - 'LOGNAME': 'cltbld', 07:03:46 INFO - 'MAIL': '/var/mail/cltbld', 07:03:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:03:46 INFO - 'MOZ_NO_REMOTE': '1', 07:03:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:03:46 INFO - 'NO_EM_RESTART': '1', 07:03:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:03:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:03:46 INFO - 'PWD': '/builds/slave/test', 07:03:46 INFO - 'SHELL': '/bin/bash', 07:03:46 INFO - 'SHLVL': '1', 07:03:46 INFO - 'TERM': 'linux', 07:03:46 INFO - 'TMOUT': '86400', 07:03:46 INFO - 'USER': 'cltbld', 07:03:46 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1476453806.485331-1094630735', 07:03:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:03:46 INFO - '_': '/tools/buildbot/bin/python'} 07:03:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:03:46 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 07:03:46 INFO - Cleaning up... 07:03:46 INFO - Return code: 0 07:03:46 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:03:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:03:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:03:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:03:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:03:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:03:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:03:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fc2c1c50f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b178a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2be4ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2be1f60>, '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 0x2be2d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be31d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1476453806.485331-1094630735', '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 07:03:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:03:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:03:46 INFO - Using env: {'DISPLAY': ':0', 07:03:46 INFO - 'HOME': '/home/cltbld', 07:03:46 INFO - 'LANG': 'en_US.UTF-8', 07:03:46 INFO - 'LANGUAGE': 'en_US:en', 07:03:46 INFO - 'LOGNAME': 'cltbld', 07:03:46 INFO - 'MAIL': '/var/mail/cltbld', 07:03:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:03:46 INFO - 'MOZ_NO_REMOTE': '1', 07:03:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:03:46 INFO - 'NO_EM_RESTART': '1', 07:03:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:03:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:03:46 INFO - 'PWD': '/builds/slave/test', 07:03:46 INFO - 'SHELL': '/bin/bash', 07:03:46 INFO - 'SHLVL': '1', 07:03:46 INFO - 'TERM': 'linux', 07:03:46 INFO - 'TMOUT': '86400', 07:03:46 INFO - 'USER': 'cltbld', 07:03:46 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1476453806.485331-1094630735', 07:03:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:03:46 INFO - '_': '/tools/buildbot/bin/python'} 07:03:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:03:46 INFO - Downloading/unpacking psutil>=3.1.1 07:03:46 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 07:03:46 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 07:03:46 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 07:03:46 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 07:03:46 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 07:03:46 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 07:03:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:03:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:03:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:03:48 INFO - Installing collected packages: psutil 07:03:48 INFO - Running setup.py install for psutil 07:03:48 INFO - building 'psutil._psutil_linux' extension 07:03:48 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 07:03:48 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 07:03:49 INFO - building 'psutil._psutil_posix' extension 07:03:49 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 07:03:49 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 07:03:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:03:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:03:49 INFO - Successfully installed psutil 07:03:49 INFO - Cleaning up... 07:03:49 INFO - Return code: 0 07:03:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:03:49 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')]} 07:03:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:03:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:03:49 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')]} 07:03:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:03:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:03:49 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 0x7fc2c1c50f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b178a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2be4ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2be1f60>, '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 0x2be2d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be31d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1476453806.485331-1094630735', '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 07:03:49 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 07:03:49 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 07:03:49 INFO - Using env: {'DISPLAY': ':0', 07:03:49 INFO - 'HOME': '/home/cltbld', 07:03:49 INFO - 'LANG': 'en_US.UTF-8', 07:03:49 INFO - 'LANGUAGE': 'en_US:en', 07:03:49 INFO - 'LOGNAME': 'cltbld', 07:03:49 INFO - 'MAIL': '/var/mail/cltbld', 07:03:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:03:49 INFO - 'MOZ_NO_REMOTE': '1', 07:03:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:03:49 INFO - 'NO_EM_RESTART': '1', 07:03:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:03:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:03:49 INFO - 'PWD': '/builds/slave/test', 07:03:49 INFO - 'SHELL': '/bin/bash', 07:03:49 INFO - 'SHLVL': '1', 07:03:49 INFO - 'TERM': 'linux', 07:03:49 INFO - 'TMOUT': '86400', 07:03:49 INFO - 'USER': 'cltbld', 07:03:49 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1476453806.485331-1094630735', 07:03:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:03:49 INFO - '_': '/tools/buildbot/bin/python'} 07:03:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:03:49 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:03:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:03:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:03:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:03:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:03:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:03:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:03:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:03:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:03:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:03:51 INFO - Installing collected packages: mozsystemmonitor 07:03:51 INFO - Running setup.py install for mozsystemmonitor 07:03:51 INFO - Successfully installed mozsystemmonitor 07:03:51 INFO - Cleaning up... 07:03:51 INFO - Return code: 0 07:03:51 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:03:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:03:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:03:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:03:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:03:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:03:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:03:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2c1c50f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b178a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2be4ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2be1f60>, '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 0x2be2d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be31d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1476453806.485331-1094630735', '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 07:03:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:03:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:03:51 INFO - Using env: {'DISPLAY': ':0', 07:03:51 INFO - 'HOME': '/home/cltbld', 07:03:51 INFO - 'LANG': 'en_US.UTF-8', 07:03:51 INFO - 'LANGUAGE': 'en_US:en', 07:03:51 INFO - 'LOGNAME': 'cltbld', 07:03:51 INFO - 'MAIL': '/var/mail/cltbld', 07:03:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:03:51 INFO - 'MOZ_NO_REMOTE': '1', 07:03:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:03:51 INFO - 'NO_EM_RESTART': '1', 07:03:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:03:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:03:51 INFO - 'PWD': '/builds/slave/test', 07:03:51 INFO - 'SHELL': '/bin/bash', 07:03:51 INFO - 'SHLVL': '1', 07:03:51 INFO - 'TERM': 'linux', 07:03:51 INFO - 'TMOUT': '86400', 07:03:51 INFO - 'USER': 'cltbld', 07:03:51 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1476453806.485331-1094630735', 07:03:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:03:51 INFO - '_': '/tools/buildbot/bin/python'} 07:03:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:03:51 INFO - Downloading/unpacking jsonschema==2.5.1 07:03:51 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 07:03:51 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 07:03:51 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 07:03:51 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 07:03:51 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 07:03:51 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 07:03:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:03:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:03:53 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 07:03:53 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 07:03:53 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 07:03:53 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 07:03:53 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 07:03:53 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 07:03:53 INFO - Downloading functools32-3.2.3-2.tar.gz 07:03:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:03:54 INFO - warning: no files found matching '*.txt' 07:03:54 INFO - no previously-included directories found matching 'build' 07:03:54 INFO - no previously-included directories found matching 'dist' 07:03:54 INFO - no previously-included directories found matching '.git*' 07:03:54 INFO - Installing collected packages: jsonschema, functools32 07:03:54 INFO - Running setup.py install for functools32 07:03:54 INFO - warning: no files found matching '*.txt' 07:03:54 INFO - no previously-included directories found matching 'build' 07:03:54 INFO - no previously-included directories found matching 'dist' 07:03:54 INFO - no previously-included directories found matching '.git*' 07:03:54 INFO - Successfully installed jsonschema functools32 07:03:54 INFO - Cleaning up... 07:03:54 INFO - Return code: 0 07:03:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:03:54 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')]} 07:03:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:03:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:03:54 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')]} 07:03:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:03:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:03:54 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 0x7fc2c1c50f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b178a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2be4ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2be1f60>, '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 0x2be2d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be31d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1476453806.485331-1094630735', '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 07:03:54 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 07:03:54 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 07:03:54 INFO - Using env: {'DISPLAY': ':0', 07:03:54 INFO - 'HOME': '/home/cltbld', 07:03:54 INFO - 'LANG': 'en_US.UTF-8', 07:03:54 INFO - 'LANGUAGE': 'en_US:en', 07:03:54 INFO - 'LOGNAME': 'cltbld', 07:03:54 INFO - 'MAIL': '/var/mail/cltbld', 07:03:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:03:54 INFO - 'MOZ_NO_REMOTE': '1', 07:03:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:03:54 INFO - 'NO_EM_RESTART': '1', 07:03:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:03:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:03:54 INFO - 'PWD': '/builds/slave/test', 07:03:54 INFO - 'SHELL': '/bin/bash', 07:03:54 INFO - 'SHLVL': '1', 07:03:54 INFO - 'TERM': 'linux', 07:03:54 INFO - 'TMOUT': '86400', 07:03:54 INFO - 'USER': 'cltbld', 07:03:54 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1476453806.485331-1094630735', 07:03:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:03:54 INFO - '_': '/tools/buildbot/bin/python'} 07:03:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:03:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:03:54 INFO - Cleaning up... 07:03:54 INFO - Return code: 0 07:03:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:03:54 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')]} 07:03:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:03:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:03:54 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')]} 07:03:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:03:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:03:54 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 0x7fc2c1c50f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b178a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2be4ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2be1f60>, '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 0x2be2d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be31d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1476453806.485331-1094630735', '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 07:03:54 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 07:03:54 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 07:03:54 INFO - Using env: {'DISPLAY': ':0', 07:03:54 INFO - 'HOME': '/home/cltbld', 07:03:54 INFO - 'LANG': 'en_US.UTF-8', 07:03:54 INFO - 'LANGUAGE': 'en_US:en', 07:03:54 INFO - 'LOGNAME': 'cltbld', 07:03:54 INFO - 'MAIL': '/var/mail/cltbld', 07:03:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:03:54 INFO - 'MOZ_NO_REMOTE': '1', 07:03:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:03:54 INFO - 'NO_EM_RESTART': '1', 07:03:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:03:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:03:54 INFO - 'PWD': '/builds/slave/test', 07:03:54 INFO - 'SHELL': '/bin/bash', 07:03:54 INFO - 'SHLVL': '1', 07:03:54 INFO - 'TERM': 'linux', 07:03:54 INFO - 'TMOUT': '86400', 07:03:54 INFO - 'USER': 'cltbld', 07:03:54 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1476453806.485331-1094630735', 07:03:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:03:54 INFO - '_': '/tools/buildbot/bin/python'} 07:03:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:03:54 INFO - Downloading/unpacking blobuploader==1.2.4 07:03:54 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 07:03:54 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 07:03:54 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 07:03:54 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 07:03:54 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 07:03:54 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 07:03:56 INFO - Downloading blobuploader-1.2.4.tar.gz 07:03:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:03:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:03:56 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 07:03:56 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 07:03:56 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 07:03:56 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 07:03:56 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 07:03:56 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 07:03:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:03:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:03:57 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 07:03:57 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 07:03:57 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 07:03:57 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 07:03:57 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 07:03:57 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 07:03:57 INFO - Downloading docopt-0.6.1.tar.gz 07:03:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:03:57 INFO - Installing collected packages: blobuploader, requests, docopt 07:03:57 INFO - Running setup.py install for blobuploader 07:03:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:03:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:03:57 INFO - Running setup.py install for requests 07:03:57 INFO - Running setup.py install for docopt 07:03:57 INFO - Successfully installed blobuploader requests docopt 07:03:57 INFO - Cleaning up... 07:03:57 INFO - Return code: 0 07:03:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:03:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:03:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:03:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:03:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:03:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:03:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:03:57 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 0x7fc2c1c50f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b178a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2be4ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2be1f60>, '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 0x2be2d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be31d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1476453806.485331-1094630735', '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 07:03:57 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 07:03:57 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 07:03:57 INFO - Using env: {'DISPLAY': ':0', 07:03:57 INFO - 'HOME': '/home/cltbld', 07:03:57 INFO - 'LANG': 'en_US.UTF-8', 07:03:57 INFO - 'LANGUAGE': 'en_US:en', 07:03:57 INFO - 'LOGNAME': 'cltbld', 07:03:57 INFO - 'MAIL': '/var/mail/cltbld', 07:03:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:03:57 INFO - 'MOZ_NO_REMOTE': '1', 07:03:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:03:57 INFO - 'NO_EM_RESTART': '1', 07:03:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:03:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:03:57 INFO - 'PWD': '/builds/slave/test', 07:03:57 INFO - 'SHELL': '/bin/bash', 07:03:57 INFO - 'SHLVL': '1', 07:03:57 INFO - 'TERM': 'linux', 07:03:57 INFO - 'TMOUT': '86400', 07:03:57 INFO - 'USER': 'cltbld', 07:03:57 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1476453806.485331-1094630735', 07:03:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:03:57 INFO - '_': '/tools/buildbot/bin/python'} 07:03:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:03:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:03:58 INFO - Running setup.py (path:/tmp/pip-kuLA4j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:03:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:03:58 INFO - Running setup.py (path:/tmp/pip-4OMWAH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:03:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:03:58 INFO - Running setup.py (path:/tmp/pip-v4fgcV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:03:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:03:58 INFO - Running setup.py (path:/tmp/pip-j7sEc9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:03:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:03:58 INFO - Running setup.py (path:/tmp/pip-xR2dKU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:03:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:03:58 INFO - Running setup.py (path:/tmp/pip-OsyxXm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:03:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:03:58 INFO - Running setup.py (path:/tmp/pip-AL6tEO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:03:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:03:58 INFO - Running setup.py (path:/tmp/pip-LETA0f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:03:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:03:58 INFO - Running setup.py (path:/tmp/pip-4R7yxF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:03:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:03:58 INFO - Running setup.py (path:/tmp/pip-rck2MC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:03:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:03:58 INFO - Running setup.py (path:/tmp/pip-Hyke1p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:03:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:03:58 INFO - Running setup.py (path:/tmp/pip-vOYW_p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:03:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:03:59 INFO - Running setup.py (path:/tmp/pip-2lGqGt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:03:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:03:59 INFO - Running setup.py (path:/tmp/pip-XMAguf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:03:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:03:59 INFO - Running setup.py (path:/tmp/pip-tHyv3s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:03:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:03:59 INFO - Running setup.py (path:/tmp/pip-Nk3qYY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:03:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:03:59 INFO - Running setup.py (path:/tmp/pip-EZoBDT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:03:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:03:59 INFO - Running setup.py install for manifestparser 07:03:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:03:59 INFO - Running setup.py install for mozcrash 07:03:59 INFO - Running setup.py install for mozdebug 07:03:59 INFO - Running setup.py install for mozdevice 07:03:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:03:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:03:59 INFO - Running setup.py install for mozfile 07:03:59 INFO - Running setup.py install for mozhttpd 07:04:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:04:00 INFO - Running setup.py install for mozinfo 07:04:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:04:00 INFO - Running setup.py install for mozInstall 07:04:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:04:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:04:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:04:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:04:00 INFO - Running setup.py install for mozleak 07:04:00 INFO - Running setup.py install for mozlog 07:04:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:04:00 INFO - Running setup.py install for moznetwork 07:04:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:04:00 INFO - Running setup.py install for mozprocess 07:04:00 INFO - Running setup.py install for mozprofile 07:04:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:04:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:04:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:04:00 INFO - Running setup.py install for mozrunner 07:04:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:04:00 INFO - Running setup.py install for mozscreenshot 07:04:01 INFO - Running setup.py install for moztest 07:04:01 INFO - Running setup.py install for mozversion 07:04:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:04:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:04:01 INFO - Cleaning up... 07:04:01 INFO - Return code: 0 07:04:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:04:01 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')]} 07:04:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:04:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:04:01 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')]} 07:04:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:04:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:04:01 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 0x7fc2c1c50f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b178a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2be4ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2be1f60>, '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 0x2be2d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be31d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1476453806.485331-1094630735', '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 07:04:01 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 07:04:01 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 07:04:01 INFO - Using env: {'DISPLAY': ':0', 07:04:01 INFO - 'HOME': '/home/cltbld', 07:04:01 INFO - 'LANG': 'en_US.UTF-8', 07:04:01 INFO - 'LANGUAGE': 'en_US:en', 07:04:01 INFO - 'LOGNAME': 'cltbld', 07:04:01 INFO - 'MAIL': '/var/mail/cltbld', 07:04:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:04:01 INFO - 'MOZ_NO_REMOTE': '1', 07:04:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:04:01 INFO - 'NO_EM_RESTART': '1', 07:04:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:04:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:04:01 INFO - 'PWD': '/builds/slave/test', 07:04:01 INFO - 'SHELL': '/bin/bash', 07:04:01 INFO - 'SHLVL': '1', 07:04:01 INFO - 'TERM': 'linux', 07:04:01 INFO - 'TMOUT': '86400', 07:04:01 INFO - 'USER': 'cltbld', 07:04:01 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1476453806.485331-1094630735', 07:04:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:04:01 INFO - '_': '/tools/buildbot/bin/python'} 07:04:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:04:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:04:01 INFO - Running setup.py (path:/tmp/pip-LYZlxv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:04:01 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)) 07:04:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:04:01 INFO - Running setup.py (path:/tmp/pip-hhaJQU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:04:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:04:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:04:01 INFO - Running setup.py (path:/tmp/pip-E4uwj6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:04:01 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)) 07:04:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:04:01 INFO - Running setup.py (path:/tmp/pip-2giv5i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:04:01 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)) 07:04:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:04:01 INFO - Running setup.py (path:/tmp/pip-Dd577Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:04:01 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)) 07:04:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:04:01 INFO - Running setup.py (path:/tmp/pip-Yi0vpx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:04:01 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)) 07:04:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:04:01 INFO - Running setup.py (path:/tmp/pip-rKqCzY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:04:01 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)) 07:04:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:04:01 INFO - Running setup.py (path:/tmp/pip-29fXBd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:04:02 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)) 07:04:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:04:02 INFO - Running setup.py (path:/tmp/pip-MoX4gT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:04:02 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)) 07:04:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:04:02 INFO - Running setup.py (path:/tmp/pip-ZV3BTj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:04:02 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)) 07:04:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:04:02 INFO - Running setup.py (path:/tmp/pip-PVCjyg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:04:02 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)) 07:04:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:04:02 INFO - Running setup.py (path:/tmp/pip-f8NC91-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:04:02 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)) 07:04:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:04:02 INFO - Running setup.py (path:/tmp/pip-XQoaVG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:04:02 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)) 07:04:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:04:02 INFO - Running setup.py (path:/tmp/pip-X6DbXT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:04:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 07:04:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:04:02 INFO - Running setup.py (path:/tmp/pip-dYmuYa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:04:02 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)) 07:04:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:04:02 INFO - Running setup.py (path:/tmp/pip-oxJmEz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:04:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 07:04:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:04:02 INFO - Running setup.py (path:/tmp/pip-UBmD6X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:04:02 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)) 07:04:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:04:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:04:02 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)) 07:04:02 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)) 07:04:02 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)) 07:04:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:04:02 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 07:04:02 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 07:04:02 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 07:04:02 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 07:04:02 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 07:04:02 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 07:04:04 INFO - Downloading blessings-1.6.tar.gz 07:04:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:04:04 INFO - Installing collected packages: blessings 07:04:04 INFO - Running setup.py install for blessings 07:04:04 INFO - Successfully installed blessings 07:04:04 INFO - Cleaning up... 07:04:04 INFO - Return code: 0 07:04:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:04:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:04:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:04:05 INFO - Reading from file tmpfile_stdout 07:04:05 INFO - Current package versions: 07:04:05 INFO - argparse == 1.2.1 07:04:05 INFO - blessings == 1.6 07:04:05 INFO - blobuploader == 1.2.4 07:04:05 INFO - docopt == 0.6.1 07:04:05 INFO - functools32 == 3.2.3-2 07:04:05 INFO - jsonschema == 2.5.1 07:04:05 INFO - manifestparser == 1.1 07:04:05 INFO - mozInstall == 1.12 07:04:05 INFO - mozcrash == 0.17 07:04:05 INFO - mozdebug == 0.1 07:04:05 INFO - mozdevice == 0.48 07:04:05 INFO - mozfile == 1.2 07:04:05 INFO - mozhttpd == 0.7 07:04:05 INFO - mozinfo == 0.9 07:04:05 INFO - mozleak == 0.1 07:04:05 INFO - mozlog == 3.4 07:04:05 INFO - moznetwork == 0.27 07:04:05 INFO - mozprocess == 0.23 07:04:05 INFO - mozprofile == 0.28 07:04:05 INFO - mozrunner == 6.12 07:04:05 INFO - mozscreenshot == 0.1 07:04:05 INFO - mozsystemmonitor == 0.3 07:04:05 INFO - moztest == 0.7 07:04:05 INFO - mozversion == 1.4 07:04:05 INFO - psutil == 3.1.1 07:04:05 INFO - requests == 1.2.3 07:04:05 INFO - wsgiref == 0.1.2 07:04:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:04:05 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')]} 07:04:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:04:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:04:05 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')]} 07:04:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:04:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:04:05 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 0x7fc2c1c50f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b178a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2be4ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2be1f60>, '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 0x2be2d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be31d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1476453806.485331-1094630735', '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 07:04:05 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 07:04:05 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 07:04:05 INFO - Using env: {'DISPLAY': ':0', 07:04:05 INFO - 'HOME': '/home/cltbld', 07:04:05 INFO - 'LANG': 'en_US.UTF-8', 07:04:05 INFO - 'LANGUAGE': 'en_US:en', 07:04:05 INFO - 'LOGNAME': 'cltbld', 07:04:05 INFO - 'MAIL': '/var/mail/cltbld', 07:04:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:04:05 INFO - 'MOZ_NO_REMOTE': '1', 07:04:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:04:05 INFO - 'NO_EM_RESTART': '1', 07:04:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:04:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:04:05 INFO - 'PWD': '/builds/slave/test', 07:04:05 INFO - 'SHELL': '/bin/bash', 07:04:05 INFO - 'SHLVL': '1', 07:04:05 INFO - 'TERM': 'linux', 07:04:05 INFO - 'TMOUT': '86400', 07:04:05 INFO - 'USER': 'cltbld', 07:04:05 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1476453806.485331-1094630735', 07:04:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:04:05 INFO - '_': '/tools/buildbot/bin/python'} 07:04:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:04:05 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)) 07:04:05 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)) 07:04:05 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)) 07:04:05 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)) 07:04:05 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)) 07:04:05 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)) 07:04:05 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)) 07:04:05 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)) 07:04:05 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)) 07:04:05 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)) 07:04:05 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)) 07:04:05 INFO - Cleaning up... 07:04:05 INFO - Return code: 0 07:04:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:04:05 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')]} 07:04:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:04:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:04:05 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')]} 07:04:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:04:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:04:05 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 0x7fc2c1c50f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b178a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2be4ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2be1f60>, '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 0x2be2d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be31d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1476453806.485331-1094630735', '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 07:04:05 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 07:04:05 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 07:04:05 INFO - Using env: {'DISPLAY': ':0', 07:04:05 INFO - 'HOME': '/home/cltbld', 07:04:05 INFO - 'LANG': 'en_US.UTF-8', 07:04:05 INFO - 'LANGUAGE': 'en_US:en', 07:04:05 INFO - 'LOGNAME': 'cltbld', 07:04:05 INFO - 'MAIL': '/var/mail/cltbld', 07:04:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:04:05 INFO - 'MOZ_NO_REMOTE': '1', 07:04:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:04:05 INFO - 'NO_EM_RESTART': '1', 07:04:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:04:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:04:05 INFO - 'PWD': '/builds/slave/test', 07:04:05 INFO - 'SHELL': '/bin/bash', 07:04:05 INFO - 'SHLVL': '1', 07:04:05 INFO - 'TERM': 'linux', 07:04:05 INFO - 'TMOUT': '86400', 07:04:05 INFO - 'USER': 'cltbld', 07:04:05 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1476453806.485331-1094630735', 07:04:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:04:05 INFO - '_': '/tools/buildbot/bin/python'} 07:04:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:04:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:04:05 INFO - Cleaning up... 07:04:05 INFO - Return code: 0 07:04:05 INFO - Running post-action listener: _resource_record_post_action 07:04:05 INFO - Running post-action listener: _start_resource_monitoring 07:04:05 INFO - Starting resource monitoring. 07:04:05 INFO - [mozharness: 2016-10-14 14:04:05.597493Z] Finished create-virtualenv step (success) 07:04:05 INFO - [mozharness: 2016-10-14 14:04:05.597966Z] Running install step. 07:04:05 INFO - Running pre-action listener: _resource_record_pre_action 07:04:05 INFO - Running main action method: install 07:04:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:04:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:04:05 INFO - Reading from file tmpfile_stdout 07:04:05 INFO - Detecting whether we're running mozinstall >=1.0... 07:04:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:04:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:04:05 INFO - Reading from file tmpfile_stdout 07:04:05 INFO - Output received: 07:04:05 INFO - Usage: mozinstall [options] installer 07:04:05 INFO - Options: 07:04:05 INFO - -h, --help show this help message and exit 07:04:05 INFO - -d DEST, --destination=DEST 07:04:05 INFO - Directory to install application into. [default: 07:04:05 INFO - "/builds/slave/test"] 07:04:05 INFO - --app=APP Application being installed. [default: firefox] 07:04:05 INFO - mkdir: /builds/slave/test/build/application 07:04:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 07:04:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 07:04:22 INFO - Reading from file tmpfile_stdout 07:04:22 INFO - Output received: 07:04:22 INFO - /builds/slave/test/build/application/firefox/firefox 07:04:22 INFO - Running post-action listener: _resource_record_post_action 07:04:22 INFO - [mozharness: 2016-10-14 14:04:22.381828Z] Finished install step (success) 07:04:22 INFO - [mozharness: 2016-10-14 14:04:22.382077Z] Running run-tests step. 07:04:22 INFO - Running pre-action listener: _resource_record_pre_action 07:04:22 INFO - Running main action method: run_tests 07:04:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:04:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:04:22 INFO - Python 2.7.3 07:04:22 INFO - Return code: 0 07:04:22 INFO - grabbing minidump binary from tooltool 07:04:22 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')]} 07:04:22 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 0x2be1f60>, '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 0x2be2d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be31d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:04:22 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 07:04:22 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 07:04:22 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 07:04:22 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:04:22 INFO - Return code: 0 07:04:22 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:04:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:04:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:04:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:04:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:04:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AG3-GhfwRxqUJt864HcpdQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 07:04:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/AG3-GhfwRxqUJt864HcpdQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-035 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 07:04:22 INFO - Using env: {'DISPLAY': ':0', 07:04:22 INFO - 'HOME': '/home/cltbld', 07:04:22 INFO - 'LANG': 'en_US.UTF-8', 07:04:22 INFO - 'LANGUAGE': 'en_US:en', 07:04:22 INFO - 'LOGNAME': 'cltbld', 07:04:22 INFO - 'MAIL': '/var/mail/cltbld', 07:04:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:04:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:04:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:04:22 INFO - 'MOZ_NO_REMOTE': '1', 07:04:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:04:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:04:22 INFO - 'NO_EM_RESTART': '1', 07:04:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:04:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:04:22 INFO - 'PWD': '/builds/slave/test', 07:04:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:04:22 INFO - 'SHELL': '/bin/bash', 07:04:22 INFO - 'SHLVL': '1', 07:04:22 INFO - 'TERM': 'linux', 07:04:22 INFO - 'TMOUT': '86400', 07:04:22 INFO - 'USER': 'cltbld', 07:04:22 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1476453806.485331-1094630735', 07:04:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:04:22 INFO - '_': '/tools/buildbot/bin/python'} 07:04:22 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AG3-GhfwRxqUJt864HcpdQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 07:04:22 INFO - mozversion application_buildid: 20161014053035 07:04:22 INFO - mozversion application_changeset: 3d5379dbab72482bea7cbfbb503de9f70672a897 07:04:22 INFO - mozversion application_display_name: Nightly 07:04:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:04:22 INFO - mozversion application_name: Firefox 07:04:22 INFO - mozversion application_remotingname: firefox 07:04:22 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 07:04:22 INFO - mozversion application_vendor: Mozilla 07:04:22 INFO - mozversion application_version: 52.0a1 07:04:22 INFO - mozversion platform_buildid: 20161014053035 07:04:22 INFO - mozversion platform_changeset: 3d5379dbab72482bea7cbfbb503de9f70672a897 07:04:22 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 07:04:22 INFO - mozversion platform_version: 52.0a1 07:04:22 INFO - using testdate: 1476453862 07:04:22 INFO - actual date: 1476453862 07:04:22 INFO - starting webserver on 'localhost:37998' 07:04:22 INFO - SUITE-START | Running 2 tests 07:04:22 INFO - TEST-START | dromaeo_css 07:04:22 INFO - operating with platform_type : linux_ 07:04:22 INFO - Initialising browser for dromaeo_css test... 07:04:22 INFO - TEST-INFO | started process 11976 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwpmc8V/profile http://localhost:37998/getInfo.html) 07:04:26 INFO - PROCESS | 11976 | __metrics Screen width/height:1600/1200 07:04:26 INFO - PROCESS | 11976 | colorDepth:24 07:04:26 INFO - PROCESS | 11976 | Browser inner width/height: 1024/697 07:04:26 INFO - PROCESS | 11976 | __metrics 07:04:27 INFO - PROCESS | 11976 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:04:27 INFO - PROCESS | 11976 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:04:27 INFO - PROCESS | 11976 | console.error: PushService: 07:04:27 INFO - PROCESS | 11976 | stateChangeProcessEnqueue: Error transitioning state 07:04:27 INFO - PROCESS | 11976 | UnknownError 07:04:28 INFO - TEST-INFO | 11976: exit 0 07:04:28 INFO - Browser initialized. 07:04:28 INFO - Running cycle 1/1 for dromaeo_css test... 07:04:28 INFO - TEST-INFO | started process 12058 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwpmc8V/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:04:40 INFO - PROCESS | 12058 | RSS: Main: 174092288 07:04:40 INFO - PROCESS | 12058 | 07:05:32 INFO - PROCESS | 12058 | 1476453932292 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 07:07:46 INFO - PROCESS | 12058 | Cycle 1(1): loaded http://localhost:37998/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37998/tests/dromaeo/cssquery-ext.html) 07:07:47 INFO - PROCESS | 12058 | RSS: Main: 310042624 07:07:47 INFO - PROCESS | 12058 | 07:10:50 INFO - PROCESS | 12058 | Cycle 1(1): loaded http://localhost:37998/tests/dromaeo/cssquery-ext.html (next: http://localhost:37998/tests/dromaeo/cssquery-jquery.html) 07:10:50 INFO - PROCESS | 12058 | RSS: Main: 284835840 07:10:50 INFO - PROCESS | 12058 | 07:13:53 INFO - PROCESS | 12058 | Cycle 1(1): loaded http://localhost:37998/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37998/tests/dromaeo/cssquery-mootools.html) 07:13:53 INFO - PROCESS | 12058 | RSS: Main: 219365376 07:13:53 INFO - PROCESS | 12058 | 07:16:56 INFO - PROCESS | 12058 | Cycle 1(1): loaded http://localhost:37998/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37998/tests/dromaeo/cssquery-prototype.html) 07:16:56 INFO - PROCESS | 12058 | RSS: Main: 245858304 07:16:56 INFO - PROCESS | 12058 | 07:19:59 INFO - PROCESS | 12058 | Cycle 1(1): loaded http://localhost:37998/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37998/tests/dromaeo/cssquery-yui.html) 07:20:00 INFO - PROCESS | 12058 | RSS: Main: 219774976 07:20:00 INFO - PROCESS | 12058 | 07:23:03 INFO - PROCESS | 12058 | Cycle 1(1): loaded http://localhost:37998/tests/dromaeo/cssquery-yui.html (next: http://localhost:37998/tests/dromaeo/cssquery-dojo.html) 07:23:03 INFO - PROCESS | 12058 | RSS: Main: 301535232 07:23:03 INFO - PROCESS | 12058 | 07:23:03 INFO - PROCESS | 12058 | __start_tp_report 07:23:03 INFO - PROCESS | 12058 | _x_x_mozilla_page_load 07:23:03 INFO - PROCESS | 12058 | _x_x_mozilla_page_load_details 07:23:03 INFO - PROCESS | 12058 | |i|pagename|runs| 07:23:03 INFO - PROCESS | 12058 | |0;dojo.html;2543.415014546654;2558.9232323030315;2591.4946585415846;2592.4815036992595;2605.3226161198095;4488.573585509372;4590.885227869297;4624.237000894853;4679.789409476577;4684.6252299816015;4584.495705472404;4634.1195172917205;4665.276882083274;4705.552972467619;4745.928811067837;4215.451991241134;4232.640225580829;4270.3807947847545;4277.3156294992805;4287.8070486828165;4317.805698743564;4335.241332766769;4343.891402714926;4354.1074079813725;4406.030673251879;4309.073549186908;4339.500957389909;4355.433793606827;4364.519902810688;4394.714343567658;4167.9583204167875;4210.389493523453;4214.852480163179;4261.403403523509;4279.807408666617;4239.236937351276;4249.575042495757;4254.149170165948;4261.573842615745;4264.87205383839;4630.259158534652;4657.743824089674;4666.929996050062;4668.136394766979;4675.88310292242;4931.876703082416;4978.6514943953935;4981.402231732215;4983.327250821129;4984.4267909190185;5010.048090862724;5016.698998060128;5017.64876458648;5019.171836646985;5025.547700706954;4580.290055041474;4668.906621867543;4669.276262179367;4672.462666793294;4672.462666793328;4930.235813448921;4992.700437973734;5006.1739812930455;5015.4733752956145;5015.498450155028;4873.366462359871;4892.926606100911;4893.143699852582;4897.240927656219;4950.801967921243;4872.82945096927;4934.9013019739405;4968.105740966661;4970.2296144097;5040.722760248148;4933.062718083553;4948.331975183376;4993.550580447777;5021.397432308073;5057.620678449131;4941.901161976813;4958.851234462972;4963.503649635008;4971.875703107379;5046.268291097844;4118.814653340607;4195.098053918422;4197.643200328006;4281.828726850953;4326.870193894189;3643.635636436335;3669.5963444021136;3679.6504332088407;3682.8895133146048;3717.5724791648768;4326.805293761789;4327.307630779061;4336.241157797373;4382.9342559861625;4387.254166791653;4893.241547560134;5022.9748851256245;5028.145215313405;5028.296038554606;5031.371078615173;3925.3326934421216;4265.210935976854;4271.594198551133;4289.699721019561;4331.198728235287;4508.887277818081;4523.954760452353;4553.043860789205;4562.794674239667;4567.200739870509;4657.604103651228;4661.184292748688;4662.626989840736;4669.346291519122;4707.529247075334;4868.123737727243;4919.335889654851;4996.200607902718;5007.073691367109;5057.797688092435;4596.3283120088945;4642.489326174185;4684.156851766715;4699.530046995274;4755.738434386004;4949.777260023236;4952.851414457572;4965.875853103701;5007.8748031298055;5024.020316038337;5019.272205530105;5019.548240658358;5020.523050310215;5030.0694371541385;5049.84850454487;4749.9050019000115;4798.49615790337;4811.08589368019;4860.3681521401995;4882.7558622068045;4798.424189097191;4832.226843704991;4861.295112208639;4863.027394521039;4872.123017856678;4769.141554520081;4797.424309082932;4818;4837;4887.682300650446;4912.803487860589;4918.458969513279;4922.409310882717;4922.655414120977;4980.576650984562;4652.744099074584;4809.5430934061205;4810.927836082532;4811.615070794398;4869.975650121727 07:23:03 INFO - PROCESS | 12058 | |1;ext.html;542.5822116969933;566.2723400430448;570.113473548632;572.9484346408823;574.0786038408352;26047.218583442504;26511.337216569595;36347.091322716915;37882.8105859471;38027;37735.24529509408;37903.62096379035;38097.42853857194;38106.8094659527;38180.85457436275;7025.332593403644;7453.590052547108;7588.051493563305;7592.278733520307;7596.240375962415;7051.048108505363;7271.927280727205;7278.490505664606;7278.599677017762;9564.282678799083;9602.039796020377;9939.453330066843;9949.104580587746;9949.403035817873;9958.153556947667;9867.309288349783;9922.801543969152;9943.154831839302;9943.204543636528;9944.45305508204;9616.90383096167;9675.080867317663;9677.225821934227;9682.46746428946;9684.61261549537;35834.46248306277;35846.28307433837;35848.28303433917;35946;36277.81861090704;2958.1421387797513;3000.684928082542;3033.1658793831652;3156.305612765188;3233.143217047484;26902.730972690217;27204.47092697758;27347.316317092034;28185.436291274265;28713.712862871314;3222.6938440848317;3314.8176850273226;3439.9656003439895;3502.5446691929887;3537.9115522111897;217792;217822;218244;218927;219162;29570.704292956794;50588.747056265216;51006.74496627493;51146;51157.48842511602;30342;31676.683233167834;31812.840935795637;31897.52153717696;31900;11969.401529923469;12006.459709313101;12020.939895300467;12023.158378913568;12028.398580070962;10441.634542790965;10496.790064198673;10510;10511.10655594283;10511.789764204674;6934.965325173443;7186.38915692172;7278.235785242579;7621.618919054025;7692.269234422721;2997.385535965122;3029.697230191039;3045.6040714706946;3051.1456792098247;3095.8297293648616;807.3460496997451;842.7598134531752;846.1369403208693;883.8409086364516;981.9067188617213;25175.748242517708;26410.6038409424;26562.86718566395;26833.865830670722;26922.32694182661;882.3162049411757;903.503073309677;923.1645360948353;956.6795123633522;962.451402700454;14038.578842634737;14191.71616567663;14245.85754142466;14602.707945841024;14652.926735366467;333777;337823;337829;338300;338743;46520.3021954671;46722;46861.297080543816;46922.29616555754;47020.059598807835;1132.4337831084458;1134.8638163420442;1154.5958914380067;1161.33223396549;1162.1167912386477;25008.62487062659;25164.748352516974;25313.240602781945;25485.872570637028;26075.217743467725;38961.41557876691;39191.804040979616;39234;39246.4113038299;39338.2132357357;278757;279627.6018619894;279930;280624;280936;23638.409039774142;23907.88046059828;23976.760232397453;24001.399965001016;24004.27987160388;37917.68177946833;38478.80760596179;38896.416553752286;39007.804960975016;39008.609913900495;32030.35939281248;33053;33218.16954576155;33673.326533469684;33701;32945.505817412275;33062.17344566405;33203.50194747129;33951.49072763861;34597;31802.204944876565;32789;32955.83522082374;33005.834970824995;33015.50476742899;27459;29352;30721;31000.68999309978;31143;23630.645540316564;23751.762482375645;23810.761892381546;24037.158699445434;24041.51916961686 07:23:03 INFO - PROCESS | 12058 | |2;jquery.html;341.2798994122403;355.14055984517466;362.26098758532595;363.1665328072075;385.3545311603066;11949.64151075468;12939.029572782043;15565.688686226284;15841.841581584209;15986.440474583393;13362.19826810392;13914.791278130811;14089.084209526374;14140.717185656307;14272.928635356835;4523.321501774727;4684.789184486689;4687.343771871923;4688.765561721917;4698.2482802751565;4470.396496472983;4610.723356598598;4614;4634.930476042862;4638.026014536951;4625.560571745696;4626.421697287839;4628.352030715702;4628.467726211495;4629.074185162964;4586.7477288749105;4613.677042607019;4621.699589526686;4892.241702536113;4923.236898280772;4420.690551661385;4422.491413487458;4422.933655995163;4423.933640995388;4427.977860110711;17816.287348506045;19595.314164004332;19754.70367944482;19826.603467930705;19836.801631983784;1658.7511873218994;1775.0858307971405;1787.240422820296;1882.9811701882943;1995.321590659183;8912.509811960339;9066.456012639263;9078.452377986445;9230.630774769;9485.573149208301;1633.4955912489909;1658.2620733773476;1664.25108701085;1666.924988375526;1726.930922763088;59231.40768592302;63777.76777812754;64335;65699.67150164266;66556;9579.185769209695;9807.460589667495;9919.206463483048;10058.748531286776;10215.693529194135;13645.11306765057;13689.383977721027;13690.315484225748;13705.588832335046;13710.45158193681;6806.693698783468;7002.40479559233;7051.29487051301;7224.638768061681;7241;6610.735570577123;6614.305497922649;6819.147606549182;6871.14110736158;6916.688749006206;4208.915821683549;4244.193603215379;4247.702660813775;4285.164392943346;4351.782410879443;1794.7577077094425;1798.2896755781655;1805.395192610464;1841.2911029253835;1847.7967423583395;793.964271607779;810.266708628692;889.9009722992099;918.9816203675889;939.4738946190155;15466.514710733762;16358.182090895407;16615;17164;17315.307387704604;807.184743409161;807.5962018990505;850.3112478892117;859.3898332184094;864.6671031652733;8856.734297971072;8986.101389860962;9070.682526111686;9559.187469065067;9643.807123857483;354080;367076;367116;367859;368547;67969.3203067973;67975;69473.30526694769;69645.30354696389;70865;860.875173099897;873.9431936924079;881.6341218394294;882.1046637662778;882.4661080046608;14397.856021439651;14785;14794.926025369805;14895.92552037233;15437.614059648598;34699.09154996499;35028.64971350254;35682.46476302806;35907;36105.819470902476;41558.376624351265;45820.77089614664;46137.538624614666;48010;48404.27393589028;6803.965980170067;7038.788836334918;7072.787816365517;7216.96391518039;7337.3396394322635;10135.49322533885;11685.298882067103;11717;12052.879471205526;12072.458705524088;9971;10112.696619101438;10139.594416223563;10501.949805019436;10580.682579522627;9958.203343732339;10023.949880250551;10035.046670566284;10122.190224782147;10207.438590877277;9516.333856630246;9671.661491847752;9889.950550247202;10135.189184865341;10294.845577316497;7079.82300442493;7152.177499587614;7417;7426.071741032371;7490.70037198506;8659.87010194835;10006.44964526958;10361.896381036093;10422.895771042497;10933.398663073289 07:23:03 INFO - PROCESS | 12058 | |3;mootools.html;1231.1566576894834;1316.677414033562;1355.2342926246672;1422.9288535573219;1674.5897255172476;6298.212723409574;7215.2063273039985;7478.513896596717;7495.475316727824;7674.424418168645;7575.469717119804;7596.392288616911;7632.122305934819;7639.923600764005;7644.923550764491;2003.7294965179733;2036.6639504481743;2041.397787652646;2041.969370459437;2042.7650820155723;2070.4306315763133;2112.2818241797786;2119.459537817854;2126.5002724359765;2136.935891923245;2051.52814852584;2057.609054279682;2058;2060.3406909788873;2066.7003284523735;2026.7669218039966;2034.5015471209501;2039.836813054952;2042.2239548971445;2044.7750747417772;1962.617289628523;1997.4307322413188;2047.8156965873143;2049.3954283486405;2052.2611859730487;7480.663370148356;7585.810354741176;7586.544807311524;7588.430867684946;7590.24097590242;4344.261475549132;4569.405977222939;4570.314452832068;4591.449026116854;4602.6087782538525;2652.65515482986;2786.4845003674386;2837.8297302161936;2846.103477404611;2858.656961164653;922.7139586728135;927.4620719982462;944.1361154543584;958.8753462049958;960.4813400763579;10097.798044039151;10165.796684066278;10200.183985281234;10201.846972295421;10210.591576336938;3785.3943369060817;3875.7093218008763;4084.591540845892;4107.445494858216;4133.855315063958;8087.393445491612;8107.270345668918;8118.75643730689;8118.918810811816;8123.7156699515235;4555.681102322806;4563.315502674625;4566.74882881438;4567.246404343313;4568.200564901129;3579.409397449444;3709.276691045274;3946.565877753445;4244.702870799015;4251.022264879095;3258.3971965186515;3289.703926646613;3415;3424.143964008998;3431.725461963037;990.6087095597198;994.1351024608637;994.7264502261792;997.3654497553597;1009.4902074452355;636.1348565950304;663.4427081251675;708.9858202835915;735.2867718313228;757.4394947738743;4608.101420223092;4689.742064186503;4863.805447782049;4866.853994380174;5024.949750502448;825.5707032343148;855.9700410485603;856.8286342731354;875.8729984152262;901.6430272439936;3324.7007769300394;3334.2164591321507;3343.331333733263;3350.0619826448633;3402.8468718907707;8782.692605758768;9134.081842813084;9154.771130721785;9170.95414522923;9179.908200918038;8727.912720872837;8729.825403491895;8781.2535934445;8787.868181977276;9074.092590740873;6831.28271531492;6897.379235868796;6946;6947.7220911162985;6995.510314278054;4570.268756998864;4647.395838540968;4691.765411729468;4800.591949684316;4867.63492738039;7944.84110317802;8104.108548059827;8164.142765009826;8230.259276665129;8266.256036956704;8310.54292013945;8405.621747021522;8424.368172387218;8425.873611895704;8478.491290522341;5360.973195133999;5503.422140675171;6128.295246046583;6210.813675589739;6236.314005459487;6807.3873351398615;7053.788386348418;7076.469264805263;7286.344229019413;7288.416926645749;6504.3733378571815;6524.086627872197;6576.704048317933;6667.66661666905;6808.251092379735;6498.512611554247;6539.4768418525455;6549.8690026200165;6620.304867988792;6720.0591917132615;6133.601315914451;6220.440160385587;6221.2845522765465;6223.190985171898;6224.906626400517;3996.200759847984;4068.9186216276103;4088.34586466164;4129.132882094794;4213.662906967496;4150.937735934024;4210.747355158578;4226.704130710944;4311.33174357975;4327.567243275647 07:23:03 INFO - PROCESS | 12058 | |4;prototype.html;292.89894986229746;293.71069496545897;301.3626180627972;305.2947690834172;306.24816076532113;4549.363089167511;4667.673262871596;4695.225287827513;4750.216214324637;4789.712617242968;4545.36364908913;4608.700434471765;4670.579647831694;4685.36747539083;4699.718016918979;3709.7032237421076;3714.0900479382453;3725.3108174987715;3728.310262601414;3736.252749450114;3507.281007393491;3722.478852960574;3723.2925744108534;3732.9066773330746;3737.7570457920147;3441.0558034710434;3696.445533170019;3709.443583462475;3725.795081270529;3726.720495962814;3686.8156592170553;3699.167687270343;3703.759255648374;3713.294474049935;3714.3314203443233;3292.160499072745;3294.637589865113;3295.9340813183603;3296.983515082409;3296.9835150824333;4467.597916187527;4499.280115181588;4514.7291162530355;4581.1753884300815;4585.380973568593;4210.831566737327;4210.978945105255;4217.891054472763;4225.450691410128;4231.640310573605;4842.903141937177;4845.685030473043;4883.9511604883855;4906.386701662293;4914.90170196594;3590.7845529268066;3590.910227244314;3592.982035089834;3616.367135751233;3633;4869.269609558559;5103.923441148386;5124.871878203;5129.743512824344;5148.279240906276;3580.6367797254206;3582.158192824684;3598.2803439312243;3604.621514740967;3611.8555257789394;3660.9864574483927;3661.9267614647556;3669.1377526281303;3670.46778217157;3673.008287762289;4072.490938632671;4116.773577453268;4149.25313443577;4187.225363307798;4195.496540415109;4916.5004673574185;4918.278602489172;4919.3358896549225;4930.827421040318;4933.777979990909;1177.8292147638545;1206.7224538356227;1240.34881687115;1254.2975933477283;1257.3713143428286;841.7222316635496;874.7157173918501;877.7059685005595;879.4151888993747;880.9779755506164;4343.913121737548;4381.145676593097;4381.802818873159;4392.363107349416;4403.3174857897075;5046.470120637354;5066.797328106908;5072.391313042459;5075.593952483792;5103.974480127576;4193.266178418826;4377.4528183977;4393.802278897458;4416.84541041062;4441.156180325728;2679.61145633882;2689.784817214618;2701.5816696234633;2703.891404524136;2705.1613999660167;4755.595774359149;4763.476017638057;4798.568128868418;4818.951810481921;4820.39745031871;5082.593392528589;5154.355705536733;5167.276581278624;5187.63686541946;5195.558377537876;4750.976245118753;4761.595264402565;4798.640101992364;4809.78355973989;4818.614510839195;5111.233315002779;5113.74431278442;5122.692638441706;5131.25596788471;5133.8716532087;4794.760261986957;4992.625553083606;5009.148444764472;5027;5027.673201241908;4659.534046595313;4718;4796.400449943757;4799.328094066903;4809.278608208797;3598.280343931172;3621.402468592653;3622.673959343672;3626.945595816012;3627.093226693327;4901.436334821398;5082;5082.517160869711;5094.847154585368;5100.107481190851;4897.094037603055;4923.0153969205585;4930.802767889244;4939.48135445773;4954.801807927643;4922.714382713532;4926.778294976663;4938.456769755252;4943;4946.282788995647;4635.629149667951;4684.742339171378;4794.544518270759;4862.294967229803;4922.852314430573;4826.686265392738;4908.779104940215;4938.407391113089;4963.875903102306;4973.35346404965;4858.222684370484;4859.902801944012;4864.951350486592;4867.1969125093965;4868.90262194747 07:23:03 INFO - PROCESS | 12058 | |5;yui.html;618.6473709985308;652.0024362725032;664.8537321789205;735.8381156145646;750.6922161913614;12404.937975310122;13470.124441911268;13480.865191348084;13485.123466974652;13534.864651353495;12893.484260629588;12993.61019169424;13046.73906521869;13082.1496602721;13088.411021504031;612.3325575123115;627.5732501898707;739.2755100002001;747.2154238050053;766.6243540665062;765.223298352173;767.996160019202;768.2893323675605;768.800111970886;771.6758961236294;817.9713710020151;819.3731795176694;820.1634332980357;820.1675299570941;820.3027426687328;645.8740545593611;665.3213722003529;782.5500337306072;801.9077806052262;817.6198067898426;595.8689088400545;595.9451770366427;596.6777939912442;596.7255062671134;596.7791916990741;11960.1627886048;11977.520899164023;11991;11991.880081199251;11994.400279986054;12153.574624888086;12407.131500794949;12888.54890078852;13083.476660933551;13440.798388024188;11245.484216558336;11402.71493212668;11635.360055196961;11794.823077653842;12176.330301833395;3924.3328634132267;3953.1303113315025;3960.940585891214;3999.8400463865446;4059.3910913363234;57772.42227577713;57849;57946.420535794525;58445.7077714613;58862.70568647172;10027.448490332956;10081.596736130472;10104.69685909424;10162.745931351776;10362.689119326433;18861;18919.716204256947;18971.43085707431;19033.90483047576;19063.90468047651;5466.098093814556;5550.528205102531;5671.489565939085;5740.799072032459;5780.4508571686465;3470.7570470066853;3571.464280357915;3605.152789094561;3609.2781443711365;3624.637536246407;2707.7021527631946;2799.832010079361;2804.35499835035;2809.5504719244823;2816.352238985045;474.6582460628342;478.4927976345016;481.7254165125915;491.5698763581866;497.3584076541221;499.4530988567513;500.69207437425723;502.5251137674935;589.3046205477492;610.4780412747126;7436.814079648052;7901.197679340699;8110.31062359706;8120.512769233746;8135.7152499662225;501.6388200495637;554.3818642213984;590.6544671367212;593.8930992421406;597.137136837266;3595.2449985502813;3820.2741479118877;3845.9807700961874;3883.5922228165637;3901.3757798752063;52118.21822672663;52223.73888130535;52569.737151313995;53359.73320133374;65415;81251;85932.57033714792;85985.99999999875;87236.56381718178;89626.55186724024;42830;42855;43723.56276437258;43987.78006110013;47472.5252747475;7066.17410061232;7226.89159662616;7437.516561423707;7512.21121782205;7627.313541781266;42149.367759484245;42848.57151428446;43350.566494335915;43881;45358.546414535434;57518.42481575298;61898.69050654898;64859;64905.67547162423;67540.6622966882;9592.328537002342;9609.327347085637;9846.458444785605;10289.485525723534;10545.57817687284;10745.409002504937;11165.330080195214;13018.804717929428;13118.868811312146;13770.518031868836;9877.50612469388;9963.701088967342;11209.215354925076;11567.942160289145;11598.94200528992;11864.516676060157;12104.878951210376;12434.502619895466;12534.87465125337;12818.294993775431;11692.123090768397;11970.401479926142;12192.207506512048;12842;12851.421686023965;9625.51872406363;9963.800723985334;10044.196464282986;10275.691729248134;10412.895871041193;8870;9506.61973521079;10201.183905287708;10265.22838833496;10425.53085111187 07:23:03 INFO - PROCESS | 12058 | __end_tp_report 07:23:03 INFO - PROCESS | 12058 | __start_cc_report 07:23:03 INFO - PROCESS | 12058 | _x_x_mozilla_cycle_collect,1425 07:23:03 INFO - PROCESS | 12058 | __end_cc_report 07:23:03 INFO - PROCESS | 12058 | __startTimestamp1476454983734__endTimestamp 07:23:03 INFO - PROCESS | 12058 | 07:23:03 INFO - PROCESS | 12058 | ------- Summary: start ------- 07:23:03 INFO - PROCESS | 12058 | Number of tests: 6 07:23:03 INFO - PROCESS | 12058 | 07:23:03 INFO - PROCESS | 12058 | [#0] dojo.html Cycles:165 Average:4605.20 Median:4705.55 stddev:487.43 (10.4%) stddev-sans-first:461.31 07:23:03 INFO - PROCESS | 12058 | Values: 2543.4 2558.9 2591.5 2592.5 2605.3 4488.6 4590.9 4624.2 4679.8 4684.6 4584.5 4634.1 4665.3 4705.6 4745.9 4215.5 4232.6 4270.4 4277.3 4287.8 4317.8 4335.2 4343.9 4354.1 4406.0 4309.1 4339.5 4355.4 4364.5 4394.7 4168.0 4210.4 4214.9 4261.4 4279.8 4239.2 4249.6 4254.1 4261.6 4264.9 4630.3 4657.7 4666.9 4668.1 4675.9 4931.9 4978.7 4981.4 4983.3 4984.4 5010.0 5016.7 5017.6 5019.2 5025.5 4580.3 4668.9 4669.3 4672.5 4672.5 4930.2 4992.7 5006.2 5015.5 5015.5 4873.4 4892.9 4893.1 4897.2 4950.8 4872.8 4934.9 4968.1 4970.2 5040.7 4933.1 4948.3 4993.6 5021.4 5057.6 4941.9 4958.9 4963.5 4971.9 5046.3 4118.8 4195.1 4197.6 4281.8 4326.9 3643.6 3669.6 3679.7 3682.9 3717.6 4326.8 4327.3 4336.2 4382.9 4387.3 4893.2 5023.0 5028.1 5028.3 5031.4 3925.3 4265.2 4271.6 4289.7 4331.2 4508.9 4524.0 4553.0 4562.8 4567.2 4657.6 4661.2 4662.6 4669.3 4707.5 4868.1 4919.3 4996.2 5007.1 5057.8 4596.3 4642.5 4684.2 4699.5 4755.7 4949.8 4952.9 4965.9 5007.9 5024.0 5019.3 5019.5 5020.5 5030.1 5049.8 4749.9 4798.5 4811.1 4860.4 4882.8 4798.4 4832.2 4861.3 4863.0 4872.1 4769.1 4797.4 4818.0 4837.0 4887.7 4912.8 4918.5 4922.4 4922.7 4980.6 4652.7 4809.5 4810.9 4811.6 4870.0 07:23:03 INFO - PROCESS | 12058 | 07:23:03 INFO - PROCESS | 12058 | [#1] ext.html Cycles:180 Average:41773.89 Median:183497.58 stddev:74321.49 (40.5%) stddev-sans-first:74465.51 07:23:03 INFO - PROCESS | 12058 | Values: 542.6 566.3 570.1 572.9 574.1 26047.2 26511.3 36347.1 37882.8 38027.0 37735.2 37903.6 38097.4 38106.8 38180.9 7025.3 7453.6 7588.1 7592.3 7596.2 7051.0 7271.9 7278.5 7278.6 9564.3 9602.0 9939.5 9949.1 9949.4 9958.2 9867.3 9922.8 9943.2 9943.2 9944.5 9616.9 9675.1 9677.2 9682.5 9684.6 35834.5 35846.3 35848.3 35946.0 36277.8 2958.1 3000.7 3033.2 3156.3 3233.1 26902.7 27204.5 27347.3 28185.4 28713.7 3222.7 3314.8 3440.0 3502.5 3537.9 217792.0 217822.0 218244.0 218927.0 219162.0 29570.7 50588.7 51006.7 51146.0 51157.5 30342.0 31676.7 31812.8 31897.5 31900.0 11969.4 12006.5 12020.9 12023.2 12028.4 10441.6 10496.8 10510.0 10511.1 10511.8 6935.0 7186.4 7278.2 7621.6 7692.3 2997.4 3029.7 3045.6 3051.1 3095.8 807.3 842.8 846.1 883.8 981.9 25175.7 26410.6 26562.9 26833.9 26922.3 882.3 903.5 923.2 956.7 962.5 14038.6 14191.7 14245.9 14602.7 14652.9 333777.0 337823.0 337829.0 338300.0 338743.0 46520.3 46722.0 46861.3 46922.3 47020.1 1132.4 1134.9 1154.6 1161.3 1162.1 25008.6 25164.7 25313.2 25485.9 26075.2 38961.4 39191.8 39234.0 39246.4 39338.2 278757.0 279627.6 279930.0 280624.0 280936.0 23638.4 23907.9 23976.8 24001.4 24004.3 37917.7 38478.8 38896.4 39007.8 39008.6 32030.4 33053.0 33218.2 33673.3 33701.0 32945.5 33062.2 33203.5 33951.5 34597.0 31802.2 32789.0 32955.8 33005.8 33015.5 27459.0 29352.0 30721.0 31000.7 31143.0 23630.6 23751.8 23810.8 24037.2 24041.5 07:23:03 INFO - PROCESS | 12058 | 07:23:03 INFO - PROCESS | 12058 | [#2] jquery.html Cycles:180 Average:22676.36 Median:4425.96 stddev:60159.45 (1359.2%) stddev-sans-first:60304.84 07:23:03 INFO - PROCESS | 12058 | Values: 341.3 355.1 362.3 363.2 385.4 11949.6 12939.0 15565.7 15841.8 15986.4 13362.2 13914.8 14089.1 14140.7 14272.9 4523.3 4684.8 4687.3 4688.8 4698.2 4470.4 4610.7 4614.0 4634.9 4638.0 4625.6 4626.4 4628.4 4628.5 4629.1 4586.7 4613.7 4621.7 4892.2 4923.2 4420.7 4422.5 4422.9 4423.9 4428.0 17816.3 19595.3 19754.7 19826.6 19836.8 1658.8 1775.1 1787.2 1883.0 1995.3 8912.5 9066.5 9078.5 9230.6 9485.6 1633.5 1658.3 1664.3 1666.9 1726.9 59231.4 63777.8 64335.0 65699.7 66556.0 9579.2 9807.5 9919.2 10058.7 10215.7 13645.1 13689.4 13690.3 13705.6 13710.5 6806.7 7002.4 7051.3 7224.6 7241.0 6610.7 6614.3 6819.1 6871.1 6916.7 4208.9 4244.2 4247.7 4285.2 4351.8 1794.8 1798.3 1805.4 1841.3 1847.8 794.0 810.3 889.9 919.0 939.5 15466.5 16358.2 16615.0 17164.0 17315.3 807.2 807.6 850.3 859.4 864.7 8856.7 8986.1 9070.7 9559.2 9643.8 354080.0 367076.0 367116.0 367859.0 368547.0 67969.3 67975.0 69473.3 69645.3 70865.0 860.9 873.9 881.6 882.1 882.5 14397.9 14785.0 14794.9 14895.9 15437.6 34699.1 35028.6 35682.5 35907.0 36105.8 41558.4 45820.8 46137.5 48010.0 48404.3 6804.0 7038.8 7072.8 7217.0 7337.3 10135.5 11685.3 11717.0 12052.9 12072.5 9971.0 10112.7 10139.6 10501.9 10580.7 9958.2 10023.9 10035.0 10122.2 10207.4 9516.3 9671.7 9890.0 10135.2 10294.8 7079.8 7152.2 7417.0 7426.1 7490.7 8659.9 10006.4 10361.9 10422.9 10933.4 07:23:03 INFO - PROCESS | 12058 | 07:23:03 INFO - PROCESS | 12058 | [#3] mootools.html Cycles:180 Average:4835.36 Median:5192.96 stddev:2703.15 (52.1%) stddev-sans-first:2697.17 07:23:03 INFO - PROCESS | 12058 | Values: 1231.2 1316.7 1355.2 1422.9 1674.6 6298.2 7215.2 7478.5 7495.5 7674.4 7575.5 7596.4 7632.1 7639.9 7644.9 2003.7 2036.7 2041.4 2042.0 2042.8 2070.4 2112.3 2119.5 2126.5 2136.9 2051.5 2057.6 2058.0 2060.3 2066.7 2026.8 2034.5 2039.8 2042.2 2044.8 1962.6 1997.4 2047.8 2049.4 2052.3 7480.7 7585.8 7586.5 7588.4 7590.2 4344.3 4569.4 4570.3 4591.4 4602.6 2652.7 2786.5 2837.8 2846.1 2858.7 922.7 927.5 944.1 958.9 960.5 10097.8 10165.8 10200.2 10201.8 10210.6 3785.4 3875.7 4084.6 4107.4 4133.9 8087.4 8107.3 8118.8 8118.9 8123.7 4555.7 4563.3 4566.7 4567.2 4568.2 3579.4 3709.3 3946.6 4244.7 4251.0 3258.4 3289.7 3415.0 3424.1 3431.7 990.6 994.1 994.7 997.4 1009.5 636.1 663.4 709.0 735.3 757.4 4608.1 4689.7 4863.8 4866.9 5024.9 825.6 856.0 856.8 875.9 901.6 3324.7 3334.2 3343.3 3350.1 3402.8 8782.7 9134.1 9154.8 9171.0 9179.9 8727.9 8729.8 8781.3 8787.9 9074.1 6831.3 6897.4 6946.0 6947.7 6995.5 4570.3 4647.4 4691.8 4800.6 4867.6 7944.8 8104.1 8164.1 8230.3 8266.3 8310.5 8405.6 8424.4 8425.9 8478.5 5361.0 5503.4 6128.3 6210.8 6236.3 6807.4 7053.8 7076.5 7286.3 7288.4 6504.4 6524.1 6576.7 6667.7 6808.3 6498.5 6539.5 6549.9 6620.3 6720.1 6133.6 6220.4 6221.3 6223.2 6224.9 3996.2 4068.9 4088.3 4129.1 4213.7 4150.9 4210.7 4226.7 4311.3 4327.6 07:23:03 INFO - PROCESS | 12058 | 07:23:03 INFO - PROCESS | 12058 | [#4] prototype.html Cycles:180 Average:4101.04 Median:4669.13 stddev:1180.69 (25.3%) stddev-sans-first:1148.88 07:23:03 INFO - PROCESS | 12058 | Values: 292.9 293.7 301.4 305.3 306.2 4549.4 4667.7 4695.2 4750.2 4789.7 4545.4 4608.7 4670.6 4685.4 4699.7 3709.7 3714.1 3725.3 3728.3 3736.3 3507.3 3722.5 3723.3 3732.9 3737.8 3441.1 3696.4 3709.4 3725.8 3726.7 3686.8 3699.2 3703.8 3713.3 3714.3 3292.2 3294.6 3295.9 3297.0 3297.0 4467.6 4499.3 4514.7 4581.2 4585.4 4210.8 4211.0 4217.9 4225.5 4231.6 4842.9 4845.7 4884.0 4906.4 4914.9 3590.8 3590.9 3593.0 3616.4 3633.0 4869.3 5103.9 5124.9 5129.7 5148.3 3580.6 3582.2 3598.3 3604.6 3611.9 3661.0 3661.9 3669.1 3670.5 3673.0 4072.5 4116.8 4149.3 4187.2 4195.5 4916.5 4918.3 4919.3 4930.8 4933.8 1177.8 1206.7 1240.3 1254.3 1257.4 841.7 874.7 877.7 879.4 881.0 4343.9 4381.1 4381.8 4392.4 4403.3 5046.5 5066.8 5072.4 5075.6 5104.0 4193.3 4377.5 4393.8 4416.8 4441.2 2679.6 2689.8 2701.6 2703.9 2705.2 4755.6 4763.5 4798.6 4819.0 4820.4 5082.6 5154.4 5167.3 5187.6 5195.6 4751.0 4761.6 4798.6 4809.8 4818.6 5111.2 5113.7 5122.7 5131.3 5133.9 4794.8 4992.6 5009.1 5027.0 5027.7 4659.5 4718.0 4796.4 4799.3 4809.3 3598.3 3621.4 3622.7 3626.9 3627.1 4901.4 5082.0 5082.5 5094.8 5100.1 4897.1 4923.0 4930.8 4939.5 4954.8 4922.7 4926.8 4938.5 4943.0 4946.3 4635.6 4684.7 4794.5 4862.3 4922.9 4826.7 4908.8 4938.4 4963.9 4973.4 4858.2 4859.9 4865.0 4867.2 4868.9 07:23:03 INFO - PROCESS | 12058 | 07:23:03 INFO - PROCESS | 12058 | [#5] yui.html Cycles:180 Average:15582.58 Median:23973.59 stddev:20686.31 (86.3%) stddev-sans-first:20713.82 07:23:03 INFO - PROCESS | 12058 | Values: 618.6 652.0 664.9 735.8 750.7 12404.9 13470.1 13480.9 13485.1 13534.9 12893.5 12993.6 13046.7 13082.1 13088.4 612.3 627.6 739.3 747.2 766.6 765.2 768.0 768.3 768.8 771.7 818.0 819.4 820.2 820.2 820.3 645.9 665.3 782.6 801.9 817.6 595.9 595.9 596.7 596.7 596.8 11960.2 11977.5 11991.0 11991.9 11994.4 12153.6 12407.1 12888.5 13083.5 13440.8 11245.5 11402.7 11635.4 11794.8 12176.3 3924.3 3953.1 3960.9 3999.8 4059.4 57772.4 57849.0 57946.4 58445.7 58862.7 10027.4 10081.6 10104.7 10162.7 10362.7 18861.0 18919.7 18971.4 19033.9 19063.9 5466.1 5550.5 5671.5 5740.8 5780.5 3470.8 3571.5 3605.2 3609.3 3624.6 2707.7 2799.8 2804.4 2809.6 2816.4 474.7 478.5 481.7 491.6 497.4 499.5 500.7 502.5 589.3 610.5 7436.8 7901.2 8110.3 8120.5 8135.7 501.6 554.4 590.7 593.9 597.1 3595.2 3820.3 3846.0 3883.6 3901.4 52118.2 52223.7 52569.7 53359.7 65415.0 81251.0 85932.6 85986.0 87236.6 89626.6 42830.0 42855.0 43723.6 43987.8 47472.5 7066.2 7226.9 7437.5 7512.2 7627.3 42149.4 42848.6 43350.6 43881.0 45358.5 57518.4 61898.7 64859.0 64905.7 67540.7 9592.3 9609.3 9846.5 10289.5 10545.6 10745.4 11165.3 13018.8 13118.9 13770.5 9877.5 9963.7 11209.2 11567.9 11598.9 11864.5 12104.9 12434.5 12534.9 12818.3 11692.1 11970.4 12192.2 12842.0 12851.4 9625.5 9963.8 10044.2 10275.7 10412.9 8870.0 9506.6 10201.2 10265.2 10425.5 07:23:03 INFO - PROCESS | 12058 | -------- Summary: end -------- 07:23:03 INFO - PROCESS | 12058 | 07:23:04 INFO - TEST-INFO | 12058: exit 0 07:23:04 INFO - TEST-OK | dromaeo_css | took 1122091ms 07:23:04 INFO - TEST-START | kraken 07:23:04 INFO - operating with platform_type : linux_ 07:23:04 INFO - Initialising browser for kraken test... 07:23:04 INFO - TEST-INFO | started process 12366 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_hoiBq/profile http://localhost:37998/getInfo.html) 07:23:08 INFO - PROCESS | 12366 | __metrics Screen width/height:1600/1200 07:23:08 INFO - PROCESS | 12366 | colorDepth:24 07:23:08 INFO - PROCESS | 12366 | Browser inner width/height: 1024/697 07:23:08 INFO - PROCESS | 12366 | __metrics 07:23:09 INFO - PROCESS | 12366 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:23:09 INFO - PROCESS | 12366 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:23:09 INFO - PROCESS | 12366 | console.error: PushService: 07:23:09 INFO - PROCESS | 12366 | stateChangeProcessEnqueue: Error transitioning state 07:23:09 INFO - PROCESS | 12366 | UnknownError 07:23:10 INFO - TEST-INFO | 12366: exit 0 07:23:10 INFO - Browser initialized. 07:23:10 INFO - Running cycle 1/1 for kraken test... 07:23:10 INFO - TEST-INFO | started process 12447 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_hoiBq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:23:22 INFO - PROCESS | 12447 | RSS: Main: 174284800 07:23:22 INFO - PROCESS | 12447 | 07:23:25 INFO - PROCESS | 12447 | Cycle 1(1): loaded http://localhost:37998/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37998/tests/kraken/driver.html?testName=audio-beat-detection) 07:23:26 INFO - PROCESS | 12447 | RSS: Main: 229896192 07:23:26 INFO - PROCESS | 12447 | 07:23:29 INFO - PROCESS | 12447 | Cycle 1(1): loaded http://localhost:37998/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37998/tests/kraken/driver.html?testName=audio-dft) 07:23:29 INFO - PROCESS | 12447 | RSS: Main: 217403392 07:23:29 INFO - PROCESS | 12447 | 07:23:33 INFO - PROCESS | 12447 | Cycle 1(1): loaded http://localhost:37998/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37998/tests/kraken/driver.html?testName=audio-fft) 07:23:33 INFO - PROCESS | 12447 | RSS: Main: 299876352 07:23:33 INFO - PROCESS | 12447 | 07:23:36 INFO - PROCESS | 12447 | Cycle 1(1): loaded http://localhost:37998/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37998/tests/kraken/driver.html?testName=audio-oscillator) 07:23:36 INFO - PROCESS | 12447 | RSS: Main: 210604032 07:23:36 INFO - PROCESS | 12447 | 07:23:38 INFO - PROCESS | 12447 | Cycle 1(1): loaded http://localhost:37998/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37998/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:23:39 INFO - PROCESS | 12447 | RSS: Main: 213942272 07:23:39 INFO - PROCESS | 12447 | 07:23:42 INFO - PROCESS | 12447 | Cycle 1(1): loaded http://localhost:37998/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37998/tests/kraken/driver.html?testName=imaging-darkroom) 07:23:43 INFO - PROCESS | 12447 | RSS: Main: 249442304 07:23:43 INFO - PROCESS | 12447 | 07:23:46 INFO - PROCESS | 12447 | Cycle 1(1): loaded http://localhost:37998/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37998/tests/kraken/driver.html?testName=imaging-desaturate) 07:23:47 INFO - PROCESS | 12447 | RSS: Main: 248455168 07:23:47 INFO - PROCESS | 12447 | 07:23:50 INFO - PROCESS | 12447 | Cycle 1(1): loaded http://localhost:37998/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37998/tests/kraken/driver.html?testName=json-parse-financial) 07:23:50 INFO - PROCESS | 12447 | RSS: Main: 248356864 07:23:50 INFO - PROCESS | 12447 | 07:23:52 INFO - PROCESS | 12447 | Cycle 1(1): loaded http://localhost:37998/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37998/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:23:53 INFO - PROCESS | 12447 | RSS: Main: 209981440 07:23:53 INFO - PROCESS | 12447 | 07:23:54 INFO - PROCESS | 12447 | Cycle 1(1): loaded http://localhost:37998/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37998/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:23:55 INFO - PROCESS | 12447 | RSS: Main: 218705920 07:23:55 INFO - PROCESS | 12447 | 07:23:57 INFO - PROCESS | 12447 | Cycle 1(1): loaded http://localhost:37998/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37998/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:23:58 INFO - PROCESS | 12447 | RSS: Main: 226578432 07:23:58 INFO - PROCESS | 12447 | 07:24:01 INFO - PROCESS | 12447 | Cycle 1(1): loaded http://localhost:37998/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37998/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:24:02 INFO - PROCESS | 12447 | RSS: Main: 235438080 07:24:02 INFO - PROCESS | 12447 | 07:24:05 INFO - PROCESS | 12447 | Cycle 1(1): loaded http://localhost:37998/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37998/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:24:05 INFO - PROCESS | 12447 | RSS: Main: 232374272 07:24:05 INFO - PROCESS | 12447 | 07:24:07 INFO - PROCESS | 12447 | Cycle 1(1): loaded http://localhost:37998/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37998/tests/kraken/driver.html?testName=ai-astar) 07:24:07 INFO - PROCESS | 12447 | RSS: Main: 283336704 07:24:07 INFO - PROCESS | 12447 | 07:24:07 INFO - PROCESS | 12447 | __start_tp_report 07:24:07 INFO - PROCESS | 12447 | _x_x_mozilla_page_load 07:24:07 INFO - PROCESS | 12447 | _x_x_mozilla_page_load_details 07:24:07 INFO - PROCESS | 12447 | |i|pagename|runs| 07:24:07 INFO - PROCESS | 12447 | |0;ai-astar;108;114;113;114;114;114;114;113;113;114 07:24:07 INFO - PROCESS | 12447 | |1;audio-beat-detection;151;125;127;179;128;132;134;131;171;133 07:24:07 INFO - PROCESS | 12447 | |2;audio-dft;162;161;162;162;162;162;162;162;163;161 07:24:07 INFO - PROCESS | 12447 | |3;audio-fft;76;76;77;125;77;76;76;76;100;76 07:24:07 INFO - PROCESS | 12447 | |4;audio-oscillator;85;84;131;84;84;84;110;84;84;118 07:24:07 INFO - PROCESS | 12447 | |5;imaging-gaussian-blur;117;116;117;116;117;117;117;117;116;116 07:24:07 INFO - PROCESS | 12447 | |6;imaging-darkroom;147;147;148;147;147;147;147;147;147;148 07:24:07 INFO - PROCESS | 12447 | |7;imaging-desaturate;85;85;85;85;85;85;85;85;85;85 07:24:07 INFO - PROCESS | 12447 | |8;json-parse-financial;65;66;66;65;66;67;67;68;68;67 07:24:07 INFO - PROCESS | 12447 | |9;json-stringify-tinderbox;68;68;67;68;68;68;68;68;68;69 07:24:07 INFO - PROCESS | 12447 | |10;stanford-crypto-aes;83;83;83;82;83;83;87;81;89;82 07:24:07 INFO - PROCESS | 12447 | |11;stanford-crypto-ccm;140;140;141;144;139;140;138;139;191;137 07:24:07 INFO - PROCESS | 12447 | |12;stanford-crypto-pbkdf2;179;183;184;179;181;184;177;180;176;180 07:24:07 INFO - PROCESS | 12447 | |13;stanford-crypto-sha256-iterative;73;74;73;71;70;70;70;68;69;69 07:24:07 INFO - PROCESS | 12447 | __end_tp_report 07:24:07 INFO - PROCESS | 12447 | __start_cc_report 07:24:07 INFO - PROCESS | 12447 | _x_x_mozilla_cycle_collect,1995 07:24:07 INFO - PROCESS | 12447 | __end_cc_report 07:24:07 INFO - PROCESS | 12447 | __startTimestamp1476455047714__endTimestamp 07:24:07 INFO - PROCESS | 12447 | 07:24:07 INFO - PROCESS | 12447 | ------- Summary: start ------- 07:24:07 INFO - PROCESS | 12447 | Number of tests: 14 07:24:07 INFO - PROCESS | 12447 | 07:24:07 INFO - PROCESS | 12447 | [#0] ai-astar Cycles:10 Average:113.10 Median:114.00 stddev:1.85 (1.6%) stddev-sans-first:0.50 07:24:07 INFO - PROCESS | 12447 | Values: 108.0 114.0 113.0 114.0 114.0 114.0 114.0 113.0 113.0 114.0 07:24:07 INFO - PROCESS | 12447 | 07:24:07 INFO - PROCESS | 12447 | [#1] audio-beat-detection Cycles:10 Average:141.10 Median:133.50 stddev:19.32 (14.5%) stddev-sans-first:20.16 07:24:07 INFO - PROCESS | 12447 | Values: 151.0 125.0 127.0 179.0 128.0 132.0 134.0 131.0 171.0 133.0 07:24:07 INFO - PROCESS | 12447 | 07:24:07 INFO - PROCESS | 12447 | [#2] audio-dft Cycles:10 Average:161.90 Median:162.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 07:24:07 INFO - PROCESS | 12447 | Values: 162.0 161.0 162.0 162.0 162.0 162.0 162.0 162.0 163.0 161.0 07:24:07 INFO - PROCESS | 12447 | 07:24:07 INFO - PROCESS | 12447 | [#3] audio-fft Cycles:10 Average:83.50 Median:76.00 stddev:16.39 (21.6%) stddev-sans-first:17.15 07:24:07 INFO - PROCESS | 12447 | Values: 76.0 76.0 77.0 125.0 77.0 76.0 76.0 76.0 100.0 76.0 07:24:07 INFO - PROCESS | 12447 | 07:24:07 INFO - PROCESS | 12447 | [#4] audio-oscillator Cycles:10 Average:94.80 Median:84.00 stddev:17.87 (21.3%) stddev-sans-first:18.60 07:24:07 INFO - PROCESS | 12447 | Values: 85.0 84.0 131.0 84.0 84.0 84.0 110.0 84.0 84.0 118.0 07:24:07 INFO - PROCESS | 12447 | 07:24:07 INFO - PROCESS | 12447 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 07:24:07 INFO - PROCESS | 12447 | Values: 117.0 116.0 117.0 116.0 117.0 117.0 117.0 117.0 116.0 116.0 07:24:07 INFO - PROCESS | 12447 | 07:24:07 INFO - PROCESS | 12447 | [#6] imaging-darkroom Cycles:10 Average:147.20 Median:147.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 07:24:07 INFO - PROCESS | 12447 | Values: 147.0 147.0 148.0 147.0 147.0 147.0 147.0 147.0 147.0 148.0 07:24:07 INFO - PROCESS | 12447 | 07:24:07 INFO - PROCESS | 12447 | [#7] imaging-desaturate Cycles:10 Average:85.00 Median:85.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 07:24:07 INFO - PROCESS | 12447 | Values: 85.0 85.0 85.0 85.0 85.0 85.0 85.0 85.0 85.0 85.0 07:24:07 INFO - PROCESS | 12447 | 07:24:07 INFO - PROCESS | 12447 | [#8] json-parse-financial Cycles:10 Average:66.50 Median:67.00 stddev:1.08 (1.6%) stddev-sans-first:1.00 07:24:07 INFO - PROCESS | 12447 | Values: 65.0 66.0 66.0 65.0 66.0 67.0 67.0 68.0 68.0 67.0 07:24:07 INFO - PROCESS | 12447 | 07:24:07 INFO - PROCESS | 12447 | [#9] json-stringify-tinderbox Cycles:10 Average:68.00 Median:68.00 stddev:0.47 (0.7%) stddev-sans-first:0.50 07:24:07 INFO - PROCESS | 12447 | Values: 68.0 68.0 67.0 68.0 68.0 68.0 68.0 68.0 68.0 69.0 07:24:07 INFO - PROCESS | 12447 | 07:24:07 INFO - PROCESS | 12447 | [#10] stanford-crypto-aes Cycles:10 Average:83.60 Median:83.00 stddev:2.46 (3.0%) stddev-sans-first:2.60 07:24:07 INFO - PROCESS | 12447 | Values: 83.0 83.0 83.0 82.0 83.0 83.0 87.0 81.0 89.0 82.0 07:24:07 INFO - PROCESS | 12447 | 07:24:07 INFO - PROCESS | 12447 | [#11] stanford-crypto-ccm Cycles:10 Average:144.90 Median:140.00 stddev:16.31 (11.6%) stddev-sans-first:17.20 07:24:07 INFO - PROCESS | 12447 | Values: 140.0 140.0 141.0 144.0 139.0 140.0 138.0 139.0 191.0 137.0 07:24:07 INFO - PROCESS | 12447 | 07:24:07 INFO - PROCESS | 12447 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.30 Median:180.50 stddev:2.75 (1.5%) stddev-sans-first:2.88 07:24:07 INFO - PROCESS | 12447 | Values: 179.0 183.0 184.0 179.0 181.0 184.0 177.0 180.0 176.0 180.0 07:24:07 INFO - PROCESS | 12447 | 07:24:07 INFO - PROCESS | 12447 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.70 Median:70.50 stddev:2.00 (2.8%) stddev-sans-first:1.94 07:24:07 INFO - PROCESS | 12447 | Values: 73.0 74.0 73.0 71.0 70.0 70.0 70.0 68.0 69.0 69.0 07:24:07 INFO - PROCESS | 12447 | -------- Summary: end -------- 07:24:07 INFO - PROCESS | 12447 | 07:24:08 INFO - TEST-INFO | 12447: exit 0 07:24:08 INFO - TEST-OK | kraken | took 64174ms 07:24:08 INFO - SUITE-END | took 1186s 07:24:09 INFO - Completed test suite (00:19:46) 07:24:09 INFO - javascript benchmark 07:24:09 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2543.415014546654, 2558.9232323030315, 2591.4946585415846, 2592.4815036992595, 2605.3226161198095, 4488.573585509372, 4590.885227869297, 4624.237000894853, 4679.789409476577, 4684.6252299816015, 4584.495705472404, 4634.1195172917205, 4665.276882083274, 4705.552972467619, 4745.928811067837, 4215.451991241134, 4232.640225580829, 4270.3807947847545, 4277.3156294992805, 4287.8070486828165, 4317.805698743564, 4335.241332766769, 4343.891402714926, 4354.1074079813725, 4406.030673251879, 4309.073549186908, 4339.500957389909, 4355.433793606827, 4364.519902810688, 4394.714343567658, 4167.9583204167875, 4210.389493523453, 4214.852480163179, 4261.403403523509, 4279.807408666617, 4239.236937351276, 4249.575042495757, 4254.149170165948, 4261.573842615745, 4264.87205383839, 4630.259158534652, 4657.743824089674, 4666.929996050062, 4668.136394766979, 4675.88310292242, 4931.876703082416, 4978.6514943953935, 4981.402231732215, 4983.327250821129, 4984.4267909190185, 5010.048090862724, 5016.698998060128, 5017.64876458648, 5019.171836646985, 5025.547700706954, 4580.290055041474, 4668.906621867543, 4669.276262179367, 4672.462666793294, 4672.462666793328, 4930.235813448921, 4992.700437973734, 5006.1739812930455, 5015.4733752956145, 5015.498450155028, 4873.366462359871, 4892.926606100911, 4893.143699852582, 4897.240927656219, 4950.801967921243, 4872.82945096927, 4934.9013019739405, 4968.105740966661, 4970.2296144097, 5040.722760248148, 4933.062718083553, 4948.331975183376, 4993.550580447777, 5021.397432308073, 5057.620678449131, 4941.901161976813, 4958.851234462972, 4963.503649635008, 4971.875703107379, 5046.268291097844, 4118.814653340607, 4195.098053918422, 4197.643200328006, 4281.828726850953, 4326.870193894189, 3643.635636436335, 3669.5963444021136, 3679.6504332088407, 3682.8895133146048, 3717.5724791648768, 4326.805293761789, 4327.307630779061, 4336.241157797373, 4382.9342559861625, 4387.254166791653, 4893.241547560134, 5022.9748851256245, 5028.145215313405, 5028.296038554606, 5031.371078615173, 3925.3326934421216, 4265.210935976854, 4271.594198551133, 4289.699721019561, 4331.198728235287, 4508.887277818081, 4523.954760452353, 4553.043860789205, 4562.794674239667, 4567.200739870509, 4657.604103651228, 4661.184292748688, 4662.626989840736, 4669.346291519122, 4707.529247075334, 4868.123737727243, 4919.335889654851, 4996.200607902718, 5007.073691367109, 5057.797688092435, 4596.3283120088945, 4642.489326174185, 4684.156851766715, 4699.530046995274, 4755.738434386004, 4949.777260023236, 4952.851414457572, 4965.875853103701, 5007.8748031298055, 5024.020316038337, 5019.272205530105, 5019.548240658358, 5020.523050310215, 5030.0694371541385, 5049.84850454487, 4749.9050019000115, 4798.49615790337, 4811.08589368019, 4860.3681521401995, 4882.7558622068045, 4798.424189097191, 4832.226843704991, 4861.295112208639, 4863.027394521039, 4872.123017856678, 4769.141554520081, 4797.424309082932, 4818.0, 4837.0, 4887.682300650446, 4912.803487860589, 4918.458969513279, 4922.409310882717, 4922.655414120977, 4980.576650984562, 4652.744099074584, 4809.5430934061205, 4810.927836082532, 4811.615070794398, 4869.975650121727], "value": 4573.609478297137, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [542.5822116969933, 566.2723400430448, 570.113473548632, 572.9484346408823, 574.0786038408352, 26047.218583442504, 26511.337216569595, 36347.091322716915, 37882.8105859471, 38027.0, 37735.24529509408, 37903.62096379035, 38097.42853857194, 38106.8094659527, 38180.85457436275, 7025.332593403644, 7453.590052547108, 7588.051493563305, 7592.278733520307, 7596.240375962415, 7051.048108505363, 7271.927280727205, 7278.490505664606, 7278.599677017762, 9564.282678799083, 9602.039796020377, 9939.453330066843, 9949.104580587746, 9949.403035817873, 9958.153556947667, 9867.309288349783, 9922.801543969152, 9943.154831839302, 9943.204543636528, 9944.45305508204, 9616.90383096167, 9675.080867317663, 9677.225821934227, 9682.46746428946, 9684.61261549537, 35834.46248306277, 35846.28307433837, 35848.28303433917, 35946.0, 36277.81861090704, 2958.1421387797513, 3000.684928082542, 3033.1658793831652, 3156.305612765188, 3233.143217047484, 26902.730972690217, 27204.47092697758, 27347.316317092034, 28185.436291274265, 28713.712862871314, 3222.6938440848317, 3314.8176850273226, 3439.9656003439895, 3502.5446691929887, 3537.9115522111897, 217792.0, 217822.0, 218244.0, 218927.0, 219162.0, 29570.704292956794, 50588.747056265216, 51006.74496627493, 51146.0, 51157.48842511602, 30342.0, 31676.683233167834, 31812.840935795637, 31897.52153717696, 31900.0, 11969.401529923469, 12006.459709313101, 12020.939895300467, 12023.158378913568, 12028.398580070962, 10441.634542790965, 10496.790064198673, 10510.0, 10511.10655594283, 10511.789764204674, 6934.965325173443, 7186.38915692172, 7278.235785242579, 7621.618919054025, 7692.269234422721, 2997.385535965122, 3029.697230191039, 3045.6040714706946, 3051.1456792098247, 3095.8297293648616, 807.3460496997451, 842.7598134531752, 846.1369403208693, 883.8409086364516, 981.9067188617213, 25175.748242517708, 26410.6038409424, 26562.86718566395, 26833.865830670722, 26922.32694182661, 882.3162049411757, 903.503073309677, 923.1645360948353, 956.6795123633522, 962.451402700454, 14038.578842634737, 14191.71616567663, 14245.85754142466, 14602.707945841024, 14652.926735366467, 333777.0, 337823.0, 337829.0, 338300.0, 338743.0, 46520.3021954671, 46722.0, 46861.297080543816, 46922.29616555754, 47020.059598807835, 1132.4337831084458, 1134.8638163420442, 1154.5958914380067, 1161.33223396549, 1162.1167912386477, 25008.62487062659, 25164.748352516974, 25313.240602781945, 25485.872570637028, 26075.217743467725, 38961.41557876691, 39191.804040979616, 39234.0, 39246.4113038299, 39338.2132357357, 278757.0, 279627.6018619894, 279930.0, 280624.0, 280936.0, 23638.409039774142, 23907.88046059828, 23976.760232397453, 24001.399965001016, 24004.27987160388, 37917.68177946833, 38478.80760596179, 38896.416553752286, 39007.804960975016, 39008.609913900495, 32030.35939281248, 33053.0, 33218.16954576155, 33673.326533469684, 33701.0, 32945.505817412275, 33062.17344566405, 33203.50194747129, 33951.49072763861, 34597.0, 31802.204944876565, 32789.0, 32955.83522082374, 33005.834970824995, 33015.50476742899, 27459.0, 29352.0, 30721.0, 31000.68999309978, 31143.0, 23630.645540316564, 23751.762482375645, 23810.761892381546, 24037.158699445434, 24041.51916961686], "value": 15779.315225622995, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.2798994122403, 355.14055984517466, 362.26098758532595, 363.1665328072075, 385.3545311603066, 11949.64151075468, 12939.029572782043, 15565.688686226284, 15841.841581584209, 15986.440474583393, 13362.19826810392, 13914.791278130811, 14089.084209526374, 14140.717185656307, 14272.928635356835, 4523.321501774727, 4684.789184486689, 4687.343771871923, 4688.765561721917, 4698.2482802751565, 4470.396496472983, 4610.723356598598, 4614.0, 4634.930476042862, 4638.026014536951, 4625.560571745696, 4626.421697287839, 4628.352030715702, 4628.467726211495, 4629.074185162964, 4586.7477288749105, 4613.677042607019, 4621.699589526686, 4892.241702536113, 4923.236898280772, 4420.690551661385, 4422.491413487458, 4422.933655995163, 4423.933640995388, 4427.977860110711, 17816.287348506045, 19595.314164004332, 19754.70367944482, 19826.603467930705, 19836.801631983784, 1658.7511873218994, 1775.0858307971405, 1787.240422820296, 1882.9811701882943, 1995.321590659183, 8912.509811960339, 9066.456012639263, 9078.452377986445, 9230.630774769, 9485.573149208301, 1633.4955912489909, 1658.2620733773476, 1664.25108701085, 1666.924988375526, 1726.930922763088, 59231.40768592302, 63777.76777812754, 64335.0, 65699.67150164266, 66556.0, 9579.185769209695, 9807.460589667495, 9919.206463483048, 10058.748531286776, 10215.693529194135, 13645.11306765057, 13689.383977721027, 13690.315484225748, 13705.588832335046, 13710.45158193681, 6806.693698783468, 7002.40479559233, 7051.29487051301, 7224.638768061681, 7241.0, 6610.735570577123, 6614.305497922649, 6819.147606549182, 6871.14110736158, 6916.688749006206, 4208.915821683549, 4244.193603215379, 4247.702660813775, 4285.164392943346, 4351.782410879443, 1794.7577077094425, 1798.2896755781655, 1805.395192610464, 1841.2911029253835, 1847.7967423583395, 793.964271607779, 810.266708628692, 889.9009722992099, 918.9816203675889, 939.4738946190155, 15466.514710733762, 16358.182090895407, 16615.0, 17164.0, 17315.307387704604, 807.184743409161, 807.5962018990505, 850.3112478892117, 859.3898332184094, 864.6671031652733, 8856.734297971072, 8986.101389860962, 9070.682526111686, 9559.187469065067, 9643.807123857483, 354080.0, 367076.0, 367116.0, 367859.0, 368547.0, 67969.3203067973, 67975.0, 69473.30526694769, 69645.30354696389, 70865.0, 860.875173099897, 873.9431936924079, 881.6341218394294, 882.1046637662778, 882.4661080046608, 14397.856021439651, 14785.0, 14794.926025369805, 14895.92552037233, 15437.614059648598, 34699.09154996499, 35028.64971350254, 35682.46476302806, 35907.0, 36105.819470902476, 41558.376624351265, 45820.77089614664, 46137.538624614666, 48010.0, 48404.27393589028, 6803.965980170067, 7038.788836334918, 7072.787816365517, 7216.96391518039, 7337.3396394322635, 10135.49322533885, 11685.298882067103, 11717.0, 12052.879471205526, 12072.458705524088, 9971.0, 10112.696619101438, 10139.594416223563, 10501.949805019436, 10580.682579522627, 9958.203343732339, 10023.949880250551, 10035.046670566284, 10122.190224782147, 10207.438590877277, 9516.333856630246, 9671.661491847752, 9889.950550247202, 10135.189184865341, 10294.845577316497, 7079.82300442493, 7152.177499587614, 7417.0, 7426.071741032371, 7490.70037198506, 8659.87010194835, 10006.44964526958, 10361.896381036093, 10422.895771042497, 10933.398663073289], "value": 7736.588409551777, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1231.1566576894834, 1316.677414033562, 1355.2342926246672, 1422.9288535573219, 1674.5897255172476, 6298.212723409574, 7215.2063273039985, 7478.513896596717, 7495.475316727824, 7674.424418168645, 7575.469717119804, 7596.392288616911, 7632.122305934819, 7639.923600764005, 7644.923550764491, 2003.7294965179733, 2036.6639504481743, 2041.397787652646, 2041.969370459437, 2042.7650820155723, 2070.4306315763133, 2112.2818241797786, 2119.459537817854, 2126.5002724359765, 2136.935891923245, 2051.52814852584, 2057.609054279682, 2058.0, 2060.3406909788873, 2066.7003284523735, 2026.7669218039966, 2034.5015471209501, 2039.836813054952, 2042.2239548971445, 2044.7750747417772, 1962.617289628523, 1997.4307322413188, 2047.8156965873143, 2049.3954283486405, 2052.2611859730487, 7480.663370148356, 7585.810354741176, 7586.544807311524, 7588.430867684946, 7590.24097590242, 4344.261475549132, 4569.405977222939, 4570.314452832068, 4591.449026116854, 4602.6087782538525, 2652.65515482986, 2786.4845003674386, 2837.8297302161936, 2846.103477404611, 2858.656961164653, 922.7139586728135, 927.4620719982462, 944.1361154543584, 958.8753462049958, 960.4813400763579, 10097.798044039151, 10165.796684066278, 10200.183985281234, 10201.846972295421, 10210.591576336938, 3785.3943369060817, 3875.7093218008763, 4084.591540845892, 4107.445494858216, 4133.855315063958, 8087.393445491612, 8107.270345668918, 8118.75643730689, 8118.918810811816, 8123.7156699515235, 4555.681102322806, 4563.315502674625, 4566.74882881438, 4567.246404343313, 4568.200564901129, 3579.409397449444, 3709.276691045274, 3946.565877753445, 4244.702870799015, 4251.022264879095, 3258.3971965186515, 3289.703926646613, 3415.0, 3424.143964008998, 3431.725461963037, 990.6087095597198, 994.1351024608637, 994.7264502261792, 997.3654497553597, 1009.4902074452355, 636.1348565950304, 663.4427081251675, 708.9858202835915, 735.2867718313228, 757.4394947738743, 4608.101420223092, 4689.742064186503, 4863.805447782049, 4866.853994380174, 5024.949750502448, 825.5707032343148, 855.9700410485603, 856.8286342731354, 875.8729984152262, 901.6430272439936, 3324.7007769300394, 3334.2164591321507, 3343.331333733263, 3350.0619826448633, 3402.8468718907707, 8782.692605758768, 9134.081842813084, 9154.771130721785, 9170.95414522923, 9179.908200918038, 8727.912720872837, 8729.825403491895, 8781.2535934445, 8787.868181977276, 9074.092590740873, 6831.28271531492, 6897.379235868796, 6946.0, 6947.7220911162985, 6995.510314278054, 4570.268756998864, 4647.395838540968, 4691.765411729468, 4800.591949684316, 4867.63492738039, 7944.84110317802, 8104.108548059827, 8164.142765009826, 8230.259276665129, 8266.256036956704, 8310.54292013945, 8405.621747021522, 8424.368172387218, 8425.873611895704, 8478.491290522341, 5360.973195133999, 5503.422140675171, 6128.295246046583, 6210.813675589739, 6236.314005459487, 6807.3873351398615, 7053.788386348418, 7076.469264805263, 7286.344229019413, 7288.416926645749, 6504.3733378571815, 6524.086627872197, 6576.704048317933, 6667.66661666905, 6808.251092379735, 6498.512611554247, 6539.4768418525455, 6549.8690026200165, 6620.304867988792, 6720.0591917132615, 6133.601315914451, 6220.440160385587, 6221.2845522765465, 6223.190985171898, 6224.906626400517, 3996.200759847984, 4068.9186216276103, 4088.34586466164, 4129.132882094794, 4213.662906967496, 4150.937735934024, 4210.747355158578, 4226.704130710944, 4311.33174357975, 4327.567243275647], "value": 3891.009220441455, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [292.89894986229746, 293.71069496545897, 301.3626180627972, 305.2947690834172, 306.24816076532113, 4549.363089167511, 4667.673262871596, 4695.225287827513, 4750.216214324637, 4789.712617242968, 4545.36364908913, 4608.700434471765, 4670.579647831694, 4685.36747539083, 4699.718016918979, 3709.7032237421076, 3714.0900479382453, 3725.3108174987715, 3728.310262601414, 3736.252749450114, 3507.281007393491, 3722.478852960574, 3723.2925744108534, 3732.9066773330746, 3737.7570457920147, 3441.0558034710434, 3696.445533170019, 3709.443583462475, 3725.795081270529, 3726.720495962814, 3686.8156592170553, 3699.167687270343, 3703.759255648374, 3713.294474049935, 3714.3314203443233, 3292.160499072745, 3294.637589865113, 3295.9340813183603, 3296.983515082409, 3296.9835150824333, 4467.597916187527, 4499.280115181588, 4514.7291162530355, 4581.1753884300815, 4585.380973568593, 4210.831566737327, 4210.978945105255, 4217.891054472763, 4225.450691410128, 4231.640310573605, 4842.903141937177, 4845.685030473043, 4883.9511604883855, 4906.386701662293, 4914.90170196594, 3590.7845529268066, 3590.910227244314, 3592.982035089834, 3616.367135751233, 3633.0, 4869.269609558559, 5103.923441148386, 5124.871878203, 5129.743512824344, 5148.279240906276, 3580.6367797254206, 3582.158192824684, 3598.2803439312243, 3604.621514740967, 3611.8555257789394, 3660.9864574483927, 3661.9267614647556, 3669.1377526281303, 3670.46778217157, 3673.008287762289, 4072.490938632671, 4116.773577453268, 4149.25313443577, 4187.225363307798, 4195.496540415109, 4916.5004673574185, 4918.278602489172, 4919.3358896549225, 4930.827421040318, 4933.777979990909, 1177.8292147638545, 1206.7224538356227, 1240.34881687115, 1254.2975933477283, 1257.3713143428286, 841.7222316635496, 874.7157173918501, 877.7059685005595, 879.4151888993747, 880.9779755506164, 4343.913121737548, 4381.145676593097, 4381.802818873159, 4392.363107349416, 4403.3174857897075, 5046.470120637354, 5066.797328106908, 5072.391313042459, 5075.593952483792, 5103.974480127576, 4193.266178418826, 4377.4528183977, 4393.802278897458, 4416.84541041062, 4441.156180325728, 2679.61145633882, 2689.784817214618, 2701.5816696234633, 2703.891404524136, 2705.1613999660167, 4755.595774359149, 4763.476017638057, 4798.568128868418, 4818.951810481921, 4820.39745031871, 5082.593392528589, 5154.355705536733, 5167.276581278624, 5187.63686541946, 5195.558377537876, 4750.976245118753, 4761.595264402565, 4798.640101992364, 4809.78355973989, 4818.614510839195, 5111.233315002779, 5113.74431278442, 5122.692638441706, 5131.25596788471, 5133.8716532087, 4794.760261986957, 4992.625553083606, 5009.148444764472, 5027.0, 5027.673201241908, 4659.534046595313, 4718.0, 4796.400449943757, 4799.328094066903, 4809.278608208797, 3598.280343931172, 3621.402468592653, 3622.673959343672, 3626.945595816012, 3627.093226693327, 4901.436334821398, 5082.0, 5082.517160869711, 5094.847154585368, 5100.107481190851, 4897.094037603055, 4923.0153969205585, 4930.802767889244, 4939.48135445773, 4954.801807927643, 4922.714382713532, 4926.778294976663, 4938.456769755252, 4943.0, 4946.282788995647, 4635.629149667951, 4684.742339171378, 4794.544518270759, 4862.294967229803, 4922.852314430573, 4826.686265392738, 4908.779104940215, 4938.407391113089, 4963.875903102306, 4973.35346404965, 4858.222684370484, 4859.902801944012, 4864.951350486592, 4867.1969125093965, 4868.90262194747], "value": 3728.3742314613114, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [618.6473709985308, 652.0024362725032, 664.8537321789205, 735.8381156145646, 750.6922161913614, 12404.937975310122, 13470.124441911268, 13480.865191348084, 13485.123466974652, 13534.864651353495, 12893.484260629588, 12993.61019169424, 13046.73906521869, 13082.1496602721, 13088.411021504031, 612.3325575123115, 627.5732501898707, 739.2755100002001, 747.2154238050053, 766.6243540665062, 765.223298352173, 767.996160019202, 768.2893323675605, 768.800111970886, 771.6758961236294, 817.9713710020151, 819.3731795176694, 820.1634332980357, 820.1675299570941, 820.3027426687328, 645.8740545593611, 665.3213722003529, 782.5500337306072, 801.9077806052262, 817.6198067898426, 595.8689088400545, 595.9451770366427, 596.6777939912442, 596.7255062671134, 596.7791916990741, 11960.1627886048, 11977.520899164023, 11991.0, 11991.880081199251, 11994.400279986054, 12153.574624888086, 12407.131500794949, 12888.54890078852, 13083.476660933551, 13440.798388024188, 11245.484216558336, 11402.71493212668, 11635.360055196961, 11794.823077653842, 12176.330301833395, 3924.3328634132267, 3953.1303113315025, 3960.940585891214, 3999.8400463865446, 4059.3910913363234, 57772.42227577713, 57849.0, 57946.420535794525, 58445.7077714613, 58862.70568647172, 10027.448490332956, 10081.596736130472, 10104.69685909424, 10162.745931351776, 10362.689119326433, 18861.0, 18919.716204256947, 18971.43085707431, 19033.90483047576, 19063.90468047651, 5466.098093814556, 5550.528205102531, 5671.489565939085, 5740.799072032459, 5780.4508571686465, 3470.7570470066853, 3571.464280357915, 3605.152789094561, 3609.2781443711365, 3624.637536246407, 2707.7021527631946, 2799.832010079361, 2804.35499835035, 2809.5504719244823, 2816.352238985045, 474.6582460628342, 478.4927976345016, 481.7254165125915, 491.5698763581866, 497.3584076541221, 499.4530988567513, 500.69207437425723, 502.5251137674935, 589.3046205477492, 610.4780412747126, 7436.814079648052, 7901.197679340699, 8110.31062359706, 8120.512769233746, 8135.7152499662225, 501.6388200495637, 554.3818642213984, 590.6544671367212, 593.8930992421406, 597.137136837266, 3595.2449985502813, 3820.2741479118877, 3845.9807700961874, 3883.5922228165637, 3901.3757798752063, 52118.21822672663, 52223.73888130535, 52569.737151313995, 53359.73320133374, 65415.0, 81251.0, 85932.57033714792, 85985.99999999875, 87236.56381718178, 89626.55186724024, 42830.0, 42855.0, 43723.56276437258, 43987.78006110013, 47472.5252747475, 7066.17410061232, 7226.89159662616, 7437.516561423707, 7512.21121782205, 7627.313541781266, 42149.367759484245, 42848.57151428446, 43350.566494335915, 43881.0, 45358.546414535434, 57518.42481575298, 61898.69050654898, 64859.0, 64905.67547162423, 67540.6622966882, 9592.328537002342, 9609.327347085637, 9846.458444785605, 10289.485525723534, 10545.57817687284, 10745.409002504937, 11165.330080195214, 13018.804717929428, 13118.868811312146, 13770.518031868836, 9877.50612469388, 9963.701088967342, 11209.215354925076, 11567.942160289145, 11598.94200528992, 11864.516676060157, 12104.878951210376, 12434.502619895466, 12534.87465125337, 12818.294993775431, 11692.123090768397, 11970.401479926142, 12192.207506512048, 12842.0, 12851.421686023965, 9625.51872406363, 9963.800723985334, 10044.196464282986, 10275.691729248134, 10412.895871041193, 8870.0, 9506.61973521079, 10201.183905287708, 10265.22838833496, 10425.53085111187], "value": 6231.585753978817, "unit": "score"}], "name": "dromaeo_css", "value": 6079.307855348887, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 114.0, 113.0, 114.0, 114.0, 114.0, 114.0, 113.0, 113.0, 114.0], "value": 113.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 125.0, 127.0, 179.0, 128.0, 132.0, 134.0, 131.0, 171.0, 133.0], "value": 141.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 162.0, 162.0, 162.0, 162.0, 162.0, 162.0, 163.0, 161.0], "value": 161.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 77.0, 125.0, 77.0, 76.0, 76.0, 76.0, 100.0, 76.0], "value": 83.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 131.0, 84.0, 84.0, 84.0, 110.0, 84.0, 84.0, 118.0], "value": 94.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 116.0, 116.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 148.0, 147.0, 147.0, 147.0, 147.0, 147.0, 147.0, 148.0], "value": 147.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 85.0, 85.0, 85.0, 85.0, 85.0, 85.0, 85.0, 85.0], "value": 85.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 66.0, 65.0, 66.0, 67.0, 67.0, 68.0, 68.0, 67.0], "value": 66.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 67.0, 68.0, 68.0, 68.0, 68.0, 68.0, 68.0, 69.0], "value": 68.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 83.0, 82.0, 83.0, 83.0, 87.0, 81.0, 89.0, 82.0], "value": 83.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 140.0, 141.0, 144.0, 139.0, 140.0, 138.0, 139.0, 191.0, 137.0], "value": 144.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 183.0, 184.0, 179.0, 181.0, 184.0, 177.0, 180.0, 176.0, 180.0], "value": 180.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 73.0, 71.0, 70.0, 70.0, 70.0, 68.0, 69.0, 69.0], "value": 70.7, "unit": "score"}], "name": "kraken", "value": 1557.2, "alertThreshold": 2.0}]} 07:24:09 INFO - Return code: 0 07:24:09 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:24:09 INFO - # TBPL SUCCESS # 07:24:09 INFO - Running post-action listener: _resource_record_post_action 07:24:09 INFO - [mozharness: 2016-10-14 14:24:09.514500Z] Finished run-tests step (success) 07:24:09 INFO - Running post-run listener: _resource_record_post_run 07:24:09 INFO - Total resource usage - Wall time: 1203s; CPU: 13.0%; Read bytes: 8192; Write bytes: 396713984; Read time: 40; Write time: 272104 07:24:09 INFO - TinderboxPrint: CPU usage
13.0% 07:24:09 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 40 07:24:09 INFO - TinderboxPrint: I/O write bytes / time
396,713,984 / 272,104 07:24:09 INFO - TinderboxPrint: CPU idle
8,349.0 (87.0%) 07:24:09 INFO - TinderboxPrint: CPU user
1,202.2 (12.5%) 07:24:09 INFO - TinderboxPrint: Swap in / out
0 / 0 07:24:09 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 102408192; Read time: 0; Write time: 88504 07:24:09 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 8192; Write bytes: 293396480; Read time: 40; Write time: 183576 07:24:10 INFO - Running post-run listener: _upload_blobber_files 07:24:10 INFO - Blob upload gear active. 07:24:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:24:10 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 07:24:10 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', 'Autoland-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:24:10 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 Autoland-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:24:10 INFO - (blobuploader) - INFO - Open directory for files ... 07:24:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:24:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:24:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:24:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:24:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:24:11 INFO - (blobuploader) - INFO - Done attempting. 07:24:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:24:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:24:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:24:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:24:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:24:15 INFO - (blobuploader) - INFO - Done attempting. 07:24:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:24:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:24:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:24:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:24:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:24:15 INFO - (blobuploader) - INFO - Done attempting. 07:24:15 INFO - (blobuploader) - INFO - Iteration through files over. 07:24:15 INFO - Return code: 0 07:24:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:24:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:24:15 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/42de460f9202164fb3e60bb0d77a88b3c36f247755ff7c0f1f93fa57ffbef59062fdd7ba09dc275c43ebd91dd870db8bd14ce974c33950a9629839e724ca5fb2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/5f8dd8f38723789a75f099ae95acb73975d51c7d1f465b32d5d4b26b8edef70afb366bd23c392d072af90120577afc1446b3662f4e15939db036891a0e94e841", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/0874195f8662308bd4efb62cc7415a5b929fcae82eca70681330e59660fc5144b2853debdb944bd72b6ddffdd7d2810b56f30f386aa869ff850de5754daca397"} 07:24:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:24:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:24:15 INFO - Contents: 07:24:15 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/42de460f9202164fb3e60bb0d77a88b3c36f247755ff7c0f1f93fa57ffbef59062fdd7ba09dc275c43ebd91dd870db8bd14ce974c33950a9629839e724ca5fb2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/5f8dd8f38723789a75f099ae95acb73975d51c7d1f465b32d5d4b26b8edef70afb366bd23c392d072af90120577afc1446b3662f4e15939db036891a0e94e841", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/0874195f8662308bd4efb62cc7415a5b929fcae82eca70681330e59660fc5144b2853debdb944bd72b6ddffdd7d2810b56f30f386aa869ff850de5754daca397"} 07:24:15 INFO - Running post-run listener: copy_logs_to_upload_dir 07:24:15 INFO - Copying logs to upload dir... 07:24:15 INFO - mkdir: /builds/slave/test/build/upload/logs 07:24:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1246.842767 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-10-14 07:24:15.480382) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-14 07:24:15.484166) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=ed14279e38b05397cf4aa391000001a4-1476453806.485331-1094630735 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/42de460f9202164fb3e60bb0d77a88b3c36f247755ff7c0f1f93fa57ffbef59062fdd7ba09dc275c43ebd91dd870db8bd14ce974c33950a9629839e724ca5fb2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/5f8dd8f38723789a75f099ae95acb73975d51c7d1f465b32d5d4b26b8edef70afb366bd23c392d072af90120577afc1446b3662f4e15939db036891a0e94e841", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/0874195f8662308bd4efb62cc7415a5b929fcae82eca70681330e59660fc5144b2853debdb944bd72b6ddffdd7d2810b56f30f386aa869ff850de5754daca397"} build_url:https://queue.taskcluster.net/v1/task/AG3-GhfwRxqUJt864HcpdQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011352 build_url: 'https://queue.taskcluster.net/v1/task/AG3-GhfwRxqUJt864HcpdQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/42de460f9202164fb3e60bb0d77a88b3c36f247755ff7c0f1f93fa57ffbef59062fdd7ba09dc275c43ebd91dd870db8bd14ce974c33950a9629839e724ca5fb2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/5f8dd8f38723789a75f099ae95acb73975d51c7d1f465b32d5d4b26b8edef70afb366bd23c392d072af90120577afc1446b3662f4e15939db036891a0e94e841", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/0874195f8662308bd4efb62cc7415a5b929fcae82eca70681330e59660fc5144b2853debdb944bd72b6ddffdd7d2810b56f30f386aa869ff850de5754daca397"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-14 07:24:15.511331) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 07:24:15.511884) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=ed14279e38b05397cf4aa391000001a4-1476453806.485331-1094630735 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004466 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 07:24:15.597779) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-14 07:24:15.598119) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-14 07:24:15.598453) ========= ========= Total master_lag: 0.18 =========