builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-053 starttime: 1485917407.73 results: success (0) buildid: 20170131164508 builduid: f9601fdcd93d4b5a844dffba3207da19 revision: f39a49e249ecea5f35bc096657fc778619ba60fd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-31 18:50:07.727936) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-31 18:50:07.728399) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-31 18:50:07.728687) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-31 18:50:07.741512) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 18:50:07.741808) ========= 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=920470bf432669ad7e3a5387000001ca-1485917407.131498-1615724139 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-01-31 18:50:07-- 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% 547K=0.02s 2017-01-31 18:50:07 (547 KB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.130693 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 18:50:07.883595) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 18:50:07.883905) ========= 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=920470bf432669ad7e3a5387000001ca-1485917407.131498-1615724139 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013789 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 18:50:07.933279) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-31 18:50:07.933637) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev f39a49e249ecea5f35bc096657fc778619ba60fd --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev f39a49e249ecea5f35bc096657fc778619ba60fd --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=920470bf432669ad7e3a5387000001ca-1485917407.131498-1615724139 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-01-31 18:50:08,003 truncating revision to first 12 chars 2017-01-31 18:50:08,003 Setting DEBUG logging. 2017-01-31 18:50:08,003 attempt 1/10 2017-01-31 18:50:08,003 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/f39a49e249ec?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-31 18:50:09,153 unpacking tar archive at: mozilla-beta-f39a49e249ec/testing/mozharness/ program finished with exit code 0 elapsedTime=1.723337 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-31 18:50:09.669841) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-31 18:50:09.670210) ========= script_repo_revision: f39a49e249ecea5f35bc096657fc778619ba60fd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-31 18:50:09.670607) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-31 18:50:09.670874) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-31 18:50:09.679798) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 31 secs) (at 2017-01-31 18:50:09.680158) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] 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=920470bf432669ad7e3a5387000001ca-1485917407.131498-1615724139 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:50:09 INFO - MultiFileLogger online at 20170131 18:50:09 in /builds/slave/test 18:50:09 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 18:50:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:50:09 INFO - {'append_to_log': False, 18:50:09 INFO - 'base_work_dir': '/builds/slave/test', 18:50:09 INFO - 'blob_upload_branch': 'Mozilla-Beta', 18:50:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:50:09 INFO - 'branch': 'Mozilla-Beta', 18:50:09 INFO - 'buildbot_json_path': 'buildprops.json', 18:50:09 INFO - 'config_files': ('talos/linux_config.py',), 18:50:09 INFO - 'default_actions': ('clobber', 18:50:09 INFO - 'read-buildbot-config', 18:50:09 INFO - 'download-and-extract', 18:50:09 INFO - 'populate-webroot', 18:50:09 INFO - 'create-virtualenv', 18:50:09 INFO - 'install', 18:50:09 INFO - 'run-tests'), 18:50:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:50:09 INFO - 'download_minidump_stackwalk': True, 18:50:09 INFO - 'download_symbols': 'ondemand', 18:50:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:50:09 INFO - 'tooltool.py': '/tools/tooltool.py', 18:50:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:50:09 INFO - '/tools/misc-python/virtualenv.py')}, 18:50:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:50:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:50:09 INFO - 'installer_path': 'installer.exe', 18:50:09 INFO - 'log_level': 'info', 18:50:09 INFO - 'log_name': 'talos', 18:50:09 INFO - 'log_to_console': True, 18:50:09 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:50:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:50:09 INFO - 'opt_config_files': (), 18:50:09 INFO - 'pip_index': False, 18:50:09 INFO - 'sps_profile': False, 18:50:09 INFO - 'sps_profile_interval': 0, 18:50:09 INFO - 'suite': 'dromaeojs', 18:50:09 INFO - 'system_bits': '32', 18:50:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:50:09 INFO - 'title': 'talos-linux64-ix-053', 18:50:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:50:09 INFO - 'use_talos_json': True, 18:50:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:50:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:50:09 INFO - 'work_dir': 'build'} 18:50:09 INFO - [mozharness: 2017-02-01 02:50:09.845533Z] Running clobber step. 18:50:09 INFO - Running pre-action listener: _resource_record_pre_action 18:50:09 INFO - Running main action method: clobber 18:50:09 INFO - rmtree: /builds/slave/test/build 18:50:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:50:10 INFO - Running post-action listener: _resource_record_post_action 18:50:10 INFO - [mozharness: 2017-02-01 02:50:10.329631Z] Finished clobber step (success) 18:50:10 INFO - [mozharness: 2017-02-01 02:50:10.329730Z] Running read-buildbot-config step. 18:50:10 INFO - Running pre-action listener: _resource_record_pre_action 18:50:10 INFO - Running main action method: read_buildbot_config 18:50:10 INFO - Using buildbot properties: 18:50:10 INFO - { 18:50:10 INFO - "project": "", 18:50:10 INFO - "product": "firefox", 18:50:10 INFO - "script_repo_revision": "production", 18:50:10 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 18:50:10 INFO - "repository": "", 18:50:10 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 18:50:10 INFO - "buildid": "20170131164508", 18:50:10 INFO - "pgo_build": "True", 18:50:10 INFO - "basedir": "/builds/slave/test", 18:50:10 INFO - "buildnumber": 119, 18:50:10 INFO - "slavename": "talos-linux64-ix-053", 18:50:10 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 18:50:10 INFO - "platform": "ubuntu64_hw", 18:50:10 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 18:50:10 INFO - "branch": "mozilla-beta", 18:50:10 INFO - "repo_path": "releases/mozilla-beta", 18:50:10 INFO - "revision": "f39a49e249ecea5f35bc096657fc778619ba60fd", 18:50:10 INFO - "stage_platform": "linux64-pgo", 18:50:10 INFO - "builduid": "f9601fdcd93d4b5a844dffba3207da19", 18:50:10 INFO - "slavebuilddir": "test" 18:50:10 INFO - } 18:50:10 INFO - Found installer url https://queue.taskcluster.net/v1/task/LMk7YVshRBuZB5Od5CMElQ/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2. 18:50:10 INFO - Running post-action listener: _resource_record_post_action 18:50:10 INFO - [mozharness: 2017-02-01 02:50:10.332123Z] Finished read-buildbot-config step (success) 18:50:10 INFO - [mozharness: 2017-02-01 02:50:10.332210Z] Running download-and-extract step. 18:50:10 INFO - Running pre-action listener: _resource_record_pre_action 18:50:10 INFO - Running main action method: download_and_extract 18:50:10 INFO - mkdir: /builds/slave/test/build/tests 18:50:10 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')]} 18:50:10 INFO - trying https://queue.taskcluster.net/v1/task/LMk7YVshRBuZB5Od5CMElQ/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.test_packages.json 18:50:10 INFO - Downloading https://queue.taskcluster.net/v1/task/LMk7YVshRBuZB5Od5CMElQ/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-52.0.en-US.linux-x86_64.test_packages.json 18:50:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LMk7YVshRBuZB5Od5CMElQ/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 18:50:10 INFO - Downloaded 1327 bytes. 18:50:10 INFO - Reading from file /builds/slave/test/build/firefox-52.0.en-US.linux-x86_64.test_packages.json 18:50:10 INFO - Using the following test package requirements: 18:50:10 INFO - {u'common': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip'], 18:50:10 INFO - u'cppunittest': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 18:50:10 INFO - u'firefox-52.0.en-US.linux-x86_64.cppunittest.tests.zip'], 18:50:10 INFO - u'gtest': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 18:50:10 INFO - u'firefox-52.0.en-US.linux-x86_64.gtest.tests.zip'], 18:50:10 INFO - u'jittest': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 18:50:10 INFO - u'jsshell-linux-x86_64.zip'], 18:50:10 INFO - u'mochitest': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 18:50:10 INFO - u'firefox-52.0.en-US.linux-x86_64.mochitest.tests.zip'], 18:50:10 INFO - u'mozbase': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip'], 18:50:10 INFO - u'reftest': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 18:50:10 INFO - u'firefox-52.0.en-US.linux-x86_64.reftest.tests.zip'], 18:50:10 INFO - u'talos': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 18:50:10 INFO - u'firefox-52.0.en-US.linux-x86_64.talos.tests.zip'], 18:50:10 INFO - u'web-platform': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 18:50:10 INFO - u'firefox-52.0.en-US.linux-x86_64.web-platform.tests.zip'], 18:50:10 INFO - u'xpcshell': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 18:50:10 INFO - u'firefox-52.0.en-US.linux-x86_64.xpcshell.tests.zip']} 18:50:10 INFO - Downloading packages: [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:50:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LMk7YVshRBuZB5Od5CMElQ/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.common.tests.zip 18:50:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LMk7YVshRBuZB5Od5CMElQ/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:50:10 INFO - Fetch https://queue.taskcluster.net/v1/task/LMk7YVshRBuZB5Od5CMElQ/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.common.tests.zip into memory 18:50:11 INFO - Http code: 200 18:50:11 INFO - via: 1.1 0cf1d7257c633ae75623c5e75bf3805e.cloudfront.net (CloudFront) 18:50:11 INFO - x-amz-cf-id: L-K3wrroS8ni2G-1bwmh0L7N_T6rzef9T7sCSGnhjv1ViRRafovB1Q== 18:50:11 INFO - x-amz-version-id: cIwiyVLQNjdw_Umu_RcAcQKwpqWSw8kl 18:50:11 INFO - Expected file size: 21948874 18:50:11 INFO - Obtained file size: 21948874 18:50:15 INFO - Downloading packages: [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', u'firefox-52.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 18:50:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LMk7YVshRBuZB5Od5CMElQ/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.common.tests.zip 18:50:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LMk7YVshRBuZB5Od5CMElQ/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:50:15 INFO - Fetch https://queue.taskcluster.net/v1/task/LMk7YVshRBuZB5Od5CMElQ/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.common.tests.zip into memory 18:50:16 INFO - Http code: 200 18:50:16 INFO - via: 1.1 f1a40337a32137e1c23ceffead6a50d5.cloudfront.net (CloudFront) 18:50:16 INFO - x-amz-cf-id: qaJL_KFbpW7y_arr9H0luy6P33JY5xwpVpmrBQ8vhFMZ5sy4WzOFow== 18:50:16 INFO - x-amz-version-id: cIwiyVLQNjdw_Umu_RcAcQKwpqWSw8kl 18:50:16 INFO - Expected file size: 21948874 18:50:16 INFO - Obtained file size: 21948874 18:50:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LMk7YVshRBuZB5Od5CMElQ/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.talos.tests.zip 18:50:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LMk7YVshRBuZB5Od5CMElQ/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 18:50:20 INFO - Fetch https://queue.taskcluster.net/v1/task/LMk7YVshRBuZB5Od5CMElQ/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.talos.tests.zip into memory 18:50:21 INFO - Http code: 200 18:50:21 INFO - via: 1.1 f1a40337a32137e1c23ceffead6a50d5.cloudfront.net (CloudFront) 18:50:21 INFO - x-amz-cf-id: Cpc7SPGTjJVHlbjA6bwYf-LQf626MXYSFCwyi66flloCGu3bWgZ6RQ== 18:50:21 INFO - x-amz-version-id: gBfC2IkdsuNyVejWij767acrMldYCCv3 18:50:21 INFO - Expected file size: 14403005 18:50:21 INFO - Obtained file size: 14403005 18:50:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:50:21 INFO - trying https://queue.taskcluster.net/v1/task/LMk7YVshRBuZB5Od5CMElQ/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 18:50:21 INFO - Downloading https://queue.taskcluster.net/v1/task/LMk7YVshRBuZB5Od5CMElQ/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 18:50:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LMk7YVshRBuZB5Od5CMElQ/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-52.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 18:50:23 INFO - Downloaded 57702585 bytes. 18:50:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LMk7YVshRBuZB5Od5CMElQ/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 18:50:23 INFO - mkdir: /builds/slave/test/properties 18:50:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:50:23 INFO - Writing to file /builds/slave/test/properties/build_url 18:50:23 INFO - Contents: 18:50:23 INFO - build_url:https://queue.taskcluster.net/v1/task/LMk7YVshRBuZB5Od5CMElQ/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 18:50:24 INFO - Running post-action listener: _resource_record_post_action 18:50:24 INFO - Running post-action listener: set_extra_try_arguments 18:50:24 INFO - [mozharness: 2017-02-01 02:50:24.232804Z] Finished download-and-extract step (success) 18:50:24 INFO - [mozharness: 2017-02-01 02:50:24.233030Z] Running populate-webroot step. 18:50:24 INFO - Running pre-action listener: _resource_record_pre_action 18:50:24 INFO - Running main action method: populate_webroot 18:50:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:50:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:50:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:50:24 INFO - '--tppagecycles', 18:50:24 INFO - '7'], 18:50:24 INFO - 'tests': ['tsvgm']}}, 18:50:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:50:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:50:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:50:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:50:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:50:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:50:24 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:50:24 INFO - 'talos_options': ['--disable-e10s'], 18:50:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:50:24 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:50:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:50:24 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:50:24 INFO - 'talos_options': ['--disable-e10s'], 18:50:24 INFO - 'tests': ['damp', 'tps']}, 18:50:24 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:50:24 INFO - 'tests': ['damp', 'tps']}, 18:50:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:50:24 INFO - 'tests': ['dromaeo_dom']}, 18:50:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:50:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:50:24 INFO - 'tests': ['basic_compositor_video']}, 18:50:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:50:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:50:24 INFO - 'tests': ['a11yr', 18:50:24 INFO - 'ts_paint', 18:50:24 INFO - 'tpaint', 18:50:24 INFO - 'sessionrestore', 18:50:24 INFO - 'sessionrestore_no_auto_restore', 18:50:24 INFO - 'tabpaint']}, 18:50:24 INFO - 'other-e10s': {'tests': ['a11yr', 18:50:24 INFO - 'ts_paint', 18:50:24 INFO - 'tpaint', 18:50:24 INFO - 'sessionrestore', 18:50:24 INFO - 'sessionrestore_no_auto_restore', 18:50:24 INFO - 'tabpaint']}, 18:50:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:50:24 INFO - 'ts_paint', 18:50:24 INFO - 'tpaint', 18:50:24 INFO - 'sessionrestore', 18:50:24 INFO - 'sessionrestore_no_auto_restore', 18:50:24 INFO - 'tabpaint']}, 18:50:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:50:24 INFO - 'ts_paint', 18:50:24 INFO - 'tpaint', 18:50:24 INFO - 'sessionrestore', 18:50:24 INFO - 'sessionrestore_no_auto_restore', 18:50:24 INFO - 'tabpaint']}, 18:50:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:50:24 INFO - 'tests': ['a11yr', 18:50:24 INFO - 'ts_paint', 18:50:24 INFO - 'tpaint', 18:50:24 INFO - 'sessionrestore', 18:50:24 INFO - 'sessionrestore_no_auto_restore', 18:50:24 INFO - 'tabpaint']}, 18:50:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:50:24 INFO - 'tests': ['a11yr', 18:50:24 INFO - 'ts_paint', 18:50:24 INFO - 'tpaint', 18:50:24 INFO - 'sessionrestore', 18:50:24 INFO - 'sessionrestore_no_auto_restore', 18:50:24 INFO - 'tabpaint']}, 18:50:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:50:24 INFO - 'tests': ['tsvgx', 18:50:24 INFO - 'tsvgr_opacity', 18:50:24 INFO - 'tart', 18:50:24 INFO - 'tscrollx', 18:50:24 INFO - 'cart']}, 18:50:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:50:24 INFO - 'tsvgr_opacity', 18:50:24 INFO - 'tart', 18:50:24 INFO - 'tscrollx', 18:50:24 INFO - 'cart']}, 18:50:24 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:50:24 INFO - 'talos_options': ['--disable-e10s'], 18:50:24 INFO - 'tests': ['tp5o']}, 18:50:24 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:50:24 INFO - 'tests': ['tp5o']}, 18:50:24 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:50:24 INFO - 'talos_options': ['--disable-e10s', 18:50:24 INFO - '--xperf_path', 18:50:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:50:24 INFO - 'tests': ['tp5n']}, 18:50:24 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:50:24 INFO - 'talos_options': ['--xperf_path', 18:50:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:50:24 INFO - 'tests': ['tp5n']}}, 18:50:24 INFO - 'talos.zip': {'path': '', 18:50:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:50:24 INFO - Running post-action listener: _resource_record_post_action 18:50:24 INFO - [mozharness: 2017-02-01 02:50:24.251558Z] Finished populate-webroot step (success) 18:50:24 INFO - [mozharness: 2017-02-01 02:50:24.251645Z] Running create-virtualenv step. 18:50:24 INFO - Running pre-action listener: _resource_record_pre_action 18:50:24 INFO - Running main action method: create_virtualenv 18:50:24 INFO - Creating virtualenv /builds/slave/test/build/venv 18:50:24 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:50:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:50:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:50:24 INFO - Using real prefix '/usr' 18:50:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:50:24 INFO - Installing distribute.............................................................................................................................................................................................done. 18:50:26 INFO - Installing pip.................done. 18:50:26 INFO - Return code: 0 18:50:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:50:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:50:27 INFO - Reading from file tmpfile_stdout 18:50:27 INFO - Output received: 18:50:27 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 18:50:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:50:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:50:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:50:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:50:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:50:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8991af7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13168a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13e3ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e0f60>, '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 0x13e1d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e21d0>, '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': '920470bf432669ad7e3a5387000001ca-1485917407.131498-1615724139', '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 18:50:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:50:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:50:27 INFO - Using env: {'DISPLAY': ':0', 18:50:27 INFO - 'HOME': '/home/cltbld', 18:50:27 INFO - 'LANG': 'en_US.UTF-8', 18:50:27 INFO - 'LANGUAGE': 'en_US:en', 18:50:27 INFO - 'LOGNAME': 'cltbld', 18:50:27 INFO - 'MAIL': '/var/mail/cltbld', 18:50:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:50:27 INFO - 'MOZ_NO_REMOTE': '1', 18:50:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:50:27 INFO - 'NO_EM_RESTART': '1', 18:50:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:50:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:50:27 INFO - 'PWD': '/builds/slave/test', 18:50:27 INFO - 'SHELL': '/bin/bash', 18:50:27 INFO - 'SHLVL': '1', 18:50:27 INFO - 'TERM': 'linux', 18:50:27 INFO - 'TMOUT': '86400', 18:50:27 INFO - 'USER': 'cltbld', 18:50:27 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1485917407.131498-1615724139', 18:50:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:50:27 INFO - '_': '/tools/buildbot/bin/python'} 18:50:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:50:27 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 18:50:27 INFO - Cleaning up... 18:50:27 INFO - Return code: 0 18:50:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:50:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:50:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:50:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:50:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:50:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8991af7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13168a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13e3ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e0f60>, '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 0x13e1d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e21d0>, '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': '920470bf432669ad7e3a5387000001ca-1485917407.131498-1615724139', '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 18:50:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:50:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:50:27 INFO - Using env: {'DISPLAY': ':0', 18:50:27 INFO - 'HOME': '/home/cltbld', 18:50:27 INFO - 'LANG': 'en_US.UTF-8', 18:50:27 INFO - 'LANGUAGE': 'en_US:en', 18:50:27 INFO - 'LOGNAME': 'cltbld', 18:50:27 INFO - 'MAIL': '/var/mail/cltbld', 18:50:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:50:27 INFO - 'MOZ_NO_REMOTE': '1', 18:50:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:50:27 INFO - 'NO_EM_RESTART': '1', 18:50:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:50:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:50:27 INFO - 'PWD': '/builds/slave/test', 18:50:27 INFO - 'SHELL': '/bin/bash', 18:50:27 INFO - 'SHLVL': '1', 18:50:27 INFO - 'TERM': 'linux', 18:50:27 INFO - 'TMOUT': '86400', 18:50:27 INFO - 'USER': 'cltbld', 18:50:27 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1485917407.131498-1615724139', 18:50:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:50:27 INFO - '_': '/tools/buildbot/bin/python'} 18:50:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:50:27 INFO - Downloading/unpacking psutil>=3.1.1 18:50:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:50:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:50:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:50:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:50:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:50:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:50:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:50:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:50:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:50:29 INFO - Installing collected packages: psutil 18:50:29 INFO - Running setup.py install for psutil 18:50:29 INFO - building 'psutil._psutil_linux' extension 18:50:29 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 18:50:29 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 18:50:29 INFO - building 'psutil._psutil_posix' extension 18:50:29 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 18:50:29 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 18:50:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:50:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:50:30 INFO - Successfully installed psutil 18:50:30 INFO - Cleaning up... 18:50:30 INFO - Return code: 0 18:50:30 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:50:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:50:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:50:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:50:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:50:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8991af7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13168a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13e3ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e0f60>, '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 0x13e1d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e21d0>, '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': '920470bf432669ad7e3a5387000001ca-1485917407.131498-1615724139', '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 18:50:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:50:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:50:30 INFO - Using env: {'DISPLAY': ':0', 18:50:30 INFO - 'HOME': '/home/cltbld', 18:50:30 INFO - 'LANG': 'en_US.UTF-8', 18:50:30 INFO - 'LANGUAGE': 'en_US:en', 18:50:30 INFO - 'LOGNAME': 'cltbld', 18:50:30 INFO - 'MAIL': '/var/mail/cltbld', 18:50:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:50:30 INFO - 'MOZ_NO_REMOTE': '1', 18:50:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:50:30 INFO - 'NO_EM_RESTART': '1', 18:50:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:50:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:50:30 INFO - 'PWD': '/builds/slave/test', 18:50:30 INFO - 'SHELL': '/bin/bash', 18:50:30 INFO - 'SHLVL': '1', 18:50:30 INFO - 'TERM': 'linux', 18:50:30 INFO - 'TMOUT': '86400', 18:50:30 INFO - 'USER': 'cltbld', 18:50:30 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1485917407.131498-1615724139', 18:50:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:50:30 INFO - '_': '/tools/buildbot/bin/python'} 18:50:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:50:30 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:50:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:50:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:50:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:50:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:50:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:50:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:50:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:50:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:50:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:50:32 INFO - Installing collected packages: mozsystemmonitor 18:50:32 INFO - Running setup.py install for mozsystemmonitor 18:50:32 INFO - Successfully installed mozsystemmonitor 18:50:32 INFO - Cleaning up... 18:50:32 INFO - Return code: 0 18:50:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:50:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:50:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:50:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:50:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:50:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f8991af7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13168a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13e3ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e0f60>, '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 0x13e1d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e21d0>, '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': '920470bf432669ad7e3a5387000001ca-1485917407.131498-1615724139', '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 18:50:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:50:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:50:32 INFO - Using env: {'DISPLAY': ':0', 18:50:32 INFO - 'HOME': '/home/cltbld', 18:50:32 INFO - 'LANG': 'en_US.UTF-8', 18:50:32 INFO - 'LANGUAGE': 'en_US:en', 18:50:32 INFO - 'LOGNAME': 'cltbld', 18:50:32 INFO - 'MAIL': '/var/mail/cltbld', 18:50:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:50:32 INFO - 'MOZ_NO_REMOTE': '1', 18:50:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:50:32 INFO - 'NO_EM_RESTART': '1', 18:50:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:50:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:50:32 INFO - 'PWD': '/builds/slave/test', 18:50:32 INFO - 'SHELL': '/bin/bash', 18:50:32 INFO - 'SHLVL': '1', 18:50:32 INFO - 'TERM': 'linux', 18:50:32 INFO - 'TMOUT': '86400', 18:50:32 INFO - 'USER': 'cltbld', 18:50:32 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1485917407.131498-1615724139', 18:50:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:50:32 INFO - '_': '/tools/buildbot/bin/python'} 18:50:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:50:32 INFO - Downloading/unpacking jsonschema==2.5.1 18:50:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:50:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:50:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:50:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:50:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:50:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:50:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:50:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:50:34 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 18:50:34 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 18:50:34 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 18:50:34 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 18:50:34 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 18:50:34 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 18:50:35 INFO - Downloading functools32-3.2.3-2.tar.gz 18:50:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:50:35 INFO - warning: no files found matching '*.txt' 18:50:35 INFO - no previously-included directories found matching 'build' 18:50:35 INFO - no previously-included directories found matching 'dist' 18:50:35 INFO - no previously-included directories found matching '.git*' 18:50:35 INFO - Installing collected packages: jsonschema, functools32 18:50:35 INFO - Running setup.py install for functools32 18:50:35 INFO - warning: no files found matching '*.txt' 18:50:35 INFO - no previously-included directories found matching 'build' 18:50:35 INFO - no previously-included directories found matching 'dist' 18:50:35 INFO - no previously-included directories found matching '.git*' 18:50:35 INFO - Successfully installed jsonschema functools32 18:50:35 INFO - Cleaning up... 18:50:35 INFO - Return code: 0 18:50:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:50:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:50:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:50:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:50:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:50:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8991af7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13168a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13e3ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e0f60>, '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 0x13e1d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e21d0>, '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': '920470bf432669ad7e3a5387000001ca-1485917407.131498-1615724139', '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 18:50:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:50:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:50:35 INFO - Using env: {'DISPLAY': ':0', 18:50:35 INFO - 'HOME': '/home/cltbld', 18:50:35 INFO - 'LANG': 'en_US.UTF-8', 18:50:35 INFO - 'LANGUAGE': 'en_US:en', 18:50:35 INFO - 'LOGNAME': 'cltbld', 18:50:35 INFO - 'MAIL': '/var/mail/cltbld', 18:50:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:50:35 INFO - 'MOZ_NO_REMOTE': '1', 18:50:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:50:35 INFO - 'NO_EM_RESTART': '1', 18:50:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:50:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:50:35 INFO - 'PWD': '/builds/slave/test', 18:50:35 INFO - 'SHELL': '/bin/bash', 18:50:35 INFO - 'SHLVL': '1', 18:50:35 INFO - 'TERM': 'linux', 18:50:35 INFO - 'TMOUT': '86400', 18:50:35 INFO - 'USER': 'cltbld', 18:50:35 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1485917407.131498-1615724139', 18:50:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:50:35 INFO - '_': '/tools/buildbot/bin/python'} 18:50:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:50:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:50:35 INFO - Cleaning up... 18:50:35 INFO - Return code: 0 18:50:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:50:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:50:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:50:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:50:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:50:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f8991af7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13168a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13e3ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e0f60>, '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 0x13e1d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e21d0>, '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': '920470bf432669ad7e3a5387000001ca-1485917407.131498-1615724139', '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 18:50:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:50:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:50:35 INFO - Using env: {'DISPLAY': ':0', 18:50:35 INFO - 'HOME': '/home/cltbld', 18:50:35 INFO - 'LANG': 'en_US.UTF-8', 18:50:35 INFO - 'LANGUAGE': 'en_US:en', 18:50:35 INFO - 'LOGNAME': 'cltbld', 18:50:35 INFO - 'MAIL': '/var/mail/cltbld', 18:50:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:50:35 INFO - 'MOZ_NO_REMOTE': '1', 18:50:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:50:35 INFO - 'NO_EM_RESTART': '1', 18:50:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:50:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:50:35 INFO - 'PWD': '/builds/slave/test', 18:50:35 INFO - 'SHELL': '/bin/bash', 18:50:35 INFO - 'SHLVL': '1', 18:50:35 INFO - 'TERM': 'linux', 18:50:35 INFO - 'TMOUT': '86400', 18:50:35 INFO - 'USER': 'cltbld', 18:50:35 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1485917407.131498-1615724139', 18:50:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:50:35 INFO - '_': '/tools/buildbot/bin/python'} 18:50:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:50:35 INFO - Downloading/unpacking blobuploader==1.2.4 18:50:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:50:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:50:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:50:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:50:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:50:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:50:37 INFO - Downloading blobuploader-1.2.4.tar.gz 18:50:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:50:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:50:37 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 18:50:37 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 18:50:37 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 18:50:37 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 18:50:37 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 18:50:37 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 18:50:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:50:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:50:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:50:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:50:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:50:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:50:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:50:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:50:38 INFO - Downloading docopt-0.6.1.tar.gz 18:50:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:50:38 INFO - Installing collected packages: blobuploader, requests, docopt 18:50:38 INFO - Running setup.py install for blobuploader 18:50:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:50:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:50:38 INFO - Running setup.py install for requests 18:50:38 INFO - Running setup.py install for docopt 18:50:39 INFO - Successfully installed blobuploader requests docopt 18:50:39 INFO - Cleaning up... 18:50:39 INFO - Return code: 0 18:50:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:50:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:50:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:50:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:50:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:50:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8991af7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13168a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13e3ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e0f60>, '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 0x13e1d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e21d0>, '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': '920470bf432669ad7e3a5387000001ca-1485917407.131498-1615724139', '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 18:50:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:50:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:50:39 INFO - Using env: {'DISPLAY': ':0', 18:50:39 INFO - 'HOME': '/home/cltbld', 18:50:39 INFO - 'LANG': 'en_US.UTF-8', 18:50:39 INFO - 'LANGUAGE': 'en_US:en', 18:50:39 INFO - 'LOGNAME': 'cltbld', 18:50:39 INFO - 'MAIL': '/var/mail/cltbld', 18:50:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:50:39 INFO - 'MOZ_NO_REMOTE': '1', 18:50:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:50:39 INFO - 'NO_EM_RESTART': '1', 18:50:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:50:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:50:39 INFO - 'PWD': '/builds/slave/test', 18:50:39 INFO - 'SHELL': '/bin/bash', 18:50:39 INFO - 'SHLVL': '1', 18:50:39 INFO - 'TERM': 'linux', 18:50:39 INFO - 'TMOUT': '86400', 18:50:39 INFO - 'USER': 'cltbld', 18:50:39 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1485917407.131498-1615724139', 18:50:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:50:39 INFO - '_': '/tools/buildbot/bin/python'} 18:50:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:50:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:50:39 INFO - Running setup.py (path:/tmp/pip-cfaTrb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:50:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:50:39 INFO - Running setup.py (path:/tmp/pip-sd_u9U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:50:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:50:39 INFO - Running setup.py (path:/tmp/pip-axLbxr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:50:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:50:39 INFO - Running setup.py (path:/tmp/pip-jL6Wi0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:50:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:50:39 INFO - Running setup.py (path:/tmp/pip-0pkuv5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:50:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:50:39 INFO - Running setup.py (path:/tmp/pip-RGIxJJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:50:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:50:39 INFO - Running setup.py (path:/tmp/pip-GldMB6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:50:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:50:39 INFO - Running setup.py (path:/tmp/pip-VE8ifC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:50:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:50:39 INFO - Running setup.py (path:/tmp/pip-m6WlgC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:50:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:50:39 INFO - Running setup.py (path:/tmp/pip-MZ6ok7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:50:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:50:40 INFO - Running setup.py (path:/tmp/pip-2dZ49Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:50:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:50:40 INFO - Running setup.py (path:/tmp/pip-rae7sm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:50:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:50:40 INFO - Running setup.py (path:/tmp/pip-OqNV9y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:50:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:50:40 INFO - Running setup.py (path:/tmp/pip-k3rYsC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:50:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:50:40 INFO - Running setup.py (path:/tmp/pip-cQFwR5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:50:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:50:40 INFO - Running setup.py (path:/tmp/pip-TjhokS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:50:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:50:40 INFO - Running setup.py (path:/tmp/pip-nEjC3H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:50:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:50:40 INFO - Running setup.py install for manifestparser 18:50:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:50:40 INFO - Running setup.py install for mozcrash 18:50:40 INFO - Running setup.py install for mozdebug 18:50:40 INFO - Running setup.py install for mozdevice 18:50:41 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:50:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:50:41 INFO - Running setup.py install for mozfile 18:50:41 INFO - Running setup.py install for mozhttpd 18:50:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:50:41 INFO - Running setup.py install for mozinfo 18:50:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:50:41 INFO - Running setup.py install for mozInstall 18:50:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:50:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:50:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:50:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:50:41 INFO - Running setup.py install for mozleak 18:50:41 INFO - Running setup.py install for mozlog 18:50:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:50:41 INFO - Running setup.py install for moznetwork 18:50:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:50:41 INFO - Running setup.py install for mozprocess 18:50:41 INFO - Running setup.py install for mozprofile 18:50:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:50:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:50:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:50:41 INFO - Running setup.py install for mozrunner 18:50:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:50:42 INFO - Running setup.py install for mozscreenshot 18:50:42 INFO - Running setup.py install for moztest 18:50:42 INFO - Running setup.py install for mozversion 18:50:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:50:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:50:42 INFO - Cleaning up... 18:50:42 INFO - Return code: 0 18:50:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:50:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:50:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:50:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:50:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:50:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8991af7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13168a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13e3ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e0f60>, '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 0x13e1d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e21d0>, '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': '920470bf432669ad7e3a5387000001ca-1485917407.131498-1615724139', '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 18:50:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:50:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:50:42 INFO - Using env: {'DISPLAY': ':0', 18:50:42 INFO - 'HOME': '/home/cltbld', 18:50:42 INFO - 'LANG': 'en_US.UTF-8', 18:50:42 INFO - 'LANGUAGE': 'en_US:en', 18:50:42 INFO - 'LOGNAME': 'cltbld', 18:50:42 INFO - 'MAIL': '/var/mail/cltbld', 18:50:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:50:42 INFO - 'MOZ_NO_REMOTE': '1', 18:50:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:50:42 INFO - 'NO_EM_RESTART': '1', 18:50:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:50:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:50:42 INFO - 'PWD': '/builds/slave/test', 18:50:42 INFO - 'SHELL': '/bin/bash', 18:50:42 INFO - 'SHLVL': '1', 18:50:42 INFO - 'TERM': 'linux', 18:50:42 INFO - 'TMOUT': '86400', 18:50:42 INFO - 'USER': 'cltbld', 18:50:42 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1485917407.131498-1615724139', 18:50:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:50:42 INFO - '_': '/tools/buildbot/bin/python'} 18:50:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:50:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:50:42 INFO - Running setup.py (path:/tmp/pip-tkH6K2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:50:42 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)) 18:50:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:50:42 INFO - Running setup.py (path:/tmp/pip-Xax_gl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:50:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:50:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:50:42 INFO - Running setup.py (path:/tmp/pip-P4Iufq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:50:42 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)) 18:50:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:50:42 INFO - Running setup.py (path:/tmp/pip-bEm6FX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:50:42 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)) 18:50:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:50:42 INFO - Running setup.py (path:/tmp/pip-0ASSip-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:50:42 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)) 18:50:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:50:42 INFO - Running setup.py (path:/tmp/pip-q2CDDe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:50:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 18:50:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:50:43 INFO - Running setup.py (path:/tmp/pip-IrbbTs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:50:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 18:50:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:50:43 INFO - Running setup.py (path:/tmp/pip-nWtgG3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:50:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:50:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:50:43 INFO - Running setup.py (path:/tmp/pip-YRFKjC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:50:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 18:50:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:50:43 INFO - Running setup.py (path:/tmp/pip-wwzWuO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:50:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:50:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:50:43 INFO - Running setup.py (path:/tmp/pip-pdJGBL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:50:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 18:50:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:50:43 INFO - Running setup.py (path:/tmp/pip-xWOmay-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:50:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 18:50:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:50:43 INFO - Running setup.py (path:/tmp/pip-d6ZWJV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:50:43 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)) 18:50:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:50:43 INFO - Running setup.py (path:/tmp/pip-K7NBa6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:50:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 18:50:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:50:43 INFO - Running setup.py (path:/tmp/pip-rgNwq4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:50:43 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)) 18:50:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:50:43 INFO - Running setup.py (path:/tmp/pip-1gbfiI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:50:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 18:50:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:50:43 INFO - Running setup.py (path:/tmp/pip-CiDp60-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:50:43 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)) 18:50:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:50:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:50:43 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)) 18:50:43 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)) 18:50:43 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)) 18:50:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:50:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:50:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:50:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:50:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:50:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:50:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:50:45 INFO - Downloading blessings-1.6.tar.gz 18:50:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:50:45 INFO - Installing collected packages: blessings 18:50:45 INFO - Running setup.py install for blessings 18:50:46 INFO - Successfully installed blessings 18:50:46 INFO - Cleaning up... 18:50:46 INFO - Return code: 0 18:50:46 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:50:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:50:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:50:46 INFO - Reading from file tmpfile_stdout 18:50:46 INFO - Current package versions: 18:50:46 INFO - argparse == 1.2.1 18:50:46 INFO - blessings == 1.6 18:50:46 INFO - blobuploader == 1.2.4 18:50:46 INFO - docopt == 0.6.1 18:50:46 INFO - functools32 == 3.2.3-2 18:50:46 INFO - jsonschema == 2.5.1 18:50:46 INFO - manifestparser == 1.1 18:50:46 INFO - mozInstall == 1.12 18:50:46 INFO - mozcrash == 1.0 18:50:46 INFO - mozdebug == 0.1 18:50:46 INFO - mozdevice == 0.48 18:50:46 INFO - mozfile == 1.2 18:50:46 INFO - mozhttpd == 0.7 18:50:46 INFO - mozinfo == 0.9 18:50:46 INFO - mozleak == 0.1 18:50:46 INFO - mozlog == 3.4 18:50:46 INFO - moznetwork == 0.27 18:50:46 INFO - mozprocess == 0.23 18:50:46 INFO - mozprofile == 0.28 18:50:46 INFO - mozrunner == 6.13 18:50:46 INFO - mozscreenshot == 0.1 18:50:46 INFO - mozsystemmonitor == 0.3 18:50:46 INFO - moztest == 0.8 18:50:46 INFO - mozversion == 1.4 18:50:46 INFO - psutil == 3.1.1 18:50:46 INFO - requests == 1.2.3 18:50:46 INFO - wsgiref == 0.1.2 18:50:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:50: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')]} 18:50:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:50:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50: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')]} 18:50:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:50:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8991af7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13168a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13e3ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e0f60>, '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 0x13e1d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e21d0>, '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': '920470bf432669ad7e3a5387000001ca-1485917407.131498-1615724139', '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 18:50:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 18:50:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:50:46 INFO - Using env: {'DISPLAY': ':0', 18:50:46 INFO - 'HOME': '/home/cltbld', 18:50:46 INFO - 'LANG': 'en_US.UTF-8', 18:50:46 INFO - 'LANGUAGE': 'en_US:en', 18:50:46 INFO - 'LOGNAME': 'cltbld', 18:50:46 INFO - 'MAIL': '/var/mail/cltbld', 18:50:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:50:46 INFO - 'MOZ_NO_REMOTE': '1', 18:50:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:50:46 INFO - 'NO_EM_RESTART': '1', 18:50:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:50:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:50:46 INFO - 'PWD': '/builds/slave/test', 18:50:46 INFO - 'SHELL': '/bin/bash', 18:50:46 INFO - 'SHLVL': '1', 18:50:46 INFO - 'TERM': 'linux', 18:50:46 INFO - 'TMOUT': '86400', 18:50:46 INFO - 'USER': 'cltbld', 18:50:46 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1485917407.131498-1615724139', 18:50:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:50:46 INFO - '_': '/tools/buildbot/bin/python'} 18:50:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:50:46 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)) 18:50:46 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)) 18:50:46 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)) 18:50:46 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)) 18:50:46 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)) 18:50:46 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)) 18:50:46 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)) 18:50:46 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)) 18:50:46 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)) 18:50:46 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)) 18:50:46 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)) 18:50:46 INFO - Cleaning up... 18:50:46 INFO - Return code: 0 18:50:46 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:50: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')]} 18:50:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:50:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50: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')]} 18:50:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:50:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8991af7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13168a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13e3ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e0f60>, '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 0x13e1d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e21d0>, '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': '920470bf432669ad7e3a5387000001ca-1485917407.131498-1615724139', '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 18:50: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', 'jsonschema'] in /builds/slave/test/build 18:50: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 jsonschema 18:50:46 INFO - Using env: {'DISPLAY': ':0', 18:50:46 INFO - 'HOME': '/home/cltbld', 18:50:46 INFO - 'LANG': 'en_US.UTF-8', 18:50:46 INFO - 'LANGUAGE': 'en_US:en', 18:50:46 INFO - 'LOGNAME': 'cltbld', 18:50:46 INFO - 'MAIL': '/var/mail/cltbld', 18:50:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:50:46 INFO - 'MOZ_NO_REMOTE': '1', 18:50:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:50:46 INFO - 'NO_EM_RESTART': '1', 18:50:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:50:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:50:46 INFO - 'PWD': '/builds/slave/test', 18:50:46 INFO - 'SHELL': '/bin/bash', 18:50:46 INFO - 'SHLVL': '1', 18:50:46 INFO - 'TERM': 'linux', 18:50:46 INFO - 'TMOUT': '86400', 18:50:46 INFO - 'USER': 'cltbld', 18:50:46 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1485917407.131498-1615724139', 18:50:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:50:46 INFO - '_': '/tools/buildbot/bin/python'} 18:50:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:50:46 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:50:46 INFO - Cleaning up... 18:50:46 INFO - Return code: 0 18:50:46 INFO - Running post-action listener: _resource_record_post_action 18:50:46 INFO - Running post-action listener: _start_resource_monitoring 18:50:46 INFO - Starting resource monitoring. 18:50:46 INFO - [mozharness: 2017-02-01 02:50:46.826813Z] Finished create-virtualenv step (success) 18:50:46 INFO - [mozharness: 2017-02-01 02:50:46.827261Z] Running install step. 18:50:46 INFO - Running pre-action listener: _resource_record_pre_action 18:50:46 INFO - Running main action method: install 18:50:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:50:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:50:47 INFO - Reading from file tmpfile_stdout 18:50:47 INFO - Detecting whether we're running mozinstall >=1.0... 18:50:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:50:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:50:47 INFO - Reading from file tmpfile_stdout 18:50:47 INFO - Output received: 18:50:47 INFO - Usage: mozinstall [options] installer 18:50:47 INFO - Options: 18:50:47 INFO - -h, --help show this help message and exit 18:50:47 INFO - -d DEST, --destination=DEST 18:50:47 INFO - Directory to install application into. [default: 18:50:47 INFO - "/builds/slave/test"] 18:50:47 INFO - --app=APP Application being installed. [default: firefox] 18:50:47 INFO - mkdir: /builds/slave/test/build/application 18:50:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 18:50:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 18:51:00 INFO - Reading from file tmpfile_stdout 18:51:00 INFO - Output received: 18:51:00 INFO - /builds/slave/test/build/application/firefox/firefox 18:51:00 INFO - Running post-action listener: _resource_record_post_action 18:51:00 INFO - [mozharness: 2017-02-01 02:51:00.743661Z] Finished install step (success) 18:51:00 INFO - [mozharness: 2017-02-01 02:51:00.743902Z] Running run-tests step. 18:51:00 INFO - Running pre-action listener: _resource_record_pre_action 18:51:00 INFO - Running main action method: run_tests 18:51:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:51:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:51:00 INFO - Python 2.7.3 18:51:00 INFO - Return code: 0 18:51:00 INFO - grabbing minidump binary from tooltool 18:51:00 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')]} 18:51:00 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 0x13e0f60>, '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 0x13e1d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e21d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:51:00 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 18:51:00 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 18:51:00 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 18:51:00 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:51:00 INFO - Return code: 0 18:51:00 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:51:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:51:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:51:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:51:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:51:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LMk7YVshRBuZB5Od5CMElQ/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--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 18:51:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/LMk7YVshRBuZB5Od5CMElQ/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-053 --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 18:51:00 INFO - Using env: {'DISPLAY': ':0', 18:51:00 INFO - 'HOME': '/home/cltbld', 18:51:00 INFO - 'LANG': 'en_US.UTF-8', 18:51:00 INFO - 'LANGUAGE': 'en_US:en', 18:51:00 INFO - 'LOGNAME': 'cltbld', 18:51:00 INFO - 'MAIL': '/var/mail/cltbld', 18:51:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:51:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:51:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:51:00 INFO - 'MOZ_NO_REMOTE': '1', 18:51:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:51:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:51:00 INFO - 'NO_EM_RESTART': '1', 18:51:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:51:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:51:00 INFO - 'PWD': '/builds/slave/test', 18:51:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:51:00 INFO - 'SHELL': '/bin/bash', 18:51:00 INFO - 'SHLVL': '1', 18:51:00 INFO - 'TERM': 'linux', 18:51:00 INFO - 'TMOUT': '86400', 18:51:00 INFO - 'USER': 'cltbld', 18:51:00 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1485917407.131498-1615724139', 18:51:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:51:00 INFO - '_': '/tools/buildbot/bin/python'} 18:51:00 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LMk7YVshRBuZB5Od5CMElQ/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--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 18:51:01 INFO - mozversion application_buildid: 20170131164508 18:51:01 INFO - mozversion application_changeset: f39a49e249ecea5f35bc096657fc778619ba60fd 18:51:01 INFO - mozversion application_display_name: Firefox 18:51:01 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:51:01 INFO - mozversion application_name: Firefox 18:51:01 INFO - mozversion application_remotingname: firefox 18:51:01 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 18:51:01 INFO - mozversion application_vendor: Mozilla 18:51:01 INFO - mozversion application_version: 52.0 18:51:01 INFO - mozversion platform_buildid: 20170131164508 18:51:01 INFO - mozversion platform_changeset: f39a49e249ecea5f35bc096657fc778619ba60fd 18:51:01 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 18:51:01 INFO - mozversion platform_version: 52.0 18:51:01 INFO - using testdate: 1485917461 18:51:01 INFO - actual date: 1485917461 18:51:01 INFO - starting webserver on 'localhost:39624' 18:51:01 INFO - SUITE-START | Running 2 tests 18:51:01 INFO - TEST-START | dromaeo_css 18:51:01 INFO - operating with platform_type : linux_ 18:51:01 INFO - Initialising browser for dromaeo_css test... 18:51:01 INFO - TEST-INFO | started process 5479 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpc4CxoB/profile http://localhost:39624/getInfo.html) 18:51:05 INFO - PROCESS | 5479 | __metrics Screen width/height:1600/1200 18:51:05 INFO - PROCESS | 5479 | colorDepth:24 18:51:05 INFO - PROCESS | 5479 | Browser inner width/height: 1024/697 18:51:05 INFO - PROCESS | 5479 | __metrics 18:51:05 INFO - PROCESS | 5479 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:51:05 INFO - PROCESS | 5479 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:51:06 INFO - PROCESS | 5479 | console.error: PushService: 18:51:06 INFO - PROCESS | 5479 | stateChangeProcessEnqueue: Error transitioning state 18:51:06 INFO - PROCESS | 5479 | UnknownError 18:51:06 INFO - TEST-INFO | 5479: exit 0 18:51:06 INFO - Browser initialized. 18:51:06 INFO - Running cycle 1/1 for dromaeo_css test... 18:51:06 INFO - TEST-INFO | started process 5572 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpc4CxoB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:51:18 INFO - PROCESS | 5572 | RSS: Main: 141893632 18:51:18 INFO - PROCESS | 5572 | 18:54:19 INFO - PROCESS | 5572 | Cycle 1(1): loaded http://localhost:39624/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39624/tests/dromaeo/cssquery-ext.html) 18:54:20 INFO - PROCESS | 5572 | RSS: Main: 238592000 18:54:20 INFO - PROCESS | 5572 | 18:57:23 INFO - PROCESS | 5572 | Cycle 1(1): loaded http://localhost:39624/tests/dromaeo/cssquery-ext.html (next: http://localhost:39624/tests/dromaeo/cssquery-jquery.html) 18:57:23 INFO - PROCESS | 5572 | RSS: Main: 230707200 18:57:23 INFO - PROCESS | 5572 | 19:00:25 INFO - PROCESS | 5572 | Cycle 1(1): loaded http://localhost:39624/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39624/tests/dromaeo/cssquery-mootools.html) 19:00:26 INFO - PROCESS | 5572 | RSS: Main: 179994624 19:00:26 INFO - PROCESS | 5572 | 19:03:28 INFO - PROCESS | 5572 | Cycle 1(1): loaded http://localhost:39624/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39624/tests/dromaeo/cssquery-prototype.html) 19:03:28 INFO - PROCESS | 5572 | RSS: Main: 219123712 19:03:28 INFO - PROCESS | 5572 | 19:06:31 INFO - PROCESS | 5572 | Cycle 1(1): loaded http://localhost:39624/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39624/tests/dromaeo/cssquery-yui.html) 19:06:31 INFO - PROCESS | 5572 | RSS: Main: 179752960 19:06:31 INFO - PROCESS | 5572 | 19:09:34 INFO - PROCESS | 5572 | Cycle 1(1): loaded http://localhost:39624/tests/dromaeo/cssquery-yui.html (next: http://localhost:39624/tests/dromaeo/cssquery-dojo.html) 19:09:34 INFO - PROCESS | 5572 | RSS: Main: 264179712 19:09:34 INFO - PROCESS | 5572 | 19:09:34 INFO - PROCESS | 5572 | __start_tp_report 19:09:34 INFO - PROCESS | 5572 | _x_x_mozilla_page_load 19:09:34 INFO - PROCESS | 5572 | _x_x_mozilla_page_load_details 19:09:34 INFO - PROCESS | 5572 | |i|pagename|runs| 19:09:34 INFO - PROCESS | 5572 | |0;dojo.html;3144.9213769655757;3171.5401266816298;3179.8410079496025;3197.5203719442097;3219.356128774244;5380;5429.158480435529;5441.047816632088;5456.781728730856;5460.972695136519;5486.423925487826;5498.422665620113;5526;5526.834194974157;5529.861753456165;5036.974815125919;5059.2916991621205;5084.949150508484;5115.846524604268;5153.613478989072;4922.237053256733;4945.505449455061;4958.454569997298;5087.10975579274;5140.203268493371;4950.6287028472825;5050.040492306468;5060.493950604929;5062.848114556569;5071.467495912932;5061;5064.11378008847;5067.34124563808;5073.213651883945;5104.157813960692;4855.4901735317635;4871.480058616187;4969.0807200668;4980.501949805027;5039.647224694273;5340.91988620171;5346.973265133688;5360.249565060894;5366.70483123428;5414;5748.482636562688;5762.423757624246;5798.275215598051;5844.941550584483;5849.064149736065;5733.770649174028;5872.970635146839;5882.882342353172;5901.321348044987;5901.852453688649;5423.864403389907;5424.728763561806;5456.236126942192;5475.233467314579;5536.474034966672;5930.851728706817;5934.05055191176;5942.910856337072;5944.197533332945;5954.940450595439;5541.27963364768;5731.8853622928145;5733.655980641175;5747.453991870766;5747.942520574825;5638.689872056994;5730.1404789281105;5767.769289228468;5773.740181691834;5832.24180856486;5736.483716465538;5773.220615216976;5783.16144159103;5783.16144159103;5783.652980821164;5779.479846813723;5782.59521833476;5784.132380142928;5786.971065144648;5820.708964551755;4476.26141686618;4484.372187893698;4507.909841803145;4509.12072145935;4522.23122069249;3988.4335427261176;4023.8591649292134;4032.395140728914;4050.8784736457956;4050.959490405058;5530.972345138249;5552.666839989613;5553.194786755897;5571.749271282802;5605.355384130796;5702.686352250583;5748.856278592985;5781.277340332458;5798.449147330997;5845.678487683218;5609.859753506195;5615.326160860723;5625.803096891589;5637.43625637433;5646.322441307069;5038;5053.620978426633;5054.418741844662;5056.519630635084;5111.642185047085;5842.678652674143;5966.522678185822;6090.482309003695;6097.268327800603;6111.5110791137495;5820.5925585210325;5845.736941837543;5857.677827719516;5906.675132867562;5939.821805345847;6084.513238940785;6099.573029887865;6104.633721976696;6104.664243466652;6108.5723999319625;5610.13042978339;5727.31272247333;5781.017227071492;5784.392638772868;5784.4504772046585;5683.602147849776;5759.1649210865025;5779.2198053263955;5809.767609295581;5898.233229680114;5655.4910058094965;5705.4009329019855;5717.17101020358;5717.285339332583;5722.027255366681;5689;5698.031334673033;5700.230468886647;5712.80005199816;5714.257146570919;5498.1752737089755;5541.584381171348;5662.915056274242;5665.433456654301;5715.31416230055;5771.047777116729;5804.448577385142;5811.912821307768;5822;5833.416658334133;5423.078076726887;5461.2627295316215;5628.380878103487;5640.943590564042;5643.181738647791 19:09:34 INFO - PROCESS | 5572 | |1;ext.html;568.11657872009;578.6065475476676;629.7543957856435;644.2880616918305;665.2715276771933;35390.469142962844;38001.23997520048;46601.76699116504;47570.76214618931;47698;48228.51771482284;48505.27242091363;48627;48768;48800.26799598;8472.661093556251;8985.281177505783;9133.862992055125;9137.223336016446;9137.269018478506;8566.229039386453;10795.730106747355;11039.613613523528;11040.116790656728;11365.204435689506;11077.446127693584;11133.053690436323;11147.331160130418;11149.21955463118;11155.553777848876;10949.83575246372;11015.449227538671;11017.228793984425;11025.338479691203;11029.117670586334;10616.2037847161;10685.198610104273;10709.625163119254;10711.464426778708;10717.78564428707;44355.33466997997;44633.55366446327;44722;45103.32345014827;45232.3215151773;3344.147242453159;3652.579953305351;3706.8517259309597;3754.4743735877;4394.736315821061;33799.6620033799;33862.49206261908;34081;34129.82935085333;34218.82890585556;4531.274996000624;4534.183846907556;4576.496585375606;4584.404027476407;4687.460941991646;342324;342733;344420;344500;345375;63815.36184638094;64309.356906431276;64322.35677643164;64383;64607.353926460135;40039.1992160161;40905.59094409018;40969.590304097175;43200.783996079816;67865.32134678689;18307.908460457613;18452.35416760407;18453.1696073677;18502.074896255403;18506.537336566424;11342.092632589372;11573.76852462946;14045.367958441895;14419.35112919921;14609.853901461061;20135.39593812188;20806.375808725763;20991;21076.15695372168;21114;7211.350978411968;7238.081908294306;7409.518381305305;7470.3276705096805;7555.697772089165;888.1606881496923;944.7576436985343;947.7204224753576;1001.9098281154731;1030.4538594544904;33350.49974250388;33608.5182466075;33781.49327760086;34188.48717269243;34448.31103377918;1130.2370899642708;1137.4597066393562;1151.5048529132553;1154.5670373609896;1168.3282112785182;14500.419983200765;17471.213795379237;18511.90744046298;18594.163262652943;18996.620067598848;496779;499996;500385;504663;506898;62339.6883015582;62362;62739.372606273355;62815.371846280956;62823;1281.6988007818156;1321.4449931028992;1335.933203339829;1379.5654368873772;1406.367134789349;32321.51517727189;33574.83212584019;34641.30717385688;34864.650119184094;34896.65103349036;54691.72654136704;55214;55293.723531382086;55345;55366.72316638391;379431;379540;381338;471007;482608;30279.394412112077;30548.541771873883;30592;32143;32415.351692965538;48501.27248091211;50855.74572127116;51050;51383.22925156201;51498.22752658788;42400.36399453949;42868.35697464478;43449.56550434455;43593.78203108964;45194.54805451904;42116.368254475594;42795;43039.354409683256;43768.78115609402;44188.7790561058;42548.57451425446;42766;43274.78362608167;43915.78042109897;43998.12003759971;35774;38563.807180963915;38750;38838.80580597079;39389;29463.26341841471;29513.26216844596;29778;29791.85104074466;29854.402911942074 19:09:34 INFO - PROCESS | 5572 | |2;jquery.html;405.5457887166372;411.436332224852;412.0851709205563;417.72847649028137;429.19954285258;17638;19468.707969380433;19616.313429029968;20539.691904621443;20566.383008509732;19585.90207048963;19755.901220493914;19925.900370498162;19939.700904486443;20104.69842952357;5427.375851777047;5786.56600754943;5817.447342502455;5822.650640961535;5830.5044071254;5608.943910560883;5756.280464941882;5831.096180092092;5837.270341207349;5837.299524057119;5723.599348045638;5759.107338362561;5764.106563482667;5765.164051212592;5765.250517432728;5557.72211389433;5690.032694441913;5722.828315150552;5755.0504166812425;5764.452377024176;5140.280360749498;5154.92267615986;5162.12243918531;5166.354205724285;5182.067227898977;21841.453963650878;22559.661605075937;22713.54572908549;23486;23762.52474950491;2327.5810354136247;2461.59383701689;2493.0651005872796;2621.449495605925;2892.652881654215;10474.528646210938;10609.098226650744;10766.784664306748;10886.129109671207;10988.450577471094;1828.5702859828064;1855.4711907106546;1920.1167462967053;1974.3978086683555;2048.7848775878615;78382.6080869592;78711.60644196742;79354;80676.59661701653;81262.59368703178;10981.670549883516;11085.556577736801;11183.720406989727;11230.270032447865;11321.94339028316;15690.450834220748;15722.213889305718;15730.370785168694;15743.921280393524;15751.291191896393;7902.24928631779;8022.719204827919;8083.676652933935;8171.019477662719;8283.917160828436;7579.355754760907;7668.1181664109345;7811.843763124819;7856.292933636;7901.723439679584;4728.148933192058;4767.547083027107;4780.593649539828;4825.662203645781;4884.584810291165;1987.7117817916321;2017.6872584749387;2056.8765874047604;2057.2696692674185;2071.275053731212;987.827130252203;988.1699372526969;1023.2223510132204;1030.1501261459177;1030.8866024737272;18660.34688785886;18715.625687486176;18794.530136746416;19099.2360305589;19484.220631174594;959.9040095990484;961.9663311784194;1002.7493126718321;1024.882138554076;1032.396048311746;10178.491075446198;10793.568257269779;10798.78402431947;10811.243212975174;10854.45727713611;513793;519045;519344;532500;553595;89614.55192724125;90448.54775726079;90847.54576227209;91677.54161229284;92177;969.3311614985778;970.684527528556;991.0881988570533;993.7515621094726;999.2905037423366;16465.506034818976;16894.66210675804;17245.827541724422;17272.740908886124;17524.211410486303;29742;42035.159296814505;42441.36337954995;42854.78572607242;43129.784351078044;56644.7167764175;58516.82966340735;59097.40902590919;60471.09293360515;60770.392296076476;7685.846283074419;8222;8236.83526329458;8255.669773209005;8579.914200857911;11468.197226194086;11630.068664980752;11797.587084452003;12305.44625491873;12307.20003199789;9798.65704700332;9934.602615895283;10307.742306442398;10322.329048611817;10510.579576817148;10153.49232538385;10277.74305642365;10320.896791031993;10485.790284194425;10874.619388321687;10237.18102551809;10382.584696612052;10409.375437473776;10543.94728026355;10882.183836212474;7979.321757650665;8014.318782903519;8179.1411901751835;8205.097439281793;8246.711365102432;9786.41281523111;9914.702558923243;9975.850362244717;10040.799184016425;10324.845127323246 19:09:34 INFO - PROCESS | 5572 | |3;mootools.html;1341.7853143497043;1478.0318891126312;1509.2981763479984;1656.1470842516105;1799.2802878848458;8059.395545334101;8516.574171291433;8866.645334186642;8888.999999999993;9103.31725120617;8933;9012.143846334604;9013.233875120623;9020.548972551363;9026.684066057689;2408.8795560222006;2417.987910060447;2438.2563318187945;2439.7072351317865;2444.340028192387;2368.4434157972864;2436.3665446983737;2437.8415402998835;2437.9512409751796;2439.0853429963763;2366.148186652804;2383.1301574925114;2391.449966507704;2397.5444665513583;2407.8555286682767;2160.6650969099815;2169.8915054247223;2183.15948359881;2183.2467798609455;2242.4281808138985;2361.2680069178436;2380.238323736405;2402.327348342467;2409.4337830609793;2409.831311808174;8664.436811607226;8686.86969695455;8767.561621918878;8783.297336213087;8892.777680558038;5291.550218231455;5488.121900495901;5500.312460942382;5500.559955203574;5504.669719816784;3404.415815149156;3499.7375196860085;3581.7850928944345;3595.227026189382;3608.224231790178;1040.6461802986946;1068.7862427514528;1073.1843798712957;1073.431081526792;1077.647552321839;12040.277583345027;12077.818832717428;12132.45403956824;12144.271343719405;12217.083718721131;4335.479742430865;4566.452025756864;4623.445186577632;4666.859994200179;4696.319033740088;9942.850857237147;9945.552450139761;9953.104220620178;9953.303268771117;9963.202943764627;5433.185022246695;5436.537894278951;5439.48324909133;5443.891122177534;5451.209574611659;4394.956050439519;4517.480489743722;4562.429696287964;4629.282461218517;5106.080905436983;3832.616738326145;3850.537935447764;3990.620891015349;3996.8800935971385;4004.1791432756213;1084.1706094837455;1089.455272363818;1099.934003959765;1109.073923274059;1115.6876074699098;789.6486063701713;881.0968757024101;901.6213190460022;917.6696389299847;925.8888933328044;5854.2389489366105;5941.376155503696;5995.43043410884;6013.548983826143;6145.170401995673;430.37165738022213;991.6182269826024;1047.738065483629;1050.6480329089688;1078.1374900079904;4119.443875076826;4216.262154123016;4227.598378154071;4233.830646774095;4262.104957958863;10775.676729698122;11236;11239.494222759831;11265.436728163559;11271.718207044889;10906.672799816019;11147.498362573704;11151;11197.384143872165;11217.15871309655;8253.876191857127;8286.58567071644;8404.327653787683;8408.83182336362;8414.284785793276;5735.913961290667;5777.16231146473;5829.562782791392;5835.883282334415;5909.556783241188;9933.602655893683;9971.800563988534;10031.398116113056;10100.696979090639;10108.747281318027;9260.629574816932;9614.567344469377;9862;10259.7435064124;10328.431936243578;7332.046833911558;7503.7748867534065;7547.547147171187;7616.771496855104;7717.536947783151;8798.780030499289;8805;8865.335099867407;8868.733937981613;8890.06654301289;7559.773206803805;7607.35337496319;7814.140444551209;7918.445708800328;8031.437799353989;7734.883976740466;7772.261635144652;7850.842983140419;7860;8014.519128852287;7086.610236436839;7192.352688258082;7195.352418282379;7197.568145911262;7203.567785932861;5021.623378246719;5251.186066159751;5299.258103865539;5495.780168793203;5522.91715624274;5216.765245563883;5228.37259528859;5255.474452554714;5262.736863156751;5280.339957505312 19:09:34 INFO - PROCESS | 5572 | |4;prototype.html;343.7078483289205;344.9448088305871;353.27996200013854;357.6495034865833;358.7237826873309;5272.393674727407;5273.499017593332;5290.523852853243;5295.152775555912;5342.439043900393;5002.424721157068;5102.693838369701;5142.202958541423;5224.693826543365;5248.947510524894;4164.8542301019315;4170.47869016373;4175.728577642443;4209.49486061673;4223.1975924574235;4210.3684447332835;4226.788660566975;4242.257604919142;4278.4010238566625;4282.571742825724;4135.648469880064;4171.624553790159;4184.68182522506;4189;4201.2017716633745;4165.146145040259;4176.3085950190225;4178.185253875496;4190.371444283352;4194.853180138711;3747.625237476258;3764.4729737836724;3776.678982286509;3790.374588192945;3795.6583907448185;4969.8012079516775;5088.92366614501;5153.330067091254;5212.6611770235195;5213.921791173136;4722.386089808338;4750.928736068961;4762.928556071662;4774.379330686988;4795.568398844086;5476.452354764531;5496.5053145217125;5498.835034948958;5512.283403157604;5540.279763630742;4014.9402390438245;4118.691098167649;4157.896052598679;4175.812088455997;4193.601607847249;5585.832425027215;5702.828915132512;5733.254676892019;5744.741486633112;5748.2527271454865;4203.579642035772;4210.410542524049;4213.871994642685;4226.809793559297;4252.619167761575;4010.598940105966;4302.343597714902;4309.1381723655395;4309.159713855831;4309.870703878889;4743.1225223333795;4743.549362810528;4749.786259618326;4766.213574760195;4795.544423279783;5548.389677135512;5552.861178470571;5557.972210138923;5566.248556444828;5581.553475721932;1388.4723804954053;1394.3237529798039;1399.4192410149878;1410.2314238739912;1415.8938079644067;981.3277904635347;982.6560703753629;985.2659768472533;989.1592146675268;1001.8096561653263;5009.7244651544515;5197.194434862602;5209.947900520946;5253.317068781034;5297.284866542967;5464;5487.341519017664;5497.945020549823;5503.2020357048;5503.9174412383845;5168.054209100852;5225.555827754607;5226.608004399685;5235.162374020215;5254.6847189168775;2805.7032986241493;2904.622399088147;2928.546075358323;2936.9573801300658;2942.264433891527;5658.490735833795;5697.829065128053;5702.54379649627;5710.914336284959;5741.392410125171;5463.508284254357;5574.777008919597;5584.776608935678;5632.352279487829;5685.1756495307945;5663.375439718583;5693.95017793594;5695.373508914016;5720.542356611461;5738.368779434259;5519.199716041232;5521.365043019943;5535.225068490495;5560.833175004756;5561.749721262472;5382.865428364322;5407.864803379947;5433.646812957303;5469.72651367438;5525.944740552543;5684.261046063985;5699.51554117888;5709.22925405065;5714.20001199824;5720.199172115824;4006.3790112532606;4025.416314634303;4056.776877271778;4061.4313996039987;4062.7765472899273;5379.623426360117;5390.328998010629;5461.726913654222;5461.9180712290145;5485.472773541957;5259.553622753773;5297.973510132425;5325.520703136736;5337.064614462108;5338.252644629677;5131.692098474104;5276.577873770012;5282.4717528246865;5352.1971704243115;5354.919676204782;5284.418713941386;5294.23233631129;5332;5333.4133245343755;5346.78612855497;5343.358796944236;5364.195370694271;5384.7576859040655;5391.75737091824;5472.726363681721;5051.823186188612;5283.788648454019;5289.206619007025;5291.232771248225;5328 19:09:34 INFO - PROCESS | 5572 | |5;yui.html;755.5919803306215;774.6901239504198;820.1470470710461;855.4183155454289;894.1728900766791;15142.848571514282;16139.273732682042;16155.596110097253;16176.514704558853;16256.512304630865;14755.77866332006;14831.110133392003;14849.777253341208;14893.851061489382;14910.552683419488;711.985760284794;729.106844115959;847.2417186617973;853.2491407561353;869.0397111192136;856.5674334461088;860.1312674199073;861.7543999960012;862.4178679391417;862.965481380744;918.0498184379169;920.5213289089669;920.8158368326328;922.4327038871113;923.5843870258377;906.4425378392307;915.1946287267228;915.3135148638521;916.1662886773038;919.8620206968941;672.9865402691968;674.190970834995;674.5817593092314;674.676155445389;674.8245456181379;15060.246987650684;15107.848921510864;15159.62100947474;15160.241987900561;15196.392144314214;14382.92808535961;15266.694666106727;15429;15774;15805.130717810516;11768.235064720766;11998.64004079879;12217.328046957413;12424.813627795591;12445.875541244654;3784.3188226119287;3787.696984241254;3828.55971563271;3844.7693138411787;3934.8229329680235;58482.707586461795;64363.67818160879;64537;65553.67223163901;67903.3209667902;11169.162312826571;11573.88426115745;11686.18196726238;11861.76276474483;11887.762244755057;22349.55300893973;22351;22354.32937011892;22573.4356641082;22631.43421414445;9056.773580660405;9239.538023098818;9286.210672092948;9430.245580353556;9507.239420846316;4827.565519103297;4870.9269360959615;4908.852734417973;5024.547790698854;5166.405863325691;3370.1743072946792;3442.9311413771584;3476.478528220787;3488.389531831919;3491.127218195451;617.0928734759896;628.5914155798768;630.5271046714964;632.4150161100963;633.7940169444856;614.0394455760274;644.1079105439001;680.2483256002146;694.8749225139524;709.1277728394105;8855.33584981129;8991.865122023175;9036.774080647905;9309.162175404246;9468.19520340765;570.8375599180577;577.2812848004262;587.8736071744595;590.271015296109;701.4809041309496;4451.710638808467;4513.6684678020065;4539.6368290536675;4542.250528662734;4587.082583483317;58628;60755.39244607586;97422.5128874351;98539.50730246303;98809.50595246977;83942;86229;86465;87823.56088219646;88065.67901481483;75629.62185189148;77053;77357;78546.6072669633;79032;8742.169493898069;8819.647214111365;9009.504477253813;9176.632934682539;9244.768880778034;51136;51947.922083117766;52743;53318.200226997404;53927.43959130934;63866;69220.65389673221;71902.64048679723;72477;74916.25083749092;11620.6513804586;11720.531178752753;11797.587084452003;11929.880701192877;12052.156349055482;12432.751344972869;12686.175398599062;12923.095383323453;12983.870161298266;12996.610101696964;11464.598739044093;11532.365719885487;11714.355710435668;11814;11920.821187682364;11326.263792853768;11434.313941163557;11457.255278406878;11701.239419437708;11868.465919033837;11422.885771142182;11685.123615729024;11699.649010529698;11774.3524106175;11919.940400297943;9478.905210947802;9699.418034917928;9718;9730.41617502952;9826.75433114178;10139.543720532742;10161.491925403849;10175.28772985884;10217.438040907524;10219 19:09:34 INFO - PROCESS | 5572 | __end_tp_report 19:09:34 INFO - PROCESS | 5572 | __start_cc_report 19:09:34 INFO - PROCESS | 5572 | _x_x_mozilla_cycle_collect,944 19:09:34 INFO - PROCESS | 5572 | __end_cc_report 19:09:34 INFO - PROCESS | 5572 | __startTimestamp1485918574265__endTimestamp 19:09:34 INFO - PROCESS | 5572 | 19:09:34 INFO - PROCESS | 5572 | ------- Summary: start ------- 19:09:34 INFO - PROCESS | 5572 | Number of tests: 6 19:09:34 INFO - PROCESS | 5572 | 19:09:34 INFO - PROCESS | 5572 | [#0] dojo.html Cycles:165 Average:5430.18 Median:5640.94 stddev:596.12 (10.6%) stddev-sans-first:570.35 19:09:34 INFO - PROCESS | 5572 | Values: 3144.9 3171.5 3179.8 3197.5 3219.4 5380.0 5429.2 5441.0 5456.8 5461.0 5486.4 5498.4 5526.0 5526.8 5529.9 5037.0 5059.3 5084.9 5115.8 5153.6 4922.2 4945.5 4958.5 5087.1 5140.2 4950.6 5050.0 5060.5 5062.8 5071.5 5061.0 5064.1 5067.3 5073.2 5104.2 4855.5 4871.5 4969.1 4980.5 5039.6 5340.9 5347.0 5360.2 5366.7 5414.0 5748.5 5762.4 5798.3 5844.9 5849.1 5733.8 5873.0 5882.9 5901.3 5901.9 5423.9 5424.7 5456.2 5475.2 5536.5 5930.9 5934.1 5942.9 5944.2 5954.9 5541.3 5731.9 5733.7 5747.5 5747.9 5638.7 5730.1 5767.8 5773.7 5832.2 5736.5 5773.2 5783.2 5783.2 5783.7 5779.5 5782.6 5784.1 5787.0 5820.7 4476.3 4484.4 4507.9 4509.1 4522.2 3988.4 4023.9 4032.4 4050.9 4051.0 5531.0 5552.7 5553.2 5571.7 5605.4 5702.7 5748.9 5781.3 5798.4 5845.7 5609.9 5615.3 5625.8 5637.4 5646.3 5038.0 5053.6 5054.4 5056.5 5111.6 5842.7 5966.5 6090.5 6097.3 6111.5 5820.6 5845.7 5857.7 5906.7 5939.8 6084.5 6099.6 6104.6 6104.7 6108.6 5610.1 5727.3 5781.0 5784.4 5784.5 5683.6 5759.2 5779.2 5809.8 5898.2 5655.5 5705.4 5717.2 5717.3 5722.0 5689.0 5698.0 5700.2 5712.8 5714.3 5498.2 5541.6 5662.9 5665.4 5715.3 5771.0 5804.4 5811.9 5822.0 5833.4 5423.1 5461.3 5628.4 5640.9 5643.2 19:09:34 INFO - PROCESS | 5572 | 19:09:34 INFO - PROCESS | 5572 | [#1] ext.html Cycles:180 Average:59819.74 Median:209950.90 stddev:112780.60 (53.7%) stddev-sans-first:113009.24 19:09:34 INFO - PROCESS | 5572 | Values: 568.1 578.6 629.8 644.3 665.3 35390.5 38001.2 46601.8 47570.8 47698.0 48228.5 48505.3 48627.0 48768.0 48800.3 8472.7 8985.3 9133.9 9137.2 9137.3 8566.2 10795.7 11039.6 11040.1 11365.2 11077.4 11133.1 11147.3 11149.2 11155.6 10949.8 11015.4 11017.2 11025.3 11029.1 10616.2 10685.2 10709.6 10711.5 10717.8 44355.3 44633.6 44722.0 45103.3 45232.3 3344.1 3652.6 3706.9 3754.5 4394.7 33799.7 33862.5 34081.0 34129.8 34218.8 4531.3 4534.2 4576.5 4584.4 4687.5 342324.0 342733.0 344420.0 344500.0 345375.0 63815.4 64309.4 64322.4 64383.0 64607.4 40039.2 40905.6 40969.6 43200.8 67865.3 18307.9 18452.4 18453.2 18502.1 18506.5 11342.1 11573.8 14045.4 14419.4 14609.9 20135.4 20806.4 20991.0 21076.2 21114.0 7211.4 7238.1 7409.5 7470.3 7555.7 888.2 944.8 947.7 1001.9 1030.5 33350.5 33608.5 33781.5 34188.5 34448.3 1130.2 1137.5 1151.5 1154.6 1168.3 14500.4 17471.2 18511.9 18594.2 18996.6 496779.0 499996.0 500385.0 504663.0 506898.0 62339.7 62362.0 62739.4 62815.4 62823.0 1281.7 1321.4 1335.9 1379.6 1406.4 32321.5 33574.8 34641.3 34864.7 34896.7 54691.7 55214.0 55293.7 55345.0 55366.7 379431.0 379540.0 381338.0 471007.0 482608.0 30279.4 30548.5 30592.0 32143.0 32415.4 48501.3 50855.7 51050.0 51383.2 51498.2 42400.4 42868.4 43449.6 43593.8 45194.5 42116.4 42795.0 43039.4 43768.8 44188.8 42548.6 42766.0 43274.8 43915.8 43998.1 35774.0 38563.8 38750.0 38838.8 39389.0 29463.3 29513.3 29778.0 29791.9 29854.4 19:09:34 INFO - PROCESS | 5572 | 19:09:34 INFO - PROCESS | 5572 | [#2] jquery.html Cycles:180 Average:29780.82 Median:21226.44 stddev:86847.93 (409.1%) stddev-sans-first:87063.55 19:09:34 INFO - PROCESS | 5572 | Values: 405.5 411.4 412.1 417.7 429.2 17638.0 19468.7 19616.3 20539.7 20566.4 19585.9 19755.9 19925.9 19939.7 20104.7 5427.4 5786.6 5817.4 5822.7 5830.5 5608.9 5756.3 5831.1 5837.3 5837.3 5723.6 5759.1 5764.1 5765.2 5765.3 5557.7 5690.0 5722.8 5755.1 5764.5 5140.3 5154.9 5162.1 5166.4 5182.1 21841.5 22559.7 22713.5 23486.0 23762.5 2327.6 2461.6 2493.1 2621.4 2892.7 10474.5 10609.1 10766.8 10886.1 10988.5 1828.6 1855.5 1920.1 1974.4 2048.8 78382.6 78711.6 79354.0 80676.6 81262.6 10981.7 11085.6 11183.7 11230.3 11321.9 15690.5 15722.2 15730.4 15743.9 15751.3 7902.2 8022.7 8083.7 8171.0 8283.9 7579.4 7668.1 7811.8 7856.3 7901.7 4728.1 4767.5 4780.6 4825.7 4884.6 1987.7 2017.7 2056.9 2057.3 2071.3 987.8 988.2 1023.2 1030.2 1030.9 18660.3 18715.6 18794.5 19099.2 19484.2 959.9 962.0 1002.7 1024.9 1032.4 10178.5 10793.6 10798.8 10811.2 10854.5 513793.0 519045.0 519344.0 532500.0 553595.0 89614.6 90448.5 90847.5 91677.5 92177.0 969.3 970.7 991.1 993.8 999.3 16465.5 16894.7 17245.8 17272.7 17524.2 29742.0 42035.2 42441.4 42854.8 43129.8 56644.7 58516.8 59097.4 60471.1 60770.4 7685.8 8222.0 8236.8 8255.7 8579.9 11468.2 11630.1 11797.6 12305.4 12307.2 9798.7 9934.6 10307.7 10322.3 10510.6 10153.5 10277.7 10320.9 10485.8 10874.6 10237.2 10382.6 10409.4 10543.9 10882.2 7979.3 8014.3 8179.1 8205.1 8246.7 9786.4 9914.7 9975.9 10040.8 10324.8 19:09:34 INFO - PROCESS | 5572 | 19:09:34 INFO - PROCESS | 5572 | [#3] mootools.html Cycles:180 Average:5827.06 Median:5222.57 stddev:3299.78 (63.2%) stddev-sans-first:3291.82 19:09:34 INFO - PROCESS | 5572 | Values: 1341.8 1478.0 1509.3 1656.1 1799.3 8059.4 8516.6 8866.6 8889.0 9103.3 8933.0 9012.1 9013.2 9020.5 9026.7 2408.9 2418.0 2438.3 2439.7 2444.3 2368.4 2436.4 2437.8 2438.0 2439.1 2366.1 2383.1 2391.4 2397.5 2407.9 2160.7 2169.9 2183.2 2183.2 2242.4 2361.3 2380.2 2402.3 2409.4 2409.8 8664.4 8686.9 8767.6 8783.3 8892.8 5291.6 5488.1 5500.3 5500.6 5504.7 3404.4 3499.7 3581.8 3595.2 3608.2 1040.6 1068.8 1073.2 1073.4 1077.6 12040.3 12077.8 12132.5 12144.3 12217.1 4335.5 4566.5 4623.4 4666.9 4696.3 9942.9 9945.6 9953.1 9953.3 9963.2 5433.2 5436.5 5439.5 5443.9 5451.2 4395.0 4517.5 4562.4 4629.3 5106.1 3832.6 3850.5 3990.6 3996.9 4004.2 1084.2 1089.5 1099.9 1109.1 1115.7 789.6 881.1 901.6 917.7 925.9 5854.2 5941.4 5995.4 6013.5 6145.2 430.4 991.6 1047.7 1050.6 1078.1 4119.4 4216.3 4227.6 4233.8 4262.1 10775.7 11236.0 11239.5 11265.4 11271.7 10906.7 11147.5 11151.0 11197.4 11217.2 8253.9 8286.6 8404.3 8408.8 8414.3 5735.9 5777.2 5829.6 5835.9 5909.6 9933.6 9971.8 10031.4 10100.7 10108.7 9260.6 9614.6 9862.0 10259.7 10328.4 7332.0 7503.8 7547.5 7616.8 7717.5 8798.8 8805.0 8865.3 8868.7 8890.1 7559.8 7607.4 7814.1 7918.4 8031.4 7734.9 7772.3 7850.8 7860.0 8014.5 7086.6 7192.4 7195.4 7197.6 7203.6 5021.6 5251.2 5299.3 5495.8 5522.9 5216.8 5228.4 5255.5 5262.7 5280.3 19:09:34 INFO - PROCESS | 5572 | 19:09:34 INFO - PROCESS | 5572 | [#4] prototype.html Cycles:180 Average:4619.15 Median:5254.00 stddev:1310.23 (24.9%) stddev-sans-first:1274.00 19:09:34 INFO - PROCESS | 5572 | Values: 343.7 344.9 353.3 357.6 358.7 5272.4 5273.5 5290.5 5295.2 5342.4 5002.4 5102.7 5142.2 5224.7 5248.9 4164.9 4170.5 4175.7 4209.5 4223.2 4210.4 4226.8 4242.3 4278.4 4282.6 4135.6 4171.6 4184.7 4189.0 4201.2 4165.1 4176.3 4178.2 4190.4 4194.9 3747.6 3764.5 3776.7 3790.4 3795.7 4969.8 5088.9 5153.3 5212.7 5213.9 4722.4 4750.9 4762.9 4774.4 4795.6 5476.5 5496.5 5498.8 5512.3 5540.3 4014.9 4118.7 4157.9 4175.8 4193.6 5585.8 5702.8 5733.3 5744.7 5748.3 4203.6 4210.4 4213.9 4226.8 4252.6 4010.6 4302.3 4309.1 4309.2 4309.9 4743.1 4743.5 4749.8 4766.2 4795.5 5548.4 5552.9 5558.0 5566.2 5581.6 1388.5 1394.3 1399.4 1410.2 1415.9 981.3 982.7 985.3 989.2 1001.8 5009.7 5197.2 5209.9 5253.3 5297.3 5464.0 5487.3 5497.9 5503.2 5503.9 5168.1 5225.6 5226.6 5235.2 5254.7 2805.7 2904.6 2928.5 2937.0 2942.3 5658.5 5697.8 5702.5 5710.9 5741.4 5463.5 5574.8 5584.8 5632.4 5685.2 5663.4 5694.0 5695.4 5720.5 5738.4 5519.2 5521.4 5535.2 5560.8 5561.7 5382.9 5407.9 5433.6 5469.7 5525.9 5684.3 5699.5 5709.2 5714.2 5720.2 4006.4 4025.4 4056.8 4061.4 4062.8 5379.6 5390.3 5461.7 5461.9 5485.5 5259.6 5298.0 5325.5 5337.1 5338.3 5131.7 5276.6 5282.5 5352.2 5354.9 5284.4 5294.2 5332.0 5333.4 5346.8 5343.4 5364.2 5384.8 5391.8 5472.7 5051.8 5283.8 5289.2 5291.2 5328.0 19:09:34 INFO - PROCESS | 5572 | 19:09:34 INFO - PROCESS | 5572 | [#5] yui.html Cycles:180 Average:18672.88 Median:574.06 stddev:25349.73 (4415.9%) stddev-sans-first:25385.14 19:09:34 INFO - PROCESS | 5572 | Values: 755.6 774.7 820.1 855.4 894.2 15142.8 16139.3 16155.6 16176.5 16256.5 14755.8 14831.1 14849.8 14893.9 14910.6 712.0 729.1 847.2 853.2 869.0 856.6 860.1 861.8 862.4 863.0 918.0 920.5 920.8 922.4 923.6 906.4 915.2 915.3 916.2 919.9 673.0 674.2 674.6 674.7 674.8 15060.2 15107.8 15159.6 15160.2 15196.4 14382.9 15266.7 15429.0 15774.0 15805.1 11768.2 11998.6 12217.3 12424.8 12445.9 3784.3 3787.7 3828.6 3844.8 3934.8 58482.7 64363.7 64537.0 65553.7 67903.3 11169.2 11573.9 11686.2 11861.8 11887.8 22349.6 22351.0 22354.3 22573.4 22631.4 9056.8 9239.5 9286.2 9430.2 9507.2 4827.6 4870.9 4908.9 5024.5 5166.4 3370.2 3442.9 3476.5 3488.4 3491.1 617.1 628.6 630.5 632.4 633.8 614.0 644.1 680.2 694.9 709.1 8855.3 8991.9 9036.8 9309.2 9468.2 570.8 577.3 587.9 590.3 701.5 4451.7 4513.7 4539.6 4542.3 4587.1 58628.0 60755.4 97422.5 98539.5 98809.5 83942.0 86229.0 86465.0 87823.6 88065.7 75629.6 77053.0 77357.0 78546.6 79032.0 8742.2 8819.6 9009.5 9176.6 9244.8 51136.0 51947.9 52743.0 53318.2 53927.4 63866.0 69220.7 71902.6 72477.0 74916.3 11620.7 11720.5 11797.6 11929.9 12052.2 12432.8 12686.2 12923.1 12983.9 12996.6 11464.6 11532.4 11714.4 11814.0 11920.8 11326.3 11434.3 11457.3 11701.2 11868.5 11422.9 11685.1 11699.6 11774.4 11919.9 9478.9 9699.4 9718.0 9730.4 9826.8 10139.5 10161.5 10175.3 10217.4 10219.0 19:09:34 INFO - PROCESS | 5572 | -------- Summary: end -------- 19:09:34 INFO - PROCESS | 5572 | 19:09:35 INFO - TEST-INFO | 5572: exit 0 19:09:35 INFO - TEST-OK | dromaeo_css | took 1114009ms 19:09:35 INFO - TEST-START | kraken 19:09:35 INFO - operating with platform_type : linux_ 19:09:35 INFO - Initialising browser for kraken test... 19:09:35 INFO - TEST-INFO | started process 5873 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLOQu8f/profile http://localhost:39624/getInfo.html) 19:09:39 INFO - PROCESS | 5873 | __metrics Screen width/height:1600/1200 19:09:39 INFO - PROCESS | 5873 | colorDepth:24 19:09:39 INFO - PROCESS | 5873 | Browser inner width/height: 1024/697 19:09:39 INFO - PROCESS | 5873 | __metrics 19:09:39 INFO - PROCESS | 5873 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:09:39 INFO - PROCESS | 5873 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:09:40 INFO - PROCESS | 5873 | console.error: PushService: 19:09:40 INFO - PROCESS | 5873 | stateChangeProcessEnqueue: Error transitioning state 19:09:40 INFO - PROCESS | 5873 | UnknownError 19:09:40 INFO - TEST-INFO | 5873: exit 0 19:09:40 INFO - Browser initialized. 19:09:40 INFO - Running cycle 1/1 for kraken test... 19:09:40 INFO - TEST-INFO | started process 5961 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLOQu8f/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:09:52 INFO - PROCESS | 5961 | RSS: Main: 144994304 19:09:52 INFO - PROCESS | 5961 | 19:09:54 INFO - PROCESS | 5961 | Cycle 1(1): loaded http://localhost:39624/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39624/tests/kraken/driver.html?testName=audio-beat-detection) 19:09:55 INFO - PROCESS | 5961 | RSS: Main: 186568704 19:09:55 INFO - PROCESS | 5961 | 19:09:58 INFO - PROCESS | 5961 | Cycle 1(1): loaded http://localhost:39624/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39624/tests/kraken/driver.html?testName=audio-dft) 19:09:58 INFO - PROCESS | 5961 | RSS: Main: 175726592 19:09:58 INFO - PROCESS | 5961 | 19:10:01 INFO - PROCESS | 5961 | Cycle 1(1): loaded http://localhost:39624/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39624/tests/kraken/driver.html?testName=audio-fft) 19:10:02 INFO - PROCESS | 5961 | RSS: Main: 267776000 19:10:02 INFO - PROCESS | 5961 | 19:10:04 INFO - PROCESS | 5961 | Cycle 1(1): loaded http://localhost:39624/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39624/tests/kraken/driver.html?testName=audio-oscillator) 19:10:04 INFO - PROCESS | 5961 | RSS: Main: 174059520 19:10:04 INFO - PROCESS | 5961 | 19:10:07 INFO - PROCESS | 5961 | Cycle 1(1): loaded http://localhost:39624/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39624/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:10:07 INFO - PROCESS | 5961 | RSS: Main: 177266688 19:10:07 INFO - PROCESS | 5961 | 19:10:10 INFO - PROCESS | 5961 | Cycle 1(1): loaded http://localhost:39624/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39624/tests/kraken/driver.html?testName=imaging-darkroom) 19:10:11 INFO - PROCESS | 5961 | RSS: Main: 215584768 19:10:11 INFO - PROCESS | 5961 | 19:10:14 INFO - PROCESS | 5961 | Cycle 1(1): loaded http://localhost:39624/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39624/tests/kraken/driver.html?testName=imaging-desaturate) 19:10:15 INFO - PROCESS | 5961 | RSS: Main: 213966848 19:10:15 INFO - PROCESS | 5961 | 19:10:17 INFO - PROCESS | 5961 | Cycle 1(1): loaded http://localhost:39624/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39624/tests/kraken/driver.html?testName=json-parse-financial) 19:10:18 INFO - PROCESS | 5961 | RSS: Main: 213385216 19:10:18 INFO - PROCESS | 5961 | 19:10:19 INFO - PROCESS | 5961 | Cycle 1(1): loaded http://localhost:39624/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39624/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:10:20 INFO - PROCESS | 5961 | RSS: Main: 175104000 19:10:20 INFO - PROCESS | 5961 | 19:10:22 INFO - PROCESS | 5961 | Cycle 1(1): loaded http://localhost:39624/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39624/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:10:22 INFO - PROCESS | 5961 | RSS: Main: 182198272 19:10:22 INFO - PROCESS | 5961 | 19:10:24 INFO - PROCESS | 5961 | Cycle 1(1): loaded http://localhost:39624/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39624/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:10:25 INFO - PROCESS | 5961 | RSS: Main: 188932096 19:10:25 INFO - PROCESS | 5961 | 19:10:27 INFO - PROCESS | 5961 | Cycle 1(1): loaded http://localhost:39624/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39624/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:10:28 INFO - PROCESS | 5961 | RSS: Main: 196694016 19:10:28 INFO - PROCESS | 5961 | 19:10:31 INFO - PROCESS | 5961 | Cycle 1(1): loaded http://localhost:39624/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39624/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:10:31 INFO - PROCESS | 5961 | RSS: Main: 193708032 19:10:31 INFO - PROCESS | 5961 | 19:10:33 INFO - PROCESS | 5961 | Cycle 1(1): loaded http://localhost:39624/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39624/tests/kraken/driver.html?testName=ai-astar) 19:10:33 INFO - PROCESS | 5961 | RSS: Main: 245354496 19:10:33 INFO - PROCESS | 5961 | 19:10:33 INFO - PROCESS | 5961 | __start_tp_report 19:10:33 INFO - PROCESS | 5961 | _x_x_mozilla_page_load 19:10:33 INFO - PROCESS | 5961 | _x_x_mozilla_page_load_details 19:10:33 INFO - PROCESS | 5961 | |i|pagename|runs| 19:10:33 INFO - PROCESS | 5961 | |0;ai-astar;101;110;113;113;112;114;111;110;113;111 19:10:33 INFO - PROCESS | 5961 | |1;audio-beat-detection;146;118;119;152;118;117;118;119;144;117 19:10:33 INFO - PROCESS | 5961 | |2;audio-dft;161;160;160;159;158;160;158;158;161;158 19:10:33 INFO - PROCESS | 5961 | |3;audio-fft;74;76;75;107;75;75;75;76;107;74 19:10:33 INFO - PROCESS | 5961 | |4;audio-oscillator;82;81;127;81;82;81;102;81;81;115 19:10:33 INFO - PROCESS | 5961 | |5;imaging-gaussian-blur;117;116;117;116;117;117;117;117;116;116 19:10:33 INFO - PROCESS | 5961 | |6;imaging-darkroom;146;146;146;146;146;145;145;146;146;145 19:10:33 INFO - PROCESS | 5961 | |7;imaging-desaturate;84;84;85;84;85;85;84;85;84;85 19:10:33 INFO - PROCESS | 5961 | |8;json-parse-financial;50;50;51;50;50;51;50;50;50;50 19:10:33 INFO - PROCESS | 5961 | |9;json-stringify-tinderbox;61;59;59;59;59;60;59;59;59;60 19:10:33 INFO - PROCESS | 5961 | |10;stanford-crypto-aes;82;77;75;75;76;75;75;83;75;75 19:10:33 INFO - PROCESS | 5961 | |11;stanford-crypto-ccm;123;130;124;126;122;136;121;122;164;124 19:10:33 INFO - PROCESS | 5961 | |12;stanford-crypto-pbkdf2;159;155;156;158;154;157;161;161;153;154 19:10:33 INFO - PROCESS | 5961 | |13;stanford-crypto-sha256-iterative;63;68;63;63;63;61;62;65;62;61 19:10:33 INFO - PROCESS | 5961 | __end_tp_report 19:10:33 INFO - PROCESS | 5961 | __start_cc_report 19:10:33 INFO - PROCESS | 5961 | _x_x_mozilla_cycle_collect,1571 19:10:33 INFO - PROCESS | 5961 | __end_cc_report 19:10:33 INFO - PROCESS | 5961 | __startTimestamp1485918633556__endTimestamp 19:10:33 INFO - PROCESS | 5961 | 19:10:33 INFO - PROCESS | 5961 | ------- Summary: start ------- 19:10:33 INFO - PROCESS | 5961 | Number of tests: 14 19:10:33 INFO - PROCESS | 5961 | 19:10:33 INFO - PROCESS | 5961 | [#0] ai-astar Cycles:10 Average:110.80 Median:112.50 stddev:3.71 (3.3%) stddev-sans-first:1.45 19:10:33 INFO - PROCESS | 5961 | Values: 101.0 110.0 113.0 113.0 112.0 114.0 111.0 110.0 113.0 111.0 19:10:33 INFO - PROCESS | 5961 | 19:10:33 INFO - PROCESS | 5961 | [#1] audio-beat-detection Cycles:10 Average:126.80 Median:119.00 stddev:14.32 (12.0%) stddev-sans-first:13.40 19:10:33 INFO - PROCESS | 5961 | Values: 146.0 118.0 119.0 152.0 118.0 117.0 118.0 119.0 144.0 117.0 19:10:33 INFO - PROCESS | 5961 | 19:10:33 INFO - PROCESS | 5961 | [#2] audio-dft Cycles:10 Average:159.30 Median:160.00 stddev:1.25 (0.8%) stddev-sans-first:1.17 19:10:33 INFO - PROCESS | 5961 | Values: 161.0 160.0 160.0 159.0 158.0 160.0 158.0 158.0 161.0 158.0 19:10:33 INFO - PROCESS | 5961 | 19:10:33 INFO - PROCESS | 5961 | [#3] audio-fft Cycles:10 Average:81.40 Median:75.00 stddev:13.51 (18.0%) stddev-sans-first:14.06 19:10:33 INFO - PROCESS | 5961 | Values: 74.0 76.0 75.0 107.0 75.0 75.0 75.0 76.0 107.0 74.0 19:10:33 INFO - PROCESS | 5961 | 19:10:33 INFO - PROCESS | 5961 | [#4] audio-oscillator Cycles:10 Average:91.30 Median:81.00 stddev:17.17 (21.2%) stddev-sans-first:17.88 19:10:33 INFO - PROCESS | 5961 | Values: 82.0 81.0 127.0 81.0 82.0 81.0 102.0 81.0 81.0 115.0 19:10:33 INFO - PROCESS | 5961 | 19:10:33 INFO - PROCESS | 5961 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 19:10:33 INFO - PROCESS | 5961 | Values: 117.0 116.0 117.0 116.0 117.0 117.0 117.0 117.0 116.0 116.0 19:10:33 INFO - PROCESS | 5961 | 19:10:33 INFO - PROCESS | 5961 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 19:10:33 INFO - PROCESS | 5961 | Values: 146.0 146.0 146.0 146.0 146.0 145.0 145.0 146.0 146.0 145.0 19:10:33 INFO - PROCESS | 5961 | 19:10:33 INFO - PROCESS | 5961 | [#7] imaging-desaturate Cycles:10 Average:84.50 Median:85.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 19:10:33 INFO - PROCESS | 5961 | Values: 84.0 84.0 85.0 84.0 85.0 85.0 84.0 85.0 84.0 85.0 19:10:33 INFO - PROCESS | 5961 | 19:10:33 INFO - PROCESS | 5961 | [#8] json-parse-financial Cycles:10 Average:50.20 Median:50.00 stddev:0.42 (0.8%) stddev-sans-first:0.44 19:10:33 INFO - PROCESS | 5961 | Values: 50.0 50.0 51.0 50.0 50.0 51.0 50.0 50.0 50.0 50.0 19:10:33 INFO - PROCESS | 5961 | 19:10:33 INFO - PROCESS | 5961 | [#9] json-stringify-tinderbox Cycles:10 Average:59.40 Median:59.00 stddev:0.70 (1.2%) stddev-sans-first:0.44 19:10:33 INFO - PROCESS | 5961 | Values: 61.0 59.0 59.0 59.0 59.0 60.0 59.0 59.0 59.0 60.0 19:10:33 INFO - PROCESS | 5961 | 19:10:33 INFO - PROCESS | 5961 | [#10] stanford-crypto-aes Cycles:10 Average:76.80 Median:75.50 stddev:3.08 (4.1%) stddev-sans-first:2.64 19:10:33 INFO - PROCESS | 5961 | Values: 82.0 77.0 75.0 75.0 76.0 75.0 75.0 83.0 75.0 75.0 19:10:33 INFO - PROCESS | 5961 | 19:10:33 INFO - PROCESS | 5961 | [#11] stanford-crypto-ccm Cycles:10 Average:129.20 Median:125.00 stddev:13.05 (10.4%) stddev-sans-first:13.64 19:10:33 INFO - PROCESS | 5961 | Values: 123.0 130.0 124.0 126.0 122.0 136.0 121.0 122.0 164.0 124.0 19:10:33 INFO - PROCESS | 5961 | 19:10:33 INFO - PROCESS | 5961 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:156.80 Median:157.50 stddev:2.90 (1.8%) stddev-sans-first:2.96 19:10:33 INFO - PROCESS | 5961 | Values: 159.0 155.0 156.0 158.0 154.0 157.0 161.0 161.0 153.0 154.0 19:10:33 INFO - PROCESS | 5961 | 19:10:33 INFO - PROCESS | 5961 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.10 Median:63.00 stddev:2.08 (3.3%) stddev-sans-first:2.20 19:10:33 INFO - PROCESS | 5961 | Values: 63.0 68.0 63.0 63.0 63.0 61.0 62.0 65.0 62.0 61.0 19:10:33 INFO - PROCESS | 5961 | -------- Summary: end -------- 19:10:33 INFO - PROCESS | 5961 | 19:10:34 INFO - TEST-INFO | 5961: exit 0 19:10:34 INFO - TEST-OK | kraken | took 59566ms 19:10:34 INFO - SUITE-END | took 1173s 19:10:34 INFO - Completed test suite (00:19:33) 19:10:34 INFO - javascript benchmark 19:10:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3144.9213769655757, 3171.5401266816298, 3179.8410079496025, 3197.5203719442097, 3219.356128774244, 5380.0, 5429.158480435529, 5441.047816632088, 5456.781728730856, 5460.972695136519, 5486.423925487826, 5498.422665620113, 5526.0, 5526.834194974157, 5529.861753456165, 5036.974815125919, 5059.2916991621205, 5084.949150508484, 5115.846524604268, 5153.613478989072, 4922.237053256733, 4945.505449455061, 4958.454569997298, 5087.10975579274, 5140.203268493371, 4950.6287028472825, 5050.040492306468, 5060.493950604929, 5062.848114556569, 5071.467495912932, 5061.0, 5064.11378008847, 5067.34124563808, 5073.213651883945, 5104.157813960692, 4855.4901735317635, 4871.480058616187, 4969.0807200668, 4980.501949805027, 5039.647224694273, 5340.91988620171, 5346.973265133688, 5360.249565060894, 5366.70483123428, 5414.0, 5748.482636562688, 5762.423757624246, 5798.275215598051, 5844.941550584483, 5849.064149736065, 5733.770649174028, 5872.970635146839, 5882.882342353172, 5901.321348044987, 5901.852453688649, 5423.864403389907, 5424.728763561806, 5456.236126942192, 5475.233467314579, 5536.474034966672, 5930.851728706817, 5934.05055191176, 5942.910856337072, 5944.197533332945, 5954.940450595439, 5541.27963364768, 5731.8853622928145, 5733.655980641175, 5747.453991870766, 5747.942520574825, 5638.689872056994, 5730.1404789281105, 5767.769289228468, 5773.740181691834, 5832.24180856486, 5736.483716465538, 5773.220615216976, 5783.16144159103, 5783.16144159103, 5783.652980821164, 5779.479846813723, 5782.59521833476, 5784.132380142928, 5786.971065144648, 5820.708964551755, 4476.26141686618, 4484.372187893698, 4507.909841803145, 4509.12072145935, 4522.23122069249, 3988.4335427261176, 4023.8591649292134, 4032.395140728914, 4050.8784736457956, 4050.959490405058, 5530.972345138249, 5552.666839989613, 5553.194786755897, 5571.749271282802, 5605.355384130796, 5702.686352250583, 5748.856278592985, 5781.277340332458, 5798.449147330997, 5845.678487683218, 5609.859753506195, 5615.326160860723, 5625.803096891589, 5637.43625637433, 5646.322441307069, 5038.0, 5053.620978426633, 5054.418741844662, 5056.519630635084, 5111.642185047085, 5842.678652674143, 5966.522678185822, 6090.482309003695, 6097.268327800603, 6111.5110791137495, 5820.5925585210325, 5845.736941837543, 5857.677827719516, 5906.675132867562, 5939.821805345847, 6084.513238940785, 6099.573029887865, 6104.633721976696, 6104.664243466652, 6108.5723999319625, 5610.13042978339, 5727.31272247333, 5781.017227071492, 5784.392638772868, 5784.4504772046585, 5683.602147849776, 5759.1649210865025, 5779.2198053263955, 5809.767609295581, 5898.233229680114, 5655.4910058094965, 5705.4009329019855, 5717.17101020358, 5717.285339332583, 5722.027255366681, 5689.0, 5698.031334673033, 5700.230468886647, 5712.80005199816, 5714.257146570919, 5498.1752737089755, 5541.584381171348, 5662.915056274242, 5665.433456654301, 5715.31416230055, 5771.047777116729, 5804.448577385142, 5811.912821307768, 5822.0, 5833.416658334133, 5423.078076726887, 5461.2627295316215, 5628.380878103487, 5640.943590564042, 5643.181738647791], "value": 5391.09293439281, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [568.11657872009, 578.6065475476676, 629.7543957856435, 644.2880616918305, 665.2715276771933, 35390.469142962844, 38001.23997520048, 46601.76699116504, 47570.76214618931, 47698.0, 48228.51771482284, 48505.27242091363, 48627.0, 48768.0, 48800.26799598, 8472.661093556251, 8985.281177505783, 9133.862992055125, 9137.223336016446, 9137.269018478506, 8566.229039386453, 10795.730106747355, 11039.613613523528, 11040.116790656728, 11365.204435689506, 11077.446127693584, 11133.053690436323, 11147.331160130418, 11149.21955463118, 11155.553777848876, 10949.83575246372, 11015.449227538671, 11017.228793984425, 11025.338479691203, 11029.117670586334, 10616.2037847161, 10685.198610104273, 10709.625163119254, 10711.464426778708, 10717.78564428707, 44355.33466997997, 44633.55366446327, 44722.0, 45103.32345014827, 45232.3215151773, 3344.147242453159, 3652.579953305351, 3706.8517259309597, 3754.4743735877, 4394.736315821061, 33799.6620033799, 33862.49206261908, 34081.0, 34129.82935085333, 34218.82890585556, 4531.274996000624, 4534.183846907556, 4576.496585375606, 4584.404027476407, 4687.460941991646, 342324.0, 342733.0, 344420.0, 344500.0, 345375.0, 63815.36184638094, 64309.356906431276, 64322.35677643164, 64383.0, 64607.353926460135, 40039.1992160161, 40905.59094409018, 40969.590304097175, 43200.783996079816, 67865.32134678689, 18307.908460457613, 18452.35416760407, 18453.1696073677, 18502.074896255403, 18506.537336566424, 11342.092632589372, 11573.76852462946, 14045.367958441895, 14419.35112919921, 14609.853901461061, 20135.39593812188, 20806.375808725763, 20991.0, 21076.15695372168, 21114.0, 7211.350978411968, 7238.081908294306, 7409.518381305305, 7470.3276705096805, 7555.697772089165, 888.1606881496923, 944.7576436985343, 947.7204224753576, 1001.9098281154731, 1030.4538594544904, 33350.49974250388, 33608.5182466075, 33781.49327760086, 34188.48717269243, 34448.31103377918, 1130.2370899642708, 1137.4597066393562, 1151.5048529132553, 1154.5670373609896, 1168.3282112785182, 14500.419983200765, 17471.213795379237, 18511.90744046298, 18594.163262652943, 18996.620067598848, 496779.0, 499996.0, 500385.0, 504663.0, 506898.0, 62339.6883015582, 62362.0, 62739.372606273355, 62815.371846280956, 62823.0, 1281.6988007818156, 1321.4449931028992, 1335.933203339829, 1379.5654368873772, 1406.367134789349, 32321.51517727189, 33574.83212584019, 34641.30717385688, 34864.650119184094, 34896.65103349036, 54691.72654136704, 55214.0, 55293.723531382086, 55345.0, 55366.72316638391, 379431.0, 379540.0, 381338.0, 471007.0, 482608.0, 30279.394412112077, 30548.541771873883, 30592.0, 32143.0, 32415.351692965538, 48501.27248091211, 50855.74572127116, 51050.0, 51383.22925156201, 51498.22752658788, 42400.36399453949, 42868.35697464478, 43449.56550434455, 43593.78203108964, 45194.54805451904, 42116.368254475594, 42795.0, 43039.354409683256, 43768.78115609402, 44188.7790561058, 42548.57451425446, 42766.0, 43274.78362608167, 43915.78042109897, 43998.12003759971, 35774.0, 38563.807180963915, 38750.0, 38838.80580597079, 39389.0, 29463.26341841471, 29513.26216844596, 29778.0, 29791.85104074466, 29854.402911942074], "value": 21159.904809814, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [405.5457887166372, 411.436332224852, 412.0851709205563, 417.72847649028137, 429.19954285258, 17638.0, 19468.707969380433, 19616.313429029968, 20539.691904621443, 20566.383008509732, 19585.90207048963, 19755.901220493914, 19925.900370498162, 19939.700904486443, 20104.69842952357, 5427.375851777047, 5786.56600754943, 5817.447342502455, 5822.650640961535, 5830.5044071254, 5608.943910560883, 5756.280464941882, 5831.096180092092, 5837.270341207349, 5837.299524057119, 5723.599348045638, 5759.107338362561, 5764.106563482667, 5765.164051212592, 5765.250517432728, 5557.72211389433, 5690.032694441913, 5722.828315150552, 5755.0504166812425, 5764.452377024176, 5140.280360749498, 5154.92267615986, 5162.12243918531, 5166.354205724285, 5182.067227898977, 21841.453963650878, 22559.661605075937, 22713.54572908549, 23486.0, 23762.52474950491, 2327.5810354136247, 2461.59383701689, 2493.0651005872796, 2621.449495605925, 2892.652881654215, 10474.528646210938, 10609.098226650744, 10766.784664306748, 10886.129109671207, 10988.450577471094, 1828.5702859828064, 1855.4711907106546, 1920.1167462967053, 1974.3978086683555, 2048.7848775878615, 78382.6080869592, 78711.60644196742, 79354.0, 80676.59661701653, 81262.59368703178, 10981.670549883516, 11085.556577736801, 11183.720406989727, 11230.270032447865, 11321.94339028316, 15690.450834220748, 15722.213889305718, 15730.370785168694, 15743.921280393524, 15751.291191896393, 7902.24928631779, 8022.719204827919, 8083.676652933935, 8171.019477662719, 8283.917160828436, 7579.355754760907, 7668.1181664109345, 7811.843763124819, 7856.292933636, 7901.723439679584, 4728.148933192058, 4767.547083027107, 4780.593649539828, 4825.662203645781, 4884.584810291165, 1987.7117817916321, 2017.6872584749387, 2056.8765874047604, 2057.2696692674185, 2071.275053731212, 987.827130252203, 988.1699372526969, 1023.2223510132204, 1030.1501261459177, 1030.8866024737272, 18660.34688785886, 18715.625687486176, 18794.530136746416, 19099.2360305589, 19484.220631174594, 959.9040095990484, 961.9663311784194, 1002.7493126718321, 1024.882138554076, 1032.396048311746, 10178.491075446198, 10793.568257269779, 10798.78402431947, 10811.243212975174, 10854.45727713611, 513793.0, 519045.0, 519344.0, 532500.0, 553595.0, 89614.55192724125, 90448.54775726079, 90847.54576227209, 91677.54161229284, 92177.0, 969.3311614985778, 970.684527528556, 991.0881988570533, 993.7515621094726, 999.2905037423366, 16465.506034818976, 16894.66210675804, 17245.827541724422, 17272.740908886124, 17524.211410486303, 29742.0, 42035.159296814505, 42441.36337954995, 42854.78572607242, 43129.784351078044, 56644.7167764175, 58516.82966340735, 59097.40902590919, 60471.09293360515, 60770.392296076476, 7685.846283074419, 8222.0, 8236.83526329458, 8255.669773209005, 8579.914200857911, 11468.197226194086, 11630.068664980752, 11797.587084452003, 12305.44625491873, 12307.20003199789, 9798.65704700332, 9934.602615895283, 10307.742306442398, 10322.329048611817, 10510.579576817148, 10153.49232538385, 10277.74305642365, 10320.896791031993, 10485.790284194425, 10874.619388321687, 10237.18102551809, 10382.584696612052, 10409.375437473776, 10543.94728026355, 10882.183836212474, 7979.321757650665, 8014.318782903519, 8179.1411901751835, 8205.097439281793, 8246.711365102432, 9786.41281523111, 9914.702558923243, 9975.850362244717, 10040.799184016425, 10324.845127323246], "value": 9092.815720830746, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1341.7853143497043, 1478.0318891126312, 1509.2981763479984, 1656.1470842516105, 1799.2802878848458, 8059.395545334101, 8516.574171291433, 8866.645334186642, 8888.999999999993, 9103.31725120617, 8933.0, 9012.143846334604, 9013.233875120623, 9020.548972551363, 9026.684066057689, 2408.8795560222006, 2417.987910060447, 2438.2563318187945, 2439.7072351317865, 2444.340028192387, 2368.4434157972864, 2436.3665446983737, 2437.8415402998835, 2437.9512409751796, 2439.0853429963763, 2366.148186652804, 2383.1301574925114, 2391.449966507704, 2397.5444665513583, 2407.8555286682767, 2160.6650969099815, 2169.8915054247223, 2183.15948359881, 2183.2467798609455, 2242.4281808138985, 2361.2680069178436, 2380.238323736405, 2402.327348342467, 2409.4337830609793, 2409.831311808174, 8664.436811607226, 8686.86969695455, 8767.561621918878, 8783.297336213087, 8892.777680558038, 5291.550218231455, 5488.121900495901, 5500.312460942382, 5500.559955203574, 5504.669719816784, 3404.415815149156, 3499.7375196860085, 3581.7850928944345, 3595.227026189382, 3608.224231790178, 1040.6461802986946, 1068.7862427514528, 1073.1843798712957, 1073.431081526792, 1077.647552321839, 12040.277583345027, 12077.818832717428, 12132.45403956824, 12144.271343719405, 12217.083718721131, 4335.479742430865, 4566.452025756864, 4623.445186577632, 4666.859994200179, 4696.319033740088, 9942.850857237147, 9945.552450139761, 9953.104220620178, 9953.303268771117, 9963.202943764627, 5433.185022246695, 5436.537894278951, 5439.48324909133, 5443.891122177534, 5451.209574611659, 4394.956050439519, 4517.480489743722, 4562.429696287964, 4629.282461218517, 5106.080905436983, 3832.616738326145, 3850.537935447764, 3990.620891015349, 3996.8800935971385, 4004.1791432756213, 1084.1706094837455, 1089.455272363818, 1099.934003959765, 1109.073923274059, 1115.6876074699098, 789.6486063701713, 881.0968757024101, 901.6213190460022, 917.6696389299847, 925.8888933328044, 5854.2389489366105, 5941.376155503696, 5995.43043410884, 6013.548983826143, 6145.170401995673, 430.37165738022213, 991.6182269826024, 1047.738065483629, 1050.6480329089688, 1078.1374900079904, 4119.443875076826, 4216.262154123016, 4227.598378154071, 4233.830646774095, 4262.104957958863, 10775.676729698122, 11236.0, 11239.494222759831, 11265.436728163559, 11271.718207044889, 10906.672799816019, 11147.498362573704, 11151.0, 11197.384143872165, 11217.15871309655, 8253.876191857127, 8286.58567071644, 8404.327653787683, 8408.83182336362, 8414.284785793276, 5735.913961290667, 5777.16231146473, 5829.562782791392, 5835.883282334415, 5909.556783241188, 9933.602655893683, 9971.800563988534, 10031.398116113056, 10100.696979090639, 10108.747281318027, 9260.629574816932, 9614.567344469377, 9862.0, 10259.7435064124, 10328.431936243578, 7332.046833911558, 7503.7748867534065, 7547.547147171187, 7616.771496855104, 7717.536947783151, 8798.780030499289, 8805.0, 8865.335099867407, 8868.733937981613, 8890.06654301289, 7559.773206803805, 7607.35337496319, 7814.140444551209, 7918.445708800328, 8031.437799353989, 7734.883976740466, 7772.261635144652, 7850.842983140419, 7860.0, 8014.519128852287, 7086.610236436839, 7192.352688258082, 7195.352418282379, 7197.568145911262, 7203.567785932861, 5021.623378246719, 5251.186066159751, 5299.258103865539, 5495.780168793203, 5522.91715624274, 5216.765245563883, 5228.37259528859, 5255.474452554714, 5262.736863156751, 5280.339957505312], "value": 4638.702402383053, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.7078483289205, 344.9448088305871, 353.27996200013854, 357.6495034865833, 358.7237826873309, 5272.393674727407, 5273.499017593332, 5290.523852853243, 5295.152775555912, 5342.439043900393, 5002.424721157068, 5102.693838369701, 5142.202958541423, 5224.693826543365, 5248.947510524894, 4164.8542301019315, 4170.47869016373, 4175.728577642443, 4209.49486061673, 4223.1975924574235, 4210.3684447332835, 4226.788660566975, 4242.257604919142, 4278.4010238566625, 4282.571742825724, 4135.648469880064, 4171.624553790159, 4184.68182522506, 4189.0, 4201.2017716633745, 4165.146145040259, 4176.3085950190225, 4178.185253875496, 4190.371444283352, 4194.853180138711, 3747.625237476258, 3764.4729737836724, 3776.678982286509, 3790.374588192945, 3795.6583907448185, 4969.8012079516775, 5088.92366614501, 5153.330067091254, 5212.6611770235195, 5213.921791173136, 4722.386089808338, 4750.928736068961, 4762.928556071662, 4774.379330686988, 4795.568398844086, 5476.452354764531, 5496.5053145217125, 5498.835034948958, 5512.283403157604, 5540.279763630742, 4014.9402390438245, 4118.691098167649, 4157.896052598679, 4175.812088455997, 4193.601607847249, 5585.832425027215, 5702.828915132512, 5733.254676892019, 5744.741486633112, 5748.2527271454865, 4203.579642035772, 4210.410542524049, 4213.871994642685, 4226.809793559297, 4252.619167761575, 4010.598940105966, 4302.343597714902, 4309.1381723655395, 4309.159713855831, 4309.870703878889, 4743.1225223333795, 4743.549362810528, 4749.786259618326, 4766.213574760195, 4795.544423279783, 5548.389677135512, 5552.861178470571, 5557.972210138923, 5566.248556444828, 5581.553475721932, 1388.4723804954053, 1394.3237529798039, 1399.4192410149878, 1410.2314238739912, 1415.8938079644067, 981.3277904635347, 982.6560703753629, 985.2659768472533, 989.1592146675268, 1001.8096561653263, 5009.7244651544515, 5197.194434862602, 5209.947900520946, 5253.317068781034, 5297.284866542967, 5464.0, 5487.341519017664, 5497.945020549823, 5503.2020357048, 5503.9174412383845, 5168.054209100852, 5225.555827754607, 5226.608004399685, 5235.162374020215, 5254.6847189168775, 2805.7032986241493, 2904.622399088147, 2928.546075358323, 2936.9573801300658, 2942.264433891527, 5658.490735833795, 5697.829065128053, 5702.54379649627, 5710.914336284959, 5741.392410125171, 5463.508284254357, 5574.777008919597, 5584.776608935678, 5632.352279487829, 5685.1756495307945, 5663.375439718583, 5693.95017793594, 5695.373508914016, 5720.542356611461, 5738.368779434259, 5519.199716041232, 5521.365043019943, 5535.225068490495, 5560.833175004756, 5561.749721262472, 5382.865428364322, 5407.864803379947, 5433.646812957303, 5469.72651367438, 5525.944740552543, 5684.261046063985, 5699.51554117888, 5709.22925405065, 5714.20001199824, 5720.199172115824, 4006.3790112532606, 4025.416314634303, 4056.776877271778, 4061.4313996039987, 4062.7765472899273, 5379.623426360117, 5390.328998010629, 5461.726913654222, 5461.9180712290145, 5485.472773541957, 5259.553622753773, 5297.973510132425, 5325.520703136736, 5337.064614462108, 5338.252644629677, 5131.692098474104, 5276.577873770012, 5282.4717528246865, 5352.1971704243115, 5354.919676204782, 5284.418713941386, 5294.23233631129, 5332.0, 5333.4133245343755, 5346.78612855497, 5343.358796944236, 5364.195370694271, 5384.7576859040655, 5391.75737091824, 5472.726363681721, 5051.823186188612, 5283.788648454019, 5289.206619007025, 5291.232771248225, 5328.0], "value": 4208.492904551232, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [755.5919803306215, 774.6901239504198, 820.1470470710461, 855.4183155454289, 894.1728900766791, 15142.848571514282, 16139.273732682042, 16155.596110097253, 16176.514704558853, 16256.512304630865, 14755.77866332006, 14831.110133392003, 14849.777253341208, 14893.851061489382, 14910.552683419488, 711.985760284794, 729.106844115959, 847.2417186617973, 853.2491407561353, 869.0397111192136, 856.5674334461088, 860.1312674199073, 861.7543999960012, 862.4178679391417, 862.965481380744, 918.0498184379169, 920.5213289089669, 920.8158368326328, 922.4327038871113, 923.5843870258377, 906.4425378392307, 915.1946287267228, 915.3135148638521, 916.1662886773038, 919.8620206968941, 672.9865402691968, 674.190970834995, 674.5817593092314, 674.676155445389, 674.8245456181379, 15060.246987650684, 15107.848921510864, 15159.62100947474, 15160.241987900561, 15196.392144314214, 14382.92808535961, 15266.694666106727, 15429.0, 15774.0, 15805.130717810516, 11768.235064720766, 11998.64004079879, 12217.328046957413, 12424.813627795591, 12445.875541244654, 3784.3188226119287, 3787.696984241254, 3828.55971563271, 3844.7693138411787, 3934.8229329680235, 58482.707586461795, 64363.67818160879, 64537.0, 65553.67223163901, 67903.3209667902, 11169.162312826571, 11573.88426115745, 11686.18196726238, 11861.76276474483, 11887.762244755057, 22349.55300893973, 22351.0, 22354.32937011892, 22573.4356641082, 22631.43421414445, 9056.773580660405, 9239.538023098818, 9286.210672092948, 9430.245580353556, 9507.239420846316, 4827.565519103297, 4870.9269360959615, 4908.852734417973, 5024.547790698854, 5166.405863325691, 3370.1743072946792, 3442.9311413771584, 3476.478528220787, 3488.389531831919, 3491.127218195451, 617.0928734759896, 628.5914155798768, 630.5271046714964, 632.4150161100963, 633.7940169444856, 614.0394455760274, 644.1079105439001, 680.2483256002146, 694.8749225139524, 709.1277728394105, 8855.33584981129, 8991.865122023175, 9036.774080647905, 9309.162175404246, 9468.19520340765, 570.8375599180577, 577.2812848004262, 587.8736071744595, 590.271015296109, 701.4809041309496, 4451.710638808467, 4513.6684678020065, 4539.6368290536675, 4542.250528662734, 4587.082583483317, 58628.0, 60755.39244607586, 97422.5128874351, 98539.50730246303, 98809.50595246977, 83942.0, 86229.0, 86465.0, 87823.56088219646, 88065.67901481483, 75629.62185189148, 77053.0, 77357.0, 78546.6072669633, 79032.0, 8742.169493898069, 8819.647214111365, 9009.504477253813, 9176.632934682539, 9244.768880778034, 51136.0, 51947.922083117766, 52743.0, 53318.200226997404, 53927.43959130934, 63866.0, 69220.65389673221, 71902.64048679723, 72477.0, 74916.25083749092, 11620.6513804586, 11720.531178752753, 11797.587084452003, 11929.880701192877, 12052.156349055482, 12432.751344972869, 12686.175398599062, 12923.095383323453, 12983.870161298266, 12996.610101696964, 11464.598739044093, 11532.365719885487, 11714.355710435668, 11814.0, 11920.821187682364, 11326.263792853768, 11434.313941163557, 11457.255278406878, 11701.239419437708, 11868.465919033837, 11422.885771142182, 11685.123615729024, 11699.649010529698, 11774.3524106175, 11919.940400297943, 9478.905210947802, 9699.418034917928, 9718.0, 9730.41617502952, 9826.75433114178, 10139.543720532742, 10161.491925403849, 10175.28772985884, 10217.438040907524, 10219.0], "value": 7268.143070743629, "unit": "score"}], "name": "dromaeo_css", "value": 7266.306536646364, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 110.0, 113.0, 113.0, 112.0, 114.0, 111.0, 110.0, 113.0, 111.0], "value": 110.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 118.0, 119.0, 152.0, 118.0, 117.0, 118.0, 119.0, 144.0, 117.0], "value": 126.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 160.0, 159.0, 158.0, 160.0, 158.0, 158.0, 161.0, 158.0], "value": 159.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 75.0, 107.0, 75.0, 75.0, 75.0, 76.0, 107.0, 74.0], "value": 81.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 127.0, 81.0, 82.0, 81.0, 102.0, 81.0, 81.0, 115.0], "value": 91.3, "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": [146.0, 146.0, 146.0, 146.0, 146.0, 145.0, 145.0, 146.0, 146.0, 145.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 85.0, 84.0, 85.0, 85.0, 84.0, 85.0, 84.0, 85.0], "value": 84.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 51.0, 50.0, 50.0, 51.0, 50.0, 50.0, 50.0, 50.0], "value": 50.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 59.0, 59.0, 59.0, 59.0, 60.0, 59.0, 59.0, 59.0, 60.0], "value": 59.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 77.0, 75.0, 75.0, 76.0, 75.0, 75.0, 83.0, 75.0, 75.0], "value": 76.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 130.0, 124.0, 126.0, 122.0, 136.0, 121.0, 122.0, 164.0, 124.0], "value": 129.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 155.0, 156.0, 158.0, 154.0, 157.0, 161.0, 161.0, 153.0, 154.0], "value": 156.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 68.0, 63.0, 63.0, 63.0, 61.0, 62.0, 65.0, 62.0, 61.0], "value": 63.1, "unit": "score"}], "name": "kraken", "value": 1451.8999999999999, "alertThreshold": 2.0}]} 19:10:34 INFO - Return code: 0 19:10:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:10:34 INFO - # TBPL SUCCESS # 19:10:34 INFO - Running post-action listener: _resource_record_post_action 19:10:34 INFO - [mozharness: 2017-02-01 03:10:34.883451Z] Finished run-tests step (success) 19:10:34 INFO - Running post-run listener: _resource_record_post_run 19:10:34 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 815104; Write bytes: 320942080; Read time: 380; Write time: 236596 19:10:34 INFO - TinderboxPrint: CPU usage
13.0% 19:10:34 INFO - TinderboxPrint: I/O read bytes / time
815,104 / 380 19:10:34 INFO - TinderboxPrint: I/O write bytes / time
320,942,080 / 236,596 19:10:34 INFO - TinderboxPrint: CPU idle
8,251.7 (87.0%) 19:10:34 INFO - TinderboxPrint: CPU user
1,189.0 (12.5%) 19:10:34 INFO - TinderboxPrint: Swap in / out
0 / 0 19:10:34 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 8192; Write bytes: 101036032; Read time: 84; Write time: 86052 19:10:34 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 806912; Write bytes: 219906048; Read time: 296; Write time: 150544 19:10:35 INFO - Running post-run listener: _upload_blobber_files 19:10:35 INFO - Blob upload gear active. 19:10:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:10:35 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 19:10:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:10:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:10:35 INFO - (blobuploader) - INFO - Open directory for files ... 19:10:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:10:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:10:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:10:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:10:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:10:36 INFO - (blobuploader) - INFO - Done attempting. 19:10:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:10:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:10:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:10:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:10:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:10:40 INFO - (blobuploader) - INFO - Done attempting. 19:10:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:10:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:10:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:10:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:10:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:10:41 INFO - (blobuploader) - INFO - Done attempting. 19:10:41 INFO - (blobuploader) - INFO - Iteration through files over. 19:10:41 INFO - Return code: 0 19:10:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:10:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:10:41 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ea43d38223c137583f5806155c2ce30f36eb851d956a838210787043eba2a50d50453353cab12509a17fddb4e07c2350ce3492f93ffaccc8547f677f1d73714c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2ac9db837b42afd14c2a00ce7541c3388d99ed80809a266f3658303cb7bda58f35f6fa6efdf68b10e97e566a886e59d8376570df79a891a5346edfbae005fbac", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4dcb3bbbe0fd72a7c9c91687e6a7c366052f7827482cfc2b7b397f1f177a97878044a9207c7f6eb620643af9ec3f806df0ef4745272d798c97cdd759e3b0be8c"} 19:10:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:10:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:10:41 INFO - Contents: 19:10:41 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ea43d38223c137583f5806155c2ce30f36eb851d956a838210787043eba2a50d50453353cab12509a17fddb4e07c2350ce3492f93ffaccc8547f677f1d73714c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2ac9db837b42afd14c2a00ce7541c3388d99ed80809a266f3658303cb7bda58f35f6fa6efdf68b10e97e566a886e59d8376570df79a891a5346edfbae005fbac", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4dcb3bbbe0fd72a7c9c91687e6a7c366052f7827482cfc2b7b397f1f177a97878044a9207c7f6eb620643af9ec3f806df0ef4745272d798c97cdd759e3b0be8c"} 19:10:41 INFO - Running post-run listener: copy_logs_to_upload_dir 19:10:41 INFO - Copying logs to upload dir... 19:10:41 INFO - mkdir: /builds/slave/test/build/upload/logs 19:10:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1231.467627 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 31 secs) (at 2017-01-31 19:10:41.151758) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-31 19:10:41.155016) ========= 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=920470bf432669ad7e3a5387000001ca-1485917407.131498-1615724139 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ea43d38223c137583f5806155c2ce30f36eb851d956a838210787043eba2a50d50453353cab12509a17fddb4e07c2350ce3492f93ffaccc8547f677f1d73714c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2ac9db837b42afd14c2a00ce7541c3388d99ed80809a266f3658303cb7bda58f35f6fa6efdf68b10e97e566a886e59d8376570df79a891a5346edfbae005fbac", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4dcb3bbbe0fd72a7c9c91687e6a7c366052f7827482cfc2b7b397f1f177a97878044a9207c7f6eb620643af9ec3f806df0ef4745272d798c97cdd759e3b0be8c"} build_url:https://queue.taskcluster.net/v1/task/LMk7YVshRBuZB5Od5CMElQ/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011250 build_url: 'https://queue.taskcluster.net/v1/task/LMk7YVshRBuZB5Od5CMElQ/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ea43d38223c137583f5806155c2ce30f36eb851d956a838210787043eba2a50d50453353cab12509a17fddb4e07c2350ce3492f93ffaccc8547f677f1d73714c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2ac9db837b42afd14c2a00ce7541c3388d99ed80809a266f3658303cb7bda58f35f6fa6efdf68b10e97e566a886e59d8376570df79a891a5346edfbae005fbac", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4dcb3bbbe0fd72a7c9c91687e6a7c366052f7827482cfc2b7b397f1f177a97878044a9207c7f6eb620643af9ec3f806df0ef4745272d798c97cdd759e3b0be8c"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-31 19:10:41.179222) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 19:10:41.179520) ========= 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=920470bf432669ad7e3a5387000001ca-1485917407.131498-1615724139 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004430 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 19:10:41.264052) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-31 19:10:41.264562) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-31 19:10:41.265033) ========= ========= Total master_lag: 0.16 =========