builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-055 starttime: 1485311187.52 results: success (0) buildid: 20170124163348 builduid: a6903288ef854d368d80913c985784aa revision: 3861c5f62b183a33e3b70d8321b48fdaac05bec1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-24 18:26:27.515502) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-24 18:26:27.516008) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-24 18:26:27.516312) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-24 18:26:27.531679) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-24 18:26:27.532053) ========= 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=bb9b54edd4a927dc735d112f000001d9-1485311187.133233-85291916 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-01-24 18:26:27-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 102M=0s 2017-01-24 18:26:28 (102 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.141798 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-24 18:26:27.688936) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-24 18:26:27.689274) ========= 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=bb9b54edd4a927dc735d112f000001d9-1485311187.133233-85291916 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014889 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-24 18:26:27.740132) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-24 18:26:27.740511) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 3861c5f62b183a33e3b70d8321b48fdaac05bec1 --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 3861c5f62b183a33e3b70d8321b48fdaac05bec1 --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=bb9b54edd4a927dc735d112f000001d9-1485311187.133233-85291916 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-01-24 18:26:28,161 truncating revision to first 12 chars 2017-01-24 18:26:28,161 Setting DEBUG logging. 2017-01-24 18:26:28,161 attempt 1/10 2017-01-24 18:26:28,161 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/3861c5f62b18?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-24 18:26:29,002 unpacking tar archive at: mozilla-beta-3861c5f62b18/testing/mozharness/ program finished with exit code 0 elapsedTime=1.147226 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-24 18:26:28.901520) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-24 18:26:28.901850) ========= script_repo_revision: 3861c5f62b183a33e3b70d8321b48fdaac05bec1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-24 18:26:28.902305) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-24 18:26:28.902599) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-24 18:26:28.913103) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 31 secs) (at 2017-01-24 18:26:28.913536) ========= /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=bb9b54edd4a927dc735d112f000001d9-1485311187.133233-85291916 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:26:29 INFO - MultiFileLogger online at 20170124 18:26:29 in /builds/slave/test 18:26:29 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:26:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:26:29 INFO - {'append_to_log': False, 18:26:29 INFO - 'base_work_dir': '/builds/slave/test', 18:26:29 INFO - 'blob_upload_branch': 'Mozilla-Beta', 18:26:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:26:29 INFO - 'branch': 'Mozilla-Beta', 18:26:29 INFO - 'buildbot_json_path': 'buildprops.json', 18:26:29 INFO - 'config_files': ('talos/linux_config.py',), 18:26:29 INFO - 'default_actions': ('clobber', 18:26:29 INFO - 'read-buildbot-config', 18:26:29 INFO - 'download-and-extract', 18:26:29 INFO - 'populate-webroot', 18:26:29 INFO - 'create-virtualenv', 18:26:29 INFO - 'install', 18:26:29 INFO - 'run-tests'), 18:26:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:26:29 INFO - 'download_minidump_stackwalk': True, 18:26:29 INFO - 'download_symbols': 'ondemand', 18:26:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:26:29 INFO - 'tooltool.py': '/tools/tooltool.py', 18:26:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:26:29 INFO - '/tools/misc-python/virtualenv.py')}, 18:26:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:26:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:26:29 INFO - 'installer_path': 'installer.exe', 18:26:29 INFO - 'log_level': 'info', 18:26:29 INFO - 'log_name': 'talos', 18:26:29 INFO - 'log_to_console': True, 18:26:29 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:26:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:26:29 INFO - 'opt_config_files': (), 18:26:29 INFO - 'pip_index': False, 18:26:29 INFO - 'sps_profile': False, 18:26:29 INFO - 'sps_profile_interval': 0, 18:26:29 INFO - 'suite': 'dromaeojs', 18:26:29 INFO - 'system_bits': '32', 18:26:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:26:29 INFO - 'title': 'talos-linux64-ix-055', 18:26:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:26:29 INFO - 'use_talos_json': True, 18:26:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:26:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:26:29 INFO - 'work_dir': 'build'} 18:26:29 INFO - [mozharness: 2017-01-25 02:26:29.426961Z] Running clobber step. 18:26:29 INFO - Running pre-action listener: _resource_record_pre_action 18:26:29 INFO - Running main action method: clobber 18:26:29 INFO - rmtree: /builds/slave/test/build 18:26:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:26:29 INFO - Running post-action listener: _resource_record_post_action 18:26:29 INFO - [mozharness: 2017-01-25 02:26:29.875946Z] Finished clobber step (success) 18:26:29 INFO - [mozharness: 2017-01-25 02:26:29.876046Z] Running read-buildbot-config step. 18:26:29 INFO - Running pre-action listener: _resource_record_pre_action 18:26:29 INFO - Running main action method: read_buildbot_config 18:26:29 INFO - Using buildbot properties: 18:26:29 INFO - { 18:26:29 INFO - "project": "", 18:26:29 INFO - "product": "firefox", 18:26:29 INFO - "script_repo_revision": "production", 18:26:29 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 18:26:29 INFO - "repository": "", 18:26:29 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 18:26:29 INFO - "buildid": "20170124163348", 18:26:29 INFO - "pgo_build": "True", 18:26:29 INFO - "basedir": "/builds/slave/test", 18:26:29 INFO - "buildnumber": 65, 18:26:29 INFO - "slavename": "talos-linux64-ix-055", 18:26:29 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 18:26:29 INFO - "platform": "ubuntu64_hw", 18:26:29 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 18:26:29 INFO - "branch": "mozilla-beta", 18:26:29 INFO - "repo_path": "releases/mozilla-beta", 18:26:29 INFO - "revision": "3861c5f62b183a33e3b70d8321b48fdaac05bec1", 18:26:29 INFO - "stage_platform": "linux64-pgo", 18:26:29 INFO - "builduid": "a6903288ef854d368d80913c985784aa", 18:26:29 INFO - "slavebuilddir": "test" 18:26:29 INFO - } 18:26:29 INFO - Found installer url https://queue.taskcluster.net/v1/task/Tqh9IocdQa-2pwaE69dh5w/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2. 18:26:29 INFO - Running post-action listener: _resource_record_post_action 18:26:29 INFO - [mozharness: 2017-01-25 02:26:29.878447Z] Finished read-buildbot-config step (success) 18:26:29 INFO - [mozharness: 2017-01-25 02:26:29.878533Z] Running download-and-extract step. 18:26:29 INFO - Running pre-action listener: _resource_record_pre_action 18:26:29 INFO - Running main action method: download_and_extract 18:26:29 INFO - mkdir: /builds/slave/test/build/tests 18:26:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:26:29 INFO - trying https://queue.taskcluster.net/v1/task/Tqh9IocdQa-2pwaE69dh5w/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.test_packages.json 18:26:29 INFO - Downloading https://queue.taskcluster.net/v1/task/Tqh9IocdQa-2pwaE69dh5w/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:26:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Tqh9IocdQa-2pwaE69dh5w/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:26:30 INFO - Downloaded 1327 bytes. 18:26:30 INFO - Reading from file /builds/slave/test/build/firefox-52.0.en-US.linux-x86_64.test_packages.json 18:26:30 INFO - Using the following test package requirements: 18:26:30 INFO - {u'common': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip'], 18:26:30 INFO - u'cppunittest': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 18:26:30 INFO - u'firefox-52.0.en-US.linux-x86_64.cppunittest.tests.zip'], 18:26:30 INFO - u'gtest': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 18:26:30 INFO - u'firefox-52.0.en-US.linux-x86_64.gtest.tests.zip'], 18:26:30 INFO - u'jittest': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 18:26:30 INFO - u'jsshell-linux-x86_64.zip'], 18:26:30 INFO - u'mochitest': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 18:26:30 INFO - u'firefox-52.0.en-US.linux-x86_64.mochitest.tests.zip'], 18:26:30 INFO - u'mozbase': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip'], 18:26:30 INFO - u'reftest': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 18:26:30 INFO - u'firefox-52.0.en-US.linux-x86_64.reftest.tests.zip'], 18:26:30 INFO - u'talos': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 18:26:30 INFO - u'firefox-52.0.en-US.linux-x86_64.talos.tests.zip'], 18:26:30 INFO - u'web-platform': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 18:26:30 INFO - u'firefox-52.0.en-US.linux-x86_64.web-platform.tests.zip'], 18:26:30 INFO - u'xpcshell': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 18:26:30 INFO - u'firefox-52.0.en-US.linux-x86_64.xpcshell.tests.zip']} 18:26:30 INFO - Downloading packages: [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:26:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Tqh9IocdQa-2pwaE69dh5w/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.common.tests.zip 18:26:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Tqh9IocdQa-2pwaE69dh5w/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:26:30 INFO - Fetch https://queue.taskcluster.net/v1/task/Tqh9IocdQa-2pwaE69dh5w/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.common.tests.zip into memory 18:26:31 INFO - Http code: 200 18:26:31 INFO - via: 1.1 ae162f6796e551002447afd7c07ec67a.cloudfront.net (CloudFront) 18:26:31 INFO - x-amz-cf-id: r5SIjtwGs8_i7_0OM7PsJvAK3t3gabSMdryK6W8DHlwbQYrCMVXiRg== 18:26:31 INFO - x-amz-version-id: QocHg6.dOgjlLK649kopD6K.7n6U0jiR 18:26:31 INFO - Expected file size: 21950128 18:26:31 INFO - Obtained file size: 21950128 18:26:35 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:26:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Tqh9IocdQa-2pwaE69dh5w/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.common.tests.zip 18:26:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Tqh9IocdQa-2pwaE69dh5w/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:26:35 INFO - Fetch https://queue.taskcluster.net/v1/task/Tqh9IocdQa-2pwaE69dh5w/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.common.tests.zip into memory 18:26:35 INFO - Http code: 200 18:26:35 INFO - via: 1.1 73fa5a1ee49cf827e596b502b5927eab.cloudfront.net (CloudFront) 18:26:35 INFO - x-amz-cf-id: BQDbwe3KiHBTQqOnLZ4waEizAgIDcNAClCEY0Ram8S5NSeXPuwZb1w== 18:26:35 INFO - x-amz-version-id: QocHg6.dOgjlLK649kopD6K.7n6U0jiR 18:26:36 INFO - Expected file size: 21950128 18:26:36 INFO - Obtained file size: 21950128 18:26:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Tqh9IocdQa-2pwaE69dh5w/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.talos.tests.zip 18:26:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Tqh9IocdQa-2pwaE69dh5w/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 18:26:40 INFO - Fetch https://queue.taskcluster.net/v1/task/Tqh9IocdQa-2pwaE69dh5w/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.talos.tests.zip into memory 18:26:41 INFO - Http code: 200 18:26:41 INFO - via: 1.1 bfd7406fdd888e34dfca79886cb7c964.cloudfront.net (CloudFront) 18:26:41 INFO - x-amz-cf-id: 724Uw7Brt2Q_zFYAyrvdcc_HO0Wy53cu5u39aZaZjdC4DyQNTXPZ8A== 18:26:41 INFO - x-amz-version-id: 1Btr8usfzEELK_fbt3Egz1jr5QG627LG 18:26:41 INFO - Expected file size: 14402992 18:26:41 INFO - Obtained file size: 14402992 18:26:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:26:41 INFO - trying https://queue.taskcluster.net/v1/task/Tqh9IocdQa-2pwaE69dh5w/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 18:26:41 INFO - Downloading https://queue.taskcluster.net/v1/task/Tqh9IocdQa-2pwaE69dh5w/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:26:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Tqh9IocdQa-2pwaE69dh5w/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:26:42 INFO - Downloaded 57679639 bytes. 18:26:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Tqh9IocdQa-2pwaE69dh5w/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 18:26:42 INFO - mkdir: /builds/slave/test/properties 18:26:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:26:42 INFO - Writing to file /builds/slave/test/properties/build_url 18:26:42 INFO - Contents: 18:26:42 INFO - build_url:https://queue.taskcluster.net/v1/task/Tqh9IocdQa-2pwaE69dh5w/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 18:26:43 INFO - Running post-action listener: _resource_record_post_action 18:26:43 INFO - Running post-action listener: set_extra_try_arguments 18:26:43 INFO - [mozharness: 2017-01-25 02:26:43.190618Z] Finished download-and-extract step (success) 18:26:43 INFO - [mozharness: 2017-01-25 02:26:43.190843Z] Running populate-webroot step. 18:26:43 INFO - Running pre-action listener: _resource_record_pre_action 18:26:43 INFO - Running main action method: populate_webroot 18:26:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:26:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:26:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:26:43 INFO - '--tppagecycles', 18:26:43 INFO - '7'], 18:26:43 INFO - 'tests': ['tsvgm']}}, 18:26:43 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:26:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:26:43 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:26:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:26:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:26:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:26:43 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:26:43 INFO - 'talos_options': ['--disable-e10s'], 18:26:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:26:43 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:26:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:26:43 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:26:43 INFO - 'talos_options': ['--disable-e10s'], 18:26:43 INFO - 'tests': ['damp', 'tps']}, 18:26:43 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:26:43 INFO - 'tests': ['damp', 'tps']}, 18:26:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:26:43 INFO - 'tests': ['dromaeo_dom']}, 18:26:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:26:43 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:26:43 INFO - 'tests': ['basic_compositor_video']}, 18:26:43 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:26:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:26:43 INFO - 'tests': ['a11yr', 18:26:43 INFO - 'ts_paint', 18:26:43 INFO - 'tpaint', 18:26:43 INFO - 'sessionrestore', 18:26:43 INFO - 'sessionrestore_no_auto_restore', 18:26:43 INFO - 'tabpaint']}, 18:26:43 INFO - 'other-e10s': {'tests': ['a11yr', 18:26:43 INFO - 'ts_paint', 18:26:43 INFO - 'tpaint', 18:26:43 INFO - 'sessionrestore', 18:26:43 INFO - 'sessionrestore_no_auto_restore', 18:26:43 INFO - 'tabpaint']}, 18:26:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:26:43 INFO - 'ts_paint', 18:26:43 INFO - 'tpaint', 18:26:43 INFO - 'sessionrestore', 18:26:43 INFO - 'sessionrestore_no_auto_restore', 18:26:43 INFO - 'tabpaint']}, 18:26:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:26:43 INFO - 'ts_paint', 18:26:43 INFO - 'tpaint', 18:26:43 INFO - 'sessionrestore', 18:26:43 INFO - 'sessionrestore_no_auto_restore', 18:26:43 INFO - 'tabpaint']}, 18:26:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:26:43 INFO - 'tests': ['a11yr', 18:26:43 INFO - 'ts_paint', 18:26:43 INFO - 'tpaint', 18:26:43 INFO - 'sessionrestore', 18:26:43 INFO - 'sessionrestore_no_auto_restore', 18:26:43 INFO - 'tabpaint']}, 18:26:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:26:43 INFO - 'tests': ['a11yr', 18:26:43 INFO - 'ts_paint', 18:26:43 INFO - 'tpaint', 18:26:43 INFO - 'sessionrestore', 18:26:43 INFO - 'sessionrestore_no_auto_restore', 18:26:43 INFO - 'tabpaint']}, 18:26:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:26:43 INFO - 'tests': ['tsvgx', 18:26:43 INFO - 'tsvgr_opacity', 18:26:43 INFO - 'tart', 18:26:43 INFO - 'tscrollx', 18:26:43 INFO - 'cart']}, 18:26:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:26:43 INFO - 'tsvgr_opacity', 18:26:43 INFO - 'tart', 18:26:43 INFO - 'tscrollx', 18:26:43 INFO - 'cart']}, 18:26:43 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:26:43 INFO - 'talos_options': ['--disable-e10s'], 18:26:43 INFO - 'tests': ['tp5o']}, 18:26:43 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:26:43 INFO - 'tests': ['tp5o']}, 18:26:43 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:26:43 INFO - 'talos_options': ['--disable-e10s', 18:26:43 INFO - '--xperf_path', 18:26:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:26:43 INFO - 'tests': ['tp5n']}, 18:26:43 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:26:43 INFO - 'talos_options': ['--xperf_path', 18:26:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:26:43 INFO - 'tests': ['tp5n']}}, 18:26:43 INFO - 'talos.zip': {'path': '', 18:26:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:26:43 INFO - Running post-action listener: _resource_record_post_action 18:26:43 INFO - [mozharness: 2017-01-25 02:26:43.209305Z] Finished populate-webroot step (success) 18:26:43 INFO - [mozharness: 2017-01-25 02:26:43.209392Z] Running create-virtualenv step. 18:26:43 INFO - Running pre-action listener: _resource_record_pre_action 18:26:43 INFO - Running main action method: create_virtualenv 18:26:43 INFO - Creating virtualenv /builds/slave/test/build/venv 18:26:43 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:26:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:26:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:26:43 INFO - Using real prefix '/usr' 18:26:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:26:43 INFO - Installing distribute.............................................................................................................................................................................................done. 18:26:45 INFO - Installing pip.................done. 18:26:45 INFO - Return code: 0 18:26:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:26:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:26:45 INFO - Reading from file tmpfile_stdout 18:26:45 INFO - Output received: 18:26:45 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:26:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:26:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:26:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:26:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:26:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:26:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb8e0acf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1574ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1571f60>, '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 0x1572d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15731d0>, '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': 'bb9b54edd4a927dc735d112f000001d9-1485311187.133233-85291916', '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:26:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:26:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:26:46 INFO - Using env: {'DISPLAY': ':0', 18:26:46 INFO - 'HOME': '/home/cltbld', 18:26:46 INFO - 'LANG': 'en_US.UTF-8', 18:26:46 INFO - 'LANGUAGE': 'en_US:en', 18:26:46 INFO - 'LOGNAME': 'cltbld', 18:26:46 INFO - 'MAIL': '/var/mail/cltbld', 18:26:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:26:46 INFO - 'MOZ_NO_REMOTE': '1', 18:26:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:26:46 INFO - 'NO_EM_RESTART': '1', 18:26:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:26:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:26:46 INFO - 'PWD': '/builds/slave/test', 18:26:46 INFO - 'SHELL': '/bin/bash', 18:26:46 INFO - 'SHLVL': '1', 18:26:46 INFO - 'TERM': 'linux', 18:26:46 INFO - 'TMOUT': '86400', 18:26:46 INFO - 'USER': 'cltbld', 18:26:46 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1485311187.133233-85291916', 18:26:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:26:46 INFO - '_': '/tools/buildbot/bin/python'} 18:26:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:26:46 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 18:26:46 INFO - Cleaning up... 18:26:46 INFO - Return code: 0 18:26:46 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:26: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:26:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:26:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26: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:26:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:26:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb8e0acf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1574ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1571f60>, '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 0x1572d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15731d0>, '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': 'bb9b54edd4a927dc735d112f000001d9-1485311187.133233-85291916', '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:26:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:26:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:26:46 INFO - Using env: {'DISPLAY': ':0', 18:26:46 INFO - 'HOME': '/home/cltbld', 18:26:46 INFO - 'LANG': 'en_US.UTF-8', 18:26:46 INFO - 'LANGUAGE': 'en_US:en', 18:26:46 INFO - 'LOGNAME': 'cltbld', 18:26:46 INFO - 'MAIL': '/var/mail/cltbld', 18:26:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:26:46 INFO - 'MOZ_NO_REMOTE': '1', 18:26:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:26:46 INFO - 'NO_EM_RESTART': '1', 18:26:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:26:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:26:46 INFO - 'PWD': '/builds/slave/test', 18:26:46 INFO - 'SHELL': '/bin/bash', 18:26:46 INFO - 'SHLVL': '1', 18:26:46 INFO - 'TERM': 'linux', 18:26:46 INFO - 'TMOUT': '86400', 18:26:46 INFO - 'USER': 'cltbld', 18:26:46 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1485311187.133233-85291916', 18:26:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:26:46 INFO - '_': '/tools/buildbot/bin/python'} 18:26:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:26:46 INFO - Downloading/unpacking psutil>=3.1.1 18:26:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:26:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:26:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:26:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:26:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:26:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:26:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:26:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:26:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:26:48 INFO - Installing collected packages: psutil 18:26:48 INFO - Running setup.py install for psutil 18:26:48 INFO - building 'psutil._psutil_linux' extension 18:26:48 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 18:26:48 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 18:26:48 INFO - building 'psutil._psutil_posix' extension 18:26:48 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:26:48 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 18:26:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:26:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:26:49 INFO - Successfully installed psutil 18:26:49 INFO - Cleaning up... 18:26:49 INFO - Return code: 0 18:26:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:26:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:26:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:26:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:26:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:26:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb8e0acf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1574ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1571f60>, '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 0x1572d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15731d0>, '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': 'bb9b54edd4a927dc735d112f000001d9-1485311187.133233-85291916', '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:26:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:26:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:26:49 INFO - Using env: {'DISPLAY': ':0', 18:26:49 INFO - 'HOME': '/home/cltbld', 18:26:49 INFO - 'LANG': 'en_US.UTF-8', 18:26:49 INFO - 'LANGUAGE': 'en_US:en', 18:26:49 INFO - 'LOGNAME': 'cltbld', 18:26:49 INFO - 'MAIL': '/var/mail/cltbld', 18:26:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:26:49 INFO - 'MOZ_NO_REMOTE': '1', 18:26:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:26:49 INFO - 'NO_EM_RESTART': '1', 18:26:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:26:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:26:49 INFO - 'PWD': '/builds/slave/test', 18:26:49 INFO - 'SHELL': '/bin/bash', 18:26:49 INFO - 'SHLVL': '1', 18:26:49 INFO - 'TERM': 'linux', 18:26:49 INFO - 'TMOUT': '86400', 18:26:49 INFO - 'USER': 'cltbld', 18:26:49 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1485311187.133233-85291916', 18:26:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:26:49 INFO - '_': '/tools/buildbot/bin/python'} 18:26:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:26:49 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:26:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:26:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:26:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:26:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:26:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:26:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:26:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:26:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:26:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:26:51 INFO - Installing collected packages: mozsystemmonitor 18:26:51 INFO - Running setup.py install for mozsystemmonitor 18:26:51 INFO - Successfully installed mozsystemmonitor 18:26:51 INFO - Cleaning up... 18:26:51 INFO - Return code: 0 18:26:51 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:26:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:26:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:26:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:26:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:26:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb8e0acf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1574ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1571f60>, '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 0x1572d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15731d0>, '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': 'bb9b54edd4a927dc735d112f000001d9-1485311187.133233-85291916', '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:26:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:26:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:26:51 INFO - Using env: {'DISPLAY': ':0', 18:26:51 INFO - 'HOME': '/home/cltbld', 18:26:51 INFO - 'LANG': 'en_US.UTF-8', 18:26:51 INFO - 'LANGUAGE': 'en_US:en', 18:26:51 INFO - 'LOGNAME': 'cltbld', 18:26:51 INFO - 'MAIL': '/var/mail/cltbld', 18:26:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:26:51 INFO - 'MOZ_NO_REMOTE': '1', 18:26:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:26:51 INFO - 'NO_EM_RESTART': '1', 18:26:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:26:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:26:51 INFO - 'PWD': '/builds/slave/test', 18:26:51 INFO - 'SHELL': '/bin/bash', 18:26:51 INFO - 'SHLVL': '1', 18:26:51 INFO - 'TERM': 'linux', 18:26:51 INFO - 'TMOUT': '86400', 18:26:51 INFO - 'USER': 'cltbld', 18:26:51 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1485311187.133233-85291916', 18:26:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:26:51 INFO - '_': '/tools/buildbot/bin/python'} 18:26:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:26:51 INFO - Downloading/unpacking jsonschema==2.5.1 18:26:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:26:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:26:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:26:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:26:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:26:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:26:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:26:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:26:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:26:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:26:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:26:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:26:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:26:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:26:53 INFO - Downloading functools32-3.2.3-2.tar.gz 18:26:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:26:53 INFO - warning: no files found matching '*.txt' 18:26:53 INFO - no previously-included directories found matching 'build' 18:26:53 INFO - no previously-included directories found matching 'dist' 18:26:53 INFO - no previously-included directories found matching '.git*' 18:26:53 INFO - Installing collected packages: jsonschema, functools32 18:26:54 INFO - Running setup.py install for functools32 18:26:54 INFO - warning: no files found matching '*.txt' 18:26:54 INFO - no previously-included directories found matching 'build' 18:26:54 INFO - no previously-included directories found matching 'dist' 18:26:54 INFO - no previously-included directories found matching '.git*' 18:26:54 INFO - Successfully installed jsonschema functools32 18:26:54 INFO - Cleaning up... 18:26:54 INFO - Return code: 0 18:26:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:26:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:26:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:26:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:26:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:26:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb8e0acf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1574ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1571f60>, '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 0x1572d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15731d0>, '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': 'bb9b54edd4a927dc735d112f000001d9-1485311187.133233-85291916', '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:26:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:26:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:26:54 INFO - Using env: {'DISPLAY': ':0', 18:26:54 INFO - 'HOME': '/home/cltbld', 18:26:54 INFO - 'LANG': 'en_US.UTF-8', 18:26:54 INFO - 'LANGUAGE': 'en_US:en', 18:26:54 INFO - 'LOGNAME': 'cltbld', 18:26:54 INFO - 'MAIL': '/var/mail/cltbld', 18:26:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:26:54 INFO - 'MOZ_NO_REMOTE': '1', 18:26:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:26:54 INFO - 'NO_EM_RESTART': '1', 18:26:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:26:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:26:54 INFO - 'PWD': '/builds/slave/test', 18:26:54 INFO - 'SHELL': '/bin/bash', 18:26:54 INFO - 'SHLVL': '1', 18:26:54 INFO - 'TERM': 'linux', 18:26:54 INFO - 'TMOUT': '86400', 18:26:54 INFO - 'USER': 'cltbld', 18:26:54 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1485311187.133233-85291916', 18:26:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:26:54 INFO - '_': '/tools/buildbot/bin/python'} 18:26:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:26:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:26:54 INFO - Cleaning up... 18:26:54 INFO - Return code: 0 18:26:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:26:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:26:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:26:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:26:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:26:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb8e0acf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1574ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1571f60>, '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 0x1572d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15731d0>, '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': 'bb9b54edd4a927dc735d112f000001d9-1485311187.133233-85291916', '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:26:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:26:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:26:54 INFO - Using env: {'DISPLAY': ':0', 18:26:54 INFO - 'HOME': '/home/cltbld', 18:26:54 INFO - 'LANG': 'en_US.UTF-8', 18:26:54 INFO - 'LANGUAGE': 'en_US:en', 18:26:54 INFO - 'LOGNAME': 'cltbld', 18:26:54 INFO - 'MAIL': '/var/mail/cltbld', 18:26:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:26:54 INFO - 'MOZ_NO_REMOTE': '1', 18:26:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:26:54 INFO - 'NO_EM_RESTART': '1', 18:26:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:26:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:26:54 INFO - 'PWD': '/builds/slave/test', 18:26:54 INFO - 'SHELL': '/bin/bash', 18:26:54 INFO - 'SHLVL': '1', 18:26:54 INFO - 'TERM': 'linux', 18:26:54 INFO - 'TMOUT': '86400', 18:26:54 INFO - 'USER': 'cltbld', 18:26:54 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1485311187.133233-85291916', 18:26:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:26:54 INFO - '_': '/tools/buildbot/bin/python'} 18:26:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:26:54 INFO - Downloading/unpacking blobuploader==1.2.4 18:26:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:26:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:26:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:26:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:26:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:26:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:26:56 INFO - Downloading blobuploader-1.2.4.tar.gz 18:26:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:26:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:26:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:26:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:26:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:26:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:26:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:26:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:26:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:26:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:26:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:26:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:26:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:26:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:26:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:26:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:26:57 INFO - Downloading docopt-0.6.1.tar.gz 18:26:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:26:57 INFO - Installing collected packages: blobuploader, requests, docopt 18:26:57 INFO - Running setup.py install for blobuploader 18:26:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:26:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:26:57 INFO - Running setup.py install for requests 18:26:58 INFO - Running setup.py install for docopt 18:26:58 INFO - Successfully installed blobuploader requests docopt 18:26:58 INFO - Cleaning up... 18:26:58 INFO - Return code: 0 18:26:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:26:58 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:26:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:26:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:58 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:26:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:26:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:58 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 0x7fcb8e0acf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1574ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1571f60>, '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 0x1572d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15731d0>, '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': 'bb9b54edd4a927dc735d112f000001d9-1485311187.133233-85291916', '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:26:58 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:26:58 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:26:58 INFO - Using env: {'DISPLAY': ':0', 18:26:58 INFO - 'HOME': '/home/cltbld', 18:26:58 INFO - 'LANG': 'en_US.UTF-8', 18:26:58 INFO - 'LANGUAGE': 'en_US:en', 18:26:58 INFO - 'LOGNAME': 'cltbld', 18:26:58 INFO - 'MAIL': '/var/mail/cltbld', 18:26:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:26:58 INFO - 'MOZ_NO_REMOTE': '1', 18:26:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:26:58 INFO - 'NO_EM_RESTART': '1', 18:26:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:26:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:26:58 INFO - 'PWD': '/builds/slave/test', 18:26:58 INFO - 'SHELL': '/bin/bash', 18:26:58 INFO - 'SHLVL': '1', 18:26:58 INFO - 'TERM': 'linux', 18:26:58 INFO - 'TMOUT': '86400', 18:26:58 INFO - 'USER': 'cltbld', 18:26:58 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1485311187.133233-85291916', 18:26:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:26:58 INFO - '_': '/tools/buildbot/bin/python'} 18:26:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:26:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:26:58 INFO - Running setup.py (path:/tmp/pip-mVZpbz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:26:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:26:58 INFO - Running setup.py (path:/tmp/pip-cq7XgC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:26:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:26:58 INFO - Running setup.py (path:/tmp/pip-wdGiAO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:26:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:26:58 INFO - Running setup.py (path:/tmp/pip-wOMJQV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:26:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:26:58 INFO - Running setup.py (path:/tmp/pip-pWkBNu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:26:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:26:58 INFO - Running setup.py (path:/tmp/pip-SepPf7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:26:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:26:58 INFO - Running setup.py (path:/tmp/pip-nIg_qu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:26:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:26:58 INFO - Running setup.py (path:/tmp/pip-Va5Frk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:26:59 INFO - Running setup.py (path:/tmp/pip-jVyMV_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:26:59 INFO - Running setup.py (path:/tmp/pip-9DXFwa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:26:59 INFO - Running setup.py (path:/tmp/pip-ys70tO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:26:59 INFO - Running setup.py (path:/tmp/pip-IkWZau-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:26:59 INFO - Running setup.py (path:/tmp/pip-Hw67tN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:26:59 INFO - Running setup.py (path:/tmp/pip-pgSW66-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:26:59 INFO - Running setup.py (path:/tmp/pip-hXH6PL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:26:59 INFO - Running setup.py (path:/tmp/pip-X03tBS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:26:59 INFO - Running setup.py (path:/tmp/pip-vWRI5M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:26:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:26:59 INFO - Running setup.py install for manifestparser 18:26:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:26:59 INFO - Running setup.py install for mozcrash 18:26:59 INFO - Running setup.py install for mozdebug 18:26:59 INFO - Running setup.py install for mozdevice 18:27:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:27:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:27:00 INFO - Running setup.py install for mozfile 18:27:00 INFO - Running setup.py install for mozhttpd 18:27:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:27:00 INFO - Running setup.py install for mozinfo 18:27:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:27:00 INFO - Running setup.py install for mozInstall 18:27:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:27:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:27:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:27:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:27:00 INFO - Running setup.py install for mozleak 18:27:00 INFO - Running setup.py install for mozlog 18:27:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:27:00 INFO - Running setup.py install for moznetwork 18:27:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:27:00 INFO - Running setup.py install for mozprocess 18:27:00 INFO - Running setup.py install for mozprofile 18:27:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:27:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:27:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:27:01 INFO - Running setup.py install for mozrunner 18:27:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:27:01 INFO - Running setup.py install for mozscreenshot 18:27:01 INFO - Running setup.py install for moztest 18:27:01 INFO - Running setup.py install for mozversion 18:27:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:27:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:27:01 INFO - Cleaning up... 18:27:01 INFO - Return code: 0 18:27:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:27:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:27:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:27:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:27:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:27:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb8e0acf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1574ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1571f60>, '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 0x1572d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15731d0>, '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': 'bb9b54edd4a927dc735d112f000001d9-1485311187.133233-85291916', '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:27:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:27:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:27:01 INFO - Using env: {'DISPLAY': ':0', 18:27:01 INFO - 'HOME': '/home/cltbld', 18:27:01 INFO - 'LANG': 'en_US.UTF-8', 18:27:01 INFO - 'LANGUAGE': 'en_US:en', 18:27:01 INFO - 'LOGNAME': 'cltbld', 18:27:01 INFO - 'MAIL': '/var/mail/cltbld', 18:27:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:27:01 INFO - 'MOZ_NO_REMOTE': '1', 18:27:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:27:01 INFO - 'NO_EM_RESTART': '1', 18:27:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:27:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:27:01 INFO - 'PWD': '/builds/slave/test', 18:27:01 INFO - 'SHELL': '/bin/bash', 18:27:01 INFO - 'SHLVL': '1', 18:27:01 INFO - 'TERM': 'linux', 18:27:01 INFO - 'TMOUT': '86400', 18:27:01 INFO - 'USER': 'cltbld', 18:27:01 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1485311187.133233-85291916', 18:27:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:27:01 INFO - '_': '/tools/buildbot/bin/python'} 18:27:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:27:01 INFO - Running setup.py (path:/tmp/pip-us8Vl8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:27:01 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 18:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:27:01 INFO - Running setup.py (path:/tmp/pip-OM9PMo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:27:01 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:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:27:01 INFO - Running setup.py (path:/tmp/pip-TBOheq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:27:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:27:01 INFO - Running setup.py (path:/tmp/pip-IEb4Ud-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:27:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:27:01 INFO - Running setup.py (path:/tmp/pip-_ftk5A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:27:02 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:27:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:27:02 INFO - Running setup.py (path:/tmp/pip-UrK_3F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:27:02 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:27:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:27:02 INFO - Running setup.py (path:/tmp/pip-0Pxgjt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:27:02 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:27:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:27:02 INFO - Running setup.py (path:/tmp/pip-633VHa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:27:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:27:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:27:02 INFO - Running setup.py (path:/tmp/pip-zhwZgJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:27:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 18:27:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:27:02 INFO - Running setup.py (path:/tmp/pip-mhsLHX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:27:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:27:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:27:02 INFO - Running setup.py (path:/tmp/pip-ydu5rG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:27:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 18:27:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:27:02 INFO - Running setup.py (path:/tmp/pip-gw3YKc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:27:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 18:27:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:27:02 INFO - Running setup.py (path:/tmp/pip-ar8Rio-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:27:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 18:27:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:27:02 INFO - Running setup.py (path:/tmp/pip-dbD6Va-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:27:02 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:27:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:27:02 INFO - Running setup.py (path:/tmp/pip-wHpixO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:27:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 18:27:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:27:02 INFO - Running setup.py (path:/tmp/pip-00w1V2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:27:02 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:27:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:27:02 INFO - Running setup.py (path:/tmp/pip-i07AqF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:27:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 18:27:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:27:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:27:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:27:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:27:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:27:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:27:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:27:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:27:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:27:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:27:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:27:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:27:04 INFO - Downloading blessings-1.6.tar.gz 18:27:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:27:05 INFO - Installing collected packages: blessings 18:27:05 INFO - Running setup.py install for blessings 18:27:05 INFO - Successfully installed blessings 18:27:05 INFO - Cleaning up... 18:27:05 INFO - Return code: 0 18:27:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:27:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:27:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:27:05 INFO - Reading from file tmpfile_stdout 18:27:05 INFO - Current package versions: 18:27:05 INFO - argparse == 1.2.1 18:27:05 INFO - blessings == 1.6 18:27:05 INFO - blobuploader == 1.2.4 18:27:05 INFO - docopt == 0.6.1 18:27:05 INFO - functools32 == 3.2.3-2 18:27:05 INFO - jsonschema == 2.5.1 18:27:05 INFO - manifestparser == 1.1 18:27:05 INFO - mozInstall == 1.12 18:27:05 INFO - mozcrash == 1.0 18:27:05 INFO - mozdebug == 0.1 18:27:05 INFO - mozdevice == 0.48 18:27:05 INFO - mozfile == 1.2 18:27:05 INFO - mozhttpd == 0.7 18:27:05 INFO - mozinfo == 0.9 18:27:05 INFO - mozleak == 0.1 18:27:05 INFO - mozlog == 3.4 18:27:05 INFO - moznetwork == 0.27 18:27:05 INFO - mozprocess == 0.23 18:27:05 INFO - mozprofile == 0.28 18:27:05 INFO - mozrunner == 6.13 18:27:05 INFO - mozscreenshot == 0.1 18:27:05 INFO - mozsystemmonitor == 0.3 18:27:05 INFO - moztest == 0.8 18:27:05 INFO - mozversion == 1.4 18:27:05 INFO - psutil == 3.1.1 18:27:05 INFO - requests == 1.2.3 18:27:05 INFO - wsgiref == 0.1.2 18:27:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:27:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:27:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:27:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:27:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:27:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb8e0acf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1574ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1571f60>, '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 0x1572d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15731d0>, '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': 'bb9b54edd4a927dc735d112f000001d9-1485311187.133233-85291916', '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:27:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 18:27:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:27:05 INFO - Using env: {'DISPLAY': ':0', 18:27:05 INFO - 'HOME': '/home/cltbld', 18:27:05 INFO - 'LANG': 'en_US.UTF-8', 18:27:05 INFO - 'LANGUAGE': 'en_US:en', 18:27:05 INFO - 'LOGNAME': 'cltbld', 18:27:05 INFO - 'MAIL': '/var/mail/cltbld', 18:27:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:27:05 INFO - 'MOZ_NO_REMOTE': '1', 18:27:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:27:05 INFO - 'NO_EM_RESTART': '1', 18:27:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:27:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:27:05 INFO - 'PWD': '/builds/slave/test', 18:27:05 INFO - 'SHELL': '/bin/bash', 18:27:05 INFO - 'SHLVL': '1', 18:27:05 INFO - 'TERM': 'linux', 18:27:05 INFO - 'TMOUT': '86400', 18:27:05 INFO - 'USER': 'cltbld', 18:27:05 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1485311187.133233-85291916', 18:27:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:27:05 INFO - '_': '/tools/buildbot/bin/python'} 18:27:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:27:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:27:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 18:27:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 18:27:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:27:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 18:27:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 18:27:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 18:27:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 18:27:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 18:27:05 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:27:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:27:05 INFO - Cleaning up... 18:27:05 INFO - Return code: 0 18:27:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:27:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:27:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:27:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:27:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:27:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb8e0acf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1574ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1571f60>, '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 0x1572d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15731d0>, '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': 'bb9b54edd4a927dc735d112f000001d9-1485311187.133233-85291916', '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:27:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:27:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:27:05 INFO - Using env: {'DISPLAY': ':0', 18:27:05 INFO - 'HOME': '/home/cltbld', 18:27:05 INFO - 'LANG': 'en_US.UTF-8', 18:27:05 INFO - 'LANGUAGE': 'en_US:en', 18:27:05 INFO - 'LOGNAME': 'cltbld', 18:27:05 INFO - 'MAIL': '/var/mail/cltbld', 18:27:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:27:05 INFO - 'MOZ_NO_REMOTE': '1', 18:27:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:27:05 INFO - 'NO_EM_RESTART': '1', 18:27:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:27:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:27:05 INFO - 'PWD': '/builds/slave/test', 18:27:05 INFO - 'SHELL': '/bin/bash', 18:27:05 INFO - 'SHLVL': '1', 18:27:05 INFO - 'TERM': 'linux', 18:27:05 INFO - 'TMOUT': '86400', 18:27:05 INFO - 'USER': 'cltbld', 18:27:05 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1485311187.133233-85291916', 18:27:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:27:05 INFO - '_': '/tools/buildbot/bin/python'} 18:27:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:27:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:27:05 INFO - Cleaning up... 18:27:05 INFO - Return code: 0 18:27:05 INFO - Running post-action listener: _resource_record_post_action 18:27:05 INFO - Running post-action listener: _start_resource_monitoring 18:27:05 INFO - Starting resource monitoring. 18:27:05 INFO - [mozharness: 2017-01-25 02:27:05.893552Z] Finished create-virtualenv step (success) 18:27:05 INFO - [mozharness: 2017-01-25 02:27:05.894053Z] Running install step. 18:27:05 INFO - Running pre-action listener: _resource_record_pre_action 18:27:05 INFO - Running main action method: install 18:27:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:27:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:27:06 INFO - Reading from file tmpfile_stdout 18:27:06 INFO - Detecting whether we're running mozinstall >=1.0... 18:27:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:27:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:27:06 INFO - Reading from file tmpfile_stdout 18:27:06 INFO - Output received: 18:27:06 INFO - Usage: mozinstall [options] installer 18:27:06 INFO - Options: 18:27:06 INFO - -h, --help show this help message and exit 18:27:06 INFO - -d DEST, --destination=DEST 18:27:06 INFO - Directory to install application into. [default: 18:27:06 INFO - "/builds/slave/test"] 18:27:06 INFO - --app=APP Application being installed. [default: firefox] 18:27:06 INFO - mkdir: /builds/slave/test/build/application 18:27:06 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:27:06 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:27:19 INFO - Reading from file tmpfile_stdout 18:27:19 INFO - Output received: 18:27:19 INFO - /builds/slave/test/build/application/firefox/firefox 18:27:19 INFO - Running post-action listener: _resource_record_post_action 18:27:19 INFO - [mozharness: 2017-01-25 02:27:19.814183Z] Finished install step (success) 18:27:19 INFO - [mozharness: 2017-01-25 02:27:19.814422Z] Running run-tests step. 18:27:19 INFO - Running pre-action listener: _resource_record_pre_action 18:27:19 INFO - Running main action method: run_tests 18:27:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:27:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:27:19 INFO - Python 2.7.3 18:27:19 INFO - Return code: 0 18:27:19 INFO - grabbing minidump binary from tooltool 18:27:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:27:19 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 0x1571f60>, '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 0x1572d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15731d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:27:19 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:27:19 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:27:19 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:27:19 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:27:19 INFO - Return code: 0 18:27:19 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:27:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:27:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:27:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:27:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:27:19 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/Tqh9IocdQa-2pwaE69dh5w/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-055', '--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:27:19 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/Tqh9IocdQa-2pwaE69dh5w/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-055 --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:27:19 INFO - Using env: {'DISPLAY': ':0', 18:27:19 INFO - 'HOME': '/home/cltbld', 18:27:19 INFO - 'LANG': 'en_US.UTF-8', 18:27:19 INFO - 'LANGUAGE': 'en_US:en', 18:27:19 INFO - 'LOGNAME': 'cltbld', 18:27:19 INFO - 'MAIL': '/var/mail/cltbld', 18:27:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:27:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:27:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:27:19 INFO - 'MOZ_NO_REMOTE': '1', 18:27:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:27:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:27:19 INFO - 'NO_EM_RESTART': '1', 18:27:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:27:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:27:19 INFO - 'PWD': '/builds/slave/test', 18:27:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:27:19 INFO - 'SHELL': '/bin/bash', 18:27:19 INFO - 'SHLVL': '1', 18:27:19 INFO - 'TERM': 'linux', 18:27:19 INFO - 'TMOUT': '86400', 18:27:19 INFO - 'USER': 'cltbld', 18:27:19 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1485311187.133233-85291916', 18:27:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:27:19 INFO - '_': '/tools/buildbot/bin/python'} 18:27:19 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/Tqh9IocdQa-2pwaE69dh5w/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-055', '--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:27:20 INFO - mozversion application_buildid: 20170124163348 18:27:20 INFO - mozversion application_changeset: 3861c5f62b183a33e3b70d8321b48fdaac05bec1 18:27:20 INFO - mozversion application_display_name: Firefox 18:27:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:27:20 INFO - mozversion application_name: Firefox 18:27:20 INFO - mozversion application_remotingname: firefox 18:27:20 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 18:27:20 INFO - mozversion application_vendor: Mozilla 18:27:20 INFO - mozversion application_version: 52.0 18:27:20 INFO - mozversion platform_buildid: 20170124163348 18:27:20 INFO - mozversion platform_changeset: 3861c5f62b183a33e3b70d8321b48fdaac05bec1 18:27:20 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 18:27:20 INFO - mozversion platform_version: 52.0 18:27:20 INFO - using testdate: 1485311240 18:27:20 INFO - actual date: 1485311240 18:27:20 INFO - starting webserver on 'localhost:44529' 18:27:20 INFO - SUITE-START | Running 2 tests 18:27:20 INFO - TEST-START | dromaeo_css 18:27:20 INFO - operating with platform_type : linux_ 18:27:20 INFO - Initialising browser for dromaeo_css test... 18:27:20 INFO - TEST-INFO | started process 26650 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsb4DtM/profile http://localhost:44529/getInfo.html) 18:27:24 INFO - PROCESS | 26650 | __metrics Screen width/height:1600/1200 18:27:24 INFO - PROCESS | 26650 | colorDepth:24 18:27:24 INFO - PROCESS | 26650 | Browser inner width/height: 1024/697 18:27:24 INFO - PROCESS | 26650 | __metrics 18:27:24 INFO - PROCESS | 26650 | 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:27:24 INFO - PROCESS | 26650 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:27:25 INFO - PROCESS | 26650 | console.error: PushService: 18:27:25 INFO - PROCESS | 26650 | stateChangeProcessEnqueue: Error transitioning state 18:27:25 INFO - PROCESS | 26650 | UnknownError 18:27:25 INFO - TEST-INFO | 26650: exit 0 18:27:25 INFO - Browser initialized. 18:27:25 INFO - Running cycle 1/1 for dromaeo_css test... 18:27:25 INFO - TEST-INFO | started process 26749 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsb4DtM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:27:37 INFO - PROCESS | 26749 | RSS: Main: 141549568 18:27:37 INFO - PROCESS | 26749 | 18:30:38 INFO - PROCESS | 26749 | Cycle 1(1): loaded http://localhost:44529/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44529/tests/dromaeo/cssquery-ext.html) 18:30:39 INFO - PROCESS | 26749 | RSS: Main: 240918528 18:30:39 INFO - PROCESS | 26749 | 18:33:42 INFO - PROCESS | 26749 | Cycle 1(1): loaded http://localhost:44529/tests/dromaeo/cssquery-ext.html (next: http://localhost:44529/tests/dromaeo/cssquery-jquery.html) 18:33:42 INFO - PROCESS | 26749 | RSS: Main: 225058816 18:33:42 INFO - PROCESS | 26749 | 18:36:44 INFO - PROCESS | 26749 | Cycle 1(1): loaded http://localhost:44529/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44529/tests/dromaeo/cssquery-mootools.html) 18:36:45 INFO - PROCESS | 26749 | RSS: Main: 217391104 18:36:45 INFO - PROCESS | 26749 | 18:39:47 INFO - PROCESS | 26749 | Cycle 1(1): loaded http://localhost:44529/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44529/tests/dromaeo/cssquery-prototype.html) 18:39:47 INFO - PROCESS | 26749 | RSS: Main: 220766208 18:39:47 INFO - PROCESS | 26749 | 18:42:50 INFO - PROCESS | 26749 | Cycle 1(1): loaded http://localhost:44529/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44529/tests/dromaeo/cssquery-yui.html) 18:42:51 INFO - PROCESS | 26749 | RSS: Main: 176422912 18:42:51 INFO - PROCESS | 26749 | 18:45:53 INFO - PROCESS | 26749 | Cycle 1(1): loaded http://localhost:44529/tests/dromaeo/cssquery-yui.html (next: http://localhost:44529/tests/dromaeo/cssquery-dojo.html) 18:45:53 INFO - PROCESS | 26749 | RSS: Main: 260214784 18:45:53 INFO - PROCESS | 26749 | 18:45:53 INFO - PROCESS | 26749 | __start_tp_report 18:45:53 INFO - PROCESS | 26749 | _x_x_mozilla_page_load 18:45:53 INFO - PROCESS | 26749 | _x_x_mozilla_page_load_details 18:45:53 INFO - PROCESS | 26749 | |i|pagename|runs| 18:45:53 INFO - PROCESS | 26749 | |0;dojo.html;3214.6946040126186;3258.5763850699414;3258.837058147092;3265.869365225391;3292.407366673999;5475.425080366559;5592.6085174037835;5598.860028499284;5599;5607.887842243153;5567.415421380758;5615.045442274813;5616.101423772197;5622.437756224375;5642.633228840123;5035.3705786776745;5053.77258023388;5088.0587091388215;5131.666441681297;5167.664101833388;5040.143175660147;5052.444231134572;5093.567046801008;5146.74266286686;5150.3047088642925;4968.925466118011;4978.775955082012;5014.498550144993;5028.723420211887;5051.747412629372;5072.797088116471;5084.313617661605;5148.871278218037;5158.7420628968775;5188.11801993662;4848.878778030542;5036.269740887551;5057.5195356441445;5114.769835357418;5136.717480538568;5377.059014672417;5378.1663842104535;5410.567154627621;5472.7263636818;5554.250176226238;5848.298204215522;5850.912236316459;5947.226860508106;5947.286325640907;5954.821355359346;5947.405259474018;5982.491488223506;5995.160677505153;6003;6003.159557661931;5565.30433695788;5599.860003499904;5609.354924183689;5617.269754931915;5629.437056294338;6132.17215675878;6136.079588061826;6149.0161574148815;6155.876882462326;6171.6297022178815;5666.178404131378;5887.234659494324;5891.7054147292465;5901.7639294427745;5906.852328691731;5894.145348924468;5926.140709597084;5999.190109335271;6003.189569408161;6015.488683461867;5934.673592952429;5945;5955.553333500005;5956.970215148896;5958.046712526062;5906.143609176645;5913.822585322448;5915.852103697442;5940.138679891392;5963.850903727442;4551.590356867898;4580.129775342674;4582.266837306015;4649.418822647169;4657.417822772153;4171.812268447927;4191.706580539394;4202.579742025834;4214.452121224283;4218.704690671623;5718.027935250934;5725.713714314267;5728;5728.255326807572;5728.427157284322;5816.82549523515;5870.941290587125;5904.911426328608;5925.170476133345;6012.248468941382;5710.600257981984;5723.971380143073;5724.685142317213;5733.3406658234;5752.223449834219;5187.610929180251;5188.636795424284;5191;5194.22086687;5249.868753281122;6111.19673459643;6116.274903966807;6120.14317995481;6237.688115594293;6291.591046581958;5882.647041177543;5992.760289588367;6015.849603759941;6021.488173505126;6026.367231440636;6218.129461875432;6247.156633854372;6252.218472690914;6253.468455181361;6260.780872669435;5545.916811247916;5825.009748342876;5827.038538641077;5828.5919985602295;5867.911981320198;5740.885182296247;5806;5808.186853840551;5850.23946886902;5929.377415371324;5715.799947001835;5741.712914354182;5755.769769209185;5759.424057594207;5764.423557644202;5742.339630942495;5748.022836117786;5749.942500574941;5792.594518383841;5796.188533605215;5683.914741278967;5790.536757059341;5821.243238378984;5824.94175058244;5827.737751801264;5966.075258334965;5969.074793407029;5970.910436343545;5971.850703732442;5980.401959803985;5634.8027819027775;5649;5663.688497132697;5776.393478684678;5812.18629391894 18:45:53 INFO - PROCESS | 26749 | |1;ext.html;591.9704014799258;616.941390567896;644.1561554363784;663.9103720997664;688.8105770913002;34348.48477272843;37037;37057.44413833788;45845.54154458454;46559.767201163944;47368.28947565789;47650.76174619122;47719;47728.28407573889;47795.28307075397;8899.777505562364;9063.909360906373;9073.183413492785;9073.863892041625;9074.27405807537;8589.699360522383;8697.869531957025;8770.254528365065;8784.868226976601;8882.156195161482;10804.243702940797;11051.668449946514;11083.612073577426;11331.263467874604;11364;11041.447927603627;11105.72235694106;11108.833367499494;11114;11125.554977800879;10500.684979450629;10549.367037977747;10557.41934193619;10571.260011799177;10578.259521833475;44442.77778611118;44915;45020.77489612564;45052.54947450549;45136.77431612821;3496.772709773882;3650.817459127033;3653.5798383186007;3754.6620804127488;4241.448611680493;33024;33301.50047749286;33304.6669533304;33324.166895827555;33631.49552756686;4223.556526564728;4262.46719160105;4267.445232119804;4278.272693642089;4318.676099292566;326799;330952;331699.34150329337;332139;334162;62186.37813621806;62194.378056219764;62514.68742656348;62523;62590.687046565385;35922.10194745152;36267;36508.45237321442;37921.431178532344;38000.23999519994;17017.404390846263;17165.82834171667;17188.22652980619;17215.053172075404;17236.741448878278;12891.871081289255;13000.08999370035;13040.54358097462;13043.673908152372;13045.47818087285;18665.720014199796;18987.43037708871;20372.89813550923;20989.68515472269;21226.78773212279;7368.815779605446;7556.886646700304;7580.734674286374;7730.7680769577;7791.10402303731;936.321167153808;1061.230607809332;1067.4982758103679;1180.055955235808;1185.3065956415421;32464;32469.67530324714;32800.1799955003;32910.506342404886;32960.7970329144;1100.5047728522197;1108.7449886525983;1109.7003808971695;1137.5449820071872;1189.5539172810197;17394.565135871704;17473.825261747475;17478.388256410966;17543.21055552503;17622.647547048986;522828;524628;528064;528228;530025;63010;63382.683086585195;63520.36479635237;63536.36463635397;63667.04499432512;1181.4919584578713;1192.6481687902262;1229.8893099621077;1334.619633404475;1340.0887396570427;31595.84202078975;34502.48246276258;35008.47487287642;35079;35305.293894122486;51355.74322128366;52038.73980630073;52093.47906521038;52102.91588336424;52173.47826521838;399755;401669;402148;403084;403583;24577.13980010691;24602;24607.50784984326;27816.72183278141;28433.28916777097;50371.74814125906;50387;50430.747846262;50651.24023139729;50757.74621127018;40542.59457405388;41437;41948;42396.36405453982;43402;41787.62604257438;41963;42760.78619606882;43238.78380608077;43399;42525.78737106295;43636.56363436325;43734.781326093165;43793.12413751643;43803.12393752171;36058.63941360553;37572.24855502929;38192.61807381891;38543;38723.2255354897;25437.491250175262;25483.745162548137;25727.614085789104;26093.608595871458;26213.868930655226 18:45:53 INFO - PROCESS | 26749 | |2;jquery.html;394.439895348605;395.9128991621844;426.48395441515765;429.8474041715191;430.2298884995857;13228.206307621549;13291.734165316688;18749.531261718465;20276.18895244192;20292;21627.134914603437;22543.210987615435;22913.77086229137;22921.656175157386;22995.540089198206;6198.938010619881;6550.541462097655;6580.210374755036;6604.834879128012;6608.900866487006;6572.901406478906;6683.899741503857;6686.364795344434;7244.601546914944;7512.924870751277;7640.961795191016;7648.617569121549;7661.846763064763;7665.655045522937;7669.118051424102;7281.635918204095;7453.627318634074;7492.400607951406;7513.474056816025;7517.4737768356235;7070.116235470567;7279.927200727978;7312.597807120606;7348.5958272295;7370.963145184293;25559.74440255611;26889.865550672315;27192.59211111835;27456.725432745818;27719;2617.9869100654373;2625.2518032360877;2702.6756789185424;2752.1881045091745;2849.244950088228;16382.672346553003;16447.91776041124;16485.68114550833;16595.6680866382;16737;2107.95784084319;2126.8404869634683;2129.1270579062666;2171.323047596352;2276.46503071778;72756.63621681911;87565.56217218937;90551;91021;99090;14778.078849749463;18277.90861045713;18368.448946531626;18476.260949561867;18589.34937277189;33623.83188084044;33691.49462758061;33841.492377614355;33877;33892.15269618279;9855.211583073336;9886.65396711126;9918.30571859963;10026.348287361445;10089.495525223856;7999.240072193133;8157.221566312405;8318.251357377743;8334.791630209293;8555.614997325136;9408.306504829197;9415.90584094164;9570;9585.760355991017;9597.264779537798;3391.4389890614757;3495.965040349615;3511.2801875615437;3732.869349572752;3782.451544526083;921.2998121427763;949.9905000949902;982.2583949118513;1038.1279725030859;1080.065743132185;19783.802161978485;20083.29708460226;20149.39551813448;20164.69752953707;20198.79801201969;984.778424854402;990.5938565188238;1000.7448100734266;1013.2704452793977;1023.0383439566932;11018.834717479243;11463.885361146282;11470.541178352772;11566.47950842214;11680.824787628193;448262;455553;462831;465383;466059;90557.54721226484;93022.53488732514;93271;93434.53282733542;94652.52673736587;981.6809536900536;987.6592575561419;1000.5697550053401;1003.7239759066315;1005.2410430125209;17694.469165925042;18110.81889181091;18126.184321705292;18276.360327388476;18354.174062166967;42838.14323713571;42882.35676464793;44667.55332446634;44790.77604612087;44851.327230092225;61493;62362;62417.687911560155;63476.68261658847;63729.362706374195;8866.069062748576;9278.95360523193;9330.906690933003;9410.729551510458;9458.810823783624;13797.448102075805;13906.652333691738;13983.18622948204;14254.643633909236;14498.85501144975;12061.577844775391;12222.816657749963;12274.509019639114;12365.134440589072;12570.87429125697;6823.454123669995;8115.75652730419;11925.253625179364;12164.635060948185;12321.876781232073;11869.703257418289;12098.455569499569;12120.151589388659;12538.937305313415;12816.871831281567;8910.55447227623;9170.908290917272;9211.585478653611;9262.120098590623;9399.248060155307;9739.561719722487;12231.510739570318;12242.265464072185;12305.630831075083;12357.25856448616 18:45:53 INFO - PROCESS | 26749 | |3;mootools.html;1377.7037936843576;1522.406261557992;1544.629288970647;1690.6027083635342;1829.1311626977179;8041.276285134344;8659.567021648923;9051.366404351682;9076.50079245642;9211.539423028855;9041.819163616723;9122.589483473243;9127.13292237238;9130.634774609025;9134.77163070922;2427.0777104700232;2448.7306396296394;2449.020391843268;2452.9877350613224;2458.7418321076298;2405.405005255967;2440.5728997425467;2440.719317278509;2446.6330050492393;2446.9510609787794;2459.764497000105;2465.2111324376206;2498.837575557592;2500.8874600642926;2501.2246203676827;2310.4801419680607;2346.1905642553293;2352.96470552941;2405.2663937499055;2407.121400688745;2430.586800243945;2442.3283597010786;2451.840630359021;2452.4604586991013;2453.1904471524354;8970.82058358829;9039;9095.954520227422;9149.725508234696;9192.034836342222;5448.128299472105;5646.20953066571;5662.37713851476;5663.915041274384;5667;3308.073739352985;3400.1669590950373;3437.1750779812915;3440.0367896988882;3443.1220038889924;1072.2922870905243;1084.1164450972433;1092.6612750047511;1103.0348445110528;1105.3865104866795;12544.435500402504;12569.937150314281;12660.303683297416;12666.240025598401;12668.936655316664;4685.531446855287;4762.261849413347;4762.517826018088;4793.952060479421;4890.902181956341;10107.64623238183;10117.898821011842;10119.13987311072;10121.544530495998;10130.037646423578;5467.917981230285;5470.234167216593;5476.288082549243;5477.397486276506;5485.753141108659;4364.148990946798;4562.726236425825;4627.120847039052;4965.975170124127;5056.039352523009;3983.601639836051;4067.7559346439307;4136.607022332873;4158.854440094643;4164.541900390955;1229;1257.6855786053486;1274.407400558726;1283.525095714573;1289.5679947217786;868.7524055644111;925.319889880937;935.2891802230354;936.2463217110155;958.5015791788231;5682.289713785777;5774.703950641805;5944.316403613553;5998.490128339053;5998.520118390518;449.1824878720697;451.96610254231064;467.81988934259874;481.13636023338194;485.2091091520869;4070.7354021988476;4144.8134833932545;4162.83348666056;4170.52039015511;4176.31090870003;11088.778224435628;11442.256253343507;11456.94271528637;11501.13741469393;11505.769884602429;11301.49143288554;11344.886551134548;11361.772764544663;11362.318260904372;11363.431828408546;8202.753917382488;8275.50346979183;8379.162083791573;8389.45468544555;8445.028821685462;6027.879442411215;6161.753529858755;6202.34875338101;6303.306636270098;6305.054241863757;10009.249306302203;10065;10183.592656293666;10328.586856525655;10605.89394106049;8819.426737262058;10400.323978941344;10496.632617858339;10583.31208471447;10740.516676749408;7852.685892564234;7864.882026769717;8143.75568732939;8144.429889907887;8156.306713929145;8761.25529330014;8923.107689231025;8979.14698103679;8979.910200897908;8981.550922453982;7393.963030184815;7467.253274672489;7500.174980752004;7533.096028476618;7565.167831538636;7326.780196594111;7459.515131516434;7496.100467943882;7499.100107986858;7543.245675432632;7111.751088711871;7235.565866048054;7236.457265704988;7243.5653860766415;7247.746328878464;4961.3798275215595;5157.793688252578;5255.868603284948;5259.053370393366;5414.458554144711;5134.486551344836;5212.50481204285;5241.423443421297;5321.255024296679;5327.0411325961695 18:45:53 INFO - PROCESS | 26749 | |4;prototype.html;319.9712025917668;352.7424979764772;355.3656722749891;355.95016697662317;356.1630169102609;5331.840044798662;5342.492463215993;5377.763114483671;5409.9729501352485;5419.701916394597;5295.226099256744;5353.00969320675;5353.625246232766;5357.8392648220615;5359.919601205985;4018.327812005013;4213.230443213857;4239.9152016959715;4252.255855225339;4255.999840037589;3913.3934240192816;3934.1344904121047;4084.9591504084874;4142.550107462394;4148.357004664282;4132.2768515509815;4134.4625198724125;4137.069159439132;4139.254934111858;4140.668746500288;4141.937870931938;4150.315197992358;4167.499900011988;4176.433837311009;4201.70588058836;3630.2013557017412;3705.907352316187;3707.295613833363;3740.102375429877;3745.2696724138805;5181.637285390024;5194.63637545372;5197.636165468419;5207.427183009866;5209.1404918188455;4815.542523460265;4836.443808961979;4844.006978569385;4859.032092002744;4865.5864251538305;5380.569554435636;5552.944470555283;5614.052622373287;5614.803481878155;5654.236678048452;4022.5172979242384;4026.838926442939;4036.535798383165;4037.354023356249;4039.5152581690386;5579.888402231933;5719.65682059082;5786.508146807527;5812.157237200582;5865.237519122529;3907.8632247871187;4035.535913369941;4144.689148313889;4204.411382406465;4219.261629214937;4239.321708526621;4239.448871646666;4254.255505286563;4263.829446822155;4266.168097221012;4614.877156508044;4766.713997160182;4772.785224664898;4802.303665968415;4814.229723244264;5581.609287349846;5615.6630602164005;5639.689817060101;5655.773769049192;5659.858503537363;1395.323268214915;1398.1331574423718;1405.051590176647;1413.3710498827922;1423.323921137472;953.208836665566;980.6028558433845;985.8422652375585;998.0568362896993;1018.3380802478448;5246.032737723353;5308.628396012242;5310.070737620901;5311.282976798083;5320.308359913264;5544.750486228129;5545.833624991256;5554.611177217556;5556.388797232301;5563.805266815642;5097.413797413271;5212.64471237481;5281.419043905167;5285.986987417135;5323.467653234646;2908.5200941844782;2942.8234305941282;2958.467475854367;2958.8372639505033;2969.539721343195;5804.361520232771;5832;5836.182934389189;5846.853828654318;5871;5586.329640443173;5868.149118377897;5887.941120588825;5923.200367950358;5924.341184468356;6036.185115009505;6077.756889724362;6088.452039316483;6114.205153330127;6154.538409619386;5586.441355864381;5781.739821708117;5796.565257605612;5800.535957123504;5866.325372582208;5744.569157313134;5758.4817366435545;5763.4812866842185;5775.9133612995;5785.942140578709;5838.532917366685;5892.5285977122585;5964.880702385841;6036.215292012011;6114.969425153024;4196.265653510684;4198.139381426861;4210.073783767566;4249.830006799817;4260.510041345285;5726.627769194989;5878.29460464747;5880.735366908414;5883;5896.616719913192;5587.7485513152815;5588.441155884379;5645.548356131581;5650.830475085754;5669.42826019605;5569.470900264469;5571.582131340085;5608.298962629671;5611.747471363717;5662.971685141712;5403.324584426947;5540.22436858832;5548.69482178484;5617.213590097308;5631.802886898939;5625.549956003591;5656.745446454837;5666.886662266814;5672.801451949162;5681.659100453986;5508.724563771875;5518.19986102021;5538.695371754592;5550.833474995757;5552.444755524415 18:45:53 INFO - PROCESS | 26749 | |5;yui.html;732.3189433826542;746.9402447804174;781.1056340490139;819.2176471469747;821.6877586517122;14017.36921838517;14973.850261497382;14995.850041499581;15002.849971500294;15024.549263522098;13500.12249203801;13519.391627376766;13569.45722171112;13606.387712552934;13656.931715341409;728.0717085715728;745.3738859358139;844.1010324004915;859.3769517100114;867.154524338771;861.1345597674328;875.0942774228674;875.264777586827;876.027609353617;876.1370050500284;904.8280826642916;905.42053086025;906.0576999920075;906.3429013964889;906.9682561110362;913;913.0914739833842;913.4975763330175;913.6254135804288;913.9817203655956;656.4944992355908;660.8050625065617;661.6526323680058;661.8841702702056;665.8335416145964;13723.725525489534;14049.719005619932;14049.719005619932;14131.646708832259;14541.34563944625;14352.64118397038;14678.192699401528;14987.100773953598;15125.621859453491;15149;11759.529618815237;12094.758104837854;12138.878611213862;12345.259284442873;12462.875371246353;3764.6611804937415;3802.049487628093;3827.4832897558754;3840.711946604016;3891.2606604745292;61803;63564;63881.68059159721;64564;64859.02711459332;11049.17131215162;11159.72100697489;11339.14956378275;11413.42932853354;11544.711382215512;20250.392488225378;20519.794802051787;20534.178632854517;20596;20735.27426540064;8249.422540422232;8268.421210515206;8329.625166867505;8488.40581159313;8666.480011199348;4561.475430325489;4606.124836281096;4701.811927522929;4727.929081063787;4741.786619602057;3273.5908011498564;3341.532185494033;3376.1053320869987;3376.139084533428;3384.221629025338;565.7425871228581;571.0321005895051;575.5999580291741;584.1004852527145;587.6004317064437;538.4050624060435;578.8176724331822;601.7863658401204;617.561531312773;640.4812102197235;8467.026291976379;8521.488710677379;8630.76086576976;8731.825363492695;8763.780905477413;567.7643777832236;571.928508936383;599.3526990849872;603.5654328883197;611.6330201878837;3947.1908258806875;3967.980160099181;4749.928751068736;4894.902101958012;5092.8472145835685;53492;85297.57351213328;88718.55640721755;88986;90852;76808;77471;78681.60659196667;79999.60000199961;80172;68872;69272;69286.65356673184;70195;70212.94680579795;9691.321607487409;10082.581907871521;10127.544260508148;10221.182305415698;10237.385756854612;41609.167816644105;41821.79089104535;42784;46029.76985115053;49309.506904930495;58967;60595;62405;62516;63861;12527.18573292733;12645.178063425848;12703.36483175819;13078.607641770763;13197.010224233029;13831.446742130202;13844.238566878515;14048.297585120907;14110.717785644434;14121.788173177205;12264.141510094209;12274.386280686109;12368;12501.24992500453;12642.55751048709;12738.617841464771;12934.870651293742;13256.867431325949;13317.66705832362;14576.198309093103;13528.526501572398;13582.86417135816;13709.108907920952;13803.309834508435;14054.227017514135;10410.219233557362;10850.29473084247;10946.562137514411;11105.833412498981;11119.777604448027;10451.268411211142;10596.258261921592;10599.788004240027;10728.678139655824;10788.838167427339 18:45:53 INFO - PROCESS | 26749 | __end_tp_report 18:45:53 INFO - PROCESS | 26749 | __start_cc_report 18:45:53 INFO - PROCESS | 26749 | _x_x_mozilla_cycle_collect,976 18:45:53 INFO - PROCESS | 26749 | __end_cc_report 18:45:53 INFO - PROCESS | 26749 | __startTimestamp1485312353833__endTimestamp 18:45:53 INFO - PROCESS | 26749 | 18:45:53 INFO - PROCESS | 26749 | ------- Summary: start ------- 18:45:53 INFO - PROCESS | 26749 | Number of tests: 6 18:45:53 INFO - PROCESS | 26749 | 18:45:53 INFO - PROCESS | 26749 | [#0] dojo.html Cycles:165 Average:5544.35 Median:5741.71 stddev:612.73 (10.7%) stddev-sans-first:586.73 18:45:53 INFO - PROCESS | 26749 | Values: 3214.7 3258.6 3258.8 3265.9 3292.4 5475.4 5592.6 5598.9 5599.0 5607.9 5567.4 5615.0 5616.1 5622.4 5642.6 5035.4 5053.8 5088.1 5131.7 5167.7 5040.1 5052.4 5093.6 5146.7 5150.3 4968.9 4978.8 5014.5 5028.7 5051.7 5072.8 5084.3 5148.9 5158.7 5188.1 4848.9 5036.3 5057.5 5114.8 5136.7 5377.1 5378.2 5410.6 5472.7 5554.3 5848.3 5850.9 5947.2 5947.3 5954.8 5947.4 5982.5 5995.2 6003.0 6003.2 5565.3 5599.9 5609.4 5617.3 5629.4 6132.2 6136.1 6149.0 6155.9 6171.6 5666.2 5887.2 5891.7 5901.8 5906.9 5894.1 5926.1 5999.2 6003.2 6015.5 5934.7 5945.0 5955.6 5957.0 5958.0 5906.1 5913.8 5915.9 5940.1 5963.9 4551.6 4580.1 4582.3 4649.4 4657.4 4171.8 4191.7 4202.6 4214.5 4218.7 5718.0 5725.7 5728.0 5728.3 5728.4 5816.8 5870.9 5904.9 5925.2 6012.2 5710.6 5724.0 5724.7 5733.3 5752.2 5187.6 5188.6 5191.0 5194.2 5249.9 6111.2 6116.3 6120.1 6237.7 6291.6 5882.6 5992.8 6015.8 6021.5 6026.4 6218.1 6247.2 6252.2 6253.5 6260.8 5545.9 5825.0 5827.0 5828.6 5867.9 5740.9 5806.0 5808.2 5850.2 5929.4 5715.8 5741.7 5755.8 5759.4 5764.4 5742.3 5748.0 5749.9 5792.6 5796.2 5683.9 5790.5 5821.2 5824.9 5827.7 5966.1 5969.1 5970.9 5971.9 5980.4 5634.8 5649.0 5663.7 5776.4 5812.2 18:45:53 INFO - PROCESS | 26749 | 18:45:53 INFO - PROCESS | 26749 | [#1] ext.html Cycles:180 Average:58721.20 Median:37746.84 stddev:113169.45 (299.8%) stddev-sans-first:113402.76 18:45:53 INFO - PROCESS | 26749 | Values: 592.0 616.9 644.2 663.9 688.8 34348.5 37037.0 37057.4 45845.5 46559.8 47368.3 47650.8 47719.0 47728.3 47795.3 8899.8 9063.9 9073.2 9073.9 9074.3 8589.7 8697.9 8770.3 8784.9 8882.2 10804.2 11051.7 11083.6 11331.3 11364.0 11041.4 11105.7 11108.8 11114.0 11125.6 10500.7 10549.4 10557.4 10571.3 10578.3 44442.8 44915.0 45020.8 45052.5 45136.8 3496.8 3650.8 3653.6 3754.7 4241.4 33024.0 33301.5 33304.7 33324.2 33631.5 4223.6 4262.5 4267.4 4278.3 4318.7 326799.0 330952.0 331699.3 332139.0 334162.0 62186.4 62194.4 62514.7 62523.0 62590.7 35922.1 36267.0 36508.5 37921.4 38000.2 17017.4 17165.8 17188.2 17215.1 17236.7 12891.9 13000.1 13040.5 13043.7 13045.5 18665.7 18987.4 20372.9 20989.7 21226.8 7368.8 7556.9 7580.7 7730.8 7791.1 936.3 1061.2 1067.5 1180.1 1185.3 32464.0 32469.7 32800.2 32910.5 32960.8 1100.5 1108.7 1109.7 1137.5 1189.6 17394.6 17473.8 17478.4 17543.2 17622.6 522828.0 524628.0 528064.0 528228.0 530025.0 63010.0 63382.7 63520.4 63536.4 63667.0 1181.5 1192.6 1229.9 1334.6 1340.1 31595.8 34502.5 35008.5 35079.0 35305.3 51355.7 52038.7 52093.5 52102.9 52173.5 399755.0 401669.0 402148.0 403084.0 403583.0 24577.1 24602.0 24607.5 27816.7 28433.3 50371.7 50387.0 50430.7 50651.2 50757.7 40542.6 41437.0 41948.0 42396.4 43402.0 41787.6 41963.0 42760.8 43238.8 43399.0 42525.8 43636.6 43734.8 43793.1 43803.1 36058.6 37572.2 38192.6 38543.0 38723.2 25437.5 25483.7 25727.6 26093.6 26213.9 18:45:53 INFO - PROCESS | 26749 | 18:45:53 INFO - PROCESS | 26749 | [#2] jquery.html Cycles:180 Average:30180.52 Median:3757.66 stddev:75943.89 (2021.0%) stddev-sans-first:76124.00 18:45:53 INFO - PROCESS | 26749 | Values: 394.4 395.9 426.5 429.8 430.2 13228.2 13291.7 18749.5 20276.2 20292.0 21627.1 22543.2 22913.8 22921.7 22995.5 6198.9 6550.5 6580.2 6604.8 6608.9 6572.9 6683.9 6686.4 7244.6 7512.9 7641.0 7648.6 7661.8 7665.7 7669.1 7281.6 7453.6 7492.4 7513.5 7517.5 7070.1 7279.9 7312.6 7348.6 7371.0 25559.7 26889.9 27192.6 27456.7 27719.0 2618.0 2625.3 2702.7 2752.2 2849.2 16382.7 16447.9 16485.7 16595.7 16737.0 2108.0 2126.8 2129.1 2171.3 2276.5 72756.6 87565.6 90551.0 91021.0 99090.0 14778.1 18277.9 18368.4 18476.3 18589.3 33623.8 33691.5 33841.5 33877.0 33892.2 9855.2 9886.7 9918.3 10026.3 10089.5 7999.2 8157.2 8318.3 8334.8 8555.6 9408.3 9415.9 9570.0 9585.8 9597.3 3391.4 3496.0 3511.3 3732.9 3782.5 921.3 950.0 982.3 1038.1 1080.1 19783.8 20083.3 20149.4 20164.7 20198.8 984.8 990.6 1000.7 1013.3 1023.0 11018.8 11463.9 11470.5 11566.5 11680.8 448262.0 455553.0 462831.0 465383.0 466059.0 90557.5 93022.5 93271.0 93434.5 94652.5 981.7 987.7 1000.6 1003.7 1005.2 17694.5 18110.8 18126.2 18276.4 18354.2 42838.1 42882.4 44667.6 44790.8 44851.3 61493.0 62362.0 62417.7 63476.7 63729.4 8866.1 9279.0 9330.9 9410.7 9458.8 13797.4 13906.7 13983.2 14254.6 14498.9 12061.6 12222.8 12274.5 12365.1 12570.9 6823.5 8115.8 11925.3 12164.6 12321.9 11869.7 12098.5 12120.2 12538.9 12816.9 8910.6 9170.9 9211.6 9262.1 9399.2 9739.6 12231.5 12242.3 12305.6 12357.3 18:45:53 INFO - PROCESS | 26749 | 18:45:53 INFO - PROCESS | 26749 | [#3] mootools.html Cycles:180 Average:5908.59 Median:4723.90 stddev:3385.84 (71.7%) stddev-sans-first:3378.22 18:45:53 INFO - PROCESS | 26749 | Values: 1377.7 1522.4 1544.6 1690.6 1829.1 8041.3 8659.6 9051.4 9076.5 9211.5 9041.8 9122.6 9127.1 9130.6 9134.8 2427.1 2448.7 2449.0 2453.0 2458.7 2405.4 2440.6 2440.7 2446.6 2447.0 2459.8 2465.2 2498.8 2500.9 2501.2 2310.5 2346.2 2353.0 2405.3 2407.1 2430.6 2442.3 2451.8 2452.5 2453.2 8970.8 9039.0 9096.0 9149.7 9192.0 5448.1 5646.2 5662.4 5663.9 5667.0 3308.1 3400.2 3437.2 3440.0 3443.1 1072.3 1084.1 1092.7 1103.0 1105.4 12544.4 12569.9 12660.3 12666.2 12668.9 4685.5 4762.3 4762.5 4794.0 4890.9 10107.6 10117.9 10119.1 10121.5 10130.0 5467.9 5470.2 5476.3 5477.4 5485.8 4364.1 4562.7 4627.1 4966.0 5056.0 3983.6 4067.8 4136.6 4158.9 4164.5 1229.0 1257.7 1274.4 1283.5 1289.6 868.8 925.3 935.3 936.2 958.5 5682.3 5774.7 5944.3 5998.5 5998.5 449.2 452.0 467.8 481.1 485.2 4070.7 4144.8 4162.8 4170.5 4176.3 11088.8 11442.3 11456.9 11501.1 11505.8 11301.5 11344.9 11361.8 11362.3 11363.4 8202.8 8275.5 8379.2 8389.5 8445.0 6027.9 6161.8 6202.3 6303.3 6305.1 10009.2 10065.0 10183.6 10328.6 10605.9 8819.4 10400.3 10496.6 10583.3 10740.5 7852.7 7864.9 8143.8 8144.4 8156.3 8761.3 8923.1 8979.1 8979.9 8981.6 7394.0 7467.3 7500.2 7533.1 7565.2 7326.8 7459.5 7496.1 7499.1 7543.2 7111.8 7235.6 7236.5 7243.6 7247.7 4961.4 5157.8 5255.9 5259.1 5414.5 5134.5 5212.5 5241.4 5321.3 5327.0 18:45:53 INFO - PROCESS | 26749 | 18:45:53 INFO - PROCESS | 26749 | [#4] prototype.html Cycles:180 Average:4727.99 Median:5355.73 stddev:1390.74 (26.0%) stddev-sans-first:1354.72 18:45:53 INFO - PROCESS | 26749 | Values: 320.0 352.7 355.4 356.0 356.2 5331.8 5342.5 5377.8 5410.0 5419.7 5295.2 5353.0 5353.6 5357.8 5359.9 4018.3 4213.2 4239.9 4252.3 4256.0 3913.4 3934.1 4085.0 4142.6 4148.4 4132.3 4134.5 4137.1 4139.3 4140.7 4141.9 4150.3 4167.5 4176.4 4201.7 3630.2 3705.9 3707.3 3740.1 3745.3 5181.6 5194.6 5197.6 5207.4 5209.1 4815.5 4836.4 4844.0 4859.0 4865.6 5380.6 5552.9 5614.1 5614.8 5654.2 4022.5 4026.8 4036.5 4037.4 4039.5 5579.9 5719.7 5786.5 5812.2 5865.2 3907.9 4035.5 4144.7 4204.4 4219.3 4239.3 4239.4 4254.3 4263.8 4266.2 4614.9 4766.7 4772.8 4802.3 4814.2 5581.6 5615.7 5639.7 5655.8 5659.9 1395.3 1398.1 1405.1 1413.4 1423.3 953.2 980.6 985.8 998.1 1018.3 5246.0 5308.6 5310.1 5311.3 5320.3 5544.8 5545.8 5554.6 5556.4 5563.8 5097.4 5212.6 5281.4 5286.0 5323.5 2908.5 2942.8 2958.5 2958.8 2969.5 5804.4 5832.0 5836.2 5846.9 5871.0 5586.3 5868.1 5887.9 5923.2 5924.3 6036.2 6077.8 6088.5 6114.2 6154.5 5586.4 5781.7 5796.6 5800.5 5866.3 5744.6 5758.5 5763.5 5775.9 5785.9 5838.5 5892.5 5964.9 6036.2 6115.0 4196.3 4198.1 4210.1 4249.8 4260.5 5726.6 5878.3 5880.7 5883.0 5896.6 5587.7 5588.4 5645.5 5650.8 5669.4 5569.5 5571.6 5608.3 5611.7 5663.0 5403.3 5540.2 5548.7 5617.2 5631.8 5625.5 5656.7 5666.9 5672.8 5681.7 5508.7 5518.2 5538.7 5550.8 5552.4 18:45:53 INFO - PROCESS | 26749 | 18:45:53 INFO - PROCESS | 26749 | [#5] yui.html Cycles:180 Average:17742.32 Median:25317.95 stddev:23232.51 (91.8%) stddev-sans-first:23262.57 18:45:53 INFO - PROCESS | 26749 | Values: 732.3 746.9 781.1 819.2 821.7 14017.4 14973.9 14995.9 15002.8 15024.5 13500.1 13519.4 13569.5 13606.4 13656.9 728.1 745.4 844.1 859.4 867.2 861.1 875.1 875.3 876.0 876.1 904.8 905.4 906.1 906.3 907.0 913.0 913.1 913.5 913.6 914.0 656.5 660.8 661.7 661.9 665.8 13723.7 14049.7 14049.7 14131.6 14541.3 14352.6 14678.2 14987.1 15125.6 15149.0 11759.5 12094.8 12138.9 12345.3 12462.9 3764.7 3802.0 3827.5 3840.7 3891.3 61803.0 63564.0 63881.7 64564.0 64859.0 11049.2 11159.7 11339.1 11413.4 11544.7 20250.4 20519.8 20534.2 20596.0 20735.3 8249.4 8268.4 8329.6 8488.4 8666.5 4561.5 4606.1 4701.8 4727.9 4741.8 3273.6 3341.5 3376.1 3376.1 3384.2 565.7 571.0 575.6 584.1 587.6 538.4 578.8 601.8 617.6 640.5 8467.0 8521.5 8630.8 8731.8 8763.8 567.8 571.9 599.4 603.6 611.6 3947.2 3968.0 4749.9 4894.9 5092.8 53492.0 85297.6 88718.6 88986.0 90852.0 76808.0 77471.0 78681.6 79999.6 80172.0 68872.0 69272.0 69286.7 70195.0 70212.9 9691.3 10082.6 10127.5 10221.2 10237.4 41609.2 41821.8 42784.0 46029.8 49309.5 58967.0 60595.0 62405.0 62516.0 63861.0 12527.2 12645.2 12703.4 13078.6 13197.0 13831.4 13844.2 14048.3 14110.7 14121.8 12264.1 12274.4 12368.0 12501.2 12642.6 12738.6 12934.9 13256.9 13317.7 14576.2 13528.5 13582.9 13709.1 13803.3 14054.2 10410.2 10850.3 10946.6 11105.8 11119.8 10451.3 10596.3 10599.8 10728.7 10788.8 18:45:53 INFO - PROCESS | 26749 | -------- Summary: end -------- 18:45:53 INFO - PROCESS | 26749 | 18:45:54 INFO - TEST-INFO | 26749: exit 0 18:45:54 INFO - TEST-OK | dromaeo_css | took 1114460ms 18:45:54 INFO - TEST-START | kraken 18:45:54 INFO - operating with platform_type : linux_ 18:45:54 INFO - Initialising browser for kraken test... 18:45:54 INFO - TEST-INFO | started process 27051 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMjo8TU/profile http://localhost:44529/getInfo.html) 18:45:58 INFO - PROCESS | 27051 | __metrics Screen width/height:1600/1200 18:45:58 INFO - PROCESS | 27051 | colorDepth:24 18:45:58 INFO - PROCESS | 27051 | Browser inner width/height: 1024/697 18:45:58 INFO - PROCESS | 27051 | __metrics 18:45:58 INFO - PROCESS | 27051 | 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:45:58 INFO - PROCESS | 27051 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:45:59 INFO - PROCESS | 27051 | console.error: PushService: 18:45:59 INFO - PROCESS | 27051 | stateChangeProcessEnqueue: Error transitioning state 18:45:59 INFO - PROCESS | 27051 | UnknownError 18:46:00 INFO - TEST-INFO | 27051: exit 0 18:46:00 INFO - Browser initialized. 18:46:00 INFO - Running cycle 1/1 for kraken test... 18:46:00 INFO - TEST-INFO | started process 27143 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMjo8TU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:46:11 INFO - PROCESS | 27143 | RSS: Main: 141484032 18:46:11 INFO - PROCESS | 27143 | 18:46:14 INFO - PROCESS | 27143 | Cycle 1(1): loaded http://localhost:44529/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44529/tests/kraken/driver.html?testName=audio-beat-detection) 18:46:15 INFO - PROCESS | 27143 | RSS: Main: 183214080 18:46:15 INFO - PROCESS | 27143 | 18:46:17 INFO - PROCESS | 27143 | Cycle 1(1): loaded http://localhost:44529/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44529/tests/kraken/driver.html?testName=audio-dft) 18:46:18 INFO - PROCESS | 27143 | RSS: Main: 173142016 18:46:18 INFO - PROCESS | 27143 | 18:46:21 INFO - PROCESS | 27143 | Cycle 1(1): loaded http://localhost:44529/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44529/tests/kraken/driver.html?testName=audio-fft) 18:46:21 INFO - PROCESS | 27143 | RSS: Main: 264396800 18:46:21 INFO - PROCESS | 27143 | 18:46:23 INFO - PROCESS | 27143 | Cycle 1(1): loaded http://localhost:44529/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44529/tests/kraken/driver.html?testName=audio-oscillator) 18:46:24 INFO - PROCESS | 27143 | RSS: Main: 170762240 18:46:24 INFO - PROCESS | 27143 | 18:46:26 INFO - PROCESS | 27143 | Cycle 1(1): loaded http://localhost:44529/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44529/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:46:26 INFO - PROCESS | 27143 | RSS: Main: 173092864 18:46:26 INFO - PROCESS | 27143 | 18:46:29 INFO - PROCESS | 27143 | Cycle 1(1): loaded http://localhost:44529/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44529/tests/kraken/driver.html?testName=imaging-darkroom) 18:46:30 INFO - PROCESS | 27143 | RSS: Main: 208515072 18:46:30 INFO - PROCESS | 27143 | 18:46:33 INFO - PROCESS | 27143 | Cycle 1(1): loaded http://localhost:44529/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44529/tests/kraken/driver.html?testName=imaging-desaturate) 18:46:34 INFO - PROCESS | 27143 | RSS: Main: 210972672 18:46:34 INFO - PROCESS | 27143 | 18:46:36 INFO - PROCESS | 27143 | Cycle 1(1): loaded http://localhost:44529/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44529/tests/kraken/driver.html?testName=json-parse-financial) 18:46:37 INFO - PROCESS | 27143 | RSS: Main: 209850368 18:46:37 INFO - PROCESS | 27143 | 18:46:39 INFO - PROCESS | 27143 | Cycle 1(1): loaded http://localhost:44529/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44529/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:46:39 INFO - PROCESS | 27143 | RSS: Main: 172195840 18:46:39 INFO - PROCESS | 27143 | 18:46:41 INFO - PROCESS | 27143 | Cycle 1(1): loaded http://localhost:44529/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44529/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:46:42 INFO - PROCESS | 27143 | RSS: Main: 178061312 18:46:42 INFO - PROCESS | 27143 | 18:46:43 INFO - PROCESS | 27143 | Cycle 1(1): loaded http://localhost:44529/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44529/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:46:44 INFO - PROCESS | 27143 | RSS: Main: 187437056 18:46:44 INFO - PROCESS | 27143 | 18:46:47 INFO - PROCESS | 27143 | Cycle 1(1): loaded http://localhost:44529/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44529/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:46:47 INFO - PROCESS | 27143 | RSS: Main: 196587520 18:46:47 INFO - PROCESS | 27143 | 18:46:50 INFO - PROCESS | 27143 | Cycle 1(1): loaded http://localhost:44529/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44529/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:46:51 INFO - PROCESS | 27143 | RSS: Main: 192229376 18:46:51 INFO - PROCESS | 27143 | 18:46:52 INFO - PROCESS | 27143 | Cycle 1(1): loaded http://localhost:44529/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44529/tests/kraken/driver.html?testName=ai-astar) 18:46:52 INFO - PROCESS | 27143 | RSS: Main: 244404224 18:46:52 INFO - PROCESS | 27143 | 18:46:52 INFO - PROCESS | 27143 | __start_tp_report 18:46:52 INFO - PROCESS | 27143 | _x_x_mozilla_page_load 18:46:52 INFO - PROCESS | 27143 | _x_x_mozilla_page_load_details 18:46:52 INFO - PROCESS | 27143 | |i|pagename|runs| 18:46:52 INFO - PROCESS | 27143 | |0;ai-astar;101;109;108;108;108;108;109;108;108;108 18:46:52 INFO - PROCESS | 27143 | |1;audio-beat-detection;159;122;118;160;124;123;119;121;154;118 18:46:52 INFO - PROCESS | 27143 | |2;audio-dft;159;161;161;160;160;161;161;159;162;165 18:46:52 INFO - PROCESS | 27143 | |3;audio-fft;74;74;75;118;76;76;75;75;98;74 18:46:52 INFO - PROCESS | 27143 | |4;audio-oscillator;81;80;118;81;82;81;98;81;81;113 18:46:52 INFO - PROCESS | 27143 | |5;imaging-gaussian-blur;117;116;116;116;116;116;117;116;116;116 18:46:52 INFO - PROCESS | 27143 | |6;imaging-darkroom;147;146;146;146;146;147;146;186;146;146 18:46:52 INFO - PROCESS | 27143 | |7;imaging-desaturate;84;85;85;85;85;84;84;85;85;85 18:46:52 INFO - PROCESS | 27143 | |8;json-parse-financial;53;50;53;51;63;51;51;50;51;50 18:46:52 INFO - PROCESS | 27143 | |9;json-stringify-tinderbox;60;61;59;60;60;64;63;60;60;60 18:46:52 INFO - PROCESS | 27143 | |10;stanford-crypto-aes;84;75;83;74;75;75;76;75;75;80 18:46:52 INFO - PROCESS | 27143 | |11;stanford-crypto-ccm;134;122;122;128;124;121;120;128;161;127 18:46:52 INFO - PROCESS | 27143 | |12;stanford-crypto-pbkdf2;159;152;152;152;157;157;155;158;153;157 18:46:52 INFO - PROCESS | 27143 | |13;stanford-crypto-sha256-iterative;62;64;63;63;62;63;62;62;60;63 18:46:52 INFO - PROCESS | 27143 | __end_tp_report 18:46:52 INFO - PROCESS | 27143 | __start_cc_report 18:46:52 INFO - PROCESS | 27143 | _x_x_mozilla_cycle_collect,1520 18:46:52 INFO - PROCESS | 27143 | __end_cc_report 18:46:52 INFO - PROCESS | 27143 | __startTimestamp1485312412961__endTimestamp 18:46:52 INFO - PROCESS | 27143 | 18:46:52 INFO - PROCESS | 27143 | ------- Summary: start ------- 18:46:52 INFO - PROCESS | 27143 | Number of tests: 14 18:46:52 INFO - PROCESS | 27143 | 18:46:52 INFO - PROCESS | 27143 | [#0] ai-astar Cycles:10 Average:107.50 Median:108.00 stddev:2.32 (2.1%) stddev-sans-first:0.44 18:46:52 INFO - PROCESS | 27143 | Values: 101.0 109.0 108.0 108.0 108.0 108.0 109.0 108.0 108.0 108.0 18:46:52 INFO - PROCESS | 27143 | 18:46:52 INFO - PROCESS | 27143 | [#1] audio-beat-detection Cycles:10 Average:131.80 Median:123.50 stddev:18.02 (14.6%) stddev-sans-first:16.21 18:46:52 INFO - PROCESS | 27143 | Values: 159.0 122.0 118.0 160.0 124.0 123.0 119.0 121.0 154.0 118.0 18:46:52 INFO - PROCESS | 27143 | 18:46:52 INFO - PROCESS | 27143 | [#2] audio-dft Cycles:10 Average:160.90 Median:161.00 stddev:1.73 (1.1%) stddev-sans-first:1.69 18:46:52 INFO - PROCESS | 27143 | Values: 159.0 161.0 161.0 160.0 160.0 161.0 161.0 159.0 162.0 165.0 18:46:52 INFO - PROCESS | 27143 | 18:46:52 INFO - PROCESS | 27143 | [#3] audio-fft Cycles:10 Average:81.50 Median:75.00 stddev:14.76 (19.7%) stddev-sans-first:15.40 18:46:52 INFO - PROCESS | 27143 | Values: 74.0 74.0 75.0 118.0 76.0 76.0 75.0 75.0 98.0 74.0 18:46:52 INFO - PROCESS | 27143 | 18:46:52 INFO - PROCESS | 27143 | [#4] audio-oscillator Cycles:10 Average:89.60 Median:81.00 stddev:14.70 (18.1%) stddev-sans-first:15.26 18:46:52 INFO - PROCESS | 27143 | Values: 81.0 80.0 118.0 81.0 82.0 81.0 98.0 81.0 81.0 113.0 18:46:52 INFO - PROCESS | 27143 | 18:46:52 INFO - PROCESS | 27143 | [#5] imaging-gaussian-blur Cycles:10 Average:116.20 Median:116.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 18:46:52 INFO - PROCESS | 27143 | Values: 117.0 116.0 116.0 116.0 116.0 116.0 117.0 116.0 116.0 116.0 18:46:52 INFO - PROCESS | 27143 | 18:46:52 INFO - PROCESS | 27143 | [#6] imaging-darkroom Cycles:10 Average:150.20 Median:146.00 stddev:12.59 (8.6%) stddev-sans-first:13.30 18:46:52 INFO - PROCESS | 27143 | Values: 147.0 146.0 146.0 146.0 146.0 147.0 146.0 186.0 146.0 146.0 18:46:52 INFO - PROCESS | 27143 | 18:46:52 INFO - PROCESS | 27143 | [#7] imaging-desaturate Cycles:10 Average:84.70 Median:85.00 stddev:0.48 (0.6%) stddev-sans-first:0.44 18:46:52 INFO - PROCESS | 27143 | Values: 84.0 85.0 85.0 85.0 85.0 84.0 84.0 85.0 85.0 85.0 18:46:52 INFO - PROCESS | 27143 | 18:46:52 INFO - PROCESS | 27143 | [#8] json-parse-financial Cycles:10 Average:52.30 Median:51.00 stddev:3.92 (7.7%) stddev-sans-first:4.15 18:46:52 INFO - PROCESS | 27143 | Values: 53.0 50.0 53.0 51.0 63.0 51.0 51.0 50.0 51.0 50.0 18:46:52 INFO - PROCESS | 27143 | 18:46:52 INFO - PROCESS | 27143 | [#9] json-stringify-tinderbox Cycles:10 Average:60.70 Median:60.00 stddev:1.57 (2.6%) stddev-sans-first:1.64 18:46:52 INFO - PROCESS | 27143 | Values: 60.0 61.0 59.0 60.0 60.0 64.0 63.0 60.0 60.0 60.0 18:46:52 INFO - PROCESS | 27143 | 18:46:52 INFO - PROCESS | 27143 | [#10] stanford-crypto-aes Cycles:10 Average:77.20 Median:75.50 stddev:3.71 (4.9%) stddev-sans-first:3.00 18:46:52 INFO - PROCESS | 27143 | Values: 84.0 75.0 83.0 74.0 75.0 75.0 76.0 75.0 75.0 80.0 18:46:52 INFO - PROCESS | 27143 | 18:46:52 INFO - PROCESS | 27143 | [#11] stanford-crypto-ccm Cycles:10 Average:128.70 Median:127.50 stddev:12.12 (9.5%) stddev-sans-first:12.70 18:46:52 INFO - PROCESS | 27143 | Values: 134.0 122.0 122.0 128.0 124.0 121.0 120.0 128.0 161.0 127.0 18:46:52 INFO - PROCESS | 27143 | 18:46:52 INFO - PROCESS | 27143 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:155.20 Median:157.00 stddev:2.74 (1.7%) stddev-sans-first:2.54 18:46:52 INFO - PROCESS | 27143 | Values: 159.0 152.0 152.0 152.0 157.0 157.0 155.0 158.0 153.0 157.0 18:46:52 INFO - PROCESS | 27143 | 18:46:52 INFO - PROCESS | 27143 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.40 Median:63.00 stddev:1.07 (1.7%) stddev-sans-first:1.13 18:46:52 INFO - PROCESS | 27143 | Values: 62.0 64.0 63.0 63.0 62.0 63.0 62.0 62.0 60.0 63.0 18:46:52 INFO - PROCESS | 27143 | -------- Summary: end -------- 18:46:52 INFO - PROCESS | 27143 | 18:46:53 INFO - TEST-INFO | 27143: exit 0 18:46:53 INFO - TEST-OK | kraken | took 59408ms 18:46:53 INFO - SUITE-END | took 1173s 18:46:54 INFO - Completed test suite (00:19:34) 18:46:54 INFO - javascript benchmark 18:46:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3214.6946040126186, 3258.5763850699414, 3258.837058147092, 3265.869365225391, 3292.407366673999, 5475.425080366559, 5592.6085174037835, 5598.860028499284, 5599.0, 5607.887842243153, 5567.415421380758, 5615.045442274813, 5616.101423772197, 5622.437756224375, 5642.633228840123, 5035.3705786776745, 5053.77258023388, 5088.0587091388215, 5131.666441681297, 5167.664101833388, 5040.143175660147, 5052.444231134572, 5093.567046801008, 5146.74266286686, 5150.3047088642925, 4968.925466118011, 4978.775955082012, 5014.498550144993, 5028.723420211887, 5051.747412629372, 5072.797088116471, 5084.313617661605, 5148.871278218037, 5158.7420628968775, 5188.11801993662, 4848.878778030542, 5036.269740887551, 5057.5195356441445, 5114.769835357418, 5136.717480538568, 5377.059014672417, 5378.1663842104535, 5410.567154627621, 5472.7263636818, 5554.250176226238, 5848.298204215522, 5850.912236316459, 5947.226860508106, 5947.286325640907, 5954.821355359346, 5947.405259474018, 5982.491488223506, 5995.160677505153, 6003.0, 6003.159557661931, 5565.30433695788, 5599.860003499904, 5609.354924183689, 5617.269754931915, 5629.437056294338, 6132.17215675878, 6136.079588061826, 6149.0161574148815, 6155.876882462326, 6171.6297022178815, 5666.178404131378, 5887.234659494324, 5891.7054147292465, 5901.7639294427745, 5906.852328691731, 5894.145348924468, 5926.140709597084, 5999.190109335271, 6003.189569408161, 6015.488683461867, 5934.673592952429, 5945.0, 5955.553333500005, 5956.970215148896, 5958.046712526062, 5906.143609176645, 5913.822585322448, 5915.852103697442, 5940.138679891392, 5963.850903727442, 4551.590356867898, 4580.129775342674, 4582.266837306015, 4649.418822647169, 4657.417822772153, 4171.812268447927, 4191.706580539394, 4202.579742025834, 4214.452121224283, 4218.704690671623, 5718.027935250934, 5725.713714314267, 5728.0, 5728.255326807572, 5728.427157284322, 5816.82549523515, 5870.941290587125, 5904.911426328608, 5925.170476133345, 6012.248468941382, 5710.600257981984, 5723.971380143073, 5724.685142317213, 5733.3406658234, 5752.223449834219, 5187.610929180251, 5188.636795424284, 5191.0, 5194.22086687, 5249.868753281122, 6111.19673459643, 6116.274903966807, 6120.14317995481, 6237.688115594293, 6291.591046581958, 5882.647041177543, 5992.760289588367, 6015.849603759941, 6021.488173505126, 6026.367231440636, 6218.129461875432, 6247.156633854372, 6252.218472690914, 6253.468455181361, 6260.780872669435, 5545.916811247916, 5825.009748342876, 5827.038538641077, 5828.5919985602295, 5867.911981320198, 5740.885182296247, 5806.0, 5808.186853840551, 5850.23946886902, 5929.377415371324, 5715.799947001835, 5741.712914354182, 5755.769769209185, 5759.424057594207, 5764.423557644202, 5742.339630942495, 5748.022836117786, 5749.942500574941, 5792.594518383841, 5796.188533605215, 5683.914741278967, 5790.536757059341, 5821.243238378984, 5824.94175058244, 5827.737751801264, 5966.075258334965, 5969.074793407029, 5970.910436343545, 5971.850703732442, 5980.401959803985, 5634.8027819027775, 5649.0, 5663.688497132697, 5776.393478684678, 5812.18629391894], "value": 5504.286438773811, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [591.9704014799258, 616.941390567896, 644.1561554363784, 663.9103720997664, 688.8105770913002, 34348.48477272843, 37037.0, 37057.44413833788, 45845.54154458454, 46559.767201163944, 47368.28947565789, 47650.76174619122, 47719.0, 47728.28407573889, 47795.28307075397, 8899.777505562364, 9063.909360906373, 9073.183413492785, 9073.863892041625, 9074.27405807537, 8589.699360522383, 8697.869531957025, 8770.254528365065, 8784.868226976601, 8882.156195161482, 10804.243702940797, 11051.668449946514, 11083.612073577426, 11331.263467874604, 11364.0, 11041.447927603627, 11105.72235694106, 11108.833367499494, 11114.0, 11125.554977800879, 10500.684979450629, 10549.367037977747, 10557.41934193619, 10571.260011799177, 10578.259521833475, 44442.77778611118, 44915.0, 45020.77489612564, 45052.54947450549, 45136.77431612821, 3496.772709773882, 3650.817459127033, 3653.5798383186007, 3754.6620804127488, 4241.448611680493, 33024.0, 33301.50047749286, 33304.6669533304, 33324.166895827555, 33631.49552756686, 4223.556526564728, 4262.46719160105, 4267.445232119804, 4278.272693642089, 4318.676099292566, 326799.0, 330952.0, 331699.34150329337, 332139.0, 334162.0, 62186.37813621806, 62194.378056219764, 62514.68742656348, 62523.0, 62590.687046565385, 35922.10194745152, 36267.0, 36508.45237321442, 37921.431178532344, 38000.23999519994, 17017.404390846263, 17165.82834171667, 17188.22652980619, 17215.053172075404, 17236.741448878278, 12891.871081289255, 13000.08999370035, 13040.54358097462, 13043.673908152372, 13045.47818087285, 18665.720014199796, 18987.43037708871, 20372.89813550923, 20989.68515472269, 21226.78773212279, 7368.815779605446, 7556.886646700304, 7580.734674286374, 7730.7680769577, 7791.10402303731, 936.321167153808, 1061.230607809332, 1067.4982758103679, 1180.055955235808, 1185.3065956415421, 32464.0, 32469.67530324714, 32800.1799955003, 32910.506342404886, 32960.7970329144, 1100.5047728522197, 1108.7449886525983, 1109.7003808971695, 1137.5449820071872, 1189.5539172810197, 17394.565135871704, 17473.825261747475, 17478.388256410966, 17543.21055552503, 17622.647547048986, 522828.0, 524628.0, 528064.0, 528228.0, 530025.0, 63010.0, 63382.683086585195, 63520.36479635237, 63536.36463635397, 63667.04499432512, 1181.4919584578713, 1192.6481687902262, 1229.8893099621077, 1334.619633404475, 1340.0887396570427, 31595.84202078975, 34502.48246276258, 35008.47487287642, 35079.0, 35305.293894122486, 51355.74322128366, 52038.73980630073, 52093.47906521038, 52102.91588336424, 52173.47826521838, 399755.0, 401669.0, 402148.0, 403084.0, 403583.0, 24577.13980010691, 24602.0, 24607.50784984326, 27816.72183278141, 28433.28916777097, 50371.74814125906, 50387.0, 50430.747846262, 50651.24023139729, 50757.74621127018, 40542.59457405388, 41437.0, 41948.0, 42396.36405453982, 43402.0, 41787.62604257438, 41963.0, 42760.78619606882, 43238.78380608077, 43399.0, 42525.78737106295, 43636.56363436325, 43734.781326093165, 43793.12413751643, 43803.12393752171, 36058.63941360553, 37572.24855502929, 38192.61807381891, 38543.0, 38723.2255354897, 25437.491250175262, 25483.745162548137, 25727.614085789104, 26093.608595871458, 26213.868930655226], "value": 20498.10746343854, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [394.439895348605, 395.9128991621844, 426.48395441515765, 429.8474041715191, 430.2298884995857, 13228.206307621549, 13291.734165316688, 18749.531261718465, 20276.18895244192, 20292.0, 21627.134914603437, 22543.210987615435, 22913.77086229137, 22921.656175157386, 22995.540089198206, 6198.938010619881, 6550.541462097655, 6580.210374755036, 6604.834879128012, 6608.900866487006, 6572.901406478906, 6683.899741503857, 6686.364795344434, 7244.601546914944, 7512.924870751277, 7640.961795191016, 7648.617569121549, 7661.846763064763, 7665.655045522937, 7669.118051424102, 7281.635918204095, 7453.627318634074, 7492.400607951406, 7513.474056816025, 7517.4737768356235, 7070.116235470567, 7279.927200727978, 7312.597807120606, 7348.5958272295, 7370.963145184293, 25559.74440255611, 26889.865550672315, 27192.59211111835, 27456.725432745818, 27719.0, 2617.9869100654373, 2625.2518032360877, 2702.6756789185424, 2752.1881045091745, 2849.244950088228, 16382.672346553003, 16447.91776041124, 16485.68114550833, 16595.6680866382, 16737.0, 2107.95784084319, 2126.8404869634683, 2129.1270579062666, 2171.323047596352, 2276.46503071778, 72756.63621681911, 87565.56217218937, 90551.0, 91021.0, 99090.0, 14778.078849749463, 18277.90861045713, 18368.448946531626, 18476.260949561867, 18589.34937277189, 33623.83188084044, 33691.49462758061, 33841.492377614355, 33877.0, 33892.15269618279, 9855.211583073336, 9886.65396711126, 9918.30571859963, 10026.348287361445, 10089.495525223856, 7999.240072193133, 8157.221566312405, 8318.251357377743, 8334.791630209293, 8555.614997325136, 9408.306504829197, 9415.90584094164, 9570.0, 9585.760355991017, 9597.264779537798, 3391.4389890614757, 3495.965040349615, 3511.2801875615437, 3732.869349572752, 3782.451544526083, 921.2998121427763, 949.9905000949902, 982.2583949118513, 1038.1279725030859, 1080.065743132185, 19783.802161978485, 20083.29708460226, 20149.39551813448, 20164.69752953707, 20198.79801201969, 984.778424854402, 990.5938565188238, 1000.7448100734266, 1013.2704452793977, 1023.0383439566932, 11018.834717479243, 11463.885361146282, 11470.541178352772, 11566.47950842214, 11680.824787628193, 448262.0, 455553.0, 462831.0, 465383.0, 466059.0, 90557.54721226484, 93022.53488732514, 93271.0, 93434.53282733542, 94652.52673736587, 981.6809536900536, 987.6592575561419, 1000.5697550053401, 1003.7239759066315, 1005.2410430125209, 17694.469165925042, 18110.81889181091, 18126.184321705292, 18276.360327388476, 18354.174062166967, 42838.14323713571, 42882.35676464793, 44667.55332446634, 44790.77604612087, 44851.327230092225, 61493.0, 62362.0, 62417.687911560155, 63476.68261658847, 63729.362706374195, 8866.069062748576, 9278.95360523193, 9330.906690933003, 9410.729551510458, 9458.810823783624, 13797.448102075805, 13906.652333691738, 13983.18622948204, 14254.643633909236, 14498.85501144975, 12061.577844775391, 12222.816657749963, 12274.509019639114, 12365.134440589072, 12570.87429125697, 6823.454123669995, 8115.75652730419, 11925.253625179364, 12164.635060948185, 12321.876781232073, 11869.703257418289, 12098.455569499569, 12120.151589388659, 12538.937305313415, 12816.871831281567, 8910.55447227623, 9170.908290917272, 9211.585478653611, 9262.120098590623, 9399.248060155307, 9739.561719722487, 12231.510739570318, 12242.265464072185, 12305.630831075083, 12357.25856448616], "value": 10727.978120601672, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1377.7037936843576, 1522.406261557992, 1544.629288970647, 1690.6027083635342, 1829.1311626977179, 8041.276285134344, 8659.567021648923, 9051.366404351682, 9076.50079245642, 9211.539423028855, 9041.819163616723, 9122.589483473243, 9127.13292237238, 9130.634774609025, 9134.77163070922, 2427.0777104700232, 2448.7306396296394, 2449.020391843268, 2452.9877350613224, 2458.7418321076298, 2405.405005255967, 2440.5728997425467, 2440.719317278509, 2446.6330050492393, 2446.9510609787794, 2459.764497000105, 2465.2111324376206, 2498.837575557592, 2500.8874600642926, 2501.2246203676827, 2310.4801419680607, 2346.1905642553293, 2352.96470552941, 2405.2663937499055, 2407.121400688745, 2430.586800243945, 2442.3283597010786, 2451.840630359021, 2452.4604586991013, 2453.1904471524354, 8970.82058358829, 9039.0, 9095.954520227422, 9149.725508234696, 9192.034836342222, 5448.128299472105, 5646.20953066571, 5662.37713851476, 5663.915041274384, 5667.0, 3308.073739352985, 3400.1669590950373, 3437.1750779812915, 3440.0367896988882, 3443.1220038889924, 1072.2922870905243, 1084.1164450972433, 1092.6612750047511, 1103.0348445110528, 1105.3865104866795, 12544.435500402504, 12569.937150314281, 12660.303683297416, 12666.240025598401, 12668.936655316664, 4685.531446855287, 4762.261849413347, 4762.517826018088, 4793.952060479421, 4890.902181956341, 10107.64623238183, 10117.898821011842, 10119.13987311072, 10121.544530495998, 10130.037646423578, 5467.917981230285, 5470.234167216593, 5476.288082549243, 5477.397486276506, 5485.753141108659, 4364.148990946798, 4562.726236425825, 4627.120847039052, 4965.975170124127, 5056.039352523009, 3983.601639836051, 4067.7559346439307, 4136.607022332873, 4158.854440094643, 4164.541900390955, 1229.0, 1257.6855786053486, 1274.407400558726, 1283.525095714573, 1289.5679947217786, 868.7524055644111, 925.319889880937, 935.2891802230354, 936.2463217110155, 958.5015791788231, 5682.289713785777, 5774.703950641805, 5944.316403613553, 5998.490128339053, 5998.520118390518, 449.1824878720697, 451.96610254231064, 467.81988934259874, 481.13636023338194, 485.2091091520869, 4070.7354021988476, 4144.8134833932545, 4162.83348666056, 4170.52039015511, 4176.31090870003, 11088.778224435628, 11442.256253343507, 11456.94271528637, 11501.13741469393, 11505.769884602429, 11301.49143288554, 11344.886551134548, 11361.772764544663, 11362.318260904372, 11363.431828408546, 8202.753917382488, 8275.50346979183, 8379.162083791573, 8389.45468544555, 8445.028821685462, 6027.879442411215, 6161.753529858755, 6202.34875338101, 6303.306636270098, 6305.054241863757, 10009.249306302203, 10065.0, 10183.592656293666, 10328.586856525655, 10605.89394106049, 8819.426737262058, 10400.323978941344, 10496.632617858339, 10583.31208471447, 10740.516676749408, 7852.685892564234, 7864.882026769717, 8143.75568732939, 8144.429889907887, 8156.306713929145, 8761.25529330014, 8923.107689231025, 8979.14698103679, 8979.910200897908, 8981.550922453982, 7393.963030184815, 7467.253274672489, 7500.174980752004, 7533.096028476618, 7565.167831538636, 7326.780196594111, 7459.515131516434, 7496.100467943882, 7499.100107986858, 7543.245675432632, 7111.751088711871, 7235.565866048054, 7236.457265704988, 7243.5653860766415, 7247.746328878464, 4961.3798275215595, 5157.793688252578, 5255.868603284948, 5259.053370393366, 5414.458554144711, 5134.486551344836, 5212.50481204285, 5241.423443421297, 5321.255024296679, 5327.0411325961695], "value": 4640.874261650904, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [319.9712025917668, 352.7424979764772, 355.3656722749891, 355.95016697662317, 356.1630169102609, 5331.840044798662, 5342.492463215993, 5377.763114483671, 5409.9729501352485, 5419.701916394597, 5295.226099256744, 5353.00969320675, 5353.625246232766, 5357.8392648220615, 5359.919601205985, 4018.327812005013, 4213.230443213857, 4239.9152016959715, 4252.255855225339, 4255.999840037589, 3913.3934240192816, 3934.1344904121047, 4084.9591504084874, 4142.550107462394, 4148.357004664282, 4132.2768515509815, 4134.4625198724125, 4137.069159439132, 4139.254934111858, 4140.668746500288, 4141.937870931938, 4150.315197992358, 4167.499900011988, 4176.433837311009, 4201.70588058836, 3630.2013557017412, 3705.907352316187, 3707.295613833363, 3740.102375429877, 3745.2696724138805, 5181.637285390024, 5194.63637545372, 5197.636165468419, 5207.427183009866, 5209.1404918188455, 4815.542523460265, 4836.443808961979, 4844.006978569385, 4859.032092002744, 4865.5864251538305, 5380.569554435636, 5552.944470555283, 5614.052622373287, 5614.803481878155, 5654.236678048452, 4022.5172979242384, 4026.838926442939, 4036.535798383165, 4037.354023356249, 4039.5152581690386, 5579.888402231933, 5719.65682059082, 5786.508146807527, 5812.157237200582, 5865.237519122529, 3907.8632247871187, 4035.535913369941, 4144.689148313889, 4204.411382406465, 4219.261629214937, 4239.321708526621, 4239.448871646666, 4254.255505286563, 4263.829446822155, 4266.168097221012, 4614.877156508044, 4766.713997160182, 4772.785224664898, 4802.303665968415, 4814.229723244264, 5581.609287349846, 5615.6630602164005, 5639.689817060101, 5655.773769049192, 5659.858503537363, 1395.323268214915, 1398.1331574423718, 1405.051590176647, 1413.3710498827922, 1423.323921137472, 953.208836665566, 980.6028558433845, 985.8422652375585, 998.0568362896993, 1018.3380802478448, 5246.032737723353, 5308.628396012242, 5310.070737620901, 5311.282976798083, 5320.308359913264, 5544.750486228129, 5545.833624991256, 5554.611177217556, 5556.388797232301, 5563.805266815642, 5097.413797413271, 5212.64471237481, 5281.419043905167, 5285.986987417135, 5323.467653234646, 2908.5200941844782, 2942.8234305941282, 2958.467475854367, 2958.8372639505033, 2969.539721343195, 5804.361520232771, 5832.0, 5836.182934389189, 5846.853828654318, 5871.0, 5586.329640443173, 5868.149118377897, 5887.941120588825, 5923.200367950358, 5924.341184468356, 6036.185115009505, 6077.756889724362, 6088.452039316483, 6114.205153330127, 6154.538409619386, 5586.441355864381, 5781.739821708117, 5796.565257605612, 5800.535957123504, 5866.325372582208, 5744.569157313134, 5758.4817366435545, 5763.4812866842185, 5775.9133612995, 5785.942140578709, 5838.532917366685, 5892.5285977122585, 5964.880702385841, 6036.215292012011, 6114.969425153024, 4196.265653510684, 4198.139381426861, 4210.073783767566, 4249.830006799817, 4260.510041345285, 5726.627769194989, 5878.29460464747, 5880.735366908414, 5883.0, 5896.616719913192, 5587.7485513152815, 5588.441155884379, 5645.548356131581, 5650.830475085754, 5669.42826019605, 5569.470900264469, 5571.582131340085, 5608.298962629671, 5611.747471363717, 5662.971685141712, 5403.324584426947, 5540.22436858832, 5548.69482178484, 5617.213590097308, 5631.802886898939, 5625.549956003591, 5656.745446454837, 5666.886662266814, 5672.801451949162, 5681.659100453986, 5508.724563771875, 5518.19986102021, 5538.695371754592, 5550.833474995757, 5552.444755524415], "value": 4289.033685865785, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [732.3189433826542, 746.9402447804174, 781.1056340490139, 819.2176471469747, 821.6877586517122, 14017.36921838517, 14973.850261497382, 14995.850041499581, 15002.849971500294, 15024.549263522098, 13500.12249203801, 13519.391627376766, 13569.45722171112, 13606.387712552934, 13656.931715341409, 728.0717085715728, 745.3738859358139, 844.1010324004915, 859.3769517100114, 867.154524338771, 861.1345597674328, 875.0942774228674, 875.264777586827, 876.027609353617, 876.1370050500284, 904.8280826642916, 905.42053086025, 906.0576999920075, 906.3429013964889, 906.9682561110362, 913.0, 913.0914739833842, 913.4975763330175, 913.6254135804288, 913.9817203655956, 656.4944992355908, 660.8050625065617, 661.6526323680058, 661.8841702702056, 665.8335416145964, 13723.725525489534, 14049.719005619932, 14049.719005619932, 14131.646708832259, 14541.34563944625, 14352.64118397038, 14678.192699401528, 14987.100773953598, 15125.621859453491, 15149.0, 11759.529618815237, 12094.758104837854, 12138.878611213862, 12345.259284442873, 12462.875371246353, 3764.6611804937415, 3802.049487628093, 3827.4832897558754, 3840.711946604016, 3891.2606604745292, 61803.0, 63564.0, 63881.68059159721, 64564.0, 64859.02711459332, 11049.17131215162, 11159.72100697489, 11339.14956378275, 11413.42932853354, 11544.711382215512, 20250.392488225378, 20519.794802051787, 20534.178632854517, 20596.0, 20735.27426540064, 8249.422540422232, 8268.421210515206, 8329.625166867505, 8488.40581159313, 8666.480011199348, 4561.475430325489, 4606.124836281096, 4701.811927522929, 4727.929081063787, 4741.786619602057, 3273.5908011498564, 3341.532185494033, 3376.1053320869987, 3376.139084533428, 3384.221629025338, 565.7425871228581, 571.0321005895051, 575.5999580291741, 584.1004852527145, 587.6004317064437, 538.4050624060435, 578.8176724331822, 601.7863658401204, 617.561531312773, 640.4812102197235, 8467.026291976379, 8521.488710677379, 8630.76086576976, 8731.825363492695, 8763.780905477413, 567.7643777832236, 571.928508936383, 599.3526990849872, 603.5654328883197, 611.6330201878837, 3947.1908258806875, 3967.980160099181, 4749.928751068736, 4894.902101958012, 5092.8472145835685, 53492.0, 85297.57351213328, 88718.55640721755, 88986.0, 90852.0, 76808.0, 77471.0, 78681.60659196667, 79999.60000199961, 80172.0, 68872.0, 69272.0, 69286.65356673184, 70195.0, 70212.94680579795, 9691.321607487409, 10082.581907871521, 10127.544260508148, 10221.182305415698, 10237.385756854612, 41609.167816644105, 41821.79089104535, 42784.0, 46029.76985115053, 49309.506904930495, 58967.0, 60595.0, 62405.0, 62516.0, 63861.0, 12527.18573292733, 12645.178063425848, 12703.36483175819, 13078.607641770763, 13197.010224233029, 13831.446742130202, 13844.238566878515, 14048.297585120907, 14110.717785644434, 14121.788173177205, 12264.141510094209, 12274.386280686109, 12368.0, 12501.24992500453, 12642.55751048709, 12738.617841464771, 12934.870651293742, 13256.867431325949, 13317.66705832362, 14576.198309093103, 13528.526501572398, 13582.86417135816, 13709.108907920952, 13803.309834508435, 14054.227017514135, 10410.219233557362, 10850.29473084247, 10946.562137514411, 11105.833412498981, 11119.777604448027, 10451.268411211142, 10596.258261921592, 10599.788004240027, 10728.678139655824, 10788.838167427339], "value": 7136.985165903913, "unit": "score"}], "name": "dromaeo_css", "value": 7457.19655359318, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 109.0, 108.0, 108.0, 108.0, 108.0, 109.0, 108.0, 108.0, 108.0], "value": 107.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 122.0, 118.0, 160.0, 124.0, 123.0, 119.0, 121.0, 154.0, 118.0], "value": 131.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 161.0, 161.0, 160.0, 160.0, 161.0, 161.0, 159.0, 162.0, 165.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 75.0, 118.0, 76.0, 76.0, 75.0, 75.0, 98.0, 74.0], "value": 81.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 80.0, 118.0, 81.0, 82.0, 81.0, 98.0, 81.0, 81.0, 113.0], "value": 89.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 116.0, 116.0, 116.0, 116.0, 117.0, 116.0, 116.0, 116.0], "value": 116.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 146.0, 146.0, 147.0, 146.0, 186.0, 146.0, 146.0], "value": 150.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 85.0, 85.0, 85.0, 84.0, 84.0, 85.0, 85.0, 85.0], "value": 84.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 50.0, 53.0, 51.0, 63.0, 51.0, 51.0, 50.0, 51.0, 50.0], "value": 52.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 59.0, 60.0, 60.0, 64.0, 63.0, 60.0, 60.0, 60.0], "value": 60.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 75.0, 83.0, 74.0, 75.0, 75.0, 76.0, 75.0, 75.0, 80.0], "value": 77.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 122.0, 122.0, 128.0, 124.0, 121.0, 120.0, 128.0, 161.0, 127.0], "value": 128.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 152.0, 152.0, 152.0, 157.0, 157.0, 155.0, 158.0, 153.0, 157.0], "value": 155.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 64.0, 63.0, 63.0, 62.0, 63.0, 62.0, 62.0, 60.0, 63.0], "value": 62.4, "unit": "score"}], "name": "kraken", "value": 1458.9000000000003, "alertThreshold": 2.0}]} 18:46:54 INFO - Return code: 0 18:46:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:46:54 INFO - # TBPL SUCCESS # 18:46:54 INFO - Running post-action listener: _resource_record_post_action 18:46:54 INFO - [mozharness: 2017-01-25 02:46:54.246398Z] Finished run-tests step (success) 18:46:54 INFO - Running post-run listener: _resource_record_post_run 18:46:54 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 0; Write bytes: 322162688; Read time: 0; Write time: 166412 18:46:54 INFO - TinderboxPrint: CPU usage
12.9% 18:46:54 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 18:46:54 INFO - TinderboxPrint: I/O write bytes / time
322,162,688 / 166,412 18:46:54 INFO - TinderboxPrint: CPU idle
8,256.2 (87.1%) 18:46:54 INFO - TinderboxPrint: CPU user
1,185.1 (12.5%) 18:46:54 INFO - TinderboxPrint: Swap in / out
0 / 0 18:46:54 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 96903168; Read time: 0; Write time: 65396 18:46:54 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 220381184; Read time: 0; Write time: 100676 18:46:54 INFO - Running post-run listener: _upload_blobber_files 18:46:54 INFO - Blob upload gear active. 18:46:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:46:54 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 18:46:54 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'] 18:46:54 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 18:46:55 INFO - (blobuploader) - INFO - Open directory for files ... 18:46:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:46:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:46:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:46:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:46:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:46:56 INFO - (blobuploader) - INFO - Done attempting. 18:46:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:46:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:46:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:46:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:46:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:46:56 INFO - (blobuploader) - INFO - Done attempting. 18:46:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:46:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:46:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:47:00 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:47:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:47:00 INFO - (blobuploader) - INFO - Done attempting. 18:47:00 INFO - (blobuploader) - INFO - Iteration through files over. 18:47:00 INFO - Return code: 0 18:47:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:47:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:47:00 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/096202beb86f3f08ae133bd0b3d7209c91676e9738ddaa9307901de66ce53a0be610fcfe78e4e6a34cd95fc4927b89697ede3c25956e33953cacf08d0d5c2c30", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c825245ec353983c92f3e8e8841e8134db079000a6cb89910c23e2bbf4074fe37359972f3a910aebb00f7e9e66fec6d603b759794ae5d0a8b88787796f6d382f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a44faccb7615bce025fc32ceb77afd637e638948b7431df0a6cd39d09c64400c6d29a822ba1c0c214fca46525791be9c917e88dd76a89007ff310f931c14423e"} 18:47:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:47:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:47:00 INFO - Contents: 18:47:00 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/096202beb86f3f08ae133bd0b3d7209c91676e9738ddaa9307901de66ce53a0be610fcfe78e4e6a34cd95fc4927b89697ede3c25956e33953cacf08d0d5c2c30", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c825245ec353983c92f3e8e8841e8134db079000a6cb89910c23e2bbf4074fe37359972f3a910aebb00f7e9e66fec6d603b759794ae5d0a8b88787796f6d382f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a44faccb7615bce025fc32ceb77afd637e638948b7431df0a6cd39d09c64400c6d29a822ba1c0c214fca46525791be9c917e88dd76a89007ff310f931c14423e"} 18:47:00 INFO - Running post-run listener: copy_logs_to_upload_dir 18:47:00 INFO - Copying logs to upload dir... 18:47:00 INFO - mkdir: /builds/slave/test/build/upload/logs 18:47:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1231.477762 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 31 secs) (at 2017-01-24 18:47:00.402922) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-24 18:47:00.406566) ========= 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=bb9b54edd4a927dc735d112f000001d9-1485311187.133233-85291916 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/096202beb86f3f08ae133bd0b3d7209c91676e9738ddaa9307901de66ce53a0be610fcfe78e4e6a34cd95fc4927b89697ede3c25956e33953cacf08d0d5c2c30", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c825245ec353983c92f3e8e8841e8134db079000a6cb89910c23e2bbf4074fe37359972f3a910aebb00f7e9e66fec6d603b759794ae5d0a8b88787796f6d382f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a44faccb7615bce025fc32ceb77afd637e638948b7431df0a6cd39d09c64400c6d29a822ba1c0c214fca46525791be9c917e88dd76a89007ff310f931c14423e"} build_url:https://queue.taskcluster.net/v1/task/Tqh9IocdQa-2pwaE69dh5w/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011412 build_url: 'https://queue.taskcluster.net/v1/task/Tqh9IocdQa-2pwaE69dh5w/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/096202beb86f3f08ae133bd0b3d7209c91676e9738ddaa9307901de66ce53a0be610fcfe78e4e6a34cd95fc4927b89697ede3c25956e33953cacf08d0d5c2c30", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c825245ec353983c92f3e8e8841e8134db079000a6cb89910c23e2bbf4074fe37359972f3a910aebb00f7e9e66fec6d603b759794ae5d0a8b88787796f6d382f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a44faccb7615bce025fc32ceb77afd637e638948b7431df0a6cd39d09c64400c6d29a822ba1c0c214fca46525791be9c917e88dd76a89007ff310f931c14423e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-24 18:47:00.433292) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-24 18:47:00.433658) ========= 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=bb9b54edd4a927dc735d112f000001d9-1485311187.133233-85291916 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004464 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-24 18:47:00.517073) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-24 18:47:00.517394) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-24 18:47:00.517688) ========= ========= Total master_lag: 0.17 =========